Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 20 01:41:00 volumio volumio[11235]: info: Adding plugin bluetooth to MyMusic Plugins Dec 20 01:41:00 volumio volumio[11235]: info: Adding plugin multiroom to MyMusic Plugins Dec 20 01:41:00 volumio volumio[11235]: info: Adding plugin metavolumio to MyMusic Plugins Dec 20 01:41:00 volumio volumio[11235]: info: Adding plugin cd_controller to MyMusic Plugins Dec 20 01:41:00 volumio volumio[11235]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 20 01:41:00 volumio volumio[11235]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 20 01:41:00 volumio volumio[11235]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 20 01:41:00 volumio volumio[11235]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 20 01:41:01 volumio volumio[11235]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 20 01:41:01 volumio volumio[11235]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 20 01:41:01 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 01:41:01 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 01:41:01 volumio volumio[11235]: info: Starting MyVolumio Remote Streaming Endpoints Dec 20 01:41:01 volumio volumio[11235]: info: MyVolumio not started Dec 20 01:41:01 volumio volumio[11235]: info: Initializing device activation check Dec 20 01:41:01 volumio volumio[11235]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 20 01:41:01 volumio volumio[11235]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 20 01:41:02 volumio volumio[11235]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 20 01:41:02 volumio volumio[11235]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 20 01:41:02 volumio volumio[11235]: info: Streaming services startup Dec 20 01:41:02 volumio volumio[11235]: info: Starting Streaming Daemon Dec 20 01:41:02 volumio sudo[11814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 20 01:41:02 volumio sudo[11814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 01:41:02 volumio volumio[11235]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 20 01:41:02 volumio sudo[11814]: pam_unix(sudo:session): session closed for user root Dec 20 01:41:02 volumio volumio[11235]: error: Cannot start Volumio Streaming Daemon Dec 20 01:41:02 volumio volumio[11235]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 20 01:41:02 volumio volumio[11235]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 20 01:41:04 volumio volumio[11235]: info: Dec 20 01:41:04 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:04 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:04 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:04 volumio volumio[11235]: info: Dec 20 01:41:04 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:04 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:04 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:04 volumio volumio[11235]: info: Dec 20 01:41:04 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:04 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:04 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:04 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 20 01:41:04 volumio volumio[11235]: info: sendMpdCommand status took 7 milliseconds Dec 20 01:41:04 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:04 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:04 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 20 01:41:04 volumio volumio[11235]: info: sendMpdCommand status took 12 milliseconds Dec 20 01:41:04 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 20 01:41:04 volumio volumio[11235]: info: sendMpdCommand status took 11 milliseconds Dec 20 01:41:04 volumio volumio[11235]: info: sendMpdCommand clearerror took 7 milliseconds Dec 20 01:41:04 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 20 01:41:04 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:04 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:04 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:04 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:04 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:04 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:04 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:04 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:04 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13869,"duration":707,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"p3D4buuKxj6cI4J78=","artist":null,"album":null,"uri":"http://m7.music.126.net/20251220020550/8c7f8edf5cf3d632d7478d5eab45b57c/ymusic/6419/4021/c0ac/13e00bbe8ca049b3f1d1a18d6cfd7957.mp3?vuutv=qQM0mhtB8tkizEo/aOeFPLJbrfnnaC0kmHbhl7stp7Q370ehTkGabvZ6vOHCpocXwCAl+HmqKtJvCqaycfXDCPBN+/p3D4buuKxj6cI4J78=","trackType":""} Dec 20 01:41:04 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:04 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:41:04 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:04 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:41:04 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:04 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:04 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:04 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:04 volumio volumio[11235]: info: ------------------------------ 37ms Dec 20 01:41:04 volumio volumio[11235]: info: sendMpdCommand clearerror took 29 milliseconds Dec 20 01:41:04 volumio volumio[11235]: info: sendMpdCommand clearerror took 28 milliseconds Dec 20 01:41:04 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 24 milliseconds Dec 20 01:41:04 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 24 milliseconds Dec 20 01:41:04 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:04 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:04 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:04 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:04 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:04 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13869,"duration":707,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"p3D4buuKxj6cI4J78=","artist":null,"album":null,"uri":"http://m7.music.126.net/20251220020550/8c7f8edf5cf3d632d7478d5eab45b57c/ymusic/6419/4021/c0ac/13e00bbe8ca049b3f1d1a18d6cfd7957.mp3?vuutv=qQM0mhtB8tkizEo/aOeFPLJbrfnnaC0kmHbhl7stp7Q370ehTkGabvZ6vOHCpocXwCAl+HmqKtJvCqaycfXDCPBN+/p3D4buuKxj6cI4J78=","trackType":""} Dec 20 01:41:04 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:04 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:41:04 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:04 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:41:04 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:04 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:04 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:04 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:04 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:04 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:04 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:04 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13869,"duration":707,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"p3D4buuKxj6cI4J78=","artist":null,"album":null,"uri":"http://m7.music.126.net/20251220020550/8c7f8edf5cf3d632d7478d5eab45b57c/ymusic/6419/4021/c0ac/13e00bbe8ca049b3f1d1a18d6cfd7957.mp3?vuutv=qQM0mhtB8tkizEo/aOeFPLJbrfnnaC0kmHbhl7stp7Q370ehTkGabvZ6vOHCpocXwCAl+HmqKtJvCqaycfXDCPBN+/p3D4buuKxj6cI4J78=","trackType":""} Dec 20 01:41:04 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:04 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:41:04 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:04 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:41:04 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:04 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:04 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:04 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:04 volumio volumio[11235]: info: ------------------------------ 70ms Dec 20 01:41:04 volumio volumio[11235]: info: ------------------------------ 70ms Dec 20 01:41:06 volumio volumio[11235]: info: Dec 20 01:41:06 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:06 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:06 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:06 volumio volumio[11235]: info: Dec 20 01:41:06 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:06 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:06 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:06 volumio volumio[11235]: info: Dec 20 01:41:06 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:06 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:06 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:06 volumio volumio[11235]: info: sendMpdCommand status took 2 milliseconds Dec 20 01:41:06 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:06 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:06 volumio volumio[11235]: info: sendMpdCommand status took 3 milliseconds Dec 20 01:41:06 volumio volumio[11235]: info: sendMpdCommand status took 2 milliseconds Dec 20 01:41:06 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 20 01:41:06 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:06 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:06 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:06 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:06 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:06 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:06 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:06 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:06 volumio volumio[11235]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15238,"duration":707,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"p3D4buuKxj6cI4J78=","artist":null,"album":null,"uri":"http://m7.music.126.net/20251220020550/8c7f8edf5cf3d632d7478d5eab45b57c/ymusic/6419/4021/c0ac/13e00bbe8ca049b3f1d1a18d6cfd7957.mp3?vuutv=qQM0mhtB8tkizEo/aOeFPLJbrfnnaC0kmHbhl7stp7Q370ehTkGabvZ6vOHCpocXwCAl+HmqKtJvCqaycfXDCPBN+/p3D4buuKxj6cI4J78=","trackType":""} Dec 20 01:41:06 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:06 volumio volumio[11235]: info: CoreStateMachine::syncState stateService pause Dec 20 01:41:06 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:06 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:06 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:41:06 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:06 volumio volumio[11235]: info: CoreStateMachine::stPlaybackTimer Dec 20 01:41:06 volumio volumio[11235]: info: ------------------------------ 15ms Dec 20 01:41:06 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 20 01:41:06 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 20 01:41:06 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:06 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:06 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:06 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:06 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:06 volumio volumio[11235]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15238,"duration":707,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"p3D4buuKxj6cI4J78=","artist":null,"album":null,"uri":"http://m7.music.126.net/20251220020550/8c7f8edf5cf3d632d7478d5eab45b57c/ymusic/6419/4021/c0ac/13e00bbe8ca049b3f1d1a18d6cfd7957.mp3?vuutv=qQM0mhtB8tkizEo/aOeFPLJbrfnnaC0kmHbhl7stp7Q370ehTkGabvZ6vOHCpocXwCAl+HmqKtJvCqaycfXDCPBN+/p3D4buuKxj6cI4J78=","trackType":""} Dec 20 01:41:06 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:06 volumio volumio[11235]: info: CoreStateMachine::syncState stateService pause Dec 20 01:41:06 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:06 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:06 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:06 volumio volumio[11235]: info: CoreStateMachine::stPlaybackTimer Dec 20 01:41:06 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:06 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:06 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:06 volumio volumio[11235]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15238,"duration":707,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"p3D4buuKxj6cI4J78=","artist":null,"album":null,"uri":"http://m7.music.126.net/20251220020550/8c7f8edf5cf3d632d7478d5eab45b57c/ymusic/6419/4021/c0ac/13e00bbe8ca049b3f1d1a18d6cfd7957.mp3?vuutv=qQM0mhtB8tkizEo/aOeFPLJbrfnnaC0kmHbhl7stp7Q370ehTkGabvZ6vOHCpocXwCAl+HmqKtJvCqaycfXDCPBN+/p3D4buuKxj6cI4J78=","trackType":""} Dec 20 01:41:06 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:06 volumio volumio[11235]: info: CoreStateMachine::syncState stateService pause Dec 20 01:41:06 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:06 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:06 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:06 volumio volumio[11235]: info: CoreStateMachine::stPlaybackTimer Dec 20 01:41:06 volumio volumio[11235]: info: ------------------------------ 31ms Dec 20 01:41:06 volumio volumio[11235]: info: ------------------------------ 31ms Dec 20 01:41:09 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 01:41:09 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 01:41:09 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 01:41:15 volumio volumio[11235]: info: Dec 20 01:41:15 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:15 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:15 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:15 volumio volumio[11235]: info: Dec 20 01:41:15 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:15 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:15 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:15 volumio volumio[11235]: info: Dec 20 01:41:15 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:15 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:15 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:15 volumio volumio[11235]: info: sendMpdCommand status took 5 milliseconds Dec 20 01:41:15 volumio volumio[11235]: info: sendMpdCommand status took 3 milliseconds Dec 20 01:41:15 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:15 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:15 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:15 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:15 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:15 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:15 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:15 volumio volumio[11235]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 01:41:15 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:15 volumio volumio[11235]: info: CoreStateMachine::syncState stateService stop Dec 20 01:41:15 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:15 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:15 volumio volumio[11235]: info: CoreStateMachine::stPlaybackTimer Dec 20 01:41:15 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:15 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:15 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:15 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:41:15 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:15 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:15 volumio volumio[11235]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 01:41:15 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:15 volumio volumio[11235]: info: CoreStateMachine::syncState stateService stop Dec 20 01:41:15 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus stop Dec 20 01:41:15 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:15 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:15 volumio volumio[11235]: info: No code Dec 20 01:41:15 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:15 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:15 volumio volumio[11235]: info: ------------------------------ 57ms Dec 20 01:41:15 volumio volumio[11235]: info: ------------------------------ 56ms Dec 20 01:41:15 volumio volumio[11235]: info: sendMpdCommand status took 54 milliseconds Dec 20 01:41:15 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:15 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:15 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:15 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:15 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:15 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:15 volumio volumio[11235]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 01:41:15 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:15 volumio volumio[11235]: info: CoreStateMachine::syncState stateService stop Dec 20 01:41:15 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus stop Dec 20 01:41:15 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:15 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:15 volumio volumio[11235]: info: No code Dec 20 01:41:15 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:15 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:15 volumio volumio[11235]: info: ------------------------------ 92ms Dec 20 01:41:16 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 01:41:16 volumio volumio[11235]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 20 01:41:16 volumio volumio[11235]: info: CoreCommandRouter::volumioGetState Dec 20 01:41:19 volumio volumio[11235]: info: Dec 20 01:41:19 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:19 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:19 volumio volumio[11235]: info: Dec 20 01:41:19 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:19 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:19 volumio volumio[11235]: info: Dec 20 01:41:19 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:19 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:19 volumio volumio[11235]: info: Dec 20 01:41:19 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:19 volumio volumio[11235]: info: sendMpdCommand status took 8 milliseconds Dec 20 01:41:19 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:19 volumio volumio[11235]: info: Dec 20 01:41:19 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:19 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:19 volumio volumio[11235]: info: sendMpdCommand status took 17 milliseconds Dec 20 01:41:19 volumio volumio[11235]: info: sendMpdCommand status took 15 milliseconds Dec 20 01:41:19 volumio volumio[11235]: info: sendMpdCommand status took 11 milliseconds Dec 20 01:41:19 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 20 01:41:19 volumio volumio[11235]: info: sendMpdCommand status took 8 milliseconds Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:19 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:19 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:19 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":707,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"p3D4buuKxj6cI4J78=","artist":null,"album":null,"uri":"http://m7.music.126.net/20251220020550/8c7f8edf5cf3d632d7478d5eab45b57c/ymusic/6419/4021/c0ac/13e00bbe8ca049b3f1d1a18d6cfd7957.mp3?vuutv=qQM0mhtB8tkizEo/aOeFPLJbrfnnaC0kmHbhl7stp7Q370ehTkGabvZ6vOHCpocXwCAl+HmqKtJvCqaycfXDCPBN+/p3D4buuKxj6cI4J78=","trackType":""} Dec 20 01:41:19 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus stop Dec 20 01:41:19 volumio volumio[11235]: info: ------------------------------ 32ms Dec 20 01:41:19 volumio volumio[11235]: info: Dec 20 01:41:19 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:19 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:19 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 20 01:41:19 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 20 01:41:19 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 20 01:41:19 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:19 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:19 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:19 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:19 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":707,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"p3D4buuKxj6cI4J78=","artist":null,"album":null,"uri":"http://m7.music.126.net/20251220020550/8c7f8edf5cf3d632d7478d5eab45b57c/ymusic/6419/4021/c0ac/13e00bbe8ca049b3f1d1a18d6cfd7957.mp3?vuutv=qQM0mhtB8tkizEo/aOeFPLJbrfnnaC0kmHbhl7stp7Q370ehTkGabvZ6vOHCpocXwCAl+HmqKtJvCqaycfXDCPBN+/p3D4buuKxj6cI4J78=","trackType":""} Dec 20 01:41:19 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:19 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:41:19 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:19 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:19 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:19 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":707,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"p3D4buuKxj6cI4J78=","artist":null,"album":null,"uri":"http://m7.music.126.net/20251220020550/8c7f8edf5cf3d632d7478d5eab45b57c/ymusic/6419/4021/c0ac/13e00bbe8ca049b3f1d1a18d6cfd7957.mp3?vuutv=qQM0mhtB8tkizEo/aOeFPLJbrfnnaC0kmHbhl7stp7Q370ehTkGabvZ6vOHCpocXwCAl+HmqKtJvCqaycfXDCPBN+/p3D4buuKxj6cI4J78=","trackType":""} Dec 20 01:41:19 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:19 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:19 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:19 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:19 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":707,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"p3D4buuKxj6cI4J78=","artist":null,"album":null,"uri":"http://m7.music.126.net/20251220020550/8c7f8edf5cf3d632d7478d5eab45b57c/ymusic/6419/4021/c0ac/13e00bbe8ca049b3f1d1a18d6cfd7957.mp3?vuutv=qQM0mhtB8tkizEo/aOeFPLJbrfnnaC0kmHbhl7stp7Q370ehTkGabvZ6vOHCpocXwCAl+HmqKtJvCqaycfXDCPBN+/p3D4buuKxj6cI4J78=","trackType":""} Dec 20 01:41:19 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:19 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:19 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:19 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:19 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":707,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"p3D4buuKxj6cI4J78=","artist":null,"album":null,"uri":"http://m7.music.126.net/20251220020550/8c7f8edf5cf3d632d7478d5eab45b57c/ymusic/6419/4021/c0ac/13e00bbe8ca049b3f1d1a18d6cfd7957.mp3?vuutv=qQM0mhtB8tkizEo/aOeFPLJbrfnnaC0kmHbhl7stp7Q370ehTkGabvZ6vOHCpocXwCAl+HmqKtJvCqaycfXDCPBN+/p3D4buuKxj6cI4J78=","trackType":""} Dec 20 01:41:19 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:19 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:19 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:20 volumio volumio[11235]: info: ------------------------------ 161ms Dec 20 01:41:20 volumio volumio[11235]: info: ------------------------------ 160ms Dec 20 01:41:20 volumio volumio[11235]: info: ------------------------------ 157ms Dec 20 01:41:20 volumio volumio[11235]: info: ------------------------------ 155ms Dec 20 01:41:20 volumio volumio[11235]: info: sendMpdCommand status took 130 milliseconds Dec 20 01:41:20 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:20 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:20 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 20 01:41:20 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:20 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:20 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:20 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:20 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":707,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"p3D4buuKxj6cI4J78=","artist":null,"album":null,"uri":"http://m7.music.126.net/20251220020550/8c7f8edf5cf3d632d7478d5eab45b57c/ymusic/6419/4021/c0ac/13e00bbe8ca049b3f1d1a18d6cfd7957.mp3?vuutv=qQM0mhtB8tkizEo/aOeFPLJbrfnnaC0kmHbhl7stp7Q370ehTkGabvZ6vOHCpocXwCAl+HmqKtJvCqaycfXDCPBN+/p3D4buuKxj6cI4J78=","trackType":""} Dec 20 01:41:20 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:20 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:41:20 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:20 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:41:20 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:20 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:20 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:20 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:20 volumio volumio[11235]: info: ------------------------------ 179ms Dec 20 01:41:21 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 01:41:21 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 01:41:21 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 20 01:41:21 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 20 01:41:21 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 20 01:41:21 volumio volumio[11235]: info: CoreCommandRouter::volumioGetBrowseSources Dec 20 01:41:21 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 01:41:22 volumio volumio[11235]: info: Dec 20 01:41:22 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:22 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:22 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:22 volumio volumio[11235]: info: Dec 20 01:41:22 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:22 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:22 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:22 volumio volumio[11235]: info: Dec 20 01:41:22 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:22 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:22 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:22 volumio volumio[11235]: info: sendMpdCommand status took 3 milliseconds Dec 20 01:41:22 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:22 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:22 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:22 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:22 volumio volumio[11235]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 01:41:22 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:22 volumio volumio[11235]: info: CoreStateMachine::syncState stateService stop Dec 20 01:41:22 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:22 volumio volumio[11235]: info: CoreStateMachine::stPlaybackTimer Dec 20 01:41:22 volumio volumio[11235]: info: ------------------------------ 18ms Dec 20 01:41:22 volumio volumio[11235]: info: sendMpdCommand status took 19 milliseconds Dec 20 01:41:22 volumio volumio[11235]: info: sendMpdCommand status took 18 milliseconds Dec 20 01:41:22 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:22 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:22 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:22 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:22 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:22 volumio volumio[11235]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 01:41:22 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:22 volumio volumio[11235]: info: CoreStateMachine::syncState stateService stop Dec 20 01:41:22 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus stop Dec 20 01:41:22 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:22 volumio volumio[11235]: info: No code Dec 20 01:41:22 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:22 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:22 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:22 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:22 volumio volumio[11235]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 01:41:22 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:22 volumio volumio[11235]: info: CoreStateMachine::syncState stateService stop Dec 20 01:41:22 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus stop Dec 20 01:41:22 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:22 volumio volumio[11235]: info: No code Dec 20 01:41:22 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:22 volumio volumio[11235]: info: ------------------------------ 71ms Dec 20 01:41:22 volumio volumio[11235]: info: ------------------------------ 71ms Dec 20 01:41:22 volumio volumio[11235]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 20 01:41:22 volumio volumio[11235]: info: Completed starting MyVolumio Plugin Dec 20 01:41:22 volumio volumio[11235]: [Metrics] CommandRouter: 48s 85.79ms Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::volumiosetStartupVolume Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::Close All Modals sent Dec 20 01:41:22 volumio volumio[11235]: info: CoreCommandRouter::Close All Modals sent Dec 20 01:41:23 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 20 01:41:23 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 20 01:41:23 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 20 01:41:29 volumio volumio[11235]: info: BOOT COMPLETED Dec 20 01:41:35 volumio volumio-remote-updater[2456]: Test mode disabled Dec 20 01:41:35 volumio volumio-remote-updater[2456]: Alpha mode disabled Dec 20 01:41:35 volumio volumio-remote-updater[2456]: Alpha legacy test mode disabled Dec 20 01:41:35 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 20 01:41:36 volumio volumio[11235]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 20 01:41:36 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 20 01:41:51 volumio volumio[11235]: info: Clearing queue after UPNP request Dec 20 01:41:52 volumio volumio[11235]: info: CoreStateMachine::ClearQueue Dec 20 01:41:52 volumio volumio[11235]: info: CoreStateMachine::stop Dec 20 01:41:52 volumio volumio[11235]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 01:41:52 volumio volumio[11235]: info: CorePlayQueue::clearPlayQueue Dec 20 01:41:52 volumio volumio[11235]: info: CorePlayQueue::saveQueue Dec 20 01:41:52 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:52 volumio volumio[11235]: info: CoreCommandRouter::volumioPushQueue Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: error: updateQueue error: null Dec 20 01:41:52 volumio volumio[11235]: error: updateQueue error: null Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 7ms Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:41:52 volumio volumio[11235]: error: updateQueue error: null Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 5ms Dec 20 01:41:52 volumio volumio[11235]: info: Starting UPNP Playback Dec 20 01:41:52 volumio volumio[11235]: info: Preparing playback through UPNP Dec 20 01:41:52 volumio volumio[11235]: info: CoreCommandRouter::volumioGetState Dec 20 01:41:52 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:52 volumio volumio[11235]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 8ms Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 7ms Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 9ms Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 5ms Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 5ms Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 6ms Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: Dec 20 01:41:52 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:52 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 6ms Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:41:52 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:41:54 volumio volumio[11235]: info: Dec 20 01:41:54 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:54 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:54 volumio volumio[11235]: info: Dec 20 01:41:54 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:54 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:54 volumio volumio[11235]: info: Dec 20 01:41:54 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:54 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:54 volumio volumio[11235]: info: Dec 20 01:41:54 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:54 volumio volumio[11235]: info: sendMpdCommand status took 30 milliseconds Dec 20 01:41:54 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:54 volumio volumio[11235]: info: Dec 20 01:41:54 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:54 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:54 volumio volumio[11235]: info: Dec 20 01:41:54 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:54 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:54 volumio volumio[11235]: info: sendMpdCommand status took 40 milliseconds Dec 20 01:41:54 volumio volumio[11235]: info: sendMpdCommand status took 42 milliseconds Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:54 volumio volumio[11235]: info: Dec 20 01:41:54 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:54 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:54 volumio volumio[11235]: info: Dec 20 01:41:54 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:54 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:54 volumio volumio[11235]: info: sendMpdCommand status took 30 milliseconds Dec 20 01:41:54 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 30 milliseconds Dec 20 01:41:54 volumio volumio[11235]: info: sendMpdCommand status took 29 milliseconds Dec 20 01:41:54 volumio volumio[11235]: info: ------------------------------ 22ms Dec 20 01:41:54 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 13 milliseconds Dec 20 01:41:54 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 20 01:41:54 volumio volumio[11235]: info: ------------------------------ 11ms Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:54 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:54 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:54 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:54 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:54 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:41:54 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:54 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:41:54 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus stop Dec 20 01:41:54 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:54 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:54 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:54 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2 Kbps","isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:41:54 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:54 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:41:54 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:54 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:41:54 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:41:54 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:54 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:41:54 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:54 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:54 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:54 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:54 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:54 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:54 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2 Kbps","isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:41:54 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:54 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:41:54 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:54 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:41:54 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:41:54 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:54 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:54 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:54 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:54 volumio volumio[11235]: info: ------------------------------ 107ms Dec 20 01:41:54 volumio volumio[11235]: info: ------------------------------ 147ms Dec 20 01:41:54 volumio volumio[11235]: info: ------------------------------ 146ms Dec 20 01:41:54 volumio volumio[11235]: info: Dec 20 01:41:54 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:54 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:55 volumio volumio[11235]: info: sendMpdCommand status took 102 milliseconds Dec 20 01:41:55 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 89 milliseconds Dec 20 01:41:55 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 87 milliseconds Dec 20 01:41:55 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:55 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:41:55 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:55 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:55 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:55 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:55 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2 Kbps","isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:41:55 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:55 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:55 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:55 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:55 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2 Kbps","isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:41:55 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:55 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:55 volumio volumio[11235]: info: ------------------------------ 238ms Dec 20 01:41:55 volumio volumio[11235]: info: ------------------------------ 236ms Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: ------------------------------ 180ms Dec 20 01:41:55 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 171 milliseconds Dec 20 01:41:55 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:41:55 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:55 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:41:55 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2 Kbps","isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:41:55 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:41:55 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:55 volumio volumio[11235]: info: ------------------------------ 319ms Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:41:55 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:41:55 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:41:59 volumio volumio[11235]: info: Clearing queue after UPNP request Dec 20 01:41:59 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::ClearQueue Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::stop Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::stPlaybackTimer Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::updateTrackBlock Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::getTrackBlock Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::serviceStop Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:59 volumio volumio[11235]: info: ControllerMpd::stop Dec 20 01:41:59 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand stop Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::clearPlayQueue Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::saveQueue Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::volumioPushQueue Dec 20 01:41:59 volumio volumio[11235]: info: Dec 20 01:41:59 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:59 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:59 volumio volumio[11235]: info: Dec 20 01:41:59 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:59 volumio volumio[11235]: info: sendMpdCommand stop took 44 milliseconds Dec 20 01:41:59 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:59 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:59 volumio volumio[11235]: info: Dec 20 01:41:59 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:59 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:59 volumio volumio[11235]: info: Dec 20 01:41:59 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:59 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:59 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:59 volumio volumio[11235]: info: Dec 20 01:41:59 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:41:59 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:41:59 volumio volumio[11235]: info: Dec 20 01:41:59 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:41:59 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:41:59 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:41:59 volumio volumio[11235]: error: updateQueue error: null Dec 20 01:41:59 volumio volumio[11235]: error: updateQueue error: null Dec 20 01:41:59 volumio volumio[11235]: error: updateQueue error: null Dec 20 01:41:59 volumio volumio[11235]: info: ------------------------------ 11ms Dec 20 01:41:59 volumio volumio[11235]: info: sendMpdCommand status took 10 milliseconds Dec 20 01:41:59 volumio volumio[11235]: info: ------------------------------ 9ms Dec 20 01:41:59 volumio volumio[11235]: info: sendMpdCommand status took 8 milliseconds Dec 20 01:41:59 volumio volumio[11235]: info: ------------------------------ 8ms Dec 20 01:41:59 volumio volumio[11235]: info: sendMpdCommand status took 5 milliseconds Dec 20 01:41:59 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:59 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:59 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:41:59 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:59 volumio volumio[11235]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 01:41:59 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::syncState stateService stop Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus stop Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:59 volumio volumio[11235]: info: No code Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:59 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:59 volumio volumio[11235]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 01:41:59 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::syncState stateService stop Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus stop Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:59 volumio volumio[11235]: info: No code Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:59 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:59 volumio volumio[11235]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 01:41:59 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::syncState stateService stop Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus stop Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:59 volumio volumio[11235]: info: No code Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:41:59 volumio volumio[11235]: info: ------------------------------ 106ms Dec 20 01:41:59 volumio volumio[11235]: info: ------------------------------ 107ms Dec 20 01:41:59 volumio volumio[11235]: info: ------------------------------ 107ms Dec 20 01:41:59 volumio volumio[11235]: info: Starting UPNP Playback Dec 20 01:41:59 volumio volumio[11235]: info: Preparing playback through UPNP Dec 20 01:41:59 volumio volumio[11235]: info: CoreCommandRouter::volumioGetState Dec 20 01:41:59 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:41:59 volumio volumio[11235]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 1ms Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 1ms Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: Dec 20 01:42:00 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:00 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:00 volumio volumio[11235]: info: ------------------------------ 1ms Dec 20 01:42:01 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 20 01:42:01 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 20 01:42:02 volumio volumio[11235]: info: Dec 20 01:42:02 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:02 volumio volumio[11235]: info: Dec 20 01:42:02 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:02 volumio volumio[11235]: info: Dec 20 01:42:02 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:02 volumio volumio[11235]: info: Dec 20 01:42:02 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:02 volumio volumio[11235]: info: Dec 20 01:42:02 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:02 volumio volumio[11235]: info: Dec 20 01:42:02 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand status took 10 milliseconds Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand status took 9 milliseconds Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand status took 8 milliseconds Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand status took 9 milliseconds Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand status took 17 milliseconds Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand status took 15 milliseconds Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:02 volumio volumio[11235]: info: Dec 20 01:42:02 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:02 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:02 volumio volumio[11235]: info: Dec 20 01:42:02 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:02 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:02 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:42:02 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus stop Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:02 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:02 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:42:02 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:02 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:02 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:02 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:42:02 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:02 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:02 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:02 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:42:02 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:02 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:02 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:02 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:42:02 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:02 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:02 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:02 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:42:02 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:02 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:02 volumio volumio[11235]: info: ------------------------------ 75ms Dec 20 01:42:02 volumio volumio[11235]: info: ------------------------------ 160ms Dec 20 01:42:02 volumio volumio[11235]: info: ------------------------------ 159ms Dec 20 01:42:02 volumio volumio[11235]: info: ------------------------------ 156ms Dec 20 01:42:02 volumio volumio[11235]: info: ------------------------------ 154ms Dec 20 01:42:02 volumio volumio[11235]: info: ------------------------------ 153ms Dec 20 01:42:02 volumio volumio[11235]: info: Dec 20 01:42:02 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:02 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:02 volumio volumio[11235]: info: Dec 20 01:42:02 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:02 volumio volumio[11235]: info: Dec 20 01:42:02 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:02 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:02 volumio volumio[11235]: info: Dec 20 01:42:02 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:02 volumio volumio[11235]: info: ------------------------------ 139ms Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand status took 137 milliseconds Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: ------------------------------ 159ms Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand status took 155 milliseconds Dec 20 01:42:02 volumio volumio[11235]: info: ------------------------------ 156ms Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand status took 155 milliseconds Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 148 milliseconds Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:02 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:02 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2 Kbps","isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:42:02 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:02 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:02 volumio volumio[11235]: info: ------------------------------ 325ms Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 38 milliseconds Dec 20 01:42:02 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 39 milliseconds Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:02 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:02 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:02 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":136,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2 Kbps","isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:42:02 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:02 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:02 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:02 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:02 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":136,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2 Kbps","isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:42:02 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:02 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:02 volumio volumio[11235]: info: ------------------------------ 289ms Dec 20 01:42:02 volumio volumio[11235]: info: ------------------------------ 288ms Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:02 volumio volumio[11235]: info: Starting UPNP Playback Dec 20 01:42:02 volumio volumio[11235]: info: Preparing playback through UPNP Dec 20 01:42:02 volumio volumio[11235]: info: Consume mode Dec 20 01:42:02 volumio volumio[11235]: info: CoreCommandRouter::volumioGetState Dec 20 01:42:02 volumio volumio[11235]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 6ms Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 5ms Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 7ms Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 6ms Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 10ms Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 10ms Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 6ms Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 5ms Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 7ms Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 9ms Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 8ms Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 7ms Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 8ms Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 5ms Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: Dec 20 01:42:03 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:03 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 7ms Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 6ms Dec 20 01:42:03 volumio volumio[11235]: info: ------------------------------ 5ms Dec 20 01:42:03 volumio volumio[11235]: info: Starting UPNP Playback Dec 20 01:42:03 volumio volumio[11235]: info: Preparing playback through UPNP Dec 20 01:42:03 volumio volumio[11235]: info: Consume mode Dec 20 01:42:03 volumio volumio[11235]: info: CoreCommandRouter::volumioGetState Dec 20 01:42:03 volumio volumio[11235]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 8ms Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 8ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 11ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 7ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 6ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 6ms Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 5ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 10ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 9ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 8ms Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 13ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 12ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 11ms Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 8ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 8ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 7ms Dec 20 01:42:04 volumio volumio[11235]: info: Starting UPNP Playback Dec 20 01:42:04 volumio volumio[11235]: info: Preparing playback through UPNP Dec 20 01:42:04 volumio volumio[11235]: info: Consume mode Dec 20 01:42:04 volumio volumio[11235]: info: CoreCommandRouter::volumioGetState Dec 20 01:42:04 volumio volumio[11235]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 5ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 9ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 8ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 7ms Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 1ms Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: Dec 20 01:42:04 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:04 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:04 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:11 volumio volumio[11235]: info: Dec 20 01:42:11 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:11 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:11 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:11 volumio volumio[11235]: info: Dec 20 01:42:11 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:11 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:11 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:11 volumio volumio[11235]: info: Dec 20 01:42:11 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:11 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:11 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:11 volumio volumio[11235]: info: sendMpdCommand status took 6 milliseconds Dec 20 01:42:11 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:11 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:11 volumio volumio[11235]: info: sendMpdCommand status took 7 milliseconds Dec 20 01:42:11 volumio volumio[11235]: info: sendMpdCommand status took 6 milliseconds Dec 20 01:42:11 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 20 01:42:11 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:11 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:11 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:11 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:11 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:11 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:11 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:11 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":243000,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2171 Kbps","isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:42:11 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:11 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:11 volumio volumio[11235]: info: ------------------------------ 205ms Dec 20 01:42:11 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 193 milliseconds Dec 20 01:42:11 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 192 milliseconds Dec 20 01:42:11 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:11 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:11 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:11 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:11 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":243000,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2171 Kbps","isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:42:11 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:11 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:11 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:11 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:11 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":243000,"duration":1149,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2171 Kbps","isStreaming":false,"title":"I. Bewegt, nicht zu schnell","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQrCMBAAv7JHBZttwQ8YhdKDIhT0HJJtE5puYZMg-DZvfszqweMwzGDKi5iRkOYSTSaHNZ5LChZuJI1qsD8cT6il2IlJoII-8LBwILiI2gOlyhWBTVs4r_pu2G2xfb_-VGmSGL7p1YfojcxrPP1OdaM6BZoeNOYdcLA-w7NAsp4pRjVEYz__UrcyngAAAA==.flac","trackType":"flac"} Dec 20 01:42:11 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:11 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:11 volumio volumio[11235]: info: ------------------------------ 256ms Dec 20 01:42:11 volumio volumio[11235]: info: ------------------------------ 254ms Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:11 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:11 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:11 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:11 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:11 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:11 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:11 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:11 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: Dec 20 01:42:12 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:12 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:12 volumio volumio[11235]: info: Dec 20 01:42:12 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:12 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:12 volumio volumio[11235]: info: Dec 20 01:42:12 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:12 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:12 volumio volumio[11235]: info: sendMpdCommand status took 3 milliseconds Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:12 volumio volumio[11235]: info: sendMpdCommand status took 2 milliseconds Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:12 volumio volumio[11235]: info: sendMpdCommand status took 3 milliseconds Dec 20 01:42:12 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:12 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:12 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:12 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":958,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"II. Andante quasi Allegretto","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQ6CMBBFrzJLXdAB4wWKJoSFxoRE15N2gIbSxml7O3deTHTh8ue99zHlKDQx8lo8ZbZY46UkZ-DO0qgGB306YyvFLIEFKhhcGGNwDFdRR-BU2SKw60rIG35QsHvs3q__qloW777pbXZ-Jlm3ePk91QfV9wp0sLTp8CyUHGjveRLOOarRk_kABABneJ8AAAA=.flac","trackType":"flac"} Dec 20 01:42:12 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:12 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:12 volumio volumio[11235]: info: ------------------------------ 27ms Dec 20 01:42:12 volumio volumio[11235]: info: Dec 20 01:42:12 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:12 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:12 volumio volumio[11235]: info: Dec 20 01:42:12 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:12 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 31 milliseconds Dec 20 01:42:12 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 29 milliseconds Dec 20 01:42:12 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:12 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:12 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:12 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":958,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"II. Andante quasi Allegretto","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQ6CMBBFrzJLXdAB4wWKJoSFxoRE15N2gIbSxml7O3deTHTh8ue99zHlKDQx8lo8ZbZY46UkZ-DO0qgGB306YyvFLIEFKhhcGGNwDFdRR-BU2SKw60rIG35QsHvs3q__qloW777pbXZ-Jlm3ePk91QfV9wp0sLTp8CyUHGjveRLOOarRk_kABABneJ8AAAA=.flac","trackType":"flac"} Dec 20 01:42:12 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:12 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:12 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:12 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:12 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":958,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"II. Andante quasi Allegretto","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQ6CMBBFrzJLXdAB4wWKJoSFxoRE15N2gIbSxml7O3deTHTh8ue99zHlKDQx8lo8ZbZY46UkZ-DO0qgGB306YyvFLIEFKhhcGGNwDFdRR-BU2SKw60rIG35QsHvs3q__qloW777pbXZ-Jlm3ePk91QfV9wp0sLTp8CyUHGjveRLOOarRk_kABABneJ8AAAA=.flac","trackType":"flac"} Dec 20 01:42:12 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:12 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:12 volumio volumio[11235]: info: ------------------------------ 85ms Dec 20 01:42:12 volumio volumio[11235]: info: ------------------------------ 86ms Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: Dec 20 01:42:12 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:12 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:12 volumio volumio[11235]: info: Dec 20 01:42:12 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:12 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:12 volumio volumio[11235]: info: Dec 20 01:42:12 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:12 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:12 volumio volumio[11235]: info: Dec 20 01:42:12 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:12 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:12 volumio volumio[11235]: info: ------------------------------ 115ms Dec 20 01:42:12 volumio volumio[11235]: info: sendMpdCommand status took 114 milliseconds Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:12 volumio volumio[11235]: info: ------------------------------ 18ms Dec 20 01:42:12 volumio volumio[11235]: info: sendMpdCommand status took 15 milliseconds Dec 20 01:42:12 volumio volumio[11235]: info: ------------------------------ 15ms Dec 20 01:42:12 volumio volumio[11235]: info: sendMpdCommand status took 13 milliseconds Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:12 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 20 01:42:12 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 20 01:42:12 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:12 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:12 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:12 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:12 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":1,"seek":45,"duration":958,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2046 Kbps","isStreaming":false,"title":"II. Andante quasi Allegretto","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQ6CMBBFrzJLXdAB4wWKJoSFxoRE15N2gIbSxml7O3deTHTh8ue99zHlKDQx8lo8ZbZY46UkZ-DO0qgGB306YyvFLIEFKhhcGGNwDFdRR-BU2SKw60rIG35QsHvs3q__qloW777pbXZ-Jlm3ePk91QfV9wp0sLTp8CyUHGjveRLOOarRk_kABABneJ8AAAA=.flac","trackType":"flac"} Dec 20 01:42:12 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:12 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:12 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:12 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:12 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":1,"seek":91,"duration":958,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2054 Kbps","isStreaming":false,"title":"II. Andante quasi Allegretto","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQ6CMBBFrzJLXdAB4wWKJoSFxoRE15N2gIbSxml7O3deTHTh8ue99zHlKDQx8lo8ZbZY46UkZ-DO0qgGB306YyvFLIEFKhhcGGNwDFdRR-BU2SKw60rIG35QsHvs3q__qloW777pbXZ-Jlm3ePk91QfV9wp0sLTp8CyUHGjveRLOOarRk_kABABneJ8AAAA=.flac","trackType":"flac"} Dec 20 01:42:12 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:12 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:12 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:12 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:12 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":1,"seek":96,"duration":958,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2054 Kbps","isStreaming":false,"title":"II. Andante quasi Allegretto","artist":"Günter Wand/Berliner Philharmoniker","album":"Anton Bruckner: Sinfonien","uri":"http://192.168.8.106:57645/b64/H4sIAAAAAAAA_z2MQQ6CMBBFrzJLXdAB4wWKJoSFxoRE15N2gIbSxml7O3deTHTh8ue99zHlKDQx8lo8ZbZY46UkZ-DO0qgGB306YyvFLIEFKhhcGGNwDFdRR-BU2SKw60rIG35QsHvs3q__qloW777pbXZ-Jlm3ePk91QfV9wp0sLTp8CyUHGjveRLOOarRk_kABABneJ8AAAA=.flac","trackType":"flac"} Dec 20 01:42:12 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:12 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:12 volumio volumio[11235]: info: ------------------------------ 214ms Dec 20 01:42:12 volumio volumio[11235]: info: ------------------------------ 108ms Dec 20 01:42:12 volumio volumio[11235]: info: ------------------------------ 105ms Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:12 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:12 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:36 volumio kernel: perf: interrupt took too long (4013 > 3977), lowering kernel.perf_event_max_sample_rate to 49750 Dec 20 01:42:41 volumio volumio[11235]: info: Clearing queue after UPNP request Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::ClearQueue Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::stop Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::stPlaybackTimer Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::updateTrackBlock Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::getTrackBlock Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::serviceStop Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:42:42 volumio volumio[11235]: info: ControllerMpd::stop Dec 20 01:42:42 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand stop Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::clearPlayQueue Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::saveQueue Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::volumioPushQueue Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:42 volumio volumio[11235]: info: sendMpdCommand stop took 38 milliseconds Dec 20 01:42:42 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:42 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:42 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:42 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:42 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:42 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: sendMpdCommand status took 6 milliseconds Dec 20 01:42:42 volumio volumio[11235]: info: sendMpdCommand status took 4 milliseconds Dec 20 01:42:42 volumio volumio[11235]: info: sendMpdCommand status took 4 milliseconds Dec 20 01:42:42 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:42 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:42 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:42 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:42:42 volumio volumio[11235]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 01:42:42 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::syncState stateService stop Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus stop Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:42 volumio volumio[11235]: info: No code Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:42 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:42:42 volumio volumio[11235]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 01:42:42 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::syncState stateService stop Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus stop Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:42 volumio volumio[11235]: info: No code Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:42 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:42:42 volumio volumio[11235]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 01:42:42 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::syncState stateService stop Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus stop Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:42 volumio volumio[11235]: info: No code Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 105ms Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 105ms Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 104ms Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: error: updateQueue error: null Dec 20 01:42:42 volumio volumio[11235]: error: updateQueue error: null Dec 20 01:42:42 volumio volumio[11235]: error: updateQueue error: null Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 131ms Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 28ms Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 27ms Dec 20 01:42:42 volumio volumio[11235]: info: Starting UPNP Playback Dec 20 01:42:42 volumio volumio[11235]: info: Preparing playback through UPNP Dec 20 01:42:42 volumio volumio[11235]: info: CoreCommandRouter::volumioGetState Dec 20 01:42:42 volumio volumio[11235]: info: CorePlayQueue::getTrack 0 Dec 20 01:42:42 volumio volumio[11235]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 5ms Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 1ms Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 3ms Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 6ms Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 5ms Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:42 volumio volumio[11235]: info: Dec 20 01:42:42 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:42 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 4ms Dec 20 01:42:42 volumio volumio[11235]: info: ------------------------------ 2ms Dec 20 01:42:44 volumio volumio[11235]: info: Dec 20 01:42:44 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:44 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:44 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:44 volumio volumio[11235]: info: Dec 20 01:42:44 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:44 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:44 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:44 volumio volumio[11235]: info: Dec 20 01:42:44 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:44 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:44 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:44 volumio volumio[11235]: info: Dec 20 01:42:44 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:44 volumio volumio[11235]: info: sendMpdCommand status took 7 milliseconds Dec 20 01:42:44 volumio volumio[11235]: info: sendMpdCommand status took 6 milliseconds Dec 20 01:42:44 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:44 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:44 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:44 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:44 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:44 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:44 volumio volumio[11235]: info: Dec 20 01:42:44 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:44 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:44 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:45 volumio volumio[11235]: info: Dec 20 01:42:45 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:45 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:45 volumio volumio[11235]: info: Dec 20 01:42:45 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:45 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:45 volumio volumio[11235]: info: Dec 20 01:42:45 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:45 volumio volumio[11235]: info: sendMpdCommand status took 12 milliseconds Dec 20 01:42:45 volumio volumio[11235]: info: sendMpdCommand status took 9 milliseconds Dec 20 01:42:45 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 20 01:42:45 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 20 01:42:45 volumio volumio[11235]: info: sendMpdCommand status took 7 milliseconds Dec 20 01:42:45 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:45 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:45 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:45 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Long Season","artist":"Fishmans, Fishmans (AlbumArtist)","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:45 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus stop Dec 20 01:42:45 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:45 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:45 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Long Season","artist":"Fishmans, Fishmans (AlbumArtist)","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:45 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:45 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:45 volumio volumio[11235]: info: ------------------------------ 54ms Dec 20 01:42:45 volumio volumio[11235]: info: ------------------------------ 76ms Dec 20 01:42:45 volumio volumio[11235]: info: Dec 20 01:42:45 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:45 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:45 volumio volumio[11235]: info: Dec 20 01:42:45 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:45 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:45 volumio volumio[11235]: info: sendMpdCommand status took 69 milliseconds Dec 20 01:42:45 volumio volumio[11235]: info: ------------------------------ 71ms Dec 20 01:42:45 volumio volumio[11235]: info: sendMpdCommand status took 65 milliseconds Dec 20 01:42:45 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 64 milliseconds Dec 20 01:42:45 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 62 milliseconds Dec 20 01:42:45 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 61 milliseconds Dec 20 01:42:45 volumio volumio[11235]: info: ------------------------------ 9ms Dec 20 01:42:45 volumio volumio[11235]: info: sendMpdCommand status took 6 milliseconds Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:45 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:45 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:45 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Long Season","artist":"Fishmans","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:45 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:45 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:45 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:45 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:45 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Long Season","artist":"Fishmans","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:45 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:45 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:45 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:45 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:45 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Long Season","artist":"Fishmans","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:45 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:45 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:45 volumio volumio[11235]: info: ------------------------------ 202ms Dec 20 01:42:45 volumio volumio[11235]: info: ------------------------------ 197ms Dec 20 01:42:45 volumio volumio[11235]: info: ------------------------------ 195ms Dec 20 01:42:45 volumio volumio[11235]: info: Dec 20 01:42:45 volumio volumio[11235]: ---------------------------- MPD announces system playlist update Dec 20 01:42:45 volumio volumio[11235]: info: Ignoring MPD Status Update Dec 20 01:42:45 volumio volumio[11235]: info: Dec 20 01:42:45 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:45 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 149 milliseconds Dec 20 01:42:45 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 149 milliseconds Dec 20 01:42:45 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 148 milliseconds Dec 20 01:42:45 volumio volumio[11235]: info: ------------------------------ 34ms Dec 20 01:42:45 volumio volumio[11235]: info: sendMpdCommand status took 33 milliseconds Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:45 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:45 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:45 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"522 Kbps","isStreaming":false,"title":"Long Season","artist":"Fishmans","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:45 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:45 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:45 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:45 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:45 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"522 Kbps","isStreaming":false,"title":"Long Season","artist":"Fishmans","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:45 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:45 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:45 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:45 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:45 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":182,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"522 Kbps","isStreaming":false,"title":"Long Season","artist":"Fishmans","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:45 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:45 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:45 volumio volumio[11235]: info: ------------------------------ 337ms Dec 20 01:42:45 volumio volumio[11235]: info: ------------------------------ 333ms Dec 20 01:42:45 volumio volumio[11235]: info: ------------------------------ 269ms Dec 20 01:42:45 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 117 milliseconds Dec 20 01:42:45 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:45 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:45 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:45 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":501,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"522 Kbps","isStreaming":false,"title":"Long Season","artist":"Fishmans","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:45 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:45 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:45 volumio volumio[11235]: info: ------------------------------ 211ms Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:45 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:45 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:45 volumio volumio[11235]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Fishmans/98.12.28%20%E7%94%B7%E9%81%94%E3%81%AE%E5%88%A5%E3%82%8C/daacc26e-e56d-4710-99ca-f818272627ba.png' Dec 20 01:42:45 volumio volumio[11235]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Fishmans/98.12.28%20%E7%94%B7%E9%81%94%E3%81%AE%E5%88%A5%E3%82%8C/daacc26e-e56d-4710-99ca-f818272627ba.png' Dec 20 01:42:48 volumio volumio[11235]: info: Dec 20 01:42:48 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:48 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:48 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:48 volumio volumio[11235]: info: Dec 20 01:42:48 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:48 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:48 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:48 volumio volumio[11235]: info: Dec 20 01:42:48 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:48 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:48 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:48 volumio volumio[11235]: info: sendMpdCommand status took 3 milliseconds Dec 20 01:42:48 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:48 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:48 volumio volumio[11235]: info: sendMpdCommand status took 4 milliseconds Dec 20 01:42:48 volumio volumio[11235]: info: sendMpdCommand status took 10 milliseconds Dec 20 01:42:48 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 20 01:42:48 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:48 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:48 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:48 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:48 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:48 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:48 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:48 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":374000,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"461 Kbps","isStreaming":false,"title":"Long Season","artist":"Fishmans","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:48 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:48 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:48 volumio volumio[11235]: info: ------------------------------ 50ms Dec 20 01:42:48 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 20 01:42:48 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 20 01:42:48 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:48 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:48 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:48 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:48 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":374000,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"461 Kbps","isStreaming":false,"title":"Long Season","artist":"Fishmans","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:48 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:48 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:48 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:48 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:48 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":374000,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"461 Kbps","isStreaming":false,"title":"Long Season","artist":"Fishmans","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:48 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:48 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:48 volumio volumio[11235]: info: ------------------------------ 91ms Dec 20 01:42:48 volumio volumio[11235]: info: ------------------------------ 90ms Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:48 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:48 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:48 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:48 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:48 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:48 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:48 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:48 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:50 volumio volumio[11235]: info: Dec 20 01:42:50 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:50 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:50 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:50 volumio volumio[11235]: info: Dec 20 01:42:50 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:50 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:50 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:50 volumio volumio[11235]: info: Dec 20 01:42:50 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:50 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:50 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:50 volumio volumio[11235]: info: sendMpdCommand status took 4 milliseconds Dec 20 01:42:50 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:50 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:50 volumio volumio[11235]: info: sendMpdCommand status took 3 milliseconds Dec 20 01:42:50 volumio volumio[11235]: info: sendMpdCommand status took 1 milliseconds Dec 20 01:42:50 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 20 01:42:50 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:50 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:50 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:50 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:50 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:50 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:50 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:50 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"944 Kbps","isStreaming":false,"title":"Long Season","artist":"Fishmans","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:50 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:50 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:50 volumio volumio[11235]: info: ------------------------------ 39ms Dec 20 01:42:50 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 20 01:42:50 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 20 01:42:50 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:50 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:50 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:50 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:50 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"944 Kbps","isStreaming":false,"title":"Long Season","artist":"Fishmans","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:50 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:50 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:50 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:50 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:50 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"944 Kbps","isStreaming":false,"title":"Long Season","artist":"Fishmans","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:50 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:50 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:50 volumio volumio[11235]: info: ------------------------------ 77ms Dec 20 01:42:50 volumio volumio[11235]: info: ------------------------------ 76ms Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:50 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:50 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:50 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:50 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:50 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:50 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:50 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:50 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:52 volumio volumio[11235]: info: Dec 20 01:42:52 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:52 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:52 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:52 volumio volumio[11235]: info: Dec 20 01:42:52 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:52 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:52 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:52 volumio volumio[11235]: info: Dec 20 01:42:52 volumio volumio[11235]: ---------------------------- MPD announces state update: player Dec 20 01:42:52 volumio volumio[11235]: info: ControllerMpd::getState Dec 20 01:42:52 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand status Dec 20 01:42:52 volumio volumio[11235]: info: sendMpdCommand status took 3 milliseconds Dec 20 01:42:52 volumio volumio[11235]: info: sendMpdCommand status took 2 milliseconds Dec 20 01:42:52 volumio volumio[11235]: info: sendMpdCommand status took 2 milliseconds Dec 20 01:42:52 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:52 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:52 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:52 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:52 volumio volumio[11235]: verbose: ControllerMpd::parseState Dec 20 01:42:52 volumio volumio[11235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 01:42:52 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 20 01:42:52 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 20 01:42:52 volumio volumio[11235]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 20 01:42:52 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:52 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:52 volumio volumio[11235]: verbose: ControllerMpd::parseTrackInfo Dec 20 01:42:52 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:52 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:52 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":433000,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"521 Kbps","isStreaming":false,"title":"Long Season","artist":"Fishmans","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:52 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:52 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:52 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:52 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:52 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":433000,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"521 Kbps","isStreaming":false,"title":"Long Season","artist":"Fishmans","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:52 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:52 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:52 volumio volumio[11235]: info: ControllerMpd::pushState Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::servicePushState Dec 20 01:42:52 volumio volumio[11235]: verbose: In UPNP mode Dec 20 01:42:52 volumio volumio[11235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":433000,"duration":2491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"521 Kbps","isStreaming":false,"title":"Long Season","artist":"Fishmans","album":"98.12.28 男達の別れ","uri":"http://192.168.8.106:57645/external/audio/media/1000027802.flac","trackType":"flac"} Dec 20 01:42:52 volumio volumio[11235]: verbose: CURRENT POSITION 0 Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::syncState stateService play Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::syncState currentStatus play Dec 20 01:42:52 volumio volumio[11235]: info: Received an update from plugin. extracting info from payload Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::pushState Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::volumioPushState Dec 20 01:42:52 volumio volumio[11235]: info: ------------------------------ 71ms Dec 20 01:42:52 volumio volumio[11235]: info: ------------------------------ 73ms Dec 20 01:42:52 volumio volumio[11235]: info: ------------------------------ 73ms Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:52 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:52 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:52 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:52 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:52 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:52 volumio volumio[11235]: info: CoreCommandRouter::volumioGetQueue Dec 20 01:42:52 volumio volumio[11235]: info: CoreStateMachine::getQueue Dec 20 01:42:52 volumio volumio[11235]: info: CorePlayQueue::getQueue Dec 20 01:42:55 volumio volumio[11235]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 01:42:55 volumio volumio[11235]: Error: connect ETIMEDOUT 31.13.95.33:443 Dec 20 01:42:55 volumio volumio[11235]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 20 01:42:55 volumio volumio[11235]: errno: -110, Dec 20 01:42:55 volumio volumio[11235]: code: 'ETIMEDOUT', Dec 20 01:42:55 volumio volumio[11235]: syscall: 'connect', Dec 20 01:42:55 volumio volumio[11235]: address: '31.13.95.33', Dec 20 01:42:55 volumio volumio[11235]: port: 443 Dec 20 01:42:55 volumio volumio[11235]: } Dec 20 01:42:55 volumio volumio[11235]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 01:42:55 volumio sudo[12431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-20 01:41' Dec 20 01:42:55 volumio sudo[12431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"