-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Sun 2025-05-04 19:04:28 CST. -- May 04 19:03:02 volumio wpa_supplicant[874]: wlan0: Failed to initiate sched scan May 04 19:03:11 volumio wpa_supplicant[874]: wlan0: Failed to initiate sched scan May 04 19:03:11 volumio volumio[995]: info: Clearing queue after UPNP request May 04 19:03:12 volumio volumio[995]: info: CoreStateMachine::ClearQueue May 04 19:03:12 volumio volumio[995]: info: CoreStateMachine::stop May 04 19:03:12 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 19:03:12 volumio volumio[995]: info: CorePlayQueue::clearPlayQueue May 04 19:03:12 volumio volumio[995]: info: CorePlayQueue::saveQueue May 04 19:03:12 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:12 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: error: updateQueue error: null May 04 19:03:12 volumio volumio[995]: error: updateQueue error: null May 04 19:03:12 volumio volumio[995]: error: updateQueue error: null May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 4ms May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 3ms May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 2ms May 04 19:03:12 volumio volumio[995]: info: Starting UPNP Playback May 04 19:03:12 volumio volumio[995]: info: Preparing playback through UPNP May 04 19:03:12 volumio volumio[995]: info: CoreCommandRouter::volumioGetState May 04 19:03:12 volumio volumio[995]: info: CorePlayQueue::getTrack 0 May 04 19:03:12 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 4ms May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 3ms May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 2ms May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 3ms May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 3ms May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 3ms May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 4ms May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 3ms May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 2ms May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 3ms May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 2ms May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 1ms May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 3ms May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 3ms May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 2ms May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: May 04 19:03:12 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:12 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 4ms May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 3ms May 04 19:03:12 volumio volumio[995]: info: ------------------------------ 2ms May 04 19:03:13 volumio volumio[995]: info: May 04 19:03:13 volumio volumio[995]: ---------------------------- MPD announces state update: player May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::getState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status May 04 19:03:13 volumio volumio[995]: info: May 04 19:03:13 volumio volumio[995]: ---------------------------- MPD announces state update: player May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::getState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status May 04 19:03:13 volumio volumio[995]: info: May 04 19:03:13 volumio volumio[995]: ---------------------------- MPD announces state update: player May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::getState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status May 04 19:03:13 volumio volumio[995]: info: May 04 19:03:13 volumio volumio[995]: ---------------------------- MPD announces state update: player May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand status took 40 milliseconds May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::getState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand status took 41 milliseconds May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand status took 39 milliseconds May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand status took 2 milliseconds May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand playlistinfo took 2 milliseconds May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::servicePushState May 04 19:03:13 volumio volumio[995]: verbose: In UPNP mode May 04 19:03:13 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":149,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Moment","artist":"Able Heart","album":"This Moment","uri":"http://aqqmusic.tc.qq.com/F000003dbzJz4QT6jX.flac?guid=000000004ee6317100000194f337c7bf&vkey=681871964BC3BAB31CD42E92656AFBFE72B2517698FA968FEA044E2EE367A7251AFA497A65ADB6329EB9C8058E329FB9FFB931F92641EDA6__v21e2a171d&uin=88340707&redirect=1&fromtag=111114","trackType":""} May 04 19:03:13 volumio volumio[995]: verbose: CURRENT POSITION 0 May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState stateService play May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop May 04 19:03:13 volumio volumio[995]: info: ------------------------------ 48ms May 04 19:03:13 volumio volumio[995]: info: May 04 19:03:13 volumio volumio[995]: ---------------------------- MPD announces state update: player May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::getState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status May 04 19:03:13 volumio volumio[995]: info: May 04 19:03:13 volumio volumio[995]: ---------------------------- MPD announces state update: player May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::getState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand playlistinfo took 7 milliseconds May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand playlistinfo took 7 milliseconds May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand playlistinfo took 7 milliseconds May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand status took 3 milliseconds May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand status took 2 milliseconds May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::servicePushState May 04 19:03:13 volumio volumio[995]: verbose: In UPNP mode May 04 19:03:13 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":149,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Moment","artist":"Able Heart","album":"This Moment","uri":"http://aqqmusic.tc.qq.com/F000003dbzJz4QT6jX.flac?guid=000000004ee6317100000194f337c7bf&vkey=681871964BC3BAB31CD42E92656AFBFE72B2517698FA968FEA044E2EE367A7251AFA497A65ADB6329EB9C8058E329FB9FFB931F92641EDA6__v21e2a171d&uin=88340707&redirect=1&fromtag=111114","trackType":""} May 04 19:03:13 volumio volumio[995]: verbose: CURRENT POSITION 0 May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState stateService play May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play May 04 19:03:13 volumio volumio[995]: info: Received an update from plugin. extracting info from payload May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::servicePushState May 04 19:03:13 volumio volumio[995]: verbose: In UPNP mode May 04 19:03:13 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":149,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Moment","artist":"Able Heart","album":"This Moment","uri":"http://aqqmusic.tc.qq.com/F000003dbzJz4QT6jX.flac?guid=000000004ee6317100000194f337c7bf&vkey=681871964BC3BAB31CD42E92656AFBFE72B2517698FA968FEA044E2EE367A7251AFA497A65ADB6329EB9C8058E329FB9FFB931F92641EDA6__v21e2a171d&uin=88340707&redirect=1&fromtag=111114","trackType":""} May 04 19:03:13 volumio volumio[995]: verbose: CURRENT POSITION 0 May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState stateService play May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play May 04 19:03:13 volumio volumio[995]: info: Received an update from plugin. extracting info from payload May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::servicePushState May 04 19:03:13 volumio volumio[995]: verbose: In UPNP mode May 04 19:03:13 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":149,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Moment","artist":"Able Heart","album":"This Moment","uri":"http://aqqmusic.tc.qq.com/F000003dbzJz4QT6jX.flac?guid=000000004ee6317100000194f337c7bf&vkey=681871964BC3BAB31CD42E92656AFBFE72B2517698FA968FEA044E2EE367A7251AFA497A65ADB6329EB9C8058E329FB9FFB931F92641EDA6__v21e2a171d&uin=88340707&redirect=1&fromtag=111114","trackType":""} May 04 19:03:13 volumio volumio[995]: verbose: CURRENT POSITION 0 May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState stateService play May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play May 04 19:03:13 volumio volumio[995]: info: Received an update from plugin. extracting info from payload May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:13 volumio volumio[995]: info: ------------------------------ 87ms May 04 19:03:13 volumio volumio[995]: info: ------------------------------ 86ms May 04 19:03:13 volumio volumio[995]: info: ------------------------------ 50ms May 04 19:03:13 volumio volumio[995]: info: May 04 19:03:13 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:13 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:13 volumio volumio[995]: info: May 04 19:03:13 volumio volumio[995]: ---------------------------- MPD announces state update: player May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand playlistinfo took 38 milliseconds May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand playlistinfo took 37 milliseconds May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::getState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::servicePushState May 04 19:03:13 volumio volumio[995]: verbose: In UPNP mode May 04 19:03:13 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":149,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Moment","artist":"Able Heart","album":"This Moment","uri":"http://aqqmusic.tc.qq.com/F000003dbzJz4QT6jX.flac?guid=000000004ee6317100000194f337c7bf&vkey=681871964BC3BAB31CD42E92656AFBFE72B2517698FA968FEA044E2EE367A7251AFA497A65ADB6329EB9C8058E329FB9FFB931F92641EDA6__v21e2a171d&uin=88340707&redirect=1&fromtag=111114","trackType":""} May 04 19:03:13 volumio volumio[995]: verbose: CURRENT POSITION 0 May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState stateService play May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play May 04 19:03:13 volumio volumio[995]: info: Received an update from plugin. extracting info from payload May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::servicePushState May 04 19:03:13 volumio volumio[995]: verbose: In UPNP mode May 04 19:03:13 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":149,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Moment","artist":"Able Heart","album":"This Moment","uri":"http://aqqmusic.tc.qq.com/F000003dbzJz4QT6jX.flac?guid=000000004ee6317100000194f337c7bf&vkey=681871964BC3BAB31CD42E92656AFBFE72B2517698FA968FEA044E2EE367A7251AFA497A65ADB6329EB9C8058E329FB9FFB931F92641EDA6__v21e2a171d&uin=88340707&redirect=1&fromtag=111114","trackType":""} May 04 19:03:13 volumio volumio[995]: verbose: CURRENT POSITION 0 May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState stateService play May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play May 04 19:03:13 volumio volumio[995]: info: Received an update from plugin. extracting info from payload May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:13 volumio volumio[995]: info: ------------------------------ 67ms May 04 19:03:13 volumio volumio[995]: info: ------------------------------ 67ms May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::getQueue May 04 19:03:13 volumio volumio[995]: info: CorePlayQueue::getQueue May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::getQueue May 04 19:03:13 volumio volumio[995]: info: CorePlayQueue::getQueue May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::getQueue May 04 19:03:13 volumio volumio[995]: info: CorePlayQueue::getQueue May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::getQueue May 04 19:03:13 volumio volumio[995]: info: CorePlayQueue::getQueue May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::getQueue May 04 19:03:13 volumio volumio[995]: info: CorePlayQueue::getQueue May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::getQueue May 04 19:03:13 volumio volumio[995]: info: CorePlayQueue::getQueue May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::getQueue May 04 19:03:13 volumio volumio[995]: info: CorePlayQueue::getQueue May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::getQueue May 04 19:03:13 volumio volumio[995]: info: CorePlayQueue::getQueue May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::getQueue May 04 19:03:13 volumio volumio[995]: info: CorePlayQueue::getQueue May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::getQueue May 04 19:03:13 volumio volumio[995]: info: CorePlayQueue::getQueue May 04 19:03:13 volumio volumio[995]: info: May 04 19:03:13 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:13 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:13 volumio volumio[995]: info: May 04 19:03:13 volumio volumio[995]: ---------------------------- MPD announces state update: player May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::getState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status May 04 19:03:13 volumio volumio[995]: info: May 04 19:03:13 volumio volumio[995]: ---------------------------- MPD announces system playlist update May 04 19:03:13 volumio volumio[995]: info: Ignoring MPD Status Update May 04 19:03:13 volumio volumio[995]: info: May 04 19:03:13 volumio volumio[995]: ---------------------------- MPD announces state update: player May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::getState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status May 04 19:03:13 volumio volumio[995]: info: ------------------------------ 49ms May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand status took 48 milliseconds May 04 19:03:13 volumio volumio[995]: info: ------------------------------ 6ms May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand status took 6 milliseconds May 04 19:03:13 volumio volumio[995]: info: ------------------------------ 5ms May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand status took 5 milliseconds May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseState May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand playlistinfo took 4 milliseconds May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand playlistinfo took 4 milliseconds May 04 19:03:13 volumio volumio[995]: info: sendMpdCommand playlistinfo took 3 milliseconds May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo May 04 19:03:13 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::servicePushState May 04 19:03:13 volumio volumio[995]: verbose: In UPNP mode May 04 19:03:13 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":149,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1593 Kbps","isStreaming":false,"title":"This Moment","artist":"Able Heart","album":"This Moment","uri":"http://aqqmusic.tc.qq.com/F000003dbzJz4QT6jX.flac?guid=000000004ee6317100000194f337c7bf&vkey=681871964BC3BAB31CD42E92656AFBFE72B2517698FA968FEA044E2EE367A7251AFA497A65ADB6329EB9C8058E329FB9FFB931F92641EDA6__v21e2a171d&uin=88340707&redirect=1&fromtag=111114","trackType":""} May 04 19:03:13 volumio volumio[995]: verbose: CURRENT POSITION 0 May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState stateService play May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play May 04 19:03:13 volumio volumio[995]: info: Received an update from plugin. extracting info from payload May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::servicePushState May 04 19:03:13 volumio volumio[995]: verbose: In UPNP mode May 04 19:03:13 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":149,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1659 Kbps","isStreaming":false,"title":"This Moment","artist":"Able Heart","album":"This Moment","uri":"http://aqqmusic.tc.qq.com/F000003dbzJz4QT6jX.flac?guid=000000004ee6317100000194f337c7bf&vkey=681871964BC3BAB31CD42E92656AFBFE72B2517698FA968FEA044E2EE367A7251AFA497A65ADB6329EB9C8058E329FB9FFB931F92641EDA6__v21e2a171d&uin=88340707&redirect=1&fromtag=111114","trackType":""} May 04 19:03:13 volumio volumio[995]: verbose: CURRENT POSITION 0 May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState stateService play May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play May 04 19:03:13 volumio volumio[995]: info: Received an update from plugin. extracting info from payload May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:13 volumio volumio[995]: info: ControllerMpd::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::servicePushState May 04 19:03:13 volumio volumio[995]: verbose: In UPNP mode May 04 19:03:13 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":149,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1659 Kbps","isStreaming":false,"title":"This Moment","artist":"Able Heart","album":"This Moment","uri":"http://aqqmusic.tc.qq.com/F000003dbzJz4QT6jX.flac?guid=000000004ee6317100000194f337c7bf&vkey=681871964BC3BAB31CD42E92656AFBFE72B2517698FA968FEA044E2EE367A7251AFA497A65ADB6329EB9C8058E329FB9FFB931F92641EDA6__v21e2a171d&uin=88340707&redirect=1&fromtag=111114","trackType":""} May 04 19:03:13 volumio volumio[995]: verbose: CURRENT POSITION 0 May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState stateService play May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play May 04 19:03:13 volumio volumio[995]: info: Received an update from plugin. extracting info from payload May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::pushState May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioPushState May 04 19:03:13 volumio volumio[995]: info: ------------------------------ 99ms May 04 19:03:13 volumio volumio[995]: info: ------------------------------ 56ms May 04 19:03:13 volumio volumio[995]: info: ------------------------------ 55ms May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::getQueue May 04 19:03:13 volumio volumio[995]: info: CorePlayQueue::getQueue May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::getQueue May 04 19:03:13 volumio volumio[995]: info: CorePlayQueue::getQueue May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::getQueue May 04 19:03:13 volumio volumio[995]: info: CorePlayQueue::getQueue May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::getQueue May 04 19:03:13 volumio volumio[995]: info: CorePlayQueue::getQueue May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::getQueue May 04 19:03:13 volumio volumio[995]: info: CorePlayQueue::getQueue May 04 19:03:13 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue May 04 19:03:13 volumio volumio[995]: info: CoreStateMachine::getQueue May 04 19:03:13 volumio volumio[995]: info: CorePlayQueue::getQueue May 04 19:03:19 volumio wpa_supplicant[874]: wlan0: Failed to initiate sched scan May 04 19:03:28 volumio wpa_supplicant[874]: wlan0: Failed to initiate sched scan May 04 19:03:36 volumio wpa_supplicant[874]: wlan0: Failed to initiate sched scan May 04 19:03:45 volumio wpa_supplicant[874]: wlan0: Failed to initiate sched scan May 04 19:03:53 volumio wpa_supplicant[874]: wlan0: Failed to initiate sched scan May 04 19:04:01 volumio wpa_supplicant[874]: wlan0: Failed to initiate sched scan May 04 19:04:10 volumio wpa_supplicant[874]: wlan0: Failed to initiate sched scan May 04 19:04:18 volumio wpa_supplicant[874]: wlan0: Failed to initiate sched scan May 04 19:04:27 volumio wpa_supplicant[874]: wlan0: Failed to initiate sched scan May 04 19:04:27 volumio volumio[995]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 19:04:27 volumio volumio[995]: Error: connect ETIMEDOUT 31.13.95.34:80 May 04 19:04:27 volumio volumio[995]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 04 19:04:27 volumio volumio[995]: errno: -110, May 04 19:04:27 volumio volumio[995]: code: 'ETIMEDOUT', May 04 19:04:27 volumio volumio[995]: syscall: 'connect', May 04 19:04:27 volumio volumio[995]: address: '31.13.95.34', May 04 19:04:27 volumio volumio[995]: port: 80 May 04 19:04:27 volumio volumio[995]: } May 04 19:04:27 volumio volumio[995]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 19:04:28 volumio sudo[1970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-04 19:03 May 04 19:04:28 volumio sudo[1970]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"