-- Logs begin at Sat 2024-10-26 15:55:29 WEST, end at Sat 2024-10-26 15:58:34 WEST. -- Oct 26 15:57:01 volumio volumio[1216]: info: Preload queue cleared Oct 26 15:57:01 volumio volumio[1216]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 26 15:57:01 volumio volumio[1216]: info: CoreStateMachine::ClearQueue Oct 26 15:57:01 volumio volumio[1216]: info: CoreStateMachine::stop Oct 26 15:57:01 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:57:01 volumio volumio[1216]: info: CorePlayQueue::clearPlayQueue Oct 26 15:57:01 volumio volumio[1216]: info: CorePlayQueue::saveQueue Oct 26 15:57:01 volumio volumio[1216]: info: CoreCommandRouter::volumioPushQueue Oct 26 15:57:01 volumio volumio[1216]: info: CoreStateMachine::addQueueItems Oct 26 15:57:01 volumio volumio[1216]: info: CorePlayQueue::addQueueItems Oct 26 15:57:01 volumio volumio[1216]: info: Preload queue cleared Oct 26 15:57:01 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282246 Oct 26 15:57:01 volumio volumio[1216]: info: Exploding uri qobuz://song/48282246 in service qobuz Oct 26 15:57:01 volumio volumio[1216]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 26 15:57:01 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282247 Oct 26 15:57:01 volumio volumio[1216]: info: Exploding uri qobuz://song/48282247 in service qobuz Oct 26 15:57:01 volumio volumio[1216]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 26 15:57:01 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282248 Oct 26 15:57:01 volumio volumio[1216]: info: Exploding uri qobuz://song/48282248 in service qobuz Oct 26 15:57:01 volumio volumio[1216]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 26 15:57:01 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282249 Oct 26 15:57:01 volumio volumio[1216]: info: Exploding uri qobuz://song/48282249 in service qobuz Oct 26 15:57:01 volumio volumio[1216]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 26 15:57:01 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282250 Oct 26 15:57:01 volumio volumio[1216]: info: Exploding uri qobuz://song/48282250 in service qobuz Oct 26 15:57:01 volumio volumio[1216]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 26 15:57:01 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282251 Oct 26 15:57:01 volumio volumio[1216]: info: Exploding uri qobuz://song/48282251 in service qobuz Oct 26 15:57:01 volumio volumio[1216]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 26 15:57:01 volumio volumio[1216]: info: explodeUri took 761 milliseconds Oct 26 15:57:01 volumio volumio[1216]: info: Preload queue cleared Oct 26 15:57:01 volumio volumio[1216]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 26 15:57:01 volumio volumio[1216]: info: CoreStateMachine::ClearQueue Oct 26 15:57:01 volumio volumio[1216]: info: CoreStateMachine::stop Oct 26 15:57:01 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:57:01 volumio volumio[1216]: info: CorePlayQueue::clearPlayQueue Oct 26 15:57:01 volumio volumio[1216]: info: CorePlayQueue::saveQueue Oct 26 15:57:01 volumio volumio[1216]: info: CoreCommandRouter::volumioPushQueue Oct 26 15:57:01 volumio volumio[1216]: info: CoreStateMachine::addQueueItems Oct 26 15:57:01 volumio volumio[1216]: info: CorePlayQueue::addQueueItems Oct 26 15:57:01 volumio volumio[1216]: info: Preload queue cleared Oct 26 15:57:01 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282246 Oct 26 15:57:01 volumio volumio[1216]: info: Exploding uri qobuz://song/48282246 in service qobuz Oct 26 15:57:01 volumio volumio[1216]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 26 15:57:01 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282247 Oct 26 15:57:01 volumio volumio[1216]: info: Exploding uri qobuz://song/48282247 in service qobuz Oct 26 15:57:01 volumio volumio[1216]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 26 15:57:01 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282248 Oct 26 15:57:01 volumio volumio[1216]: info: Exploding uri qobuz://song/48282248 in service qobuz Oct 26 15:57:01 volumio volumio[1216]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 26 15:57:01 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282249 Oct 26 15:57:01 volumio volumio[1216]: info: Exploding uri qobuz://song/48282249 in service qobuz Oct 26 15:57:01 volumio volumio[1216]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 26 15:57:01 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282250 Oct 26 15:57:01 volumio volumio[1216]: info: Using cached record of: qobuz://song/48282250 Oct 26 15:57:01 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282251 Oct 26 15:57:01 volumio volumio[1216]: info: Exploding uri qobuz://song/48282251 in service qobuz Oct 26 15:57:01 volumio volumio[1216]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 26 15:57:01 volumio volumio[1216]: info: explodeUri took 818 milliseconds Oct 26 15:57:01 volumio volumio[1216]: info: explodeUri took 828 milliseconds Oct 26 15:57:01 volumio volumio[1216]: info: explodeUri took 843 milliseconds Oct 26 15:57:01 volumio volumio[1216]: info: explodeUri took 846 milliseconds Oct 26 15:57:02 volumio volumio[1216]: info: explodeUri took 1025 milliseconds Oct 26 15:57:02 volumio volumio[1216]: info: CoreCommandRouter::volumioPushQueue Oct 26 15:57:02 volumio volumio[1216]: info: CorePlayQueue::saveQueue Oct 26 15:57:02 volumio volumio[1216]: info: CoreStateMachine::updateTrackBlock Oct 26 15:57:02 volumio volumio[1216]: info: CorePlayQueue::getTrackBlock Oct 26 15:57:02 volumio volumio[1216]: info: CoreCommandRouter::volumioPlay Oct 26 15:57:02 volumio volumio[1216]: info: CoreStateMachine::play index 0 Oct 26 15:57:02 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:57:02 volumio volumio[1216]: info: CoreStateMachine::stop Oct 26 15:57:02 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:57:02 volumio volumio[1216]: info: CoreStateMachine::play index undefined Oct 26 15:57:02 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:57:02 volumio volumio[1216]: info: CorePlayQueue::getTrack 0 Oct 26 15:57:02 volumio volumio[1216]: info: CoreStateMachine::startPlaybackTimer Oct 26 15:57:02 volumio volumio[1216]: info: CorePlayQueue::getTrack 0 Oct 26 15:57:02 volumio volumio[1216]: info: [1729954622092] ControllerQobuz::clearAddPlayTrack Oct 26 15:57:02 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 15:57:02 volumio volumio[1216]: info: Oct 26 15:57:02 volumio volumio[1216]: ---------------------------- MPD announces state update: player Oct 26 15:57:02 volumio volumio[1216]: info: sendMpdCommand stop took 8 milliseconds Oct 26 15:57:02 volumio volumio[1216]: info: ControllerMpd::getState Oct 26 15:57:02 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:57:02 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 15:57:02 volumio volumio[1216]: info: Oct 26 15:57:02 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Oct 26 15:57:02 volumio volumio[1216]: info: Ignoring MPD Status Update Oct 26 15:57:02 volumio volumio[1216]: info: sendMpdCommand status took 1 milliseconds Oct 26 15:57:02 volumio volumio[1216]: info: sendMpdCommand clear took 1 milliseconds Oct 26 15:57:02 volumio volumio[1216]: verbose: ControllerMpd::parseState Oct 26 15:57:02 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:57:02 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/48282246" Oct 26 15:57:02 volumio volumio[1216]: error: updateQueue error: null Oct 26 15:57:02 volumio volumio[1216]: info: ------------------------------ 3ms Oct 26 15:57:02 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 26 15:57:02 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:57:02 volumio volumio[1216]: STREAMING PROXY: Handling url /?data=qobuz://song/48282246 Oct 26 15:57:02 volumio volumio[1216]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 26 15:57:02 volumio volumio[1216]: info: ------------------------------ 5ms Oct 26 15:57:02 volumio volumio[1216]: info: Executing endpoint getStreamUrlqobuz Oct 26 15:57:02 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 26 15:57:02 volumio volumio[1216]: info: explodeUri took 457 milliseconds Oct 26 15:57:02 volumio volumio[1216]: info: explodeUri took 451 milliseconds Oct 26 15:57:02 volumio volumio[1216]: info: explodeUri took 468 milliseconds Oct 26 15:57:02 volumio volumio[1216]: info: explodeUri took 593 milliseconds Oct 26 15:57:02 volumio volumio[1216]: info: explodeUri took 659 milliseconds Oct 26 15:57:02 volumio volumio[1216]: info: CoreCommandRouter::volumioPushQueue Oct 26 15:57:02 volumio volumio[1216]: info: CorePlayQueue::saveQueue Oct 26 15:57:02 volumio volumio[1216]: info: CoreStateMachine::updateTrackBlock Oct 26 15:57:02 volumio volumio[1216]: info: CorePlayQueue::getTrackBlock Oct 26 15:57:02 volumio volumio[1216]: info: CoreCommandRouter::volumioPlay Oct 26 15:57:02 volumio volumio[1216]: info: CoreStateMachine::play index 0 Oct 26 15:57:02 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:57:02 volumio volumio[1216]: info: CoreStateMachine::stop Oct 26 15:57:02 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:57:02 volumio volumio[1216]: info: CoreStateMachine::play index undefined Oct 26 15:57:02 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:57:02 volumio volumio[1216]: info: CorePlayQueue::getTrack 0 Oct 26 15:57:02 volumio volumio[1216]: info: CoreStateMachine::startPlaybackTimer Oct 26 15:57:02 volumio volumio[1216]: info: CorePlayQueue::getTrack 0 Oct 26 15:57:02 volumio volumio[1216]: info: [1729954622524] ControllerQobuz::clearAddPlayTrack Oct 26 15:57:02 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 15:57:02 volumio volumio[1216]: info: getStreamUrl took 701 milliseconds Oct 26 15:57:02 volumio volumio[1216]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2386042&eid=48282246&fmt=7&profile=raw&app_id=539451548&cid=2392600&etsp=1729958222&hmac=kpdmkeapaBiVEBpYb_dnQBDA5hE Oct 26 15:57:02 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 15:57:02 volumio volumio[1216]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 26 15:57:02 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 26 15:57:02 volumio volumio[1216]: info: Received Get System Version Oct 26 15:57:02 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 15:57:03 volumio volumio[1216]: info: Received Get System Info Oct 26 15:57:03 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:57:03 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:57:03 volumio volumio[1216]: info: Discovery: Getting this device information Oct 26 15:57:03 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 26 15:57:03 volumio volumio[1216]: info: CorePlayQueue::getTrack 0 Oct 26 15:57:03 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:57:03 volumio volumio[1216]: STREAMING PROXY: Response: 200, length: 88822666 Oct 26 15:57:03 volumio volumio[1216]: STREAMING PROXY: Client dropped request, destroying Oct 26 15:57:03 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/48282246" Oct 26 15:57:03 volumio volumio[1216]: info: sendMpdCommand stop took 487 milliseconds Oct 26 15:57:03 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 15:57:03 volumio volumio[1216]: info: Oct 26 15:57:03 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Oct 26 15:57:03 volumio volumio[1216]: info: Ignoring MPD Status Update Oct 26 15:57:03 volumio volumio[1216]: info: Oct 26 15:57:03 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Oct 26 15:57:03 volumio volumio[1216]: info: Ignoring MPD Status Update Oct 26 15:57:03 volumio volumio[1216]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/48282246" took 2 milliseconds Oct 26 15:57:03 volumio volumio[1216]: info: sendMpdCommand clear took 2 milliseconds Oct 26 15:57:03 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 26 15:57:03 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand play Oct 26 15:57:03 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/48282246" Oct 26 15:57:03 volumio volumio[1216]: error: updateQueue error: null Oct 26 15:57:03 volumio volumio[1216]: error: updateQueue error: null Oct 26 15:57:03 volumio volumio[1216]: STREAMING PROXY: Handling url /?data=qobuz://song/48282246 Oct 26 15:57:03 volumio volumio[1216]: info: ------------------------------ 4ms Oct 26 15:57:03 volumio volumio[1216]: info: ------------------------------ 3ms Oct 26 15:57:03 volumio volumio[1216]: info: sendMpdCommand play took 3 milliseconds Oct 26 15:57:03 volumio volumio[1216]: info: Executing endpoint getStreamUrlqobuz Oct 26 15:57:03 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 26 15:57:03 volumio volumio[1216]: info: Preload queue cleared Oct 26 15:57:03 volumio volumio[1216]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 26 15:57:03 volumio volumio[1216]: info: CoreStateMachine::ClearQueue Oct 26 15:57:03 volumio volumio[1216]: info: CoreStateMachine::stop Oct 26 15:57:03 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:57:03 volumio volumio[1216]: info: CorePlayQueue::clearPlayQueue Oct 26 15:57:03 volumio volumio[1216]: info: CorePlayQueue::saveQueue Oct 26 15:57:03 volumio volumio[1216]: info: CoreCommandRouter::volumioPushQueue Oct 26 15:57:03 volumio volumio[1216]: info: CoreStateMachine::addQueueItems Oct 26 15:57:03 volumio volumio[1216]: info: CorePlayQueue::addQueueItems Oct 26 15:57:03 volumio volumio[1216]: info: Preload queue cleared Oct 26 15:57:03 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282246 Oct 26 15:57:03 volumio volumio[1216]: info: Using cached record of: qobuz://song/48282246 Oct 26 15:57:03 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282247 Oct 26 15:57:03 volumio volumio[1216]: info: Using cached record of: qobuz://song/48282247 Oct 26 15:57:03 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282248 Oct 26 15:57:03 volumio volumio[1216]: info: Using cached record of: qobuz://song/48282248 Oct 26 15:57:03 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282249 Oct 26 15:57:03 volumio volumio[1216]: info: Using cached record of: qobuz://song/48282249 Oct 26 15:57:03 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282250 Oct 26 15:57:03 volumio volumio[1216]: info: Using cached record of: qobuz://song/48282250 Oct 26 15:57:03 volumio volumio[1216]: info: Adding Item to queue: qobuz://song/48282251 Oct 26 15:57:03 volumio volumio[1216]: info: Using cached record of: qobuz://song/48282251 Oct 26 15:57:03 volumio volumio[1216]: info: CoreCommandRouter::volumioPushQueue Oct 26 15:57:03 volumio volumio[1216]: info: CorePlayQueue::saveQueue Oct 26 15:57:03 volumio volumio[1216]: info: CoreStateMachine::updateTrackBlock Oct 26 15:57:03 volumio volumio[1216]: info: CorePlayQueue::getTrackBlock Oct 26 15:57:03 volumio volumio[1216]: info: CoreCommandRouter::volumioPlay Oct 26 15:57:03 volumio volumio[1216]: info: CoreStateMachine::play index 0 Oct 26 15:57:03 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:57:03 volumio volumio[1216]: info: CoreStateMachine::stop Oct 26 15:57:03 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:57:03 volumio volumio[1216]: info: CoreStateMachine::play index undefined Oct 26 15:57:03 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:57:03 volumio volumio[1216]: info: CorePlayQueue::getTrack 0 Oct 26 15:57:03 volumio volumio[1216]: info: CoreStateMachine::startPlaybackTimer Oct 26 15:57:03 volumio volumio[1216]: info: CorePlayQueue::getTrack 0 Oct 26 15:57:03 volumio volumio[1216]: info: [1729954623134] ControllerQobuz::clearAddPlayTrack Oct 26 15:57:03 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 15:57:03 volumio volumio[1216]: info: getStreamUrl took 708 milliseconds Oct 26 15:57:03 volumio volumio[1216]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2386042&eid=48282246&fmt=7&profile=raw&app_id=539451548&cid=2392600&etsp=1729958223&hmac=wzOZsufS8xFmmgcEh8A21gISQZQ Oct 26 15:57:03 volumio volumio[1216]: STREAMING PROXY: Response: 200, length: 88822666 Oct 26 15:57:03 volumio volumio[1216]: STREAMING PROXY: Client dropped request, destroying Oct 26 15:57:03 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/48282246" Oct 26 15:57:03 volumio volumio[1216]: info: sendMpdCommand stop took 612 milliseconds Oct 26 15:57:03 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 15:57:03 volumio volumio[1216]: info: Oct 26 15:57:03 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Oct 26 15:57:03 volumio volumio[1216]: info: Ignoring MPD Status Update Oct 26 15:57:03 volumio volumio[1216]: info: Oct 26 15:57:03 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Oct 26 15:57:03 volumio volumio[1216]: info: Ignoring MPD Status Update Oct 26 15:57:03 volumio volumio[1216]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/48282246" took 3 milliseconds Oct 26 15:57:03 volumio volumio[1216]: info: sendMpdCommand clear took 2 milliseconds Oct 26 15:57:03 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 26 15:57:03 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand play Oct 26 15:57:03 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/48282246" Oct 26 15:57:03 volumio volumio[1216]: error: updateQueue error: null Oct 26 15:57:03 volumio volumio[1216]: error: updateQueue error: null Oct 26 15:57:03 volumio volumio[1216]: STREAMING PROXY: Handling url /?data=qobuz://song/48282246 Oct 26 15:57:03 volumio volumio[1216]: info: ------------------------------ 3ms Oct 26 15:57:03 volumio volumio[1216]: info: ------------------------------ 3ms Oct 26 15:57:03 volumio volumio[1216]: info: sendMpdCommand play took 1 milliseconds Oct 26 15:57:03 volumio volumio[1216]: info: Executing endpoint getStreamUrlqobuz Oct 26 15:57:03 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 26 15:57:04 volumio volumio[1216]: info: CoreCommandRouter::volumioPlay Oct 26 15:57:04 volumio volumio[1216]: info: CoreStateMachine::play index undefined Oct 26 15:57:04 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:57:04 volumio volumio[1216]: info: CorePlayQueue::getTrack 0 Oct 26 15:57:04 volumio volumio[1216]: info: CoreStateMachine::startPlaybackTimer Oct 26 15:57:04 volumio volumio[1216]: info: CorePlayQueue::getTrack 0 Oct 26 15:57:04 volumio volumio[1216]: info: [1729954624148] ControllerQobuz::clearAddPlayTrack Oct 26 15:57:04 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 15:57:04 volumio volumio[1216]: info: getStreamUrl took 542 milliseconds Oct 26 15:57:04 volumio volumio[1216]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2386042&eid=48282246&fmt=7&profile=raw&app_id=539451548&cid=2392600&etsp=1729958224&hmac=RKAWRL7hCM3fI-x5sls1xFDg7gk Oct 26 15:57:04 volumio volumio[1216]: STREAMING PROXY: Response: 200, length: 88822666 Oct 26 15:57:04 volumio volumio[1216]: STREAMING PROXY: Client dropped request, destroying Oct 26 15:57:04 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/48282246" Oct 26 15:57:04 volumio volumio[1216]: info: sendMpdCommand stop took 162 milliseconds Oct 26 15:57:04 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 15:57:04 volumio volumio[1216]: info: Oct 26 15:57:04 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Oct 26 15:57:04 volumio volumio[1216]: info: Ignoring MPD Status Update Oct 26 15:57:04 volumio volumio[1216]: info: Oct 26 15:57:04 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Oct 26 15:57:04 volumio volumio[1216]: info: Ignoring MPD Status Update Oct 26 15:57:04 volumio volumio[1216]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/48282246" took 1 milliseconds Oct 26 15:57:04 volumio volumio[1216]: info: sendMpdCommand clear took 1 milliseconds Oct 26 15:57:04 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 26 15:57:04 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand play Oct 26 15:57:04 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/48282246" Oct 26 15:57:04 volumio volumio[1216]: error: updateQueue error: null Oct 26 15:57:04 volumio volumio[1216]: error: updateQueue error: null Oct 26 15:57:04 volumio volumio[1216]: info: ------------------------------ 3ms Oct 26 15:57:04 volumio volumio[1216]: info: ------------------------------ 2ms Oct 26 15:57:04 volumio volumio[1216]: info: sendMpdCommand play took 2 milliseconds Oct 26 15:57:04 volumio volumio[1216]: STREAMING PROXY: Handling url /?data=qobuz://song/48282246 Oct 26 15:57:04 volumio volumio[1216]: info: Executing endpoint getStreamUrlqobuz Oct 26 15:57:04 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 26 15:57:04 volumio volumio[1216]: info: getStreamUrl took 525 milliseconds Oct 26 15:57:04 volumio volumio[1216]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2386042&eid=48282246&fmt=7&profile=raw&app_id=539451548&cid=2392600&etsp=1729958224&hmac=RKAWRL7hCM3fI-x5sls1xFDg7gk Oct 26 15:57:04 volumio volumio[1216]: STREAMING PROXY: Response: 200, length: 88822666 Oct 26 15:57:04 volumio volumio[1216]: STREAMING PROXY: Client dropped request, destroying Oct 26 15:57:04 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/48282246" Oct 26 15:57:04 volumio volumio[1216]: info: Oct 26 15:57:04 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Oct 26 15:57:04 volumio volumio[1216]: info: Ignoring MPD Status Update Oct 26 15:57:04 volumio volumio[1216]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/48282246" took 1 milliseconds Oct 26 15:57:04 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 26 15:57:04 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand play Oct 26 15:57:04 volumio volumio[1216]: info: ------------------------------ 1ms Oct 26 15:57:04 volumio volumio[1216]: info: sendMpdCommand play took 1 milliseconds Oct 26 15:57:04 volumio volumio[1216]: STREAMING PROXY: Handling url /?data=qobuz://song/48282246 Oct 26 15:57:04 volumio volumio[1216]: info: Executing endpoint getStreamUrlqobuz Oct 26 15:57:04 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 26 15:57:05 volumio volumio[1216]: info: getStreamUrl took 576 milliseconds Oct 26 15:57:05 volumio volumio[1216]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2386042&eid=48282246&fmt=7&profile=raw&app_id=539451548&cid=2392600&etsp=1729958225&hmac=wgVusuGxtMEQId2bM8aEPaajOAs Oct 26 15:57:05 volumio volumio[1216]: STREAMING PROXY: Response: 200, length: 88822666 Oct 26 15:57:05 volumio volumio[1216]: info: Oct 26 15:57:05 volumio volumio[1216]: ---------------------------- MPD announces state update: player Oct 26 15:57:05 volumio volumio[1216]: info: ControllerMpd::getState Oct 26 15:57:05 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:57:05 volumio volumio[1216]: info: Oct 26 15:57:05 volumio volumio[1216]: ---------------------------- MPD announces state update: player Oct 26 15:57:05 volumio volumio[1216]: info: sendMpdCommand status took 9 milliseconds Oct 26 15:57:05 volumio volumio[1216]: info: ControllerMpd::getState Oct 26 15:57:05 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:57:05 volumio volumio[1216]: verbose: ControllerMpd::parseState Oct 26 15:57:05 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:57:05 volumio volumio[1216]: info: sendMpdCommand status took 2 milliseconds Oct 26 15:57:05 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 26 15:57:05 volumio volumio[1216]: verbose: ControllerMpd::parseState Oct 26 15:57:05 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:57:05 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:57:05 volumio volumio[1216]: info: ControllerMpd::pushState Oct 26 15:57:05 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Oct 26 15:57:05 volumio volumio[1216]: info: CorePlayQueue::getTrack 0 Oct 26 15:57:05 volumio volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":308,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"292 Kbps","isStreaming":false,"title":"48282246","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/48282246","trackType":"qobuz"} Oct 26 15:57:05 volumio volumio[1216]: verbose: CURRENT POSITION 0 Oct 26 15:57:05 volumio volumio[1216]: info: CoreStateMachine::syncState stateService play Oct 26 15:57:05 volumio volumio[1216]: info: CoreStateMachine::syncState currentStatus stop Oct 26 15:57:05 volumio volumio[1216]: info: ------------------------------ 14ms Oct 26 15:57:05 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 26 15:57:05 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:57:05 volumio volumio[1216]: info: ControllerMpd::pushState Oct 26 15:57:05 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Oct 26 15:57:05 volumio volumio[1216]: info: CorePlayQueue::getTrack 0 Oct 26 15:57:05 volumio volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":308,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1527 Kbps","isStreaming":false,"title":"48282246","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/48282246","trackType":"qobuz"} Oct 26 15:57:05 volumio volumio[1216]: verbose: CURRENT POSITION 0 Oct 26 15:57:05 volumio volumio[1216]: info: CoreStateMachine::syncState stateService play Oct 26 15:57:05 volumio volumio[1216]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:57:05 volumio volumio[1216]: info: Received an update from plugin. extracting info from payload Oct 26 15:57:05 volumio volumio[1216]: info: CoreStateMachine::pushState Oct 26 15:57:05 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 15:57:05 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Oct 26 15:57:05 volumio volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:57:05 volumio volumio[1216]: info: MRS: Pushing multiroomSync output Oct 26 15:57:05 volumio volumio[1216]: info: CoreStateMachine::pushState Oct 26 15:57:05 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Oct 26 15:57:05 volumio volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:57:05 volumio volumio[1216]: info: MRS: Pushing multiroomSync output Oct 26 15:57:05 volumio volumio[1216]: info: ------------------------------ 28ms Oct 26 15:57:06 volumio volumio[1216]: STREAMING PROXY: Client dropped request, destroying Oct 26 15:57:07 volumio volumio[1216]: info: Executing endpoint metavolumio Oct 26 15:57:07 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 26 15:57:07 volumio volumio[1216]: info: Executing endpoint metavolumio Oct 26 15:57:07 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 26 15:57:07 volumio volumio[1216]: info: Executing endpoint metavolumio Oct 26 15:57:07 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 26 15:57:07 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:57:07 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:57:07 volumio volumio[1216]: info: Discovery: Getting this device information Oct 26 15:57:07 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 26 15:57:07 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:57:09 volumio volumio[1216]: error: Failed request for metavolumio API Oct 26 15:57:22 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:57:22 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:57:22 volumio volumio[1216]: info: Discovery: Getting this device information Oct 26 15:57:22 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 26 15:57:22 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:57:50 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:57:50 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:57:50 volumio volumio[1216]: info: Discovery: Getting this device information Oct 26 15:57:50 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 26 15:57:50 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:57:50 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:57:50 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:57:50 volumio volumio[1216]: info: Discovery: Getting this device information Oct 26 15:57:50 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 26 15:57:50 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:57:50 volumio volumio[1216]: verbose: New Socket.io Connection to 192.168.100.125:3000 from 192.168.100.142 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 26 15:57:51 volumio volumio[1216]: verbose: New Socket.io Connection to 192.168.100.125 from 192.168.100.142 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Oct 26 15:57:51 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 15:57:51 volumio volumio[1216]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 15:57:51 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 15:57:51 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 26 15:57:51 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 26 15:57:51 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 26 15:57:51 volumio volumio[1216]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 26 15:57:51 volumio volumio[1216]: info: Received Get System Info Oct 26 15:57:51 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:57:51 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:57:51 volumio volumio[1216]: info: Discovery: Getting this device information Oct 26 15:57:51 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 26 15:57:51 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:57:51 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 26 15:57:51 volumio volumio[1216]: info: Listing playlists Oct 26 15:57:51 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Oct 26 15:57:51 volumio volumio[1216]: info: CoreStateMachine::getQueue Oct 26 15:57:51 volumio volumio[1216]: info: CorePlayQueue::getQueue Oct 26 15:57:51 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 26 15:57:52 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:57:52 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:57:52 volumio volumio[1216]: info: Discovery: Getting this device information Oct 26 15:57:52 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 26 15:57:52 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:58:22 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:58:22 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:58:22 volumio volumio[1216]: info: Discovery: Getting this device information Oct 26 15:58:22 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 26 15:58:22 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:58:25 volumio volumio[1216]: info: Discovery: Getting this device information Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:58:25 volumio volumio[1216]: info: Discovery: Getting this device information Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:58:25 volumio volumio[1216]: verbose: New Socket.io Connection to 192.168.100.125 from 192.168.100.142 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 26 15:58:25 volumio volumio[1216]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 26 15:58:25 volumio volumio[1216]: info: Received Get System Info Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:58:25 volumio volumio[1216]: info: Discovery: Getting this device information Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 26 15:58:25 volumio volumio[1216]: info: Listing playlists Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Oct 26 15:58:25 volumio volumio[1216]: info: CoreStateMachine::getQueue Oct 26 15:58:25 volumio volumio[1216]: info: CorePlayQueue::getQueue Oct 26 15:58:25 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 26 15:58:26 volumio volumio[1216]: verbose: New Socket.io Connection to 192.168.100.125:3000 from 192.168.100.142 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 26 15:58:31 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Oct 26 15:58:32 volumio volumio[1216]: info: MRS: Setting this device as single Oct 26 15:58:32 volumio volumio[1216]: info: Oct 26 15:58:32 volumio volumio[1216]: [1729954712004] ---------------------------- Setting Multiroom Single Oct 26 15:58:32 volumio volumio[1216]: info: MRS: disable multiroom output Oct 26 15:58:32 volumio volumio[1216]: info: MRS: Set multiroom target PCM to volumioDiscard Oct 26 15:58:32 volumio volumio[1216]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Oct 26 15:58:32 volumio volumio[1216]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Oct 26 15:58:32 volumio volumio[1216]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Oct 26 15:58:32 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=6/ABRT Oct 26 15:58:32 volumio systemd[1]: mpd.service: Failed with result 'signal'. Oct 26 15:58:33 volumio volumio[1216]: info: MRS: STOPPING volumioStreaming Oct 26 15:58:33 volumio sudo[1840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 26 15:58:33 volumio sudo[1840]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 15:58:33 volumio sudo[1843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream0.ts /tmp/hls/stream1.ts /tmp/hls/stream10.ts /tmp/hls/stream11.ts /tmp/hls/stream12.ts /tmp/hls/stream13.ts /tmp/hls/stream14.ts /tmp/hls/stream15.ts /tmp/hls/stream16.ts /tmp/hls/stream17.ts /tmp/hls/stream18.ts /tmp/hls/stream19.ts /tmp/hls/stream2.ts /tmp/hls/stream20.ts /tmp/hls/stream21.ts /tmp/hls/stream22.ts /tmp/hls/stream23.ts /tmp/hls/stream24.ts /tmp/hls/stream25.ts /tmp/hls/stream26.ts /tmp/hls/stream27.ts /tmp/hls/stream28.ts /tmp/hls/stream29.ts /tmp/hls/stream3.ts /tmp/hls/stream30.ts /tmp/hls/stream31.ts /tmp/hls/stream32.ts /tmp/hls/stream33.ts /tmp/hls/stream34.ts /tmp/hls/stream35.ts /tmp/hls/stream36.ts /tmp/hls/stream37.ts /tmp/hls/stream38.ts /tmp/hls/stream39.ts /tmp/hls/stream4.ts /tmp/hls/stream40.ts /tmp/hls/stream41.ts /tmp/hls/stream42.ts /tmp/hls/stream43.ts /tmp/hls/stream44.ts /tmp/hls/stream45.ts Oct 26 15:58:33 volumio sudo[1843]: volumio : (command continued) /tmp/hls/stream46.ts /tmp/hls/stream47.ts /tmp/hls/stream48.ts /tmp/hls/stream49.ts /tmp/hls/stream5.ts /tmp/hls/stream50.ts /tmp/hls/stream51.ts /tmp/hls/stream52.ts /tmp/hls/stream53.ts /tmp/hls/stream54.ts /tmp/hls/stream55.ts /tmp/hls/stream56.ts /tmp/hls/stream57.ts /tmp/hls/stream58.ts /tmp/hls/stream59.ts /tmp/hls/stream6.ts /tmp/hls/stream60.ts /tmp/hls/stream61.ts /tmp/hls/stream62.ts /tmp/hls/stream63.ts /tmp/hls/stream64.ts /tmp/hls/stream65.ts /tmp/hls/stream66.ts /tmp/hls/stream67.ts /tmp/hls/stream68.ts /tmp/hls/stream69.ts /tmp/hls/stream7.ts /tmp/hls/stream70.ts /tmp/hls/stream71.ts /tmp/hls/stream72.ts /tmp/hls/stream73.ts /tmp/hls/stream74.ts /tmp/hls/stream75.ts /tmp/hls/stream76.ts /tmp/hls/stream77.ts /tmp/hls/stream78.ts /tmp/hls/stream79.ts /tmp/hls/stream8.ts /tmp/hls/stream80.ts /tmp/hls/stream81.ts /tmp/hls/stream82.ts /tmp/hls/stream83.ts /tmp/hls/stream84.ts /tmp/hls/stream85.ts Oct 26 15:58:33 volumio sudo[1843]: volumio : (command continued) /tmp/hls/stream86.ts /tmp/hls/stream87.ts /tmp/hls/stream9.ts Oct 26 15:58:33 volumio sudo[1843]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 15:58:33 volumio systemd[1]: Stopping VolumioStreamingService... Oct 26 15:58:33 volumio sudo[1843]: pam_unix(sudo:session): session closed for user root Oct 26 15:58:33 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Oct 26 15:58:33 volumio systemd[1]: volumioStreaming.service: Succeeded. Oct 26 15:58:33 volumio volumio[1216]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 15:58:33 volumio systemd[1]: Stopped VolumioStreamingService. Oct 26 15:58:33 volumio volumio[1216]: info: MRS: Removed streaming files Oct 26 15:58:33 volumio sudo[1840]: pam_unix(sudo:session): session closed for user root Oct 26 15:58:33 volumio volumio[1216]: info: MRS: volumioStreaming STOPPED Oct 26 15:58:34 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Oct 26 15:58:34 volumio volumio[1216]: info: MRS: Starting cast device: WiiM-Ultra-04e1c4acf9eea318f94a68a680a30073 Oct 26 15:58:34 volumio volumio[1216]: info: Enabled audio output: WiiM-Ultra-04e1c4acf9eea318f94a68a680a30073 Oct 26 15:58:34 volumio volumio[1216]: info: MRS: Setting this device as Streaming Server Oct 26 15:58:34 volumio volumio[1216]: info: Oct 26 15:58:34 volumio volumio[1216]: [1729954714616] ---------------------------- MRS: Setting Streaming Server Oct 26 15:58:34 volumio volumio[1216]: info: MRS: enable multiroom server output Oct 26 15:58:34 volumio volumio[1216]: info: MRS: Set multiroom target PCM to volumioMultiRoom Oct 26 15:58:34 volumio volumio[1216]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Oct 26 15:58:34 volumio volumio[1216]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Oct 26 15:58:34 volumio volumio[1216]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Oct 26 15:58:34 volumio volumio[1216]: info: MRS: STARTING volumioStreaming Oct 26 15:58:34 volumio sudo[1848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 26 15:58:34 volumio sudo[1848]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 15:58:34 volumio sudo[1848]: pam_unix(sudo:session): session closed for user root Oct 26 15:58:34 volumio sudo[1850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Oct 26 15:58:34 volumio sudo[1850]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 15:58:34 volumio volumio[1216]: info: MRS: Connected to cast device: WiiM-Ultra-04e1c4acf9eea318f94a68a680a30073 Oct 26 15:58:34 volumio volumio[1216]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 15:58:34 volumio volumio[1216]: TypeError: Cannot read property 'on' of undefined Oct 26 15:58:34 volumio volumio[1216]: at /myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:29162 Oct 26 15:58:34 volumio volumio[1216]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/platform.js:95:20 Oct 26 15:58:34 volumio volumio[1216]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/receiver.js:51:14 Oct 26 15:58:34 volumio volumio[1216]: at fn.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:27:7) Oct 26 15:58:34 volumio volumio[1216]: at fn.emit (events.js:327:22) Oct 26 15:58:34 volumio volumio[1216]: at Channel.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:16:10) Oct 26 15:58:34 volumio volumio[1216]: at Channel.emit (events.js:315:20) Oct 26 15:58:34 volumio volumio[1216]: at Client.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:23:10) Oct 26 15:58:34 volumio volumio[1216]: at Client.emit (events.js:327:22) Oct 26 15:58:34 volumio volumio[1216]: at PacketStreamWrapper.onpacket (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:81:10) Oct 26 15:58:34 volumio volumio[1216]: at PacketStreamWrapper.emit (events.js:315:20) Oct 26 15:58:34 volumio volumio[1216]: at TLSSocket. (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/packet-stream-wrapper.js:28:16) Oct 26 15:58:34 volumio volumio[1216]: at TLSSocket.emit (events.js:315:20) Oct 26 15:58:34 volumio volumio[1216]: at emitReadable_ (internal/streams/readable.js:569:12) Oct 26 15:58:34 volumio volumio[1216]: at processTicksAndRejections (internal/process/task_queues.js:79:21) Oct 26 15:58:34 volumio volumio[1216]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 15:58:34 volumio systemd[1]: Started VolumioStreamingService. Oct 26 15:58:34 volumio sudo[1850]: pam_unix(sudo:session): session closed for user root Oct 26 15:58:34 volumio sudo[1861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 26 15:58:34 volumio sudo[1861]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 15:58:34 volumio sudo[1861]: pam_unix(sudo:session): session closed for user root Oct 26 15:58:34 volumio sudo[1866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-26 15:57 Oct 26 15:58:34 volumio sudo[1866]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"