-- Logs begin at Sat 2026-04-11 15:26:10 CST, end at Sat 2026-04-11 15:55:48 CST. -- Apr 11 15:54:17 volumio volumio[1038]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 11 15:54:17 volumio volumio[1038]: info: Retrying Login Due to Network Error Apr 11 15:54:24 volumio volumio[1038]: info: Apr 11 15:54:24 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:54:24 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:54:24 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:54:24 volumio volumio[1038]: info: Apr 11 15:54:24 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:54:24 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:54:24 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:54:24 volumio volumio[1038]: info: Apr 11 15:54:24 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:54:24 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:54:24 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:54:24 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Apr 11 15:54:24 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Apr 11 15:54:24 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:54:24 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:54:24 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:54:24 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:54:24 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Apr 11 15:54:24 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 15:54:24 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 15:54:24 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:54:24 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:54:24 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:54:24 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:54:24 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:54:24 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:54:24 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:54:24 volumio volumio[1038]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":41979,"duration":252,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m701.music.126.net/20260411161842/aa4acea6c4f868d59631ebec37b148a0/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953304214/974c/c6a5/2858/85aced1e34bf6ba9bd1e6dc0ec8dc27a.mp3?vuutv=5s3Itl1sV+b6M20pI8ghcqeiElyh50a/K4+9bJK3Mot7gMlu6CPbXzweR3AReqVfif10cF5KB6s0iScWbi5UrvfOKGSDsN7oo9UADgV7x28=&dlna=1","trackType":""} Apr 11 15:54:24 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:54:24 volumio volumio[1038]: info: CoreStateMachine::syncState stateService pause Apr 11 15:54:24 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:54:24 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:24 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 15:54:24 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:24 volumio volumio[1038]: info: CoreStateMachine::stPlaybackTimer Apr 11 15:54:24 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:54:24 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:54:24 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:54:24 volumio volumio[1038]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":41979,"duration":252,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m701.music.126.net/20260411161842/aa4acea6c4f868d59631ebec37b148a0/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953304214/974c/c6a5/2858/85aced1e34bf6ba9bd1e6dc0ec8dc27a.mp3?vuutv=5s3Itl1sV+b6M20pI8ghcqeiElyh50a/K4+9bJK3Mot7gMlu6CPbXzweR3AReqVfif10cF5KB6s0iScWbi5UrvfOKGSDsN7oo9UADgV7x28=&dlna=1","trackType":""} Apr 11 15:54:24 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:54:24 volumio volumio[1038]: info: CoreStateMachine::syncState stateService pause Apr 11 15:54:24 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:54:24 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:24 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:24 volumio volumio[1038]: info: CoreStateMachine::stPlaybackTimer Apr 11 15:54:24 volumio volumio[1038]: info: ------------------------------ 8ms Apr 11 15:54:24 volumio volumio[1038]: info: ------------------------------ 8ms Apr 11 15:54:24 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 11 15:54:24 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:54:24 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:54:24 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:54:24 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:54:24 volumio volumio[1038]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":41979,"duration":252,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m701.music.126.net/20260411161842/aa4acea6c4f868d59631ebec37b148a0/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953304214/974c/c6a5/2858/85aced1e34bf6ba9bd1e6dc0ec8dc27a.mp3?vuutv=5s3Itl1sV+b6M20pI8ghcqeiElyh50a/K4+9bJK3Mot7gMlu6CPbXzweR3AReqVfif10cF5KB6s0iScWbi5UrvfOKGSDsN7oo9UADgV7x28=&dlna=1","trackType":""} Apr 11 15:54:24 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:54:24 volumio volumio[1038]: info: CoreStateMachine::syncState stateService pause Apr 11 15:54:24 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:54:24 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:24 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:24 volumio volumio[1038]: info: CoreStateMachine::stPlaybackTimer Apr 11 15:54:24 volumio volumio[1038]: info: ------------------------------ 11ms Apr 11 15:54:24 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:54:24 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:54:24 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:54:24 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:54:24 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:54:24 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:54:24 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:54:24 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:54:24 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:54:27 volumio volumio[1038]: info: MyVolumio login type: Token Apr 11 15:54:37 volumio volumio[1038]: info: Clearing queue after UPNP request Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::ClearQueue Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::stop Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::stPlaybackTimer Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::updateTrackBlock Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::getTrackBlock Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::serviceStop Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:54:37 volumio volumio[1038]: info: ControllerMpd::stop Apr 11 15:54:37 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::clearPlayQueue Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::saveQueue Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::volumioPushQueue Apr 11 15:54:37 volumio volumio[1038]: info: Apr 11 15:54:37 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:54:37 volumio volumio[1038]: info: sendMpdCommand stop took 4 milliseconds Apr 11 15:54:37 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:54:37 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:54:37 volumio volumio[1038]: info: Apr 11 15:54:37 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:54:37 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:54:37 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:54:37 volumio volumio[1038]: info: Apr 11 15:54:37 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:54:37 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:54:37 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:54:37 volumio volumio[1038]: info: Apr 11 15:54:37 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:37 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:37 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Apr 11 15:54:37 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Apr 11 15:54:37 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Apr 11 15:54:37 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:54:37 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:54:37 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:54:37 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:54:37 volumio volumio[1038]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 11 15:54:37 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::syncState stateService stop Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus stop Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:37 volumio volumio[1038]: info: No code Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:37 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:54:37 volumio volumio[1038]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 11 15:54:37 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::syncState stateService stop Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus stop Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:37 volumio volumio[1038]: info: No code Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:37 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:54:37 volumio volumio[1038]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 11 15:54:37 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::syncState stateService stop Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus stop Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:37 volumio volumio[1038]: info: No code Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:37 volumio volumio[1038]: info: ------------------------------ 20ms Apr 11 15:54:37 volumio volumio[1038]: info: ------------------------------ 20ms Apr 11 15:54:37 volumio volumio[1038]: info: Apr 11 15:54:37 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:37 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:37 volumio volumio[1038]: info: Apr 11 15:54:37 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:37 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:37 volumio volumio[1038]: error: updateQueue error: null Apr 11 15:54:37 volumio volumio[1038]: info: ------------------------------ 20ms Apr 11 15:54:37 volumio volumio[1038]: error: updateQueue error: null Apr 11 15:54:37 volumio volumio[1038]: error: updateQueue error: null Apr 11 15:54:37 volumio volumio[1038]: info: ------------------------------ 4ms Apr 11 15:54:37 volumio volumio[1038]: info: ------------------------------ 4ms Apr 11 15:54:37 volumio volumio[1038]: info: Starting UPNP Playback Apr 11 15:54:37 volumio volumio[1038]: info: Preparing playback through UPNP Apr 11 15:54:37 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Apr 11 15:54:37 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:54:37 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 1ms Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 1ms Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 1ms Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 1ms Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 1ms Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:54:38 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 1ms Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 1ms Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:54:38 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:54:38 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:54:38 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:54:38 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Apr 11 15:54:38 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:54:38 volumio volumio[1038]: info: sendMpdCommand status took 2 milliseconds Apr 11 15:54:38 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Apr 11 15:54:38 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Apr 11 15:54:38 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:54:38 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:54:38 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:54:38 volumio volumio[1038]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m701.music.126.net/20260411161937/84e30faaa8bafafa31bbe77a309de9be/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953304214/974c/c6a5/2858/85aced1e34bf6ba9bd1e6dc0ec8dc27a.mp3?vuutv=VPe0YwhESFXp50VUBVKx76S0noX39OAgHto82a/wRwfYZD8Y8ZB+ZTS71VYapenbmaKL71MLaROC/f/QBdXBTZVFlvzq1F1P0boexx2LI4A=&dlna=1","trackType":""} Apr 11 15:54:38 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus stop Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 2ms Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:54:38 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:54:38 volumio volumio[1038]: info: Apr 11 15:54:38 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:54:38 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:54:38 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 15:54:38 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 15:54:38 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 15:54:38 volumio volumio[1038]: info: sendMpdCommand status took 0 milliseconds Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:54:38 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:54:38 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:54:38 volumio volumio[1038]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m701.music.126.net/20260411161937/84e30faaa8bafafa31bbe77a309de9be/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953304214/974c/c6a5/2858/85aced1e34bf6ba9bd1e6dc0ec8dc27a.mp3?vuutv=VPe0YwhESFXp50VUBVKx76S0noX39OAgHto82a/wRwfYZD8Y8ZB+ZTS71VYapenbmaKL71MLaROC/f/QBdXBTZVFlvzq1F1P0boexx2LI4A=&dlna=1","trackType":""} Apr 11 15:54:38 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:54:38 volumio volumio[1038]: info: Received an update from plugin. extracting info from payload Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:38 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:54:38 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:54:38 volumio volumio[1038]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m701.music.126.net/20260411161937/84e30faaa8bafafa31bbe77a309de9be/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953304214/974c/c6a5/2858/85aced1e34bf6ba9bd1e6dc0ec8dc27a.mp3?vuutv=VPe0YwhESFXp50VUBVKx76S0noX39OAgHto82a/wRwfYZD8Y8ZB+ZTS71VYapenbmaKL71MLaROC/f/QBdXBTZVFlvzq1F1P0boexx2LI4A=&dlna=1","trackType":""} Apr 11 15:54:38 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:54:38 volumio volumio[1038]: info: Received an update from plugin. extracting info from payload Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:38 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:54:38 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:54:38 volumio volumio[1038]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m701.music.126.net/20260411161937/84e30faaa8bafafa31bbe77a309de9be/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953304214/974c/c6a5/2858/85aced1e34bf6ba9bd1e6dc0ec8dc27a.mp3?vuutv=VPe0YwhESFXp50VUBVKx76S0noX39OAgHto82a/wRwfYZD8Y8ZB+ZTS71VYapenbmaKL71MLaROC/f/QBdXBTZVFlvzq1F1P0boexx2LI4A=&dlna=1","trackType":""} Apr 11 15:54:38 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:54:38 volumio volumio[1038]: info: Received an update from plugin. extracting info from payload Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 16ms Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 15ms Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 15ms Apr 11 15:54:38 volumio volumio[1038]: info: sendMpdCommand status took 13 milliseconds Apr 11 15:54:38 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:54:38 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:54:38 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:54:38 volumio volumio[1038]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m701.music.126.net/20260411161937/84e30faaa8bafafa31bbe77a309de9be/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953304214/974c/c6a5/2858/85aced1e34bf6ba9bd1e6dc0ec8dc27a.mp3?vuutv=VPe0YwhESFXp50VUBVKx76S0noX39OAgHto82a/wRwfYZD8Y8ZB+ZTS71VYapenbmaKL71MLaROC/f/QBdXBTZVFlvzq1F1P0boexx2LI4A=&dlna=1","trackType":""} Apr 11 15:54:38 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:54:38 volumio volumio[1038]: info: Received an update from plugin. extracting info from payload Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 20ms Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 11 15:54:38 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:54:38 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:54:38 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:54:38 volumio volumio[1038]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m701.music.126.net/20260411161937/84e30faaa8bafafa31bbe77a309de9be/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953304214/974c/c6a5/2858/85aced1e34bf6ba9bd1e6dc0ec8dc27a.mp3?vuutv=VPe0YwhESFXp50VUBVKx76S0noX39OAgHto82a/wRwfYZD8Y8ZB+ZTS71VYapenbmaKL71MLaROC/f/QBdXBTZVFlvzq1F1P0boexx2LI4A=&dlna=1","trackType":""} Apr 11 15:54:38 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:54:38 volumio volumio[1038]: info: Received an update from plugin. extracting info from payload Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:54:38 volumio volumio[1038]: info: ------------------------------ 28ms Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:54:38 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:54:38 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:54:57 volumio volumio[1038]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 11 15:54:57 volumio volumio[1038]: info: Retrying Login Due to Network Error Apr 11 15:55:00 volumio volumio[1038]: info: Apr 11 15:55:00 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:55:00 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:55:00 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:55:00 volumio volumio[1038]: info: Apr 11 15:55:00 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:55:00 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:55:00 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:55:00 volumio volumio[1038]: info: Apr 11 15:55:00 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:55:00 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:55:00 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:55:00 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Apr 11 15:55:00 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Apr 11 15:55:00 volumio volumio[1038]: info: sendMpdCommand status took 0 milliseconds Apr 11 15:55:00 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:55:00 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:55:00 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:55:00 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:55:00 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:55:00 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:55:00 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 15:55:00 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 15:55:00 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 15:55:00 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:55:00 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:55:00 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:55:00 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:55:00 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:55:00 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:55:00 volumio volumio[1038]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":23489,"duration":252,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m701.music.126.net/20260411161937/84e30faaa8bafafa31bbe77a309de9be/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953304214/974c/c6a5/2858/85aced1e34bf6ba9bd1e6dc0ec8dc27a.mp3?vuutv=VPe0YwhESFXp50VUBVKx76S0noX39OAgHto82a/wRwfYZD8Y8ZB+ZTS71VYapenbmaKL71MLaROC/f/QBdXBTZVFlvzq1F1P0boexx2LI4A=&dlna=1","trackType":""} Apr 11 15:55:00 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:55:00 volumio volumio[1038]: info: CoreStateMachine::syncState stateService pause Apr 11 15:55:00 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:55:00 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:00 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 15:55:00 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:00 volumio volumio[1038]: info: CoreStateMachine::stPlaybackTimer Apr 11 15:55:00 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:55:00 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:55:00 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:55:00 volumio volumio[1038]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":23489,"duration":252,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m701.music.126.net/20260411161937/84e30faaa8bafafa31bbe77a309de9be/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953304214/974c/c6a5/2858/85aced1e34bf6ba9bd1e6dc0ec8dc27a.mp3?vuutv=VPe0YwhESFXp50VUBVKx76S0noX39OAgHto82a/wRwfYZD8Y8ZB+ZTS71VYapenbmaKL71MLaROC/f/QBdXBTZVFlvzq1F1P0boexx2LI4A=&dlna=1","trackType":""} Apr 11 15:55:00 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:55:00 volumio volumio[1038]: info: CoreStateMachine::syncState stateService pause Apr 11 15:55:00 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:55:00 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:00 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:00 volumio volumio[1038]: info: CoreStateMachine::stPlaybackTimer Apr 11 15:55:00 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:55:00 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:55:00 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:55:00 volumio volumio[1038]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":23489,"duration":252,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m701.music.126.net/20260411161937/84e30faaa8bafafa31bbe77a309de9be/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953304214/974c/c6a5/2858/85aced1e34bf6ba9bd1e6dc0ec8dc27a.mp3?vuutv=VPe0YwhESFXp50VUBVKx76S0noX39OAgHto82a/wRwfYZD8Y8ZB+ZTS71VYapenbmaKL71MLaROC/f/QBdXBTZVFlvzq1F1P0boexx2LI4A=&dlna=1","trackType":""} Apr 11 15:55:00 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:55:00 volumio volumio[1038]: info: CoreStateMachine::syncState stateService pause Apr 11 15:55:00 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:55:00 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:00 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:00 volumio volumio[1038]: info: CoreStateMachine::stPlaybackTimer Apr 11 15:55:00 volumio volumio[1038]: info: ------------------------------ 7ms Apr 11 15:55:00 volumio volumio[1038]: info: ------------------------------ 7ms Apr 11 15:55:00 volumio volumio[1038]: info: ------------------------------ 7ms Apr 11 15:55:00 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:55:00 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:55:00 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:55:00 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:55:00 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:55:00 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:55:00 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:55:00 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:55:00 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:55:07 volumio volumio[1038]: info: MyVolumio login type: Token Apr 11 15:55:37 volumio volumio[1038]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 11 15:55:37 volumio volumio[1038]: info: Retrying Login Due to Network Error Apr 11 15:55:46 volumio volumio[1038]: info: Clearing queue after UPNP request Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::ClearQueue Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::stop Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::stPlaybackTimer Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::updateTrackBlock Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::getTrackBlock Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::serviceStop Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:55:46 volumio volumio[1038]: info: ControllerMpd::stop Apr 11 15:55:46 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::clearPlayQueue Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::saveQueue Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::volumioPushQueue Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:55:46 volumio volumio[1038]: info: sendMpdCommand stop took 6 milliseconds Apr 11 15:55:46 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:55:46 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:55:46 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:55:46 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:55:46 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:55:46 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Apr 11 15:55:46 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Apr 11 15:55:46 volumio volumio[1038]: info: sendMpdCommand status took 0 milliseconds Apr 11 15:55:46 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:55:46 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:55:46 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:55:46 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:55:46 volumio volumio[1038]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 11 15:55:46 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::syncState stateService stop Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus stop Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:46 volumio volumio[1038]: info: No code Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:46 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:55:46 volumio volumio[1038]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 11 15:55:46 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::syncState stateService stop Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus stop Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:46 volumio volumio[1038]: info: No code Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:46 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:55:46 volumio volumio[1038]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 11 15:55:46 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::syncState stateService stop Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus stop Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:46 volumio volumio[1038]: info: No code Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 15ms Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 15ms Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 15ms Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: error: updateQueue error: null Apr 11 15:55:46 volumio volumio[1038]: error: updateQueue error: null Apr 11 15:55:46 volumio volumio[1038]: error: updateQueue error: null Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 16ms Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 1ms Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 1ms Apr 11 15:55:46 volumio volumio[1038]: info: Starting UPNP Playback Apr 11 15:55:46 volumio volumio[1038]: info: Preparing playback through UPNP Apr 11 15:55:46 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Apr 11 15:55:46 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Apr 11 15:55:46 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 1ms Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 1ms Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 1ms Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 1ms Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: Apr 11 15:55:46 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Apr 11 15:55:46 volumio volumio[1038]: info: Ignoring MPD Status Update Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:55:46 volumio volumio[1038]: info: ------------------------------ 0ms Apr 11 15:55:47 volumio volumio[1038]: info: MyVolumio login type: Token Apr 11 15:55:47 volumio volumio[1038]: info: Apr 11 15:55:47 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:55:47 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:55:47 volumio volumio[1038]: info: Apr 11 15:55:47 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:55:47 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:55:47 volumio volumio[1038]: info: Apr 11 15:55:47 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:55:47 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:55:47 volumio volumio[1038]: info: sendMpdCommand status took 4 milliseconds Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:55:47 volumio volumio[1038]: info: Apr 11 15:55:47 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:55:47 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:55:47 volumio volumio[1038]: info: sendMpdCommand status took 3 milliseconds Apr 11 15:55:47 volumio volumio[1038]: info: sendMpdCommand status took 3 milliseconds Apr 11 15:55:47 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:55:47 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:55:47 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:55:47 volumio volumio[1038]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4400,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m801.music.126.net/20260411162046/6fb293c0f4a74a3645ff7153a7e11ecd/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953300913/ae04/7e91/d13d/9d1b284789290b782055173d84591653.flac?vuutv=yTBbhbtXs9BYVCwQqblGXHKi4CRaUjMlfnUAgbcXsbvxxbU5EPoiIdc6jbeGjd+Ung24UnopY4voekW0xelImnEmdwBGMjEcGRnuURl8b6w=","trackType":""} Apr 11 15:55:47 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus stop Apr 11 15:55:47 volumio volumio[1038]: info: ------------------------------ 5ms Apr 11 15:55:47 volumio volumio[1038]: info: Apr 11 15:55:47 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:55:47 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:55:47 volumio volumio[1038]: info: Apr 11 15:55:47 volumio volumio[1038]: ---------------------------- MPD announces state update: player Apr 11 15:55:47 volumio volumio[1038]: info: ControllerMpd::getState Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:55:47 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Apr 11 15:55:47 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 15:55:47 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 15:55:47 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Apr 11 15:55:47 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::parseState Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:55:47 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:55:47 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:55:47 volumio volumio[1038]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4400,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m801.music.126.net/20260411162046/6fb293c0f4a74a3645ff7153a7e11ecd/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953300913/ae04/7e91/d13d/9d1b284789290b782055173d84591653.flac?vuutv=yTBbhbtXs9BYVCwQqblGXHKi4CRaUjMlfnUAgbcXsbvxxbU5EPoiIdc6jbeGjd+Ung24UnopY4voekW0xelImnEmdwBGMjEcGRnuURl8b6w=","trackType":""} Apr 11 15:55:47 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:55:47 volumio volumio[1038]: info: Received an update from plugin. extracting info from payload Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:47 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:55:47 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:55:47 volumio volumio[1038]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4400,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m801.music.126.net/20260411162046/6fb293c0f4a74a3645ff7153a7e11ecd/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953300913/ae04/7e91/d13d/9d1b284789290b782055173d84591653.flac?vuutv=yTBbhbtXs9BYVCwQqblGXHKi4CRaUjMlfnUAgbcXsbvxxbU5EPoiIdc6jbeGjd+Ung24UnopY4voekW0xelImnEmdwBGMjEcGRnuURl8b6w=","trackType":""} Apr 11 15:55:47 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:55:47 volumio volumio[1038]: info: Received an update from plugin. extracting info from payload Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:47 volumio volumio[1038]: info: ------------------------------ 12ms Apr 11 15:55:47 volumio volumio[1038]: info: ------------------------------ 12ms Apr 11 15:55:47 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 11 15:55:47 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 11 15:55:47 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:55:47 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:55:47 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:55:47 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:55:47 volumio volumio[1038]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4400,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m801.music.126.net/20260411162046/6fb293c0f4a74a3645ff7153a7e11ecd/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953300913/ae04/7e91/d13d/9d1b284789290b782055173d84591653.flac?vuutv=yTBbhbtXs9BYVCwQqblGXHKi4CRaUjMlfnUAgbcXsbvxxbU5EPoiIdc6jbeGjd+Ung24UnopY4voekW0xelImnEmdwBGMjEcGRnuURl8b6w=","trackType":""} Apr 11 15:55:47 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:55:47 volumio volumio[1038]: info: Received an update from plugin. extracting info from payload Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:47 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:55:47 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:55:47 volumio volumio[1038]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4400,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m801.music.126.net/20260411162046/6fb293c0f4a74a3645ff7153a7e11ecd/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953300913/ae04/7e91/d13d/9d1b284789290b782055173d84591653.flac?vuutv=yTBbhbtXs9BYVCwQqblGXHKi4CRaUjMlfnUAgbcXsbvxxbU5EPoiIdc6jbeGjd+Ung24UnopY4voekW0xelImnEmdwBGMjEcGRnuURl8b6w=","trackType":""} Apr 11 15:55:47 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:55:47 volumio volumio[1038]: info: Received an update from plugin. extracting info from payload Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:47 volumio volumio[1038]: info: ControllerMpd::pushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Apr 11 15:55:47 volumio volumio[1038]: verbose: In UPNP mode Apr 11 15:55:47 volumio volumio[1038]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4400,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Last Kiss","artist":"宇多田ヒカル","album":"One Last Kiss","uri":"http://m801.music.126.net/20260411162046/6fb293c0f4a74a3645ff7153a7e11ecd/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/58953300913/ae04/7e91/d13d/9d1b284789290b782055173d84591653.flac?vuutv=yTBbhbtXs9BYVCwQqblGXHKi4CRaUjMlfnUAgbcXsbvxxbU5EPoiIdc6jbeGjd+Ung24UnopY4voekW0xelImnEmdwBGMjEcGRnuURl8b6w=","trackType":""} Apr 11 15:55:47 volumio volumio[1038]: verbose: CURRENT POSITION 0 Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:55:47 volumio volumio[1038]: info: Received an update from plugin. extracting info from payload Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::pushState Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:47 volumio volumio[1038]: info: ------------------------------ 20ms Apr 11 15:55:47 volumio volumio[1038]: info: ------------------------------ 19ms Apr 11 15:55:47 volumio volumio[1038]: info: ------------------------------ 19ms Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:55:47 volumio volumio[1038]: info: CoreStateMachine::getQueue Apr 11 15:55:47 volumio volumio[1038]: info: CorePlayQueue::getQueue Apr 11 15:55:48 volumio volumio[1038]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 15:55:48 volumio volumio[1038]: Error: connect ETIMEDOUT 104.244.46.185:443 Apr 11 15:55:48 volumio volumio[1038]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 11 15:55:48 volumio volumio[1038]: errno: -110, Apr 11 15:55:48 volumio volumio[1038]: code: 'ETIMEDOUT', Apr 11 15:55:48 volumio volumio[1038]: syscall: 'connect', Apr 11 15:55:48 volumio volumio[1038]: address: '104.244.46.185', Apr 11 15:55:48 volumio volumio[1038]: port: 443 Apr 11 15:55:48 volumio volumio[1038]: } Apr 11 15:55:48 volumio volumio[1038]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 15:55:48 volumio sudo[9332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-11 15:54 Apr 11 15:55:48 volumio sudo[9332]: 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="49c352e1d55e9b76c3bd7b0e3940507619bf455a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="1d32690fc900ac8c739e7eabd35ed0f570899eb8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 19 Dec 2025 02:40:03 PM CET" VOLUMIO_VERSION="3.887" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="05e5ed27b04d8d1e8a8a87ca324a64c8"