-- Logs begin at Sat 2026-01-10 19:20:49 UTC, end at Sat 2026-01-10 20:13:33 UTC. -- Jan 10 20:12:01 pfxmusic kernel: rpi_firmware_get_throttled: 6 callbacks suppressed Jan 10 20:12:01 pfxmusic kernel: Voltage normalised (0x00000000) Jan 10 20:12:03 pfxmusic kernel: rpi_firmware_get_throttled: 6 callbacks suppressed Jan 10 20:12:03 pfxmusic kernel: Under-voltage detected! (0x00050005) Jan 10 20:12:03 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 20:12:03 pfxmusic volumio[1538]: info: CURURI: music-library Jan 10 20:12:05 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 20:12:05 pfxmusic volumio[1538]: info: CURURI: music-library/NAS Jan 10 20:12:07 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 20:12:07 pfxmusic volumio[1538]: info: CURURI: music-library/NAS/truenas_music Jan 10 20:12:12 pfxmusic volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 20:12:12 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 10 20:12:12 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 20:12:12 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 20:12:12 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 20:12:12 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 20:12:12 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 20:12:12 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 20:12:12 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 20:12:12 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 20:12:12 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 20:12:12 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 10 20:12:12 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 10 20:12:26 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 20:12:26 pfxmusic volumio[1538]: info: CURURI: music-library/NAS/truenas_music Jan 10 20:12:30 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 20:12:30 pfxmusic volumio[1538]: info: CURURI: albums:// Jan 10 20:12:30 pfxmusic volumio[1538]: listAlbums - loading Albums from cache Jan 10 20:12:39 pfxmusic volumio[1538]: info: VolumeController::SetAlsaVolume80 Jan 10 20:12:39 pfxmusic volumio[1538]: info: CoreStateMachine::pushState Jan 10 20:12:39 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:12:39 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushState Jan 10 20:12:39 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:12:39 pfxmusic volumio[1538]: info: VolumeController::SetAlsaVolume85 Jan 10 20:12:39 pfxmusic volumio[1538]: info: CoreStateMachine::pushState Jan 10 20:12:39 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:12:40 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushState Jan 10 20:12:40 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:12:40 pfxmusic volumio[1538]: info: VolumeController::SetAlsaVolume90 Jan 10 20:12:40 pfxmusic volumio[1538]: info: CoreStateMachine::pushState Jan 10 20:12:40 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:12:40 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushState Jan 10 20:12:40 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:12:40 pfxmusic volumio[1538]: info: VolumeController::SetAlsaVolume85 Jan 10 20:12:40 pfxmusic volumio[1538]: info: CoreStateMachine::pushState Jan 10 20:12:40 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:12:40 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushState Jan 10 20:12:40 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:13:07 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 20:13:07 pfxmusic volumio[1538]: info: CURURI: artists:// Jan 10 20:13:12 pfxmusic dhcpcd[956]: wlan0: carrier lost Jan 10 20:13:12 pfxmusic dhcpcd[956]: wlan0: deleting host route to 192.168.100.46 via 127.0.0.1 Jan 10 20:13:12 pfxmusic wpa_supplicant[902]: wlan0: Associated with 78:8a:20:dd:90:f1 Jan 10 20:13:12 pfxmusic dhcpcd[956]: wlan0: deleting route to 192.168.100.0/24 Jan 10 20:13:12 pfxmusic dhcpcd[956]: wlan0: deleting default route via 192.168.100.1 Jan 10 20:13:12 pfxmusic avahi-daemon[661]: Withdrawing address record for 192.168.100.46 on wlan0. Jan 10 20:13:12 pfxmusic avahi-daemon[661]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.46. Jan 10 20:13:12 pfxmusic avahi-daemon[661]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 10 20:13:12 pfxmusic volumio[1538]: info: mDNS: A device disapperared from network Jan 10 20:13:12 pfxmusic volumio[1538]: info: mDNS: Device pfxmusic disapperared from network Jan 10 20:13:13 pfxmusic wpa_supplicant[902]: wlan0: WPA: Key negotiation completed with 78:8a:20:dd:90:f1 [PTK=CCMP GTK=CCMP] Jan 10 20:13:13 pfxmusic wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to 78:8a:20:dd:90:f1 completed [id=0 id_str=] Jan 10 20:13:13 pfxmusic dhcpcd[956]: wlan0: carrier acquired Jan 10 20:13:13 pfxmusic dhcpcd[956]: wlan0: rebinding lease of 192.168.100.46 Jan 10 20:13:13 pfxmusic dhcpcd[956]: wlan0: leased 192.168.100.46 for 86400 seconds Jan 10 20:13:13 pfxmusic avahi-daemon[661]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.46. Jan 10 20:13:13 pfxmusic avahi-daemon[661]: New relevant interface wlan0.IPv4 for mDNS. Jan 10 20:13:13 pfxmusic avahi-daemon[661]: Registering new address record for 192.168.100.46 on wlan0.IPv4. Jan 10 20:13:13 pfxmusic dhcpcd[956]: wlan0: adding host route to 192.168.100.46 via 127.0.0.1 Jan 10 20:13:13 pfxmusic dhcpcd[956]: wlan0: adding route to 192.168.100.0/24 Jan 10 20:13:13 pfxmusic dhcpcd[956]: wlan0: adding default route via 192.168.100.1 Jan 10 20:13:14 pfxmusic volumio[1538]: Discovery: adding 73a0d1d1-119b-4dff-ab08-edbd80f1dc3d Jan 10 20:13:14 pfxmusic volumio[1538]: info: mDNS: Found device pfxmusic Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioGetState Jan 10 20:13:14 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreStateMachine::ClearQueue Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreStateMachine::stop Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 20:13:14 pfxmusic volumio[1538]: UNSET VOLATILE Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreStateMachine::stPlaybackTimer Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreStateMachine::updateTrackBlock Jan 10 20:13:14 pfxmusic volumio[1538]: info: CorePlayQueue::getTrackBlock Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreStateMachine::pushState Jan 10 20:13:14 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushState Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreStateMachine::serviceStop Jan 10 20:13:14 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreCommandRouter::serviceStop Jan 10 20:13:14 pfxmusic volumio[1538]: info: ControllerMpd::stop Jan 10 20:13:14 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 20:13:14 pfxmusic volumio[1538]: info: CorePlayQueue::clearPlayQueue Jan 10 20:13:14 pfxmusic volumio[1538]: info: CorePlayQueue::saveQueue Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushState Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushQueue Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreStateMachine::addQueueItems Jan 10 20:13:14 pfxmusic volumio[1538]: info: CorePlayQueue::addQueueItems Jan 10 20:13:14 pfxmusic volumio[1538]: info: Adding Item to queue: artists://%20Ward%2021 Jan 10 20:13:14 pfxmusic volumio[1538]: info: Exploding uri artists://%20Ward%2021 in service mpd Jan 10 20:13:14 pfxmusic volumio[1538]: info: CorePlayQueue::saveQueue Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushQueue Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreStateMachine::updateTrackBlock Jan 10 20:13:14 pfxmusic volumio[1538]: info: CorePlayQueue::getTrackBlock Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPlay Jan 10 20:13:14 pfxmusic volumio[1538]: UNSET VOLATILE Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreStateMachine::play index 0 Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreStateMachine::stop Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 20:13:14 pfxmusic volumio[1538]: UNSET VOLATILE Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreStateMachine::play index undefined Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 20:13:14 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:14 pfxmusic volumio[1538]: info: CoreStateMachine::startPlaybackTimer Jan 10 20:13:14 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:14 pfxmusic volumio[1538]: verbose: ControllerMpd::clearAddPlayTracks NAS/truenas_music/Various - The Rae/08 - Ward 21 - Here I Come.flac Jan 10 20:13:14 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 20:13:16 pfxmusic volumio[1538]: info: Jan 10 20:13:16 pfxmusic volumio[1538]: ---------------------------- MPD announces state update: player Jan 10 20:13:16 pfxmusic volumio[1538]: info: sendMpdCommand stop took 1922 milliseconds Jan 10 20:13:16 pfxmusic volumio[1538]: info: ControllerMpd::getState Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 20:13:16 pfxmusic volumio[1538]: info: sendMpdCommand stop took 1864 milliseconds Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 20:13:16 pfxmusic volumio[1538]: info: Jan 10 20:13:16 pfxmusic volumio[1538]: ---------------------------- MPD announces system playlist update Jan 10 20:13:16 pfxmusic volumio[1538]: info: Ignoring MPD Status Update Jan 10 20:13:16 pfxmusic volumio[1538]: info: sendMpdCommand status took 9 milliseconds Jan 10 20:13:16 pfxmusic volumio[1538]: info: sendMpdCommand clear took 8 milliseconds Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: ControllerMpd::parseState Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand add "NAS/truenas_music/Various - The Rae/08 - Ward 21 - Here I Come.flac" Jan 10 20:13:16 pfxmusic volumio[1538]: error: updateQueue error: null Jan 10 20:13:16 pfxmusic volumio[1538]: info: Jan 10 20:13:16 pfxmusic volumio[1538]: ---------------------------- MPD announces system playlist update Jan 10 20:13:16 pfxmusic volumio[1538]: info: Ignoring MPD Status Update Jan 10 20:13:16 pfxmusic volumio[1538]: info: ------------------------------ 20ms Jan 10 20:13:16 pfxmusic volumio[1538]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 10 20:13:16 pfxmusic volumio[1538]: info: sendMpdCommand add "NAS/truenas_music/Various - The Rae/08 - Ward 21 - Here I Come.flac" took 14 milliseconds Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: ControllerMpd::parseTrackInfo Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand play Jan 10 20:13:16 pfxmusic volumio[1538]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 10 20:13:16 pfxmusic volumio[1538]: info: ------------------------------ 39ms Jan 10 20:13:16 pfxmusic volumio[1538]: info: ------------------------------ 14ms Jan 10 20:13:16 pfxmusic volumio[1538]: info: sendMpdCommand play took 8 milliseconds Jan 10 20:13:16 pfxmusic volumio[1538]: info: Jan 10 20:13:16 pfxmusic volumio[1538]: ---------------------------- MPD announces state update: player Jan 10 20:13:16 pfxmusic volumio[1538]: info: ControllerMpd::getState Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 20:13:16 pfxmusic volumio[1538]: info: Jan 10 20:13:16 pfxmusic volumio[1538]: ---------------------------- MPD announces state update: player Jan 10 20:13:16 pfxmusic volumio[1538]: info: ControllerMpd::getState Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 20:13:16 pfxmusic volumio[1538]: info: sendMpdCommand status took 7 milliseconds Jan 10 20:13:16 pfxmusic volumio[1538]: info: sendMpdCommand status took 4 milliseconds Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: ControllerMpd::parseState Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: ControllerMpd::parseState Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 20:13:16 pfxmusic volumio[1538]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 10 20:13:16 pfxmusic volumio[1538]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: ControllerMpd::parseTrackInfo Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: ControllerMpd::parseTrackInfo Jan 10 20:13:16 pfxmusic volumio[1538]: info: ControllerMpd::pushState Jan 10 20:13:16 pfxmusic volumio[1538]: info: CoreCommandRouter::servicePushState Jan 10 20:13:16 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":212,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"470 Kbps","isStreaming":false,"title":"Here I Come","artist":" Ward 21","album":"The Rae","uri":"NAS/truenas_music/Various - The Rae/08 - Ward 21 - Here I Come.flac","trackType":"flac"} Jan 10 20:13:16 pfxmusic volumio[1538]: verbose: CURRENT POSITION 0 Jan 10 20:13:16 pfxmusic volumio[1538]: info: CoreStateMachine::syncState stateService play Jan 10 20:13:16 pfxmusic volumio[1538]: info: CoreStateMachine::syncState currentStatus stop Jan 10 20:13:16 pfxmusic volumio[1538]: info: CoreStateMachine::pushState Jan 10 20:13:16 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:16 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushState Jan 10 20:13:16 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:13:16 pfxmusic volumio[1538]: info: ControllerMpd::pushState Jan 10 20:13:17 pfxmusic volumio[1538]: info: CoreCommandRouter::servicePushState Jan 10 20:13:17 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:17 pfxmusic volumio[1538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":212,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"636 Kbps","isStreaming":false,"title":"Here I Come","artist":" Ward 21","album":"The Rae","uri":"NAS/truenas_music/Various - The Rae/08 - Ward 21 - Here I Come.flac","trackType":"flac"} Jan 10 20:13:17 pfxmusic volumio[1538]: verbose: CURRENT POSITION 0 Jan 10 20:13:17 pfxmusic volumio[1538]: info: CoreStateMachine::syncState stateService play Jan 10 20:13:17 pfxmusic volumio[1538]: info: CoreStateMachine::syncState currentStatus play Jan 10 20:13:17 pfxmusic volumio[1538]: info: Received an update from plugin. extracting info from payload Jan 10 20:13:17 pfxmusic volumio[1538]: info: CoreStateMachine::pushState Jan 10 20:13:17 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:17 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushState Jan 10 20:13:17 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:13:17 pfxmusic volumio[1538]: info: CoreStateMachine::pushState Jan 10 20:13:17 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:17 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushState Jan 10 20:13:17 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:13:17 pfxmusic volumio[1538]: info: ------------------------------ 87ms Jan 10 20:13:17 pfxmusic volumio[1538]: info: ------------------------------ 85ms Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreStateMachine::ClearQueue Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreStateMachine::stop Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 20:13:28 pfxmusic volumio[1538]: UNSET VOLATILE Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreStateMachine::stPlaybackTimer Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreStateMachine::updateTrackBlock Jan 10 20:13:28 pfxmusic volumio[1538]: info: CorePlayQueue::getTrackBlock Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreStateMachine::pushState Jan 10 20:13:28 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushState Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreStateMachine::serviceStop Jan 10 20:13:28 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreCommandRouter::serviceStop Jan 10 20:13:28 pfxmusic volumio[1538]: info: ControllerMpd::stop Jan 10 20:13:28 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 20:13:28 pfxmusic volumio[1538]: info: CorePlayQueue::clearPlayQueue Jan 10 20:13:28 pfxmusic volumio[1538]: info: CorePlayQueue::saveQueue Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushState Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushQueue Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreStateMachine::addQueueItems Jan 10 20:13:28 pfxmusic volumio[1538]: info: CorePlayQueue::addQueueItems Jan 10 20:13:28 pfxmusic volumio[1538]: info: Adding Item to queue: artists://BUJU%20BANTON Jan 10 20:13:28 pfxmusic volumio[1538]: info: Exploding uri artists://BUJU%20BANTON in service mpd Jan 10 20:13:28 pfxmusic volumio[1538]: info: CorePlayQueue::saveQueue Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushQueue Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreStateMachine::updateTrackBlock Jan 10 20:13:28 pfxmusic volumio[1538]: info: CorePlayQueue::getTrackBlock Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPlay Jan 10 20:13:28 pfxmusic volumio[1538]: UNSET VOLATILE Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreStateMachine::play index 0 Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreStateMachine::stop Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 20:13:28 pfxmusic volumio[1538]: UNSET VOLATILE Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreStateMachine::play index undefined Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 20:13:28 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:28 pfxmusic volumio[1538]: info: CoreStateMachine::startPlaybackTimer Jan 10 20:13:28 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:28 pfxmusic volumio[1538]: verbose: ControllerMpd::clearAddPlayTracks NAS/truenas_music/various - Bogle/06 - BUJU BANTON - Bogle (Remix).mp3 Jan 10 20:13:28 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 20:13:28 pfxmusic volumio[1538]: info: Jan 10 20:13:28 pfxmusic volumio[1538]: ---------------------------- MPD announces state update: player Jan 10 20:13:28 pfxmusic volumio[1538]: info: sendMpdCommand stop took 79 milliseconds Jan 10 20:13:28 pfxmusic volumio[1538]: info: ControllerMpd::getState Jan 10 20:13:28 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 20:13:28 pfxmusic volumio[1538]: info: sendMpdCommand stop took 18 milliseconds Jan 10 20:13:28 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 20:13:28 pfxmusic volumio[1538]: info: Jan 10 20:13:28 pfxmusic volumio[1538]: ---------------------------- MPD announces system playlist update Jan 10 20:13:28 pfxmusic volumio[1538]: info: Ignoring MPD Status Update Jan 10 20:13:28 pfxmusic volumio[1538]: info: sendMpdCommand status took 20 milliseconds Jan 10 20:13:28 pfxmusic volumio[1538]: info: sendMpdCommand clear took 19 milliseconds Jan 10 20:13:28 pfxmusic volumio[1538]: verbose: ControllerMpd::parseState Jan 10 20:13:28 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 20:13:28 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand add "NAS/truenas_music/various - Bogle/06 - BUJU BANTON - Bogle (Remix).mp3" Jan 10 20:13:28 pfxmusic volumio[1538]: error: updateQueue error: null Jan 10 20:13:28 pfxmusic volumio[1538]: info: Jan 10 20:13:28 pfxmusic volumio[1538]: ---------------------------- MPD announces system playlist update Jan 10 20:13:28 pfxmusic volumio[1538]: info: Ignoring MPD Status Update Jan 10 20:13:28 pfxmusic volumio[1538]: info: ------------------------------ 23ms Jan 10 20:13:28 pfxmusic volumio[1538]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 10 20:13:28 pfxmusic volumio[1538]: info: sendMpdCommand add "NAS/truenas_music/various - Bogle/06 - BUJU BANTON - Bogle (Remix).mp3" took 7 milliseconds Jan 10 20:13:28 pfxmusic volumio[1538]: verbose: ControllerMpd::parseTrackInfo Jan 10 20:13:28 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand play Jan 10 20:13:28 pfxmusic volumio[1538]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 10 20:13:28 pfxmusic volumio[1538]: info: ------------------------------ 40ms Jan 10 20:13:28 pfxmusic volumio[1538]: info: ------------------------------ 18ms Jan 10 20:13:28 pfxmusic volumio[1538]: info: sendMpdCommand play took 14 milliseconds Jan 10 20:13:29 pfxmusic volumio[1538]: info: Jan 10 20:13:29 pfxmusic volumio[1538]: ---------------------------- MPD announces state update: player Jan 10 20:13:29 pfxmusic volumio[1538]: info: ControllerMpd::getState Jan 10 20:13:29 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 20:13:29 pfxmusic volumio[1538]: info: Jan 10 20:13:29 pfxmusic volumio[1538]: ---------------------------- MPD announces state update: player Jan 10 20:13:29 pfxmusic volumio[1538]: info: ControllerMpd::getState Jan 10 20:13:29 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 20:13:29 pfxmusic volumio[1538]: info: sendMpdCommand status took 31 milliseconds Jan 10 20:13:29 pfxmusic volumio[1538]: verbose: ControllerMpd::parseState Jan 10 20:13:29 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 20:13:29 pfxmusic volumio[1538]: info: sendMpdCommand status took 10 milliseconds Jan 10 20:13:29 pfxmusic volumio[1538]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 10 20:13:29 pfxmusic volumio[1538]: verbose: ControllerMpd::parseState Jan 10 20:13:29 pfxmusic volumio[1538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 20:13:29 pfxmusic volumio[1538]: verbose: ControllerMpd::parseTrackInfo Jan 10 20:13:29 pfxmusic volumio[1538]: info: ControllerMpd::pushState Jan 10 20:13:29 pfxmusic volumio[1538]: info: CoreCommandRouter::servicePushState Jan 10 20:13:29 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:29 pfxmusic volumio[1538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":222,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bogle (Remix)","artist":"BUJU BANTON","album":"Bogle","uri":"NAS/truenas_music/various - Bogle/06 - BUJU BANTON - Bogle (Remix).mp3","trackType":"mp3"} Jan 10 20:13:29 pfxmusic volumio[1538]: verbose: CURRENT POSITION 0 Jan 10 20:13:29 pfxmusic volumio[1538]: info: CoreStateMachine::syncState stateService play Jan 10 20:13:29 pfxmusic volumio[1538]: info: CoreStateMachine::syncState currentStatus stop Jan 10 20:13:29 pfxmusic volumio[1538]: info: CoreStateMachine::pushState Jan 10 20:13:29 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:29 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushState Jan 10 20:13:29 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:13:29 pfxmusic volumio[1538]: info: ------------------------------ 74ms Jan 10 20:13:29 pfxmusic volumio[1538]: info: sendMpdCommand playlistinfo took 28 milliseconds Jan 10 20:13:29 pfxmusic volumio[1538]: verbose: ControllerMpd::parseTrackInfo Jan 10 20:13:29 pfxmusic volumio[1538]: info: ControllerMpd::pushState Jan 10 20:13:29 pfxmusic volumio[1538]: info: CoreCommandRouter::servicePushState Jan 10 20:13:29 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:29 pfxmusic volumio[1538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":222,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bogle (Remix)","artist":"BUJU BANTON","album":"Bogle","uri":"NAS/truenas_music/various - Bogle/06 - BUJU BANTON - Bogle (Remix).mp3","trackType":"mp3"} Jan 10 20:13:29 pfxmusic volumio[1538]: verbose: CURRENT POSITION 0 Jan 10 20:13:29 pfxmusic volumio[1538]: info: CoreStateMachine::syncState stateService play Jan 10 20:13:29 pfxmusic volumio[1538]: info: CoreStateMachine::syncState currentStatus play Jan 10 20:13:29 pfxmusic volumio[1538]: info: Received an update from plugin. extracting info from payload Jan 10 20:13:29 pfxmusic volumio[1538]: info: CoreStateMachine::pushState Jan 10 20:13:29 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:29 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushState Jan 10 20:13:29 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:13:29 pfxmusic volumio[1538]: info: CoreStateMachine::pushState Jan 10 20:13:29 pfxmusic volumio[1538]: info: CorePlayQueue::getTrack 0 Jan 10 20:13:29 pfxmusic volumio[1538]: info: CoreCommandRouter::volumioPushState Jan 10 20:13:29 pfxmusic volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:13:29 pfxmusic volumio[1538]: info: ------------------------------ 86ms Jan 10 20:13:31 pfxmusic volumio[1538]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 20:13:31 pfxmusic volumio[1538]: TypeError: Cannot read property 'slice' of null Jan 10 20:13:31 pfxmusic volumio[1538]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:714:41) Jan 10 20:13:31 pfxmusic volumio[1538]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:724:7) Jan 10 20:13:31 pfxmusic volumio[1538]: at Object.onceWrapper (events.js:315:30) Jan 10 20:13:31 pfxmusic volumio[1538]: at emitOne (events.js:116:13) Jan 10 20:13:31 pfxmusic volumio[1538]: at ClientRequest.emit (events.js:211:7) Jan 10 20:13:31 pfxmusic volumio[1538]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:551:21) Jan 10 20:13:31 pfxmusic volumio[1538]: at HTTPParser.parserOnHeadersComplete (_http_common.js:115:23) Jan 10 20:13:31 pfxmusic volumio[1538]: at TLSSocket.socketOnData (_http_client.js:440:20) Jan 10 20:13:31 pfxmusic volumio[1538]: at emitOne (events.js:116:13) Jan 10 20:13:31 pfxmusic volumio[1538]: at TLSSocket.emit (events.js:211:7) Jan 10 20:13:31 pfxmusic volumio[1538]: at addChunk (_stream_readable.js:263:12) Jan 10 20:13:31 pfxmusic volumio[1538]: at readableAddChunk (_stream_readable.js:250:11) Jan 10 20:13:31 pfxmusic volumio[1538]: at TLSSocket.Readable.push (_stream_readable.js:208:10) Jan 10 20:13:31 pfxmusic volumio[1538]: at TLSWrap.onread (net.js:607:20) Jan 10 20:13:31 pfxmusic volumio[1538]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 20:13:33 pfxmusic sudo[8811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-10 20:12 Jan 10 20:13:33 pfxmusic sudo[8811]: 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="cd11dc738d0d82822d0aa776d0729686b82e3758" VOLUMIO_FE_VERSION="77db3ca2a7f5a7023ece8c5b86e997d58abdace6" VOLUMIO_BE_VERSION="748352701ebc6ec2b59443fcac1ec9aea1f276df" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Jul 2 12:45:33 CEST 2021" VOLUMIO_VERSION="2.907" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="858e764292794952fe9c14f4c1868f5a"