-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Mon 2025-05-19 10:02:38 BST. -- May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.174 [1449.1449] INFO ProtocolHandler: [0x1fe5f50]: Received SetState message: May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.174 [1449.1449] INFO ProtocolHandler: [0x1fe5f50]: Playing state: Playing May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.174 [1449.1449] INFO ProtocolHandler: [0x1fe5f50]: Playback position: 0 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.174 [1449.1449] INFO ProtocolHandler: [0x1fe5f50]: Queue version: 3.1 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.174 [1449.1449] INFO ProtocolHandler: [0x1fe5f50]: Current track: TID: 2342091, QID: 0, Context UUID: 967090c9-b7d7-4efa-a13e-0adb4bde90e8 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.174 [1449.1449] INFO ProtocolHandler: [0x1fe5f50]: Next track: TID: 2342092, QID: 1, Context UUID: 967090c9-b7d7-4efa-a13e-0adb4bde90e8 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.174 [1449.1449] INFO MediaEngine: [0x1fe5810]: Stopping playback, clearing tracks May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.174 [1449.1449] INFO MediaEngine: [0x1fe5810]: Initiating playback May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.174 [1449.1449] INFO AudioRenderer: [0x1fe5a30]: Stopping audio renderer May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.174 [1449.1449] INFO SampleApp: Stopping playback May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.191 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: Clearing all streams May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.191 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: [Stream 21]: Disposing of audio stream May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.191 [1449.1449] INFO ContentFetcher: [0x23a4860]: Canceling download May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.191 [1449.1449] INFO SampleApp: Playback state changed: Stopped May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.191 [1449.1449] INFO ProtocolHandler: [0x1fe5f50]: Need to send state update May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.191 [1449.1449] INFO QConnectMessageSender: [0x2171950]: Sending StateUpdated message May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.191 [1449.1449] INFO SampleApp: [Stream 21]: Audio stream has been disposed of May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.192 [1449.1449] INFO MediaEngine: [0x1fe5810]: Setting current track: 2342091, initial offset: 0ms May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.192 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: Clearing all streams May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.192 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: New stream: 23 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.192 [1449.1449] INFO HttpDownloader: [0x20d5028]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=8c9b6494b0b10dc6f276be715542998a&request_ts=1747645358&track_id=2342091 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.192 [1449.1449] INFO HttpDownloader: [0x22daee8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=2342091 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.192 [1449.1449] INFO HttpDownloader: [0x22e1660]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=43bbb4b5df81259ff63ea465ef7bdcd4&request_ts=1747645358&track_id=2342091 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.192 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: [Stream 23]: Running audio stream May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.192 [1449.1449] INFO QwspMessageSender: [0x2171940]: Sending Payload message May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioGetState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::servicePushState May 19 10:02:38 officeaudio volumio[841]: info: CoreStateMachine::pushState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioPushState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioGetState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::servicePushState May 19 10:02:38 officeaudio volumio[841]: info: CoreStateMachine::pushState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioPushState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioGetState May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.196 [1449.1449] INFO MediaEngine: [0x1fe5810]: Setting next track: 2342092 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.196 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: New stream: 24 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.196 [1449.1449] INFO HttpDownloader: [0x2215548]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=bd3d5720898f825f747cb8c023d19af7&request_ts=1747645358&track_id=2342092 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.196 [1449.1449] INFO HttpDownloader: [0x20dccc8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=2342092 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.196 [1449.1449] INFO HttpDownloader: [0x20ee448]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=b20ee66348adf70e0986902c1fd6d48e&request_ts=1747645358&track_id=2342092 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.197 [1449.1449] INFO MediaEngine: [0x1fe5810]: Waiting for current stream to start before starting audio renderer May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.432 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: [Stream 24]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=2342092&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1747648958&hmac=nivtapTXM2xylJVcm54CMFsCvNY May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.526 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: [Stream 23]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=2342091&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1747648958&hmac=kZErW5bMO6evArshH1Tb0QSZ_zY May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.537 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: [Stream 23]: stream information have been fetched May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.537 [1449.1449] INFO UrlAudioSource: [0x23a3170]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=2342091&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1747648958&hmac=kZErW5bMO6evArshH1Tb0QSZ_zY May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.537 [1449.1449] INFO ContentFetcher: [0x2084f70]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=2342091&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1747648958&hmac=kZErW5bMO6evArshH1Tb0QSZ_zY, offset: 0 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.537 [1449.1449] INFO AudioRenderer: [0x1fe5a30]: Starting audio renderer, initial playback state: Playing May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.537 [1449.1449] INFO SampleApp: [Stream 23]: New audio stream (starting from 0ms) May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioGetState May 19 10:02:38 officeaudio volumio[841]: info: QobuzConnect: alread currrent service, send playback confirmation May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.538 [1449.1449] INFO SampleApp: Initiating playback, initial state: Playing May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.561 [1449.1449] INFO SampleApp: Playback state changed: Playing May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioGetState May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.561 [1449.1449] INFO StreamingEventReporter: [0x1fe6368]: Reporting streaming started event May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.562 [1449.1449] INFO HttpDownloader: [0x1fe63c0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.562 [1449.1449] INFO ProtocolHandler: [0x1fe5f50]: Need to send state update May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.562 [1449.1449] INFO QConnectMessageSender: [0x2171950]: Sending StateUpdated message May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.562 [1449.1449] INFO QwspMessageSender: [0x2171940]: Sending Payload message May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.562 [1449.1449] INFO QConnectMessageSender: [0x2171950]: Sending FileAudioQualityChanged message May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.562 [1449.1449] INFO QwspMessageSender: [0x2171940]: Sending Payload message May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.562 [1449.1449] INFO QConnectMessageSender: [0x2171950]: Sending DeviceAudioQualityChanged message May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.562 [1449.1449] INFO QwspMessageSender: [0x2171940]: Sending Payload message May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::servicePushState May 19 10:02:38 officeaudio volumio[841]: info: CoreStateMachine::pushState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioPushState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioGetState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::servicePushState May 19 10:02:38 officeaudio volumio[841]: info: CoreStateMachine::pushState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioPushState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioGetState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioGetState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::servicePushState May 19 10:02:38 officeaudio volumio[841]: info: CoreStateMachine::pushState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioPushState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioGetState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioGetState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::servicePushState May 19 10:02:38 officeaudio volumio[841]: info: CoreStateMachine::pushState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioPushState May 19 10:02:38 officeaudio volumio[841]: info: CoreCommandRouter::volumioGetState May 19 10:02:38 officeaudio volumio[841]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! May 19 10:02:38 officeaudio volumio[841]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 19 10:02:38 officeaudio volumio[841]: TypeError: Cannot read property 'indexOf' of undefined May 19 10:02:38 officeaudio volumio[841]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) May 19 10:02:38 officeaudio volumio[841]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) May 19 10:02:38 officeaudio volumio[841]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) May 19 10:02:38 officeaudio volumio[841]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 19 10:02:38 officeaudio volumio[841]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) May 19 10:02:38 officeaudio volumio[841]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) May 19 10:02:38 officeaudio volumio[841]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 19 10:02:38 officeaudio volumio[841]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 19 10:02:38 officeaudio volumio[841]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) May 19 10:02:38 officeaudio volumio[841]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 19 10:02:38 officeaudio volumio[841]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) May 19 10:02:38 officeaudio volumio[841]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) May 19 10:02:38 officeaudio volumio[841]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) May 19 10:02:38 officeaudio volumio[841]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 19 10:02:38 officeaudio volumio[841]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 19 10:02:38 officeaudio volumio[841]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) May 19 10:02:38 officeaudio volumio[841]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.644 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: [Stream 23]: Metadata became available: May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.644 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: Title: Vertigo May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.644 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: Artist: The Libertines May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.644 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: Album: Up The Bracket May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.644 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: Album art URL: https://static.qobuz.com/images/covers/61/12/0609008271261_600.jpg May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.644 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: [Stream 24]: Metadata became available: May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.644 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: Title: Death on the Stairs May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.644 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: Artist: The Libertines May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.644 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: Album: Up The Bracket May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.644 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: Album art URL: https://static.qobuz.com/images/covers/61/12/0609008271261_600.jpg May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.644 [1449.1449] INFO AudioStreamManager: [0x1fe58f8]: [Stream 24]: stream information have been fetched May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.644 [1449.1449] INFO SampleApp: [Stream 23]: Stream metadata became available: May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.644 [1449.1449] INFO SampleApp: Title: Vertigo May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.644 [1449.1449] INFO SampleApp: Artist: The Libertines May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.644 [1449.1449] INFO SampleApp: Album: Up The Bracket May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.645 [1449.1449] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/61/12/0609008271261_600.jpg May 19 10:02:38 officeaudio sudo[22156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-19 10:01 May 19 10:02:38 officeaudio sudo[22156]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.933 [1449.1449] INFO UrlAudioSource: [0x23a3170]: Content info extracted May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.933 [1449.1449] INFO UrlAudioSource: [0x23a3170]: Size: 21611776 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.935 [1449.22158] INFO AudioDecoder: [0x20133b8]: Stream info: May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.935 [1449.22158] INFO AudioDecoder: [0x20133b8]: Total samples: 6973092 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.935 [1449.22158] INFO AudioDecoder: [0x20133b8]: Sample rate: 44100 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.935 [1449.22158] INFO AudioDecoder: [0x20133b8]: Channel count: 2 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.935 [1449.22158] INFO AudioDecoder: [0x20133b8]: Bits per sample: 16 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.935 [1449.22158] INFO AudioDecoder: [0x20133b8]: Duration: 158120 May 19 10:02:38 officeaudio qobuz-connect[1449]: 20250519 10:02:38.936 [1449.1449] INFO UrlAudioSource: [0x23a3170]: Audio properties have changed 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"