-- Logs begin at Thu 2019-02-14 21:11:58 AEDT, end at Sun 2025-05-11 18:22:20 AEST. --
May 11 18:21:56 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 11 18:21:56 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 11 18:21:56 volumio volumio[1087]: info: Discovery: Getting this device information
May 11 18:21:56 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:21:56 volumio volumio[1087]: info: CorePlayQueue::getTrack 0
May 11 18:21:56 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 11 18:21:56 volumio volumio[1087]: verbose: New Socket.io Connection to 192.168.1.157:3000 from 192.168.1.192 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7
May 11 18:21:56 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:21:56 volumio volumio[1087]: info: CorePlayQueue::getTrack 0
May 11 18:21:56 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
May 11 18:21:56 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
May 11 18:21:59 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:21:59 volumio volumio[1087]: info: CorePlayQueue::getTrack 0
May 11 18:22:00 volumio sudo[26868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 11 18:22:00 volumio sudo[26868]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 11 18:22:00 volumio sudo[26868]: pam_unix(sudo:session): session closed for user root
May 11 18:22:00 volumio sudo[26871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 11 18:22:00 volumio sudo[26871]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 11 18:22:00 volumio sudo[26871]: pam_unix(sudo:session): session closed for user root
May 11 18:22:00 volumio volumio[1087]: verbose: New Socket.io Connection to 192.168.1.157 from 192.168.1.192 UA: Mozilla/5.0 (Linux; Android 14; XQ-CT72 Build/64.2.A.2.244; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8
May 11 18:22:00 volumio sudo[26874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 11 18:22:00 volumio sudo[26874]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 11 18:22:00 volumio sudo[26874]: pam_unix(sudo:session): session closed for user root
May 11 18:22:00 volumio sudo[26877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 11 18:22:00 volumio sudo[26877]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 11 18:22:00 volumio sudo[26877]: pam_unix(sudo:session): session closed for user root
May 11 18:22:00 volumio volumio[1087]: verbose: New Socket.io Connection to 192.168.1.157 from 192.168.1.192 UA: Mozilla/5.0 (Linux; Android 14; XQ-CT72 Build/64.2.A.2.244; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9
May 11 18:22:00 volumio volumio[1087]: info: Executing endpoint metavolumio
May 11 18:22:00 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 11 18:22:00 volumio volumio[1087]: info: Executing endpoint metavolumio
May 11 18:22:00 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 11 18:22:00 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 11 18:22:01 volumio volumio[1087]: info: CoreCommandRouter::volumioGetVisibleSources
May 11 18:22:01 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 11 18:22:01 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:01 volumio volumio[1087]: info: CorePlayQueue::getTrack 0
May 11 18:22:01 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 11 18:22:01 volumio volumio[1087]: info: CoreCommandRouter::volumioGetQueue
May 11 18:22:01 volumio volumio[1087]: info: CoreStateMachine::getQueue
May 11 18:22:01 volumio volumio[1087]: info: CorePlayQueue::getQueue
May 11 18:22:01 volumio volumio[1087]: info: Listing playlists
May 11 18:22:01 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 11 18:22:01 volumio volumio[1087]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom
May 11 18:22:01 volumio volumio[1087]: info: Received Get System Info
May 11 18:22:01 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 11 18:22:01 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 11 18:22:01 volumio volumio[1087]: info: Discovery: Getting this device information
May 11 18:22:01 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:01 volumio volumio[1087]: info: CorePlayQueue::getTrack 0
May 11 18:22:01 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 11 18:22:01 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:01 volumio volumio[1087]: info: CorePlayQueue::getTrack 0
May 11 18:22:01 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
May 11 18:22:01 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 11 18:22:01 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
May 11 18:22:02 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
May 11 18:22:02 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 11 18:22:02 volumio volumio[1087]: info: Received Get System Info
May 11 18:22:02 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 11 18:22:02 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 11 18:22:02 volumio volumio[1087]: info: Discovery: Getting this device information
May 11 18:22:02 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:02 volumio volumio[1087]: info: CorePlayQueue::getTrack 0
May 11 18:22:02 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 11 18:22:02 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
May 11 18:22:05 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 11 18:22:05 volumio volumio[1087]: info: Received Get System Info
May 11 18:22:05 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 11 18:22:05 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 11 18:22:05 volumio volumio[1087]: info: Discovery: Getting this device information
May 11 18:22:05 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:05 volumio volumio[1087]: info: CorePlayQueue::getTrack 0
May 11 18:22:05 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 11 18:22:07 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 11 18:22:07 volumio volumio[1087]: info: Received Get System Info
May 11 18:22:07 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 11 18:22:07 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 11 18:22:07 volumio volumio[1087]: info: Discovery: Getting this device information
May 11 18:22:07 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:07 volumio volumio[1087]: info: CorePlayQueue::getTrack 0
May 11 18:22:07 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 11 18:22:07 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri
May 11 18:22:07 volumio volumio[1087]: error: Failed request for metavolumio API
May 11 18:22:07 volumio volumio[1087]: error: Failed request for metavolumio API
May 11 18:22:08 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 11 18:22:08 volumio volumio[1087]: info: Received Get System Info
May 11 18:22:08 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 11 18:22:08 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 11 18:22:08 volumio volumio[1087]: info: Discovery: Getting this device information
May 11 18:22:08 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:08 volumio volumio[1087]: info: CorePlayQueue::getTrack 0
May 11 18:22:08 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 11 18:22:10 volumio volumio[1087]: info: handleBrowseUri took 2953 milliseconds
May 11 18:22:10 volumio volumio[1087]: info: Preload queue cleared
May 11 18:22:12 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 18:22:15 volumio qobuz-connect[19280]: 20250511 18:22:15.953 [19280.26949] INFO SampleApp: API endpoint invoked: get-display-info
May 11 18:22:15 volumio qobuz-connect[19280]: 20250511 18:22:15.962 [19280.26949] INFO SampleApp: API endpoint invoked: get-connect-info
May 11 18:22:17 volumio qobuz-connect[19280]: 20250511 18:22:17.834 [19280.26949] INFO SampleApp: API endpoint invoked: connect-to-qconnect
May 11 18:22:17 volumio qobuz-connect[19280]: 20250511 18:22:17.834 [19280.19280] INFO EndpointManager: [0xc31d30]: Updating API endpoint
May 11 18:22:17 volumio qobuz-connect[19280]: 20250511 18:22:17.834 [19280.19280] INFO EndpointManager: [0xc31d30]: Updating QConnect endpoint
May 11 18:22:17 volumio qobuz-connect[19280]: 20250511 18:22:17.834 [19280.19280] INFO ActiveStateManager: [0xc30d48]: Setting new active state: active
May 11 18:22:17 volumio qobuz-connect[19280]: 20250511 18:22:17.834 [19280.19280] INFO PlaybackSessionManager: [0xc32120]: Starting playback session maintenance
May 11 18:22:17 volumio qobuz-connect[19280]: 20250511 18:22:17.834 [19280.19280] INFO HttpDownloader: [0xc32310]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start
May 11 18:22:17 volumio qobuz-connect[19280]: 20250511 18:22:17.835 [19280.19280] INFO CloudClient: [0xc32990]: Connecting to the cloud
May 11 18:22:17 volumio qobuz-connect[19280]: 20250511 18:22:17.835 [19280.19280] INFO SampleApp: Renderer is now active
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.310 [19280.19280] INFO PlaybackSessionManager: [0xc32120]: Playback session has been refreshed
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.465 [19280.19280] INFO CloudClient: [0xc32990]: Connection established
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.465 [19280.19280] INFO QwspMessageSender: [0xd8aa50]: Sending Authenticate message
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.465 [19280.19280] INFO QwspMessageSender: [0xd8aa50]: Sending Subscribe message
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.465 [19280.19280] INFO QConnectMessageSender: [0xc6e2c8]: Sending JoinSession message
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.465 [19280.19280] INFO QConnectMessageSender: [0xc6e2c8]: Sending VolumeChanged message
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.465 [19280.19280] INFO QConnectMessageSender: [0xc6e2c8]: Sending VolumeMuted message
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.465 [19280.19280] INFO QConnectMessageSender: [0xc6e2c8]: Sending MaxAudioQualityChanged message
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.465 [19280.19280] INFO QwspMessageSender: [0xd8aa50]: Sending Payload message
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.646 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Received SetActive message: active
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.646 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Received SetState message:
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.646 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Playing state: Paused
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.646 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Playback position: 0
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.646 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Queue version: 25.1
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.646 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Current track: TID: 84888574, QID: 0, Context UUID: 7b3dae33-58c6-4f6e-afb5-8f1c12914fae
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.646 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Next track: TID: 84888575, QID: 1, Context UUID: 7b3dae33-58c6-4f6e-afb5-8f1c12914fae
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.646 [19280.19280] INFO MediaEngine: [0xc323b8]: Stopping playback, clearing tracks
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.646 [19280.19280] INFO MediaEngine: [0xc323b8]: Initiating playback
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.646 [19280.19280] INFO RendererActionAvailabilityManager: [0xc32a48]: Renderer action 'Next' is available
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.646 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Received SetLoopMode message: Off
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.646 [19280.19280] INFO PlaybackControlsManager: [0xc32790]: Setting new loop mode: Off
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.646 [19280.19280] INFO MediaEngine: [0xc323b8]: Setting current track: 84888574, initial offset: 0ms
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.646 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Clearing all streams
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.646 [19280.19280] INFO AudioStreamManager: [0xc324a0]: New stream: 1
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.646 [19280.19280] INFO HttpDownloader: [0xd0e308]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=b7020f2981a5e5bfc40934793babcef3&request_ts=1746951738&track_id=84888574
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.647 [19280.19280] INFO HttpDownloader: [0xc38d60]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=84888574
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.647 [19280.19280] INFO HttpDownloader: [0xc3baf8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=5b80f9133d6473c769ee16f0c90d3077&request_ts=1746951738&track_id=84888574
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.647 [19280.19280] INFO AudioStreamManager: [0xc324a0]: [Stream 1]: Running audio stream
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.647 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Received SetShuffleMode message: disabled
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.647 [19280.19280] INFO PlaybackControlsManager: [0xc32790]: Setting new shuffle mode: disabled
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.647 [19280.19280] INFO MediaEngine: [0xc323b8]: Setting next track: 84888575
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.647 [19280.19280] INFO AudioStreamManager: [0xc324a0]: New stream: 2
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.647 [19280.19280] INFO HttpDownloader: [0xc3cab8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=81359160d4a63f387ec7eed471e8c000&request_ts=1746951738&track_id=84888575
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.647 [19280.19280] INFO HttpDownloader: [0xc3dab0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=84888575
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.647 [19280.19280] INFO HttpDownloader: [0xc3dd60]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=64b1239a4cd363d031a92de8b2073b44&request_ts=1746951738&track_id=84888575
May 11 18:22:18 volumio qobuz-connect[19280]: 20250511 18:22:18.647 [19280.19280] INFO MediaEngine: [0xc323b8]: Waiting for current stream to start before starting audio renderer
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.004 [19280.19280] INFO AudioStreamManager: [0xc324a0]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1755722&eid=84888575&fmt=7&profile=raw&app_id=174516466&cid=1948503&etsp=1746955338&hmac=dvVPgp2cyXjWi2rGuWhE_RAegv8
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.031 [19280.19280] INFO AudioStreamManager: [0xc324a0]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1755722&eid=84888574&fmt=7&profile=raw&app_id=174516466&cid=1948503&etsp=1746955338&hmac=oDrIjHohWUHEzXE4TA_7k9Lc7yU
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.098 [19280.19280] INFO AudioStreamManager: [0xc324a0]: [Stream 2]: Metadata became available:
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.098 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Title: Silent Genesis
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.098 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Artist: Pure Reason Revolution
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.098 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Album: Eupnea
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.098 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Album art URL: https://static.qobuz.com/images/covers/hc/my/uncjn7wjjmyhc_600.jpg
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.132 [19280.19280] INFO AudioStreamManager: [0xc324a0]: [Stream 1]: stream information have been fetched
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.132 [19280.19280] INFO UrlAudioSource: [0xda5ec0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1755722&eid=84888574&fmt=7&profile=raw&app_id=174516466&cid=1948503&etsp=1746955338&hmac=oDrIjHohWUHEzXE4TA_7k9Lc7yU
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.132 [19280.19280] INFO ContentFetcher: [0xc3d278]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1755722&eid=84888574&fmt=7&profile=raw&app_id=174516466&cid=1948503&etsp=1746955338&hmac=oDrIjHohWUHEzXE4TA_7k9Lc7yU, offset: 0
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.133 [19280.19280] INFO AudioRenderer: [0xc325d8]: Starting audio renderer, initial playback state: Paused
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.133 [19280.19280] INFO SampleApp: [Stream 1]: New audio stream (starting from 0ms)
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio volumio[1087]: info: CorePlayQueue::getTrack 0
May 11 18:22:19 volumio volumio[1087]: info: QobuzConnect: setActiveState()
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio volumio[1087]: info: CorePlayQueue::getTrack 0
May 11 18:22:19 volumio volumio[1087]: info: QobuzConnect: Set Qobuz connect default volume to 100
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.137 [19280.19280] INFO VolumeManager: [0xc32820]: Setting new playback volume: 100
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio volumio[1087]: info: CorePlayQueue::getTrack 0
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio volumio[1087]: info: CorePlayQueue::getTrack 0
May 11 18:22:19 volumio volumio[1087]: info: QobuzConnect: Stopping currently active service
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioStop
May 11 18:22:19 volumio volumio[1087]: info: CoreStateMachine::stop
May 11 18:22:19 volumio volumio[1087]: info: CoreStateMachine::setConsumeUpdateService undefined
May 11 18:22:19 volumio volumio[1087]: info: CoreStateMachine::setConsumeUpdateService undefined
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::servicePushState
May 11 18:22:19 volumio volumio[1087]: info: CoreStateMachine::pushState
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioPushState
May 11 18:22:19 volumio volumio[1087]: info: MRS: Pushing multiroomSync output update for this device
May 11 18:22:19 volumio volumio[1087]: info: MRS: Pushing multiroomSync output
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio volumio[1087]: info: CorePlayQueue::getTrack 0
May 11 18:22:19 volumio volumio[1087]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received qobuzconnect
May 11 18:22:19 volumio volumio[1087]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect
May 11 18:22:19 volumio volumio[1087]: info: QobuzConnect: setActiveState() completed, send playback confirmation
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.147 [19280.19280] INFO SampleApp: Initiating playback, initial state: Paused
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.171 [19280.19280] ERROR SampleApp: Unable to pause ALSA audio sink: Function not implemented (-38)
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.171 [19280.19280] ERROR AudioRenderer: [0xc325d8]: Playback error occurred
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.171 [19280.19280] INFO AudioStreamManager: [0xc324a0]: [Stream 2]: stream information have been fetched
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.171 [19280.19280] INFO QConnectMessageSender: [0xc6e2c8]: Sending PlaybackError message
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.171 [19280.19280] INFO QwspMessageSender: [0xd8aa50]: Sending Payload message
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.171 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Need to send state update
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.171 [19280.19280] INFO QConnectMessageSender: [0xc6e2c8]: Sending StateUpdated message
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.171 [19280.19280] INFO QwspMessageSender: [0xd8aa50]: Sending Payload message
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error!
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.226 [19280.19280] INFO AudioStreamManager: [0xc324a0]: [Stream 1]: Metadata became available:
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.226 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Title: New Obsession
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.226 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Artist: Pure Reason Revolution
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.226 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Album: Eupnea
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.226 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Album art URL: https://static.qobuz.com/images/covers/hc/my/uncjn7wjjmyhc_600.jpg
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.226 [19280.19280] INFO SampleApp: [Stream 1]: Stream metadata became available:
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.226 [19280.19280] INFO SampleApp: Title: New Obsession
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.226 [19280.19280] INFO SampleApp: Artist: Pure Reason Revolution
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.226 [19280.19280] INFO SampleApp: Album: Eupnea
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.226 [19280.19280] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/hc/my/uncjn7wjjmyhc_600.jpg
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.287 [19280.19280] INFO SampleApp: Playback volume changed: 100
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.287 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Volume changed: 100
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.287 [19280.19280] INFO QConnectMessageSender: [0xc6e2c8]: Sending VolumeChanged message
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.287 [19280.19280] INFO QwspMessageSender: [0xd8aa50]: Sending Payload message
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.352 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Received SetState message:
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.352 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Playing state: Playing
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.352 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Playback position: 0
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.352 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Queue version: 25.1
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.352 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Current track: TID: 84888575, QID: 1, Context UUID: 7b3dae33-58c6-4f6e-afb5-8f1c12914fae
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.352 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Next track: TID: 84888576, QID: 2, Context UUID: 7b3dae33-58c6-4f6e-afb5-8f1c12914fae
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.352 [19280.19280] INFO MediaEngine: [0xc323b8]: Reseting
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.352 [19280.19280] INFO MediaEngine: [0xc323b8]: Stopping playback, clearing tracks
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.352 [19280.19280] INFO MediaEngine: [0xc323b8]: Initiating playback
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.352 [19280.19280] INFO AudioRenderer: [0xc325d8]: Reseting audio renderer
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.353 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Need to send state update
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.353 [19280.19280] INFO QConnectMessageSender: [0xc6e2c8]: Sending StateUpdated message
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.353 [19280.19280] INFO QwspMessageSender: [0xd8aa50]: Sending Payload message
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.353 [19280.19280] INFO MediaEngine: [0xc323b8]: Setting current track: 84888575, initial offset: 0ms
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.353 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Clearing all streams
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.353 [19280.19280] INFO AudioStreamManager: [0xc324a0]: [Stream 1]: Disposing of audio stream
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.353 [19280.19280] INFO ContentFetcher: [0xc3d278]: Canceling download
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.355 [19280.19280] INFO AudioStreamManager: [0xc324a0]: New stream: 3
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.355 [19280.19280] INFO HttpDownloader: [0xc3cab8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=c97abdc8c4bd20ec2bb055d32679beab&request_ts=1746951739&track_id=84888575
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.356 [19280.19280] INFO HttpDownloader: [0xd0e308]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=84888575
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.356 [19280.19280] INFO HttpDownloader: [0xc6e240]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=05a3c4acc008779224883ef7e287cd8f&request_ts=1746951739&track_id=84888575
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.356 [19280.19280] INFO AudioStreamManager: [0xc324a0]: [Stream 3]: Running audio stream
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.357 [19280.19280] INFO SampleApp: [Stream 1]: Audio stream has been disposed of
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.357 [19280.19280] INFO MediaEngine: [0xc323b8]: Setting next track: 84888576
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.357 [19280.19280] INFO AudioStreamManager: [0xc324a0]: New stream: 4
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.357 [19280.19280] INFO HttpDownloader: [0xe19670]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=4ba946306110826b2ed2b758a0012a30&request_ts=1746951739&track_id=84888576
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.357 [19280.19280] INFO HttpDownloader: [0xea1180]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=84888576
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.357 [19280.19280] INFO HttpDownloader: [0xe07de8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=4705e43a835fcdf7af1d03e293e0eee5&request_ts=1746951739&track_id=84888576
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.358 [19280.19280] INFO MediaEngine: [0xc323b8]: Waiting for current stream to start before starting audio renderer
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.707 [19280.19280] INFO AudioStreamManager: [0xc324a0]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1755722&eid=84888575&fmt=7&profile=raw&app_id=174516466&cid=1948503&etsp=1746955339&hmac=1EJgh-IxyHk3kFWB3nRQScrcR20
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.713 [19280.19280] INFO AudioStreamManager: [0xc324a0]: [Stream 4]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1755722&eid=84888576&fmt=7&profile=raw&app_id=174516466&cid=1948503&etsp=1746955339&hmac=48uJ2wuRo8Wp_weF0jmV5X32--w
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.756 [19280.19280] INFO AudioStreamManager: [0xc324a0]: [Stream 3]: stream information have been fetched
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.756 [19280.19280] INFO UrlAudioSource: [0xea6d98]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1755722&eid=84888575&fmt=7&profile=raw&app_id=174516466&cid=1948503&etsp=1746955339&hmac=1EJgh-IxyHk3kFWB3nRQScrcR20
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.756 [19280.19280] INFO ContentFetcher: [0xe9ef40]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1755722&eid=84888575&fmt=7&profile=raw&app_id=174516466&cid=1948503&etsp=1746955339&hmac=1EJgh-IxyHk3kFWB3nRQScrcR20, offset: 0
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.757 [19280.19280] INFO AudioRenderer: [0xc325d8]: Starting audio renderer, initial playback state: Playing
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.757 [19280.19280] INFO SampleApp: [Stream 3]: New audio stream (starting from 0ms)
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio volumio[1087]: info: QobuzConnect: setActiveState()
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio volumio[1087]: info: QobuzConnect: Set Qobuz connect default volume to 100
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.758 [19280.19280] INFO VolumeManager: [0xc32820]: Setting new playback volume: 100
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio volumio[1087]: info: QobuzConnect: Stopping currently active service
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioStop
May 11 18:22:19 volumio volumio[1087]: info: CoreStateMachine::stop
May 11 18:22:19 volumio volumio[1087]: info: CoreStateMachine::serviceStop
May 11 18:22:19 volumio volumio[1087]: info: Received STOP, but no service to execute it
May 11 18:22:19 volumio volumio[1087]: info: CoreStateMachine::setConsumeUpdateService undefined
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::servicePushState
May 11 18:22:19 volumio volumio[1087]: info: CoreStateMachine::pushState
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioPushState
May 11 18:22:19 volumio volumio[1087]: info: MRS: Pushing multiroomSync output update for this device
May 11 18:22:19 volumio volumio[1087]: info: MRS: Pushing multiroomSync output
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio volumio[1087]: info: CorePlayQueue::getTrack 0
May 11 18:22:19 volumio volumio[1087]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received qobuzconnect
May 11 18:22:19 volumio volumio[1087]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect
May 11 18:22:19 volumio volumio[1087]: info: QobuzConnect: setActiveState() completed, send playback confirmation
May 11 18:22:19 volumio volumio[1087]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.leEm1qGi6wei4RlLGaoLUJtP4Nh2.4db5a56a73e2fbd2900fa37de5bafda5.state.status'
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.790 [19280.19280] INFO SampleApp: Initiating playback, initial state: Playing
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.802 [19280.19280] INFO SampleApp: Playback state changed: Playing
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.802 [19280.19280] INFO StreamingEventReporter: [0xc32f10]: Reporting streaming started event
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.802 [19280.19280] INFO HttpDownloader: [0xc32f68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.803 [19280.19280] INFO ProtocolHandler: [0xc32af8]: Need to send state update
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.803 [19280.19280] INFO QConnectMessageSender: [0xc6e2c8]: Sending StateUpdated message
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.803 [19280.19280] INFO QwspMessageSender: [0xd8aa50]: Sending Payload message
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.803 [19280.19280] INFO QConnectMessageSender: [0xc6e2c8]: Sending FileAudioQualityChanged message
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.803 [19280.19280] INFO QwspMessageSender: [0xd8aa50]: Sending Payload message
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.803 [19280.19280] INFO QConnectMessageSender: [0xc6e2c8]: Sending DeviceAudioQualityChanged message
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::servicePushState
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.804 [19280.19280] INFO QwspMessageSender: [0xd8aa50]: Sending Payload message
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.804 [19280.19280] INFO AudioStreamManager: [0xc324a0]: [Stream 4]: Metadata became available:
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.804 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Title: Maelstrom
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.804 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Artist: Pure Reason Revolution
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.804 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Album: Eupnea
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.804 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Album art URL: https://static.qobuz.com/images/covers/hc/my/uncjn7wjjmyhc_600.jpg
May 11 18:22:19 volumio volumio[1087]: info: CoreStateMachine::pushState
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioPushState
May 11 18:22:19 volumio volumio[1087]: info: MRS: Pushing multiroomSync output update for this device
May 11 18:22:19 volumio volumio[1087]: info: MRS: Pushing multiroomSync output
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.808 [19280.19280] INFO VolumeManager: [0xc32820]: Setting new playback volume: 100
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.808 [19280.19280] INFO VolumeManager: [0xc32820]: Setting new mute state: 0
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::servicePushState
May 11 18:22:19 volumio volumio[1087]: info: CoreStateMachine::pushState
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioPushState
May 11 18:22:19 volumio volumio[1087]: info: MRS: Pushing multiroomSync output update for this device
May 11 18:22:19 volumio volumio[1087]: info: MRS: Pushing multiroomSync output
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::servicePushState
May 11 18:22:19 volumio volumio[1087]: info: CoreStateMachine::pushState
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioPushState
May 11 18:22:19 volumio volumio[1087]: info: MRS: Pushing multiroomSync output update for this device
May 11 18:22:19 volumio volumio[1087]: info: MRS: Pushing multiroomSync output
May 11 18:22:19 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
May 11 18:22:19 volumio volumio[1087]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title!
May 11 18:22:19 volumio volumio[1087]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.826 [19280.19280] INFO AudioStreamManager: [0xc324a0]: [Stream 4]: stream information have been fetched
May 11 18:22:19 volumio volumio[1087]: TypeError: Cannot read property 'indexOf' of undefined
May 11 18:22:19 volumio volumio[1087]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25)
May 11 18:22:19 volumio volumio[1087]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26)
May 11 18:22:19 volumio volumio[1087]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52)
May 11 18:22:19 volumio volumio[1087]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20)
May 11 18:22:19 volumio volumio[1087]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10)
May 11 18:22:19 volumio volumio[1087]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12)
May 11 18:22:19 volumio volumio[1087]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15)
May 11 18:22:19 volumio volumio[1087]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20)
May 11 18:22:19 volumio volumio[1087]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8)
May 11 18:22:19 volumio volumio[1087]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15)
May 11 18:22:19 volumio volumio[1087]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20)
May 11 18:22:19 volumio volumio[1087]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12)
May 11 18:22:19 volumio volumio[1087]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16)
May 11 18:22:19 volumio volumio[1087]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15)
May 11 18:22:19 volumio volumio[1087]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20)
May 11 18:22:19 volumio volumio[1087]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14)
May 11 18:22:19 volumio volumio[1087]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.930 [19280.19280] INFO AudioStreamManager: [0xc324a0]: [Stream 3]: Metadata became available:
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.930 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Title: Silent Genesis
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.930 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Artist: Pure Reason Revolution
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.930 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Album: Eupnea
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.930 [19280.19280] INFO AudioStreamManager: [0xc324a0]: Album art URL: https://static.qobuz.com/images/covers/hc/my/uncjn7wjjmyhc_600.jpg
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.930 [19280.19280] INFO SampleApp: [Stream 3]: Stream metadata became available:
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.930 [19280.19280] INFO SampleApp: Title: Silent Genesis
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.930 [19280.19280] INFO SampleApp: Artist: Pure Reason Revolution
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.930 [19280.19280] INFO SampleApp: Album: Eupnea
May 11 18:22:19 volumio qobuz-connect[19280]: 20250511 18:22:19.930 [19280.19280] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/hc/my/uncjn7wjjmyhc_600.jpg
May 11 18:22:20 volumio sudo[26963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-11 18:21
May 11 18:22:20 volumio sudo[26963]: 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="147953990fca4aa103a8f90538ef56fb0454638c"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST"
VOLUMIO_VERSION="3.807"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"