-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-06-04 19:27:09 CEST. -- Jun 04 19:26:00 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:00 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:00 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 04 19:26:00 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 04 19:26:02 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:02 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:03 raspamp sudo[2249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 04 19:26:03 raspamp sudo[2249]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:26:03 raspamp sudo[2249]: pam_unix(sudo:session): session closed for user root Jun 04 19:26:03 raspamp sudo[2251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 19:26:03 raspamp sudo[2251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:26:03 raspamp sudo[2251]: pam_unix(sudo:session): session closed for user root Jun 04 19:26:03 raspamp volumio[919]: verbose: New Socket.io Connection to 192.168.72.162 from 192.168.72.230 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 Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 19:26:04 raspamp volumio[919]: info: Discovery: Getting this device information Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:04 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::volumioGetVisibleSources Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:04 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 04 19:26:04 raspamp volumio[919]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 04 19:26:04 raspamp volumio[919]: info: Received Get System Info Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 19:26:04 raspamp volumio[919]: info: Discovery: Getting this device information Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:04 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:04 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:04 raspamp volumio[919]: info: Listing playlists Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 04 19:26:04 raspamp sudo[2256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 04 19:26:04 raspamp sudo[2256]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:26:04 raspamp sudo[2256]: pam_unix(sudo:session): session closed for user root Jun 04 19:26:04 raspamp sudo[2259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 19:26:04 raspamp sudo[2259]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:26:04 raspamp sudo[2259]: pam_unix(sudo:session): session closed for user root Jun 04 19:26:04 raspamp volumio[919]: verbose: New Socket.io Connection to 192.168.72.162 from 192.168.72.230 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 Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::volumioGetVisibleSources Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:04 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 04 19:26:04 raspamp volumio[919]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 04 19:26:04 raspamp volumio[919]: info: Received Get System Info Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 19:26:04 raspamp volumio[919]: info: Discovery: Getting this device information Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:04 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:04 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:04 raspamp volumio[919]: info: Listing playlists Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 04 19:26:04 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 04 19:26:06 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 04 19:26:06 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 04 19:26:06 raspamp volumio[919]: info: Received Get System Info Jun 04 19:26:06 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 19:26:06 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 19:26:06 raspamp volumio[919]: info: Discovery: Getting this device information Jun 04 19:26:06 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:06 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:06 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 19:26:06 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 04 19:26:06 raspamp go-librespot[1145]: time="2025-06-04T19:26:06+02:00" level=trace msg="received accesspoint ping" Jun 04 19:26:06 raspamp go-librespot[1145]: time="2025-06-04T19:26:06+02:00" level=trace msg="received accesspoint pong ack" Jun 04 19:26:07 raspamp go-librespot[1145]: time="2025-06-04T19:26:07+02:00" level=trace msg="sent dealer ping" Jun 04 19:26:07 raspamp go-librespot[1145]: time="2025-06-04T19:26:07+02:00" level=trace msg="received dealer pong" Jun 04 19:26:09 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 04 19:26:09 raspamp volumio[919]: info: Received Get System Info Jun 04 19:26:09 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 19:26:09 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 19:26:09 raspamp volumio[919]: info: Discovery: Getting this device information Jun 04 19:26:09 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:09 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:09 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 19:26:09 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 04 19:26:11 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 04 19:26:11 raspamp volumio[919]: info: Received Get System Info Jun 04 19:26:11 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 19:26:11 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 19:26:11 raspamp volumio[919]: info: Discovery: Getting this device information Jun 04 19:26:11 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:11 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:11 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 19:26:12 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 04 19:26:12 raspamp volumio[919]: info: Received Get System Info Jun 04 19:26:12 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 19:26:12 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 19:26:12 raspamp volumio[919]: info: Discovery: Getting this device information Jun 04 19:26:12 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:12 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:12 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 19:26:14 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 04 19:26:16 raspamp kernel: hwmon hwmon1: Voltage normalised Jun 04 19:26:23 raspamp kernel: hwmon hwmon1: Undervoltage detected! Jun 04 19:26:28 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 19:26:28 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 19:26:28 raspamp volumio[919]: info: Discovery: Getting this device information Jun 04 19:26:28 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:28 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:28 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.126 [1403.2348] INFO SampleApp: API endpoint invoked: get-connect-info Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.306 [1403.2348] INFO SampleApp: API endpoint invoked: connect-to-qconnect Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.306 [1403.1403] INFO EndpointManager: [0xcc7fe8]: Updating API endpoint Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.306 [1403.1403] INFO EndpointManager: [0xcc7fe8]: Updating QConnect endpoint Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.306 [1403.1403] INFO ActiveStateManager: [0xcc7000]: Setting new active state: active Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.306 [1403.1403] INFO PlaybackSessionManager: [0xcc83d8]: Starting playback session maintenance Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.306 [1403.1403] INFO HttpDownloader: [0xcc85c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.307 [1403.1403] INFO CloudClient: [0xcc8c48]: Connecting to the cloud Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.307 [1403.1403] INFO SampleApp: Renderer is now active Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.694 [1403.1403] INFO CloudClient: [0xcc8c48]: Connection established Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.694 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Authenticate message Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.694 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Subscribe message Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.694 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending JoinSession message Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.695 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending VolumeChanged message Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.695 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending VolumeMuted message Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.695 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending MaxAudioQualityChanged message Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.695 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Payload message Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.720 [1403.1403] INFO PlaybackSessionManager: [0xcc83d8]: Playback session has been refreshed Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.752 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Received SetActive message: active Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.752 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Received SetState message: Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.752 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Playing state: Paused Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.752 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Playback position: 88270 Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.752 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Queue version: 3.2 Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.752 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Current track: TID: 36090180, QID: 13, Context UUID: 304c7ccf-8ca5-462f-af5e-9b76fddd4c4d Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.752 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Next track: TID: 268051729, QID: 14, Context UUID: 304c7ccf-8ca5-462f-af5e-9b76fddd4c4d Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.752 [1403.1403] INFO MediaEngine: [0xcc8670]: Stopping playback, clearing tracks Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.752 [1403.1403] INFO MediaEngine: [0xcc8670]: Initiating playback Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.752 [1403.1403] INFO RendererActionAvailabilityManager: [0xcc8d00]: Renderer action 'Next' is available Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.753 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Received SetLoopMode message: Off Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.753 [1403.1403] INFO PlaybackControlsManager: [0xcc8a48]: Setting new loop mode: Off Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.753 [1403.1403] INFO MediaEngine: [0xcc8670]: Setting current track: 36090180, initial offset: 88270ms Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.753 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Clearing all streams Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.753 [1403.1403] INFO AudioStreamManager: [0xcc8758]: New stream: 1 Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.753 [1403.1403] INFO HttpDownloader: [0xccbfc0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=93e817816bf4c5aac44cc02322bc0493&request_ts=1749057993&track_id=36090180 Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.753 [1403.1403] INFO HttpDownloader: [0xcd4840]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=36090180 Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.753 [1403.1403] INFO HttpDownloader: [0xcced38]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=fb5e1937a0b737f46a558263eed82bea&request_ts=1749057993&track_id=36090180 Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.753 [1403.1403] INFO AudioStreamManager: [0xcc8758]: [Stream 1]: Running audio stream Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.754 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Received SetShuffleMode message: disabled Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.754 [1403.1403] INFO PlaybackControlsManager: [0xcc8a48]: Setting new shuffle mode: disabled Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.756 [1403.1403] INFO MediaEngine: [0xcc8670]: Setting next track: 268051729 Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.756 [1403.1403] INFO AudioStreamManager: [0xcc8758]: New stream: 2 Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.756 [1403.1403] INFO HttpDownloader: [0xcd44a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=dbf6df6c409477fd10be8647ef6b9ffd&request_ts=1749057993&track_id=268051729 Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.756 [1403.1403] INFO HttpDownloader: [0xda86b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=268051729 Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.756 [1403.1403] INFO HttpDownloader: [0xdaa910]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=d9e692901bf866ce9163565ed693307d&request_ts=1749057993&track_id=268051729 Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.758 [1403.1403] INFO MediaEngine: [0xcc8670]: Waiting for current stream to start before starting audio renderer Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.924 [1403.1403] INFO AudioStreamManager: [0xcc8758]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=496358&eid=268051729&fmt=7&profile=raw&app_id=174516466&cid=2725398&etsp=1749061593&hmac=GIojDuwktdRei_othrbeOJqJI4I Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.929 [1403.1403] INFO AudioStreamManager: [0xcc8758]: [Stream 2]: stream information have been fetched Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.936 [1403.1403] INFO AudioStreamManager: [0xcc8758]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=496358&eid=36090180&fmt=6&profile=raw&app_id=174516466&cid=2725398&etsp=1749061593&hmac=v12NiP-5hYbb3n1eVcim_E-NW6s Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.956 [1403.1403] INFO AudioStreamManager: [0xcc8758]: [Stream 1]: Metadata became available: Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.956 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Title: Sing It Back (Herbert's Tasteful Dub) Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.957 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Artist: Moloko Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.957 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Album: All Back to the Mine: Volume I - A Collection of Remixes (Herbert's Tasteful Dub) Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.957 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Album art URL: https://static.qobuz.com/images/covers/97/05/4050538240597_600.jpg Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.977 [1403.1403] INFO AudioStreamManager: [0xcc8758]: [Stream 2]: Metadata became available: Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.977 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Title: Special Re: Quest Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.977 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Artist: Daedelus Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.977 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Album: Labyrinths Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.977 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Album art URL: https://static.qobuz.com/images/covers/pc/jp/uxtacz0uwjppc_600.jpg Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.977 [1403.1403] INFO AudioStreamManager: [0xcc8758]: [Stream 1]: stream information have been fetched Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.977 [1403.1403] INFO UrlAudioSource: [0xda6f70]: Starting URL audio source, initial position: 88270ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=496358&eid=36090180&fmt=6&profile=raw&app_id=174516466&cid=2725398&etsp=1749061593&hmac=v12NiP-5hYbb3n1eVcim_E-NW6s Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.977 [1403.1403] INFO ContentFetcher: [0xda7098]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=496358&eid=36090180&fmt=6&profile=raw&app_id=174516466&cid=2725398&etsp=1749061593&hmac=v12NiP-5hYbb3n1eVcim_E-NW6s, offset: 0 Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.978 [1403.1403] INFO AudioRenderer: [0xcc8890]: Starting audio renderer, initial playback state: Paused Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.978 [1403.1403] INFO SampleApp: [Stream 1]: New audio stream (starting from 88270ms) Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.978 [1403.1403] INFO SampleApp: [Stream 1]: Stream metadata became available: Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.978 [1403.1403] INFO SampleApp: Title: Sing It Back (Herbert's Tasteful Dub) Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.979 [1403.1403] INFO SampleApp: Artist: Moloko Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.979 [1403.1403] INFO SampleApp: Album: All Back to the Mine: Volume I - A Collection of Remixes (Herbert's Tasteful Dub) Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.979 [1403.1403] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/97/05/4050538240597_600.jpg Jun 04 19:26:33 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:33 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:33 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:33 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:33 raspamp volumio[919]: info: QobuzConnect: setActiveState() Jun 04 19:26:33 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:33 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:33 raspamp volumio[919]: info: QobuzConnect: Set Qobuz connect default volume to 69 Jun 04 19:26:33 raspamp qobuz-connect[1403]: 20250604 19:26:33.993 [1403.1403] INFO VolumeManager: [0xcc8ad8]: Setting new playback volume: 69 Jun 04 19:26:33 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:33 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:33 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:33 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:33 raspamp volumio[919]: info: QobuzConnect: Stopping currently active service Jun 04 19:26:33 raspamp volumio[919]: info: CoreCommandRouter::volumioStop Jun 04 19:26:33 raspamp volumio[919]: info: CoreStateMachine::stop Jun 04 19:26:34 raspamp volumio[919]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 19:26:34 raspamp volumio[919]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 19:26:34 raspamp volumio[919]: info: CoreCommandRouter::servicePushState Jun 04 19:26:34 raspamp volumio[919]: info: CoreStateMachine::pushState Jun 04 19:26:34 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:26:34 raspamp volumio[919]: info: CoreCommandRouter::volumioPushState Jun 04 19:26:34 raspamp volumio[919]: info: MRS: Pushing multiroomSync output update for this device Jun 04 19:26:34 raspamp volumio[919]: info: MRS: Pushing multiroomSync output Jun 04 19:26:34 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:34 raspamp volumio[919]: info: CorePlayQueue::getTrack 0 Jun 04 19:26:34 raspamp volumio[919]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received qobuzconnect Jun 04 19:26:34 raspamp volumio[919]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect Jun 04 19:26:34 raspamp volumio[919]: info: QobuzConnect: setActiveState() completed, send playback confirmation Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.090 [1403.1403] INFO SampleApp: Initiating playback, initial state: Paused Jun 04 19:26:34 raspamp volumio[919]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.199 [1403.1403] INFO SampleApp: Playback volume changed: 69 Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.199 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Volume changed: 69 Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.199 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending VolumeChanged message Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.200 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Payload message Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.200 [1403.1403] INFO SampleApp: Playback state changed: Paused Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.200 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Need to send state update Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.200 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending StateUpdated message Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.201 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Payload message Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.201 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending FileAudioQualityChanged message Jun 04 19:26:34 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.202 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Payload message Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.202 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending DeviceAudioQualityChanged message Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.202 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Payload message Jun 04 19:26:34 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:34 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.605 [1403.1403] INFO UrlAudioSource: [0xda6f70]: Content info extracted Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.605 [1403.1403] INFO UrlAudioSource: [0xda6f70]: Size: 36565219 Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.608 [1403.2352] INFO AudioDecoder: [0xeab510]: Stream info: Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.608 [1403.2352] INFO AudioDecoder: [0xeab510]: Total samples: 15843499 Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.608 [1403.2352] INFO AudioDecoder: [0xeab510]: Sample rate: 44100 Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.608 [1403.2352] INFO AudioDecoder: [0xeab510]: Channel count: 2 Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.608 [1403.2352] INFO AudioDecoder: [0xeab510]: Bits per sample: 16 Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.608 [1403.2352] INFO AudioDecoder: [0xeab510]: Duration: 359263 Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.608 [1403.1403] INFO UrlAudioSource: [0xda6f70]: Audio properties have changed Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.608 [1403.1403] INFO UrlAudioSource: [0xda6f70]: Seeking input stream to byte offset: 8787244 Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.608 [1403.1403] INFO ContentFetcher: [0xda7098]: Adjusting download offset to: 8787244 Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.850 [1403.1403] INFO UrlAudioSource: [0xda6f70]: Seeking input stream to byte offset: 8781691 Jun 04 19:26:34 raspamp qobuz-connect[1403]: 20250604 19:26:34.850 [1403.1403] INFO ContentFetcher: [0xda7098]: Adjusting download offset to: 8781691 Jun 04 19:26:37 raspamp go-librespot[1145]: time="2025-06-04T19:26:37+02:00" level=trace msg="sent dealer ping" Jun 04 19:26:37 raspamp go-librespot[1145]: time="2025-06-04T19:26:37+02:00" level=trace msg="received dealer pong" Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.500 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Received SetState message: Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.500 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Playing state: Playing Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.500 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Playback position: Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.500 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Queue version: Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.500 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Current track: Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.500 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Next track: Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.500 [1403.1403] INFO MediaEngine: [0xcc8670]: Resuming playback Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.500 [1403.1403] INFO AudioRenderer: [0xcc8890]: Resuming audio renderer Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.500 [1403.1403] INFO SampleApp: Resuming playback Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.513 [1403.1403] INFO SampleApp: Playback state changed: Playing Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.513 [1403.1403] INFO StreamingEventReporter: [0xcc91c8]: Reporting streaming started event Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.514 [1403.1403] INFO HttpDownloader: [0xcc9220]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.514 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Need to send state update Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.514 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending StateUpdated message Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.515 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Payload message Jun 04 19:26:38 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:38 raspamp volumio[919]: info: CoreCommandRouter::servicePushState Jun 04 19:26:38 raspamp volumio[919]: info: CoreStateMachine::pushState Jun 04 19:26:38 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:26:38 raspamp volumio[919]: info: CoreCommandRouter::volumioPushState Jun 04 19:26:38 raspamp volumio[919]: info: MRS: Pushing multiroomSync output update for this device Jun 04 19:26:38 raspamp volumio[919]: info: MRS: Pushing multiroomSync output Jun 04 19:26:38 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.563 [1403.1403] INFO VolumeManager: [0xcc8ad8]: Setting new playback volume: 69 Jun 04 19:26:38 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:38 raspamp qobuz-connect[1403]: 20250604 19:26:38.567 [1403.1403] INFO VolumeManager: [0xcc8ad8]: Setting new mute state: 0 Jun 04 19:26:38 raspamp volumio[919]: info: CoreCommandRouter::servicePushState Jun 04 19:26:38 raspamp volumio[919]: info: CoreStateMachine::pushState Jun 04 19:26:38 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:26:38 raspamp volumio[919]: info: CoreCommandRouter::volumioPushState Jun 04 19:26:38 raspamp volumio[919]: info: MRS: Pushing multiroomSync output update for this device Jun 04 19:26:38 raspamp volumio[919]: info: MRS: Pushing multiroomSync output Jun 04 19:26:38 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:38 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:38 raspamp volumio[919]: info: CoreCommandRouter::servicePushState Jun 04 19:26:38 raspamp volumio[919]: info: CoreStateMachine::pushState Jun 04 19:26:38 raspamp volumio[919]: info: CoreCommandRouter::volumioPushState Jun 04 19:26:38 raspamp volumio[919]: info: MRS: Pushing multiroomSync output update for this device Jun 04 19:26:38 raspamp volumio[919]: info: MRS: Pushing multiroomSync output Jun 04 19:26:38 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:38 raspamp volumio[919]: info: [LastFM] Current track has sufficient metadata: title (Sing It Back (Herbert's Tasteful Dub)) and artist (Moloko) passed on explicitly Jun 04 19:26:38 raspamp volumio[919]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jun 04 19:26:38 raspamp volumio[919]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jun 04 19:26:38 raspamp volumio[919]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jun 04 19:26:39 raspamp volumio[919]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Sing It Back (Herbert's Tasteful Dub)","url":"https://www.last.fm/music/Moloko/_/Sing+It+Back++(Herbert%27s+Tasteful+Dub)","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"7","playcount":"48","artist":{"name":"Moloko","mbid":"2f94016a-3880-4d8c-9af9-0e197ee77189","url":"https://www.last.fm/music/Moloko"},"userplaycount":"0","userloved":"0","toptags":""}} Jun 04 19:26:48 raspamp qobuz-connect[1403]: 20250604 19:26:48.513 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Need to send state update Jun 04 19:26:48 raspamp qobuz-connect[1403]: 20250604 19:26:48.513 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending StateUpdated message Jun 04 19:26:48 raspamp qobuz-connect[1403]: 20250604 19:26:48.513 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Payload message Jun 04 19:26:48 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:48 raspamp volumio[919]: info: CoreCommandRouter::servicePushState Jun 04 19:26:48 raspamp volumio[919]: info: CoreStateMachine::pushState Jun 04 19:26:48 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:26:48 raspamp volumio[919]: info: CoreCommandRouter::volumioPushState Jun 04 19:26:48 raspamp volumio[919]: info: MRS: Pushing multiroomSync output update for this device Jun 04 19:26:48 raspamp volumio[919]: info: MRS: Pushing multiroomSync output Jun 04 19:26:48 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:48 raspamp volumio[919]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.299 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Received SetVolume message, volume: 64 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.300 [1403.1403] INFO VolumeManager: [0xcc8ad8]: Setting new playback volume: 64 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.346 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Received SetVolume message, volume: 63 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.346 [1403.1403] INFO VolumeManager: [0xcc8ad8]: Setting new playback volume: 63 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.397 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Received SetVolume message, volume: 62 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.397 [1403.1403] INFO VolumeManager: [0xcc8ad8]: Setting new playback volume: 62 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.450 [1403.1403] INFO SampleApp: Playback volume changed: 62 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.450 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Volume changed: 62 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.450 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending VolumeChanged message Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.451 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Payload message Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.452 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Received SetVolume message, volume: 61 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.452 [1403.1403] INFO VolumeManager: [0xcc8ad8]: Setting new playback volume: 61 Jun 04 19:26:49 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:49 raspamp volumio[919]: info: QobuzConnect: Volume received from daemon: 62 Jun 04 19:26:49 raspamp volumio[919]: info: VolumeController::SetAlsaVolume62 Jun 04 19:26:49 raspamp volumio[919]: info: CoreStateMachine::pushState Jun 04 19:26:49 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:26:49 raspamp volumio[919]: info: CoreCommandRouter::volumioPushState Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.510 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Received SetVolume message, volume: 60 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.510 [1403.1403] INFO VolumeManager: [0xcc8ad8]: Setting new playback volume: 60 Jun 04 19:26:49 raspamp volumio[919]: info: MRS: Pushing multiroomSync output update for this device Jun 04 19:26:49 raspamp volumio[919]: info: MRS: Pushing multiroomSync output Jun 04 19:26:49 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.555 [1403.1403] INFO VolumeManager: [0xcc8ad8]: Setting new playback volume: 62 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.562 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Received SetVolume message, volume: 59 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.562 [1403.1403] INFO VolumeManager: [0xcc8ad8]: Setting new playback volume: 59 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.602 [1403.1403] INFO SampleApp: Playback volume changed: 59 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.603 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Volume changed: 59 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.603 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending VolumeChanged message Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.603 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Payload message Jun 04 19:26:49 raspamp volumio[919]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jun 04 19:26:49 raspamp volumio[919]: SPOTIFY: SPOTIFY VOLUME 69 Jun 04 19:26:49 raspamp volumio[919]: SPOTIFY: VOLUMIO VOLUME 62 Jun 04 19:26:49 raspamp volumio[919]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 04 19:26:49 raspamp volumio[919]: info: Setting Spotify Volume from Volumio: 62 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.614 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Received SetVolume message, volume: 58 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.614 [1403.1403] INFO VolumeManager: [0xcc8ad8]: Setting new playback volume: 58 Jun 04 19:26:49 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:49 raspamp volumio[919]: info: QobuzConnect: Volume received from daemon: 59 Jun 04 19:26:49 raspamp volumio[919]: info: VolumeController::SetAlsaVolume59 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.680 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Received SetVolume message, volume: 57 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.680 [1403.1403] INFO VolumeManager: [0xcc8ad8]: Setting new playback volume: 57 Jun 04 19:26:49 raspamp volumio[919]: info: CoreStateMachine::pushState Jun 04 19:26:49 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:26:49 raspamp volumio[919]: info: CoreCommandRouter::volumioPushState Jun 04 19:26:49 raspamp volumio[919]: info: MRS: Pushing multiroomSync output update for this device Jun 04 19:26:49 raspamp volumio[919]: info: MRS: Pushing multiroomSync output Jun 04 19:26:49 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.735 [1403.1403] INFO VolumeManager: [0xcc8ad8]: Setting new playback volume: 59 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.737 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Received SetVolume message, volume: 56 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.737 [1403.1403] INFO VolumeManager: [0xcc8ad8]: Setting new playback volume: 56 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.764 [1403.1403] INFO SampleApp: Playback volume changed: 56 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.764 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Volume changed: 56 Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.764 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending VolumeChanged message Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.764 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Payload message Jun 04 19:26:49 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:49 raspamp volumio[919]: info: QobuzConnect: Volume received from daemon: 56 Jun 04 19:26:49 raspamp volumio[919]: info: VolumeController::SetAlsaVolume56 Jun 04 19:26:49 raspamp volumio[919]: info: CoreStateMachine::pushState Jun 04 19:26:49 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:26:49 raspamp volumio[919]: info: CoreCommandRouter::volumioPushState Jun 04 19:26:49 raspamp volumio[919]: info: MRS: Pushing multiroomSync output update for this device Jun 04 19:26:49 raspamp volumio[919]: info: MRS: Pushing multiroomSync output Jun 04 19:26:49 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:49 raspamp qobuz-connect[1403]: 20250604 19:26:49.872 [1403.1403] INFO VolumeManager: [0xcc8ad8]: Setting new playback volume: 56 Jun 04 19:26:49 raspamp volumio[919]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Jun 04 19:26:49 raspamp volumio[919]: SPOTIFY: SPOTIFY VOLUME 62 Jun 04 19:26:49 raspamp volumio[919]: SPOTIFY: VOLUMIO VOLUME 59 Jun 04 19:26:49 raspamp volumio[919]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 04 19:26:49 raspamp volumio[919]: info: Setting Spotify Volume from Volumio: 59 Jun 04 19:26:49 raspamp volumio[919]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jun 04 19:26:49 raspamp volumio[919]: SPOTIFY: SPOTIFY VOLUME 59 Jun 04 19:26:49 raspamp volumio[919]: SPOTIFY: VOLUMIO VOLUME 56 Jun 04 19:26:49 raspamp volumio[919]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 04 19:26:49 raspamp volumio[919]: info: Setting Spotify Volume from Volumio: 56 Jun 04 19:26:51 raspamp volumio[919]: SPOTIFY: SETTING SPOTIFY VOLUME 56 Jun 04 19:26:51 raspamp volumio[919]: info: Sending Spotify command with payload to local API: /player/volume Jun 04 19:26:51 raspamp go-librespot[1145]: time="2025-06-04T19:26:51+02:00" level=debug msg="update volume to 36699/65535" Jun 04 19:26:52 raspamp go-librespot[1145]: time="2025-06-04T19:26:52+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 04 19:26:52 raspamp go-librespot[1145]: time="2025-06-04T19:26:52+02:00" level=trace msg="emitting websocket event: volume" Jun 04 19:26:52 raspamp volumio[919]: SPOTIFY: received: {"type":"volume","data":{"value":56,"max":100}} Jun 04 19:26:52 raspamp volumio[919]: SPOTIFY: RECEIVED SPOTIFY VOLUME 56 Jun 04 19:26:58 raspamp qobuz-connect[1403]: 20250604 19:26:58.514 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Need to send state update Jun 04 19:26:58 raspamp qobuz-connect[1403]: 20250604 19:26:58.514 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending StateUpdated message Jun 04 19:26:58 raspamp qobuz-connect[1403]: 20250604 19:26:58.514 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Payload message Jun 04 19:26:58 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:58 raspamp volumio[919]: info: CoreCommandRouter::servicePushState Jun 04 19:26:58 raspamp volumio[919]: info: CoreStateMachine::pushState Jun 04 19:26:58 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:26:58 raspamp volumio[919]: info: CoreCommandRouter::volumioPushState Jun 04 19:26:58 raspamp volumio[919]: info: MRS: Pushing multiroomSync output update for this device Jun 04 19:26:58 raspamp volumio[919]: info: MRS: Pushing multiroomSync output Jun 04 19:26:58 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:58 raspamp volumio[919]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jun 04 19:26:58 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 19:26:58 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 19:26:58 raspamp volumio[919]: info: Discovery: Getting this device information Jun 04 19:26:58 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:26:58 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 19:27:07 raspamp go-librespot[1145]: time="2025-06-04T19:27:07+02:00" level=trace msg="sent dealer ping" Jun 04 19:27:07 raspamp go-librespot[1145]: time="2025-06-04T19:27:07+02:00" level=trace msg="received dealer pong" Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.427 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Received SetState message: Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.427 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Playing state: Playing Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.427 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Playback position: 0 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.427 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Queue version: 3.2 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.427 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Current track: TID: 268051729, QID: 14, Context UUID: 304c7ccf-8ca5-462f-af5e-9b76fddd4c4d Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.427 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Next track: TID: 208155659, QID: 15, Context UUID: 304c7ccf-8ca5-462f-af5e-9b76fddd4c4d Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.428 [1403.1403] INFO MediaEngine: [0xcc8670]: Stopping playback, clearing tracks Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.428 [1403.1403] INFO MediaEngine: [0xcc8670]: Initiating playback Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.428 [1403.1403] INFO AudioRenderer: [0xcc8890]: Stopping audio renderer Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.428 [1403.1403] INFO SampleApp: Stopping playback Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.435 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Clearing all streams Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.435 [1403.1403] INFO AudioStreamManager: [0xcc8758]: [Stream 1]: Disposing of audio stream Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.436 [1403.1403] INFO ContentFetcher: [0xda7098]: Canceling download Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.448 [1403.1403] INFO SampleApp: Playback state changed: Stopped Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.449 [1403.1403] INFO StreamingEventReporter: [0xcc91c8]: Reporting streaming ended event Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.449 [1403.1403] INFO HttpDownloader: [0xcc9220]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.449 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Need to send state update Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.449 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending StateUpdated message Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.449 [1403.1403] INFO SampleApp: [Stream 1]: Audio stream has been disposed of Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.450 [1403.1403] INFO MediaEngine: [0xcc8670]: Setting current track: 268051729, initial offset: 0ms Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.450 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Clearing all streams Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.450 [1403.1403] INFO AudioStreamManager: [0xcc8758]: New stream: 3 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.450 [1403.1403] INFO HttpDownloader: [0xda8c60]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=3ad2f2e855aef6f09e2ece5000028405&request_ts=1749058027&track_id=268051729 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.450 [1403.1403] INFO HttpDownloader: [0xf2e730]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=268051729 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.450 [1403.1403] INFO HttpDownloader: [0xccbfc0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=fb4fff6374608a735a3ed3d95f7fe3b6&request_ts=1749058027&track_id=268051729 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.450 [1403.1403] INFO AudioStreamManager: [0xcc8758]: [Stream 3]: Running audio stream Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.451 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Payload message Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::servicePushState Jun 04 19:27:07 raspamp volumio[919]: info: CoreStateMachine::pushState Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioPushState Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.455 [1403.1403] INFO MediaEngine: [0xcc8670]: Setting next track: 208155659 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.455 [1403.1403] INFO AudioStreamManager: [0xcc8758]: New stream: 4 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.455 [1403.1403] INFO HttpDownloader: [0xe6ba70]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=533b6f211444e039fcf46f0b03c865cc&request_ts=1749058027&track_id=208155659 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.455 [1403.1403] INFO HttpDownloader: [0xda3f90]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=208155659 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.455 [1403.1403] INFO HttpDownloader: [0xe41520]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=f3768a24afacaf48bc2f30da7b2cc019&request_ts=1749058027&track_id=208155659 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.458 [1403.1403] INFO MediaEngine: [0xcc8670]: Waiting for current stream to start before starting audio renderer Jun 04 19:27:07 raspamp volumio[919]: info: MRS: Pushing multiroomSync output update for this device Jun 04 19:27:07 raspamp volumio[919]: info: MRS: Pushing multiroomSync output Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::servicePushState Jun 04 19:27:07 raspamp volumio[919]: info: CoreStateMachine::pushState Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioPushState Jun 04 19:27:07 raspamp volumio[919]: info: MRS: Pushing multiroomSync output update for this device Jun 04 19:27:07 raspamp volumio[919]: info: MRS: Pushing multiroomSync output Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.586 [1403.1403] INFO AudioStreamManager: [0xcc8758]: [Stream 4]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=496358&eid=208155659&fmt=6&profile=raw&app_id=174516466&cid=2725398&etsp=1749061627&hmac=4pcpIadR6EZJrK4UEuGc7Bvdrok Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.592 [1403.1403] INFO AudioStreamManager: [0xcc8758]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=496358&eid=268051729&fmt=7&profile=raw&app_id=174516466&cid=2725398&etsp=1749061627&hmac=1Qh8QmzNlG7rBJHnBK55WF2DmwQ Jun 04 19:27:07 raspamp volumio[919]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jun 04 19:27:07 raspamp volumio[919]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.616 [1403.1403] INFO AudioStreamManager: [0xcc8758]: [Stream 3]: stream information have been fetched Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.616 [1403.1403] INFO UrlAudioSource: [0xda40d0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=496358&eid=268051729&fmt=7&profile=raw&app_id=174516466&cid=2725398&etsp=1749061627&hmac=1Qh8QmzNlG7rBJHnBK55WF2DmwQ Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.617 [1403.1403] INFO ContentFetcher: [0xf2bd30]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=496358&eid=268051729&fmt=7&profile=raw&app_id=174516466&cid=2725398&etsp=1749061627&hmac=1Qh8QmzNlG7rBJHnBK55WF2DmwQ, offset: 0 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.618 [1403.1403] INFO AudioRenderer: [0xcc8890]: Starting audio renderer, initial playback state: Playing Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.618 [1403.1403] INFO SampleApp: [Stream 3]: New audio stream (starting from 0ms) Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:27:07 raspamp volumio[919]: info: QobuzConnect: alread currrent service, send playback confirmation Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.763 [1403.1403] INFO SampleApp: Initiating playback, initial state: Playing Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.832 [1403.1403] INFO SampleApp: Playback state changed: Playing Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.832 [1403.1403] INFO StreamingEventReporter: [0xcc91c8]: Reporting streaming started event Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.833 [1403.1403] INFO HttpDownloader: [0xcc9220]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.833 [1403.1403] INFO ProtocolHandler: [0xcc8db0]: Need to send state update Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.833 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending StateUpdated message Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.833 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Payload message Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.835 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending FileAudioQualityChanged message Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.835 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Payload message Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.836 [1403.1403] INFO QConnectMessageSender: [0xd087b0]: Sending DeviceAudioQualityChanged message Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::servicePushState Jun 04 19:27:07 raspamp volumio[919]: info: CoreStateMachine::pushState Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.838 [1403.1403] INFO QwspMessageSender: [0xdd2d38]: Sending Payload message Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.838 [1403.1403] INFO AudioStreamManager: [0xcc8758]: [Stream 3]: Metadata became available: Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.838 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Title: Special Re: Quest Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.838 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Artist: Daedelus Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.838 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Album: Labyrinths Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.838 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Album art URL: https://static.qobuz.com/images/covers/pc/jp/uxtacz0uwjppc_600.jpg Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.838 [1403.1403] INFO AudioStreamManager: [0xcc8758]: [Stream 4]: stream information have been fetched Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.838 [1403.1403] INFO AudioStreamManager: [0xcc8758]: [Stream 4]: Metadata became available: Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.838 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Title: Billion Dollar Gravy Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.838 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Artist: London Elektricity Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.838 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Album: Billion Dollar Gravy Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.838 [1403.1403] INFO AudioStreamManager: [0xcc8758]: Album art URL: https://static.qobuz.com/images/covers/rc/vs/srcw3je39vsrc_600.jpg Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioPushState Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.839 [1403.1403] INFO SampleApp: [Stream 3]: Stream metadata became available: Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.839 [1403.1403] INFO SampleApp: Title: Special Re: Quest Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.839 [1403.1403] INFO SampleApp: Artist: Daedelus Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.839 [1403.1403] INFO SampleApp: Album: Labyrinths Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.839 [1403.1403] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/pc/jp/uxtacz0uwjppc_600.jpg Jun 04 19:27:07 raspamp volumio[919]: info: MRS: Pushing multiroomSync output update for this device Jun 04 19:27:07 raspamp volumio[919]: info: MRS: Pushing multiroomSync output Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::servicePushState Jun 04 19:27:07 raspamp volumio[919]: info: CoreStateMachine::pushState Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioPushState Jun 04 19:27:07 raspamp volumio[919]: info: MRS: Pushing multiroomSync output update for this device Jun 04 19:27:07 raspamp volumio[919]: info: MRS: Pushing multiroomSync output Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::servicePushState Jun 04 19:27:07 raspamp volumio[919]: info: CoreStateMachine::pushState Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioPushState Jun 04 19:27:07 raspamp volumio[919]: info: MRS: Pushing multiroomSync output update for this device Jun 04 19:27:07 raspamp volumio[919]: info: MRS: Pushing multiroomSync output Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::servicePushState Jun 04 19:27:07 raspamp volumio[919]: info: CoreStateMachine::pushState Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioPushState Jun 04 19:27:07 raspamp volumio[919]: info: MRS: Pushing multiroomSync output update for this device Jun 04 19:27:07 raspamp volumio[919]: info: MRS: Pushing multiroomSync output Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::servicePushState Jun 04 19:27:07 raspamp volumio[919]: info: CoreStateMachine::pushState Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioPushState Jun 04 19:27:07 raspamp volumio[919]: info: MRS: Pushing multiroomSync output update for this device Jun 04 19:27:07 raspamp volumio[919]: info: MRS: Pushing multiroomSync output Jun 04 19:27:07 raspamp volumio[919]: info: CoreCommandRouter::volumioGetState Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.983 [1403.1403] INFO UrlAudioSource: [0xda40d0]: Content info extracted Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.983 [1403.1403] INFO UrlAudioSource: [0xda40d0]: Size: 44001911 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.986 [1403.2407] INFO AudioDecoder: [0xe6b440]: Stream info: Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.986 [1403.2407] INFO AudioDecoder: [0xe6b440]: Total samples: 9469952 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.986 [1403.2407] INFO AudioDecoder: [0xe6b440]: Sample rate: 44100 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.986 [1403.2407] INFO AudioDecoder: [0xe6b440]: Channel count: 2 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.987 [1403.2407] INFO AudioDecoder: [0xe6b440]: Bits per sample: 24 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.987 [1403.2407] INFO AudioDecoder: [0xe6b440]: Duration: 214738 Jun 04 19:27:07 raspamp qobuz-connect[1403]: 20250604 19:27:07.990 [1403.1403] INFO UrlAudioSource: [0xda40d0]: Audio properties have changed Jun 04 19:27:08 raspamp volumio[919]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Jun 04 19:27:08 raspamp volumio[919]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 19:27:08 raspamp volumio[919]: TypeError: Cannot read property 'indexOf' of undefined Jun 04 19:27:08 raspamp volumio[919]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Jun 04 19:27:08 raspamp volumio[919]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Jun 04 19:27:08 raspamp volumio[919]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Jun 04 19:27:08 raspamp volumio[919]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 04 19:27:08 raspamp volumio[919]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Jun 04 19:27:08 raspamp volumio[919]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Jun 04 19:27:08 raspamp volumio[919]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 04 19:27:08 raspamp volumio[919]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 04 19:27:08 raspamp volumio[919]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Jun 04 19:27:08 raspamp volumio[919]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 04 19:27:08 raspamp volumio[919]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Jun 04 19:27:08 raspamp volumio[919]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Jun 04 19:27:08 raspamp volumio[919]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Jun 04 19:27:08 raspamp volumio[919]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 04 19:27:08 raspamp volumio[919]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 04 19:27:08 raspamp volumio[919]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Jun 04 19:27:08 raspamp volumio[919]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 19:27:09 raspamp sudo[2417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-04 19:26 Jun 04 19:27:09 raspamp sudo[2417]: 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"