Dec 12 17:23:01 volumio volumio[1165]: info: Preload queue cleared Dec 12 17:23:13 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 12 17:23:13 volumio volumio[1165]: info: Preload queue cleared Dec 12 17:23:14 volumio volumio[1165]: info: Preload queue cleared Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::ClearQueue Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::stop Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::stPlaybackTimer Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::serviceStop Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::serviceStop Dec 12 17:23:14 volumio volumio[1165]: info: [1765556594143] ControllerUPNPBrowser::stop Dec 12 17:23:14 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::clearPlayQueue Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::saveQueue Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::addQueueItems Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::addQueueItems Dec 12 17:23:14 volumio volumio[1165]: info: Preload queue cleared Dec 12 17:23:14 volumio volumio[1165]: info: Adding Item to queue: upnp/folder/http://192.168.1.92:32469/ContentDirectory/5f14a9b4-272d-84c7-719b-c4a7dd8e48e0/control.xml@61ee20f7add1a7e8c115 Dec 12 17:23:14 volumio volumio[1165]: info: Exploding uri upnp/folder/http://192.168.1.92:32469/ContentDirectory/5f14a9b4-272d-84c7-719b-c4a7dd8e48e0/control.xml@61ee20f7add1a7e8c115 in service upnp_browser Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::saveQueue Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::play index 0 Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::stop Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::play index undefined Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:14 volumio volumio[1165]: info: Dec 12 17:23:14 volumio volumio[1165]: ---------------------------- MPD announces state update: player Dec 12 17:23:14 volumio volumio[1165]: info: sendMpdCommand stop took 50 milliseconds Dec 12 17:23:14 volumio volumio[1165]: info: ControllerMpd::getState Dec 12 17:23:14 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Dec 12 17:23:14 volumio volumio[1165]: info: Dec 12 17:23:14 volumio volumio[1165]: ---------------------------- MPD announces state update: player Dec 12 17:23:14 volumio volumio[1165]: info: ControllerMpd::getState Dec 12 17:23:14 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Dec 12 17:23:14 volumio volumio[1165]: info: Dec 12 17:23:14 volumio volumio[1165]: ---------------------------- MPD announces state update: player Dec 12 17:23:14 volumio volumio[1165]: info: ControllerMpd::getState Dec 12 17:23:14 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Dec 12 17:23:14 volumio volumio[1165]: info: sendMpdCommand status took 7 milliseconds Dec 12 17:23:14 volumio volumio[1165]: info: sendMpdCommand status took 5 milliseconds Dec 12 17:23:14 volumio volumio[1165]: info: sendMpdCommand status took 4 milliseconds Dec 12 17:23:14 volumio volumio[1165]: verbose: ControllerMpd::parseState Dec 12 17:23:14 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 17:23:14 volumio volumio[1165]: verbose: ControllerMpd::parseState Dec 12 17:23:14 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 17:23:14 volumio volumio[1165]: verbose: ControllerMpd::parseState Dec 12 17:23:14 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 17:23:14 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 12 17:23:14 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 12 17:23:14 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 12 17:23:14 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Dec 12 17:23:14 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Dec 12 17:23:14 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Dec 12 17:23:14 volumio volumio[1165]: info: ControllerMpd::pushState Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:14 volumio volumio[1165]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp4","artist":null,"album":null,"uri":"http://192.168.1.92:32469/object/1052cd5bf9695d0b5574/file.mp4","trackType":"mp4"} Dec 12 17:23:14 volumio volumio[1165]: verbose: CURRENT POSITION 0 Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:14 volumio volumio[1165]: info: No code Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:14 volumio volumio[1165]: info: ControllerMpd::pushState Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:14 volumio volumio[1165]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp4","artist":null,"album":null,"uri":"http://192.168.1.92:32469/object/1052cd5bf9695d0b5574/file.mp4","trackType":"mp4"} Dec 12 17:23:14 volumio volumio[1165]: verbose: CURRENT POSITION 0 Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:14 volumio volumio[1165]: info: No code Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:14 volumio volumio[1165]: info: ControllerMpd::pushState Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:14 volumio volumio[1165]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp4","artist":null,"album":null,"uri":"http://192.168.1.92:32469/object/1052cd5bf9695d0b5574/file.mp4","trackType":"mp4"} Dec 12 17:23:14 volumio volumio[1165]: verbose: CURRENT POSITION 0 Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::syncState stateService stop Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:14 volumio volumio[1165]: info: No code Dec 12 17:23:14 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:14 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:14 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:14 volumio volumio[1165]: info: ------------------------------ 118ms Dec 12 17:23:14 volumio volumio[1165]: info: ------------------------------ 117ms Dec 12 17:23:14 volumio volumio[1165]: info: ------------------------------ 116ms Dec 12 17:23:18 volumio volumio[1165]: info: Preload queue cleared Dec 12 17:23:18 volumio volumio[1165]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 12 17:23:18 volumio volumio[1165]: info: CoreStateMachine::ClearQueue Dec 12 17:23:18 volumio volumio[1165]: info: CoreStateMachine::stop Dec 12 17:23:18 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:18 volumio volumio[1165]: info: CorePlayQueue::clearPlayQueue Dec 12 17:23:18 volumio volumio[1165]: info: CorePlayQueue::saveQueue Dec 12 17:23:18 volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Dec 12 17:23:18 volumio volumio[1165]: info: CoreStateMachine::addQueueItems Dec 12 17:23:18 volumio volumio[1165]: info: CorePlayQueue::addQueueItems Dec 12 17:23:18 volumio volumio[1165]: info: Preload queue cleared Dec 12 17:23:18 volumio volumio[1165]: info: Adding Item to queue: upnp/folder/http://192.168.1.92:32469/ContentDirectory/5f14a9b4-272d-84c7-719b-c4a7dd8e48e0/control.xml@61ee20f7add1a7e8c115 Dec 12 17:23:18 volumio volumio[1165]: info: Using cached record of: upnp/folder/http://192.168.1.92:32469/ContentDirectory/5f14a9b4-272d-84c7-719b-c4a7dd8e48e0/control.xml@61ee20f7add1a7e8c115 Dec 12 17:23:18 volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Dec 12 17:23:18 volumio volumio[1165]: info: CorePlayQueue::saveQueue Dec 12 17:23:18 volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Dec 12 17:23:18 volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Dec 12 17:23:18 volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Dec 12 17:23:18 volumio volumio[1165]: info: CoreStateMachine::play index 0 Dec 12 17:23:18 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:18 volumio volumio[1165]: info: CoreStateMachine::stop Dec 12 17:23:18 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:18 volumio volumio[1165]: info: CoreStateMachine::play index undefined Dec 12 17:23:18 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:18 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:20 volumio volumio[1165]: info: CoreCommandRouter::volumioplayNextItems Dec 12 17:23:20 volumio volumio[1165]: info: CoreStateMachine::playNextItems Dec 12 17:23:20 volumio volumio[1165]: info: CorePlayQueue::addPlayNextItems Dec 12 17:23:20 volumio volumio[1165]: info: Preload queue cleared Dec 12 17:23:20 volumio volumio[1165]: info: Adding Item to play next: upnp/folder/http://192.168.1.92:32469/ContentDirectory/5f14a9b4-272d-84c7-719b-c4a7dd8e48e0/control.xml@61ee20f7add1a7e8c115 Dec 12 17:23:20 volumio volumio[1165]: info: Using cached record of: upnp/folder/http://192.168.1.92:32469/ContentDirectory/5f14a9b4-272d-84c7-719b-c4a7dd8e48e0/control.xml@61ee20f7add1a7e8c115 Dec 12 17:23:20 volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Dec 12 17:23:20 volumio volumio[1165]: info: CorePlayQueue::saveQueue Dec 12 17:23:20 volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Dec 12 17:23:20 volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Dec 12 17:23:22 volumio volumio[1165]: info: Preload queue cleared Dec 12 17:23:22 volumio volumio[1165]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 12 17:23:22 volumio volumio[1165]: info: CoreStateMachine::ClearQueue Dec 12 17:23:22 volumio volumio[1165]: info: CoreStateMachine::stop Dec 12 17:23:22 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:22 volumio volumio[1165]: info: CorePlayQueue::clearPlayQueue Dec 12 17:23:22 volumio volumio[1165]: info: CorePlayQueue::saveQueue Dec 12 17:23:22 volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Dec 12 17:23:22 volumio volumio[1165]: info: CoreStateMachine::addQueueItems Dec 12 17:23:22 volumio volumio[1165]: info: CorePlayQueue::addQueueItems Dec 12 17:23:22 volumio volumio[1165]: info: Preload queue cleared Dec 12 17:23:22 volumio volumio[1165]: info: Adding Item to queue: upnp/folder/http://192.168.1.92:32469/ContentDirectory/5f14a9b4-272d-84c7-719b-c4a7dd8e48e0/control.xml@61ee20f7add1a7e8c115 Dec 12 17:23:22 volumio volumio[1165]: info: Using cached record of: upnp/folder/http://192.168.1.92:32469/ContentDirectory/5f14a9b4-272d-84c7-719b-c4a7dd8e48e0/control.xml@61ee20f7add1a7e8c115 Dec 12 17:23:22 volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Dec 12 17:23:22 volumio volumio[1165]: info: CorePlayQueue::saveQueue Dec 12 17:23:22 volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Dec 12 17:23:22 volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Dec 12 17:23:22 volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Dec 12 17:23:22 volumio volumio[1165]: info: CoreStateMachine::play index 0 Dec 12 17:23:22 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:22 volumio volumio[1165]: info: CoreStateMachine::stop Dec 12 17:23:22 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:22 volumio volumio[1165]: info: CoreStateMachine::play index undefined Dec 12 17:23:22 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:22 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:22 volumio volumio[1165]: info: Preload queue cleared Dec 12 17:23:22 volumio volumio[1165]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 12 17:23:22 volumio volumio[1165]: info: CoreStateMachine::ClearQueue Dec 12 17:23:22 volumio volumio[1165]: info: CoreStateMachine::stop Dec 12 17:23:22 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:22 volumio volumio[1165]: info: CorePlayQueue::clearPlayQueue Dec 12 17:23:22 volumio volumio[1165]: info: CorePlayQueue::saveQueue Dec 12 17:23:22 volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Dec 12 17:23:22 volumio volumio[1165]: info: CoreStateMachine::addQueueItems Dec 12 17:23:22 volumio volumio[1165]: info: CorePlayQueue::addQueueItems Dec 12 17:23:22 volumio volumio[1165]: info: Preload queue cleared Dec 12 17:23:22 volumio volumio[1165]: info: Adding Item to queue: upnp/folder/http://192.168.1.92:32469/ContentDirectory/5f14a9b4-272d-84c7-719b-c4a7dd8e48e0/control.xml@61ee20f7add1a7e8c115 Dec 12 17:23:22 volumio volumio[1165]: info: Using cached record of: upnp/folder/http://192.168.1.92:32469/ContentDirectory/5f14a9b4-272d-84c7-719b-c4a7dd8e48e0/control.xml@61ee20f7add1a7e8c115 Dec 12 17:23:22 volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Dec 12 17:23:23 volumio volumio[1165]: info: CorePlayQueue::saveQueue Dec 12 17:23:23 volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Dec 12 17:23:23 volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Dec 12 17:23:23 volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Dec 12 17:23:23 volumio volumio[1165]: info: CoreStateMachine::play index 0 Dec 12 17:23:23 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:23 volumio volumio[1165]: info: CoreStateMachine::stop Dec 12 17:23:23 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:23 volumio volumio[1165]: info: CoreStateMachine::play index undefined Dec 12 17:23:23 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:23 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:25 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 12 17:23:25 volumio volumio[1165]: info: Preload queue cleared Dec 12 17:23:26 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 12 17:23:26 volumio volumio[1165]: info: Preload queue cleared Dec 12 17:23:26 volumio volumio[1165]: info: Preloading song: upnp/http://192.168.1.92:32469/ContentDirectory/5f14a9b4-272d-84c7-719b-c4a7dd8e48e0/control.xml@8727a50e7c0617ce66f2 Dec 12 17:23:26 volumio volumio[1165]: info: Exploding uri upnp/http://192.168.1.92:32469/ContentDirectory/5f14a9b4-272d-84c7-719b-c4a7dd8e48e0/control.xml@8727a50e7c0617ce66f2 in service upnp_browser Dec 12 17:23:26 volumio volumio[1165]: info: No valid Plugin REST Endpoint Dec 12 17:23:26 volumio volumio[1165]: info: No valid Plugin REST Endpoint Dec 12 17:23:27 volumio volumio[1165]: info: Preload queue cleared Dec 12 17:23:27 volumio volumio[1165]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 12 17:23:27 volumio volumio[1165]: info: CoreStateMachine::ClearQueue Dec 12 17:23:27 volumio volumio[1165]: info: CoreStateMachine::stop Dec 12 17:23:27 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:27 volumio volumio[1165]: info: CorePlayQueue::clearPlayQueue Dec 12 17:23:27 volumio volumio[1165]: info: CorePlayQueue::saveQueue Dec 12 17:23:27 volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Dec 12 17:23:27 volumio volumio[1165]: info: CoreStateMachine::addQueueItems Dec 12 17:23:27 volumio volumio[1165]: info: CorePlayQueue::addQueueItems Dec 12 17:23:27 volumio volumio[1165]: info: Preload queue cleared Dec 12 17:23:27 volumio volumio[1165]: info: Adding Item to queue: upnp/folder/http://192.168.1.92:32469/ContentDirectory/5f14a9b4-272d-84c7-719b-c4a7dd8e48e0/control.xml@d98ac6c959e4500a8d46 Dec 12 17:23:27 volumio volumio[1165]: info: Exploding uri upnp/folder/http://192.168.1.92:32469/ContentDirectory/5f14a9b4-272d-84c7-719b-c4a7dd8e48e0/control.xml@d98ac6c959e4500a8d46 in service upnp_browser Dec 12 17:23:27 volumio volumio[1165]: info: CoreCommandRouter::volumioPushQueue Dec 12 17:23:27 volumio volumio[1165]: info: CorePlayQueue::saveQueue Dec 12 17:23:27 volumio volumio[1165]: info: CoreStateMachine::updateTrackBlock Dec 12 17:23:27 volumio volumio[1165]: info: CorePlayQueue::getTrackBlock Dec 12 17:23:27 volumio volumio[1165]: info: CoreCommandRouter::volumioPlay Dec 12 17:23:27 volumio volumio[1165]: info: CoreStateMachine::play index 0 Dec 12 17:23:27 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:27 volumio volumio[1165]: info: CoreStateMachine::stop Dec 12 17:23:27 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:27 volumio volumio[1165]: info: CoreStateMachine::play index undefined Dec 12 17:23:27 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 17:23:27 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:27 volumio volumio[1165]: info: CoreStateMachine::startPlaybackTimer Dec 12 17:23:27 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:27 volumio volumio[1165]: info: [1765556607859] ControllerUPNPBrowser::clearAddPlayTrack Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 17:23:27 volumio volumio[1165]: info: sendMpdCommand stop took 6 milliseconds Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand clear Dec 12 17:23:27 volumio volumio[1165]: info: Dec 12 17:23:27 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Dec 12 17:23:27 volumio volumio[1165]: info: Ignoring MPD Status Update Dec 12 17:23:27 volumio volumio[1165]: info: sendMpdCommand clear took 2 milliseconds Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.92:32469/object/8727a50e7c0617ce66f2/file.flac" Dec 12 17:23:27 volumio volumio[1165]: info: Dec 12 17:23:27 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Dec 12 17:23:27 volumio volumio[1165]: info: Ignoring MPD Status Update Dec 12 17:23:27 volumio volumio[1165]: info: Dec 12 17:23:27 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Dec 12 17:23:27 volumio volumio[1165]: info: Ignoring MPD Status Update Dec 12 17:23:27 volumio volumio[1165]: error: updateQueue error: null Dec 12 17:23:27 volumio volumio[1165]: info: ------------------------------ 5ms Dec 12 17:23:27 volumio volumio[1165]: error: updateQueue error: null Dec 12 17:23:27 volumio volumio[1165]: error: updateQueue error: null Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.92:32469/object/8727a50e7c0617ce66f2/file.flac" Dec 12 17:23:27 volumio volumio[1165]: info: ------------------------------ 44ms Dec 12 17:23:27 volumio volumio[1165]: info: ------------------------------ 43ms Dec 12 17:23:27 volumio volumio[1165]: info: Dec 12 17:23:27 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Dec 12 17:23:27 volumio volumio[1165]: info: Ignoring MPD Status Update Dec 12 17:23:27 volumio volumio[1165]: info: sendMpdCommand add "http://192.168.1.92:32469/object/8727a50e7c0617ce66f2/file.flac" took 20 milliseconds Dec 12 17:23:27 volumio volumio[1165]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand play Dec 12 17:23:27 volumio volumio[1165]: info: Dec 12 17:23:27 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Dec 12 17:23:27 volumio volumio[1165]: info: Ignoring MPD Status Update Dec 12 17:23:27 volumio volumio[1165]: info: Dec 12 17:23:27 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Dec 12 17:23:27 volumio volumio[1165]: info: Ignoring MPD Status Update Dec 12 17:23:27 volumio volumio[1165]: info: ------------------------------ 11ms Dec 12 17:23:27 volumio volumio[1165]: info: sendMpdCommand play took 9 milliseconds Dec 12 17:23:27 volumio volumio[1165]: info: ------------------------------ 10ms Dec 12 17:23:27 volumio volumio[1165]: info: ------------------------------ 6ms Dec 12 17:23:27 volumio volumio[1165]: info: Dec 12 17:23:27 volumio volumio[1165]: ---------------------------- MPD announces state update: player Dec 12 17:23:27 volumio volumio[1165]: info: ControllerMpd::getState Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Dec 12 17:23:27 volumio volumio[1165]: info: Dec 12 17:23:27 volumio volumio[1165]: ---------------------------- MPD announces state update: player Dec 12 17:23:27 volumio volumio[1165]: info: ControllerMpd::getState Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Dec 12 17:23:27 volumio volumio[1165]: info: Dec 12 17:23:27 volumio volumio[1165]: ---------------------------- MPD announces state update: player Dec 12 17:23:27 volumio volumio[1165]: info: Dec 12 17:23:27 volumio volumio[1165]: ---------------------------- MPD announces state update: player Dec 12 17:23:27 volumio volumio[1165]: info: ControllerMpd::getState Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Dec 12 17:23:27 volumio volumio[1165]: info: sendMpdCommand status took 6 milliseconds Dec 12 17:23:27 volumio volumio[1165]: info: ControllerMpd::getState Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::parseState Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 17:23:27 volumio volumio[1165]: info: Dec 12 17:23:27 volumio volumio[1165]: ---------------------------- MPD announces state update: player Dec 12 17:23:27 volumio volumio[1165]: info: ControllerMpd::getState Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Dec 12 17:23:27 volumio volumio[1165]: info: Dec 12 17:23:27 volumio volumio[1165]: ---------------------------- MPD announces state update: player Dec 12 17:23:27 volumio volumio[1165]: info: ControllerMpd::getState Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Dec 12 17:23:27 volumio volumio[1165]: info: sendMpdCommand status took 17 milliseconds Dec 12 17:23:27 volumio volumio[1165]: info: sendMpdCommand status took 15 milliseconds Dec 12 17:23:27 volumio volumio[1165]: info: sendMpdCommand status took 14 milliseconds Dec 12 17:23:27 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::parseState Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::parseState Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::parseState Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 17:23:27 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Dec 12 17:23:28 volumio volumio[1165]: info: ControllerMpd::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:28 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.flac","artist":null,"album":null,"uri":"http://192.168.1.92:32469/object/8727a50e7c0617ce66f2/file.flac","trackType":"flac"} Dec 12 17:23:28 volumio volumio[1165]: verbose: CURRENT POSITION 0 Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Dec 12 17:23:28 volumio volumio[1165]: info: ------------------------------ 126ms Dec 12 17:23:28 volumio volumio[1165]: info: Dec 12 17:23:28 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Dec 12 17:23:28 volumio volumio[1165]: info: Ignoring MPD Status Update Dec 12 17:23:28 volumio volumio[1165]: info: Dec 12 17:23:28 volumio volumio[1165]: ---------------------------- MPD announces state update: player Dec 12 17:23:28 volumio volumio[1165]: info: ControllerMpd::getState Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Dec 12 17:23:28 volumio volumio[1165]: info: Dec 12 17:23:28 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Dec 12 17:23:28 volumio volumio[1165]: info: Ignoring MPD Status Update Dec 12 17:23:28 volumio volumio[1165]: info: Dec 12 17:23:28 volumio volumio[1165]: ---------------------------- MPD announces state update: player Dec 12 17:23:28 volumio volumio[1165]: info: ControllerMpd::getState Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Dec 12 17:23:28 volumio volumio[1165]: info: Dec 12 17:23:28 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Dec 12 17:23:28 volumio volumio[1165]: info: Ignoring MPD Status Update Dec 12 17:23:28 volumio volumio[1165]: info: Dec 12 17:23:28 volumio volumio[1165]: ---------------------------- MPD announces state update: player Dec 12 17:23:28 volumio volumio[1165]: info: sendMpdCommand status took 145 milliseconds Dec 12 17:23:28 volumio volumio[1165]: info: sendMpdCommand status took 145 milliseconds Dec 12 17:23:28 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 138 milliseconds Dec 12 17:23:28 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 138 milliseconds Dec 12 17:23:28 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 138 milliseconds Dec 12 17:23:28 volumio volumio[1165]: info: ControllerMpd::getState Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::parseState Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::parseState Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Dec 12 17:23:28 volumio volumio[1165]: info: ControllerMpd::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:28 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.flac","artist":null,"album":null,"uri":"http://192.168.1.92:32469/object/8727a50e7c0617ce66f2/file.flac","trackType":"flac"} Dec 12 17:23:28 volumio volumio[1165]: verbose: CURRENT POSITION 0 Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Dec 12 17:23:28 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:28 volumio volumio[1165]: info: ControllerMpd::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:28 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.flac","artist":null,"album":null,"uri":"http://192.168.1.92:32469/object/8727a50e7c0617ce66f2/file.flac","trackType":"flac"} Dec 12 17:23:28 volumio volumio[1165]: verbose: CURRENT POSITION 0 Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Dec 12 17:23:28 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:28 volumio volumio[1165]: info: ControllerMpd::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:28 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.flac","artist":null,"album":null,"uri":"http://192.168.1.92:32469/object/8727a50e7c0617ce66f2/file.flac","trackType":"flac"} Dec 12 17:23:28 volumio volumio[1165]: verbose: CURRENT POSITION 0 Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Dec 12 17:23:28 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:28 volumio volumio[1165]: info: ------------------------------ 349ms Dec 12 17:23:28 volumio volumio[1165]: info: ------------------------------ 345ms Dec 12 17:23:28 volumio volumio[1165]: info: ------------------------------ 346ms Dec 12 17:23:28 volumio volumio[1165]: info: ------------------------------ 247ms Dec 12 17:23:28 volumio volumio[1165]: info: sendMpdCommand status took 236 milliseconds Dec 12 17:23:28 volumio volumio[1165]: info: ------------------------------ 235ms Dec 12 17:23:28 volumio volumio[1165]: info: sendMpdCommand status took 235 milliseconds Dec 12 17:23:28 volumio volumio[1165]: info: ------------------------------ 234ms Dec 12 17:23:28 volumio volumio[1165]: info: sendMpdCommand status took 212 milliseconds Dec 12 17:23:28 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 213 milliseconds Dec 12 17:23:28 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 214 milliseconds Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::parseState Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::parseState Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::parseState Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Dec 12 17:23:28 volumio volumio[1165]: info: ControllerMpd::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:28 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sticker On My Suitcase","artist":"Alle Farben","album":"Sticker On My Suitcase","uri":"http://192.168.1.92:32469/object/8727a50e7c0617ce66f2/file.flac","trackType":"flac"} Dec 12 17:23:28 volumio volumio[1165]: verbose: CURRENT POSITION 0 Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Dec 12 17:23:28 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:28 volumio volumio[1165]: info: ControllerMpd::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:28 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sticker On My Suitcase","artist":"Alle Farben","album":"Sticker On My Suitcase","uri":"http://192.168.1.92:32469/object/8727a50e7c0617ce66f2/file.flac","trackType":"flac"} Dec 12 17:23:28 volumio volumio[1165]: verbose: CURRENT POSITION 0 Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Dec 12 17:23:28 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:28 volumio volumio[1165]: info: ------------------------------ 408ms Dec 12 17:23:28 volumio volumio[1165]: info: ------------------------------ 406ms Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioGetQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioGetQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioGetQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioGetQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 123 milliseconds Dec 12 17:23:28 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 122 milliseconds Dec 12 17:23:28 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 122 milliseconds Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Dec 12 17:23:28 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Dec 12 17:23:28 volumio volumio[1165]: info: ControllerMpd::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:28 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":228,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1204 Kbps","isStreaming":false,"title":"Sticker On My Suitcase","artist":"Alle Farben","album":"Sticker On My Suitcase","uri":"http://192.168.1.92:32469/object/8727a50e7c0617ce66f2/file.flac","trackType":"flac"} Dec 12 17:23:28 volumio volumio[1165]: verbose: CURRENT POSITION 0 Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Dec 12 17:23:28 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:28 volumio volumio[1165]: info: ControllerMpd::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:28 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":239,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1204 Kbps","isStreaming":false,"title":"Sticker On My Suitcase","artist":"Alle Farben","album":"Sticker On My Suitcase","uri":"http://192.168.1.92:32469/object/8727a50e7c0617ce66f2/file.flac","trackType":"flac"} Dec 12 17:23:28 volumio volumio[1165]: verbose: CURRENT POSITION 0 Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Dec 12 17:23:28 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:28 volumio volumio[1165]: info: ControllerMpd::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Dec 12 17:23:28 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":262,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1204 Kbps","isStreaming":false,"title":"Sticker On My Suitcase","artist":"Alle Farben","album":"Sticker On My Suitcase","uri":"http://192.168.1.92:32469/object/8727a50e7c0617ce66f2/file.flac","trackType":"flac"} Dec 12 17:23:28 volumio volumio[1165]: verbose: CURRENT POSITION 0 Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Dec 12 17:23:28 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 12 17:23:28 volumio volumio[1165]: info: ------------------------------ 458ms Dec 12 17:23:28 volumio volumio[1165]: info: ------------------------------ 448ms Dec 12 17:23:28 volumio volumio[1165]: info: ------------------------------ 430ms Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioGetQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioGetQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioGetQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioGetQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioGetQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreCommandRouter::volumioGetQueue Dec 12 17:23:28 volumio volumio[1165]: info: CoreStateMachine::getQueue Dec 12 17:23:28 volumio volumio[1165]: info: CorePlayQueue::getQueue Dec 12 17:24:49 volumio volumio[1165]: verbose: New Socket.io Connection to 192.168.1.173 from 192.168.1.122 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Dec 12 17:24:51 volumio volumio[1165]: verbose: New Socket.io Connection to 192.168.1.173 from 192.168.1.122 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Dec 12 17:24:52 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 12 17:24:52 volumio volumio[1165]: info: CoreCommandRouter::volumioGetVisibleSources Dec 12 17:24:52 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 17:24:52 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Dec 12 17:24:52 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 12 17:24:52 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 12 17:24:52 volumio volumio[1165]: info: Received Get System Info Dec 12 17:24:52 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 17:24:52 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 17:24:52 volumio volumio[1165]: info: Discovery: Getting this device information Dec 12 17:24:52 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Dec 12 17:24:52 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 17:24:52 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Dec 12 17:24:52 volumio volumio[1165]: info: Listing playlists Dec 12 17:24:52 volumio volumio[1165]: info: CoreCommandRouter::volumioGetQueue Dec 12 17:24:52 volumio volumio[1165]: info: CoreStateMachine::getQueue Dec 12 17:24:52 volumio volumio[1165]: info: CorePlayQueue::getQueue Dec 12 17:24:52 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 12 17:24:52 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 12 17:24:54 volumio volumio[1165]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 17:24:54 volumio volumio[1165]: TypeError: Cannot read properties of null (reading 'slice') Dec 12 17:24:54 volumio volumio[1165]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Dec 12 17:24:54 volumio volumio[1165]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Dec 12 17:24:54 volumio volumio[1165]: at Object.onceWrapper (node:events:629:26) Dec 12 17:24:54 volumio volumio[1165]: at ClientRequest.emit (node:events:514:28) Dec 12 17:24:54 volumio volumio[1165]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Dec 12 17:24:54 volumio volumio[1165]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Dec 12 17:24:54 volumio volumio[1165]: at TLSSocket.socketOnData (node:_http_client:535:22) Dec 12 17:24:54 volumio volumio[1165]: at TLSSocket.emit (node:events:514:28) Dec 12 17:24:54 volumio volumio[1165]: at addChunk (node:internal/streams/readable:343:12) Dec 12 17:24:54 volumio volumio[1165]: at readableAddChunk (node:internal/streams/readable:316:9) Dec 12 17:24:54 volumio volumio[1165]: at Readable.push (node:internal/streams/readable:253:10) Dec 12 17:24:54 volumio volumio[1165]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Dec 12 17:24:54 volumio volumio[1165]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 17:24:55 volumio sudo[3394]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-12 17:23' Dec 12 17:24:55 volumio sudo[3394]: 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"