-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Fri 2025-05-23 23:11:45 EDT. -- May 23 23:10:00 audio-1 volumiologrotate[524]: ls: cannot access '/var/log/samba/log.wb-AUDIO': No such file or directory May 23 23:10:00 audio-1 volumiologrotate[524]: ls: cannot access '1': No such file or directory May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.984 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Received SetActive message: inactive May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.984 [1534.1534] INFO PlaybackSessionManager: [0x2224530]: Stopping playback session maintenance May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.984 [1534.1534] INFO ActiveStateManager: [0x2223158]: Setting new active state: inactive May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.984 [1534.1534] INFO MediaEngine: [0x22247c8]: Stopping playback, clearing tracks May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.984 [1534.1534] INFO MediaEngine: [0x22247c8]: Reseting May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.984 [1534.1534] INFO SampleApp: Renderer is now inactive May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.984 [1534.1534] INFO AudioRenderer: [0x22249e8]: Stopping audio renderer May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.984 [1534.1534] INFO SampleApp: Stopping playback May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.984 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Clearing all streams May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.984 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 21]: Disposing of audio stream May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.984 [1534.1534] INFO ContentFetcher: [0x23f4af0]: Canceling download May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.990 [1534.1534] INFO SampleApp: Playback state changed: Stopped May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.990 [1534.1534] INFO StreamingEventReporter: [0x2225320]: Reporting streaming ended event May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.991 [1534.1534] INFO HttpDownloader: [0x2225378]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.991 [1534.1534] INFO SampleApp: [Stream 21]: Audio stream has been disposed of May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.991 [1534.1534] INFO MediaEngine: [0x22247c8]: Setting current track: NONE May 23 23:10:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:03 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:03 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:03 audio-1 qobuz-connect[1534]: 20250523 23:10:03.992 [1534.1534] INFO MediaEngine: [0x22247c8]: Setting next track: NONE May 23 23:10:03 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:16 audio-1 volumio[837]: info: Airplay started streaming, receiving metadatas May 23 23:10:16 audio-1 volumio[837]: verbose: UNSET VOLATILE: Service: qobuzconnect May 23 23:10:16 audio-1 volumio[837]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service May 23 23:10:16 audio-1 volumio[837]: info: QobuzConnect: Received stop May 23 23:10:16 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:16 audio-1 qobuz-connect[1534]: 20250523 23:10:16.972 [1534.1534] INFO MediaEngine: [0x22247c8]: Stopping playback, keeping tracks May 23 23:10:16 audio-1 volumio[837]: info: Airplay started streaming, receiving metadatas May 23 23:10:16 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:16 audio-1 volumio[837]: info: CoreCommandRouter::volumioStop May 23 23:10:16 audio-1 volumio[837]: info: CoreStateMachine::stop May 23 23:10:16 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:10:16 audio-1 volumio[837]: info: CoreStateMachine::updateTrackBlock May 23 23:10:16 audio-1 volumio[837]: info: CorePlayQueue::getTrackBlock May 23 23:10:16 audio-1 volumio[837]: info: CoreStateMachine::stPlaybackTimer May 23 23:10:16 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:16 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:16 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:16 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:16 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:16 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:16 audio-1 volumio[837]: info: CoreStateMachine::serviceStop May 23 23:10:16 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:16 audio-1 volumio[837]: info: CoreCommandRouter::serviceStop May 23 23:10:16 audio-1 volumio[837]: info: [1748056216991] ControllerWebradio::stop May 23 23:10:16 audio-1 volumio[837]: verbose: ControllerMpd::sendMpdCommand stop May 23 23:10:16 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:10:16 audio-1 volumio[837]: info: Airplay started streaming, receiving metadatas May 23 23:10:16 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:16 audio-1 volumio[837]: info: CoreCommandRouter::volumioStop May 23 23:10:16 audio-1 volumio[837]: info: CoreStateMachine::stop May 23 23:10:16 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:10:16 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:10:16 audio-1 volumio[837]: info: Airplay started streaming, receiving metadatas May 23 23:10:16 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:16 audio-1 volumio[837]: info: CoreCommandRouter::volumioStop May 23 23:10:16 audio-1 volumio[837]: info: CoreStateMachine::stop May 23 23:10:16 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:10:16 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:10:17 audio-1 volumio[837]: info: sendMpdCommand stop took 20 milliseconds May 23 23:10:17 audio-1 shairport-sync[1323]: % Total % Received % Xferd Average Speed Time Time Time Current May 23 23:10:17 audio-1 shairport-sync[1323]: Dload Upload Total Spent Left Speed May 23 23:10:17 audio-1 volumio[837]: info: May 23 23:10:17 audio-1 volumio[837]: ---------------------------- Client requests Start Airplay PlaybackRoutine May 23 23:10:17 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback May 23 23:10:17 audio-1 volumio[837]: info: Airplay playback start May 23 23:10:17 audio-1 kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! May 23 23:10:17 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:17 audio-1 volumio[837]: info: CoreCommandRouter::volumioStop May 23 23:10:17 audio-1 volumio[837]: info: CoreStateMachine::stop May 23 23:10:17 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:10:17 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:10:17 audio-1 shairport-sync[1323]: [158B blob data] May 23 23:10:17 audio-1 volumio[837]: info: Updating volume from AirPlay: -20; 33% May 23 23:10:17 audio-1 volumio[837]: info: VolumeController::SetAlsaVolume33 May 23 23:10:17 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:17 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:17 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:17 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:17 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:17 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:17 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:17 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:17 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:17 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:17 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:17 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:17 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:18 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:18 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:18 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:18 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:18 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:18 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:18 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:18 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:18 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:18 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:18 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:18 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:24 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:24 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:24 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:24 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:25 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:25 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:25 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:25 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:25 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:25 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:25 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:25 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:32 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:32 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:32 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:32 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:32 audio-1 shairport-sync[1323]: {"time":1748056217023,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 23 23:10:32 audio-1 shairport-sync[1323]: Dload Upload Total Spent Left Speed May 23 23:10:32 audio-1 volumio[837]: info: May 23 23:10:32 audio-1 volumio[837]: ---------------------------- Client requests Stop Airplay Playback May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback May 23 23:10:32 audio-1 volumio[837]: info: Airplay playback stop May 23 23:10:32 audio-1 volumio[837]: info: Airplay Playback Stopped, clearing state May 23 23:10:32 audio-1 volumio[837]: info: CoreStateMachine::resetVolumioState May 23 23:10:32 audio-1 volumio[837]: info: CoreStateMachine::getcurrentVolume May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::volumioRetrievevolume May 23 23:10:32 audio-1 shairport-sync[1323]: [158B blob data] May 23 23:10:32 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:32 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:36 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 23:10:36 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 23:10:36 audio-1 volumio[837]: info: Discovery: Getting this device information May 23 23:10:36 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:36 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 23:10:36 audio-1 volumio[837]: verbose: New Socket.io Connection to 192.168.1.21:3000 from 192.168.1.148 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 23 23:10:36 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:36 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 23 23:10:36 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.525 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Received SetActive message: active May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.525 [1534.1534] INFO ActiveStateManager: [0x2223158]: Setting new active state: active May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.525 [1534.1534] INFO PlaybackSessionManager: [0x2224530]: Starting playback session maintenance May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.525 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending VolumeChanged message May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.525 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending VolumeMuted message May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.525 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending MaxAudioQualityChanged message May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.525 [1534.1534] INFO SampleApp: Renderer is now active May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.525 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.548 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Received SetState message: May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.548 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Playing state: Paused May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.548 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Playback position: 26539 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.548 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Queue version: 18.2 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.548 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Current track: TID: 313558361, QID: 9, Context UUID: 73aaa72d-355a-47d4-86b1-1114c1438eda May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.548 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Next track: TID: 313558362, QID: 10, Context UUID: 73aaa72d-355a-47d4-86b1-1114c1438eda May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.548 [1534.1534] INFO MediaEngine: [0x22247c8]: Stopping playback, clearing tracks May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.549 [1534.1534] INFO MediaEngine: [0x22247c8]: Initiating playback May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.549 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Received SetLoopMode message: Off May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.549 [1534.1534] INFO PlaybackControlsManager: [0x2224ba0]: Setting new loop mode: Off May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.549 [1534.1534] INFO MediaEngine: [0x22247c8]: Setting current track: 313558361, initial offset: 26539ms May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.549 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Clearing all streams May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.549 [1534.1534] INFO AudioStreamManager: [0x22248b0]: New stream: 23 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.549 [1534.1534] INFO HttpDownloader: [0x2427a88]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=2e32fe8d76bb877a409dbbef3fab71a3&request_ts=1748056240&track_id=313558361 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.549 [1534.1534] INFO HttpDownloader: [0x2420ea8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=313558361 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.549 [1534.1534] INFO HttpDownloader: [0x22ffcc0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=92fac1bf1d6c56d9caffbfe5c429df7f&request_ts=1748056240&track_id=313558361 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.549 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 23]: Running audio stream May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.549 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Received SetShuffleMode message: disabled May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.549 [1534.1534] INFO PlaybackControlsManager: [0x2224ba0]: Setting new shuffle mode: disabled May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.550 [1534.1534] INFO MediaEngine: [0x22247c8]: Setting next track: 313558362 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.550 [1534.1534] INFO AudioStreamManager: [0x22248b0]: New stream: 24 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.550 [1534.1534] INFO HttpDownloader: [0x23a1b18]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=afd1c9e6b66751b35e35b80a31f8c439&request_ts=1748056240&track_id=313558362 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.550 [1534.1534] INFO HttpDownloader: [0x2304d18]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=313558362 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.550 [1534.1534] INFO HttpDownloader: [0x239d0f0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=23434271c76f841b87f15564f5faaa94&request_ts=1748056240&track_id=313558362 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.551 [1534.1534] INFO MediaEngine: [0x22247c8]: Waiting for current stream to start before starting audio renderer May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.800 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 23]: Metadata became available: May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.800 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Title: Dead To Me May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.800 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Artist: Morcheeba May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.800 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Album: Escape The Chaos May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.800 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.801 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 24]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=313558362&fmt=7&profile=raw&app_id=174516466&cid=1496927&etsp=1748059840&hmac=l4d7I84ntiKgUxRRB-g1aVHhLM8 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.802 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 24]: Metadata became available: May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.802 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Title: Pareidolia May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.802 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Artist: Morcheeba May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.802 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Album: Escape The Chaos May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.802 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.833 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 23]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=313558361&fmt=7&profile=raw&app_id=174516466&cid=1496927&etsp=1748059840&hmac=mlWkUuiY7bxWCwuNnTrTbxi8IwU May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.845 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 23]: stream information have been fetched May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.845 [1534.1534] INFO UrlAudioSource: [0x238d530]: Starting URL audio source, initial position: 26539ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=313558361&fmt=7&profile=raw&app_id=174516466&cid=1496927&etsp=1748059840&hmac=mlWkUuiY7bxWCwuNnTrTbxi8IwU May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.845 [1534.1534] INFO ContentFetcher: [0x2420b50]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=313558361&fmt=7&profile=raw&app_id=174516466&cid=1496927&etsp=1748059840&hmac=mlWkUuiY7bxWCwuNnTrTbxi8IwU, offset: 0 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.845 [1534.1534] INFO AudioRenderer: [0x22249e8]: Starting audio renderer, initial playback state: Paused May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.845 [1534.1534] INFO SampleApp: [Stream 23]: New audio stream (starting from 26539ms) May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.846 [1534.1534] INFO SampleApp: [Stream 23]: Stream metadata became available: May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.846 [1534.1534] INFO SampleApp: Title: Dead To Me May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.846 [1534.1534] INFO SampleApp: Artist: Morcheeba May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.846 [1534.1534] INFO SampleApp: Album: Escape The Chaos May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.846 [1534.1534] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 23 23:10:40 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:40 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:40 audio-1 volumio[837]: info: QobuzConnect: setActiveState() May 23 23:10:40 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:40 audio-1 volumio[837]: info: QobuzConnect: Set Qobuz connect default volume to 100 May 23 23:10:40 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.849 [1534.1534] INFO VolumeManager: [0x2224c30]: Setting new playback volume: 100 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.849 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 24]: stream information have been fetched May 23 23:10:40 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:40 audio-1 volumio[837]: info: QobuzConnect: Stopping currently active service May 23 23:10:40 audio-1 volumio[837]: info: CoreCommandRouter::volumioStop May 23 23:10:40 audio-1 volumio[837]: info: CoreStateMachine::stop May 23 23:10:40 audio-1 volumio[837]: info: CoreStateMachine::serviceStop May 23 23:10:40 audio-1 volumio[837]: info: CoreCommandRouter::serviceStop May 23 23:10:40 audio-1 volumio[837]: info: Airplay Stop May 23 23:10:40 audio-1 volumio[837]: info: Stopping Airplay Playback and sending pause command to client via USR2 May 23 23:10:40 audio-1 sudo[2276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1323 May 23 23:10:40 audio-1 sudo[2276]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 23:10:40 audio-1 sudo[2276]: pam_unix(sudo:session): session closed for user root May 23 23:10:40 audio-1 volumio[837]: info: Shairport-Sync paused with USR2 May 23 23:10:40 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:10:40 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:40 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:40 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:40 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:40 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:40 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:40 audio-1 volumio[837]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received qobuzconnect May 23 23:10:40 audio-1 volumio[837]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect May 23 23:10:40 audio-1 volumio[837]: info: QobuzConnect: setActiveState() completed, send playback confirmation May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.942 [1534.1534] INFO SampleApp: Initiating playback, initial state: Paused May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.948 [1534.1534] ERROR SampleApp: Unable to pause ALSA audio sink: Function not implemented (-38) May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.948 [1534.1534] ERROR AudioRenderer: [0x22249e8]: Playback error occurred May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.948 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending PlaybackError message May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.948 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.949 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Need to send state update May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.949 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending StateUpdated message May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.949 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:10:40 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:40 audio-1 kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Received SetState message: May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Playing state: Playing May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Playback position: 0 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Queue version: 18.2 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Current track: TID: 313558362, QID: 10, Context UUID: 73aaa72d-355a-47d4-86b1-1114c1438eda May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Next track: TID: 313558363, QID: 11, Context UUID: 73aaa72d-355a-47d4-86b1-1114c1438eda May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO MediaEngine: [0x22247c8]: Reseting May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO MediaEngine: [0x22247c8]: Stopping playback, clearing tracks May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO MediaEngine: [0x22247c8]: Initiating playback May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO AudioRenderer: [0x22249e8]: Reseting audio renderer May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Need to send state update May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending StateUpdated message May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO MediaEngine: [0x22247c8]: Setting current track: 313558362, initial offset: 0ms May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Clearing all streams May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 23]: Disposing of audio stream May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.973 [1534.1534] INFO ContentFetcher: [0x2420b50]: Canceling download May 23 23:10:40 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.979 [1534.1534] INFO AudioStreamManager: [0x22248b0]: New stream: 25 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.979 [1534.1534] INFO HttpDownloader: [0x23a1b18]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=afd1c9e6b66751b35e35b80a31f8c439&request_ts=1748056240&track_id=313558362 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.980 [1534.1534] INFO HttpDownloader: [0x2303dc0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=313558362 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.980 [1534.1534] INFO HttpDownloader: [0x2307618]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=23434271c76f841b87f15564f5faaa94&request_ts=1748056240&track_id=313558362 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.980 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 25]: Running audio stream May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.981 [1534.1534] INFO SampleApp: [Stream 23]: Audio stream has been disposed of May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.981 [1534.1534] INFO MediaEngine: [0x22247c8]: Setting next track: 313558363 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.981 [1534.1534] INFO AudioStreamManager: [0x22248b0]: New stream: 26 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.981 [1534.1534] INFO HttpDownloader: [0x23a0608]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=18a566343f015483498bac3d6aafbcf4&request_ts=1748056240&track_id=313558363 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.982 [1534.1534] INFO HttpDownloader: [0x23d3b98]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=313558363 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.982 [1534.1534] INFO HttpDownloader: [0x2493230]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=2956f7c06c5b4b19ab97cd9d4663e278&request_ts=1748056240&track_id=313558363 May 23 23:10:40 audio-1 qobuz-connect[1534]: 20250523 23:10:40.983 [1534.1534] INFO MediaEngine: [0x22247c8]: Waiting for current stream to start before starting audio renderer May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.129 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 25]: Metadata became available: May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.129 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Title: Pareidolia May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.129 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Artist: Morcheeba May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.129 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Album: Escape The Chaos May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.129 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.155 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 25]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=313558362&fmt=7&profile=raw&app_id=174516466&cid=1496927&etsp=1748059841&hmac=H9pVG3zTABkGTP9YTUgHnnBVqe4 May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.156 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 26]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=313558363&fmt=7&profile=raw&app_id=174516466&cid=1496927&etsp=1748059841&hmac=puedO5ykOe4FIICJu8L7MA4q0bk May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.196 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 26]: Metadata became available: May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.196 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Title: Escape the Chaos May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.196 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Artist: Morcheeba May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.196 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Album: Escape The Chaos May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.196 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.197 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 26]: stream information have been fetched May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.264 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 25]: stream information have been fetched May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.264 [1534.1534] INFO UrlAudioSource: [0x2309e48]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=313558362&fmt=7&profile=raw&app_id=174516466&cid=1496927&etsp=1748059841&hmac=H9pVG3zTABkGTP9YTUgHnnBVqe4 May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.265 [1534.1534] INFO ContentFetcher: [0x246a4d0]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=313558362&fmt=7&profile=raw&app_id=174516466&cid=1496927&etsp=1748059841&hmac=H9pVG3zTABkGTP9YTUgHnnBVqe4, offset: 0 May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.265 [1534.1534] INFO AudioRenderer: [0x22249e8]: Starting audio renderer, initial playback state: Playing May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.265 [1534.1534] INFO SampleApp: [Stream 25]: New audio stream (starting from 0ms) May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.265 [1534.1534] INFO SampleApp: [Stream 25]: Stream metadata became available: May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.265 [1534.1534] INFO SampleApp: Title: Pareidolia May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.265 [1534.1534] INFO SampleApp: Artist: Morcheeba May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.265 [1534.1534] INFO SampleApp: Album: Escape The Chaos May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.265 [1534.1534] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:41 audio-1 volumio[837]: info: QobuzConnect: setActiveState() May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:41 audio-1 volumio[837]: info: QobuzConnect: Set Qobuz connect default volume to 100 May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.268 [1534.1534] INFO VolumeManager: [0x2224c30]: Setting new playback volume: 100 May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:41 audio-1 volumio[837]: info: QobuzConnect: Stopping currently active service May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioStop May 23 23:10:41 audio-1 volumio[837]: info: CoreStateMachine::stop May 23 23:10:41 audio-1 volumio[837]: info: CoreStateMachine::serviceStop May 23 23:10:41 audio-1 volumio[837]: info: Received STOP, but no service to execute it May 23 23:10:41 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:41 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:41 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:41 audio-1 volumio[837]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received qobuzconnect May 23 23:10:41 audio-1 volumio[837]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect May 23 23:10:41 audio-1 volumio[837]: info: QobuzConnect: setActiveState() completed, send playback confirmation May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.336 [1534.1534] INFO SampleApp: Initiating playback, initial state: Playing May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.355 [1534.1534] INFO SampleApp: Playback state changed: Playing May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.356 [1534.1534] INFO StreamingEventReporter: [0x2225320]: Reporting streaming started event May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.356 [1534.1534] INFO HttpDownloader: [0x2225378]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.356 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Need to send state update May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.356 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending StateUpdated message May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.356 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.357 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending FileAudioQualityChanged message May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.357 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.357 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending DeviceAudioQualityChanged message May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.358 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:41 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:41 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:41 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:41 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.408 [1534.1534] INFO UrlAudioSource: [0x2309e48]: Content info extracted May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.408 [1534.1534] INFO UrlAudioSource: [0x2309e48]: Size: 80542094 May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.408 [1534.2278] INFO AudioDecoder: [0x24143a8]: Stream info: May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.408 [1534.2278] INFO AudioDecoder: [0x24143a8]: Total samples: 21526155 May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.408 [1534.2278] INFO AudioDecoder: [0x24143a8]: Sample rate: 96000 May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.408 [1534.2278] INFO AudioDecoder: [0x24143a8]: Channel count: 2 May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.408 [1534.2278] INFO AudioDecoder: [0x24143a8]: Bits per sample: 24 May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.408 [1534.2278] INFO AudioDecoder: [0x24143a8]: Duration: 224230 May 23 23:10:41 audio-1 qobuz-connect[1534]: 20250523 23:10:41.408 [1534.1534] INFO UrlAudioSource: [0x2309e48]: Audio properties have changed May 23 23:10:41 audio-1 volumio[837]: info: Restarting Shairport-Sync after stop May 23 23:10:41 audio-1 sudo[2294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 23:10:41 audio-1 sudo[2294]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 23:10:41 audio-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 23 23:10:41 audio-1 shairport-sync[1323]: {"time":1748056232248,"response":"stopAirplayPlayback Success"} May 23 23:10:41 audio-1 systemd[1]: shairport-sync.service: Succeeded. May 23 23:10:41 audio-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 23 23:10:42 audio-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 23 23:10:42 audio-1 sudo[2294]: pam_unix(sudo:session): session closed for user root May 23 23:10:42 audio-1 volumio[837]: info: Shairport-Sync retarted May 23 23:10:49 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:49 audio-1 volumio[837]: info: Airplay started streaming, receiving metadatas May 23 23:10:49 audio-1 volumio[837]: verbose: UNSET VOLATILE: Service: qobuzconnect May 23 23:10:49 audio-1 volumio[837]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service May 23 23:10:49 audio-1 volumio[837]: info: QobuzConnect: Received stop May 23 23:10:49 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:49 audio-1 volumio[837]: info: Airplay started streaming, receiving metadatas May 23 23:10:49 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:49 audio-1 volumio[837]: info: CoreCommandRouter::volumioStop May 23 23:10:49 audio-1 volumio[837]: info: CoreStateMachine::stop May 23 23:10:49 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:10:49 audio-1 volumio[837]: info: CoreStateMachine::stPlaybackTimer May 23 23:10:49 audio-1 volumio[837]: info: CoreStateMachine::updateTrackBlock May 23 23:10:49 audio-1 volumio[837]: info: CorePlayQueue::getTrackBlock May 23 23:10:49 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:49 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:49 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:49 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.295 [1534.1534] INFO MediaEngine: [0x22247c8]: Stopping playback, keeping tracks May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.295 [1534.1534] INFO AudioRenderer: [0x22249e8]: Stopping audio renderer May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.295 [1534.1534] INFO SampleApp: Stopping playback May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.295 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Clearing all streams May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.295 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 25]: Disposing of audio stream May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.296 [1534.1534] INFO ContentFetcher: [0x246a4d0]: Canceling download May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.304 [1534.1534] INFO AudioStreamManager: [0x22248b0]: New stream: 27 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.304 [1534.1534] INFO HttpDownloader: [0x23a0608]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=f8df09bec24ba442f21adf349e44fe7c&request_ts=1748056249&track_id=313558362 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.305 [1534.1534] INFO HttpDownloader: [0x23eede0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=313558362 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.305 [1534.1534] INFO HttpDownloader: [0x2443f48]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=0923c8f9d46ae3cb581e487674b3d217&request_ts=1748056249&track_id=313558362 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.305 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 27]: Running audio stream May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.305 [1534.1534] INFO AudioStreamManager: [0x22248b0]: New stream: 28 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.305 [1534.1534] INFO HttpDownloader: [0x22ffcc0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=aefb16ec4b060bbf9d68a3311b8ae201&request_ts=1748056249&track_id=313558363 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.305 [1534.1534] INFO HttpDownloader: [0x2307618]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=313558363 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.305 [1534.1534] INFO HttpDownloader: [0x2435a20]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=e6ee6969ac3ba6af77e243811be24cb5&request_ts=1748056249&track_id=313558363 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.309 [1534.1534] INFO SampleApp: Playback state changed: Stopped May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.309 [1534.1534] INFO StreamingEventReporter: [0x2225320]: Reporting streaming ended event May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.310 [1534.1534] INFO HttpDownloader: [0x2225378]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.310 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Need to send state update May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.310 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending StateUpdated message May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.310 [1534.1534] INFO SampleApp: [Stream 25]: Audio stream has been disposed of May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.310 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:10:49 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:49 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:49 audio-1 volumio[837]: info: CoreStateMachine::serviceStop May 23 23:10:49 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:49 audio-1 volumio[837]: info: CoreCommandRouter::serviceStop May 23 23:10:49 audio-1 volumio[837]: info: [1748056249314] ControllerWebradio::stop May 23 23:10:49 audio-1 volumio[837]: verbose: ControllerMpd::sendMpdCommand stop May 23 23:10:49 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:10:49 audio-1 volumio[837]: info: Airplay started streaming, receiving metadatas May 23 23:10:49 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:49 audio-1 volumio[837]: info: CoreCommandRouter::volumioStop May 23 23:10:49 audio-1 volumio[837]: info: CoreStateMachine::stop May 23 23:10:49 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:10:49 audio-1 volumio[837]: info: Airplay started streaming, receiving metadatas May 23 23:10:49 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:49 audio-1 volumio[837]: info: CoreCommandRouter::volumioStop May 23 23:10:49 audio-1 volumio[837]: info: CoreStateMachine::stop May 23 23:10:49 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:10:49 audio-1 volumio[837]: info: QobuzConnect: playback_stop received, deferred solved May 23 23:10:49 audio-1 volumio[837]: info: QobuzConnect: playback_stop received, deferred solved May 23 23:10:49 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:49 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:49 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:49 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:49 audio-1 volumio[837]: info: sendMpdCommand stop took 25 milliseconds May 23 23:10:49 audio-1 shairport-sync[2296]: % Total % Received % Xferd Average Speed Time Time Time Current May 23 23:10:49 audio-1 shairport-sync[2296]: Dload Upload Total Spent Left Speed May 23 23:10:49 audio-1 volumio[837]: info: May 23 23:10:49 audio-1 volumio[837]: ---------------------------- Client requests Start Airplay PlaybackRoutine May 23 23:10:49 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback May 23 23:10:49 audio-1 volumio[837]: info: Airplay playback start May 23 23:10:49 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:49 audio-1 volumio[837]: info: CoreCommandRouter::volumioStop May 23 23:10:49 audio-1 volumio[837]: info: CoreStateMachine::stop May 23 23:10:49 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:10:49 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:10:49 audio-1 shairport-sync[2296]: [158B blob data] May 23 23:10:49 audio-1 volumio[837]: info: Updating volume from AirPlay: -18; 40% May 23 23:10:49 audio-1 volumio[837]: info: VolumeController::SetAlsaVolume40 May 23 23:10:49 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:49 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:49 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:49 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:49 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.457 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 28]: Metadata became available: May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.457 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Title: Escape the Chaos May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.457 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Artist: Morcheeba May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.457 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Album: Escape The Chaos May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.457 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.458 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 27]: Metadata became available: May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.458 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Title: Pareidolia May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.458 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Artist: Morcheeba May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.458 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Album: Escape The Chaos May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.458 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.458 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 27]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=313558362&fmt=7&profile=raw&app_id=174516466&cid=1496927&etsp=1748059849&hmac=gdVosAH0fd6giQ7LEZtgaTsihsQ May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.478 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 28]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=313558363&fmt=7&profile=raw&app_id=174516466&cid=1496927&etsp=1748059849&hmac=Ax-UVmPRqE4KvyrHJIkqx1XwUNY May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.521 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 28]: stream information have been fetched May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.542 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 27]: stream information have been fetched May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.542 [1534.1534] INFO UrlAudioSource: [0x2484bf8]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=313558362&fmt=7&profile=raw&app_id=174516466&cid=1496927&etsp=1748059849&hmac=gdVosAH0fd6giQ7LEZtgaTsihsQ May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.543 [1534.1534] INFO ContentFetcher: [0x2415c18]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=313558362&fmt=7&profile=raw&app_id=174516466&cid=1496927&etsp=1748059849&hmac=gdVosAH0fd6giQ7LEZtgaTsihsQ, offset: 0 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.543 [1534.1534] INFO SampleApp: [Stream 27]: New audio stream (starting from 0ms) May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.543 [1534.1534] INFO SampleApp: [Stream 27]: Stream metadata became available: May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.543 [1534.1534] INFO SampleApp: Title: Pareidolia May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.543 [1534.1534] INFO SampleApp: Artist: Morcheeba May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.543 [1534.1534] INFO SampleApp: Album: Escape The Chaos May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.543 [1534.1534] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 23 23:10:49 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:49 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.721 [1534.1534] INFO UrlAudioSource: [0x2484bf8]: Content info extracted May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.721 [1534.1534] INFO UrlAudioSource: [0x2484bf8]: Size: 80542094 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.722 [1534.2313] INFO AudioDecoder: [0x248f7c8]: Stream info: May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.722 [1534.2313] INFO AudioDecoder: [0x248f7c8]: Total samples: 21526155 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.722 [1534.2313] INFO AudioDecoder: [0x248f7c8]: Sample rate: 96000 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.722 [1534.2313] INFO AudioDecoder: [0x248f7c8]: Channel count: 2 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.722 [1534.2313] INFO AudioDecoder: [0x248f7c8]: Bits per sample: 24 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.722 [1534.2313] INFO AudioDecoder: [0x248f7c8]: Duration: 224230 May 23 23:10:49 audio-1 qobuz-connect[1534]: 20250523 23:10:49.723 [1534.1534] INFO UrlAudioSource: [0x2484bf8]: Audio properties have changed May 23 23:10:49 audio-1 sudo[2315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 23 23:10:49 audio-1 sudo[2315]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 23:10:49 audio-1 sudo[2315]: pam_unix(sudo:session): session closed for user root May 23 23:10:49 audio-1 sudo[2317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 23 23:10:49 audio-1 sudo[2317]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 23:10:49 audio-1 sudo[2317]: pam_unix(sudo:session): session closed for user root May 23 23:10:49 audio-1 volumio[837]: verbose: New Socket.io Connection to 192.168.1.21 from 192.168.1.148 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 23 23:10:50 audio-1 sudo[2321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 23 23:10:50 audio-1 sudo[2321]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 23:10:50 audio-1 sudo[2321]: pam_unix(sudo:session): session closed for user root May 23 23:10:50 audio-1 sudo[2324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 23 23:10:50 audio-1 sudo[2324]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 23:10:50 audio-1 sudo[2324]: pam_unix(sudo:session): session closed for user root May 23 23:10:50 audio-1 volumio[837]: verbose: New Socket.io Connection to 192.168.1.21 from 192.168.1.148 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:50 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:50 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:50 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:50 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:50 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetVisibleSources May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 23 23:10:50 audio-1 volumio[837]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 23 23:10:50 audio-1 volumio[837]: info: Received Get System Info May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 23:10:50 audio-1 volumio[837]: info: Discovery: Getting this device information May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:50 audio-1 volumio[837]: info: Listing playlists May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 23 23:10:50 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:51 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:51 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:51 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:51 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:51 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:51 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:51 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:51 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:51 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:51 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:51 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:51 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:51 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:51 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:51 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:51 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:51 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:52 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:52 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:52 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:52 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:52 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:52 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:52 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:52 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 23:10:52 audio-1 volumio[837]: info: Received Get System Info May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 23:10:52 audio-1 volumio[837]: info: Discovery: Getting this device information May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 23:10:52 audio-1 volumio[837]: info: Received Get System Info May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 23:10:52 audio-1 volumio[837]: info: Discovery: Getting this device information May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:52 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:53 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:53 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:53 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:53 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:53 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:53 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:53 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:10:53 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:10:53 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:00 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 23 23:11:00 audio-1 volumiologrotate[524]: ls: cannot access '/var/log/samba/log.wb-AUDIO': No such file or directory May 23 23:11:00 audio-1 volumiologrotate[524]: ls: cannot access '1': No such file or directory May 23 23:11:03 audio-1 qobuz-connect[1534]: 20250523 23:11:03.599 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Received SetState message: May 23 23:11:03 audio-1 qobuz-connect[1534]: 20250523 23:11:03.599 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Playing state: Playing May 23 23:11:03 audio-1 qobuz-connect[1534]: 20250523 23:11:03.599 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Playback position: May 23 23:11:03 audio-1 qobuz-connect[1534]: 20250523 23:11:03.599 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Queue version: May 23 23:11:03 audio-1 qobuz-connect[1534]: 20250523 23:11:03.599 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Current track: May 23 23:11:03 audio-1 qobuz-connect[1534]: 20250523 23:11:03.599 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Next track: May 23 23:11:03 audio-1 qobuz-connect[1534]: 20250523 23:11:03.599 [1534.1534] INFO MediaEngine: [0x22247c8]: Initiating playback May 23 23:11:03 audio-1 qobuz-connect[1534]: 20250523 23:11:03.599 [1534.1534] INFO AudioRenderer: [0x22249e8]: Starting audio renderer, initial playback state: Playing May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 volumio[837]: info: QobuzConnect: setActiveState() May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 volumio[837]: info: QobuzConnect: Set Qobuz connect default volume to 100 May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 qobuz-connect[1534]: 20250523 23:11:03.602 [1534.1534] INFO VolumeManager: [0x2224c30]: Setting new playback volume: 100 May 23 23:11:03 audio-1 volumio[837]: info: QobuzConnect: Stopping currently active service May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioStop May 23 23:11:03 audio-1 volumio[837]: info: CoreStateMachine::stop May 23 23:11:03 audio-1 volumio[837]: info: CoreStateMachine::serviceStop May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::serviceStop May 23 23:11:03 audio-1 volumio[837]: info: Airplay Stop May 23 23:11:03 audio-1 volumio[837]: info: Stopping Airplay Playback and sending pause command to client via USR2 May 23 23:11:03 audio-1 sudo[2397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 2296 May 23 23:11:03 audio-1 sudo[2397]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 23:11:03 audio-1 sudo[2397]: pam_unix(sudo:session): session closed for user root May 23 23:11:03 audio-1 volumio[837]: info: Shairport-Sync paused with USR2 May 23 23:11:03 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:03 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:11:03 audio-1 volumio[837]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received qobuzconnect May 23 23:11:03 audio-1 volumio[837]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect May 23 23:11:03 audio-1 volumio[837]: info: QobuzConnect: setActiveState() completed, send playback confirmation May 23 23:11:03 audio-1 qobuz-connect[1534]: 20250523 23:11:03.686 [1534.1534] INFO SampleApp: Initiating playback, initial state: Playing May 23 23:11:03 audio-1 qobuz-connect[1534]: 20250523 23:11:03.692 [1534.1534] INFO SampleApp: Playback state changed: Playing May 23 23:11:03 audio-1 qobuz-connect[1534]: 20250523 23:11:03.692 [1534.1534] INFO StreamingEventReporter: [0x2225320]: Reporting streaming started event May 23 23:11:03 audio-1 qobuz-connect[1534]: 20250523 23:11:03.692 [1534.1534] INFO HttpDownloader: [0x2225378]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 23 23:11:03 audio-1 qobuz-connect[1534]: 20250523 23:11:03.692 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Need to send state update May 23 23:11:03 audio-1 qobuz-connect[1534]: 20250523 23:11:03.692 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending StateUpdated message May 23 23:11:03 audio-1 qobuz-connect[1534]: 20250523 23:11:03.694 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:03 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:03 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:03 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:03 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:03 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:03 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:03 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:03 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:03 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:03 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:03 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:03 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 23:11:04 audio-1 volumio[837]: info: Discovery: Getting this device information May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:04 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:04 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:04 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:04 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:04 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:04 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:04 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:04 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:04 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:04 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:04 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:04 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:04 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:04 audio-1 volumio[837]: info: Restarting Shairport-Sync after stop May 23 23:11:04 audio-1 sudo[2400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 23:11:04 audio-1 sudo[2400]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 23:11:04 audio-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 23 23:11:04 audio-1 shairport-sync[2296]: {"time":1748056249348,"response":"startAirplayPlayback Success"} May 23 23:11:04 audio-1 systemd[1]: shairport-sync.service: Succeeded. May 23 23:11:04 audio-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 23 23:11:04 audio-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 23 23:11:04 audio-1 sudo[2400]: pam_unix(sudo:session): session closed for user root May 23 23:11:04 audio-1 volumio[837]: info: Shairport-Sync retarted May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.106 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Received SetState message: May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.106 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Playing state: Paused May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.106 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Playback position: May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.106 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Queue version: May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.106 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Current track: May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.106 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Next track: May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.106 [1534.1534] INFO MediaEngine: [0x22247c8]: Pausing playback May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.106 [1534.1534] INFO AudioRenderer: [0x22249e8]: Pausing audio renderer May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.106 [1534.1534] INFO SampleApp: Pausing playback May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.106 [1534.1534] ERROR SampleApp: Unable to pause ALSA audio sink: Function not implemented (-38) May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.106 [1534.1534] ERROR AudioRenderer: [0x22249e8]: Playback error occurred May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.106 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending PlaybackError message May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.106 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.106 [1534.1534] INFO SampleApp: Playback state changed: Stopped May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.106 [1534.1534] INFO StreamingEventReporter: [0x2225320]: Reporting streaming ended event May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.107 [1534.1534] INFO HttpDownloader: [0x2225378]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.107 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Need to send state update May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.107 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending StateUpdated message May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.107 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.129 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Received SetState message: May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.129 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Playing state: Playing May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.129 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Playback position: 0 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.129 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Queue version: 18.2 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.129 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Current track: TID: 313558363, QID: 11, Context UUID: 73aaa72d-355a-47d4-86b1-1114c1438eda May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.129 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Next track: TID: 3775798, QID: 12, Context UUID: 4cba0399-bd03-469d-9bfc-cade433abcb2 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.129 [1534.1534] INFO MediaEngine: [0x22247c8]: Reseting May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.129 [1534.1534] INFO MediaEngine: [0x22247c8]: Stopping playback, clearing tracks May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.129 [1534.1534] INFO MediaEngine: [0x22247c8]: Initiating playback May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.129 [1534.1534] INFO AudioRenderer: [0x22249e8]: Reseting audio renderer May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.130 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Need to send state update May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.130 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending StateUpdated message May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.130 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.130 [1534.1534] INFO MediaEngine: [0x22247c8]: Setting current track: 313558363, initial offset: 0ms May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.130 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Clearing all streams May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.130 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 27]: Disposing of audio stream May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.130 [1534.1534] INFO ContentFetcher: [0x2415c18]: Canceling download May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.136 [1534.1534] INFO AudioStreamManager: [0x22248b0]: New stream: 29 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.136 [1534.1534] INFO HttpDownloader: [0x22ffcc0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=e188fc9a644e1e02d4ce0ce49dfc3050&request_ts=1748056266&track_id=313558363 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.137 [1534.1534] INFO HttpDownloader: [0x2453808]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=313558363 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.137 [1534.1534] INFO HttpDownloader: [0x2420018]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=e96c0d9436af54a3db1c3681cbd37699&request_ts=1748056266&track_id=313558363 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.137 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 29]: Running audio stream May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.138 [1534.1534] INFO SampleApp: [Stream 27]: Audio stream has been disposed of May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.138 [1534.1534] INFO MediaEngine: [0x22247c8]: Setting next track: 3775798 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.138 [1534.1534] INFO AudioStreamManager: [0x22248b0]: New stream: 30 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.138 [1534.1534] INFO HttpDownloader: [0x2309ba8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=50ee157ecad5269da69a30ec3b552546&request_ts=1748056266&track_id=3775798 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.138 [1534.1534] INFO HttpDownloader: [0x24efd58]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=3775798 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.138 [1534.1534] INFO HttpDownloader: [0x24552e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=509378d9c4f7f3de5ac0dfc42eecc518&request_ts=1748056266&track_id=3775798 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.140 [1534.1534] INFO MediaEngine: [0x22247c8]: Waiting for current stream to start before starting audio renderer May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.355 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 29]: Metadata became available: May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.355 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Title: Escape the Chaos May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.355 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Artist: Morcheeba May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.355 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Album: Escape The Chaos May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.355 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.355 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 29]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=313558363&fmt=7&profile=raw&app_id=174516466&cid=1496927&etsp=1748059866&hmac=QGVoHoUPIhiKu4jb7WjkB58mx2g May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.355 [1534.1534] INFO UrlAudioSource: [0x23fc530]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=313558363&fmt=7&profile=raw&app_id=174516466&cid=1496927&etsp=1748059866&hmac=QGVoHoUPIhiKu4jb7WjkB58mx2g May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.356 [1534.1534] INFO ContentFetcher: [0x2309e48]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=313558363&fmt=7&profile=raw&app_id=174516466&cid=1496927&etsp=1748059866&hmac=QGVoHoUPIhiKu4jb7WjkB58mx2g, offset: 0 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.356 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 30]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=3775798&fmt=6&profile=raw&app_id=174516466&cid=1496927&etsp=1748059866&hmac=kdZu9wXtYqTnni76YtvI0UdeP2k May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.356 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 29]: stream information have been fetched May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.356 [1534.1534] INFO AudioRenderer: [0x22249e8]: Starting audio renderer, initial playback state: Playing May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.356 [1534.1534] INFO SampleApp: [Stream 29]: New audio stream (starting from 0ms) May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.356 [1534.1534] INFO SampleApp: [Stream 29]: Stream metadata became available: May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.356 [1534.1534] INFO SampleApp: Title: Escape the Chaos May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.356 [1534.1534] INFO SampleApp: Artist: Morcheeba May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.356 [1534.1534] INFO SampleApp: Album: Escape The Chaos May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.356 [1534.1534] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:06 audio-1 volumio[837]: info: QobuzConnect: setActiveState() May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.359 [1534.1534] INFO VolumeManager: [0x2224c30]: Setting new playback volume: 100 May 23 23:11:06 audio-1 volumio[837]: info: QobuzConnect: Set Qobuz connect default volume to 100 May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:06 audio-1 volumio[837]: info: QobuzConnect: Stopping currently active service May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioStop May 23 23:11:06 audio-1 volumio[837]: info: CoreStateMachine::stop May 23 23:11:06 audio-1 volumio[837]: info: CoreStateMachine::serviceStop May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::serviceStop May 23 23:11:06 audio-1 volumio[837]: info: Airplay Stop May 23 23:11:06 audio-1 volumio[837]: info: Stopping Airplay Playback and sending pause command to client via USR2 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.380 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 30]: Metadata became available: May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.380 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Title: Les Nuits May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.380 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Artist: Nightmares On Wax May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.380 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Album: Carboot Soul May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.380 [1534.1534] INFO AudioStreamManager: [0x22248b0]: Album art URL: https://static.qobuz.com/images/covers/36/61/0801061006136_600.jpg May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.380 [1534.1534] INFO AudioStreamManager: [0x22248b0]: [Stream 30]: stream information have been fetched May 23 23:11:06 audio-1 sudo[2411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 2402 May 23 23:11:06 audio-1 sudo[2411]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 23:11:06 audio-1 sudo[2411]: pam_unix(sudo:session): session closed for user root May 23 23:11:06 audio-1 volumio[837]: info: Shairport-Sync paused with USR2 May 23 23:11:06 audio-1 volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:06 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:06 audio-1 volumio[837]: info: CorePlayQueue::getTrack 0 May 23 23:11:06 audio-1 volumio[837]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received qobuzconnect May 23 23:11:06 audio-1 volumio[837]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect May 23 23:11:06 audio-1 volumio[837]: info: QobuzConnect: setActiveState() completed, send playback confirmation May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.456 [1534.1534] INFO SampleApp: Initiating playback, initial state: Playing May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.476 [1534.1534] INFO SampleApp: Playback state changed: Playing May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.476 [1534.1534] INFO StreamingEventReporter: [0x2225320]: Reporting streaming started event May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.477 [1534.1534] INFO HttpDownloader: [0x2225378]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.477 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Need to send state update May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.477 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending StateUpdated message May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.477 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.477 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending FileAudioQualityChanged message May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.478 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:06 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:06 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:06 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:06 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.931 [1534.1534] INFO UrlAudioSource: [0x23fc530]: Content info extracted May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.931 [1534.1534] INFO UrlAudioSource: [0x23fc530]: Size: 106324329 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.931 [1534.2413] INFO AudioDecoder: [0x246e8c8]: Stream info: May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.931 [1534.2413] INFO AudioDecoder: [0x246e8c8]: Total samples: 28472375 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.931 [1534.2413] INFO AudioDecoder: [0x246e8c8]: Sample rate: 96000 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.931 [1534.2413] INFO AudioDecoder: [0x246e8c8]: Channel count: 2 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.931 [1534.2413] INFO AudioDecoder: [0x246e8c8]: Bits per sample: 24 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.931 [1534.2413] INFO AudioDecoder: [0x246e8c8]: Duration: 296587 May 23 23:11:06 audio-1 qobuz-connect[1534]: 20250523 23:11:06.931 [1534.1534] INFO UrlAudioSource: [0x23fc530]: Audio properties have changed May 23 23:11:07 audio-1 volumio[837]: info: Restarting Shairport-Sync after stop May 23 23:11:07 audio-1 sudo[2415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 23:11:07 audio-1 sudo[2415]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 23:11:07 audio-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 23 23:11:07 audio-1 systemd[1]: shairport-sync.service: Succeeded. May 23 23:11:07 audio-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 23 23:11:07 audio-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 23 23:11:07 audio-1 sudo[2415]: pam_unix(sudo:session): session closed for user root May 23 23:11:07 audio-1 volumio[837]: info: Shairport-Sync retarted May 23 23:11:13 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 23 23:11:14 audio-1 volumio[837]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 23 23:11:14 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 23 23:11:14 audio-1 volumio[837]: info: Received Get System Version May 23 23:11:14 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 23 23:11:14 audio-1 volumio[837]: info: Received Get System Info May 23 23:11:14 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 23:11:14 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 23:11:14 audio-1 volumio[837]: info: Discovery: Getting this device information May 23 23:11:14 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:14 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 23:11:16 audio-1 qobuz-connect[1534]: 20250523 23:11:16.476 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Need to send state update May 23 23:11:16 audio-1 qobuz-connect[1534]: 20250523 23:11:16.477 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending StateUpdated message May 23 23:11:16 audio-1 qobuz-connect[1534]: 20250523 23:11:16.477 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:11:16 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:16 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:16 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:16 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:11:16 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:16 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 23:11:20 audio-1 volumio[837]: info: Retrieving Cloud Streaming UI May 23 23:11:20 audio-1 volumio[837]: info: Getting Tidal Cloud Configuration May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 23:11:20 audio-1 volumio[837]: info: Getting Qobuz Cloud Configuration May 23 23:11:20 audio-1 volumio[837]: info: Asking plugin for UI Config May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 23:11:20 audio-1 volumio[837]: info: Getting Spotify Cloud Configuration May 23 23:11:20 audio-1 volumio[837]: info: Asking plugin for UI Config May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 23:11:20 audio-1 volumio[837]: info: Saving Spotify Acccount May 23 23:11:20 audio-1 volumio[837]: info: Got it May 23 23:11:20 audio-1 volumio[837]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 23 23:11:20 audio-1 volumio[837]: info: Got Tidal Cloud Configuration May 23 23:11:20 audio-1 volumio[837]: info: Got it May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetBrowseSources May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetBrowseSources May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetBrowseSources May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 23 23:11:20 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 23 23:11:24 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 23 23:11:26 audio-1 qobuz-connect[1534]: 20250523 23:11:26.477 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Need to send state update May 23 23:11:26 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:26 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:26 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:26 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:11:26 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:26 audio-1 qobuz-connect[1534]: 20250523 23:11:26.479 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending StateUpdated message May 23 23:11:26 audio-1 qobuz-connect[1534]: 20250523 23:11:26.480 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:11:26 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:28 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 23 23:11:32 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 23 23:11:34 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 23:11:34 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 23:11:34 audio-1 volumio[837]: info: Discovery: Getting this device information May 23 23:11:34 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:34 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 23:11:36 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 23 23:11:36 audio-1 qobuz-connect[1534]: 20250523 23:11:36.477 [1534.1534] INFO ProtocolHandler: [0x2224f08]: Need to send state update May 23 23:11:36 audio-1 qobuz-connect[1534]: 20250523 23:11:36.477 [1534.1534] INFO QConnectMessageSender: [0x22648c0]: Sending StateUpdated message May 23 23:11:36 audio-1 qobuz-connect[1534]: 20250523 23:11:36.477 [1534.1534] INFO QwspMessageSender: [0x232ee88]: Sending Payload message May 23 23:11:36 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:36 audio-1 volumio[837]: info: CoreCommandRouter::servicePushState May 23 23:11:36 audio-1 volumio[837]: info: CoreStateMachine::pushState May 23 23:11:36 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:11:36 audio-1 volumio[837]: info: CoreCommandRouter::volumioPushState May 23 23:11:36 audio-1 volumio[837]: info: CoreCommandRouter::volumioGetState May 23 23:11:39 audio-1 volumio[837]: info: Disabling MyMusic plugin upnp May 23 23:11:39 audio-1 sudo[2472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 23 23:11:39 audio-1 sudo[2472]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 23:11:39 audio-1 systemd[1]: Stopping UPnP Renderer front-end to MPD... May 23 23:11:39 audio-1 volumio[837]: error: Upnp client error: Error: This socket has been ended by the other party May 23 23:11:40 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 23 23:11:44 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 23 23:11:44 audio-1 volumio[837]: info: Enabling MyMusic plugin upnp May 23 23:11:44 audio-1 volumio[837]: info: Enabling plugin upnp May 23 23:11:44 audio-1 volumio[837]: info: Loading plugin "upnp"... May 23 23:11:44 audio-1 volumio[837]: info: [1748056304645] Starting Upmpd Daemon May 23 23:11:44 audio-1 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 23 23:11:44 audio-1 volumio[837]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 23:11:44 audio-1 volumio[837]: Error: listen EADDRINUSE: address already in use :::6599 May 23 23:11:44 audio-1 volumio[837]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) May 23 23:11:44 audio-1 volumio[837]: at listenInCluster (net.js:1366:12) May 23 23:11:44 audio-1 volumio[837]: at Server.listen (net.js:1452:7) May 23 23:11:44 audio-1 volumio[837]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) May 23 23:11:44 audio-1 volumio[837]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) May 23 23:11:44 audio-1 volumio[837]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 23 23:11:44 audio-1 volumio[837]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 23 23:11:44 audio-1 volumio[837]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { May 23 23:11:44 audio-1 volumio[837]: code: 'EADDRINUSE', May 23 23:11:44 audio-1 volumio[837]: errno: -98, May 23 23:11:44 audio-1 volumio[837]: syscall: 'listen', May 23 23:11:44 audio-1 volumio[837]: address: '::', May 23 23:11:44 audio-1 volumio[837]: port: 6599 May 23 23:11:44 audio-1 volumio[837]: } May 23 23:11:44 audio-1 volumio[837]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 23:11:45 audio-1 sudo[2497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-23 23:10 May 23 23:11:45 audio-1 sudo[2497]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"