-- Logs begin at Thu 2025-07-03 11:12:02 UTC, end at Wed 2025-07-16 13:02:34 UTC. -- Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioPlay Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: verbose: UNSET VOLATILE Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::play index undefined Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::getTrack 3469 Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::startPlaybackTimer Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::getTrack 3469 Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: [1752670861196] ControllerWebradio::clearAddPlayTrack Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand stop Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand stop took 7 milliseconds Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand clear Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: ---------------------------- MPD announces system playlist update Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: Ignoring MPD Status Update Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand clear took 2 milliseconds Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand load "http://api.somafm.com/sonicuniverse.pls" Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: error: updateQueue error: null Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: ------------------------------ 6ms Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand load "http://api.somafm.com/sonicuniverse.pls" took 385 milliseconds Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand play Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: ---------------------------- MPD announces system playlist update Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: Ignoring MPD Status Update Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand play took 4 milliseconds Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: ------------------------------ 3ms Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: ---------------------------- MPD announces system playlist update Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: Ignoring MPD Status Update Jul 16 13:01:01 stream-box-s2-ultra volumio[1217]: info: ------------------------------ 4ms Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: ---------------------------- MPD announces state update: player Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: ControllerMpd::getState Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: ---------------------------- MPD announces system playlist update Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: Ignoring MPD Status Update Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: ---------------------------- MPD announces state update: player Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: ControllerMpd::getState Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand status took 7 milliseconds Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::parseState Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: ------------------------------ 9ms Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand status took 7 milliseconds Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::parseState Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: ControllerMpd::pushState Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::servicePushState Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::getTrack 3469 Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":301,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"sonicuniverse-128-mp3","artist":"Sonic Universe: Nu Jazz plus avant-garde Euro Jazz: Transcending the boundaries of jazz with experimental takes on tradition. [SomaFM]","album":null,"uri":"http://ice6.somafm.com/sonicuniverse-128-mp3","trackType":""} Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: verbose: CURRENT POSITION 3469 Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::syncState stateService play Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::pushState Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioPushState Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: ------------------------------ 35ms Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand playlistinfo took 18 milliseconds Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: ControllerMpd::pushState Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::servicePushState Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::getTrack 3469 Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"sonicuniverse-128-mp3","artist":"Sonic Universe: Nu Jazz plus avant-garde Euro Jazz: Transcending the boundaries of jazz with experimental takes on tradition. [SomaFM]","album":null,"uri":"http://ice6.somafm.com/sonicuniverse-128-mp3","trackType":""} Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: verbose: CURRENT POSITION 3469 Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::syncState stateService play Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::syncState currentStatus play Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: Received an update from plugin. extracting info from payload Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::pushState Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioPushState Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::pushState Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioPushState Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 13:01:02 stream-box-s2-ultra volumio[1217]: info: ------------------------------ 61ms Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: ---------------------------- MPD announces system playlist update Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: Ignoring MPD Status Update Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: ---------------------------- MPD announces state update: player Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: ControllerMpd::getState Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: ------------------------------ 3ms Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand status took 2 milliseconds Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::parseState Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: ControllerMpd::pushState Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::servicePushState Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::getTrack 3469 Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Diamond Dust - Vuelta Abajo","artist":"Sonic Universe: Nu Jazz plus avant-garde Euro Jazz: Transcending the boundaries of jazz with experimental takes on tradition. [SomaFM]","album":null,"uri":"http://ice6.somafm.com/sonicuniverse-128-mp3","trackType":""} Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: verbose: CURRENT POSITION 3469 Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::syncState stateService play Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::syncState currentStatus play Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: Received an update from plugin. extracting info from payload Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::pushState Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioPushState Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::pushState Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioPushState Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 13:01:03 stream-box-s2-ultra volumio[1217]: info: ------------------------------ 20ms Jul 16 13:01:22 stream-box-s2-ultra volumio[1217]: Searching all installed plugins Jul 16 13:01:22 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 16 13:01:22 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 16 13:01:22 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jul 16 13:01:22 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: inputs , search Jul 16 13:01:22 stream-box-s2-ultra volumio[1217]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin inputs Jul 16 13:01:22 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jul 16 13:01:22 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jul 16 13:01:23 stream-box-s2-ultra volumio[1217]: info: searchTIDALUri took 615 milliseconds Jul 16 13:01:23 stream-box-s2-ultra volumio[1217]: info: search took 616 milliseconds Jul 16 13:01:23 stream-box-s2-ultra volumio[1217]: Searching all installed plugins Jul 16 13:01:23 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 16 13:01:23 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 16 13:01:23 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jul 16 13:01:23 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: inputs , search Jul 16 13:01:23 stream-box-s2-ultra volumio[1217]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin inputs Jul 16 13:01:23 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jul 16 13:01:23 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jul 16 13:01:24 stream-box-s2-ultra volumio[1217]: info: searchQOBUZUri took 1411 milliseconds Jul 16 13:01:24 stream-box-s2-ultra volumio[1217]: info: All search sources collected, pushing search results Jul 16 13:01:24 stream-box-s2-ultra volumio[1217]: info: searchTIDALUri took 372 milliseconds Jul 16 13:01:24 stream-box-s2-ultra volumio[1217]: info: search took 372 milliseconds Jul 16 13:01:24 stream-box-s2-ultra volumio[1217]: info: searchQOBUZUri took 1092 milliseconds Jul 16 13:01:24 stream-box-s2-ultra volumio[1217]: info: All search sources collected, pushing search results Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: ---------------------------- MPD announces system playlist update Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: Ignoring MPD Status Update Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: ---------------------------- MPD announces state update: player Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: ControllerMpd::getState Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: ------------------------------ 4ms Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand status took 3 milliseconds Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::parseState Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: ControllerMpd::pushState Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::servicePushState Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::getTrack 3469 Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":28232,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Brand X - Disco Suicide","artist":"Sonic Universe: Nu Jazz plus avant-garde Euro Jazz: Transcending the boundaries of jazz with experimental takes on tradition. [SomaFM]","album":null,"uri":"http://ice6.somafm.com/sonicuniverse-128-mp3","trackType":""} Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: verbose: CURRENT POSITION 3469 Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::syncState stateService play Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::syncState currentStatus play Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: Received an update from plugin. extracting info from payload Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::pushState Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioPushState Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::pushState Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioPushState Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 13:01:30 stream-box-s2-ultra volumio[1217]: info: ------------------------------ 24ms Jul 16 13:01:34 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 16 13:01:35 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 16 13:01:35 stream-box-s2-ultra volumio[1217]: info: handleBrowseUri took 1661 milliseconds Jul 16 13:01:36 stream-box-s2-ultra volumio[1217]: info: handleBrowseUri took 868 milliseconds Jul 16 13:01:40 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 16 13:01:41 stream-box-s2-ultra volumio[1217]: info: handleBrowseUri took 393 milliseconds Jul 16 13:01:42 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioAddQueueItems Jul 16 13:01:42 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::addQueueItems Jul 16 13:01:42 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::addQueueItems Jul 16 13:01:42 stream-box-s2-ultra volumio[1217]: info: Adding Item to queue: qobuz://album/e0boxts0qp2vc Jul 16 13:01:42 stream-box-s2-ultra volumio[1217]: info: Exploding uri qobuz://album/e0boxts0qp2vc in service qobuz Jul 16 13:01:42 stream-box-s2-ultra volumio[1217]: info: explodeUri took 402 milliseconds Jul 16 13:01:42 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::saveQueue Jul 16 13:01:42 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioPushQueue Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::updateTrackBlock Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::getTrackBlock Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioPlay Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: verbose: UNSET VOLATILE Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::play index 3470 Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::stop Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: verbose: UNSET VOLATILE Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::stPlaybackTimer Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::updateTrackBlock Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::getTrackBlock Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::pushState Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::getTrack 3469 Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioPushState Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::serviceStop Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::getTrack 3469 Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::serviceStop Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: [1752670903214] ControllerWebradio::stop Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand stop Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: ---------------------------- MPD announces state update: player Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand stop took 362 milliseconds Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: ControllerMpd::getState Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::play index undefined Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::getTrack 3470 Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::startPlaybackTimer Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::getTrack 3470 Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: [1752670903580] ControllerQobuz::clearAddPlayTrack Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand status took 22 milliseconds Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::parseState Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: ControllerMpd::pushState Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::servicePushState Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::pushState Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::getTrack 3470 Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioPushState Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::getTrack 3470 Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jul 16 13:01:43 stream-box-s2-ultra volumio[1217]: info: ------------------------------ 38ms Jul 16 13:01:44 stream-box-s2-ultra volumio[1217]: info: getStreamUrl took 639 milliseconds Jul 16 13:01:44 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand stop Jul 16 13:01:44 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand stop took 629 milliseconds Jul 16 13:01:44 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand clear Jul 16 13:01:44 stream-box-s2-ultra volumio[1217]: info: Jul 16 13:01:44 stream-box-s2-ultra volumio[1217]: ---------------------------- MPD announces system playlist update Jul 16 13:01:44 stream-box-s2-ultra volumio[1217]: info: Ignoring MPD Status Update Jul 16 13:01:44 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand clear took 6 milliseconds Jul 16 13:01:44 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=2234175&eid=226656538&fmt=7&profile=raw&app_id=539451548&cid=1555270&etsp=1752674503&hmac=u_eZ987Vo0dP6jPKquD5mI993aM" Jul 16 13:01:44 stream-box-s2-ultra volumio[1217]: error: updateQueue error: null Jul 16 13:01:44 stream-box-s2-ultra volumio[1217]: info: ------------------------------ 6ms Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=2234175&eid=226656538&fmt=7&profile=raw&app_id=539451548&cid=1555270&etsp=1752674503&hmac=u_eZ987Vo0dP6jPKquD5mI993aM" Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: ---------------------------- MPD announces system playlist update Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: Ignoring MPD Status Update Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=2234175&eid=226656538&fmt=7&profile=raw&app_id=539451548&cid=1555270&etsp=1752674503&hmac=u_eZ987Vo0dP6jPKquD5mI993aM" took 2 milliseconds Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand play Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: ------------------------------ 4ms Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand play took 2 milliseconds Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: ---------------------------- MPD announces state update: player Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: ControllerMpd::getState Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: ---------------------------- MPD announces state update: player Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand status took 7 milliseconds Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: ControllerMpd::getState Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::parseState Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand status took 10 milliseconds Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::parseState Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: ControllerMpd::pushState Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::servicePushState Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::getTrack 3470 Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":325,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=2234175&eid=226656538&fmt=7&profile=raw&app_id=539451548&cid=1555270&etsp=1752674503&hmac=u_eZ987Vo0dP6jPKquD5mI993aM","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=2234175&eid=226656538&fmt=7&profile=raw&app_id=539451548&cid=1555270&etsp=1752674503&hmac=u_eZ987Vo0dP6jPKquD5mI993aM","trackType":"qobuz"} Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: verbose: CURRENT POSITION 3470 Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::syncState stateService play Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::pushState Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioPushState Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: ------------------------------ 101ms Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: sendMpdCommand playlistinfo took 79 milliseconds Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: ControllerMpd::pushState Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::servicePushState Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CorePlayQueue::getTrack 3470 Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":325,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1586 Kbps","isStreaming":false,"title":"file?uid=2234175&eid=226656538&fmt=7&profile=raw&app_id=539451548&cid=1555270&etsp=1752674503&hmac=u_eZ987Vo0dP6jPKquD5mI993aM","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=2234175&eid=226656538&fmt=7&profile=raw&app_id=539451548&cid=1555270&etsp=1752674503&hmac=u_eZ987Vo0dP6jPKquD5mI993aM","trackType":"qobuz"} Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: verbose: CURRENT POSITION 3470 Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::syncState stateService play Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::syncState currentStatus play Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: Received an update from plugin. extracting info from payload Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::pushState Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioPushState Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreStateMachine::pushState Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioPushState Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: ------------------------------ 188ms Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: Updating push state because input changed Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumiosetSourceActivempd Jul 16 13:01:45 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: Retrieving Cloud Streaming UI Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: Getting Tidal Cloud Configuration Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: Getting Qobuz Cloud Configuration Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: Asking plugin for UI Config Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: Getting Spotify Cloud Configuration Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: Asking plugin for UI Config Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: error: Could not retrieve plugin music_service spotify Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: Saving Spotify Acccount Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: Got it Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: Got Tidal Cloud Configuration Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: Got it Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::volumioGetBrowseSources Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 16 13:02:20 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 16 13:02:24 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 16 13:02:28 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 16 13:02:32 stream-box-s2-ultra volumio[1217]: info: Enabling MyMusic plugin metavolumio Jul 16 13:02:32 stream-box-s2-ultra volumio[1217]: info: Enabling plugin metavolumio Jul 16 13:02:32 stream-box-s2-ultra volumio[1217]: info: Loading plugin "metavolumio"... Jul 16 13:02:32 stream-box-s2-ultra volumio[1217]: info: PLUGIN START: metavolumio Jul 16 13:02:32 stream-box-s2-ultra volumio[1217]: info: Adding METAVOLUMIO REST API Endpoints Jul 16 13:02:32 stream-box-s2-ultra volumio[1217]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 16 13:02:32 stream-box-s2-ultra volumio[1217]: info: Done. Jul 16 13:02:32 stream-box-s2-ultra volumio[1217]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 16 13:02:33 stream-box-s2-ultra volumio[1217]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 16 13:02:33 stream-box-s2-ultra volumio[1217]: Error: Unable to resolve or reject the same promise twice Jul 16 13:02:33 stream-box-s2-ultra volumio[1217]: at Promise.reject (/volumio/node_modules/kew/kew.js:189:43) Jul 16 13:02:33 stream-box-s2-ultra volumio[1217]: at /volumio/app/plugins/miscellanea/albumart/albumart.js:157:15 Jul 16 13:02:33 stream-box-s2-ultra volumio[1217]: at IncomingMessage. (/volumio/app/plugins/miscellanea/albumart/albumart.js:652:18) Jul 16 13:02:33 stream-box-s2-ultra volumio[1217]: at emitNone (events.js:111:20) Jul 16 13:02:33 stream-box-s2-ultra volumio[1217]: at IncomingMessage.emit (events.js:208:7) Jul 16 13:02:33 stream-box-s2-ultra volumio[1217]: at endReadableNT (_stream_readable.js:1064:12) Jul 16 13:02:33 stream-box-s2-ultra volumio[1217]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Jul 16 13:02:33 stream-box-s2-ultra volumio[1217]: at process._tickCallback (internal/process/next_tick.js:180:9) Jul 16 13:02:33 stream-box-s2-ultra volumio[1217]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 16 13:02:34 stream-box-s2-ultra sudo[610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-16 13:01 Jul 16 13:02:34 stream-box-s2-ultra sudo[610]: 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"