-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-05-17 17:30:51 CEST. -- May 17 17:29:14 volumio avahi-daemon[690]: Record [04255CB411F0\064Volumio._raop._tcp.local IN SRV 0 0 5000 volumio.local ; ttl=120] not fitting in legacy unicast packet, dropping. May 17 17:29:14 volumio avahi-daemon[690]: Record [_Volumio._tcp.local IN PTR volumio._Volumio._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. May 17 17:29:14 volumio avahi-daemon[690]: Record [volumio._Volumio._tcp.local IN TXT "volumioName=Volumio" "UUID=d422c4c5-28b4-4064-9b56-48c5075503f0" ; ttl=4500] not fitting in legacy unicast packet, dropping. May 17 17:29:14 volumio avahi-daemon[690]: Record [volumio._Volumio._tcp.local IN SRV 0 0 3000 volumio.local ; ttl=120] not fitting in legacy unicast packet, dropping. May 17 17:29:14 volumio avahi-daemon[690]: Record [_http._tcp.local IN PTR Volumio._http._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. May 17 17:29:14 volumio avahi-daemon[690]: Record [Volumio._http._tcp.local IN TXT ; ttl=4500] not fitting in legacy unicast packet, dropping. May 17 17:29:14 volumio avahi-daemon[690]: Record [Volumio._http._tcp.local IN SRV 0 0 80 volumio.local ; ttl=120] not fitting in legacy unicast packet, dropping. May 17 17:29:59 volumio volumio[978]: info: May 17 17:29:59 volumio volumio[978]: ---------------------------- MPD announces system playlist update May 17 17:29:59 volumio volumio[978]: info: Ignoring MPD Status Update May 17 17:29:59 volumio volumio[978]: info: May 17 17:29:59 volumio volumio[978]: ---------------------------- MPD announces state update: player May 17 17:29:59 volumio volumio[978]: info: ControllerMpd::getState May 17 17:29:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 17 17:29:59 volumio volumio[978]: info: May 17 17:29:59 volumio volumio[978]: ---------------------------- MPD announces system playlist update May 17 17:29:59 volumio volumio[978]: info: Ignoring MPD Status Update May 17 17:29:59 volumio volumio[978]: info: May 17 17:29:59 volumio volumio[978]: ---------------------------- MPD announces state update: player May 17 17:29:59 volumio volumio[978]: info: ControllerMpd::getState May 17 17:29:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 17 17:29:59 volumio volumio[978]: info: May 17 17:29:59 volumio volumio[978]: ---------------------------- MPD announces system playlist update May 17 17:29:59 volumio volumio[978]: info: Ignoring MPD Status Update May 17 17:29:59 volumio volumio[978]: info: May 17 17:29:59 volumio volumio[978]: ---------------------------- MPD announces state update: player May 17 17:29:59 volumio volumio[978]: info: ControllerMpd::getState May 17 17:29:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 17 17:29:59 volumio volumio[978]: info: May 17 17:29:59 volumio volumio[978]: ---------------------------- MPD announces system playlist update May 17 17:29:59 volumio volumio[978]: info: Ignoring MPD Status Update May 17 17:29:59 volumio volumio[978]: info: May 17 17:29:59 volumio volumio[978]: ---------------------------- MPD announces state update: player May 17 17:29:59 volumio volumio[978]: info: ControllerMpd::getState May 17 17:29:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 17 17:29:59 volumio volumio[978]: info: ------------------------------ 3ms May 17 17:29:59 volumio volumio[978]: info: sendMpdCommand status took 2 milliseconds May 17 17:29:59 volumio volumio[978]: info: ------------------------------ 2ms May 17 17:29:59 volumio volumio[978]: info: sendMpdCommand status took 1 milliseconds May 17 17:29:59 volumio volumio[978]: info: ------------------------------ 2ms May 17 17:29:59 volumio volumio[978]: info: sendMpdCommand status took 2 milliseconds May 17 17:29:59 volumio volumio[978]: info: ------------------------------ 2ms May 17 17:29:59 volumio volumio[978]: verbose: ControllerMpd::parseState May 17 17:29:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 17:29:59 volumio volumio[978]: verbose: ControllerMpd::parseState May 17 17:29:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 17:29:59 volumio volumio[978]: verbose: ControllerMpd::parseState May 17 17:29:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 17:29:59 volumio volumio[978]: info: sendMpdCommand status took 1 milliseconds May 17 17:29:59 volumio volumio[978]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 17:29:59 volumio volumio[978]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 17:29:59 volumio volumio[978]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 17:29:59 volumio volumio[978]: verbose: ControllerMpd::parseState May 17 17:29:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 17:29:59 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo May 17 17:29:59 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo May 17 17:29:59 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo May 17 17:29:59 volumio volumio[978]: info: ControllerMpd::pushState May 17 17:29:59 volumio volumio[978]: info: CoreCommandRouter::servicePushState May 17 17:29:59 volumio volumio[978]: info: CoreStateMachine::pushState May 17 17:29:59 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 17:29:59 volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 17 17:29:59 volumio volumio[978]: info: ControllerMpd::pushState May 17 17:29:59 volumio volumio[978]: info: CoreCommandRouter::servicePushState May 17 17:29:59 volumio volumio[978]: info: CoreStateMachine::pushState May 17 17:29:59 volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 17 17:29:59 volumio volumio[978]: info: ControllerMpd::pushState May 17 17:29:59 volumio volumio[978]: info: CoreCommandRouter::servicePushState May 17 17:29:59 volumio volumio[978]: info: CoreStateMachine::pushState May 17 17:29:59 volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 17 17:29:59 volumio volumio[978]: info: ------------------------------ 8ms May 17 17:29:59 volumio volumio[978]: info: ------------------------------ 9ms May 17 17:29:59 volumio volumio[978]: info: ------------------------------ 8ms May 17 17:29:59 volumio volumio[978]: info: sendMpdCommand playlistinfo took 5 milliseconds May 17 17:29:59 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo May 17 17:29:59 volumio volumio[978]: info: ControllerMpd::pushState May 17 17:29:59 volumio volumio[978]: info: CoreCommandRouter::servicePushState May 17 17:29:59 volumio volumio[978]: info: CoreStateMachine::pushState May 17 17:29:59 volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 17 17:29:59 volumio volumio[978]: info: ------------------------------ 8ms May 17 17:29:59 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 17:29:59 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 17:29:59 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 17:29:59 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 17:30:15 volumio volumio[978]: info: May 17 17:30:15 volumio volumio[978]: ---------------------------- MPD announces system playlist update May 17 17:30:15 volumio volumio[978]: info: Ignoring MPD Status Update May 17 17:30:15 volumio volumio[978]: info: May 17 17:30:15 volumio volumio[978]: ---------------------------- MPD announces state update: player May 17 17:30:15 volumio volumio[978]: info: ControllerMpd::getState May 17 17:30:15 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 17 17:30:15 volumio volumio[978]: info: May 17 17:30:15 volumio volumio[978]: ---------------------------- MPD announces system playlist update May 17 17:30:15 volumio volumio[978]: info: Ignoring MPD Status Update May 17 17:30:15 volumio volumio[978]: info: May 17 17:30:15 volumio volumio[978]: ---------------------------- MPD announces state update: player May 17 17:30:15 volumio volumio[978]: info: ControllerMpd::getState May 17 17:30:15 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 17 17:30:15 volumio volumio[978]: info: May 17 17:30:15 volumio volumio[978]: ---------------------------- MPD announces system playlist update May 17 17:30:15 volumio volumio[978]: info: Ignoring MPD Status Update May 17 17:30:15 volumio volumio[978]: info: May 17 17:30:15 volumio volumio[978]: ---------------------------- MPD announces state update: player May 17 17:30:15 volumio volumio[978]: info: ControllerMpd::getState May 17 17:30:15 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 17 17:30:15 volumio volumio[978]: info: May 17 17:30:15 volumio volumio[978]: ---------------------------- MPD announces system playlist update May 17 17:30:15 volumio volumio[978]: info: Ignoring MPD Status Update May 17 17:30:15 volumio volumio[978]: info: May 17 17:30:15 volumio volumio[978]: ---------------------------- MPD announces state update: player May 17 17:30:15 volumio volumio[978]: info: ControllerMpd::getState May 17 17:30:15 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status May 17 17:30:15 volumio volumio[978]: info: ------------------------------ 3ms May 17 17:30:15 volumio volumio[978]: info: sendMpdCommand status took 3 milliseconds May 17 17:30:15 volumio volumio[978]: info: ------------------------------ 2ms May 17 17:30:15 volumio volumio[978]: info: sendMpdCommand status took 2 milliseconds May 17 17:30:15 volumio volumio[978]: info: ------------------------------ 2ms May 17 17:30:15 volumio volumio[978]: info: sendMpdCommand status took 2 milliseconds May 17 17:30:15 volumio volumio[978]: info: ------------------------------ 2ms May 17 17:30:15 volumio volumio[978]: info: sendMpdCommand status took 1 milliseconds May 17 17:30:15 volumio volumio[978]: verbose: ControllerMpd::parseState May 17 17:30:15 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 17:30:15 volumio volumio[978]: verbose: ControllerMpd::parseState May 17 17:30:15 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 17:30:15 volumio volumio[978]: verbose: ControllerMpd::parseState May 17 17:30:15 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 17:30:15 volumio volumio[978]: verbose: ControllerMpd::parseState May 17 17:30:15 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 17:30:15 volumio volumio[978]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 17:30:15 volumio volumio[978]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 17:30:15 volumio volumio[978]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 17:30:15 volumio volumio[978]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 17:30:15 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo May 17 17:30:15 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo May 17 17:30:15 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo May 17 17:30:15 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo May 17 17:30:15 volumio volumio[978]: info: ControllerMpd::pushState May 17 17:30:15 volumio volumio[978]: info: CoreCommandRouter::servicePushState May 17 17:30:15 volumio volumio[978]: info: CoreStateMachine::pushState May 17 17:30:15 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 17:30:15 volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 17 17:30:15 volumio volumio[978]: info: ControllerMpd::pushState May 17 17:30:15 volumio volumio[978]: info: CoreCommandRouter::servicePushState May 17 17:30:15 volumio volumio[978]: info: CoreStateMachine::pushState May 17 17:30:15 volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 17 17:30:15 volumio volumio[978]: info: ControllerMpd::pushState May 17 17:30:15 volumio volumio[978]: info: CoreCommandRouter::servicePushState May 17 17:30:15 volumio volumio[978]: info: CoreStateMachine::pushState May 17 17:30:15 volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 17 17:30:15 volumio volumio[978]: info: ControllerMpd::pushState May 17 17:30:15 volumio volumio[978]: info: CoreCommandRouter::servicePushState May 17 17:30:15 volumio volumio[978]: info: CoreStateMachine::pushState May 17 17:30:15 volumio volumio[978]: info: CoreCommandRouter::volumioPushState May 17 17:30:15 volumio volumio[978]: info: ------------------------------ 10ms May 17 17:30:15 volumio volumio[978]: info: ------------------------------ 9ms May 17 17:30:15 volumio volumio[978]: info: ------------------------------ 8ms May 17 17:30:15 volumio volumio[978]: info: ------------------------------ 9ms May 17 17:30:15 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 17:30:15 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 17:30:15 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 17:30:15 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 17:30:51 volumio volumio[978]: info: [1747495851690] [80s80s] Pushing the next song state Depeche Mode - Dream On and getting next track. May 17 17:30:51 volumio volumio[978]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 17:30:51 volumio volumio[978]: TypeError: Cannot set property 'name' of undefined May 17 17:30:51 volumio volumio[978]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) May 17 17:30:51 volumio volumio[978]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) May 17 17:30:51 volumio volumio[978]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) May 17 17:30:51 volumio volumio[978]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) May 17 17:30:51 volumio volumio[978]: at processImmediate (internal/timers.js:461:21) May 17 17:30:51 volumio volumio[978]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 17:30:51 volumio sudo[25232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-17 17:29 May 17 17:30:51 volumio sudo[25232]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"