-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Thu 2025-09-11 15:00:41 +07. -- Sep 11 14:59:00 volumio go-librespot[1481]: time="2025-09-11T14:59:00+07:00" level=trace msg="received accesspoint ping" Sep 11 14:59:00 volumio go-librespot[1481]: time="2025-09-11T14:59:00+07:00" level=trace msg="received accesspoint pong ack" Sep 11 14:59:00 volumio go-librespot[1481]: time="2025-09-11T14:59:00+07:00" level=trace msg="sent dealer ping" Sep 11 14:59:00 volumio go-librespot[1481]: time="2025-09-11T14:59:00+07:00" level=trace msg="received dealer pong" Sep 11 14:59:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 11 14:59:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 11 14:59:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 11 14:59:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 11 14:59:05 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 14:59:05 volumio volumio[1144]: info: CURURI: genres://Jazz Sep 11 14:59:05 volumio volumio[1144]: info: Preload queue cleared Sep 11 14:59:06 volumio wpa_supplicant[924]: wlan0: Failed to initiate sched scan Sep 11 14:59:14 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 14:59:14 volumio volumio[1144]: info: CURURI: genres://Nhac%20tre Sep 11 14:59:14 volumio volumio[1144]: info: Preload queue cleared Sep 11 14:59:14 volumio volumio[1144]: info: Preload queue cleared Sep 11 14:59:15 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 11 14:59:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 11 14:59:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 11 14:59:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 11 14:59:18 volumio wpa_supplicant[924]: wlan0: Failed to initiate sched scan Sep 11 14:59:22 volumio ntpd[829]: Soliciting pool server 2401:5b60:0:2::21 Sep 11 14:59:27 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 11 14:59:27 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 11 14:59:27 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 11 14:59:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 11 14:59:28 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 14:59:28 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 14:59:28 volumio volumio[1144]: info: Discovery: Getting this device information Sep 11 14:59:28 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Sep 11 14:59:28 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:28 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 14:59:28 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 14:59:28 volumio volumio[1144]: info: CURURI: genres://Other Sep 11 14:59:29 volumio volumio[1144]: info: Preload queue cleared Sep 11 14:59:29 volumio volumio[1144]: info: Preload queue cleared Sep 11 14:59:30 volumio wpa_supplicant[924]: wlan0: Failed to initiate sched scan Sep 11 14:59:30 volumio go-librespot[1481]: time="2025-09-11T14:59:30+07:00" level=trace msg="sent dealer ping" Sep 11 14:59:30 volumio go-librespot[1481]: time="2025-09-11T14:59:30+07:00" level=trace msg="received dealer pong" Sep 11 14:59:33 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 14:59:33 volumio volumio[1144]: info: CURURI: genres://Other/VINHSTUDIO%20-%20Kho%20nhac%20Lossless%20RIP%20tu%20CD%20www.phimhd1080.com Sep 11 14:59:33 volumio volumio[1144]: info: Preload queue cleared Sep 11 14:59:33 volumio volumio[1144]: info: Preload queue cleared Sep 11 14:59:33 volumio volumio[1144]: info: Preloading song: music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/01 - SONATINA IMPROVVISATA D'INIZIO ESTATE.dsf Sep 11 14:59:33 volumio volumio[1144]: info: Preloading song: music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/02 - AI GIOCHI ADDIO (GIULIETTA E ROMEO).dsf Sep 11 14:59:33 volumio volumio[1144]: info: Preloading song: music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/03 - O SOLE MIO.dsf Sep 11 14:59:33 volumio volumio[1144]: info: Preloading song: music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/04 - LIBERTANGO.dsf Sep 11 14:59:33 volumio volumio[1144]: info: Preloading song: music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/05 - IMAGINE.dsf Sep 11 14:59:33 volumio volumio[1144]: info: Preloading song: music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/06 - BLACK OR WHITE.dsf Sep 11 14:59:33 volumio volumio[1144]: info: Preloading song: music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/07 - AMAZING GRACE.dsf Sep 11 14:59:33 volumio volumio[1144]: info: Preloading song: music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/08 - LA PRINCIPESSA.dsf Sep 11 14:59:33 volumio volumio[1144]: info: Preloading song: music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/09 - SOMEWHERE OVER THE RAINBOW.dsf Sep 11 14:59:33 volumio volumio[1144]: info: Exploding uri music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/01 - SONATINA IMPROVVISATA D'INIZIO ESTATE.dsf in service mpd Sep 11 14:59:33 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=628&web=Fausto%20Mesolella/Fausto%20Mesolella%20-%20Live%20Ad%20Alcatraz/extralarge&path=%2Fmnt%2FNAS%2FNAS%2F2TB%2FMUSIC%2FCHON%20LOC%20QUOC%20TE%2FFausto%20Mesolella%20-%20Live%20Ad%20Alcatraz%2F01%20-%20SONATINA%20IMPROVVISATA%20D'INIZIO%20ESTATE.dsf&metadata=false Sep 11 14:59:33 volumio volumio[1144]: info: URI /mnt/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/01 - SONATINA IMPROVVISATA D'INIZIO ESTATE.dsf Sep 11 14:59:33 volumio volumio[1144]: info: Exploding uri music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/02 - AI GIOCHI ADDIO (GIULIETTA E ROMEO).dsf in service mpd Sep 11 14:59:33 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=628&web=Fausto%20Mesolella/Fausto%20Mesolella%20-%20Live%20Ad%20Alcatraz/extralarge&path=%2Fmnt%2FNAS%2FNAS%2F2TB%2FMUSIC%2FCHON%20LOC%20QUOC%20TE%2FFausto%20Mesolella%20-%20Live%20Ad%20Alcatraz%2F02%20-%20AI%20GIOCHI%20ADDIO%20(GIULIETTA%20E%20ROMEO).dsf&metadata=false Sep 11 14:59:33 volumio volumio[1144]: info: URI /mnt/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/02 - AI GIOCHI ADDIO (GIULIETTA E ROMEO).dsf Sep 11 14:59:33 volumio volumio[1144]: info: Exploding uri music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/03 - O SOLE MIO.dsf in service mpd Sep 11 14:59:33 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=628&web=Fausto%20Mesolella/Fausto%20Mesolella%20-%20Live%20Ad%20Alcatraz/extralarge&path=%2Fmnt%2FNAS%2FNAS%2F2TB%2FMUSIC%2FCHON%20LOC%20QUOC%20TE%2FFausto%20Mesolella%20-%20Live%20Ad%20Alcatraz%2F03%20-%20O%20SOLE%20MIO.dsf&metadata=false Sep 11 14:59:33 volumio volumio[1144]: info: URI /mnt/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/03 - O SOLE MIO.dsf Sep 11 14:59:33 volumio volumio[1144]: info: Exploding uri music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/04 - LIBERTANGO.dsf in service mpd Sep 11 14:59:33 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=628&web=Fausto%20Mesolella/Fausto%20Mesolella%20-%20Live%20Ad%20Alcatraz/extralarge&path=%2Fmnt%2FNAS%2FNAS%2F2TB%2FMUSIC%2FCHON%20LOC%20QUOC%20TE%2FFausto%20Mesolella%20-%20Live%20Ad%20Alcatraz%2F04%20-%20LIBERTANGO.dsf&metadata=false Sep 11 14:59:33 volumio volumio[1144]: info: URI /mnt/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/04 - LIBERTANGO.dsf Sep 11 14:59:33 volumio volumio[1144]: info: No valid Plugin REST Endpoint Sep 11 14:59:33 volumio volumio[1144]: info: No valid Plugin REST Endpoint Sep 11 14:59:33 volumio volumio[1144]: info: No valid Plugin REST Endpoint Sep 11 14:59:33 volumio volumio[1144]: info: Exploding uri music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/05 - IMAGINE.dsf in service mpd Sep 11 14:59:33 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=628&web=Fausto%20Mesolella/Fausto%20Mesolella%20-%20Live%20Ad%20Alcatraz/extralarge&path=%2Fmnt%2FNAS%2FNAS%2F2TB%2FMUSIC%2FCHON%20LOC%20QUOC%20TE%2FFausto%20Mesolella%20-%20Live%20Ad%20Alcatraz%2F05%20-%20IMAGINE.dsf&metadata=false Sep 11 14:59:33 volumio volumio[1144]: info: URI /mnt/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/05 - IMAGINE.dsf Sep 11 14:59:33 volumio volumio[1144]: info: Exploding uri music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/06 - BLACK OR WHITE.dsf in service mpd Sep 11 14:59:33 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=628&web=Fausto%20Mesolella/Fausto%20Mesolella%20-%20Live%20Ad%20Alcatraz/extralarge&path=%2Fmnt%2FNAS%2FNAS%2F2TB%2FMUSIC%2FCHON%20LOC%20QUOC%20TE%2FFausto%20Mesolella%20-%20Live%20Ad%20Alcatraz%2F06%20-%20BLACK%20OR%20WHITE.dsf&metadata=false Sep 11 14:59:33 volumio volumio[1144]: info: URI /mnt/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/06 - BLACK OR WHITE.dsf Sep 11 14:59:33 volumio volumio[1144]: info: Exploding uri music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/07 - AMAZING GRACE.dsf in service mpd Sep 11 14:59:33 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=628&web=Fausto%20Mesolella/Fausto%20Mesolella%20-%20Live%20Ad%20Alcatraz/extralarge&path=%2Fmnt%2FNAS%2FNAS%2F2TB%2FMUSIC%2FCHON%20LOC%20QUOC%20TE%2FFausto%20Mesolella%20-%20Live%20Ad%20Alcatraz%2F07%20-%20AMAZING%20GRACE.dsf&metadata=false Sep 11 14:59:33 volumio volumio[1144]: info: URI /mnt/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/07 - AMAZING GRACE.dsf Sep 11 14:59:33 volumio volumio[1144]: info: Exploding uri music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/08 - LA PRINCIPESSA.dsf in service mpd Sep 11 14:59:33 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=628&web=Fausto%20Mesolella/Fausto%20Mesolella%20-%20Live%20Ad%20Alcatraz/extralarge&path=%2Fmnt%2FNAS%2FNAS%2F2TB%2FMUSIC%2FCHON%20LOC%20QUOC%20TE%2FFausto%20Mesolella%20-%20Live%20Ad%20Alcatraz%2F08%20-%20LA%20PRINCIPESSA.dsf&metadata=false Sep 11 14:59:33 volumio volumio[1144]: info: URI /mnt/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/08 - LA PRINCIPESSA.dsf Sep 11 14:59:33 volumio volumio[1144]: info: Exploding uri music-library/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/09 - SOMEWHERE OVER THE RAINBOW.dsf in service mpd Sep 11 14:59:33 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=628&web=Fausto%20Mesolella/Fausto%20Mesolella%20-%20Live%20Ad%20Alcatraz/extralarge&path=%2Fmnt%2FNAS%2FNAS%2F2TB%2FMUSIC%2FCHON%20LOC%20QUOC%20TE%2FFausto%20Mesolella%20-%20Live%20Ad%20Alcatraz%2F09%20-%20SOMEWHERE%20OVER%20THE%20RAINBOW.dsf&metadata=false Sep 11 14:59:33 volumio volumio[1144]: info: URI /mnt/NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/09 - SOMEWHERE OVER THE RAINBOW.dsf Sep 11 14:59:39 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 11 14:59:39 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 11 14:59:39 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 11 14:59:39 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 11 14:59:41 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 14:59:41 volumio volumio[1144]: info: CURURI: genres://Other/VINHSTUDIO%20-%20Kho%20nhac%20Lossless%20RIP%20tu%20CD%20www.phimhd1080.com/Fausto%20Mesolella%20-%20Live%20Ad%20Alcatraz Sep 11 14:59:41 volumio volumio[1144]: info: Preload queue cleared Sep 11 14:59:41 volumio volumio[1144]: info: No valid Plugin REST Endpoint Sep 11 14:59:41 volumio volumio[1144]: info: No valid Plugin REST Endpoint Sep 11 14:59:41 volumio volumio[1144]: info: No valid Plugin REST Endpoint Sep 11 14:59:42 volumio wpa_supplicant[924]: wlan0: Failed to initiate sched scan Sep 11 14:59:44 volumio volumio[1144]: info: Preload queue cleared Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::ClearQueue Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::stop Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::stPlaybackTimer Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::serviceStop Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::serviceStop Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::stop Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::clearPlayQueue Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::saveQueue Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::addQueueItems Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::addQueueItems Sep 11 14:59:44 volumio volumio[1144]: info: Preload queue cleared Sep 11 14:59:44 volumio volumio[1144]: info: Adding Item to queue: genres://Other/VINHSTUDIO%20-%20Kho%20nhac%20Lossless%20RIP%20tu%20CD%20www.phimhd1080.com/Fausto%20Mesolella%20-%20Live%20Ad%20Alcatraz Sep 11 14:59:44 volumio volumio[1144]: info: Exploding uri genres://Other/VINHSTUDIO%20-%20Kho%20nhac%20Lossless%20RIP%20tu%20CD%20www.phimhd1080.com/Fausto%20Mesolella%20-%20Live%20Ad%20Alcatraz in service mpd Sep 11 14:59:44 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::saveQueue Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPlay Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::play index 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::stop Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::play index undefined Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::startPlaybackTimer Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/01 - SONATINA IMPROVVISATA D'INIZIO ESTATE.dsf Sep 11 14:59:44 volumio volumio[1144]: info: Setting Volume to 100 automatically for DSD Sep 11 14:59:44 volumio volumio[1144]: info: VolumeController::SetAlsaVolume100 Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:44 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:44 volumio volumio[1144]: info: Sep 11 14:59:44 volumio volumio[1144]: ---------------------------- MPD announces state update: player Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand stop took 72 milliseconds Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::getState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand stop took 20 milliseconds Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clear Sep 11 14:59:44 volumio volumio[1144]: info: Sep 11 14:59:44 volumio volumio[1144]: ---------------------------- MPD announces state update: player Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::getState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Sep 11 14:59:44 volumio volumio[1144]: info: Sep 11 14:59:44 volumio volumio[1144]: ---------------------------- MPD announces state update: player Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::getState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Sep 11 14:59:44 volumio volumio[1144]: info: Sep 11 14:59:44 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Sep 11 14:59:44 volumio volumio[1144]: info: Ignoring MPD Status Update Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand status took 39 milliseconds Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand clear took 39 milliseconds Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand status took 6 milliseconds Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand status took 5 milliseconds Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::parseState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/01 - SONATINA IMPROVVISATA D'INIZIO ESTATE.dsf" Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::parseState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::parseState Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 11 14:59:44 volumio volumio[1144]: verbose: CURRENT POSITION 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::syncState stateService stop Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:44 volumio volumio[1144]: info: No code Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 11 14:59:44 volumio volumio[1144]: verbose: CURRENT POSITION 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::syncState stateService stop Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:44 volumio volumio[1144]: info: No code Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:44 volumio volumio[1144]: info: ------------------------------ 40ms Sep 11 14:59:44 volumio volumio[1144]: info: ------------------------------ 39ms Sep 11 14:59:44 volumio volumio[1144]: info: Sep 11 14:59:44 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Sep 11 14:59:44 volumio volumio[1144]: info: Ignoring MPD Status Update Sep 11 14:59:44 volumio volumio[1144]: info: Sep 11 14:59:44 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Sep 11 14:59:44 volumio volumio[1144]: info: Ignoring MPD Status Update Sep 11 14:59:44 volumio volumio[1144]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 11 14:59:44 volumio volumio[1144]: error: updateQueue error: null Sep 11 14:59:44 volumio volumio[1144]: info: Sep 11 14:59:44 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Sep 11 14:59:44 volumio volumio[1144]: info: Ignoring MPD Status Update Sep 11 14:59:44 volumio volumio[1144]: info: ------------------------------ 52ms Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 50 milliseconds Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand add "NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/01 - SONATINA IMPROVVISATA D'INIZIO ESTATE.dsf" took 50 milliseconds Sep 11 14:59:44 volumio volumio[1144]: info: ------------------------------ 15ms Sep 11 14:59:44 volumio volumio[1144]: info: ------------------------------ 14ms Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand play Sep 11 14:59:44 volumio volumio[1144]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 11 14:59:44 volumio volumio[1144]: info: ------------------------------ 94ms Sep 11 14:59:44 volumio volumio[1144]: info: Sep 11 14:59:44 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Sep 11 14:59:44 volumio volumio[1144]: info: Ignoring MPD Status Update Sep 11 14:59:44 volumio volumio[1144]: info: Sep 11 14:59:44 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Sep 11 14:59:44 volumio volumio[1144]: info: Ignoring MPD Status Update Sep 11 14:59:44 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:44 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:44 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:44 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:44 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:44 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:44 volumio volumio[1144]: info: Sep 11 14:59:44 volumio volumio[1144]: ---------------------------- MPD announces state update: player Sep 11 14:59:44 volumio volumio[1144]: info: ------------------------------ 80ms Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand play took 78 milliseconds Sep 11 14:59:44 volumio volumio[1144]: info: ------------------------------ 49ms Sep 11 14:59:44 volumio volumio[1144]: info: ------------------------------ 47ms Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::getState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Sep 11 14:59:44 volumio volumio[1144]: info: Sep 11 14:59:44 volumio volumio[1144]: ---------------------------- MPD announces state update: player Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::getState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Sep 11 14:59:44 volumio volumio[1144]: info: Sep 11 14:59:44 volumio volumio[1144]: ---------------------------- MPD announces state update: player Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::getState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Sep 11 14:59:44 volumio volumio[1144]: info: Sep 11 14:59:44 volumio volumio[1144]: ---------------------------- MPD announces state update: player Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::getState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand status took 14 milliseconds Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand status took 2 milliseconds Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand status took 1 milliseconds Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::parseState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::parseState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::parseState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 14:59:44 volumio volumio[1144]: info: Sep 11 14:59:44 volumio volumio[1144]: ---------------------------- MPD announces state update: player Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::getState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Sep 11 14:59:44 volumio volumio[1144]: info: Sep 11 14:59:44 volumio volumio[1144]: ---------------------------- MPD announces state update: player Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::getState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand status took 4 milliseconds Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand status took 2 milliseconds Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand status took 2 milliseconds Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::parseState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::parseState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::parseState Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":80,"duration":305,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"SONATINA IMPROVVISATA D'INIZIO ESTATE*****","artist":"Fausto Mesolella","album":"Fausto Mesolella - Live Ad Alcatraz","uri":"NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/01 - SONATINA IMPROVVISATA D'INIZIO ESTATE.dsf","trackType":"dsf"} Sep 11 14:59:44 volumio volumio[1144]: verbose: CURRENT POSITION 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":142,"duration":305,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"SONATINA IMPROVVISATA D'INIZIO ESTATE*****","artist":"Fausto Mesolella","album":"Fausto Mesolella - Live Ad Alcatraz","uri":"NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/01 - SONATINA IMPROVVISATA D'INIZIO ESTATE.dsf","trackType":"dsf"} Sep 11 14:59:44 volumio volumio[1144]: verbose: CURRENT POSITION 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Sep 11 14:59:44 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":148,"duration":305,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"SONATINA IMPROVVISATA D'INIZIO ESTATE*****","artist":"Fausto Mesolella","album":"Fausto Mesolella - Live Ad Alcatraz","uri":"NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/01 - SONATINA IMPROVVISATA D'INIZIO ESTATE.dsf","trackType":"dsf"} Sep 11 14:59:44 volumio volumio[1144]: verbose: CURRENT POSITION 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Sep 11 14:59:44 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:44 volumio volumio[1144]: info: ------------------------------ 29ms Sep 11 14:59:44 volumio volumio[1144]: info: ------------------------------ 28ms Sep 11 14:59:44 volumio volumio[1144]: info: ------------------------------ 28ms Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 26 milliseconds Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 26 milliseconds Sep 11 14:59:44 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 27 milliseconds Sep 11 14:59:44 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":154,"duration":305,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"SONATINA IMPROVVISATA D'INIZIO ESTATE*****","artist":"Fausto Mesolella","album":"Fausto Mesolella - Live Ad Alcatraz","uri":"NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/01 - SONATINA IMPROVVISATA D'INIZIO ESTATE.dsf","trackType":"dsf"} Sep 11 14:59:44 volumio volumio[1144]: verbose: CURRENT POSITION 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Sep 11 14:59:44 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:44 volumio volumio[1144]: info: ControllerMpd::pushState Sep 11 14:59:44 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Sep 11 14:59:44 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:44 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":171,"duration":305,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"SONATINA IMPROVVISATA D'INIZIO ESTATE*****","artist":"Fausto Mesolella","album":"Fausto Mesolella - Live Ad Alcatraz","uri":"NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/01 - SONATINA IMPROVVISATA D'INIZIO ESTATE.dsf","trackType":"dsf"} Sep 11 14:59:44 volumio volumio[1144]: verbose: CURRENT POSITION 0 Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Sep 11 14:59:44 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Sep 11 14:59:44 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:45 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:45 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 14:59:45 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:45 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:45 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:45 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:45 volumio volumio[1144]: info: ControllerMpd::pushState Sep 11 14:59:45 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Sep 11 14:59:45 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:45 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":177,"duration":305,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"SONATINA IMPROVVISATA D'INIZIO ESTATE*****","artist":"Fausto Mesolella","album":"Fausto Mesolella - Live Ad Alcatraz","uri":"NAS/NAS/2TB/MUSIC/CHON LOC QUOC TE/Fausto Mesolella - Live Ad Alcatraz/01 - SONATINA IMPROVVISATA D'INIZIO ESTATE.dsf","trackType":"dsf"} Sep 11 14:59:45 volumio volumio[1144]: verbose: CURRENT POSITION 0 Sep 11 14:59:45 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Sep 11 14:59:45 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Sep 11 14:59:45 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Sep 11 14:59:45 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:45 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:45 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 14:59:45 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:45 volumio volumio[1144]: info: CoreStateMachine::pushState Sep 11 14:59:45 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:45 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Sep 11 14:59:45 volumio volumio[1144]: info: ------------------------------ 64ms Sep 11 14:59:45 volumio volumio[1144]: info: ------------------------------ 61ms Sep 11 14:59:45 volumio volumio[1144]: info: ------------------------------ 61ms Sep 11 14:59:45 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:45 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:45 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:45 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:45 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:45 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:45 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:45 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:45 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:45 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 11 14:59:51 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 11 14:59:51 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 11 14:59:51 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 11 14:59:51 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 11 14:59:53 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 14:59:53 volumio volumio[1144]: info: CURURI: genres://Pop Sep 11 14:59:53 volumio volumio[1144]: info: Preload queue cleared Sep 11 14:59:54 volumio wpa_supplicant[924]: wlan0: Failed to initiate sched scan Sep 11 14:59:58 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 14:59:58 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 14:59:58 volumio volumio[1144]: info: Discovery: Getting this device information Sep 11 14:59:58 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Sep 11 14:59:58 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 14:59:58 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 15:00:00 volumio go-librespot[1481]: time="2025-09-11T15:00:00+07:00" level=trace msg="sent dealer ping" Sep 11 15:00:00 volumio go-librespot[1481]: time="2025-09-11T15:00:00+07:00" level=trace msg="received dealer pong" Sep 11 15:00:01 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 15:00:01 volumio volumio[1144]: info: CURURI: genres://Tru%20Tinh Sep 11 15:00:01 volumio volumio[1144]: info: Preload queue cleared Sep 11 15:00:01 volumio volumio[1144]: info: Preload queue cleared Sep 11 15:00:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 11 15:00:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 11 15:00:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 11 15:00:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 11 15:00:05 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 15:00:05 volumio volumio[1144]: info: CURURI: genres://Tru%20tinh Sep 11 15:00:05 volumio volumio[1144]: info: Preload queue cleared Sep 11 15:00:05 volumio volumio[1144]: info: Preload queue cleared Sep 11 15:00:05 volumio wpa_supplicant[924]: wlan0: Failed to initiate sched scan Sep 11 15:00:12 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 15:00:12 volumio volumio[1144]: info: CURURI: genres://Vocal Sep 11 15:00:12 volumio volumio[1144]: info: Preload queue cleared Sep 11 15:00:12 volumio volumio[1144]: info: Preload queue cleared Sep 11 15:00:15 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 11 15:00:15 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 11 15:00:15 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 11 15:00:15 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 11 15:00:17 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 15:00:17 volumio volumio[1144]: info: CURURI: genres://chinese%20vocal Sep 11 15:00:17 volumio volumio[1144]: info: Preload queue cleared Sep 11 15:00:17 volumio wpa_supplicant[924]: wlan0: Failed to initiate sched scan Sep 11 15:00:20 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 15:00:20 volumio volumio[1144]: info: CURURI: genres://audiophile%20cd Sep 11 15:00:20 volumio volumio[1144]: info: Preload queue cleared Sep 11 15:00:20 volumio volumio[1144]: info: Preload queue cleared Sep 11 15:00:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 11 15:00:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 11 15:00:27 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 11 15:00:27 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 11 15:00:28 volumio ntpd[829]: Soliciting pool server 2401:5b60:0:1::52 Sep 11 15:00:28 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 15:00:28 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 15:00:28 volumio volumio[1144]: info: Discovery: Getting this device information Sep 11 15:00:28 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Sep 11 15:00:28 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Sep 11 15:00:28 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 15:00:29 volumio wpa_supplicant[924]: wlan0: Failed to initiate sched scan Sep 11 15:00:30 volumio go-librespot[1481]: time="2025-09-11T15:00:30+07:00" level=trace msg="sent dealer ping" Sep 11 15:00:30 volumio go-librespot[1481]: time="2025-09-11T15:00:30+07:00" level=trace msg="received dealer pong" Sep 11 15:00:35 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 11 15:00:35 volumio volumio[1144]: info: Preload queue cleared Sep 11 15:00:38 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 11 15:00:38 volumio volumio[1144]: info: Preload queue cleared Sep 11 15:00:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 11 15:00:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 11 15:00:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 11 15:00:39 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 11 15:00:41 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 11 15:00:41 volumio volumio[1144]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 15:00:41 volumio volumio[1144]: TypeError: Cannot read property 'length' of undefined Sep 11 15:00:41 volumio volumio[1144]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Sep 11 15:00:41 volumio volumio[1144]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Sep 11 15:00:41 volumio volumio[1144]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Sep 11 15:00:41 volumio volumio[1144]: at Parser.emit (events.js:315:20) Sep 11 15:00:41 volumio volumio[1144]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Sep 11 15:00:41 volumio volumio[1144]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Sep 11 15:00:41 volumio volumio[1144]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Sep 11 15:00:41 volumio volumio[1144]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Sep 11 15:00:41 volumio volumio[1144]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Sep 11 15:00:41 volumio volumio[1144]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Sep 11 15:00:41 volumio volumio[1144]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Sep 11 15:00:41 volumio volumio[1144]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Sep 11 15:00:41 volumio volumio[1144]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Sep 11 15:00:41 volumio volumio[1144]: at IncomingMessage.emit (events.js:327:22) Sep 11 15:00:41 volumio volumio[1144]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 11 15:00:41 volumio volumio[1144]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 11 15:00:41 volumio volumio[1144]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 15:00:41 volumio wpa_supplicant[924]: wlan0: Failed to initiate sched scan Sep 11 15:00:41 volumio sudo[13104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-11 14:59 Sep 11 15:00:41 volumio sudo[13104]: 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"