-- Logs begin at Thu 2019-02-14 21:11:59 AEDT, end at Mon 2024-04-08 17:27:54 AEST. -- Apr 08 17:26:44 spotipi systemd[1]: Starting Cleanup of Temporary Directories... Apr 08 17:26:44 spotipi systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Apr 08 17:26:44 spotipi systemd[1]: Started Cleanup of Temporary Directories. Apr 08 17:27:14 spotipi volumio[717]: info: CorePlayQueue::getTrack 2 Apr 08 17:27:14 spotipi volumio[717]: info: CorePlayQueue::getTrack 3 Apr 08 17:27:14 spotipi volumio[717]: info: Prefetching next song Apr 08 17:27:14 spotipi volumio[717]: info: DOING PREFETCH IN MPD Apr 08 17:27:14 spotipi volumio[717]: verbose: ControllerMpd::sendMpdCommand add "USB/SSD/hi-res/Cindi Lauper/1983 - She's So Unusual [CDPRT 25792]/04 - Time After Time.flac" Apr 08 17:27:14 spotipi volumio[717]: info: Apr 08 17:27:14 spotipi volumio[717]: ---------------------------- MPD announces system playlist update Apr 08 17:27:14 spotipi volumio[717]: info: Ignoring MPD Status Update Apr 08 17:27:14 spotipi volumio[717]: info: sendMpdCommand add "USB/SSD/hi-res/Cindi Lauper/1983 - She's So Unusual [CDPRT 25792]/04 - Time After Time.flac" took 2 milliseconds Apr 08 17:27:14 spotipi volumio[717]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 08 17:27:14 spotipi volumio[717]: info: Apr 08 17:27:14 spotipi volumio[717]: ---------------------------- MPD announces system playlist update Apr 08 17:27:14 spotipi volumio[717]: info: Ignoring MPD Status Update Apr 08 17:27:14 spotipi volumio[717]: info: Apr 08 17:27:14 spotipi volumio[717]: ---------------------------- MPD announces system playlist update Apr 08 17:27:14 spotipi volumio[717]: info: Ignoring MPD Status Update Apr 08 17:27:14 spotipi volumio[717]: info: ------------------------------ 3ms Apr 08 17:27:14 spotipi volumio[717]: info: sendMpdCommand consume 1 took 3 milliseconds Apr 08 17:27:14 spotipi volumio[717]: info: ------------------------------ 3ms Apr 08 17:27:14 spotipi volumio[717]: info: ------------------------------ 2ms Apr 08 17:27:19 spotipi volumio[717]: info: CoreStateMachine::startPlaybackTimer Apr 08 17:27:19 spotipi volumio[717]: info: CorePlayQueue::getTrack 3 Apr 08 17:27:19 spotipi volumio[717]: info: CoreStateMachine::pushState Apr 08 17:27:19 spotipi volumio[717]: info: CorePlayQueue::getTrack 3 Apr 08 17:27:19 spotipi volumio[717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 17:27:19 spotipi volumio[717]: info: CoreCommandRouter::volumioPushState Apr 08 17:27:19 spotipi volumio[717]: info: [LastFM] Current track has sufficient metadata: title (4 - Time After Time) and artist (Cyndi Lauper) passed on explicitly Apr 08 17:27:19 spotipi volumio[717]: info: Apr 08 17:27:19 spotipi volumio[717]: ---------------------------- MPD announces system playlist update Apr 08 17:27:19 spotipi volumio[717]: info: Ignoring MPD Status Update Apr 08 17:27:19 spotipi volumio[717]: info: Apr 08 17:27:19 spotipi volumio[717]: ---------------------------- MPD announces state update: player Apr 08 17:27:19 spotipi volumio[717]: info: ControllerMpd::getState Apr 08 17:27:19 spotipi volumio[717]: verbose: ControllerMpd::sendMpdCommand status Apr 08 17:27:19 spotipi volumio[717]: info: Apr 08 17:27:19 spotipi volumio[717]: ---------------------------- MPD announces system playlist update Apr 08 17:27:19 spotipi volumio[717]: info: Ignoring MPD Status Update Apr 08 17:27:19 spotipi volumio[717]: info: Apr 08 17:27:19 spotipi volumio[717]: ---------------------------- MPD announces state update: player Apr 08 17:27:19 spotipi volumio[717]: info: ControllerMpd::getState Apr 08 17:27:19 spotipi volumio[717]: verbose: ControllerMpd::sendMpdCommand status Apr 08 17:27:19 spotipi volumio[717]: info: Apr 08 17:27:19 spotipi volumio[717]: ---------------------------- MPD announces system playlist update Apr 08 17:27:19 spotipi volumio[717]: info: Ignoring MPD Status Update Apr 08 17:27:19 spotipi volumio[717]: info: Apr 08 17:27:19 spotipi volumio[717]: ---------------------------- MPD announces state update: player Apr 08 17:27:19 spotipi volumio[717]: info: ControllerMpd::getState Apr 08 17:27:19 spotipi volumio[717]: verbose: ControllerMpd::sendMpdCommand status Apr 08 17:27:19 spotipi volumio[717]: info: ------------------------------ 4ms Apr 08 17:27:19 spotipi volumio[717]: info: sendMpdCommand status took 3 milliseconds Apr 08 17:27:19 spotipi volumio[717]: info: ------------------------------ 3ms Apr 08 17:27:19 spotipi volumio[717]: info: sendMpdCommand status took 3 milliseconds Apr 08 17:27:19 spotipi volumio[717]: verbose: ControllerMpd::parseState Apr 08 17:27:19 spotipi volumio[717]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 17:27:19 spotipi volumio[717]: verbose: ControllerMpd::parseState Apr 08 17:27:19 spotipi volumio[717]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 17:27:19 spotipi volumio[717]: info: ------------------------------ 4ms Apr 08 17:27:19 spotipi volumio[717]: info: sendMpdCommand status took 3 milliseconds Apr 08 17:27:19 spotipi volumio[717]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 08 17:27:19 spotipi volumio[717]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 08 17:27:19 spotipi volumio[717]: verbose: ControllerMpd::parseState Apr 08 17:27:19 spotipi volumio[717]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 17:27:19 spotipi volumio[717]: verbose: ControllerMpd::parseTrackInfo Apr 08 17:27:19 spotipi volumio[717]: verbose: ControllerMpd::parseTrackInfo Apr 08 17:27:19 spotipi volumio[717]: info: ControllerMpd::pushState Apr 08 17:27:19 spotipi volumio[717]: info: CoreCommandRouter::servicePushState Apr 08 17:27:19 spotipi volumio[717]: info: CorePlayQueue::getTrack 3 Apr 08 17:27:19 spotipi volumio[717]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"644 Kbps","isStreaming":false,"title":"Time After Time","artist":"Cyndi Lauper","album":"She's So Unusual","uri":"USB/SSD/hi-res/Cindi Lauper/1983 - She's So Unusual [CDPRT 25792]/04 - Time After Time.flac","trackType":"flac"} Apr 08 17:27:19 spotipi volumio[717]: verbose: CURRENT POSITION 3 Apr 08 17:27:19 spotipi volumio[717]: info: CoreStateMachine::syncState stateService play Apr 08 17:27:19 spotipi volumio[717]: info: CoreStateMachine::syncState currentStatus play Apr 08 17:27:19 spotipi volumio[717]: info: Received an update from plugin. extracting info from payload Apr 08 17:27:19 spotipi volumio[717]: info: CoreStateMachine::pushState Apr 08 17:27:19 spotipi volumio[717]: info: CorePlayQueue::getTrack 3 Apr 08 17:27:19 spotipi volumio[717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 17:27:19 spotipi volumio[717]: info: CoreCommandRouter::volumioPushState Apr 08 17:27:19 spotipi volumio[717]: info: CoreStateMachine::pushState Apr 08 17:27:19 spotipi volumio[717]: info: CorePlayQueue::getTrack 3 Apr 08 17:27:19 spotipi volumio[717]: info: CoreCommandRouter::volumioPushState Apr 08 17:27:19 spotipi volumio[717]: info: ControllerMpd::pushState Apr 08 17:27:19 spotipi volumio[717]: info: CoreCommandRouter::servicePushState Apr 08 17:27:19 spotipi volumio[717]: info: CorePlayQueue::getTrack 3 Apr 08 17:27:19 spotipi volumio[717]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"644 Kbps","isStreaming":false,"title":"Time After Time","artist":"Cyndi Lauper","album":"She's So Unusual","uri":"USB/SSD/hi-res/Cindi Lauper/1983 - She's So Unusual [CDPRT 25792]/04 - Time After Time.flac","trackType":"flac"} Apr 08 17:27:19 spotipi volumio[717]: verbose: CURRENT POSITION 3 Apr 08 17:27:19 spotipi volumio[717]: info: CoreStateMachine::syncState stateService play Apr 08 17:27:19 spotipi volumio[717]: info: CoreStateMachine::syncState currentStatus play Apr 08 17:27:19 spotipi volumio[717]: info: Received an update from plugin. extracting info from payload Apr 08 17:27:19 spotipi volumio[717]: info: CoreStateMachine::pushState Apr 08 17:27:19 spotipi volumio[717]: info: CorePlayQueue::getTrack 3 Apr 08 17:27:19 spotipi volumio[717]: info: CoreCommandRouter::volumioPushState Apr 08 17:27:19 spotipi volumio[717]: info: CoreStateMachine::pushState Apr 08 17:27:19 spotipi volumio[717]: info: CorePlayQueue::getTrack 3 Apr 08 17:27:19 spotipi volumio[717]: info: CoreCommandRouter::volumioPushState Apr 08 17:27:19 spotipi volumio[717]: info: ------------------------------ 51ms Apr 08 17:27:19 spotipi volumio[717]: info: ------------------------------ 50ms Apr 08 17:27:19 spotipi volumio[717]: info: sendMpdCommand playlistinfo took 47 milliseconds Apr 08 17:27:19 spotipi volumio[717]: verbose: ControllerMpd::parseTrackInfo Apr 08 17:27:19 spotipi volumio[717]: info: ControllerMpd::pushState Apr 08 17:27:19 spotipi volumio[717]: info: CoreCommandRouter::servicePushState Apr 08 17:27:19 spotipi volumio[717]: info: CorePlayQueue::getTrack 3 Apr 08 17:27:19 spotipi volumio[717]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"644 Kbps","isStreaming":false,"title":"Time After Time","artist":"Cyndi Lauper","album":"She's So Unusual","uri":"USB/SSD/hi-res/Cindi Lauper/1983 - She's So Unusual [CDPRT 25792]/04 - Time After Time.flac","trackType":"flac"} Apr 08 17:27:19 spotipi volumio[717]: verbose: CURRENT POSITION 3 Apr 08 17:27:19 spotipi volumio[717]: info: CoreStateMachine::syncState stateService play Apr 08 17:27:19 spotipi volumio[717]: info: CoreStateMachine::syncState currentStatus play Apr 08 17:27:19 spotipi volumio[717]: info: Received an update from plugin. extracting info from payload Apr 08 17:27:19 spotipi volumio[717]: info: CoreStateMachine::pushState Apr 08 17:27:19 spotipi volumio[717]: info: CorePlayQueue::getTrack 3 Apr 08 17:27:19 spotipi volumio[717]: info: CoreCommandRouter::volumioPushState Apr 08 17:27:19 spotipi volumio[717]: info: CoreStateMachine::pushState Apr 08 17:27:19 spotipi volumio[717]: info: CorePlayQueue::getTrack 3 Apr 08 17:27:19 spotipi volumio[717]: info: CoreCommandRouter::volumioPushState Apr 08 17:27:19 spotipi volumio[717]: info: ------------------------------ 88ms Apr 08 17:27:19 spotipi volumio[717]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"4 - Time After Time","url":"https://www.last.fm/music/Cyndi+Lauper/_/4+-+Time+After+Time","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"6","playcount":"32","artist":{"name":"Cyndi Lauper","mbid":"7bd9e20e-74b9-446a-a2ed-a223f82a36e7","url":"https://www.last.fm/music/Cyndi+Lauper"},"userplaycount":"0","userloved":"0","toptags":""}} Apr 08 17:27:47 spotipi volumio[717]: info: CoreCommandRouter::volumioGetState Apr 08 17:27:47 spotipi volumio[717]: info: CorePlayQueue::getTrack 3 Apr 08 17:27:49 spotipi volumio[717]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 08 17:27:49 spotipi volumio[717]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 08 17:27:53 spotipi volumio[717]: info: Starting Uninstall of plugin system_hardware - iractivityled Apr 08 17:27:53 spotipi volumio[717]: info: Uninstalling plugin iractivityled Apr 08 17:27:53 spotipi lircd[1083]: lircd-0.10.1[1083]: Info: removed client Apr 08 17:27:53 spotipi lircd-0.10.1[1083]: Info: removed client Apr 08 17:27:53 spotipi volumio[717]: info: [IrActivityLed] releasing built-in LED Apr 08 17:27:53 spotipi volumio[717]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 17:27:53 spotipi volumio[717]: TypeError: Cannot read property 'toString' of undefined Apr 08 17:27:53 spotipi volumio[717]: at IrActivityLedController.releaseLed (/data/plugins/system_hardware/iractivityled/index.js:232:61) Apr 08 17:27:53 spotipi volumio[717]: at IrActivityLedController.onStop (/data/plugins/system_hardware/iractivityled/index.js:68:17) Apr 08 17:27:53 spotipi volumio[717]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 08 17:27:53 spotipi volumio[717]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Apr 08 17:27:53 spotipi volumio[717]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1459:22) Apr 08 17:27:53 spotipi volumio[717]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1218:45) Apr 08 17:27:53 spotipi volumio[717]: at Socket.emit (events.js:315:20) Apr 08 17:27:53 spotipi volumio[717]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 08 17:27:53 spotipi volumio[717]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 08 17:27:53 spotipi volumio[717]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 17:27:54 spotipi sudo[5650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-08 17:26 Apr 08 17:27:54 spotipi sudo[5650]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="56d8f4e08ca3cb143324aa0740c362c469043a9b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="a77c7968e70cebb21b39a3791120006fed600769" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 31 Mar 2024 12:34:13 PM CEST" VOLUMIO_VERSION="3.637" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="41a89107ca82d89cff75a3106ff77fdf"