-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-06-22 07:57:06 CEST. -- Jun 22 07:56:29 valou-volumio volumio[1189]: verbose: New Socket.io Connection to 192.168.1.71 from 192.168.1.4 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 8 Jun 22 07:56:29 valou-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 22 07:56:29 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetVisibleSources Jun 22 07:56:29 valou-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 22 07:56:29 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:56:29 valou-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Jun 22 07:56:29 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:56:29 valou-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Jun 22 07:56:29 valou-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 22 07:56:29 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetQueue Jun 22 07:56:29 valou-volumio volumio[1189]: info: CoreStateMachine::getQueue Jun 22 07:56:29 valou-volumio volumio[1189]: info: CorePlayQueue::getQueue Jun 22 07:56:29 valou-volumio volumio[1189]: info: Listing playlists Jun 22 07:56:29 valou-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 22 07:56:29 valou-volumio volumio[1189]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 22 07:56:29 valou-volumio volumio[1189]: info: Received Get System Info Jun 22 07:56:29 valou-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 22 07:56:29 valou-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 22 07:56:29 valou-volumio volumio[1189]: info: Discovery: Getting this device information Jun 22 07:56:29 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:56:29 valou-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Jun 22 07:56:29 valou-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 22 07:56:29 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:56:29 valou-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Jun 22 07:56:30 valou-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 22 07:56:34 valou-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 22 07:56:35 valou-volumio volumio[1189]: info: Received Get System Info Jun 22 07:56:35 valou-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 22 07:56:35 valou-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 22 07:56:35 valou-volumio volumio[1189]: info: Discovery: Getting this device information Jun 22 07:56:35 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:56:35 valou-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Jun 22 07:56:35 valou-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 22 07:57:01 valou-volumio qobuz-connect[1680]: 20250622 07:57:01.075 [1680.1801] INFO SampleApp: API endpoint invoked: get-display-info Jun 22 07:57:01 valou-volumio qobuz-connect[1680]: 20250622 07:57:01.225 [1680.1801] INFO SampleApp: API endpoint invoked: get-connect-info Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.815 [1680.1801] INFO SampleApp: API endpoint invoked: connect-to-qconnect Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.815 [1680.1680] INFO EndpointManager: [0x23dcf70]: Updating API endpoint Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.815 [1680.1680] INFO EndpointManager: [0x23dcf70]: Updating QConnect endpoint Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.815 [1680.1680] INFO ActiveStateManager: [0x23dbf88]: Setting new active state: active Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.815 [1680.1680] INFO PlaybackSessionManager: [0x23dd360]: Starting playback session maintenance Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.815 [1680.1680] INFO HttpDownloader: [0x23dd550]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.815 [1680.1680] INFO CloudClient: [0x23ddbd0]: Connecting to the cloud Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.815 [1680.1680] INFO SampleApp: Renderer is now active Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.961 [1680.1680] INFO CloudClient: [0x23ddbd0]: Connection established Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.961 [1680.1680] INFO QwspMessageSender: [0x23e7620]: Sending Authenticate message Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.961 [1680.1680] INFO QwspMessageSender: [0x23e7620]: Sending Subscribe message Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.961 [1680.1680] INFO QConnectMessageSender: [0x23e7630]: Sending JoinSession message Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.961 [1680.1680] INFO QConnectMessageSender: [0x23e7630]: Sending VolumeChanged message Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.961 [1680.1680] INFO QConnectMessageSender: [0x23e7630]: Sending VolumeMuted message Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.961 [1680.1680] INFO QConnectMessageSender: [0x23e7630]: Sending MaxAudioQualityChanged message Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.961 [1680.1680] INFO QwspMessageSender: [0x23e7620]: Sending Payload message Jun 22 07:57:05 valou-volumio qobuz-connect[1680]: 20250622 07:57:05.993 [1680.1680] INFO PlaybackSessionManager: [0x23dd360]: Playback session has been refreshed Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO ProtocolHandler: [0x23ddd38]: Received SetActive message: active Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO ProtocolHandler: [0x23ddd38]: Received SetState message: Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO ProtocolHandler: [0x23ddd38]: Playing state: Playing Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO ProtocolHandler: [0x23ddd38]: Playback position: 9028 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO ProtocolHandler: [0x23ddd38]: Queue version: 7.1 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO ProtocolHandler: [0x23ddd38]: Current track: TID: 336103003, QID: 0, Context UUID: 5d01c858-f631-4ce5-b744-936d57d86ba8 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO ProtocolHandler: [0x23ddd38]: Next track: TID: 336103004, QID: 1, Context UUID: 5d01c858-f631-4ce5-b744-936d57d86ba8 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO MediaEngine: [0x23dd5f8]: Stopping playback, clearing tracks Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO MediaEngine: [0x23dd5f8]: Initiating playback Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO ProtocolHandler: [0x23ddd38]: Received SetLoopMode message: Off Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO PlaybackControlsManager: [0x23dd9d0]: Setting new loop mode: Off Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO MediaEngine: [0x23dd5f8]: Setting current track: 336103003, initial offset: 9028ms Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: Clearing all streams Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: New stream: 15 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO HttpDownloader: [0x2621070]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=9f0e2aba7fb5c83a0854ded505e88364&request_ts=1750571826&track_id=336103003 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO HttpDownloader: [0x23e76d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=336103003 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO HttpDownloader: [0x261ef60]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=d86106708be83d8e7f4a7a0b371d42f4&request_ts=1750571826&track_id=336103003 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: [Stream 15]: Running audio stream Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO ProtocolHandler: [0x23ddd38]: Received SetShuffleMode message: disabled Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO PlaybackControlsManager: [0x23dd9d0]: Setting new shuffle mode: disabled Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO MediaEngine: [0x23dd5f8]: Setting next track: 336103004 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: New stream: 16 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO HttpDownloader: [0x261f0d8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=6f984a9c60e391ea60f50ec6f2529842&request_ts=1750571826&track_id=336103004 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO HttpDownloader: [0x258ccd8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=336103004 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO HttpDownloader: [0x258cfc0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=4390480f38c830166723c54ecbd18701&request_ts=1750571826&track_id=336103004 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.006 [1680.1680] INFO MediaEngine: [0x23dd5f8]: Waiting for current stream to start before starting audio renderer Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.127 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: [Stream 15]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=336103003&fmt=7&profile=raw&app_id=174516466&cid=2788545&etsp=1750575426&hmac=eWweCgJimYmFqum5elzV2TUzqEA Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.141 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: [Stream 16]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=336103004&fmt=7&profile=raw&app_id=174516466&cid=2788545&etsp=1750575426&hmac=Ay8hl-K7Te_Cecx2fpj_mcKvzhI Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.166 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: [Stream 15]: stream information have been fetched Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.166 [1680.1680] INFO UrlAudioSource: [0x250cbd8]: Starting URL audio source, initial position: 9028ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=336103003&fmt=7&profile=raw&app_id=174516466&cid=2788545&etsp=1750575426&hmac=eWweCgJimYmFqum5elzV2TUzqEA Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.166 [1680.1680] INFO ContentFetcher: [0x2428068]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=336103003&fmt=7&profile=raw&app_id=174516466&cid=2788545&etsp=1750575426&hmac=eWweCgJimYmFqum5elzV2TUzqEA, offset: 0 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.167 [1680.1680] INFO AudioRenderer: [0x23dd818]: Starting audio renderer, initial playback state: Playing Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.167 [1680.1680] INFO SampleApp: [Stream 15]: New audio stream (starting from 9027ms) Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Jun 22 07:57:06 valou-volumio volumio[1189]: info: QobuzConnect: setActiveState() Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Jun 22 07:57:06 valou-volumio volumio[1189]: info: QobuzConnect: Set Qobuz connect default volume to 37 Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.170 [1680.1680] INFO VolumeManager: [0x23dda60]: Setting new playback volume: 37 Jun 22 07:57:06 valou-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Jun 22 07:57:06 valou-volumio volumio[1189]: info: QobuzConnect: Stopping currently active service Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioStop Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreStateMachine::stop Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::servicePushState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreStateMachine::pushState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Jun 22 07:57:06 valou-volumio volumio[1189]: info: MRS: Pushing multiroomSync output update for this device Jun 22 07:57:06 valou-volumio volumio[1189]: info: MRS: Pushing multiroomSync output Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.176 [1680.1680] INFO VolumeManager: [0x23dda60]: Setting new playback volume: 37 Jun 22 07:57:06 valou-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Jun 22 07:57:06 valou-volumio volumio[1189]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received qobuzconnect Jun 22 07:57:06 valou-volumio volumio[1189]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect Jun 22 07:57:06 valou-volumio volumio[1189]: info: QobuzConnect: setActiveState() completed, send playback confirmation Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.180 [1680.1680] INFO SampleApp: Initiating playback, initial state: Playing Jun 22 07:57:06 valou-volumio volumio[1189]: info: [ASDebug] CurState: stop PrevState: stop Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 65536, but target_pcm volumioOutput has buffer size 32768 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 65536, but target_pcm volumioOutput has buffer size 32768 Jun 22 07:57:06 valou-volumio volumio[1189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 37 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.184 [1680.1680] INFO SampleApp: Playback state changed: Playing Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.184 [1680.1680] INFO StreamingEventReporter: [0x23de150]: Reporting streaming started event Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.184 [1680.1680] INFO HttpDownloader: [0x23de1a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.184 [1680.1680] INFO ProtocolHandler: [0x23ddd38]: Need to send state update Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.184 [1680.1680] INFO QConnectMessageSender: [0x23e7630]: Sending StateUpdated message Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.184 [1680.1680] INFO QwspMessageSender: [0x23e7620]: Sending Payload message Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.184 [1680.1680] INFO QConnectMessageSender: [0x23e7630]: Sending FileAudioQualityChanged message Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.185 [1680.1680] INFO QwspMessageSender: [0x23e7620]: Sending Payload message Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.185 [1680.1680] INFO QConnectMessageSender: [0x23e7630]: Sending DeviceAudioQualityChanged message Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.185 [1680.1680] INFO QwspMessageSender: [0x23e7620]: Sending Payload message Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::servicePushState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreStateMachine::pushState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Jun 22 07:57:06 valou-volumio volumio[1189]: info: MRS: Pushing multiroomSync output update for this device Jun 22 07:57:06 valou-volumio volumio[1189]: info: MRS: Pushing multiroomSync output Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Jun 22 07:57:06 valou-volumio volumio[1189]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received qobuzconnect Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.193 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: [Stream 16]: Metadata became available: Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.193 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: Title: Kalopsia (Alive in the Catacombs) Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.193 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: Artist: Queens Of The Stone Age Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.193 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: Album: Alive in the Catacombs (Alive in the Catacombs) Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.193 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: Album art URL: https://static.qobuz.com/images/covers/6a/lp/wxlxlfguilp6a_600.jpg Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::servicePushState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreStateMachine::pushState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Jun 22 07:57:06 valou-volumio volumio[1189]: info: MRS: Pushing multiroomSync output update for this device Jun 22 07:57:06 valou-volumio volumio[1189]: info: MRS: Pushing multiroomSync output Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::servicePushState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreStateMachine::pushState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Jun 22 07:57:06 valou-volumio volumio[1189]: info: MRS: Pushing multiroomSync output update for this device Jun 22 07:57:06 valou-volumio volumio[1189]: info: MRS: Pushing multiroomSync output Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::servicePushState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreStateMachine::pushState Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Jun 22 07:57:06 valou-volumio volumio[1189]: info: MRS: Pushing multiroomSync output update for this device Jun 22 07:57:06 valou-volumio volumio[1189]: info: MRS: Pushing multiroomSync output Jun 22 07:57:06 valou-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Jun 22 07:57:06 valou-volumio volumio[1189]: info: [ASDebug] CurState: stop PrevState: stop Jun 22 07:57:06 valou-volumio volumio[1189]: info: [ASDebug] CurState: play PrevState: stop Jun 22 07:57:06 valou-volumio volumio[1189]: info: [ASDebug] Togle GPIO: ON Jun 22 07:57:06 valou-volumio volumio[1189]: info: [ASDebug] CurState: play PrevState: play Jun 22 07:57:06 valou-volumio volumio[1189]: info: [ASDebug] CurState: play PrevState: play Jun 22 07:57:06 valou-volumio volumio[1189]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Jun 22 07:57:06 valou-volumio volumio[1189]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 22 07:57:06 valou-volumio volumio[1189]: TypeError: Cannot read property 'indexOf' of undefined Jun 22 07:57:06 valou-volumio volumio[1189]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Jun 22 07:57:06 valou-volumio volumio[1189]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Jun 22 07:57:06 valou-volumio volumio[1189]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Jun 22 07:57:06 valou-volumio volumio[1189]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 22 07:57:06 valou-volumio volumio[1189]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Jun 22 07:57:06 valou-volumio volumio[1189]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Jun 22 07:57:06 valou-volumio volumio[1189]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 22 07:57:06 valou-volumio volumio[1189]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 22 07:57:06 valou-volumio volumio[1189]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Jun 22 07:57:06 valou-volumio volumio[1189]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 22 07:57:06 valou-volumio volumio[1189]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Jun 22 07:57:06 valou-volumio volumio[1189]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Jun 22 07:57:06 valou-volumio volumio[1189]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Jun 22 07:57:06 valou-volumio volumio[1189]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 22 07:57:06 valou-volumio volumio[1189]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 22 07:57:06 valou-volumio volumio[1189]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Jun 22 07:57:06 valou-volumio volumio[1189]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.250 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: [Stream 16]: stream information have been fetched Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.268 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: [Stream 15]: Metadata became available: Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.268 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: Title: Running Joke / Paper Machete (Alive in the Catacombs) Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.268 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: Artist: Queens Of The Stone Age Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.268 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: Album: Alive in the Catacombs (Alive in the Catacombs) Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.268 [1680.1680] INFO AudioStreamManager: [0x23dd6e0]: Album art URL: https://static.qobuz.com/images/covers/6a/lp/wxlxlfguilp6a_600.jpg Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.268 [1680.1680] INFO SampleApp: [Stream 15]: Stream metadata became available: Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.268 [1680.1680] INFO SampleApp: Title: Running Joke / Paper Machete (Alive in the Catacombs) Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.268 [1680.1680] INFO SampleApp: Artist: Queens Of The Stone Age Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.268 [1680.1680] INFO SampleApp: Album: Alive in the Catacombs (Alive in the Catacombs) Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.268 [1680.1680] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/6a/lp/wxlxlfguilp6a_600.jpg Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.279 [1680.1680] INFO UrlAudioSource: [0x250cbd8]: Content info extracted Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.279 [1680.1680] INFO UrlAudioSource: [0x250cbd8]: Size: 60554328 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.280 [1680.10694] INFO AudioDecoder: [0x262c7d8]: Stream info: Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.280 [1680.10694] INFO AudioDecoder: [0x262c7d8]: Total samples: 14657266 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.280 [1680.10694] INFO AudioDecoder: [0x262c7d8]: Sample rate: 44100 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.280 [1680.10694] INFO AudioDecoder: [0x262c7d8]: Channel count: 2 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.280 [1680.10694] INFO AudioDecoder: [0x262c7d8]: Bits per sample: 24 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.280 [1680.10694] INFO AudioDecoder: [0x262c7d8]: Duration: 332364 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.280 [1680.1680] INFO UrlAudioSource: [0x250cbd8]: Audio properties have changed Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.280 [1680.1680] INFO UrlAudioSource: [0x250cbd8]: Seeking input stream to byte offset: 1453857 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.280 [1680.1680] INFO ContentFetcher: [0x2428068]: Adjusting download offset to: 1453857 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.329 [1680.1680] INFO SampleApp: Playback volume changed: 37 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.329 [1680.1680] INFO ProtocolHandler: [0x23ddd38]: Volume changed: 37 Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.329 [1680.1680] INFO QConnectMessageSender: [0x23e7630]: Sending VolumeChanged message Jun 22 07:57:06 valou-volumio qobuz-connect[1680]: 20250622 07:57:06.339 [1680.1680] INFO QwspMessageSender: [0x23e7620]: Sending Payload message Jun 22 07:57:06 valou-volumio sudo[10698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-22 07:56 Jun 22 07:57:06 valou-volumio sudo[10698]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"