-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Wed 2025-06-04 08:12:36 BST. -- Jun 04 08:11:00 officeaudio sudo[1412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jun 04 08:11:00 officeaudio sudo[1412]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 08:11:00 officeaudio systemd[1]: Started MyVolumio SSH Tunnel. Jun 04 08:11:00 officeaudio sudo[1412]: pam_unix(sudo:session): session closed for user root Jun 04 08:11:00 officeaudio volumio[847]: info: Remote SSH Started Jun 04 08:11:00 officeaudio autossh[1415]: port set to 0, monitoring disabled Jun 04 08:11:00 officeaudio autossh[1415]: starting ssh (count 1) Jun 04 08:11:00 officeaudio autossh[1415]: ssh child pid is 1418 Jun 04 08:11:00 officeaudio volumio[847]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 04 08:11:00 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:11:00 officeaudio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 04 08:11:00 officeaudio volumiossh-tunnel[1414]: Warning: Permanently added '[eu3.myvolumio.org]:2222,[167.71.136.233]:2222' (RSA) to the list of known hosts. Jun 04 08:11:04 officeaudio volumio[847]: info: BOOT COMPLETED Jun 04 08:11:08 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 04 08:11:08 officeaudio volumio[847]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 04 08:11:08 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:11:08 officeaudio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 04 08:11:13 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 08:11:13 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 08:11:13 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 04 08:11:13 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:11:13 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:11:13 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 04 08:11:13 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 04 08:11:13 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 04 08:11:13 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetBrowseSources Jun 04 08:11:13 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 08:11:13 officeaudio volumio-remote-updater[603]: No test mode Jun 04 08:11:13 officeaudio volumio-remote-updater[603]: No alpha test mode Jun 04 08:11:13 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 04 08:11:13 officeaudio volumio[847]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 04 08:11:13 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 04 08:11:15 officeaudio systemd[1]: systemd-timedated.service: Succeeded. Jun 04 08:11:34 officeaudio ntpd[815]: Soliciting pool server 139.162.219.252 Jun 04 08:11:35 officeaudio ntpd[815]: Soliciting pool server 178.62.68.79 Jun 04 08:11:35 officeaudio ntpd[815]: Soliciting pool server 193.57.144.50 Jun 04 08:11:36 officeaudio ntpd[815]: Soliciting pool server 217.154.60.177 Jun 04 08:11:36 officeaudio ntpd[815]: Soliciting pool server 149.22.188.7 Jun 04 08:11:36 officeaudio ntpd[815]: Soliciting pool server 131.111.8.60 Jun 04 08:11:37 officeaudio ntpd[815]: Soliciting pool server 178.215.228.24 Jun 04 08:11:37 officeaudio ntpd[815]: Soliciting pool server 178.62.250.107 Jun 04 08:11:37 officeaudio ntpd[815]: Soliciting pool server 141.147.71.134 Jun 04 08:11:38 officeaudio ntpd[815]: Soliciting pool server 95.215.175.2 Jun 04 08:11:38 officeaudio ntpd[815]: Soliciting pool server 139.143.5.31 Jun 04 08:11:38 officeaudio ntpd[815]: Soliciting pool server 162.159.200.1 Jun 04 08:11:39 officeaudio ntpd[815]: Soliciting pool server 85.199.214.102 Jun 04 08:11:39 officeaudio ntpd[815]: Soliciting pool server 85.199.214.100 Jun 04 08:11:39 officeaudio ntpd[815]: Soliciting pool server 109.74.206.120 Jun 04 08:11:39 officeaudio ntpd[815]: Soliciting pool server 185.83.169.27 Jun 04 08:11:39 officeaudio qobuz-connect[1378]: 20250604 08:11:39.464 [1378.1524] INFO SampleApp: API endpoint invoked: get-display-info Jun 04 08:11:39 officeaudio qobuz-connect[1378]: 20250604 08:11:39.505 [1378.1524] INFO SampleApp: API endpoint invoked: get-connect-info Jun 04 08:11:40 officeaudio ntpd[815]: Soliciting pool server 95.131.83.40 Jun 04 08:11:40 officeaudio ntpd[815]: Soliciting pool server 2606:4700:f1::1 Jun 04 08:11:40 officeaudio ntpd[815]: Soliciting pool server 80.87.128.222 Jun 04 08:11:40 officeaudio ntpd[815]: Soliciting pool server 176.58.115.34 Jun 04 08:11:42 officeaudio ntpd[815]: receive: Unexpected origin timestamp 0xebea702d.30af58fc does not match aorg 0000000000.00000000 from server@141.147.71.134 xmt 0xebea702e.6c5f99cb Jun 04 08:11:42 officeaudio ntpd[815]: receive: Unexpected origin timestamp 0xebea702d.30acb267 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xebea702e.6c23ddea Jun 04 08:11:42 officeaudio ntpd[815]: receive: Unexpected origin timestamp 0xebea702d.30b05629 does not match aorg 0000000000.00000000 from server@149.22.188.7 xmt 0xebea702e.6c85f710 Jun 04 08:11:42 officeaudio ntpd[815]: receive: Unexpected origin timestamp 0xebea702d.30b1451f does not match aorg 0000000000.00000000 from server@217.154.60.177 xmt 0xebea702e.6d05f57e Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.522 [1378.1524] INFO SampleApp: API endpoint invoked: connect-to-qconnect Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.523 [1378.1378] INFO EndpointManager: [0x902100]: Updating API endpoint Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.523 [1378.1378] INFO EndpointManager: [0x902100]: Updating QConnect endpoint Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.523 [1378.1378] INFO ActiveStateManager: [0x901118]: Setting new active state: active Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.523 [1378.1378] INFO PlaybackSessionManager: [0x9024f0]: Starting playback session maintenance Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.523 [1378.1378] INFO HttpDownloader: [0x9026e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.523 [1378.1378] INFO CloudClient: [0x902d60]: Connecting to the cloud Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.524 [1378.1378] INFO SampleApp: Renderer is now active Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.660 [1378.1378] INFO CloudClient: [0x902d60]: Connection established Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.660 [1378.1378] INFO QwspMessageSender: [0xa0d050]: Sending Authenticate message Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.660 [1378.1378] INFO QwspMessageSender: [0xa0d050]: Sending Subscribe message Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.660 [1378.1378] INFO QConnectMessageSender: [0x942ac0]: Sending JoinSession message Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.660 [1378.1378] INFO QConnectMessageSender: [0x942ac0]: Sending VolumeChanged message Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.660 [1378.1378] INFO QConnectMessageSender: [0x942ac0]: Sending VolumeMuted message Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.660 [1378.1378] INFO QConnectMessageSender: [0x942ac0]: Sending MaxAudioQualityChanged message Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.661 [1378.1378] INFO QwspMessageSender: [0xa0d050]: Sending Payload message Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.688 [1378.1378] INFO PlaybackSessionManager: [0x9024f0]: Playback session has been refreshed Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.693 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Received SetActive message: active Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.693 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Received SetState message: Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.693 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Playing state: Stopped Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.693 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Playback position: Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.693 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Queue version: 0.0 Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.693 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Current track: TID: 4294967295, QID: -1, Context UUID: Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.693 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Next track: TID: 4294967295, QID: -1, Context UUID: Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.693 [1378.1378] INFO MediaEngine: [0x902788]: Stopping playback, clearing tracks Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.693 [1378.1378] INFO MediaEngine: [0x902788]: Stopping playback, keeping tracks Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.693 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Received SetLoopMode message: Off Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.693 [1378.1378] INFO PlaybackControlsManager: [0x902b60]: Setting new loop mode: Off Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.693 [1378.1378] INFO MediaEngine: [0x902788]: Setting current track: NONE Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.693 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Received SetShuffleMode message: disabled Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.693 [1378.1378] INFO PlaybackControlsManager: [0x902b60]: Setting new shuffle mode: disabled Jun 04 08:11:42 officeaudio qobuz-connect[1378]: 20250604 08:11:42.693 [1378.1378] INFO MediaEngine: [0x902788]: Setting next track: NONE Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.049 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Received SetState message: Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.049 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Playing state: Playing Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.049 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Playback position: 0 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.050 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Queue version: 1.1 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.050 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Current track: TID: 3417134, QID: 0, Context UUID: 4c853d72-2192-4c09-b5a5-62e9174b39b1 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.050 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Next track: TID: 307603, QID: 1, Context UUID: 4c853d72-2192-4c09-b5a5-62e9174b39b1 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.050 [1378.1378] INFO MediaEngine: [0x902788]: Stopping playback, clearing tracks Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.050 [1378.1378] INFO MediaEngine: [0x902788]: Initiating playback Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.050 [1378.1378] INFO RendererActionAvailabilityManager: [0x902e18]: Renderer action 'Next' is available Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.050 [1378.1378] INFO MediaEngine: [0x902788]: Setting current track: 3417134, initial offset: 0ms Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.050 [1378.1378] INFO AudioStreamManager: [0x902870]: Clearing all streams Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.050 [1378.1378] INFO AudioStreamManager: [0x902870]: New stream: 1 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.050 [1378.1378] INFO HttpDownloader: [0x906508]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=6ee9ac2f3e50d416c24a02cf5048c029&request_ts=1749021156&track_id=3417134 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.050 [1378.1378] INFO HttpDownloader: [0x90ea28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=3417134 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.050 [1378.1378] INFO HttpDownloader: [0x908fe8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=9d4b723b5dcaa2342ba1bd857ddb40e7&request_ts=1749021156&track_id=3417134 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.050 [1378.1378] INFO AudioStreamManager: [0x902870]: [Stream 1]: Running audio stream Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.051 [1378.1378] INFO MediaEngine: [0x902788]: Setting next track: 307603 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.051 [1378.1378] INFO AudioStreamManager: [0x902870]: New stream: 2 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.051 [1378.1378] INFO HttpDownloader: [0x9e3138]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=53d4633b3064b8dc41abc0bf31c755d9&request_ts=1749021156&track_id=307603 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.051 [1378.1378] INFO HttpDownloader: [0x9e2d68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=307603 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.051 [1378.1378] INFO HttpDownloader: [0x9e4ff8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=1ee7a274a3ab913f5a1d66278cd3ae2b&request_ts=1749021156&track_id=307603 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.051 [1378.1378] INFO MediaEngine: [0x902788]: Waiting for current stream to start before starting audio renderer Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.177 [1378.1378] INFO AudioStreamManager: [0x902870]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=3417134&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1749024756&hmac=INWMnQAzmlNa-iUsyrFVnr4fwT0 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.179 [1378.1378] INFO AudioStreamManager: [0x902870]: [Stream 1]: stream information have been fetched Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.179 [1378.1378] INFO UrlAudioSource: [0x90b1a8]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=3417134&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1749024756&hmac=INWMnQAzmlNa-iUsyrFVnr4fwT0 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.179 [1378.1378] INFO ContentFetcher: [0x90b2d0]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=3417134&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1749024756&hmac=INWMnQAzmlNa-iUsyrFVnr4fwT0, offset: 0 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.179 [1378.1378] INFO AudioRenderer: [0x9029a8]: Starting audio renderer, initial playback state: Playing Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.179 [1378.1378] INFO SampleApp: [Stream 1]: New audio stream (starting from 0ms) Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:12:36 officeaudio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 04 08:12:36 officeaudio volumio[847]: info: QobuzConnect: setActiveState() Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:12:36 officeaudio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 04 08:12:36 officeaudio volumio[847]: info: QobuzConnect: Set Qobuz connect default volume to 100 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.183 [1378.1378] INFO VolumeManager: [0x902bf0]: Setting new playback volume: 100 Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:12:36 officeaudio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:12:36 officeaudio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 04 08:12:36 officeaudio volumio[847]: info: QobuzConnect: Stopping currently active service Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioStop Jun 04 08:12:36 officeaudio volumio[847]: info: CoreStateMachine::stop Jun 04 08:12:36 officeaudio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 08:12:36 officeaudio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::servicePushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreStateMachine::pushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:12:36 officeaudio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 04 08:12:36 officeaudio volumio[847]: verbose: STATE SERVICE {"status":"stop","service":"qobuzconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"qobuz","codec":"","seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false} Jun 04 08:12:36 officeaudio volumio[847]: verbose: CURRENT POSITION 0 Jun 04 08:12:36 officeaudio volumio[847]: info: CoreStateMachine::syncState stateService stop Jun 04 08:12:36 officeaudio volumio[847]: info: CoreStateMachine::syncState currentStatus stop Jun 04 08:12:36 officeaudio volumio[847]: info: CoreStateMachine::pushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:12:36 officeaudio volumio[847]: info: No code Jun 04 08:12:36 officeaudio volumio[847]: info: CoreStateMachine::pushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:12:36 officeaudio volumio[847]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.202 [1378.1378] INFO AudioStreamManager: [0x902870]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=307603&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1749024756&hmac=kPSv-zmm-OPtiM-yfoct50QLtEM Jun 04 08:12:36 officeaudio volumio[847]: info: QobuzConnect: setActiveState() completed, send playback confirmation Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.209 [1378.1378] INFO SampleApp: Initiating playback, initial state: Playing Jun 04 08:12:36 officeaudio volumio[847]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.DxASekcaG4NsRgMkpegFO0SKvXm1.e6c411213a9543bd8f367a6506fb851e.state.status' Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.262 [1378.1378] INFO SampleApp: Playback state changed: Playing Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.263 [1378.1378] INFO StreamingEventReporter: [0x9032e0]: Reporting streaming started event Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.263 [1378.1378] INFO HttpDownloader: [0x903338]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.264 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Need to send state update Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.264 [1378.1378] INFO QConnectMessageSender: [0x942ac0]: Sending StateUpdated message Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::servicePushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreStateMachine::pushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::servicePushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreStateMachine::pushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::servicePushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreStateMachine::pushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.278 [1378.1378] INFO QwspMessageSender: [0xa0d050]: Sending Payload message Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.278 [1378.1378] INFO QConnectMessageSender: [0x942ac0]: Sending FileAudioQualityChanged message Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.278 [1378.1378] INFO VolumeManager: [0x902bf0]: Setting new playback volume: 100 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.278 [1378.1378] INFO QwspMessageSender: [0xa0d050]: Sending Payload message Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.278 [1378.1378] INFO QConnectMessageSender: [0x942ac0]: Sending DeviceAudioQualityChanged message Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.278 [1378.1378] INFO QwspMessageSender: [0xa0d050]: Sending Payload message Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.279 [1378.1378] INFO AudioStreamManager: [0x902870]: [Stream 1]: Metadata became available: Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.279 [1378.1378] INFO AudioStreamManager: [0x902870]: Title: Layla (40th Anniversary Version / 2010 Remastered) Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.279 [1378.1378] INFO AudioStreamManager: [0x902870]: Artist: Derek & The Dominos Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.279 [1378.1378] INFO AudioStreamManager: [0x902870]: Album: Layla And Other Assorted Love Songs (40th Anniversary Version / 2010 Remastered) Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.279 [1378.1378] INFO AudioStreamManager: [0x902870]: Album art URL: https://static.qobuz.com/images/covers/48/30/0060075333048_600.jpg Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.279 [1378.1378] INFO AudioStreamManager: [0x902870]: [Stream 2]: Metadata became available: Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.279 [1378.1378] INFO AudioStreamManager: [0x902870]: Title: All Along the Watchtower Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.279 [1378.1378] INFO AudioStreamManager: [0x902870]: Artist: Jimi Hendrix Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.279 [1378.1378] INFO AudioStreamManager: [0x902870]: Album: Experience Hendrix: The Best Of Jimi Hendrix Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.279 [1378.1378] INFO AudioStreamManager: [0x902870]: Album art URL: https://static.qobuz.com/images/covers/78/38/0884977413878_600.jpg Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.279 [1378.1378] INFO AudioStreamManager: [0x902870]: [Stream 2]: stream information have been fetched Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.279 [1378.1378] INFO SampleApp: [Stream 1]: Stream metadata became available: Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.279 [1378.1378] INFO SampleApp: Title: Layla (40th Anniversary Version / 2010 Remastered) Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.279 [1378.1378] INFO SampleApp: Artist: Derek & The Dominos Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.279 [1378.1378] INFO SampleApp: Album: Layla And Other Assorted Love Songs (40th Anniversary Version / 2010 Remastered) Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.279 [1378.1378] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/48/30/0060075333048_600.jpg Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::servicePushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreStateMachine::pushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 04 08:12:36 officeaudio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.298 [1378.1378] INFO UrlAudioSource: [0x90b1a8]: Content info extracted Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.298 [1378.1378] INFO UrlAudioSource: [0x90b1a8]: Size: 51220106 Jun 04 08:12:36 officeaudio volumio[847]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Jun 04 08:12:36 officeaudio volumio[847]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.304 [1378.1645] INFO AudioDecoder: [0xb8a1d0]: Stream info: Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.304 [1378.1645] INFO AudioDecoder: [0xb8a1d0]: Total samples: 18691344 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.304 [1378.1645] INFO AudioDecoder: [0xb8a1d0]: Sample rate: 44100 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.304 [1378.1645] INFO AudioDecoder: [0xb8a1d0]: Channel count: 2 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.304 [1378.1645] INFO AudioDecoder: [0xb8a1d0]: Bits per sample: 16 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.304 [1378.1645] INFO AudioDecoder: [0xb8a1d0]: Duration: 423840 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.305 [1378.1378] INFO UrlAudioSource: [0x90b1a8]: Audio properties have changed Jun 04 08:12:36 officeaudio volumio[847]: TypeError: Cannot read property 'indexOf' of undefined Jun 04 08:12:36 officeaudio volumio[847]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Jun 04 08:12:36 officeaudio volumio[847]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Jun 04 08:12:36 officeaudio volumio[847]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Jun 04 08:12:36 officeaudio volumio[847]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 04 08:12:36 officeaudio volumio[847]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Jun 04 08:12:36 officeaudio volumio[847]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Jun 04 08:12:36 officeaudio volumio[847]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 04 08:12:36 officeaudio volumio[847]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 04 08:12:36 officeaudio volumio[847]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Jun 04 08:12:36 officeaudio volumio[847]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 04 08:12:36 officeaudio volumio[847]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Jun 04 08:12:36 officeaudio volumio[847]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Jun 04 08:12:36 officeaudio volumio[847]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Jun 04 08:12:36 officeaudio volumio[847]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 04 08:12:36 officeaudio volumio[847]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 04 08:12:36 officeaudio volumio[847]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Jun 04 08:12:36 officeaudio volumio[847]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.333 [1378.1378] INFO SampleApp: Playback volume changed: 100 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.333 [1378.1378] INFO ProtocolHandler: [0x902ec8]: Volume changed: 100 Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.333 [1378.1378] INFO QConnectMessageSender: [0x942ac0]: Sending VolumeChanged message Jun 04 08:12:36 officeaudio qobuz-connect[1378]: 20250604 08:12:36.334 [1378.1378] INFO QwspMessageSender: [0xa0d050]: Sending Payload message Jun 04 08:12:36 officeaudio sudo[1669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-04 08:11 Jun 04 08:12:36 officeaudio sudo[1669]: 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"