-- Logs begin at Tue 2024-04-09 15:47:46 UTC, end at Tue 2024-04-09 16:28:25 UTC. -- Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 39 milliseconds Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 4 milliseconds Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 4 Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":444,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=2934581&eid=38623128&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683619&hmac=fCqzrlojfVKPRJ_FR9REZpVQPQ4","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623128&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683619&hmac=fCqzrlojfVKPRJ_FR9REZpVQPQ4","trackType":"qobuz"} Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 4 Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService play Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus stop Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 72ms Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 25 milliseconds Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 4 Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: verbose: STATE SERVICE {"status":"play","position":0,"seek":229,"duration":444,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2887 Kbps","isStreaming":false,"title":"file?uid=2934581&eid=38623128&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683619&hmac=fCqzrlojfVKPRJ_FR9REZpVQPQ4","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623128&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683619&hmac=fCqzrlojfVKPRJ_FR9REZpVQPQ4","trackType":"qobuz"} Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 4 Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService play Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus play Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: Received an update from plugin. extracting info from payload Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:00 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 71ms Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::ClearQueue Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stop Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: verbose: UNSET VOLATILE Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stPlaybackTimer Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::updateTrackBlock Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrackBlock Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 4 Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::serviceStop Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 4 Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::serviceStop Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: [1712680027986] ControllerQobuz::stop Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::stop Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand stop Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::clearPlayQueue Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::saveQueue Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:07 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushQueue Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::addQueueItems Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::addQueueItems Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623124 Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623124 in service qobuz Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623125 Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623125 in service qobuz Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623126 Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623126 in service qobuz Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623127 Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623127 in service qobuz Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623128 Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623128 in service qobuz Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623129 Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623129 in service qobuz Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623130 Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623130 in service qobuz Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623131 Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623131 in service qobuz Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623132 Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623132 in service qobuz Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623133 Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623133 in service qobuz Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Updating push state because input changed Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumiosetSourceActivempd Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: explodeUri took 256 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: explodeUri took 235 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand stop took 277 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: explodeUri took 250 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: explodeUri took 252 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 11 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: explodeUri took 273 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 4 Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: 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?uid=2934581&eid=38623128&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683619&hmac=fCqzrlojfVKPRJ_FR9REZpVQPQ4","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623128&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683619&hmac=fCqzrlojfVKPRJ_FR9REZpVQPQ4","trackType":"qobuz"} Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 4 Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService stop Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus stop Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: No code Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 54ms Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: explodeUri took 326 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: explodeUri took 338 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: explodeUri took 354 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: explodeUri took 362 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: explodeUri took 418 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::saveQueue Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushQueue Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::updateTrackBlock Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrackBlock Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPlay Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: verbose: UNSET VOLATILE Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::play index 6 Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stop Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: verbose: UNSET VOLATILE Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::play index undefined Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 6 Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::startPlaybackTimer Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 6 Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: [1712680028455] ControllerQobuz::clearAddPlayTrack Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: getStreamUrl took 363 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand stop Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand stop took 1 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand clear Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces system playlist update Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Ignoring MPD Status Update Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand clear took 2 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand load "http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623130&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683628&hmac=UlV7jhuz189ZL5xEFm3iw78zyeQ" Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: error: updateQueue error: null Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 5ms Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand add "http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623130&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683628&hmac=UlV7jhuz189ZL5xEFm3iw78zyeQ" Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces system playlist update Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: Ignoring MPD Status Update Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand add "http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623130&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683628&hmac=UlV7jhuz189ZL5xEFm3iw78zyeQ" took 3 milliseconds Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand play Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 6ms Apr 09 16:27:08 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand play took 2 milliseconds Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 43 milliseconds Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 4 milliseconds Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 6 Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2971 Kbps","isStreaming":false,"title":"file?uid=2934581&eid=38623130&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683628&hmac=UlV7jhuz189ZL5xEFm3iw78zyeQ","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623130&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683628&hmac=UlV7jhuz189ZL5xEFm3iw78zyeQ","trackType":"qobuz"} Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 6 Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService play Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus stop Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 86ms Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 34 milliseconds Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 6 Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: verbose: STATE SERVICE {"status":"play","position":0,"seek":191,"duration":293,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2993 Kbps","isStreaming":false,"title":"file?uid=2934581&eid=38623130&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683628&hmac=UlV7jhuz189ZL5xEFm3iw78zyeQ","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623130&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683628&hmac=UlV7jhuz189ZL5xEFm3iw78zyeQ","trackType":"qobuz"} Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 6 Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService play Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus play Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: Received an update from plugin. extracting info from payload Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:09 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 79ms Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::ClearQueue Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stop Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: verbose: UNSET VOLATILE Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stPlaybackTimer Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::updateTrackBlock Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrackBlock Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 6 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::serviceStop Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 6 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::serviceStop Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: [1712680036172] ControllerQobuz::stop Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::stop Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand stop Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::clearPlayQueue Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::saveQueue Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushQueue Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::addQueueItems Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::addQueueItems Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623124 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623124 in service qobuz Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623125 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623125 in service qobuz Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623126 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623126 in service qobuz Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623127 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623127 in service qobuz Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623128 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623128 in service qobuz Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623129 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623129 in service qobuz Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623130 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623130 in service qobuz Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623131 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623131 in service qobuz Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623132 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623132 in service qobuz Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623133 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623133 in service qobuz Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Updating push state because input changed Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumiosetSourceActivempd Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand stop took 288 milliseconds Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 2 milliseconds Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 6 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: 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?uid=2934581&eid=38623130&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683628&hmac=UlV7jhuz189ZL5xEFm3iw78zyeQ","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623130&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683628&hmac=UlV7jhuz189ZL5xEFm3iw78zyeQ","trackType":"qobuz"} Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 6 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService stop Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus stop Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: No code Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 34ms Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: explodeUri took 302 milliseconds Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: explodeUri took 318 milliseconds Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: explodeUri took 307 milliseconds Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: explodeUri took 342 milliseconds Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: explodeUri took 387 milliseconds Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: explodeUri took 388 milliseconds Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: explodeUri took 393 milliseconds Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: explodeUri took 387 milliseconds Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: explodeUri took 563 milliseconds Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: explodeUri took 746 milliseconds Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::saveQueue Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushQueue Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::updateTrackBlock Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrackBlock Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPlay Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: verbose: UNSET VOLATILE Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::play index 7 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stop Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: verbose: UNSET VOLATILE Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::play index undefined Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 7 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::startPlaybackTimer Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 7 Apr 09 16:27:16 stream-box-s2-ultra volumio[1209]: info: [1712680036961] ControllerQobuz::clearAddPlayTrack Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: getStreamUrl took 349 milliseconds Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand stop Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand stop took 2 milliseconds Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand clear Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces system playlist update Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: Ignoring MPD Status Update Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand clear took 6 milliseconds Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand load "http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623131&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683637&hmac=zLYjdjUGUJYrCKI1u5lS82sVmZA" Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: error: updateQueue error: null Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 10ms Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand add "http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623131&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683637&hmac=zLYjdjUGUJYrCKI1u5lS82sVmZA" Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces system playlist update Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: Ignoring MPD Status Update Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand add "http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623131&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683637&hmac=zLYjdjUGUJYrCKI1u5lS82sVmZA" took 4 milliseconds Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand play Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 7ms Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand play took 3 milliseconds Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 31 milliseconds Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 7 Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=2934581&eid=38623131&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683637&hmac=zLYjdjUGUJYrCKI1u5lS82sVmZA","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623131&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683637&hmac=zLYjdjUGUJYrCKI1u5lS82sVmZA","trackType":"qobuz"} Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 7 Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService play Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus stop Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 60ms Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 24 milliseconds Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 7 Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: STATE SERVICE {"status":"play","position":0,"seek":181,"duration":214,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3083 Kbps","isStreaming":false,"title":"file?uid=2934581&eid=38623131&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683637&hmac=zLYjdjUGUJYrCKI1u5lS82sVmZA","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623131&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683637&hmac=zLYjdjUGUJYrCKI1u5lS82sVmZA","trackType":"qobuz"} Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 7 Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService play Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus play Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: Received an update from plugin. extracting info from payload Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:17 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 73ms Apr 09 16:27:25 stream-box-s2-ultra volumio[1209]: info: Listing playlists Apr 09 16:27:27 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: qobuz , explodeUri Apr 09 16:27:28 stream-box-s2-ultra volumio[1209]: info: explodeUri took 150 milliseconds Apr 09 16:27:28 stream-box-s2-ultra volumio[1209]: info: Saving local item Elettronica Apr 09 16:27:28 stream-box-s2-ultra volumio[1209]: info: Pushing Favourites {"uri":"qobuz://song/38623131","favourite":false} Apr 09 16:27:28 stream-box-s2-ultra volumio[1209]: info: Listing playlists Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::ClearQueue Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stop Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: verbose: UNSET VOLATILE Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stPlaybackTimer Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::updateTrackBlock Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrackBlock Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 7 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::serviceStop Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 7 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::serviceStop Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: [1712680055495] ControllerQobuz::stop Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::stop Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand stop Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::clearPlayQueue Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::saveQueue Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushQueue Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::addQueueItems Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::addQueueItems Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623124 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623124 in service qobuz Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623125 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623125 in service qobuz Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623126 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623126 in service qobuz Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623127 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623127 in service qobuz Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623128 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623128 in service qobuz Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623129 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623129 in service qobuz Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623130 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623130 in service qobuz Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623131 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623131 in service qobuz Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623132 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623132 in service qobuz Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623133 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623133 in service qobuz Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Updating push state because input changed Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumiosetSourceActivempd Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: explodeUri took 197 milliseconds Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: explodeUri took 196 milliseconds Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: explodeUri took 223 milliseconds Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: explodeUri took 212 milliseconds Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: explodeUri took 220 milliseconds Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand stop took 252 milliseconds Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 1 milliseconds Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 7 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: 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?uid=2934581&eid=38623131&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683637&hmac=zLYjdjUGUJYrCKI1u5lS82sVmZA","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623131&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683637&hmac=zLYjdjUGUJYrCKI1u5lS82sVmZA","trackType":"qobuz"} Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 7 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService stop Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus stop Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: No code Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 40ms Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: explodeUri took 272 milliseconds Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: explodeUri took 287 milliseconds Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: explodeUri took 300 milliseconds Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: explodeUri took 329 milliseconds Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: explodeUri took 353 milliseconds Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::saveQueue Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushQueue Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::updateTrackBlock Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrackBlock Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPlay Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: verbose: UNSET VOLATILE Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::play index 8 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stop Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: verbose: UNSET VOLATILE Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::play index undefined Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 8 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::startPlaybackTimer Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 8 Apr 09 16:27:35 stream-box-s2-ultra volumio[1209]: info: [1712680055883] ControllerQobuz::clearAddPlayTrack Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: getStreamUrl took 321 milliseconds Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand stop Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand stop took 1 milliseconds Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand clear Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces system playlist update Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: Ignoring MPD Status Update Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand clear took 6 milliseconds Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand load "http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623132&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683656&hmac=AabUBJWzZnNxt-cfvM29E8Z6b7g" Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: error: updateQueue error: null Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 12ms Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand add "http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623132&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683656&hmac=AabUBJWzZnNxt-cfvM29E8Z6b7g" Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces system playlist update Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: Ignoring MPD Status Update Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand add "http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623132&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683656&hmac=AabUBJWzZnNxt-cfvM29E8Z6b7g" took 3 milliseconds Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand play Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 8ms Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand play took 6 milliseconds Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 29 milliseconds Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 8 Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2824 Kbps","isStreaming":false,"title":"file?uid=2934581&eid=38623132&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683656&hmac=AabUBJWzZnNxt-cfvM29E8Z6b7g","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623132&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683656&hmac=AabUBJWzZnNxt-cfvM29E8Z6b7g","trackType":"qobuz"} Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 8 Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService play Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus stop Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 55ms Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 22 milliseconds Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 8 Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341,"duration":235,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2824 Kbps","isStreaming":false,"title":"file?uid=2934581&eid=38623132&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683656&hmac=AabUBJWzZnNxt-cfvM29E8Z6b7g","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623132&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683656&hmac=AabUBJWzZnNxt-cfvM29E8Z6b7g","trackType":"qobuz"} Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 8 Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService play Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus play Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: Received an update from plugin. extracting info from payload Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:36 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 72ms Apr 09 16:27:45 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioSeek Apr 09 16:27:45 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::seek Apr 09 16:27:45 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 8 Apr 09 16:27:45 stream-box-s2-ultra volumio[1209]: info: TRACKBLOCK {"uri":"qobuz://song/38623132","service":"qobuz","name":"Step by Step","title":"Step by Step","album":"Eye In The Sky","type":"track","tracknumber":9,"volumeNumber":1,"albumart":"https://static.qobuz.com/images/covers/85/96/0886446379685_600.jpg","duration":235,"trackType":"qobuz","samplerate":"96 KHz","bitdepth":"24 bit","explicit":false,"artist":"The Alan Parsons Project","artistUri":"qobuz://artist/122909","albumUri":"qobuz://album/0886446379685","audioQuality":"","tagImage":""} Apr 09 16:27:45 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::startPlaybackTimer Apr 09 16:27:45 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 8 Apr 09 16:27:45 stream-box-s2-ultra volumio[1209]: info: [1712680065849] ControllerQobuz::seek Apr 09 16:27:45 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 09 16:27:45 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::seek Apr 09 16:27:45 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:45 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:45 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:45 stream-box-s2-ultra volumio[1209]: info: Updating push state because input changed Apr 09 16:27:45 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumiosetSourceActivempd Apr 09 16:27:45 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: error: null Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 1 milliseconds Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 8 Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: verbose: STATE SERVICE {"status":"play","position":0,"seek":40000,"duration":235,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3087 Kbps","isStreaming":false,"title":"file?uid=2934581&eid=38623132&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683656&hmac=AabUBJWzZnNxt-cfvM29E8Z6b7g","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623132&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683656&hmac=AabUBJWzZnNxt-cfvM29E8Z6b7g","trackType":"qobuz"} Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 8 Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService play Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus play Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: Received an update from plugin. extracting info from payload Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:46 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 39ms Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::ClearQueue Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stop Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: verbose: UNSET VOLATILE Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stPlaybackTimer Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::updateTrackBlock Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrackBlock Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 8 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::serviceStop Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 8 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::serviceStop Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: [1712680068469] ControllerQobuz::stop Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::stop Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand stop Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::clearPlayQueue Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::saveQueue Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushQueue Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::addQueueItems Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::addQueueItems Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623124 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623124 in service qobuz Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623125 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623125 in service qobuz Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623126 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623126 in service qobuz Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623127 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623127 in service qobuz Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623128 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623128 in service qobuz Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623129 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623129 in service qobuz Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623130 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623130 in service qobuz Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623131 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623131 in service qobuz Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623132 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623132 in service qobuz Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/38623133 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/38623133 in service qobuz Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Updating push state because input changed Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumiosetSourceActivempd Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand stop took 138 milliseconds Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 3 milliseconds Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 8 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: 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?uid=2934581&eid=38623132&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683656&hmac=AabUBJWzZnNxt-cfvM29E8Z6b7g","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623132&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683656&hmac=AabUBJWzZnNxt-cfvM29E8Z6b7g","trackType":"qobuz"} Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 8 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService stop Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus stop Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: No code Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 40ms Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: explodeUri took 262 milliseconds Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: explodeUri took 279 milliseconds Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: explodeUri took 404 milliseconds Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: explodeUri took 440 milliseconds Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: explodeUri took 425 milliseconds Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: explodeUri took 426 milliseconds Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: explodeUri took 437 milliseconds Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: explodeUri took 459 milliseconds Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: explodeUri took 461 milliseconds Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: explodeUri took 445 milliseconds Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::saveQueue Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushQueue Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::updateTrackBlock Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrackBlock Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPlay Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: verbose: UNSET VOLATILE Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::play index 9 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stop Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: verbose: UNSET VOLATILE Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::play index undefined Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 9 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::startPlaybackTimer Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 9 Apr 09 16:27:48 stream-box-s2-ultra volumio[1209]: info: [1712680068958] ControllerQobuz::clearAddPlayTrack Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: getStreamUrl took 344 milliseconds Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand stop Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand stop took 1 milliseconds Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand clear Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces system playlist update Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: Ignoring MPD Status Update Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand clear took 4 milliseconds Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand load "http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623133&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683669&hmac=oz_15ZzxLlNum1Ob10XO96Zp41c" Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: error: updateQueue error: null Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 66ms Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand add "http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623133&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683669&hmac=oz_15ZzxLlNum1Ob10XO96Zp41c" Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces system playlist update Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: Ignoring MPD Status Update Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand add "http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623133&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683669&hmac=oz_15ZzxLlNum1Ob10XO96Zp41c" took 3 milliseconds Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand play Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 4ms Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand play took 10 milliseconds Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 39 milliseconds Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 9 milliseconds Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 9 Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":299,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2911 Kbps","isStreaming":false,"title":"file?uid=2934581&eid=38623133&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683669&hmac=oz_15ZzxLlNum1Ob10XO96Zp41c","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623133&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683669&hmac=oz_15ZzxLlNum1Ob10XO96Zp41c","trackType":"qobuz"} Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 9 Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService play Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus stop Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 88ms Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 36 milliseconds Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 9 Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: STATE SERVICE {"status":"play","position":0,"seek":368,"duration":299,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2952 Kbps","isStreaming":false,"title":"file?uid=2934581&eid=38623133&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683669&hmac=oz_15ZzxLlNum1Ob10XO96Zp41c","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623133&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683669&hmac=oz_15ZzxLlNum1Ob10XO96Zp41c","trackType":"qobuz"} Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 9 Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService play Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus play Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: Received an update from plugin. extracting info from payload Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:49 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 96ms Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioSeek Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::seek Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 9 Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: TRACKBLOCK {"uri":"qobuz://song/38623133","service":"qobuz","name":"Old and Wise","title":"Old and Wise","album":"Eye In The Sky","type":"track","tracknumber":10,"volumeNumber":1,"albumart":"https://static.qobuz.com/images/covers/85/96/0886446379685_600.jpg","duration":298,"trackType":"qobuz","samplerate":"96 KHz","bitdepth":"24 bit","explicit":false,"artist":"The Alan Parsons Project","artistUri":"qobuz://artist/122909","albumUri":"qobuz://album/0886446379685","audioQuality":"","tagImage":""} Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::startPlaybackTimer Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 9 Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: [1712680071478] ControllerQobuz::seek Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::seek Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: Updating push state because input changed Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumiosetSourceActivempd Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: error: null Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 1 milliseconds Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 9 Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: verbose: STATE SERVICE {"status":"play","position":0,"seek":65000,"duration":299,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2991 Kbps","isStreaming":false,"title":"file?uid=2934581&eid=38623133&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683669&hmac=oz_15ZzxLlNum1Ob10XO96Zp41c","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623133&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683669&hmac=oz_15ZzxLlNum1Ob10XO96Zp41c","trackType":"qobuz"} Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 9 Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService play Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus play Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: Received an update from plugin. extracting info from payload Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:51 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 33ms Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPause Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pause Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stPlaybackTimer Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::servicePause Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 9 Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePause Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: [1712680073560] ControllerQobuz::pause Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pause Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand pause Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand pause took 21 milliseconds Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 3 milliseconds Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 9 Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":66600,"duration":299,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3142 Kbps","isStreaming":false,"title":"file?uid=2934581&eid=38623133&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683669&hmac=oz_15ZzxLlNum1Ob10XO96Zp41c","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623133&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683669&hmac=oz_15ZzxLlNum1Ob10XO96Zp41c","trackType":"qobuz"} Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 9 Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService pause Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus pause Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stPlaybackTimer Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 23ms Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: Updating push state because input changed Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumiosetSourceActivempd Apr 09 16:27:53 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 09 16:28:08 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Apr 09 16:28:08 stream-box-s2-ultra volumio[1209]: info: handleBrowseUri took 419 milliseconds Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::ClearQueue Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stop Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: verbose: UNSET VOLATILE Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::updateTrackBlock Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrackBlock Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stPlaybackTimer Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 9 Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::serviceStop Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 9 Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::serviceStop Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: [1712680093931] ControllerQobuz::stop Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::stop Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand stop Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::clearPlayQueue Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::saveQueue Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushQueue Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::addQueueItems Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::addQueueItems Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/40997322 Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/40997322 in service qobuz Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/40997323 Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/40997323 in service qobuz Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/40997324 Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/40997324 in service qobuz Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/40997325 Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/40997325 in service qobuz Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/40997326 Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/40997326 in service qobuz Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/40997327 Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/40997327 in service qobuz Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/40997328 Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/40997328 in service qobuz Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/40997329 Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/40997329 in service qobuz Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/40997330 Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/40997330 in service qobuz Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Adding Item to queue: qobuz://song/40997331 Apr 09 16:28:13 stream-box-s2-ultra volumio[1209]: info: Exploding uri qobuz://song/40997331 in service qobuz Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: Updating push state because input changed Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumiosetSourceActivempd Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand stop took 109 milliseconds Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 4 milliseconds Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 9 Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: 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?uid=2934581&eid=38623133&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683669&hmac=oz_15ZzxLlNum1Ob10XO96Zp41c","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=38623133&fmt=7&profile=raw&app_id=539451548&cid=2075646&etsp=1712683669&hmac=oz_15ZzxLlNum1Ob10XO96Zp41c","trackType":"qobuz"} Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 9 Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService stop Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus stop Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: No code Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 54ms Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: explodeUri took 455 milliseconds Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: explodeUri took 462 milliseconds Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: explodeUri took 469 milliseconds Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: explodeUri took 455 milliseconds Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: explodeUri took 465 milliseconds Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: explodeUri took 462 milliseconds Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: explodeUri took 519 milliseconds Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: explodeUri took 521 milliseconds Apr 09 16:28:14 stream-box-s2-ultra volumio[1209]: info: explodeUri took 547 milliseconds Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: explodeUri took 1665 milliseconds Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::saveQueue Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushQueue Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::updateTrackBlock Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrackBlock Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPlay Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: verbose: UNSET VOLATILE Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::play index 0 Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::stop Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: verbose: UNSET VOLATILE Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::play index undefined Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 0 Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::startPlaybackTimer Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 0 Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: [1712680095612] ControllerQobuz::clearAddPlayTrack Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: getStreamUrl took 312 milliseconds Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand stop Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand stop took 3 milliseconds Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand clear Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces system playlist update Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: Ignoring MPD Status Update Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand clear took 3 milliseconds Apr 09 16:28:15 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand load "http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=40997322&fmt=27&profile=raw&app_id=539451548&cid=2075646&etsp=1712683695&hmac=9fWs0Hgt6gcZu6v1xMky0Y1ERc4" Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: error: updateQueue error: null Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 74ms Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand add "http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=40997322&fmt=27&profile=raw&app_id=539451548&cid=2075646&etsp=1712683695&hmac=9fWs0Hgt6gcZu6v1xMky0Y1ERc4" Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces system playlist update Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: Ignoring MPD Status Update Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand add "http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=40997322&fmt=27&profile=raw&app_id=539451548&cid=2075646&etsp=1712683695&hmac=9fWs0Hgt6gcZu6v1xMky0Y1ERc4" took 3 milliseconds Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand play Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 4ms Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand play took 2 milliseconds Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 59 milliseconds Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 8 milliseconds Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 0 Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":362,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"438 Kbps","isStreaming":false,"title":"file?uid=2934581&eid=40997322&fmt=27&profile=raw&app_id=539451548&cid=2075646&etsp=1712683695&hmac=9fWs0Hgt6gcZu6v1xMky0Y1ERc4","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=40997322&fmt=27&profile=raw&app_id=539451548&cid=2075646&etsp=1712683695&hmac=9fWs0Hgt6gcZu6v1xMky0Y1ERc4","trackType":"qobuz"} Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 0 Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService play Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus stop Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 97ms Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 0 Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: verbose: STATE SERVICE {"status":"play","position":0,"seek":90,"duration":362,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"438 Kbps","isStreaming":false,"title":"file?uid=2934581&eid=40997322&fmt=27&profile=raw&app_id=539451548&cid=2075646&etsp=1712683695&hmac=9fWs0Hgt6gcZu6v1xMky0Y1ERc4","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=40997322&fmt=27&profile=raw&app_id=539451548&cid=2075646&etsp=1712683695&hmac=9fWs0Hgt6gcZu6v1xMky0Y1ERc4","trackType":"qobuz"} Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 0 Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService play Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus play Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: Received an update from plugin. extracting info from payload Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:28:16 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 101ms Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioSeek Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::seek Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 0 Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: TRACKBLOCK {"uri":"qobuz://song/40997322","service":"qobuz","name":"I Robot","title":"I Robot","album":"I Robot","type":"track","tracknumber":1,"volumeNumber":1,"albumart":"https://static.qobuz.com/images/covers/41/13/0886446301341_600.jpg","duration":362,"trackType":"qobuz","samplerate":"192 KHz","bitdepth":"24 bit","explicit":false,"artist":"The Alan Parsons Project","artistUri":"qobuz://artist/122909","albumUri":"qobuz://album/0886446301341","audioQuality":"","tagImage":""} Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::startPlaybackTimer Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 0 Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: [1712680102286] ControllerQobuz::seek Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::seek Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: Updating push state because input changed Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumiosetSourceActivempd Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: error: null Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: ---------------------------- MPD announces state update: player Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::getState Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand status Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand status took 1 milliseconds Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseState Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: verbose: ControllerMpd::parseTrackInfo Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::pushState Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::servicePushState Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 0 Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: verbose: STATE SERVICE {"status":"play","position":0,"seek":61000,"duration":362,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4354 Kbps","isStreaming":false,"title":"file?uid=2934581&eid=40997322&fmt=27&profile=raw&app_id=539451548&cid=2075646&etsp=1712683695&hmac=9fWs0Hgt6gcZu6v1xMky0Y1ERc4","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=2934581&eid=40997322&fmt=27&profile=raw&app_id=539451548&cid=2075646&etsp=1712683695&hmac=9fWs0Hgt6gcZu6v1xMky0Y1ERc4","trackType":"qobuz"} Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: verbose: CURRENT POSITION 0 Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState stateService play Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::syncState currentStatus play Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: Received an update from plugin. extracting info from payload Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::pushState Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioPushState Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 09 16:28:22 stream-box-s2-ultra volumio[1209]: info: ------------------------------ 28ms Apr 09 16:28:24 stream-box-s2-ultra volumio[1209]: info: CoreCommandRouter::volumioSeek Apr 09 16:28:24 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::seek Apr 09 16:28:24 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 0 Apr 09 16:28:24 stream-box-s2-ultra volumio[1209]: info: TRACKBLOCK {"uri":"qobuz://song/40997322","service":"qobuz","name":"I Robot","title":"I Robot","album":"I Robot","type":"track","tracknumber":1,"volumeNumber":1,"albumart":"https://static.qobuz.com/images/covers/41/13/0886446301341_600.jpg","duration":362,"trackType":"qobuz","samplerate":"192 KHz","bitdepth":"24 bit","explicit":false,"artist":"The Alan Parsons Project","artistUri":"qobuz://artist/122909","albumUri":"qobuz://album/0886446301341","audioQuality":"","tagImage":""} Apr 09 16:28:24 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::startPlaybackTimer Apr 09 16:28:24 stream-box-s2-ultra volumio[1209]: info: CorePlayQueue::getTrack 0 Apr 09 16:28:24 stream-box-s2-ultra volumio[1209]: info: [1712680104588] ControllerQobuz::seek Apr 09 16:28:24 stream-box-s2-ultra volumio[1209]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 09 16:28:24 stream-box-s2-ultra volumio[1209]: info: ControllerMpd::seek Apr 09 16:28:24 stream-box-s2-ultra systemd-journal[2498]: Runtime journal is using 8.0M (max allowed 30.0M, trying to leave 70.4M free of 460.9M available → current limit 30.0M). Apr 09 16:28:24 stream-box-s2-ultra kernel: Unable to handle kernel NULL pointer dereference at virtual address 00000222 Apr 09 16:28:24 stream-box-s2-ultra kernel: pgd = aee2c000 Apr 09 16:28:24 stream-box-s2-ultra kernel: [00000222] *pgd=00000000 Apr 09 16:28:24 stream-box-s2-ultra kernel: Internal error: Oops: 5 [#1] SMP ARM Apr 09 16:28:24 stream-box-s2-ultra kernel: Modules linked in: xt_REDIRECT nf_nat_redirect bnep xt_tcpudp iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack ip_tables x_tables nfsd 8723bu(O) snd_usb_audio snd_hwdep snd_usbmidi_lib snd_seq_midi snd_seq_midi_event btusb(O) btrtl(O) btintel(O) btbcm(O) snd_rawmidi cfg80211 snd_pcm bluetooth snd_seq snd_seq_device snd_timer rfkill snd bcm2835_gpiomem fixed uio_pdrv_genirq uio i2c_dev ipv6 fuse squashfs overlay Apr 09 16:28:24 stream-box-s2-ultra kernel: CPU: 3 PID: 198 Comm: systemd-journal Tainted: G O 4.9.65-v7+ #1 Apr 09 16:28:24 stream-box-s2-ultra kernel: Hardware name: BCM2835 Apr 09 16:28:24 stream-box-s2-ultra kernel: task: ae9b8000 task.stack: aee68000 Apr 09 16:28:24 stream-box-s2-ultra kernel: PC is at skb_release_data+0x64/0x128 Apr 09 16:28:24 stream-box-s2-ultra kernel: LR is at skb_release_all+0x30/0x34 Apr 09 16:28:24 stream-box-s2-ultra kernel: pc : [<805fff80>] lr : [<80600074>] psr: 60000013 sp : aee69d78 ip : aee69d98 fp : aee69d94 Apr 09 16:28:24 stream-box-s2-ultra kernel: r10: b505a100 r9 : 00000000 r8 : 00000000 Apr 09 16:28:24 stream-box-s2-ultra kernel: r7 : 00000222 r6 : b5c2a840 r5 : b5c2a840 r4 : b5c2a840 Apr 09 16:28:24 stream-box-s2-ultra kernel: r3 : 00000000 r2 : 00000481 r1 : 00000000 r0 : b5c2a840 Apr 09 16:28:24 stream-box-s2-ultra kernel: Flags: nZCv IRQs on FIQs on Mode SVC_32 ISA ARM Segment user Apr 09 16:28:24 stream-box-s2-ultra kernel: Control: 10c5383d Table: 2ee2c06a DAC: 00000055 Apr 09 16:28:24 stream-box-s2-ultra kernel: Process systemd-journal (pid: 198, stack limit = 0xaee68210) Apr 09 16:28:24 stream-box-s2-ultra kernel: Stack: (0xaee69d78 to 0xaee6a000) Apr 09 16:28:24 stream-box-s2-ultra kernel: 9d60: b5c2a840 b5c2a840 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9d80: aee69e5c 000007de aee69dac aee69d98 80600074 805fff28 00000001 b5c2a840 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9da0: aee69dc4 aee69db0 806001f8 80600050 b5c2a840 00000022 aee69e54 aee69dc8 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9dc0: 806c2264 806001cc 00000000 00000000 b505a244 00000001 00000000 00000001 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9de0: b505a30c 00000000 b505a34c b9128700 00000022 00000001 00000022 b505a184 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9e00: aeac21c0 00000000 000004b9 000003e8 000003e8 bab6c4f8 aee69e48 8028727c Apr 09 16:28:24 stream-box-s2-ultra kernel: 9e20: 66156ca8 230d2b41 aee69e6c 00000000 aee69f00 aee69ee8 aee69f78 00000800 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9e40: 0196d009 00000000 aee69e84 aee69e58 806c2410 806c1b10 806bdb84 806be394 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9e60: b9128700 aee69e88 00000000 00000800 00000040 00000000 aee69ed4 aee69e88 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9e80: 805f71e0 806c23d4 00000000 00000000 00000000 00000022 000007de aee69ee0 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9ea0: 00000001 805c88c0 00000000 00000000 00000040 aee69f00 aed070ac 00000000 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9ec0: 00000000 b5c64a80 aee69f44 aee69ed8 80268b64 805f7150 00000800 0000001d Apr 09 16:28:24 stream-box-s2-ultra kernel: 9ee0: 0196d009 00000800 00000000 00000000 00000800 aee69ee0 00000001 805c88c0 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9f00: b5c64a80 00000000 00000000 00000000 00000000 00000000 00000000 00000000 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9f20: 8017ea84 00000800 b5c64a80 0196d009 aee69f78 00000800 aee69f74 aee69f48 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9f40: 8026996c 80268aa0 0000002b 80289034 aee69f74 00000000 00000000 b5c64a80 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9f60: b5c64a80 00000800 aee69fa4 aee69f78 8026ab28 802698e0 00000000 00000000 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9f80: 0196cfe0 76f34f10 7efdc738 00000003 80107fa4 aee68000 00000000 aee69fa8 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9fa0: 80107e20 8026aae8 0196cfe0 76f34f10 00000011 0196d009 00000800 00000011 Apr 09 16:28:24 stream-box-s2-ultra kernel: 9fc0: 0196cfe0 76f34f10 7efdc738 00000003 76f34f10 019630a0 01963088 7efdca1c Apr 09 16:28:24 stream-box-s2-ultra kernel: 9fe0: 00000000 7efdc714 004b0e78 76eebc8c 60000010 00000011 00000000 00000000 Apr 09 16:28:24 stream-box-s2-ultra kernel: [<805fff80>] (skb_release_data) from [<80600074>] (skb_release_all+0x30/0x34) Apr 09 16:28:24 stream-box-s2-ultra kernel: [<80600074>] (skb_release_all) from [<806001f8>] (consume_skb+0x38/0xe4) Apr 09 16:28:24 stream-box-s2-ultra kernel: [<806001f8>] (consume_skb) from [<806c2264>] (unix_stream_read_generic+0x760/0x834) Apr 09 16:28:24 stream-box-s2-ultra kernel: [<806c2264>] (unix_stream_read_generic) from [<806c2410>] (unix_stream_recvmsg+0x48/0x54) Apr 09 16:28:24 stream-box-s2-ultra kernel: [<806c2410>] (unix_stream_recvmsg) from [<805f71e0>] (sock_read_iter+0x9c/0xd0) Apr 09 16:28:24 stream-box-s2-ultra kernel: [<805f71e0>] (sock_read_iter) from [<80268b64>] (__vfs_read+0xd0/0x120) Apr 09 16:28:24 stream-box-s2-ultra kernel: [<80268b64>] (__vfs_read) from [<8026996c>] (vfs_read+0x98/0x16c) Apr 09 16:28:24 stream-box-s2-ultra kernel: [<8026996c>] (vfs_read) from [<8026ab28>] (SyS_read+0x4c/0xa0) Apr 09 16:28:24 stream-box-s2-ultra kernel: [<8026ab28>] (SyS_read) from [<80107e20>] (ret_fast_syscall+0x0/0x1c) Apr 09 16:28:24 stream-box-s2-ultra kernel: Code: 1afffffa f57ff05b e3510000 189da8f0 (e5d73000) Apr 09 16:28:24 stream-box-s2-ultra kernel: ---[ end trace aa1cc1ea1fdba232 ]--- Apr 09 16:28:24 stream-box-s2-ultra systemd[1]: systemd-journald.service has no holdoff time, scheduling restart. Apr 09 16:28:24 stream-box-s2-ultra systemd[1]: Stopping Journal Service... Apr 09 16:28:24 stream-box-s2-ultra systemd[1]: Starting Journal Service... Apr 09 16:28:24 stream-box-s2-ultra systemd[1]: Started Journal Service. Apr 09 16:28:24 stream-box-s2-ultra systemd-journal[2498]: Journal started Apr 09 16:28:24 stream-box-s2-ultra systemd[1]: systemd-journald.service: main process exited, code=killed, status=11/SEGV Apr 09 16:28:24 stream-box-s2-ultra systemd[1]: Unit systemd-journald.service entered failed state. Apr 09 16:28:24 stream-box-s2-ultra systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... Apr 09 16:28:24 stream-box-s2-ultra systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. Apr 09 16:28:25 stream-box-s2-ultra sudo[2514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-09 16:27 Apr 09 16:28:25 stream-box-s2-ultra sudo[2514]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="projectsbus2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 13 17:22:02 CEST 2022" VOLUMIO_VERSION="1.079" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Pro-Ject Audio Systems" VOLUMIO_MODEL="Stream Box S2 ultra" VOLUMIO_VENDOR_MODEL="Pro-Ject Stream Box S2 ultra" VOLUMIO_HASH="a5127ace113e6b9fcf2a1eaed5431093"