-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-09-17 16:19:49 CEST. -- Sep 17 16:18:04 volumio go-librespot[1318]: time="2025-09-17T16:18:04+02:00" level=trace msg="received accesspoint ping" Sep 17 16:18:04 volumio go-librespot[1318]: time="2025-09-17T16:18:04+02:00" level=trace msg="received accesspoint pong ack" Sep 17 16:18:05 volumio go-librespot[1318]: time="2025-09-17T16:18:05+02:00" level=trace msg="sent dealer ping" Sep 17 16:18:05 volumio go-librespot[1318]: time="2025-09-17T16:18:05+02:00" level=trace msg="received dealer pong" Sep 17 16:18:09 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Sep 17 16:18:09 volumio volumio[1145]: info: Listing playlists Sep 17 16:18:19 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Sep 17 16:18:25 volumio volumio[1145]: info: Sep 17 16:18:25 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Sep 17 16:18:25 volumio volumio[1145]: info: Ignoring MPD Status Update Sep 17 16:18:25 volumio volumio[1145]: info: Sep 17 16:18:25 volumio volumio[1145]: ---------------------------- MPD announces state update: player Sep 17 16:18:25 volumio volumio[1145]: info: ControllerMpd::getState Sep 17 16:18:25 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Sep 17 16:18:25 volumio volumio[1145]: info: Sep 17 16:18:25 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Sep 17 16:18:25 volumio volumio[1145]: info: Ignoring MPD Status Update Sep 17 16:18:25 volumio volumio[1145]: info: Sep 17 16:18:25 volumio volumio[1145]: ---------------------------- MPD announces state update: player Sep 17 16:18:25 volumio volumio[1145]: info: ControllerMpd::getState Sep 17 16:18:25 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Sep 17 16:18:25 volumio volumio[1145]: info: Sep 17 16:18:25 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Sep 17 16:18:25 volumio volumio[1145]: info: Ignoring MPD Status Update Sep 17 16:18:25 volumio volumio[1145]: info: Sep 17 16:18:25 volumio volumio[1145]: ---------------------------- MPD announces state update: player Sep 17 16:18:25 volumio volumio[1145]: info: ControllerMpd::getState Sep 17 16:18:25 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Sep 17 16:18:25 volumio volumio[1145]: info: ------------------------------ 7ms Sep 17 16:18:25 volumio volumio[1145]: info: sendMpdCommand status took 6 milliseconds Sep 17 16:18:25 volumio volumio[1145]: info: ------------------------------ 5ms Sep 17 16:18:25 volumio volumio[1145]: info: sendMpdCommand status took 4 milliseconds Sep 17 16:18:25 volumio volumio[1145]: info: ------------------------------ 3ms Sep 17 16:18:25 volumio volumio[1145]: info: sendMpdCommand status took 3 milliseconds Sep 17 16:18:25 volumio volumio[1145]: verbose: ControllerMpd::parseState Sep 17 16:18:25 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 16:18:25 volumio volumio[1145]: verbose: ControllerMpd::parseState Sep 17 16:18:25 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 16:18:25 volumio volumio[1145]: verbose: ControllerMpd::parseState Sep 17 16:18:25 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 16:18:25 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 17 16:18:25 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 17 16:18:25 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 17 16:18:25 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Sep 17 16:18:25 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Sep 17 16:18:25 volumio volumio[1145]: info: ControllerMpd::pushState Sep 17 16:18:25 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Sep 17 16:18:25 volumio volumio[1145]: info: CoreStateMachine::pushState Sep 17 16:18:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 16:18:25 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Sep 17 16:18:25 volumio volumio[1145]: info: ControllerMpd::pushState Sep 17 16:18:25 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Sep 17 16:18:25 volumio volumio[1145]: info: CoreStateMachine::pushState Sep 17 16:18:25 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Sep 17 16:18:26 volumio volumio[1145]: info: ControllerMpd::pushState Sep 17 16:18:26 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Sep 17 16:18:26 volumio volumio[1145]: info: CoreStateMachine::pushState Sep 17 16:18:26 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Sep 17 16:18:26 volumio volumio[1145]: info: ------------------------------ 41ms Sep 17 16:18:26 volumio volumio[1145]: info: ------------------------------ 40ms Sep 17 16:18:26 volumio volumio[1145]: info: ------------------------------ 38ms Sep 17 16:18:26 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Sep 17 16:18:26 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Sep 17 16:18:26 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Sep 17 16:18:29 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Sep 17 16:18:29 volumio volumio[1145]: info: Listing playlists Sep 17 16:18:29 volumio volumio[1145]: info: Listing playlists Sep 17 16:18:35 volumio go-librespot[1318]: time="2025-09-17T16:18:35+02:00" level=trace msg="sent dealer ping" Sep 17 16:18:35 volumio go-librespot[1318]: time="2025-09-17T16:18:35+02:00" level=trace msg="received dealer pong" Sep 17 16:18:39 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Sep 17 16:18:42 volumio volumio[1145]: info: Sep 17 16:18:42 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Sep 17 16:18:42 volumio volumio[1145]: info: Ignoring MPD Status Update Sep 17 16:18:42 volumio volumio[1145]: info: Sep 17 16:18:42 volumio volumio[1145]: ---------------------------- MPD announces state update: player Sep 17 16:18:42 volumio volumio[1145]: info: ControllerMpd::getState Sep 17 16:18:42 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Sep 17 16:18:42 volumio volumio[1145]: info: Sep 17 16:18:42 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Sep 17 16:18:42 volumio volumio[1145]: info: Ignoring MPD Status Update Sep 17 16:18:42 volumio volumio[1145]: info: Sep 17 16:18:42 volumio volumio[1145]: ---------------------------- MPD announces state update: player Sep 17 16:18:42 volumio volumio[1145]: info: ControllerMpd::getState Sep 17 16:18:42 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Sep 17 16:18:42 volumio volumio[1145]: info: Sep 17 16:18:42 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Sep 17 16:18:42 volumio volumio[1145]: info: Ignoring MPD Status Update Sep 17 16:18:42 volumio volumio[1145]: info: Sep 17 16:18:42 volumio volumio[1145]: ---------------------------- MPD announces state update: player Sep 17 16:18:42 volumio volumio[1145]: info: ControllerMpd::getState Sep 17 16:18:42 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Sep 17 16:18:42 volumio volumio[1145]: info: ------------------------------ 6ms Sep 17 16:18:42 volumio volumio[1145]: info: sendMpdCommand status took 5 milliseconds Sep 17 16:18:42 volumio volumio[1145]: info: ------------------------------ 4ms Sep 17 16:18:42 volumio volumio[1145]: info: sendMpdCommand status took 4 milliseconds Sep 17 16:18:42 volumio volumio[1145]: info: ------------------------------ 4ms Sep 17 16:18:42 volumio volumio[1145]: info: sendMpdCommand status took 3 milliseconds Sep 17 16:18:42 volumio volumio[1145]: verbose: ControllerMpd::parseState Sep 17 16:18:42 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 16:18:42 volumio volumio[1145]: verbose: ControllerMpd::parseState Sep 17 16:18:42 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 16:18:42 volumio volumio[1145]: verbose: ControllerMpd::parseState Sep 17 16:18:42 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 16:18:42 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 17 16:18:42 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 17 16:18:42 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 17 16:18:42 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Sep 17 16:18:42 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Sep 17 16:18:42 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Sep 17 16:18:42 volumio volumio[1145]: info: ControllerMpd::pushState Sep 17 16:18:42 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Sep 17 16:18:42 volumio volumio[1145]: info: CoreStateMachine::pushState Sep 17 16:18:42 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 16:18:42 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Sep 17 16:18:42 volumio volumio[1145]: info: ControllerMpd::pushState Sep 17 16:18:42 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Sep 17 16:18:42 volumio volumio[1145]: info: CoreStateMachine::pushState Sep 17 16:18:42 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Sep 17 16:18:42 volumio volumio[1145]: info: ControllerMpd::pushState Sep 17 16:18:42 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Sep 17 16:18:42 volumio volumio[1145]: info: CoreStateMachine::pushState Sep 17 16:18:42 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Sep 17 16:18:42 volumio volumio[1145]: info: ------------------------------ 31ms Sep 17 16:18:42 volumio volumio[1145]: info: ------------------------------ 30ms Sep 17 16:18:42 volumio volumio[1145]: info: ------------------------------ 28ms Sep 17 16:18:42 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Sep 17 16:18:42 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Sep 17 16:18:42 volumio volumio[1145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Sep 17 16:18:49 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Sep 17 16:18:49 volumio volumio[1145]: info: Listing playlists Sep 17 16:18:49 volumio volumio[1145]: info: Listing playlists Sep 17 16:18:59 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Sep 17 16:19:05 volumio go-librespot[1318]: time="2025-09-17T16:19:05+02:00" level=trace msg="sent dealer ping" Sep 17 16:19:05 volumio go-librespot[1318]: time="2025-09-17T16:19:05+02:00" level=trace msg="received dealer pong" Sep 17 16:19:09 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Sep 17 16:19:09 volumio volumio[1145]: info: Listing playlists Sep 17 16:19:19 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Sep 17 16:19:29 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Sep 17 16:19:29 volumio volumio[1145]: info: Listing playlists Sep 17 16:19:29 volumio volumio[1145]: info: Listing playlists Sep 17 16:19:35 volumio go-librespot[1318]: time="2025-09-17T16:19:35+02:00" level=trace msg="sent dealer ping" Sep 17 16:19:35 volumio go-librespot[1318]: time="2025-09-17T16:19:35+02:00" level=trace msg="received dealer pong" Sep 17 16:19:39 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Sep 17 16:19:49 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Sep 17 16:19:49 volumio volumio[1145]: info: Listing playlists Sep 17 16:19:49 volumio volumio[1145]: info: Listing playlists Sep 17 16:19:49 volumio volumio[1145]: info: [1758118789323] [80s80s] Pushing the next song state Chicago - Hard to Say I'm Sorry and getting next track. Sep 17 16:19:49 volumio volumio[1145]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 16:19:49 volumio volumio[1145]: TypeError: Cannot set property 'name' of undefined Sep 17 16:19:49 volumio volumio[1145]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Sep 17 16:19:49 volumio volumio[1145]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Sep 17 16:19:49 volumio volumio[1145]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Sep 17 16:19:49 volumio volumio[1145]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Sep 17 16:19:49 volumio volumio[1145]: at processImmediate (internal/timers.js:461:21) Sep 17 16:19:49 volumio volumio[1145]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 16:19:49 volumio sudo[717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-17 16:18 Sep 17 16:19:49 volumio sudo[717]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"