-- Logs begin at Tue 2024-07-02 02:08:35 UTC, end at Thu 2024-07-04 22:11:55 UTC. -- Jul 04 22:10:50 stream-box-s2-ultra volumio[961]: info: Executing endpoint metavolumio Jul 04 22:10:50 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 04 22:10:53 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 04 22:10:53 stream-box-s2-ultra volumio[961]: info: handleBrowseUri took 127 milliseconds Jul 04 22:11:02 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 04 22:11:05 stream-box-s2-ultra volumio[961]: info: handleBrowseUri took 2877 milliseconds Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::ClearQueue Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::stop Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: verbose: UNSET VOLATILE Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::stPlaybackTimer Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::updateTrackBlock Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrackBlock Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pushState Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 28 Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::serviceStop Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 28 Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::serviceStop Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: [1720131094932] ControllerQobuz::stop Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: ControllerMpd::stop Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand stop Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::clearPlayQueue Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::saveQueue Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushQueue Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::addQueueItems Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::addQueueItems Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: Adding Item to queue: qobuz://album/cg3tr4buyzgja Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: Exploding uri qobuz://album/cg3tr4buyzgja in service qobuz Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: Updating push state because input changed Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumiosetSourceActivempd Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: ---------------------------- MPD announces state update: player Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: sendMpdCommand stop took 39 milliseconds Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: ControllerMpd::getState Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand status Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: sendMpdCommand status took 2 milliseconds Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::parseState Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::parseTrackInfo Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: ControllerMpd::pushState Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::servicePushState Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pushState Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 28 Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: 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=1670328&eid=118193430&fmt=6&profile=raw&app_id=539451548&cid=1416497&etsp=1720134143&hmac=QV4kLa8s9n1rjGGfMfXgFWS2zq4","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=1670328&eid=118193430&fmt=6&profile=raw&app_id=539451548&cid=1416497&etsp=1720134143&hmac=QV4kLa8s9n1rjGGfMfXgFWS2zq4","trackType":"qobuz"} Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: verbose: CURRENT POSITION 28 Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::syncState stateService stop Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::syncState currentStatus stop Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pushState Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: No code Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pushState Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:34 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: ------------------------------ 23ms Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: explodeUri took 462 milliseconds Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::saveQueue Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushQueue Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::updateTrackBlock Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrackBlock Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPlay Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: verbose: UNSET VOLATILE Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::play index 0 Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::stop Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: verbose: UNSET VOLATILE Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::play index undefined Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 0 Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::startPlaybackTimer Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 0 Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: [1720131095408] ControllerQobuz::clearAddPlayTrack Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: getStreamUrl took 361 milliseconds Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand stop Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: sendMpdCommand stop took 2 milliseconds Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand clear Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: ---------------------------- MPD announces system playlist update Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: Ignoring MPD Status Update Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: sendMpdCommand clear took 1 milliseconds Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand load "http://streaming-qobuz-std.akamaized.net:80/file?uid=1670328&eid=213019790&fmt=6&profile=raw&app_id=539451548&cid=1416497&etsp=1720134695&hmac=z3dam30OJdftx7_SjJC3z7y9v3g" Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: error: updateQueue error: null Jul 04 22:11:35 stream-box-s2-ultra volumio[961]: info: ------------------------------ 2ms Jul 04 22:11:36 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand add "http://streaming-qobuz-std.akamaized.net:80/file?uid=1670328&eid=213019790&fmt=6&profile=raw&app_id=539451548&cid=1416497&etsp=1720134695&hmac=z3dam30OJdftx7_SjJC3z7y9v3g" Jul 04 22:11:36 stream-box-s2-ultra volumio[961]: info: Jul 04 22:11:36 stream-box-s2-ultra volumio[961]: ---------------------------- MPD announces system playlist update Jul 04 22:11:36 stream-box-s2-ultra volumio[961]: info: Ignoring MPD Status Update Jul 04 22:11:36 stream-box-s2-ultra volumio[961]: info: sendMpdCommand add "http://streaming-qobuz-std.akamaized.net:80/file?uid=1670328&eid=213019790&fmt=6&profile=raw&app_id=539451548&cid=1416497&etsp=1720134695&hmac=z3dam30OJdftx7_SjJC3z7y9v3g" took 1 milliseconds Jul 04 22:11:36 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 04 22:11:36 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand play Jul 04 22:11:36 stream-box-s2-ultra volumio[961]: info: ------------------------------ 5ms Jul 04 22:11:36 stream-box-s2-ultra volumio[961]: info: sendMpdCommand play took 3 milliseconds Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: ---------------------------- MPD announces state update: player Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: ControllerMpd::getState Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand status Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: sendMpdCommand status took 10 milliseconds Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::parseState Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: ---------------------------- MPD announces state update: player Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: ControllerMpd::getState Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand status Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::parseTrackInfo Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: ControllerMpd::pushState Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::servicePushState Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 0 Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"834 Kbps","isStreaming":false,"title":"file?uid=1670328&eid=213019790&fmt=6&profile=raw&app_id=539451548&cid=1416497&etsp=1720134695&hmac=z3dam30OJdftx7_SjJC3z7y9v3g","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=1670328&eid=213019790&fmt=6&profile=raw&app_id=539451548&cid=1416497&etsp=1720134695&hmac=z3dam30OJdftx7_SjJC3z7y9v3g","trackType":"qobuz"} Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: verbose: CURRENT POSITION 0 Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::syncState stateService play Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::syncState currentStatus stop Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pushState Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: ------------------------------ 50ms Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: sendMpdCommand status took 31 milliseconds Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::parseState Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::parseTrackInfo Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: ControllerMpd::pushState Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::servicePushState Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 0 Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":139,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"797 Kbps","isStreaming":false,"title":"file?uid=1670328&eid=213019790&fmt=6&profile=raw&app_id=539451548&cid=1416497&etsp=1720134695&hmac=z3dam30OJdftx7_SjJC3z7y9v3g","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=1670328&eid=213019790&fmt=6&profile=raw&app_id=539451548&cid=1416497&etsp=1720134695&hmac=z3dam30OJdftx7_SjJC3z7y9v3g","trackType":"qobuz"} Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: verbose: CURRENT POSITION 0 Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::syncState stateService play Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::syncState currentStatus play Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: Received an update from plugin. extracting info from payload Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pushState Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pushState Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:37 stream-box-s2-ultra volumio[961]: info: ------------------------------ 55ms Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPause Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pause Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::stPlaybackTimer Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::servicePause Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 0 Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::servicePause Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: [1720131102537] ControllerQobuz::pause Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: ControllerMpd::pause Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand pause Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: ---------------------------- MPD announces state update: player Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: sendMpdCommand pause took 116 milliseconds Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: ControllerMpd::getState Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand status Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: sendMpdCommand status took 1 milliseconds Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::parseState Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::parseTrackInfo Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: ControllerMpd::pushState Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::servicePushState Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 0 Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5874,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"874 Kbps","isStreaming":false,"title":"file?uid=1670328&eid=213019790&fmt=6&profile=raw&app_id=539451548&cid=1416497&etsp=1720134695&hmac=z3dam30OJdftx7_SjJC3z7y9v3g","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=1670328&eid=213019790&fmt=6&profile=raw&app_id=539451548&cid=1416497&etsp=1720134695&hmac=z3dam30OJdftx7_SjJC3z7y9v3g","trackType":"qobuz"} Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: verbose: CURRENT POSITION 0 Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::syncState stateService pause Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::syncState currentStatus pause Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pushState Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::stPlaybackTimer Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: ------------------------------ 11ms Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: Updating push state because input changed Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumiosetSourceActivempd Jul 04 22:11:42 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::ClearQueue Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::stop Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: verbose: UNSET VOLATILE Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::updateTrackBlock Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrackBlock Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::stPlaybackTimer Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pushState Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 0 Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::serviceStop Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 0 Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::serviceStop Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: [1720131105473] ControllerQobuz::stop Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: ControllerMpd::stop Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand stop Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::clearPlayQueue Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::saveQueue Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushQueue Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::addQueueItems Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::addQueueItems Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: Adding Item to queue: qobuz://album/q0mw78fbrvp4a Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: Exploding uri qobuz://album/q0mw78fbrvp4a in service qobuz Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: Updating push state because input changed Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumiosetSourceActivempd Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: ---------------------------- MPD announces state update: player Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: sendMpdCommand stop took 29 milliseconds Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: ControllerMpd::getState Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand status Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: sendMpdCommand status took 3 milliseconds Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::parseState Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::parseTrackInfo Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: ControllerMpd::pushState Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::servicePushState Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pushState Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 0 Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: 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=1670328&eid=213019790&fmt=6&profile=raw&app_id=539451548&cid=1416497&etsp=1720134695&hmac=z3dam30OJdftx7_SjJC3z7y9v3g","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=1670328&eid=213019790&fmt=6&profile=raw&app_id=539451548&cid=1416497&etsp=1720134695&hmac=z3dam30OJdftx7_SjJC3z7y9v3g","trackType":"qobuz"} Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: verbose: CURRENT POSITION 0 Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::syncState stateService stop Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::syncState currentStatus stop Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pushState Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: No code Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pushState Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:45 stream-box-s2-ultra volumio[961]: info: ------------------------------ 22ms Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: explodeUri took 726 milliseconds Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::saveQueue Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushQueue Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::updateTrackBlock Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrackBlock Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPlay Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: UNSET VOLATILE Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::play index 0 Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::stop Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: UNSET VOLATILE Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::play index undefined Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 0 Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::startPlaybackTimer Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 0 Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: [1720131106210] ControllerQobuz::clearAddPlayTrack Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: getStreamUrl took 313 milliseconds Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand stop Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: sendMpdCommand stop took 1 milliseconds Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand clear Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: ---------------------------- MPD announces system playlist update Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: Ignoring MPD Status Update Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: sendMpdCommand clear took 1 milliseconds Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand load "http://streaming-qobuz-std.akamaized.net:80/file?uid=1670328&eid=273641184&fmt=7&profile=raw&app_id=539451548&cid=1416497&etsp=1720134706&hmac=M_LUYqnOfISRZKbRAbEpffuPEq0" Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: error: updateQueue error: null Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: ------------------------------ 3ms Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand add "http://streaming-qobuz-std.akamaized.net:80/file?uid=1670328&eid=273641184&fmt=7&profile=raw&app_id=539451548&cid=1416497&etsp=1720134706&hmac=M_LUYqnOfISRZKbRAbEpffuPEq0" Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: ---------------------------- MPD announces system playlist update Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: Ignoring MPD Status Update Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: sendMpdCommand add "http://streaming-qobuz-std.akamaized.net:80/file?uid=1670328&eid=273641184&fmt=7&profile=raw&app_id=539451548&cid=1416497&etsp=1720134706&hmac=M_LUYqnOfISRZKbRAbEpffuPEq0" took 2 milliseconds Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand play Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: ------------------------------ 3ms Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: sendMpdCommand play took 5 milliseconds Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: ---------------------------- MPD announces state update: player Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: ControllerMpd::getState Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand status Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: ---------------------------- MPD announces state update: player Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: sendMpdCommand status took 8 milliseconds Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: ControllerMpd::getState Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand status Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::parseState Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: sendMpdCommand status took 11 milliseconds Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::parseState Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: sendMpdCommand playlistinfo took 16 milliseconds Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::parseTrackInfo Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::parseTrackInfo Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: ControllerMpd::pushState Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::servicePushState Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 0 Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":131,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=1670328&eid=273641184&fmt=7&profile=raw&app_id=539451548&cid=1416497&etsp=1720134706&hmac=M_LUYqnOfISRZKbRAbEpffuPEq0","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=1670328&eid=273641184&fmt=7&profile=raw&app_id=539451548&cid=1416497&etsp=1720134706&hmac=M_LUYqnOfISRZKbRAbEpffuPEq0","trackType":"qobuz"} Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: CURRENT POSITION 0 Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::syncState stateService play Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::syncState currentStatus stop Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pushState Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: ControllerMpd::pushState Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::servicePushState Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 0 Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":181,"duration":131,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1298 Kbps","isStreaming":false,"title":"file?uid=1670328&eid=273641184&fmt=7&profile=raw&app_id=539451548&cid=1416497&etsp=1720134706&hmac=M_LUYqnOfISRZKbRAbEpffuPEq0","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=1670328&eid=273641184&fmt=7&profile=raw&app_id=539451548&cid=1416497&etsp=1720134706&hmac=M_LUYqnOfISRZKbRAbEpffuPEq0","trackType":"qobuz"} Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: verbose: CURRENT POSITION 0 Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::syncState stateService play Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::syncState currentStatus play Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: Received an update from plugin. extracting info from payload Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pushState Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pushState Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPushState Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: ------------------------------ 108ms Jul 04 22:11:46 stream-box-s2-ultra volumio[961]: info: ------------------------------ 104ms Jul 04 22:11:54 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::volumioPause Jul 04 22:11:54 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::pause Jul 04 22:11:54 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::stPlaybackTimer Jul 04 22:11:54 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::servicePause Jul 04 22:11:54 stream-box-s2-ultra volumio[961]: info: CorePlayQueue::getTrack 0 Jul 04 22:11:54 stream-box-s2-ultra volumio[961]: info: CoreCommandRouter::servicePause Jul 04 22:11:54 stream-box-s2-ultra volumio[961]: info: [1720131114475] ControllerQobuz::pause Jul 04 22:11:54 stream-box-s2-ultra volumio[961]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 04 22:11:54 stream-box-s2-ultra volumio[961]: info: ControllerMpd::pause Jul 04 22:11:54 stream-box-s2-ultra volumio[961]: verbose: ControllerMpd::sendMpdCommand pause Jul 04 22:11:54 stream-box-s2-ultra volumio[961]: info: Jul 04 22:11:54 stream-box-s2-ultra volumio[961]: ---------------------------- MPD announces state update: player Jul 04 22:11:54 stream-box-s2-ultra volumio[961]: info: sendMpdCommand pause took 43 milliseconds Jul 04 22:11:54 stream-box-s2-ultra volumio[961]: info: ControllerMpd::getState Jul 04 22:11:55 stream-box-s2-ultra sudo[18999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-04 22:10 Jul 04 22:11:55 stream-box-s2-ultra sudo[18999]: 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"