-- Logs begin at Tue 2025-02-25 10:45:34 +07, end at Wed 2025-02-26 00:18:30 +07. -- Feb 26 00:17:20 volpi ntpd[786]: Soliciting pool server 2401:5f80:5001:1f::31f Feb 26 00:17:23 volpi go-librespot[1278]: time="2025-02-26T00:17:23+07:00" level=trace msg="sent dealer ping" Feb 26 00:17:23 volpi go-librespot[1278]: time="2025-02-26T00:17:23+07:00" level=trace msg="received dealer pong" Feb 26 00:17:27 volpi ntpd[786]: Soliciting pool server 203.113.174.44 Feb 26 00:17:35 volpi ntpd[786]: Soliciting pool server 27.71.27.209 Feb 26 00:17:45 volpi volumio[1425]: 0 Feb 26 00:17:45 volpi volumio[1425]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 26 00:17:45 volpi volumio[1425]: Dload Upload Total Spent Left Speed Feb 26 00:17:45 volpi volumio[1021]: info: Feb 26 00:17:45 volpi volumio[1021]: ---------------------------- Client requests Volume 1 Feb 26 00:17:45 volpi volumio[1021]: info: VolumeController::SetAlsaVolume1 Feb 26 00:17:45 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:45 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:45 volpi volumio[1425]: [158B blob data] Feb 26 00:17:45 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:45 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:45 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:45 volpi volumio[1425]: {"time":1740503865918,"response":"volume Success"}1 Feb 26 00:17:50 volpi volumio[1425]: 100 Feb 26 00:17:50 volpi volumio[1425]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 26 00:17:50 volpi volumio[1425]: Dload Upload Total Spent Left Speed Feb 26 00:17:50 volpi volumio[1021]: info: Feb 26 00:17:50 volpi volumio[1021]: ---------------------------- Client requests Volume 100 Feb 26 00:17:50 volpi volumio[1021]: info: VolumeController::SetAlsaVolume100 Feb 26 00:17:50 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:50 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:50 volpi volumio[1425]: [158B blob data] Feb 26 00:17:50 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:50 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:50 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:50 volpi volumio[1425]: {"time":1740503870700,"response":"volume Success"}100 Feb 26 00:17:50 volpi volumio[1425]: 0 Feb 26 00:17:50 volpi volumio[1425]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 26 00:17:50 volpi volumio[1425]: Dload Upload Total Spent Left Speed Feb 26 00:17:50 volpi volumio[1021]: info: Feb 26 00:17:50 volpi volumio[1021]: ---------------------------- Client requests Volume 1 Feb 26 00:17:50 volpi volumio[1021]: info: VolumeController::SetAlsaVolume1 Feb 26 00:17:50 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:50 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:50 volpi volumio[1425]: [158B blob data] Feb 26 00:17:50 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:50 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:50 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:50 volpi volumio[1425]: {"time":1740503870752,"response":"volume Success"}1 Feb 26 00:17:51 volpi volumio[1425]: 100 Feb 26 00:17:51 volpi volumio[1425]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 26 00:17:51 volpi volumio[1425]: Dload Upload Total Spent Left Speed Feb 26 00:17:51 volpi volumio[1021]: info: Feb 26 00:17:51 volpi volumio[1021]: ---------------------------- Client requests Volume 100 Feb 26 00:17:51 volpi volumio[1021]: info: VolumeController::SetAlsaVolume100 Feb 26 00:17:51 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:51 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:51 volpi volumio[1425]: [158B blob data] Feb 26 00:17:51 volpi volumio[1425]: {"time":1740503871503,"response":"volume Success"}100 Feb 26 00:17:51 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:51 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:51 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:53 volpi go-librespot[1278]: time="2025-02-26T00:17:53+07:00" level=trace msg="sent dealer ping" Feb 26 00:17:53 volpi volumio[1021]: info: Feb 26 00:17:53 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:53 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:53 volpi volumio[1021]: info: Feb 26 00:17:53 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:53 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:53 volpi volumio[1021]: info: Feb 26 00:17:53 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:53 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:53 volpi volumio[1021]: info: sendMpdCommand status took 2 milliseconds Feb 26 00:17:53 volpi volumio[1021]: info: sendMpdCommand status took 2 milliseconds Feb 26 00:17:53 volpi volumio[1021]: info: sendMpdCommand status took 1 milliseconds Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:53 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 00:17:53 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 00:17:53 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:53 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:53 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:17:53 volpi volumio[1021]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":126493,"duration":284,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2892 Kbps","isStreaming":false,"title":"Don't Cry (Original)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/139.flac","trackType":"flac"} Feb 26 00:17:53 volpi volumio[1021]: verbose: CURRENT POSITION 21 Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::syncState stateService pause Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::stPlaybackTimer Feb 26 00:17:53 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:53 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:17:53 volpi volumio[1021]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":126493,"duration":284,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2892 Kbps","isStreaming":false,"title":"Don't Cry (Original)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/139.flac","trackType":"flac"} Feb 26 00:17:53 volpi volumio[1021]: verbose: CURRENT POSITION 21 Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::syncState stateService pause Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::stPlaybackTimer Feb 26 00:17:53 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:53 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:17:53 volpi volumio[1021]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":126493,"duration":284,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2892 Kbps","isStreaming":false,"title":"Don't Cry (Original)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/139.flac","trackType":"flac"} Feb 26 00:17:53 volpi volumio[1021]: verbose: CURRENT POSITION 21 Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::syncState stateService pause Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::stPlaybackTimer Feb 26 00:17:53 volpi volumio[1021]: info: ------------------------------ 11ms Feb 26 00:17:53 volpi volumio[1021]: info: ------------------------------ 10ms Feb 26 00:17:53 volpi volumio[1021]: info: ------------------------------ 10ms Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:53 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:53 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:53 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:53 volpi go-librespot[1278]: time="2025-02-26T00:17:53+07:00" level=trace msg="received dealer pong" Feb 26 00:17:53 volpi volumio[1021]: info: Feb 26 00:17:53 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:53 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:53 volpi volumio[1021]: info: Feb 26 00:17:53 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:53 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:53 volpi volumio[1021]: info: Feb 26 00:17:53 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:53 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:53 volpi volumio[1021]: info: sendMpdCommand status took 2 milliseconds Feb 26 00:17:53 volpi volumio[1021]: info: sendMpdCommand status took 2 milliseconds Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:53 volpi volumio[1021]: info: sendMpdCommand status took 2 milliseconds Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:53 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 00:17:53 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:53 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:53 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:17:53 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":126845,"duration":284,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3120 Kbps","isStreaming":false,"title":"Don't Cry (Original)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/139.flac","trackType":"flac"} Feb 26 00:17:53 volpi volumio[1021]: verbose: CURRENT POSITION 21 Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:17:53 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:53 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:53 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:17:53 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":126903,"duration":284,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3090 Kbps","isStreaming":false,"title":"Don't Cry (Original)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/139.flac","trackType":"flac"} Feb 26 00:17:53 volpi volumio[1021]: verbose: CURRENT POSITION 21 Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:17:53 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:53 volpi volumio[1021]: info: ------------------------------ 17ms Feb 26 00:17:53 volpi volumio[1021]: info: ------------------------------ 17ms Feb 26 00:17:53 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 26 00:17:53 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:53 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:53 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:17:53 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":126966,"duration":284,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3045 Kbps","isStreaming":false,"title":"Don't Cry (Original)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/139.flac","trackType":"flac"} Feb 26 00:17:53 volpi volumio[1021]: verbose: CURRENT POSITION 21 Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:17:53 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:53 volpi volumio[1021]: info: ------------------------------ 21ms Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:53 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:53 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:53 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:53 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:53 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:53 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:53 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:53 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:54 volpi volumio[1021]: info: Feb 26 00:17:54 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:54 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:54 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:54 volpi volumio[1021]: info: Feb 26 00:17:54 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:54 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:54 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:54 volpi volumio[1021]: info: Feb 26 00:17:54 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:54 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:54 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:54 volpi volumio[1021]: info: sendMpdCommand status took 1 milliseconds Feb 26 00:17:54 volpi volumio[1021]: info: sendMpdCommand status took 1 milliseconds Feb 26 00:17:54 volpi volumio[1021]: info: sendMpdCommand status took 1 milliseconds Feb 26 00:17:54 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:54 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:54 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:54 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:54 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:54 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:54 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 00:17:54 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 00:17:54 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 00:17:54 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:54 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:54 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:54 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:54 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:54 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:54 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:54 volpi volumio[1021]: info: CorePlayQueue::getTrack 21 Feb 26 00:17:54 volpi volumio[1021]: 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":"Don't Cry (Original)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/139.flac","trackType":"flac"} Feb 26 00:17:54 volpi volumio[1021]: verbose: CURRENT POSITION 21 Feb 26 00:17:54 volpi volumio[1021]: info: CoreStateMachine::syncState stateService stop Feb 26 00:17:54 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:17:54 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:54 volpi volumio[1021]: info: CoreStateMachine::stPlaybackTimer Feb 26 00:17:54 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:54 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:54 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:54 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 00:17:54 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:54 volpi volumio[1021]: info: CorePlayQueue::getTrack 0 Feb 26 00:17:54 volpi volumio[1021]: 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":"Don't Cry (Original)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/139.flac","trackType":"flac"} Feb 26 00:17:54 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:17:54 volpi volumio[1021]: info: CoreStateMachine::syncState stateService stop Feb 26 00:17:54 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus stop Feb 26 00:17:54 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:54 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:54 volpi volumio[1021]: info: No code Feb 26 00:17:54 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:54 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:54 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:54 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:54 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:54 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:54 volpi volumio[1021]: info: CorePlayQueue::getTrack 0 Feb 26 00:17:54 volpi volumio[1021]: 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":"Don't Cry (Original)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/139.flac","trackType":"flac"} Feb 26 00:17:54 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:17:54 volpi volumio[1021]: info: CoreStateMachine::syncState stateService stop Feb 26 00:17:54 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus stop Feb 26 00:17:54 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:54 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:54 volpi volumio[1021]: info: No code Feb 26 00:17:54 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:54 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:54 volpi volumio[1021]: info: ------------------------------ 16ms Feb 26 00:17:54 volpi volumio[1021]: info: ------------------------------ 15ms Feb 26 00:17:54 volpi volumio[1021]: info: ------------------------------ 15ms Feb 26 00:17:54 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:54 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:54 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:55 volpi volumio[1021]: info: Clearing queue after UPNP request Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::ClearQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::stop Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::clearPlayQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::saveQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushQueue Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: error: updateQueue error: null Feb 26 00:17:56 volpi volumio[1021]: error: updateQueue error: null Feb 26 00:17:56 volpi volumio[1021]: error: updateQueue error: null Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 2ms Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:17:56 volpi volumio[1021]: info: Starting UPNP Playback Feb 26 00:17:56 volpi volumio[1021]: info: Preparing playback through UPNP Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetState Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getTrack 0 Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 26 00:17:56 volpi ntpd[786]: Soliciting pool server 103.70.115.65 Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 3ms Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 2ms Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 2ms Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 0ms Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 2ms Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 2ms Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 2ms Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand status took 3 milliseconds Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand status took 3 milliseconds Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand status took 2 milliseconds Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand status took 2 milliseconds Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:56 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:17:56 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hard Skool","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/141.flac","trackType":"flac"} Feb 26 00:17:56 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus stop Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:56 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:17:56 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hard Skool","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/141.flac","trackType":"flac"} Feb 26 00:17:56 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:17:56 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:56 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:17:56 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hard Skool","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/141.flac","trackType":"flac"} Feb 26 00:17:56 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:17:56 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 12ms Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 15ms Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 14ms Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand status took 1 milliseconds Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:56 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:17:56 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hard Skool","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/141.flac","trackType":"flac"} Feb 26 00:17:56 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:17:56 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 19ms Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand status took 12 milliseconds Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:56 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:17:56 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hard Skool","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/141.flac","trackType":"flac"} Feb 26 00:17:56 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:17:56 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 18ms Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:56 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:17:56 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hard Skool","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/141.flac","trackType":"flac"} Feb 26 00:17:56 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:17:56 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 22ms Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:17:56 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:17:56 volpi volumio[1021]: info: Feb 26 00:17:56 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 3ms Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand status took 3 milliseconds Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 3ms Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand status took 3 milliseconds Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 3ms Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand status took 3 milliseconds Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:56 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:17:56 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":99,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2631 Kbps","isStreaming":false,"title":"Hard Skool","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/141.flac","trackType":"flac"} Feb 26 00:17:56 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:17:56 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 15ms Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 26 00:17:56 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:56 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:56 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:17:56 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":162,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2632 Kbps","isStreaming":false,"title":"Hard Skool","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/141.flac","trackType":"flac"} Feb 26 00:17:56 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:17:56 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:17:56 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:17:56 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":183,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2632 Kbps","isStreaming":false,"title":"Hard Skool","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/141.flac","trackType":"flac"} Feb 26 00:17:56 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:17:56 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 24ms Feb 26 00:17:56 volpi volumio[1021]: info: ------------------------------ 23ms Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:17:56 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:17:56 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:18 volpi volumio[1021]: info: Feb 26 00:18:18 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:18:18 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:18:18 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:18:18 volpi volumio[1021]: info: Feb 26 00:18:18 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:18:18 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:18:18 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:18:18 volpi volumio[1021]: info: Feb 26 00:18:18 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:18:18 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:18:18 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:18:18 volpi volumio[1021]: info: sendMpdCommand status took 2 milliseconds Feb 26 00:18:18 volpi volumio[1021]: info: sendMpdCommand status took 2 milliseconds Feb 26 00:18:18 volpi volumio[1021]: info: sendMpdCommand status took 1 milliseconds Feb 26 00:18:18 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:18:18 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:18:18 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:18:18 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:18:18 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:18:18 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:18:18 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 00:18:18 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 00:18:18 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 00:18:18 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:18:18 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:18:18 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:18:18 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:18:18 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:18:18 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:18 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:18 volpi volumio[1021]: info: CorePlayQueue::getTrack 0 Feb 26 00:18:18 volpi volumio[1021]: 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":"Hard Skool","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/141.flac","trackType":"flac"} Feb 26 00:18:18 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:18:18 volpi volumio[1021]: info: CoreStateMachine::syncState stateService stop Feb 26 00:18:18 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:18:18 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:18 volpi volumio[1021]: info: CoreStateMachine::stPlaybackTimer Feb 26 00:18:18 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:18:18 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:18:18 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:18 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 00:18:18 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:18 volpi volumio[1021]: info: CorePlayQueue::getTrack 0 Feb 26 00:18:18 volpi volumio[1021]: 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":"Hard Skool","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/141.flac","trackType":"flac"} Feb 26 00:18:18 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:18:18 volpi volumio[1021]: info: CoreStateMachine::syncState stateService stop Feb 26 00:18:18 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus stop Feb 26 00:18:18 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:18 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:18 volpi volumio[1021]: info: No code Feb 26 00:18:18 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:18 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:18 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:18:18 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:18:18 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:18 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:18 volpi volumio[1021]: info: CorePlayQueue::getTrack 0 Feb 26 00:18:18 volpi volumio[1021]: 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":"Hard Skool","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/141.flac","trackType":"flac"} Feb 26 00:18:18 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:18:18 volpi volumio[1021]: info: CoreStateMachine::syncState stateService stop Feb 26 00:18:18 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus stop Feb 26 00:18:18 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:18 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:18 volpi volumio[1021]: info: No code Feb 26 00:18:18 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:18 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:18 volpi volumio[1021]: info: ------------------------------ 16ms Feb 26 00:18:18 volpi volumio[1021]: info: ------------------------------ 15ms Feb 26 00:18:18 volpi volumio[1021]: info: ------------------------------ 15ms Feb 26 00:18:18 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:18 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:18 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:18 volpi volumio[1021]: info: Clearing queue after UPNP request Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::ClearQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::stop Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 00:18:19 volpi volumio[1021]: info: CorePlayQueue::clearPlayQueue Feb 26 00:18:19 volpi volumio[1021]: info: CorePlayQueue::saveQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioPushQueue Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: error: updateQueue error: null Feb 26 00:18:19 volpi volumio[1021]: error: updateQueue error: null Feb 26 00:18:19 volpi volumio[1021]: error: updateQueue error: null Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 2ms Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 2ms Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:18:19 volpi volumio[1021]: info: Starting UPNP Playback Feb 26 00:18:19 volpi volumio[1021]: info: Preparing playback through UPNP Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioGetState Feb 26 00:18:19 volpi volumio[1021]: info: CorePlayQueue::getTrack 0 Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 2ms Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 2ms Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 2ms Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 2ms Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 2ms Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 2ms Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:19 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 1ms Feb 26 00:18:19 volpi volumio[1425]: 0 Feb 26 00:18:19 volpi volumio[1425]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 26 00:18:19 volpi volumio[1425]: Dload Upload Total Spent Left Speed Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- Client requests Volume 1 Feb 26 00:18:19 volpi volumio[1021]: info: VolumeController::SetAlsaVolume1 Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:19 volpi volumio[1425]: [158B blob data] Feb 26 00:18:19 volpi volumio[1425]: {"time":1740503899914,"response":"volume Success"}1 Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:18:19 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:18:19 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:18:19 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:18:19 volpi volumio[1021]: info: sendMpdCommand status took 4 milliseconds Feb 26 00:18:19 volpi volumio[1021]: info: sendMpdCommand status took 3 milliseconds Feb 26 00:18:19 volpi volumio[1021]: info: sendMpdCommand status took 3 milliseconds Feb 26 00:18:19 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:18:19 volpi volumio[1021]: info: sendMpdCommand status took 1 milliseconds Feb 26 00:18:19 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 00:18:19 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 00:18:19 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:18:19 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:18:19 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:18:19 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":536,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"November Rain (2022 version)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/140.flac","trackType":"flac"} Feb 26 00:18:19 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus stop Feb 26 00:18:19 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:18:19 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:18:19 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":536,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"November Rain (2022 version)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/140.flac","trackType":"flac"} Feb 26 00:18:19 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:18:19 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:19 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:18:19 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:18:19 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":536,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"November Rain (2022 version)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/140.flac","trackType":"flac"} Feb 26 00:18:19 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:18:19 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 12ms Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 14ms Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 14ms Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:18:19 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:18:19 volpi volumio[1021]: info: Feb 26 00:18:19 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:18:19 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:18:19 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 26 00:18:19 volpi volumio[1021]: info: sendMpdCommand status took 2 milliseconds Feb 26 00:18:19 volpi volumio[1021]: info: sendMpdCommand status took 1 milliseconds Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:18:19 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:18:19 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:18:19 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":536,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"November Rain (2022 version)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/140.flac","trackType":"flac"} Feb 26 00:18:19 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:18:19 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 19ms Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 26 00:18:19 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:18:19 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:18:19 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:18:19 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:18:19 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":536,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"November Rain (2022 version)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/140.flac","trackType":"flac"} Feb 26 00:18:19 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:18:19 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:19 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:18:19 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:18:19 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":536,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"November Rain (2022 version)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/140.flac","trackType":"flac"} Feb 26 00:18:19 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:18:19 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 19ms Feb 26 00:18:19 volpi volumio[1021]: info: ------------------------------ 18ms Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:19 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:19 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:20 volpi volumio[1021]: info: Feb 26 00:18:20 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:20 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:20 volpi volumio[1021]: info: Feb 26 00:18:20 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:18:20 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:18:20 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:18:20 volpi volumio[1021]: info: Feb 26 00:18:20 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:20 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:20 volpi volumio[1021]: info: Feb 26 00:18:20 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:18:20 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:18:20 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:18:20 volpi volumio[1021]: info: Feb 26 00:18:20 volpi volumio[1021]: ---------------------------- MPD announces system playlist update Feb 26 00:18:20 volpi volumio[1021]: info: Ignoring MPD Status Update Feb 26 00:18:20 volpi volumio[1021]: info: Feb 26 00:18:20 volpi volumio[1021]: ---------------------------- MPD announces state update: player Feb 26 00:18:20 volpi volumio[1021]: info: ControllerMpd::getState Feb 26 00:18:20 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Feb 26 00:18:20 volpi volumio[1021]: info: ------------------------------ 3ms Feb 26 00:18:20 volpi volumio[1021]: info: sendMpdCommand status took 3 milliseconds Feb 26 00:18:20 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:18:20 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:18:20 volpi volumio[1021]: info: ------------------------------ 3ms Feb 26 00:18:20 volpi volumio[1021]: info: sendMpdCommand status took 3 milliseconds Feb 26 00:18:20 volpi volumio[1021]: info: ------------------------------ 3ms Feb 26 00:18:20 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:18:20 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:18:20 volpi volumio[1021]: info: sendMpdCommand status took 3 milliseconds Feb 26 00:18:20 volpi volumio[1021]: verbose: ControllerMpd::parseState Feb 26 00:18:20 volpi volumio[1021]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 00:18:20 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 00:18:20 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 00:18:20 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:18:20 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:18:20 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:18:20 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:18:20 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":152,"duration":536,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2652 Kbps","isStreaming":false,"title":"November Rain (2022 version)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/140.flac","trackType":"flac"} Feb 26 00:18:20 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:18:20 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:20 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:18:20 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:18:20 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":157,"duration":536,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2647 Kbps","isStreaming":false,"title":"November Rain (2022 version)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/140.flac","trackType":"flac"} Feb 26 00:18:20 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:18:20 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:20 volpi volumio[1021]: info: ------------------------------ 15ms Feb 26 00:18:20 volpi volumio[1021]: info: ------------------------------ 14ms Feb 26 00:18:20 volpi volumio[1021]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 26 00:18:20 volpi volumio[1021]: verbose: ControllerMpd::parseTrackInfo Feb 26 00:18:20 volpi volumio[1021]: info: ControllerMpd::pushState Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::servicePushState Feb 26 00:18:20 volpi volumio[1021]: verbose: In UPNP mode Feb 26 00:18:20 volpi volumio[1021]: verbose: STATE SERVICE {"status":"play","position":0,"seek":189,"duration":536,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2645 Kbps","isStreaming":false,"title":"November Rain (2022 version)","artist":"Guns N' Roses","album":"Greatest Hits","uri":"http://192.168.50.1:8200/MediaItems/140.flac","trackType":"flac"} Feb 26 00:18:20 volpi volumio[1021]: verbose: CURRENT POSITION 0 Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::syncState stateService play Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::syncState currentStatus play Feb 26 00:18:20 volpi volumio[1021]: info: Received an update from plugin. extracting info from payload Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::pushState Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::volumioPushState Feb 26 00:18:20 volpi volumio[1021]: info: ------------------------------ 21ms Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:20 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:20 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:20 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:20 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:20 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:20 volpi volumio[1021]: info: CoreCommandRouter::volumioGetQueue Feb 26 00:18:20 volpi volumio[1021]: info: CoreStateMachine::getQueue Feb 26 00:18:20 volpi volumio[1021]: info: CorePlayQueue::getQueue Feb 26 00:18:23 volpi go-librespot[1278]: time="2025-02-26T00:18:23+07:00" level=trace msg="sent dealer ping" Feb 26 00:18:23 volpi go-librespot[1278]: time="2025-02-26T00:18:23+07:00" level=trace msg="received dealer pong" Feb 26 00:18:24 volpi ntpd[786]: Soliciting pool server 2401:5b60:0:1::52 Feb 26 00:18:29 volpi go-librespot[1278]: time="2025-02-26T00:18:29+07:00" level=trace msg="received accesspoint ping" Feb 26 00:18:29 volpi go-librespot[1278]: time="2025-02-26T00:18:29+07:00" level=trace msg="received accesspoint pong ack" Feb 26 00:18:29 volpi volumio[1021]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 00:18:29 volpi volumio[1021]: Error: connect ETIMEDOUT 151.101.66.79:443 Feb 26 00:18:29 volpi volumio[1021]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Feb 26 00:18:29 volpi volumio[1021]: errno: -110, Feb 26 00:18:29 volpi volumio[1021]: code: 'ETIMEDOUT', Feb 26 00:18:29 volpi volumio[1021]: syscall: 'connect', Feb 26 00:18:29 volpi volumio[1021]: address: '151.101.66.79', Feb 26 00:18:29 volpi volumio[1021]: port: 443 Feb 26 00:18:29 volpi volumio[1021]: } Feb 26 00:18:29 volpi volumio[1021]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 00:18:30 volpi sudo[7548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-26 00:17 Feb 26 00:18:30 volpi sudo[7548]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"