-- Logs begin at Mon 2025-12-15 13:12:45 UTC, end at Thu 2025-12-18 08:26:45 UTC. -- Dec 18 08:25:05 opera volumio[931]: info: CoreCommandRouter::volumioClearQueue Dec 18 08:25:05 opera volumio[931]: info: CoreStateMachine::ClearQueue Dec 18 08:25:05 opera volumio[931]: info: CoreStateMachine::stop Dec 18 08:25:05 opera volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:25:05 opera volumio[931]: verbose: UNSET VOLATILE Dec 18 08:25:05 opera volumio[931]: info: CoreStateMachine::stPlaybackTimer Dec 18 08:25:05 opera volumio[931]: info: CoreStateMachine::updateTrackBlock Dec 18 08:25:05 opera volumio[931]: info: CorePlayQueue::getTrackBlock Dec 18 08:25:05 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:05 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:05 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:05 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:05 opera volumio[931]: info: CoreStateMachine::serviceStop Dec 18 08:25:05 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:05 opera volumio[931]: info: ControllerMpd::stop Dec 18 08:25:05 opera volumio[931]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 08:25:05 opera volumio[931]: info: CorePlayQueue::clearPlayQueue Dec 18 08:25:05 opera volumio[931]: info: CorePlayQueue::saveQueue Dec 18 08:25:05 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:05 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:05 opera volumio[931]: info: CoreCommandRouter::volumioPushQueue Dec 18 08:25:05 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:05 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:05 opera volumio[931]: info: Dec 18 08:25:05 opera volumio[931]: ---------------------------- MPD announces state update: player Dec 18 08:25:05 opera volumio[931]: info: sendMpdCommand stop took 105 milliseconds Dec 18 08:25:05 opera volumio[931]: info: ControllerMpd::getState Dec 18 08:25:05 opera volumio[931]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:25:05 opera volumio[931]: info: sendMpdCommand status took 3 milliseconds Dec 18 08:25:05 opera volumio[931]: verbose: ControllerMpd::parseState Dec 18 08:25:05 opera volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:25:05 opera volumio[931]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 18 08:25:05 opera volumio[931]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:25:05 opera volumio[931]: info: ControllerMpd::pushState Dec 18 08:25:05 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:05 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:05 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:05 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:05 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:05 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:05 opera volumio[931]: 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":"Schubert - Symphonie Nr. 5, B-Dur - 3. Menuetto. Allegro molto - Trio","artist":null,"album":null,"uri":"http://192.168.8.161:8200/MediaItems/661442.dsf","trackType":"dsf"} Dec 18 08:25:05 opera volumio[931]: verbose: CURRENT POSITION 0 Dec 18 08:25:05 opera volumio[931]: info: CoreStateMachine::syncState stateService stop Dec 18 08:25:05 opera volumio[931]: info: CoreStateMachine::syncState currentStatus stop Dec 18 08:25:05 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:05 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:05 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:05 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:05 opera volumio[931]: info: No code Dec 18 08:25:05 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:05 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:05 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:05 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:05 opera volumio[931]: info: ------------------------------ 24ms Dec 18 08:25:05 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:05 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:05 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:06 opera volumio[931]: info: [VRCP] preparing for control Dec 18 08:25:06 opera volumio[931]: info: RAAT Acquiring Control Dec 18 08:25:06 opera volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:25:06 opera volumio[931]: info: CoreCommandRouter::volumioStop Dec 18 08:25:06 opera volumio[931]: info: CoreStateMachine::stop Dec 18 08:25:06 opera volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:25:06 opera volumio[931]: verbose: UNSET VOLATILE Dec 18 08:25:06 opera volumio[931]: info: RAAT Control Acquired Dec 18 08:25:06 opera volumio[931]: info: [VRCP] prepared for control Dec 18 08:25:06 opera volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:25:06 opera volumio[931]: info: [VRCP] acknowledged control Dec 18 08:25:06 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:06 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:06 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:06 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:06 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:06 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:06 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:06 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:06 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:06 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:06 opera volumio[931]: info: [VRCP] already in control, acknowledged Dec 18 08:25:06 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:06 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:06 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:06 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:06 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:06 opera volumio[931]: [VRCP] RAAT stopped playing Dec 18 08:25:06 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:06 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:06 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:06 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:06 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:06 opera volumio[931]: verbose: STATE SERVICE {"status":"stop","service":"raat","title":"Ashes of Time 東邪西毒 (cello solo by Yo-Yo Ma)","artist":"Ashes of Time 東邪西毒 (cello solo by Yo-Yo Ma)","album":"Ashes of Time Redux 東邪西毒終極版","albumart":"/albumart?web=100/extralarge&path=/tmp/raat/albumart/e65d59c865f2244413d3cdf82362e397.jpg","uri":"","trackType":"rr","seek":null,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":false,"push_time":1766046306888,"stream":false,"repeatMode":"all"} Dec 18 08:25:06 opera volumio[931]: verbose: CURRENT POSITION 0 Dec 18 08:25:06 opera volumio[931]: info: CoreStateMachine::syncState stateService stop Dec 18 08:25:06 opera volumio[931]: info: CoreStateMachine::syncState currentStatus pause Dec 18 08:25:06 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:06 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:06 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:06 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:06 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:06 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:07 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:07 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:07 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:07 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:07 opera volumio[931]: verbose: STATE SERVICE {"status":"stop","service":"raat","title":"Ashes of Time 東邪西毒 (cello solo by Yo-Yo Ma)","artist":"Ashes of Time 東邪西毒 (cello solo by Yo-Yo Ma)","album":"Ashes of Time Redux 東邪西毒終極版","albumart":"/albumart?web=100/extralarge&path=/tmp/raat/albumart/e65d59c865f2244413d3cdf82362e397.jpg","uri":"","trackType":"rr","seek":null,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":false,"push_time":1766046306945,"stream":false,"repeatMode":"all"} Dec 18 08:25:07 opera volumio[931]: verbose: CURRENT POSITION 0 Dec 18 08:25:07 opera volumio[931]: info: CoreStateMachine::syncState stateService stop Dec 18 08:25:07 opera volumio[931]: info: CoreStateMachine::syncState currentStatus pause Dec 18 08:25:07 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:07 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:07 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:07 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:07 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:07 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:07 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:07 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:07 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:07 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:09 opera volumio[931]: info: Clearing queue after UPNP request Dec 18 08:25:09 opera volumio[931]: verbose: UNSET VOLATILE Dec 18 08:25:09 opera volumio[931]: info: RAAT Giving Up Control Dec 18 08:25:09 opera volumio[931]: info: RAAT Giving Up Control: OK Dec 18 08:25:09 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:09 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:09 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:09 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:09 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:09 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:09 opera volumio[931]: verbose: STATE SERVICE {"status":"stop","service":"","title":"","artist":"","album":"","albumart":"/albumart?web=100/extralarge&path=/tmp/raat/albumart/e65d59c865f2244413d3cdf82362e397.jpg","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":false,"push_time":1766046307235,"stream":false,"repeatMode":"all"} Dec 18 08:25:09 opera volumio[931]: verbose: CURRENT POSITION 0 Dec 18 08:25:09 opera volumio[931]: info: CoreStateMachine::syncState stateService stop Dec 18 08:25:09 opera volumio[931]: info: CoreStateMachine::syncState currentStatus play Dec 18 08:25:09 opera volumio[931]: info: CoreStateMachine::play index undefined Dec 18 08:25:09 opera volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:25:09 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:09 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:09 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:09 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:09 opera volumio[931]: verbose: UNSET VOLATILE Dec 18 08:25:09 opera volumio[931]: info: CoreCommandRouter::volumioStop Dec 18 08:25:09 opera volumio[931]: info: CoreStateMachine::stop Dec 18 08:25:09 opera volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:25:09 opera volumio[931]: verbose: UNSET VOLATILE Dec 18 08:25:09 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:09 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:09 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:09 opera volumio[931]: verbose: STATE SERVICE {"status":"pause","service":"","title":"Ashes of Time 東邪西毒 (cello solo by Yo-Yo Ma)","artist":"Ashes of Time 東邪西毒 (cello solo by Yo-Yo Ma)","album":"Ashes of Time Redux 東邪西毒終極版","albumart":"/albumart","uri":"","trackType":"","seek":1000,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":false,"push_time":1766046307235,"stream":false,"repeatMode":"all"} Dec 18 08:25:09 opera volumio[931]: verbose: CURRENT POSITION 0 Dec 18 08:25:09 opera volumio[931]: info: CoreStateMachine::syncState stateService pause Dec 18 08:25:09 opera volumio[931]: info: CoreStateMachine::syncState currentStatus stop Dec 18 08:25:09 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:09 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:09 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:09 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:09 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:09 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:09 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:09 opera volumio[931]: info: CoreStateMachine::ClearQueue Dec 18 08:25:09 opera volumio[931]: info: CoreStateMachine::stop Dec 18 08:25:09 opera volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:25:09 opera volumio[931]: verbose: UNSET VOLATILE Dec 18 08:25:09 opera volumio[931]: info: CorePlayQueue::clearPlayQueue Dec 18 08:25:09 opera volumio[931]: info: CorePlayQueue::saveQueue Dec 18 08:25:09 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:09 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:09 opera volumio[931]: info: CoreCommandRouter::volumioPushQueue Dec 18 08:25:09 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:09 opera volumio[931]: info: Dec 18 08:25:09 opera volumio[931]: ---------------------------- MPD announces system playlist update Dec 18 08:25:09 opera volumio[931]: info: Ignoring MPD Status Update Dec 18 08:25:09 opera volumio[931]: error: updateQueue error: null Dec 18 08:25:09 opera volumio[931]: info: ------------------------------ 1ms Dec 18 08:25:09 opera volumio[931]: info: Starting UPNP Playback Dec 18 08:25:09 opera volumio[931]: info: Preparing playback through UPNP Dec 18 08:25:09 opera volumio[931]: info: CoreCommandRouter::volumioGetState Dec 18 08:25:09 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:09 opera volumio[931]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 08:25:10 opera volumio[931]: info: Dec 18 08:25:10 opera volumio[931]: ---------------------------- MPD announces system playlist update Dec 18 08:25:10 opera volumio[931]: info: Ignoring MPD Status Update Dec 18 08:25:10 opera volumio[931]: info: ------------------------------ 2ms Dec 18 08:25:10 opera volumio[931]: info: Dec 18 08:25:10 opera volumio[931]: ---------------------------- MPD announces system playlist update Dec 18 08:25:10 opera volumio[931]: info: Ignoring MPD Status Update Dec 18 08:25:10 opera volumio[931]: info: ------------------------------ 1ms Dec 18 08:25:10 opera volumio[931]: info: Dec 18 08:25:10 opera volumio[931]: ---------------------------- MPD announces system playlist update Dec 18 08:25:10 opera volumio[931]: info: Ignoring MPD Status Update Dec 18 08:25:10 opera volumio[931]: info: ------------------------------ 0ms Dec 18 08:25:10 opera volumio[931]: info: Dec 18 08:25:10 opera volumio[931]: ---------------------------- MPD announces system playlist update Dec 18 08:25:10 opera volumio[931]: info: Ignoring MPD Status Update Dec 18 08:25:10 opera volumio[931]: info: ------------------------------ 1ms Dec 18 08:25:10 opera volumio[931]: info: Dec 18 08:25:10 opera volumio[931]: ---------------------------- MPD announces system playlist update Dec 18 08:25:10 opera volumio[931]: info: Ignoring MPD Status Update Dec 18 08:25:10 opera volumio[931]: info: ------------------------------ 0ms Dec 18 08:25:10 opera volumio[931]: info: Dec 18 08:25:10 opera volumio[931]: ---------------------------- MPD announces system playlist update Dec 18 08:25:10 opera volumio[931]: info: Ignoring MPD Status Update Dec 18 08:25:10 opera volumio[931]: info: ------------------------------ 1ms Dec 18 08:25:10 opera volumio[931]: info: Dec 18 08:25:10 opera volumio[931]: ---------------------------- MPD announces state update: player Dec 18 08:25:10 opera volumio[931]: info: ControllerMpd::getState Dec 18 08:25:10 opera volumio[931]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:25:10 opera volumio[931]: info: Dec 18 08:25:10 opera volumio[931]: ---------------------------- MPD announces state update: player Dec 18 08:25:10 opera volumio[931]: info: sendMpdCommand status took 123 milliseconds Dec 18 08:25:10 opera volumio[931]: info: ControllerMpd::getState Dec 18 08:25:10 opera volumio[931]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:25:10 opera volumio[931]: verbose: ControllerMpd::parseState Dec 18 08:25:10 opera volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:25:10 opera volumio[931]: info: sendMpdCommand status took 2 milliseconds Dec 18 08:25:10 opera volumio[931]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 08:25:10 opera volumio[931]: verbose: ControllerMpd::parseState Dec 18 08:25:10 opera volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:25:10 opera volumio[931]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:25:10 opera volumio[931]: info: ControllerMpd::pushState Dec 18 08:25:10 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:10 opera volumio[931]: verbose: In UPNP mode Dec 18 08:25:10 opera volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":354,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"Schubert - Symphonie Nr. 5, B-Dur - 4. Allegro vivace","artist":null,"album":null,"uri":"http://192.168.8.161:8200/MediaItems/661443.dsf","trackType":"dsf"} Dec 18 08:25:10 opera volumio[931]: verbose: CURRENT POSITION 0 Dec 18 08:25:10 opera volumio[931]: info: CoreStateMachine::syncState stateService play Dec 18 08:25:10 opera volumio[931]: info: CoreStateMachine::syncState currentStatus stop Dec 18 08:25:10 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:10 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:10 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:10 opera volumio[931]: info: ------------------------------ 133ms Dec 18 08:25:10 opera volumio[931]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 18 08:25:10 opera volumio[931]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:25:10 opera volumio[931]: info: ControllerMpd::pushState Dec 18 08:25:10 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:10 opera volumio[931]: verbose: In UPNP mode Dec 18 08:25:10 opera volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":493,"duration":354,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"Schubert - Symphonie Nr. 5, B-Dur - 4. Allegro vivace","artist":null,"album":null,"uri":"http://192.168.8.161:8200/MediaItems/661443.dsf","trackType":"dsf"} Dec 18 08:25:10 opera volumio[931]: verbose: CURRENT POSITION 0 Dec 18 08:25:10 opera volumio[931]: info: CoreStateMachine::syncState stateService play Dec 18 08:25:10 opera volumio[931]: info: CoreStateMachine::syncState currentStatus play Dec 18 08:25:10 opera volumio[931]: info: Received an update from plugin. extracting info from payload Dec 18 08:25:10 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:10 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:10 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:10 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:10 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:10 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:10 opera volumio[931]: info: ------------------------------ 23ms Dec 18 08:25:10 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:10 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:10 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:18 opera volumio[931]: info: [VRCP] preparing for control Dec 18 08:25:18 opera volumio[931]: info: RAAT Acquiring Control Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::volumioStop Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::stop Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:25:18 opera volumio[931]: verbose: UNSET VOLATILE Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::stPlaybackTimer Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::updateTrackBlock Dec 18 08:25:18 opera volumio[931]: info: CorePlayQueue::getTrackBlock Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:18 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::serviceStop Dec 18 08:25:18 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:18 opera volumio[931]: info: ControllerMpd::stop Dec 18 08:25:18 opera volumio[931]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 08:25:18 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:18 opera volumio[931]: info: Dec 18 08:25:18 opera volumio[931]: ---------------------------- MPD announces state update: player Dec 18 08:25:18 opera volumio[931]: info: sendMpdCommand stop took 67 milliseconds Dec 18 08:25:18 opera volumio[931]: info: ControllerMpd::getState Dec 18 08:25:18 opera volumio[931]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:25:18 opera volumio[931]: info: RAAT Control Acquired Dec 18 08:25:18 opera volumio[931]: info: [VRCP] prepared for control Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:25:18 opera volumio[931]: info: [VRCP] acknowledged control Dec 18 08:25:18 opera volumio[931]: info: sendMpdCommand status took 4 milliseconds Dec 18 08:25:18 opera volumio[931]: verbose: ControllerMpd::parseState Dec 18 08:25:18 opera volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:18 opera volumio[931]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 18 08:25:18 opera volumio[931]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:25:18 opera volumio[931]: info: ControllerMpd::pushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:18 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:18 opera volumio[931]: 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":"Schubert - Symphonie Nr. 5, B-Dur - 4. Allegro vivace","artist":null,"album":null,"uri":"http://192.168.8.161:8200/MediaItems/661443.dsf","trackType":"dsf"} Dec 18 08:25:18 opera volumio[931]: verbose: CURRENT POSITION 0 Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::syncState stateService stop Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::syncState currentStatus pause Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:18 opera volumio[931]: info: ------------------------------ 27ms Dec 18 08:25:18 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:18 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:18 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:18 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:18 opera volumio[931]: info: [VRCP] already in control, acknowledged Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:18 opera volumio[931]: [VRCP] RAAT stopped playing Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:18 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:18 opera volumio[931]: verbose: STATE SERVICE {"status":"stop","service":"raat","title":"Ashes of Time 東邪西毒 (cello solo by Yo-Yo Ma)","artist":"Ashes of Time 東邪西毒 (cello solo by Yo-Yo Ma)","album":"Ashes of Time Redux 東邪西毒終極版","albumart":"/albumart?web=100/extralarge&path=/tmp/raat/albumart/e65d59c865f2244413d3cdf82362e397.jpg","uri":"","trackType":"rr","seek":null,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":false,"push_time":1766046318589,"stream":false,"repeatMode":"all"} Dec 18 08:25:18 opera volumio[931]: verbose: CURRENT POSITION 0 Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::syncState stateService stop Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::syncState currentStatus pause Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:18 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:18 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:18 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:18 opera volumio[931]: info: CorePlayQueue::getTrack 0 Dec 18 08:25:18 opera volumio[931]: verbose: STATE SERVICE {"status":"stop","service":"raat","title":"Ashes of Time 東邪西毒 (cello solo by Yo-Yo Ma)","artist":"Ashes of Time 東邪西毒 (cello solo by Yo-Yo Ma)","album":"Ashes of Time Redux 東邪西毒終極版","albumart":"/albumart?web=100/extralarge&path=/tmp/raat/albumart/e65d59c865f2244413d3cdf82362e397.jpg","uri":"","trackType":"rr","seek":null,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":false,"push_time":1766046318656,"stream":false,"repeatMode":"all"} Dec 18 08:25:18 opera volumio[931]: verbose: CURRENT POSITION 0 Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::syncState stateService stop Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::syncState currentStatus pause Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:18 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:18 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:18 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:18 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:18 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:39 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:39 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:39 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:39 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:39 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:59 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:25:59 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:59 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:59 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:59 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:59 opera volumio[931]: info: VolumeController::SetAlsaVolume8 Dec 18 08:25:59 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:25:59 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:25:59 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:25:59 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:25:59 opera volumio[931]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Dec 18 08:26:01 opera volumio[931]: info: VolumeController::SetAlsaVolume7 Dec 18 08:26:01 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:26:01 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:26:01 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:26:01 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:26:01 opera volumio[931]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Dec 18 08:26:20 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:26:20 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:26:20 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:26:20 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:26:20 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:26:37 opera volumio[931]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Hoff%20Ensemble/Quiet%20Winter%20Night/9da9a0ac-ba42-449a-b57f-4375ef81aed2.jpg' Dec 18 08:26:37 opera volumio[931]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Andrea%20Bocelli/Incanto/6aefd6a4-bf16-4bd6-aebc-60a137ea43da.jpg' Dec 18 08:26:40 opera volumio[931]: info: CoreCommandRouter::servicePushState Dec 18 08:26:40 opera volumio[931]: info: CoreStateMachine::pushState Dec 18 08:26:40 opera volumio[931]: info: CoreCommandRouter::volumioPushState Dec 18 08:26:40 opera volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:26:40 opera volumio[931]: info: Updating RAAT Signal Path Dec 18 08:26:45 opera volumio[931]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 08:26:45 opera volumio[931]: { Error: connect ETIMEDOUT 31.13.88.26:443 Dec 18 08:26:45 opera volumio[931]: at Object._errnoException (util.js:1022:11) Dec 18 08:26:45 opera volumio[931]: at _exceptionWithHostPort (util.js:1044:20) Dec 18 08:26:45 opera volumio[931]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Dec 18 08:26:45 opera volumio[931]: code: 'ETIMEDOUT', Dec 18 08:26:45 opera volumio[931]: errno: 'ETIMEDOUT', Dec 18 08:26:45 opera volumio[931]: syscall: 'connect', Dec 18 08:26:45 opera volumio[931]: address: '31.13.88.26', Dec 18 08:26:45 opera volumio[931]: port: 443 } Dec 18 08:26:45 opera volumio[931]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 08:26:45 opera sudo[22408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-18 08:25 Dec 18 08:26:45 opera sudo[22408]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="047a38c6e11e7058b10d3038ac9dcbff94f92828" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Apr 8 17:29:57 CEST 2022" VOLUMIO_VERSION="1.033" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="77fa3b0bf86939c934741dd9bb62adcc"