-- Logs begin at Fri 2025-05-23 05:21:00 UTC, end at Fri 2025-05-23 06:21:59 UTC. -- May 23 06:20:00 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 06:20:00 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 06:20:00 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 23 06:20:00 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 23 06:20:00 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 23 06:20:00 volumio volumio[17133]: info: CoreCommandRouter::volumioGetBrowseSources May 23 06:20:00 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 23 06:20:00 volumio volumio[17133]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 23 06:20:00 volumio volumio[17133]: info: Completed starting MyVolumio Plugin May 23 06:20:00 volumio volumio[17133]: [Metrics] CommandRouter: 33s 264.80ms May 23 06:20:00 volumio volumio[17133]: info: CoreCommandRouter::volumiosetStartupVolume May 23 06:20:00 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 06:20:00 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 06:20:00 volumio volumio[17133]: info: CoreCommandRouter::Close All Modals sent May 23 06:20:00 volumio volumio[17133]: info: CoreCommandRouter::Close All Modals sent May 23 06:20:01 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 23 06:20:01 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 06:20:01 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 23 06:20:01 volumio volumio[17133]: info: Clearing queue after UPNP request May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::ClearQueue May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::stop May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 06:20:02 volumio volumio[17133]: info: CorePlayQueue::clearPlayQueue May 23 06:20:02 volumio volumio[17133]: info: CorePlayQueue::saveQueue May 23 06:20:02 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:02 volumio volumio[17133]: info: CoreCommandRouter::volumioPushQueue May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: error: updateQueue error: null May 23 06:20:02 volumio volumio[17133]: error: updateQueue error: null May 23 06:20:02 volumio volumio[17133]: error: updateQueue error: null May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 4ms May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 3ms May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 3ms May 23 06:20:02 volumio volumio[17133]: info: Starting UPNP Playback May 23 06:20:02 volumio volumio[17133]: info: Preparing playback through UPNP May 23 06:20:02 volumio volumio[17133]: info: CoreCommandRouter::volumioGetState May 23 06:20:02 volumio volumio[17133]: info: CorePlayQueue::getTrack 0 May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 4ms May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 2ms May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 3ms May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 2ms May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 3ms May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 5ms May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 2ms May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 1ms May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 2ms May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 2ms May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 2ms May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 4ms May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 3ms May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 2ms May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:02 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 3ms May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 4ms May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 3ms May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:02 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:02 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:02 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:02 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 06:20:02 volumio volumio[17133]: info: sendMpdCommand status took 11 milliseconds May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 06:20:02 volumio volumio[17133]: info: sendMpdCommand status took 11 milliseconds May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 06:20:02 volumio volumio[17133]: info: sendMpdCommand status took 11 milliseconds May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:02 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:02 volumio volumio[17133]: info: May 23 06:20:02 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:02 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 06:20:02 volumio volumio[17133]: info: sendMpdCommand status took 27 milliseconds May 23 06:20:02 volumio volumio[17133]: info: sendMpdCommand clearerror took 19 milliseconds May 23 06:20:02 volumio volumio[17133]: info: sendMpdCommand clearerror took 18 milliseconds May 23 06:20:02 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 11 milliseconds May 23 06:20:02 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 10 milliseconds May 23 06:20:02 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 9 milliseconds May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:02 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:02 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:02 volumio volumio[17133]: verbose: In UPNP mode May 23 06:20:02 volumio volumio[17133]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":204,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张德伊玲 - 只若初见","artist":"张德伊玲","album":null,"uri":"http://fsandroid.kugou.com/202505231420/43341943c1ab87709f394a1ae0d521ec/v3/64509a6f0ca13709d9ffaec0dd6c6e4a/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx417804925_quhigh_s4232635983.flac","trackType":"flac"} May 23 06:20:02 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::syncState stateService pause May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus stop May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:02 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:20:02 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:02 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:02 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:02 volumio volumio[17133]: verbose: In UPNP mode May 23 06:20:02 volumio volumio[17133]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":204,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张德伊玲 - 只若初见","artist":"张德伊玲","album":null,"uri":"http://fsandroid.kugou.com/202505231420/43341943c1ab87709f394a1ae0d521ec/v3/64509a6f0ca13709d9ffaec0dd6c6e4a/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx417804925_quhigh_s4232635983.flac","trackType":"flac"} May 23 06:20:02 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::syncState stateService pause May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus stop May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:02 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:02 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:02 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:02 volumio volumio[17133]: verbose: In UPNP mode May 23 06:20:02 volumio volumio[17133]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":204,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张德伊玲 - 只若初见","artist":"张德伊玲","album":null,"uri":"http://fsandroid.kugou.com/202505231420/43341943c1ab87709f394a1ae0d521ec/v3/64509a6f0ca13709d9ffaec0dd6c6e4a/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx417804925_quhigh_s4232635983.flac","trackType":"flac"} May 23 06:20:02 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::syncState stateService pause May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus stop May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:02 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 68ms May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 67ms May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 66ms May 23 06:20:02 volumio volumio[17133]: info: sendMpdCommand status took 51 milliseconds May 23 06:20:02 volumio volumio[17133]: info: sendMpdCommand status took 50 milliseconds May 23 06:20:02 volumio volumio[17133]: info: sendMpdCommand clearerror took 45 milliseconds May 23 06:20:02 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 43 milliseconds May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:02 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:02 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:02 volumio volumio[17133]: verbose: In UPNP mode May 23 06:20:02 volumio volumio[17133]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":204,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张德伊玲 - 只若初见","artist":"张德伊玲","album":null,"uri":"http://fsandroid.kugou.com/202505231420/43341943c1ab87709f394a1ae0d521ec/v3/64509a6f0ca13709d9ffaec0dd6c6e4a/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx417804925_quhigh_s4232635983.flac","trackType":"flac"} May 23 06:20:02 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::syncState stateService pause May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus stop May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:02 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 84ms May 23 06:20:02 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 13 milliseconds May 23 06:20:02 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 12 milliseconds May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:02 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:02 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:02 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:02 volumio volumio[17133]: verbose: In UPNP mode May 23 06:20:02 volumio volumio[17133]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":204,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张德伊玲 - 只若初见","artist":"张德伊玲","album":null,"uri":"http://fsandroid.kugou.com/202505231420/43341943c1ab87709f394a1ae0d521ec/v3/64509a6f0ca13709d9ffaec0dd6c6e4a/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx417804925_quhigh_s4232635983.flac","trackType":"flac"} May 23 06:20:02 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::syncState stateService pause May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus stop May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:02 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:02 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:02 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:02 volumio volumio[17133]: verbose: In UPNP mode May 23 06:20:02 volumio volumio[17133]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":204,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张德伊玲 - 只若初见","artist":"张德伊玲","album":null,"uri":"http://fsandroid.kugou.com/202505231420/43341943c1ab87709f394a1ae0d521ec/v3/64509a6f0ca13709d9ffaec0dd6c6e4a/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx417804925_quhigh_s4232635983.flac","trackType":"flac"} May 23 06:20:02 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::syncState stateService pause May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus stop May 23 06:20:02 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:02 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 84ms May 23 06:20:02 volumio volumio[17133]: info: ------------------------------ 83ms May 23 06:20:07 volumio volumio[17133]: info: BOOT COMPLETED May 23 06:20:10 volumio volumio[17133]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 23 06:20:10 volumio volumio[17133]: info: Retrying Login Due to Network Error May 23 06:20:16 volumio volumio[17133]: info: May 23 06:20:16 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:16 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:16 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:16 volumio volumio[17133]: info: May 23 06:20:16 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:16 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:16 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:16 volumio volumio[17133]: info: May 23 06:20:16 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:16 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:16 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:16 volumio volumio[17133]: info: sendMpdCommand status took 2 milliseconds May 23 06:20:16 volumio volumio[17133]: info: sendMpdCommand status took 1 milliseconds May 23 06:20:16 volumio volumio[17133]: info: sendMpdCommand status took 1 milliseconds May 23 06:20:16 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:16 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:16 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:16 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:16 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:16 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:16 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 06:20:16 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 06:20:16 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 2 milliseconds May 23 06:20:16 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:16 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:16 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:16 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:16 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:16 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:16 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:16 volumio volumio[17133]: info: CorePlayQueue::getTrack 0 May 23 06:20:16 volumio volumio[17133]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张德伊玲 - 只若初见","artist":"张德伊玲","album":null,"uri":"http://fsandroid.kugou.com/202505231420/43341943c1ab87709f394a1ae0d521ec/v3/64509a6f0ca13709d9ffaec0dd6c6e4a/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx417804925_quhigh_s4232635983.flac","trackType":"flac"} May 23 06:20:16 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:16 volumio volumio[17133]: info: CoreStateMachine::syncState stateService stop May 23 06:20:16 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus stop May 23 06:20:16 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:16 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:16 volumio volumio[17133]: info: No code May 23 06:20:16 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:16 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:16 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:16 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:16 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:16 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:16 volumio volumio[17133]: info: CorePlayQueue::getTrack 0 May 23 06:20:16 volumio volumio[17133]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张德伊玲 - 只若初见","artist":"张德伊玲","album":null,"uri":"http://fsandroid.kugou.com/202505231420/43341943c1ab87709f394a1ae0d521ec/v3/64509a6f0ca13709d9ffaec0dd6c6e4a/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx417804925_quhigh_s4232635983.flac","trackType":"flac"} May 23 06:20:16 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:16 volumio volumio[17133]: info: CoreStateMachine::syncState stateService stop May 23 06:20:16 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus stop May 23 06:20:16 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:16 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:16 volumio volumio[17133]: info: No code May 23 06:20:16 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:16 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:16 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:16 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:16 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:16 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:16 volumio volumio[17133]: info: CorePlayQueue::getTrack 0 May 23 06:20:16 volumio volumio[17133]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张德伊玲 - 只若初见","artist":"张德伊玲","album":null,"uri":"http://fsandroid.kugou.com/202505231420/43341943c1ab87709f394a1ae0d521ec/v3/64509a6f0ca13709d9ffaec0dd6c6e4a/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx417804925_quhigh_s4232635983.flac","trackType":"flac"} May 23 06:20:16 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:16 volumio volumio[17133]: info: CoreStateMachine::syncState stateService stop May 23 06:20:16 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus stop May 23 06:20:16 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:16 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:16 volumio volumio[17133]: info: No code May 23 06:20:16 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:16 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:16 volumio volumio[17133]: info: ------------------------------ 54ms May 23 06:20:16 volumio volumio[17133]: info: ------------------------------ 53ms May 23 06:20:16 volumio volumio[17133]: info: ------------------------------ 53ms May 23 06:20:19 volumio volumio[17133]: info: Clearing queue after UPNP request May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::ClearQueue May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::stop May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 06:20:20 volumio volumio[17133]: info: CorePlayQueue::clearPlayQueue May 23 06:20:20 volumio volumio[17133]: info: CorePlayQueue::saveQueue May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::volumioPushQueue May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: error: updateQueue error: null May 23 06:20:20 volumio volumio[17133]: error: updateQueue error: null May 23 06:20:20 volumio volumio[17133]: error: updateQueue error: null May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 6ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 4ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 3ms May 23 06:20:20 volumio volumio[17133]: info: Starting UPNP Playback May 23 06:20:20 volumio volumio[17133]: info: Preparing playback through UPNP May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::volumioGetState May 23 06:20:20 volumio volumio[17133]: info: CorePlayQueue::getTrack 0 May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 06:20:20 volumio volumio[17133]: info: MyVolumio login type: Token May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 5ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 3ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 2ms May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 6ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 4ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 4ms May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 3ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 3ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 2ms May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 5ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 4ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 4ms May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 4ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 3ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 3ms May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 3ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 2ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 1ms May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:20 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:20 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:20 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:20 volumio volumio[17133]: info: sendMpdCommand status took 6 milliseconds May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:20 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:20 volumio volumio[17133]: info: sendMpdCommand status took 10 milliseconds May 23 06:20:20 volumio volumio[17133]: info: sendMpdCommand status took 9 milliseconds May 23 06:20:20 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:20 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:20 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:20 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 10 milliseconds May 23 06:20:20 volumio volumio[17133]: info: sendMpdCommand status took 7 milliseconds May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 9ms May 23 06:20:20 volumio volumio[17133]: info: sendMpdCommand status took 6 milliseconds May 23 06:20:20 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 6 milliseconds May 23 06:20:20 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 6 milliseconds May 23 06:20:20 volumio volumio[17133]: info: sendMpdCommand status took 4 milliseconds May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:20 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:20 volumio volumio[17133]: verbose: In UPNP mode May 23 06:20:20 volumio volumio[17133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"如果你是我的传说","artist":"杨茵茵","album":"茵茵的声音","uri":"http://fsandroid.kugou.com/202505231420/9aef887e5bdfaf612fad798125b096d5/v3/1200c6abf058492474898dd146f07f20/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx56971243_quhigh_s3764945380.flac","trackType":"flac"} May 23 06:20:20 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::syncState stateService play May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus stop May 23 06:20:20 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:20 volumio volumio[17133]: verbose: In UPNP mode May 23 06:20:20 volumio volumio[17133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"如果你是我的传说","artist":"杨茵茵","album":"茵茵的声音","uri":"http://fsandroid.kugou.com/202505231420/9aef887e5bdfaf612fad798125b096d5/v3/1200c6abf058492474898dd146f07f20/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx56971243_quhigh_s3764945380.flac","trackType":"flac"} May 23 06:20:20 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::syncState stateService play May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus play May 23 06:20:20 volumio volumio[17133]: info: Received an update from plugin. extracting info from payload May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:20 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:20 volumio volumio[17133]: verbose: In UPNP mode May 23 06:20:20 volumio volumio[17133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"如果你是我的传说","artist":"杨茵茵","album":"茵茵的声音","uri":"http://fsandroid.kugou.com/202505231420/9aef887e5bdfaf612fad798125b096d5/v3/1200c6abf058492474898dd146f07f20/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx56971243_quhigh_s3764945380.flac","trackType":"flac"} May 23 06:20:20 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::syncState stateService play May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus play May 23 06:20:20 volumio volumio[17133]: info: Received an update from plugin. extracting info from payload May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 39ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 56ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 56ms May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:20 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:20:20 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:20:20 volumio volumio[17133]: info: May 23 06:20:20 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:20:20 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:20:20 volumio volumio[17133]: info: sendMpdCommand status took 48 milliseconds May 23 06:20:20 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 44 milliseconds May 23 06:20:20 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 44 milliseconds May 23 06:20:20 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 43 milliseconds May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 7ms May 23 06:20:20 volumio volumio[17133]: info: sendMpdCommand status took 6 milliseconds May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 6ms May 23 06:20:20 volumio volumio[17133]: info: sendMpdCommand status took 4 milliseconds May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:20:20 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:20:20 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:20 volumio volumio[17133]: verbose: In UPNP mode May 23 06:20:20 volumio volumio[17133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"199 Kbps","isStreaming":false,"title":"如果你是我的传说","artist":"杨茵茵","album":"茵茵的声音","uri":"http://fsandroid.kugou.com/202505231420/9aef887e5bdfaf612fad798125b096d5/v3/1200c6abf058492474898dd146f07f20/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx56971243_quhigh_s3764945380.flac","trackType":"flac"} May 23 06:20:20 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::syncState stateService play May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus play May 23 06:20:20 volumio volumio[17133]: info: Received an update from plugin. extracting info from payload May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:20 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:20 volumio volumio[17133]: verbose: In UPNP mode May 23 06:20:20 volumio volumio[17133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"199 Kbps","isStreaming":false,"title":"如果你是我的传说","artist":"杨茵茵","album":"茵茵的声音","uri":"http://fsandroid.kugou.com/202505231420/9aef887e5bdfaf612fad798125b096d5/v3/1200c6abf058492474898dd146f07f20/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx56971243_quhigh_s3764945380.flac","trackType":"flac"} May 23 06:20:20 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::syncState stateService play May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus play May 23 06:20:20 volumio volumio[17133]: info: Received an update from plugin. extracting info from payload May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:20 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:20 volumio volumio[17133]: verbose: In UPNP mode May 23 06:20:20 volumio volumio[17133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"199 Kbps","isStreaming":false,"title":"如果你是我的传说","artist":"杨茵茵","album":"茵茵的声音","uri":"http://fsandroid.kugou.com/202505231420/9aef887e5bdfaf612fad798125b096d5/v3/1200c6abf058492474898dd146f07f20/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx56971243_quhigh_s3764945380.flac","trackType":"flac"} May 23 06:20:20 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::syncState stateService play May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus play May 23 06:20:20 volumio volumio[17133]: info: Received an update from plugin. extracting info from payload May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 107ms May 23 06:20:20 volumio volumio[17133]: info: ------------------------------ 105ms May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:20 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:20 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:20 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:20 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:21 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:21 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:21 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:21 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:21 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:21 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:21 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:21 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:21 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 74 milliseconds May 23 06:20:21 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 73 milliseconds May 23 06:20:21 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 73 milliseconds May 23 06:20:21 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:21 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:21 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:20:21 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:21 volumio volumio[17133]: verbose: In UPNP mode May 23 06:20:21 volumio volumio[17133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"199 Kbps","isStreaming":false,"title":"如果你是我的传说","artist":"杨茵茵","album":"茵茵的声音","uri":"http://fsandroid.kugou.com/202505231420/9aef887e5bdfaf612fad798125b096d5/v3/1200c6abf058492474898dd146f07f20/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx56971243_quhigh_s3764945380.flac","trackType":"flac"} May 23 06:20:21 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::syncState stateService play May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus play May 23 06:20:21 volumio volumio[17133]: info: Received an update from plugin. extracting info from payload May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:21 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:21 volumio volumio[17133]: verbose: In UPNP mode May 23 06:20:21 volumio volumio[17133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"199 Kbps","isStreaming":false,"title":"如果你是我的传说","artist":"杨茵茵","album":"茵茵的声音","uri":"http://fsandroid.kugou.com/202505231420/9aef887e5bdfaf612fad798125b096d5/v3/1200c6abf058492474898dd146f07f20/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx56971243_quhigh_s3764945380.flac","trackType":"flac"} May 23 06:20:21 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::syncState stateService play May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus play May 23 06:20:21 volumio volumio[17133]: info: Received an update from plugin. extracting info from payload May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:21 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:20:21 volumio volumio[17133]: verbose: In UPNP mode May 23 06:20:21 volumio volumio[17133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"199 Kbps","isStreaming":false,"title":"如果你是我的传说","artist":"杨茵茵","album":"茵茵的声音","uri":"http://fsandroid.kugou.com/202505231420/9aef887e5bdfaf612fad798125b096d5/v3/1200c6abf058492474898dd146f07f20/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx56971243_quhigh_s3764945380.flac","trackType":"flac"} May 23 06:20:21 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::syncState stateService play May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus play May 23 06:20:21 volumio volumio[17133]: info: Received an update from plugin. extracting info from payload May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:21 volumio volumio[17133]: info: ------------------------------ 176ms May 23 06:20:21 volumio volumio[17133]: info: ------------------------------ 134ms May 23 06:20:21 volumio volumio[17133]: info: ------------------------------ 133ms May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:21 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:21 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:21 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:21 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:21 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:21 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:21 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:21 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:30 volumio volumio[2097]: 90 May 23 06:20:30 volumio volumio[2097]: % Total % Received % Xferd Average Speed Time Time Time Current May 23 06:20:30 volumio volumio[2097]: Dload Upload Total Spent Left Speed May 23 06:20:30 volumio volumio[17133]: info: May 23 06:20:30 volumio volumio[17133]: ---------------------------- Client requests Volume 90 May 23 06:20:30 volumio volumio[17133]: info: VolumeController::SetAlsaVolume90 May 23 06:20:30 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:30 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:20:30 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:30 volumio volumio[2097]: [158B blob data] May 23 06:20:30 volumio volumio[2097]: {"time":1747981230390,"response":"volume Success"}90 May 23 06:20:30 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:30 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:30 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:30 volumio volumio[2097]: 80 May 23 06:20:30 volumio volumio[2097]: % Total % Received % Xferd Average Speed Time Time Time Current May 23 06:20:30 volumio volumio[2097]: Dload Upload Total Spent Left Speed May 23 06:20:30 volumio volumio[17133]: info: May 23 06:20:30 volumio volumio[17133]: ---------------------------- Client requests Volume 80 May 23 06:20:30 volumio volumio[17133]: info: VolumeController::SetAlsaVolume80 May 23 06:20:31 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:31 volumio volumio[2097]: [158B blob data] May 23 06:20:31 volumio volumio[2097]: {"time":1747981230997,"response":"volume Success"}80 May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:31 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:31 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:31 volumio volumio[2097]: 70 May 23 06:20:31 volumio volumio[2097]: % Total % Received % Xferd Average Speed Time Time Time Current May 23 06:20:31 volumio volumio[2097]: Dload Upload Total Spent Left Speed May 23 06:20:31 volumio volumio[17133]: info: May 23 06:20:31 volumio volumio[17133]: ---------------------------- Client requests Volume 70 May 23 06:20:31 volumio volumio[17133]: info: VolumeController::SetAlsaVolume70 May 23 06:20:31 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:31 volumio volumio[2097]: [158B blob data] May 23 06:20:31 volumio volumio[2097]: {"time":1747981231330,"response":"volume Success"}70 May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:31 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:31 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:31 volumio volumio[2097]: 60 May 23 06:20:31 volumio volumio[2097]: % Total % Received % Xferd Average Speed Time Time Time Current May 23 06:20:31 volumio volumio[2097]: Dload Upload Total Spent Left Speed May 23 06:20:31 volumio volumio[17133]: info: May 23 06:20:31 volumio volumio[17133]: ---------------------------- Client requests Volume 60 May 23 06:20:31 volumio volumio[17133]: info: VolumeController::SetAlsaVolume60 May 23 06:20:31 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:31 volumio volumio[2097]: [158B blob data] May 23 06:20:31 volumio volumio[2097]: {"time":1747981231573,"response":"volume Success"}60 May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:31 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:31 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:31 volumio volumio[2097]: 50 May 23 06:20:31 volumio volumio[2097]: % Total % Received % Xferd Average Speed Time Time Time Current May 23 06:20:31 volumio volumio[2097]: Dload Upload Total Spent Left Speed May 23 06:20:31 volumio volumio[17133]: info: May 23 06:20:31 volumio volumio[17133]: ---------------------------- Client requests Volume 50 May 23 06:20:31 volumio volumio[17133]: info: VolumeController::SetAlsaVolume50 May 23 06:20:31 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:31 volumio volumio[2097]: [158B blob data] May 23 06:20:31 volumio volumio[2097]: {"time":1747981231683,"response":"volume Success"}50 May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:31 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:31 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:31 volumio volumio[2097]: 40 May 23 06:20:31 volumio volumio[2097]: % Total % Received % Xferd Average Speed Time Time Time Current May 23 06:20:31 volumio volumio[2097]: Dload Upload Total Spent Left Speed May 23 06:20:31 volumio volumio[17133]: info: May 23 06:20:31 volumio volumio[17133]: ---------------------------- Client requests Volume 40 May 23 06:20:31 volumio volumio[17133]: info: VolumeController::SetAlsaVolume40 May 23 06:20:31 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:31 volumio volumio[2097]: [158B blob data] May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:31 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:31 volumio volumio[2097]: {"time":1747981231787,"response":"volume Success"}40 May 23 06:20:31 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:31 volumio volumio[2097]: 30 May 23 06:20:31 volumio volumio[2097]: % Total % Received % Xferd Average Speed Time Time Time Current May 23 06:20:31 volumio volumio[2097]: Dload Upload Total Spent Left Speed May 23 06:20:31 volumio volumio[17133]: info: May 23 06:20:31 volumio volumio[17133]: ---------------------------- Client requests Volume 30 May 23 06:20:31 volumio volumio[17133]: info: VolumeController::SetAlsaVolume30 May 23 06:20:31 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:31 volumio volumio[2097]: [158B blob data] May 23 06:20:31 volumio volumio[2097]: {"time":1747981231885,"response":"volume Success"}30 May 23 06:20:31 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:31 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:31 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:31 volumio volumio[2097]: 20 May 23 06:20:31 volumio volumio[2097]: % Total % Received % Xferd Average Speed Time Time Time Current May 23 06:20:31 volumio volumio[2097]: Dload Upload Total Spent Left Speed May 23 06:20:31 volumio volumio[17133]: info: May 23 06:20:31 volumio volumio[17133]: ---------------------------- Client requests Volume 20 May 23 06:20:31 volumio volumio[17133]: info: VolumeController::SetAlsaVolume20 May 23 06:20:32 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:32 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:20:32 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:32 volumio volumio[2097]: [158B blob data] May 23 06:20:32 volumio volumio[2097]: {"time":1747981231997,"response":"volume Success"}20 May 23 06:20:32 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:32 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:32 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:32 volumio volumio[2097]: 10 May 23 06:20:32 volumio volumio[2097]: % Total % Received % Xferd Average Speed Time Time Time Current May 23 06:20:32 volumio volumio[2097]: Dload Upload Total Spent Left Speed May 23 06:20:32 volumio volumio[17133]: info: May 23 06:20:32 volumio volumio[17133]: ---------------------------- Client requests Volume 10 May 23 06:20:32 volumio volumio[17133]: info: VolumeController::SetAlsaVolume10 May 23 06:20:32 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:32 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:20:32 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:32 volumio volumio[2097]: [158B blob data] May 23 06:20:32 volumio volumio[2097]: {"time":1747981232099,"response":"volume Success"}10 May 23 06:20:32 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:32 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:32 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:32 volumio volumio[2097]: 0 May 23 06:20:32 volumio volumio[2097]: % Total % Received % Xferd Average Speed Time Time Time Current May 23 06:20:32 volumio volumio[2097]: Dload Upload Total Spent Left Speed May 23 06:20:32 volumio volumio[17133]: info: May 23 06:20:32 volumio volumio[17133]: ---------------------------- Client requests Volume 1 May 23 06:20:32 volumio volumio[17133]: info: VolumeController::SetAlsaVolume1 May 23 06:20:32 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:32 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:20:32 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:32 volumio volumio[2097]: [237B blob data] May 23 06:20:32 volumio volumio[2097]: {"time":1747981232186,"response":"volume Success"}1 May 23 06:20:32 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:32 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:32 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:33 volumio volumio[2097]: 11 May 23 06:20:33 volumio volumio[2097]: % Total % Received % Xferd Average Speed Time Time Time Current May 23 06:20:33 volumio volumio[2097]: Dload Upload Total Spent Left Speed May 23 06:20:33 volumio volumio[17133]: info: May 23 06:20:33 volumio volumio[17133]: ---------------------------- Client requests Volume 11 May 23 06:20:33 volumio volumio[17133]: info: VolumeController::SetAlsaVolume11 May 23 06:20:33 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:20:33 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:20:33 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:20:33 volumio volumio[2097]: [158B blob data] May 23 06:20:33 volumio volumio[2097]: {"time":1747981233365,"response":"volume Success"}11 May 23 06:20:33 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:20:33 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:20:33 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:20:50 volumio volumio[17133]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 23 06:20:50 volumio volumio[17133]: info: Retrying Login Due to Network Error May 23 06:20:53 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 06:20:53 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 06:20:53 volumio volumio[17133]: info: Discovery: Getting this device information May 23 06:20:53 volumio volumio[17133]: info: CoreCommandRouter::volumioGetState May 23 06:20:53 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 06:20:53 volumio volumio[17133]: verbose: New Socket.io Connection to 192.168.1.11:3000 from 192.168.1.9 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 May 23 06:20:53 volumio volumio[17133]: info: CoreCommandRouter::volumioGetState May 23 06:20:53 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 23 06:20:53 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 23 06:20:54 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 06:20:54 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 06:20:54 volumio volumio[17133]: info: Discovery: Getting this device information May 23 06:20:54 volumio volumio[17133]: info: CoreCommandRouter::volumioGetState May 23 06:20:54 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 06:20:54 volumio volumio[17133]: info: CoreCommandRouter::volumioGetState May 23 06:20:55 volumio volumio[17133]: verbose: New Socket.io Connection to 192.168.1.11:3000 from 192.168.1.9 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 May 23 06:20:55 volumio volumio[17133]: info: CoreCommandRouter::volumioGetState May 23 06:20:55 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 23 06:20:55 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 23 06:20:55 volumio volumio[17133]: info: CoreCommandRouter::volumioGetState May 23 06:20:55 volumio sudo[18043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 23 06:20:55 volumio sudo[18043]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 06:20:55 volumio sudo[18043]: pam_unix(sudo:session): session closed for user root May 23 06:20:55 volumio sudo[18046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 23 06:20:55 volumio sudo[18046]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 06:20:55 volumio sudo[18046]: pam_unix(sudo:session): session closed for user root May 23 06:20:55 volumio volumio[17133]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.9 UA: Mozilla/5.0 (Linux; Android 15; 2210132C Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.260 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 23 06:20:56 volumio sudo[18054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 23 06:20:56 volumio sudo[18054]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 06:20:56 volumio sudo[18054]: pam_unix(sudo:session): session closed for user root May 23 06:20:56 volumio sudo[18057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 23 06:20:56 volumio sudo[18057]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 06:20:56 volumio sudo[18057]: pam_unix(sudo:session): session closed for user root May 23 06:20:56 volumio volumio[17133]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.9 UA: Mozilla/5.0 (Linux; Android 15; 2210132C Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.260 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 23 06:20:56 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 23 06:20:56 volumio volumio[17133]: info: CoreCommandRouter::volumioGetVisibleSources May 23 06:20:56 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 06:20:56 volumio volumio[17133]: info: CoreCommandRouter::volumioGetState May 23 06:20:56 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 23 06:20:56 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 23 06:20:56 volumio volumio[17133]: info: Received Get System Info May 23 06:20:56 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 06:20:56 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 06:20:56 volumio volumio[17133]: info: Discovery: Getting this device information May 23 06:20:56 volumio volumio[17133]: info: CoreCommandRouter::volumioGetState May 23 06:20:56 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 06:20:56 volumio volumio[17133]: info: CoreCommandRouter::volumioGetState May 23 06:20:56 volumio volumio[17133]: info: Listing playlists May 23 06:20:56 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 23 06:20:56 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 23 06:20:57 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 23 06:20:58 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 06:20:58 volumio volumio[17133]: info: Received Get System Info May 23 06:20:58 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 06:20:58 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 06:20:58 volumio volumio[17133]: info: Discovery: Getting this device information May 23 06:20:58 volumio volumio[17133]: info: CoreCommandRouter::volumioGetState May 23 06:20:58 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 06:21:00 volumio volumio[17133]: info: MyVolumio login type: Token May 23 06:21:02 volumio volumio[17133]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 06:21:03 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 23 06:21:03 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 06:21:03 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 06:21:03 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 06:21:03 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 06:21:03 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 06:21:03 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 06:21:03 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 06:21:03 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 06:21:03 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 06:21:03 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 23 06:21:03 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 23 06:21:06 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 23 06:21:15 volumio volumio[17133]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] May 23 06:21:15 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions May 23 06:21:15 volumio sudo[18187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 06:21:15 volumio sudo[18187]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 06:21:15 volumio sudo[18187]: pam_unix(sudo:session): session closed for user root May 23 06:21:15 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 06:21:15 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 06:21:15 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 06:21:15 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 06:21:15 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 06:21:15 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 06:21:15 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 06:21:15 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 06:21:15 volumio sudo[18190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 06:21:15 volumio sudo[18190]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 06:21:15 volumio systemd[1]: Stopping Music Player Daemon... May 23 06:21:15 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 06:21:15 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 23 06:21:16 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 06:21:16 volumio volumio[17133]: info: MPD Permissions set May 23 06:21:16 volumio systemd[1]: mpd.service: Succeeded. May 23 06:21:16 volumio systemd[1]: Stopped Music Player Daemon. May 23 06:21:16 volumio systemd[1]: Starting Music Player Daemon... May 23 06:21:16 volumio sudo[18199]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 23 06:21:16 volumio sudo[18199]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 06:21:16 volumio sudo[18199]: pam_unix(sudo:session): session closed for user root May 23 06:21:16 volumio volumio[17133]: error: Upnp client error: Error: This socket has been ended by the other party May 23 06:21:16 volumio mpd[18201]: May 23 06:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 23 06:21:16 volumio systemd[1]: Started Music Player Daemon. May 23 06:21:16 volumio sudo[18190]: pam_unix(sudo:session): session closed for user root May 23 06:21:16 volumio volumio[17133]: error: updateQueue error: null May 23 06:21:27 volumio volumio[17133]: info: Clearing queue after UPNP request May 23 06:21:28 volumio volumio[17133]: info: CoreStateMachine::ClearQueue May 23 06:21:28 volumio volumio[17133]: info: CoreStateMachine::stop May 23 06:21:28 volumio volumio[17133]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 06:21:28 volumio volumio[17133]: info: CoreStateMachine::stPlaybackTimer May 23 06:21:28 volumio volumio[17133]: info: CoreStateMachine::updateTrackBlock May 23 06:21:28 volumio volumio[17133]: info: CorePlayQueue::getTrackBlock May 23 06:21:28 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:21:28 volumio volumio[17133]: info: CorePlayQueue::getTrack 0 May 23 06:21:28 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:21:28 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:21:28 volumio volumio[17133]: info: CoreStateMachine::serviceStop May 23 06:21:28 volumio volumio[17133]: info: CorePlayQueue::getTrack 0 May 23 06:21:28 volumio volumio[17133]: info: ControllerMpd::stop May 23 06:21:28 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand stop May 23 06:21:28 volumio volumio[17133]: info: CorePlayQueue::clearPlayQueue May 23 06:21:28 volumio volumio[17133]: info: CorePlayQueue::saveQueue May 23 06:21:28 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:21:28 volumio volumio[17133]: info: CoreCommandRouter::volumioPushQueue May 23 06:21:28 volumio volumio[17133]: info: May 23 06:21:28 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:21:28 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:21:28 volumio volumio[17133]: info: sendMpdCommand stop took 16 milliseconds May 23 06:21:28 volumio volumio[17133]: error: updateQueue error: null May 23 06:21:28 volumio volumio[17133]: info: ------------------------------ 1ms May 23 06:21:28 volumio volumio[17133]: info: Starting UPNP Playback May 23 06:21:28 volumio volumio[17133]: info: Preparing playback through UPNP May 23 06:21:28 volumio volumio[17133]: info: CoreCommandRouter::volumioGetState May 23 06:21:28 volumio volumio[17133]: info: CorePlayQueue::getTrack 0 May 23 06:21:28 volumio volumio[17133]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 06:21:28 volumio volumio[17133]: info: May 23 06:21:28 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:21:28 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:21:28 volumio volumio[17133]: info: May 23 06:21:28 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:21:28 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:21:28 volumio volumio[17133]: info: ------------------------------ 3ms May 23 06:21:28 volumio volumio[17133]: info: ------------------------------ 1ms May 23 06:21:28 volumio volumio[17133]: info: May 23 06:21:28 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:21:28 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:21:28 volumio volumio[17133]: info: ------------------------------ 1ms May 23 06:21:28 volumio volumio[17133]: info: May 23 06:21:28 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:21:28 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:21:28 volumio volumio[17133]: info: ------------------------------ 2ms May 23 06:21:28 volumio volumio[17133]: info: May 23 06:21:28 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:21:28 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:21:28 volumio volumio[17133]: info: ------------------------------ 1ms May 23 06:21:28 volumio volumio[17133]: info: May 23 06:21:28 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:21:28 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:21:28 volumio volumio[17133]: info: ------------------------------ 2ms May 23 06:21:29 volumio volumio[17133]: info: May 23 06:21:29 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:21:29 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:21:29 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:21:29 volumio volumio[17133]: info: May 23 06:21:29 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:21:29 volumio volumio[17133]: info: sendMpdCommand status took 5 milliseconds May 23 06:21:29 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:21:29 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:21:29 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:21:29 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:21:29 volumio volumio[17133]: info: sendMpdCommand status took 1 milliseconds May 23 06:21:29 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 06:21:29 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:21:29 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:21:29 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:21:29 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:21:29 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:21:29 volumio volumio[17133]: verbose: In UPNP mode May 23 06:21:29 volumio volumio[17133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"杨茵茵 - 如果你是我的传说","artist":"杨茵茵","album":null,"uri":"http://fsandroid.kugou.com/202505231421/ffe2a7130b80144d18ffd19caa9450fe/v3/1200c6abf058492474898dd146f07f20/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx56971243_quhigh_s3764945380.flac","trackType":"flac"} May 23 06:21:29 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:21:29 volumio volumio[17133]: info: CoreStateMachine::syncState stateService play May 23 06:21:29 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus stop May 23 06:21:29 volumio volumio[17133]: info: ------------------------------ 11ms May 23 06:21:29 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 3 milliseconds May 23 06:21:29 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:21:29 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:21:29 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:21:29 volumio volumio[17133]: verbose: In UPNP mode May 23 06:21:29 volumio volumio[17133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"杨茵茵 - 如果你是我的传说","artist":"杨茵茵","album":null,"uri":"http://fsandroid.kugou.com/202505231421/ffe2a7130b80144d18ffd19caa9450fe/v3/1200c6abf058492474898dd146f07f20/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx56971243_quhigh_s3764945380.flac","trackType":"flac"} May 23 06:21:29 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:21:29 volumio volumio[17133]: info: CoreStateMachine::syncState stateService play May 23 06:21:29 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus play May 23 06:21:29 volumio volumio[17133]: info: Received an update from plugin. extracting info from payload May 23 06:21:29 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 23 06:21:29 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:21:29 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:21:29 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:21:29 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:21:29 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:21:29 volumio volumio[17133]: info: ------------------------------ 33ms May 23 06:21:29 volumio volumio[17133]: info: May 23 06:21:29 volumio volumio[17133]: ---------------------------- MPD announces system playlist update May 23 06:21:29 volumio volumio[17133]: info: Ignoring MPD Status Update May 23 06:21:29 volumio volumio[17133]: info: May 23 06:21:29 volumio volumio[17133]: ---------------------------- MPD announces state update: player May 23 06:21:29 volumio volumio[17133]: info: ControllerMpd::getState May 23 06:21:29 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand status May 23 06:21:29 volumio volumio[17133]: info: ------------------------------ 6ms May 23 06:21:29 volumio volumio[17133]: info: sendMpdCommand status took 5 milliseconds May 23 06:21:29 volumio volumio[17133]: verbose: ControllerMpd::parseState May 23 06:21:29 volumio volumio[17133]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 06:21:29 volumio volumio[17133]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 06:21:29 volumio volumio[17133]: verbose: ControllerMpd::parseTrackInfo May 23 06:21:29 volumio volumio[17133]: info: ControllerMpd::pushState May 23 06:21:29 volumio volumio[17133]: info: CoreCommandRouter::servicePushState May 23 06:21:29 volumio volumio[17133]: verbose: In UPNP mode May 23 06:21:29 volumio volumio[17133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"199 Kbps","isStreaming":false,"title":"如果你是我的传说","artist":"杨茵茵","album":"茵茵的声音","uri":"http://fsandroid.kugou.com/202505231421/ffe2a7130b80144d18ffd19caa9450fe/v3/1200c6abf058492474898dd146f07f20/yp/full/ap1005_us1286330997_df1ttgzb1rpcpm3x7emn09raet_pi2_mx56971243_quhigh_s3764945380.flac","trackType":"flac"} May 23 06:21:29 volumio volumio[17133]: verbose: CURRENT POSITION 0 May 23 06:21:29 volumio volumio[17133]: info: CoreStateMachine::syncState stateService play May 23 06:21:29 volumio volumio[17133]: info: CoreStateMachine::syncState currentStatus play May 23 06:21:29 volumio volumio[17133]: info: Received an update from plugin. extracting info from payload May 23 06:21:29 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 23 06:21:29 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:21:29 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 06:21:29 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:21:29 volumio volumio[17133]: info: CoreStateMachine::pushState May 23 06:21:29 volumio volumio[17133]: info: CoreCommandRouter::volumioPushState May 23 06:21:29 volumio volumio[17133]: info: ------------------------------ 29ms May 23 06:21:29 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:21:29 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:21:29 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:21:29 volumio volumio[17133]: info: CoreCommandRouter::volumioGetQueue May 23 06:21:29 volumio volumio[17133]: info: CoreStateMachine::getQueue May 23 06:21:29 volumio volumio[17133]: info: CorePlayQueue::getQueue May 23 06:21:30 volumio volumio[17133]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 23 06:21:30 volumio volumio[17133]: info: Retrying Login Due to Network Error May 23 06:21:40 volumio volumio[17133]: info: MyVolumio login type: Token May 23 06:21:41 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 23 06:21:41 volumio volumio-remote-updater[660]: No test mode May 23 06:21:41 volumio volumio-remote-updater[660]: No alpha test mode May 23 06:21:41 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 23 06:21:42 volumio volumio[17133]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 23 06:21:42 volumio volumio[17133]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 23 06:21:59 volumio volumio[17133]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 06:21:59 volumio volumio[17133]: Error: connect ETIMEDOUT 199.59.150.13:443 May 23 06:21:59 volumio volumio[17133]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 23 06:21:59 volumio volumio[17133]: errno: -110, May 23 06:21:59 volumio volumio[17133]: code: 'ETIMEDOUT', May 23 06:21:59 volumio volumio[17133]: syscall: 'connect', May 23 06:21:59 volumio volumio[17133]: address: '199.59.150.13', May 23 06:21:59 volumio volumio[17133]: port: 443 May 23 06:21:59 volumio volumio[17133]: } May 23 06:21:59 volumio volumio[17133]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 06:21:59 volumio sudo[18456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-23 06:20 May 23 06:21:59 volumio sudo[18456]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"