-- Logs begin at Fri 2024-12-06 03:05:53 UTC, end at Fri 2025-12-26 01:07:26 UTC. -- Dec 26 01:06:49 volumio volumio[865]: info: Clearing queue after UPNP request Dec 26 01:06:50 volumio volumio[865]: info: CoreStateMachine::ClearQueue Dec 26 01:06:50 volumio volumio[865]: info: CoreStateMachine::stop Dec 26 01:06:50 volumio volumio[865]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 01:06:50 volumio volumio[865]: UNSET VOLATILE Dec 26 01:06:50 volumio volumio[865]: info: CorePlayQueue::clearPlayQueue Dec 26 01:06:50 volumio volumio[865]: info: CorePlayQueue::saveQueue Dec 26 01:06:50 volumio volumio[865]: info: CoreCommandRouter::volumioPushState Dec 26 01:06:50 volumio volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 01:06:50 volumio volumio[865]: info: CoreCommandRouter::volumioPushQueue Dec 26 01:06:50 volumio volumio[865]: info: Dec 26 01:06:50 volumio volumio[865]: ---------------------------- MPD announces system playlist update Dec 26 01:06:50 volumio volumio[865]: info: Ignoring MPD Status Update Dec 26 01:06:50 volumio volumio[865]: error: updateQueue error: null Dec 26 01:06:50 volumio volumio[865]: info: ------------------------------ 3ms Dec 26 01:06:50 volumio volumio[865]: info: Starting UPNP Playback Dec 26 01:06:50 volumio volumio[865]: info: Preparing playback through UPNP Dec 26 01:06:50 volumio volumio[865]: info: CoreCommandRouter::volumioGetState Dec 26 01:06:50 volumio volumio[865]: info: CorePlayQueue::getTrack 0 Dec 26 01:06:50 volumio volumio[865]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 01:06:50 volumio volumio[865]: info: Dec 26 01:06:50 volumio volumio[865]: ---------------------------- MPD announces system playlist update Dec 26 01:06:50 volumio volumio[865]: info: Ignoring MPD Status Update Dec 26 01:06:50 volumio volumio[865]: info: Dec 26 01:06:50 volumio volumio[865]: ---------------------------- MPD announces system playlist update Dec 26 01:06:50 volumio volumio[865]: info: Ignoring MPD Status Update Dec 26 01:06:50 volumio volumio[865]: info: ------------------------------ 4ms Dec 26 01:06:50 volumio volumio[865]: info: ------------------------------ 2ms Dec 26 01:06:50 volumio volumio[865]: info: Dec 26 01:06:50 volumio volumio[865]: ---------------------------- MPD announces system playlist update Dec 26 01:06:50 volumio volumio[865]: info: Ignoring MPD Status Update Dec 26 01:06:50 volumio volumio[865]: info: Dec 26 01:06:50 volumio volumio[865]: ---------------------------- MPD announces system playlist update Dec 26 01:06:50 volumio volumio[865]: info: Ignoring MPD Status Update Dec 26 01:06:50 volumio volumio[865]: info: ------------------------------ 2ms Dec 26 01:06:50 volumio volumio[865]: info: ------------------------------ 2ms Dec 26 01:06:50 volumio volumio[865]: info: Dec 26 01:06:50 volumio volumio[865]: ---------------------------- MPD announces system playlist update Dec 26 01:06:50 volumio volumio[865]: info: Ignoring MPD Status Update Dec 26 01:06:50 volumio volumio[865]: info: ------------------------------ 1ms Dec 26 01:06:50 volumio volumio[865]: info: Dec 26 01:06:50 volumio volumio[865]: ---------------------------- MPD announces system playlist update Dec 26 01:06:50 volumio volumio[865]: info: Ignoring MPD Status Update Dec 26 01:06:50 volumio volumio[865]: info: ------------------------------ 1ms Dec 26 01:06:53 volumio volumio[865]: info: Dec 26 01:06:53 volumio volumio[865]: ---------------------------- MPD announces state update: player Dec 26 01:06:53 volumio volumio[865]: info: ControllerMpd::getState Dec 26 01:06:53 volumio volumio[865]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:06:53 volumio pulseaudio[1636]: [pulseaudio] server-lookup.c: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 26 01:06:53 volumio pulseaudio[1636]: [pulseaudio] main.c: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 26 01:06:53 volumio pulseaudio[1636]: [pulseaudio] bluez4-util.c: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Dec 26 01:06:53 volumio bluetoothd[758]: Endpoint registered: sender=:1.37 path=/MediaEndpoint/A2DPSource Dec 26 01:06:53 volumio bluetoothd[758]: Endpoint registered: sender=:1.37 path=/MediaEndpoint/A2DPSink Dec 26 01:06:53 volumio volumio[865]: info: Dec 26 01:06:53 volumio volumio[865]: ---------------------------- MPD announces state update: player Dec 26 01:06:53 volumio volumio[865]: info: sendMpdCommand status took 185 milliseconds Dec 26 01:06:53 volumio volumio[865]: info: ControllerMpd::getState Dec 26 01:06:53 volumio volumio[865]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:06:53 volumio volumio[865]: verbose: ControllerMpd::parseState Dec 26 01:06:53 volumio volumio[865]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 01:06:53 volumio volumio[865]: info: sendMpdCommand status took 3 milliseconds Dec 26 01:06:53 volumio volumio[865]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 26 01:06:53 volumio volumio[865]: verbose: ControllerMpd::parseState Dec 26 01:06:53 volumio volumio[865]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 01:06:53 volumio volumio[865]: verbose: ControllerMpd::parseTrackInfo Dec 26 01:06:53 volumio volumio[865]: info: ControllerMpd::pushState Dec 26 01:06:53 volumio volumio[865]: info: CoreCommandRouter::servicePushState Dec 26 01:06:53 volumio volumio[865]: In UPNP mode Dec 26 01:06:53 volumio volumio[865]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":107,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"September","artist":"Antonio R","album":"The Sax Life","uri":"http://192.168.0.12:4718/dlna/82753577","trackType":""} Dec 26 01:06:53 volumio volumio[865]: verbose: CURRENT POSITION 0 Dec 26 01:06:53 volumio volumio[865]: info: CoreStateMachine::syncState stateService play Dec 26 01:06:53 volumio volumio[865]: info: CoreStateMachine::syncState currentStatus stop Dec 26 01:06:53 volumio volumio[865]: info: CoreStateMachine::pushState Dec 26 01:06:53 volumio volumio[865]: info: CorePlayQueue::getTrack 0 Dec 26 01:06:53 volumio volumio[865]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Dec 26 01:06:53 volumio volumio[865]: info: ------------------------------ 199ms Dec 26 01:06:53 volumio volumio[865]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 26 01:06:53 volumio volumio[865]: verbose: ControllerMpd::parseTrackInfo Dec 26 01:06:53 volumio volumio[865]: info: ControllerMpd::pushState Dec 26 01:06:53 volumio volumio[865]: info: CoreCommandRouter::servicePushState Dec 26 01:06:53 volumio volumio[865]: In UPNP mode Dec 26 01:06:53 volumio volumio[865]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":107,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"September","artist":"Antonio R","album":"The Sax Life","uri":"http://192.168.0.12:4718/dlna/82753577","trackType":""} Dec 26 01:06:53 volumio volumio[865]: verbose: CURRENT POSITION 0 Dec 26 01:06:53 volumio volumio[865]: info: CoreStateMachine::syncState stateService play Dec 26 01:06:53 volumio volumio[865]: info: CoreStateMachine::syncState currentStatus play Dec 26 01:06:53 volumio volumio[865]: info: Received an update from plugin. extracting info from payload Dec 26 01:06:53 volumio volumio[865]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 26 01:06:53 volumio volumio[865]: info: CoreStateMachine::pushState Dec 26 01:06:53 volumio volumio[865]: info: CorePlayQueue::getTrack 0 Dec 26 01:06:53 volumio volumio[865]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Dec 26 01:06:53 volumio volumio[865]: info: ------------------------------ 20ms Dec 26 01:06:54 volumio volumio[865]: info: Dec 26 01:06:54 volumio volumio[865]: ---------------------------- MPD announces state update: player Dec 26 01:06:54 volumio volumio[865]: info: ControllerMpd::getState Dec 26 01:06:54 volumio volumio[865]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:06:54 volumio volumio[865]: info: sendMpdCommand status took 1 milliseconds Dec 26 01:06:54 volumio volumio[865]: verbose: ControllerMpd::parseState Dec 26 01:06:54 volumio volumio[865]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 01:06:54 volumio volumio[865]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 26 01:06:54 volumio volumio[865]: verbose: ControllerMpd::parseTrackInfo Dec 26 01:06:54 volumio volumio[865]: info: ControllerMpd::pushState Dec 26 01:06:54 volumio volumio[865]: info: CoreCommandRouter::servicePushState Dec 26 01:06:54 volumio volumio[865]: In UPNP mode Dec 26 01:06:54 volumio volumio[865]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13000,"duration":107,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"September","artist":"Antonio R","album":"The Sax Life","uri":"http://192.168.0.12:4718/dlna/82753577","trackType":""} Dec 26 01:06:54 volumio volumio[865]: verbose: CURRENT POSITION 0 Dec 26 01:06:54 volumio volumio[865]: info: CoreStateMachine::syncState stateService play Dec 26 01:06:54 volumio volumio[865]: info: CoreStateMachine::syncState currentStatus play Dec 26 01:06:54 volumio volumio[865]: info: Received an update from plugin. extracting info from payload Dec 26 01:06:54 volumio volumio[865]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 26 01:06:54 volumio volumio[865]: info: CoreStateMachine::pushState Dec 26 01:06:54 volumio volumio[865]: info: CorePlayQueue::getTrack 0 Dec 26 01:06:54 volumio volumio[865]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Dec 26 01:06:54 volumio volumio[865]: info: ------------------------------ 8ms Dec 26 01:07:12 volumio dhcpcd[1249]: eth0: carrier lost Dec 26 01:07:12 volumio dhcpcd[1249]: eth0: deleting host route to 192.168.0.202 via 127.0.0.1 Dec 26 01:07:12 volumio dhcpcd[1249]: eth0: deleting route to 192.168.0.0/24 Dec 26 01:07:12 volumio dhcpcd[1249]: eth0: deleting default route via 192.168.0.1 Dec 26 01:07:12 volumio avahi-daemon[651]: Withdrawing address record for 192.168.0.202 on eth0. Dec 26 01:07:12 volumio avahi-daemon[651]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.202. Dec 26 01:07:12 volumio avahi-daemon[651]: Interface eth0.IPv4 no longer relevant for mDNS. Dec 26 01:07:12 volumio kernel: bcmgenet fd580000.genet eth0: Link is Down Dec 26 01:07:12 volumio volumio[865]: info: mDNS: A device disapperared from network Dec 26 01:07:12 volumio volumio[865]: info: mDNS: Device volumio disapperared from network Dec 26 01:07:13 volumio ifplugd(eth0)[701]: Link beat lost. Dec 26 01:07:13 volumio bluetoothd[758]: Endpoint unregistered: sender=:1.37 path=/MediaEndpoint/A2DPSource Dec 26 01:07:13 volumio bluetoothd[758]: Endpoint unregistered: sender=:1.37 path=/MediaEndpoint/A2DPSink Dec 26 01:07:14 volumio ntpd[737]: Deleting interface #4 eth0, 192.168.0.202#123, interface stats: received=39, sent=40, dropped=0, active_time=122 secs Dec 26 01:07:14 volumio ntpd[737]: 203.32.26.46 interface 192.168.0.202 -> (none) Dec 26 01:07:14 volumio ntpd[737]: 193.123.243.2 interface 192.168.0.202 -> (none) Dec 26 01:07:14 volumio ntpd[737]: 27.102.137.217 interface 192.168.0.202 -> (none) Dec 26 01:07:14 volumio ntpd[737]: 221.151.118.78 interface 192.168.0.202 -> (none) Dec 26 01:07:14 volumio ntpd[737]: peers refreshed Dec 26 01:07:20 volumio dhcpcd[1249]: eth0: carrier acquired Dec 26 01:07:20 volumio kernel: bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 26 01:07:20 volumio dhcpcd[1249]: eth0: soliciting an IPv6 router Dec 26 01:07:20 volumio dhcpcd[1249]: eth0: soliciting a DHCP lease Dec 26 01:07:20 volumio ifplugd(eth0)[701]: Link beat detected. Dec 26 01:07:20 volumio dhcpcd[1249]: eth0: offered 192.168.0.202 from 192.168.0.1 Dec 26 01:07:25 volumio dhcpcd[1249]: eth0: leased 192.168.0.202 for 7200 seconds Dec 26 01:07:25 volumio dhcpcd[1249]: eth0: adding host route to 192.168.0.202 via 127.0.0.1 Dec 26 01:07:25 volumio dhcpcd[1249]: eth0: adding route to 192.168.0.0/24 Dec 26 01:07:25 volumio dhcpcd[1249]: eth0: adding default route via 192.168.0.1 Dec 26 01:07:25 volumio avahi-daemon[651]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.202. Dec 26 01:07:25 volumio avahi-daemon[651]: New relevant interface eth0.IPv4 for mDNS. Dec 26 01:07:25 volumio avahi-daemon[651]: Registering new address record for 192.168.0.202 on eth0.IPv4. Dec 26 01:07:25 volumio volumio[865]: info: Clearing queue after UPNP request Dec 26 01:07:26 volumio volumio[865]: info: CoreStateMachine::ClearQueue Dec 26 01:07:26 volumio volumio[865]: info: CoreStateMachine::stop Dec 26 01:07:26 volumio volumio[865]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 01:07:26 volumio volumio[865]: UNSET VOLATILE Dec 26 01:07:26 volumio volumio[865]: info: CoreStateMachine::stPlaybackTimer Dec 26 01:07:26 volumio volumio[865]: info: CoreStateMachine::updateTrackBlock Dec 26 01:07:26 volumio volumio[865]: info: CorePlayQueue::getTrackBlock Dec 26 01:07:26 volumio volumio[865]: info: CoreStateMachine::pushState Dec 26 01:07:26 volumio volumio[865]: info: CorePlayQueue::getTrack 0 Dec 26 01:07:26 volumio volumio[865]: info: CoreCommandRouter::volumioPushState Dec 26 01:07:26 volumio volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 01:07:26 volumio volumio[865]: info: CoreStateMachine::serviceStop Dec 26 01:07:26 volumio volumio[865]: info: CorePlayQueue::getTrack 0 Dec 26 01:07:26 volumio volumio[865]: info: ControllerMpd::stop Dec 26 01:07:26 volumio volumio[865]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 01:07:26 volumio volumio[865]: info: CorePlayQueue::clearPlayQueue Dec 26 01:07:26 volumio volumio[865]: info: CorePlayQueue::saveQueue Dec 26 01:07:26 volumio volumio[865]: info: CoreCommandRouter::volumioPushState Dec 26 01:07:26 volumio volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 01:07:26 volumio volumio[865]: info: CoreCommandRouter::volumioPushQueue Dec 26 01:07:26 volumio volumio[865]: info: Dec 26 01:07:26 volumio volumio[865]: ---------------------------- MPD announces system playlist update Dec 26 01:07:26 volumio volumio[865]: info: Ignoring MPD Status Update Dec 26 01:07:26 volumio volumio[865]: info: Dec 26 01:07:26 volumio volumio[865]: ---------------------------- MPD announces state update: player Dec 26 01:07:26 volumio volumio[865]: info: ControllerMpd::getState Dec 26 01:07:26 volumio volumio[865]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:07:26 volumio volumio[865]: info: sendMpdCommand stop took 48 milliseconds Dec 26 01:07:26 volumio volumio[865]: info: Starting UPNP Playback Dec 26 01:07:26 volumio volumio[865]: info: Preparing playback through UPNP Dec 26 01:07:26 volumio volumio[865]: info: CoreCommandRouter::volumioGetState Dec 26 01:07:26 volumio volumio[865]: info: CorePlayQueue::getTrack 0 Dec 26 01:07:26 volumio volumio[865]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 01:07:26 volumio volumio[865]: error: updateQueue error: null Dec 26 01:07:26 volumio volumio[865]: info: ------------------------------ 4ms Dec 26 01:07:26 volumio volumio[865]: info: sendMpdCommand status took 3 milliseconds Dec 26 01:07:26 volumio volumio[865]: verbose: ControllerMpd::parseState Dec 26 01:07:26 volumio volumio[865]: info: ControllerMpd::pushState Dec 26 01:07:26 volumio volumio[865]: info: CoreCommandRouter::servicePushState Dec 26 01:07:26 volumio volumio[865]: info: CoreStateMachine::pushState Dec 26 01:07:26 volumio volumio[865]: info: CorePlayQueue::getTrack 0 Dec 26 01:07:26 volumio volumio[865]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Dec 26 01:07:26 volumio volumio[865]: info: ------------------------------ 5ms Dec 26 01:07:26 volumio volumio[865]: Discovery: adding ba6358be-e3bb-4214-9167-7cb16fb62d9b Dec 26 01:07:26 volumio volumio[865]: info: mDNS: Found device Volumio Dec 26 01:07:26 volumio volumio[865]: info: CoreCommandRouter::volumioGetState Dec 26 01:07:26 volumio volumio[865]: info: CorePlayQueue::getTrack 0 Dec 26 01:07:26 volumio volumio[865]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 01:07:26 volumio volumio[865]: TypeError: Cannot read property 'name' of undefined Dec 26 01:07:26 volumio volumio[865]: at CoreStateMachine.getState (/volumio/app/statemachine.js:127:20) Dec 26 01:07:26 volumio volumio[865]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:99:28) Dec 26 01:07:26 volumio volumio[865]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:275:40) Dec 26 01:07:26 volumio volumio[865]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:202:12) Dec 26 01:07:26 volumio volumio[865]: at emitTwo (events.js:126:13) Dec 26 01:07:26 volumio volumio[865]: at Browser.emit (events.js:214:7) Dec 26 01:07:26 volumio volumio[865]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Dec 26 01:07:26 volumio volumio[865]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Dec 26 01:07:26 volumio volumio[865]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Dec 26 01:07:26 volumio volumio[865]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Dec 26 01:07:26 volumio volumio[865]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Dec 26 01:07:26 volumio volumio[865]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 01:07:26 volumio sudo[1900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-26 01:06 Dec 26 01:07:26 volumio sudo[1900]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="14acf028cf46278bd3fd3dd02523424a9bc825bf" VOLUMIO_FE_VERSION="9dfacc3084ddf8378a0a2ba052bab3e7aa2ae345" VOLUMIO_BE_VERSION="40a5d4b5232c61241c1a813b8652ad2b0cbd8a2c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 5 14:48:15 UTC 2021" VOLUMIO_VERSION="2.882" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="f911710eb3a296dbaa3280d30d30bab2"