-- Logs begin at Fri 2026-04-03 17:52:43 EDT, end at Fri 2026-04-03 18:54:17 EDT. -- Apr 03 18:53:04 volumio-salon qobuz-connect[1471]: 20260403 18:53:04.188 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Need to send state update Apr 03 18:53:04 volumio-salon qobuz-connect[1471]: 20260403 18:53:04.188 [1471.1471] INFO QConnectMessageSender: [0x18b72a8]: Sending StateUpdated message Apr 03 18:53:04 volumio-salon qobuz-connect[1471]: 20260403 18:53:04.188 [1471.1471] INFO QwspMessageSender: [0x18b7298]: Sending Payload message Apr 03 18:53:04 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:53:04 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:53:04 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:53:04 volumio-salon volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 18:53:04 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:53:04 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:53:04 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:53:04.195-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PLAYING positionMs=180003 volume=100 Apr 03 18:53:04 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:53:04.196-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title="Go Down Moses" Apr 03 18:53:04 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: play Apr 03 18:53:04 volumio-salon volumio[889]: info: [ASDebug] CurState: play PrevState: play Apr 03 18:53:06 volumio-salon kernel: hwmon hwmon1: Undervoltage detected! Apr 03 18:53:11 volumio-salon kernel: hwmon hwmon1: Voltage normalised Apr 03 18:53:14 volumio-salon qobuz-connect[1471]: 20260403 18:53:14.189 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Need to send state update Apr 03 18:53:14 volumio-salon qobuz-connect[1471]: 20260403 18:53:14.189 [1471.1471] INFO QConnectMessageSender: [0x18b72a8]: Sending StateUpdated message Apr 03 18:53:14 volumio-salon qobuz-connect[1471]: 20260403 18:53:14.189 [1471.1471] INFO QwspMessageSender: [0x18b7298]: Sending Payload message Apr 03 18:53:14 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:53:14 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:53:14 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:53:14 volumio-salon volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 18:53:14 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:53:14 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:53:14 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:53:14.197-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PLAYING positionMs=190005 volume=100 Apr 03 18:53:14 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:53:14.198-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title="Go Down Moses" Apr 03 18:53:14 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: play Apr 03 18:53:14 volumio-salon volumio[889]: info: [ASDebug] CurState: play PrevState: play Apr 03 18:53:24 volumio-salon qobuz-connect[1471]: 20260403 18:53:24.190 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Need to send state update Apr 03 18:53:24 volumio-salon qobuz-connect[1471]: 20260403 18:53:24.190 [1471.1471] INFO QConnectMessageSender: [0x18b72a8]: Sending StateUpdated message Apr 03 18:53:24 volumio-salon qobuz-connect[1471]: 20260403 18:53:24.190 [1471.1471] INFO QwspMessageSender: [0x18b7298]: Sending Payload message Apr 03 18:53:24 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:53:24 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:53:24 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:53:24 volumio-salon volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 18:53:24 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:53:24 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:53:24 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:53:24.197-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PLAYING positionMs=200005 volume=100 Apr 03 18:53:24 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:53:24.198-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title="Go Down Moses" Apr 03 18:53:24 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: play Apr 03 18:53:24 volumio-salon volumio[889]: info: [ASDebug] CurState: play PrevState: play Apr 03 18:53:34 volumio-salon qobuz-connect[1471]: 20260403 18:53:34.191 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Need to send state update Apr 03 18:53:34 volumio-salon qobuz-connect[1471]: 20260403 18:53:34.191 [1471.1471] INFO QConnectMessageSender: [0x18b72a8]: Sending StateUpdated message Apr 03 18:53:34 volumio-salon qobuz-connect[1471]: 20260403 18:53:34.191 [1471.1471] INFO QwspMessageSender: [0x18b7298]: Sending Payload message Apr 03 18:53:34 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:53:34 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:53:34 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:53:34 volumio-salon volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 18:53:34 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:53:34 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:53:34 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:53:34.201-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PLAYING positionMs=210006 volume=100 Apr 03 18:53:34 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:53:34.204-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title="Go Down Moses" Apr 03 18:53:34 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: play Apr 03 18:53:34 volumio-salon volumio[889]: info: [ASDebug] CurState: play PrevState: play Apr 03 18:53:36 volumio-salon kernel: hwmon hwmon1: Undervoltage detected! Apr 03 18:53:42 volumio-salon kernel: hwmon hwmon1: Voltage normalised Apr 03 18:53:44 volumio-salon qobuz-connect[1471]: 20260403 18:53:44.191 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Need to send state update Apr 03 18:53:44 volumio-salon qobuz-connect[1471]: 20260403 18:53:44.191 [1471.1471] INFO QConnectMessageSender: [0x18b72a8]: Sending StateUpdated message Apr 03 18:53:44 volumio-salon qobuz-connect[1471]: 20260403 18:53:44.191 [1471.1471] INFO QwspMessageSender: [0x18b7298]: Sending Payload message Apr 03 18:53:44 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:53:44 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:53:44 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:53:44 volumio-salon volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 18:53:44 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:53:44 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:53:44 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:53:44.198-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PLAYING positionMs=220005 volume=100 Apr 03 18:53:44 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:53:44.199-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title="Go Down Moses" Apr 03 18:53:44 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: play Apr 03 18:53:44 volumio-salon volumio[889]: info: [ASDebug] CurState: play PrevState: play Apr 03 18:53:52 volumio-salon volumio[889]: error: [LastFM] plugin is not authenticated, please retry Apr 03 18:53:54 volumio-salon qobuz-connect[1471]: 20260403 18:53:54.191 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Need to send state update Apr 03 18:53:54 volumio-salon qobuz-connect[1471]: 20260403 18:53:54.191 [1471.1471] INFO QConnectMessageSender: [0x18b72a8]: Sending StateUpdated message Apr 03 18:53:54 volumio-salon qobuz-connect[1471]: 20260403 18:53:54.191 [1471.1471] INFO QwspMessageSender: [0x18b7298]: Sending Payload message Apr 03 18:53:54 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:53:54 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:53:54 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:53:54 volumio-salon volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 18:53:54 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:53:54 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:53:54 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:53:54.198-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PLAYING positionMs=230006 volume=100 Apr 03 18:53:54 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:53:54.199-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title="Go Down Moses" Apr 03 18:53:54 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: play Apr 03 18:53:54 volumio-salon volumio[889]: info: [ASDebug] CurState: play PrevState: play Apr 03 18:53:56 volumio-salon volumiologrotate[633]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 03 18:53:56 volumio-salon volumiologrotate[633]: ls: cannot access 'SALON': No such file or directory Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.192 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Need to send state update Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.192 [1471.1471] INFO QConnectMessageSender: [0x18b72a8]: Sending StateUpdated message Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.192 [1471.1471] INFO QwspMessageSender: [0x18b7298]: Sending Payload message Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:04 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:04.201-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PLAYING positionMs=240007 volume=100 Apr 03 18:54:04 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:04.202-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title="Go Down Moses" Apr 03 18:54:04 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: play Apr 03 18:54:04 volumio-salon volumio[889]: info: [ASDebug] CurState: play PrevState: play Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.901 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Received SetState message: Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.901 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Playing state: Playing Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.901 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Playback position: 0 Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.901 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Queue version: 1.2 Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.901 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Current track: TID: 47010809, QID: 23, Context UUID: c525be35-cbe9-4bee-a54b-294b7ef75284 Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.901 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Next track: TID: 44615376, QID: 24, Context UUID: c525be35-cbe9-4bee-a54b-294b7ef75284 Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.901 [1471.1471] INFO MediaEngine: [0x172c548]: Stopping playback, clearing tracks Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.901 [1471.1471] INFO MediaEngine: [0x172c548]: Initiating playback Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.901 [1471.1471] INFO AudioRenderer: [0x172c6b0]: Stopping audio renderer Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.901 [1471.1471] INFO SampleApp: Stopping playback Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.904 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Clearing all streams Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.904 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: [Stream 15]: Disposing of audio stream Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.904 [1471.1471] INFO ContentFetcher: [0x1c40fa8]: Canceling download Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.911 [1471.1471] INFO SampleApp: Playback state changed: Stopped Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.911 [1471.1471] INFO StreamingEventReporter: [0x172ced0]: Reporting streaming ended event Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.913 [1471.1471] INFO HttpDownloader: [0x172cf28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.913 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Need to send state update Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.913 [1471.1471] INFO QConnectMessageSender: [0x18b72a8]: Sending StateUpdated message Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.914 [1471.1471] INFO QwspMessageSender: [0x18b7298]: Sending Payload message Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:04 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:04.923-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PAUSED positionMs=240007 volume=100 Apr 03 18:54:04 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:04.923-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title="Go Down Moses" Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:54:04 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.951 [1471.1471] INFO SampleApp: [Stream 15]: Audio stream has been disposed of Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.951 [1471.1471] INFO MediaEngine: [0x172c548]: Setting current track: 47010809, initial offset: 0ms Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.951 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Clearing all streams Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.951 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: New stream: 17 Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.952 [1471.1471] INFO HttpDownloader: [0x1a21f00]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=4e8c60ee516082fadaf17f00a76df926&request_ts=1775256844&track_id=47010809 Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.952 [1471.1471] INFO HttpDownloader: [0x1741220]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=47010809 Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.952 [1471.1471] INFO HttpDownloader: [0x180e3e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=0a4d2fbed47d286f67e80606a40d61bd&request_ts=1775256844&track_id=47010809 Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.952 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: [Stream 17]: Running audio stream Apr 03 18:54:04 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:04.952-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PAUSED positionMs=0 volume=100 Apr 03 18:54:04 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:04.952-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title="Go Down Moses" Apr 03 18:54:04 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: pause Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.954 [1471.1471] INFO MediaEngine: [0x172c548]: Setting next track: 44615376 Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.954 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: New stream: 18 Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.954 [1471.1471] INFO HttpDownloader: [0x1845988]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=32b3ca6d55a7086048c43480dbcf697a&request_ts=1775256844&track_id=44615376 Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.954 [1471.1471] INFO HttpDownloader: [0x1847aa0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=44615376 Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.954 [1471.1471] INFO HttpDownloader: [0x187b8d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=fdcb779d1510e6250cae0b65f69be935&request_ts=1775256844&track_id=44615376 Apr 03 18:54:04 volumio-salon qobuz-connect[1471]: 20260403 18:54:04.954 [1471.1471] INFO MediaEngine: [0x172c548]: Waiting for current stream to start before starting audio renderer Apr 03 18:54:04 volumio-salon volumio[889]: info: [GPIO_Control] Handling event: musicPause Apr 03 18:54:04 volumio-salon volumio[889]: info: [GPIO_Control] *** musicPause *** Apr 03 18:54:04 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: pause Apr 03 18:54:04 volumio-salon volumio[889]: info: [ASDebug] CurState: pause PrevState: play Apr 03 18:54:04 volumio-salon volumio[889]: info: [ASDebug] InitTimeout - Amp off in: 60000 ms Apr 03 18:54:04 volumio-salon volumio[889]: info: [ASDebug] CurState: pause PrevState: play Apr 03 18:54:04 volumio-salon volumio[889]: info: [ASDebug] InitTimeout - Amp off in: 60000 ms Apr 03 18:54:04 volumio-salon volumio[889]: info: [GPIO_Control] Turning GPIO 6 Off (musicPause) Apr 03 18:54:05 volumio-salon qobuz-connect[1471]: 20260403 18:54:05.865 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: [Stream 17]: stream information have been fetched Apr 03 18:54:05 volumio-salon qobuz-connect[1471]: 20260403 18:54:05.964 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: [Stream 17]: Metadata became available: Apr 03 18:54:05 volumio-salon qobuz-connect[1471]: 20260403 18:54:05.964 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Title: strange (2023 Remaster) Apr 03 18:54:05 volumio-salon qobuz-connect[1471]: 20260403 18:54:05.964 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Artist: Tori Amos Apr 03 18:54:05 volumio-salon qobuz-connect[1471]: 20260403 18:54:05.964 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Album: Scarlet's Walk Apr 03 18:54:05 volumio-salon qobuz-connect[1471]: 20260403 18:54:05.964 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Album art URL: https://static.qobuz.com/images/covers/hb/e7/g775anjv7e7hb_600.jpg Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.062 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: [Stream 18]: stream information have been fetched Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.074 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: [Stream 17]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2487270&eid=47010809&fmt=7&profile=raw&app_id=174516466&cid=1726506&etsp=1775260445&hmac=Pulw7-2liljP4UHf3t-jskBOmS8 Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.074 [1471.1471] INFO UrlAudioSource: [0x1730208]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2487270&eid=47010809&fmt=7&profile=raw&app_id=174516466&cid=1726506&etsp=1775260445&hmac=Pulw7-2liljP4UHf3t-jskBOmS8 Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.074 [1471.1471] INFO ContentFetcher: [0x1732180]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2487270&eid=47010809&fmt=7&profile=raw&app_id=174516466&cid=1726506&etsp=1775260445&hmac=Pulw7-2liljP4UHf3t-jskBOmS8, offset: 0 Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.075 [1471.1471] INFO AudioRenderer: [0x172c6b0]: Starting audio renderer, initial playback state: Playing Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.075 [1471.1471] INFO SampleApp: [Stream 17]: New audio stream (starting from 0ms) Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.075 [1471.1471] INFO SampleApp: [Stream 17]: Stream metadata became available: Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.075 [1471.1471] INFO SampleApp: Title: strange (2023 Remaster) Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.075 [1471.1471] INFO SampleApp: Artist: Tori Amos Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.075 [1471.1471] INFO SampleApp: Album: Scarlet's Walk Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.076 [1471.1471] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/hb/e7/g775anjv7e7hb_600.jpg Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.079 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: [Stream 18]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2487270&eid=44615376&fmt=7&profile=raw&app_id=174516466&cid=1726506&etsp=1775260445&hmac=II40JFbfNQb3f2gUK_1t0aYJhDo Apr 03 18:54:06 volumio-salon volumio[889]: info: QobuzConnect: alread currrent service, send playback confirmation Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.085 [1471.1471] INFO SampleApp: Initiating playback, initial state: Playing Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.116 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: [Stream 18]: Metadata became available: Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.116 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Title: Burning Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.116 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Artist: Sam Smith Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.116 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Album: The Thrill Of It All Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.116 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Album art URL: https://static.qobuz.com/images/covers/97/51/0060256715197_600.jpg Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.116 [1471.1471] INFO SampleApp: Playback state changed: Playing Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.116 [1471.1471] INFO StreamingEventReporter: [0x172ced0]: Reporting streaming started event Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.116 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Need to send state update Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.116 [1471.1471] INFO QConnectMessageSender: [0x18b72a8]: Sending StateUpdated message Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.116 [1471.1471] INFO QwspMessageSender: [0x18b7298]: Sending Payload message Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.117 [1471.1471] INFO QConnectMessageSender: [0x18b72a8]: Sending FileAudioQualityChanged message Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.117 [1471.1471] INFO QwspMessageSender: [0x18b7298]: Sending Payload message Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.117 [1471.1471] INFO QConnectMessageSender: [0x18b72a8]: Sending DeviceAudioQualityChanged message Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.118 [1471.1471] INFO QwspMessageSender: [0x18b7298]: Sending Payload message Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:06 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:06.135-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 18:54:06 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:06.136-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title="strange (2023 Remaster)" Apr 03 18:54:06 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:06.137-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PAUSED positionMs=0 volume=100 Apr 03 18:54:06 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:06.137-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title="strange (2023 Remaster)" Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:54:06 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:06 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:06.156-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 18:54:06 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:06.157-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title="strange (2023 Remaster)" Apr 03 18:54:06 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:06.157-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 18:54:06 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:06.158-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title="strange (2023 Remaster)" Apr 03 18:54:06 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: pause Apr 03 18:54:06 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: play Apr 03 18:54:06 volumio-salon volumio[889]: info: [GPIO_Control] Handling event: musicPlay Apr 03 18:54:06 volumio-salon volumio[889]: info: [GPIO_Control] *** musicPlay *** Apr 03 18:54:06 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: play Apr 03 18:54:06 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: play Apr 03 18:54:06 volumio-salon volumio[889]: info: [LastFM] Current track has sufficient metadata: title (strange (2023 Remaster)) and artist (Tori Amos) passed on explicitly Apr 03 18:54:06 volumio-salon volumio[889]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Apr 03 18:54:06 volumio-salon volumio[889]: info: [ASDebug] CurState: pause PrevState: play Apr 03 18:54:06 volumio-salon volumio[889]: info: [ASDebug] InitTimeout - Amp off in: 60000 ms Apr 03 18:54:06 volumio-salon volumio[889]: info: [ASDebug] CurState: play PrevState: play Apr 03 18:54:06 volumio-salon volumio[889]: info: [ASDebug] CurState: play PrevState: play Apr 03 18:54:06 volumio-salon volumio[889]: info: [ASDebug] CurState: play PrevState: play Apr 03 18:54:06 volumio-salon volumio[889]: info: [GPIO_Control] Turning GPIO 6 On (musicPlay) Apr 03 18:54:06 volumio-salon qobuz-connect[1471]: 20260403 18:54:06.221 [1471.1471] INFO HttpDownloader: [0x172cf28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Apr 03 18:54:07 volumio-salon qobuz-connect[1471]: 20260403 18:54:07.159 [1471.1471] INFO UrlAudioSource: [0x1730208]: Content info extracted Apr 03 18:54:07 volumio-salon qobuz-connect[1471]: 20260403 18:54:07.159 [1471.1471] INFO UrlAudioSource: [0x1730208]: Size: 68016315 Apr 03 18:54:07 volumio-salon qobuz-connect[1471]: 20260403 18:54:07.159 [1471.10661] INFO AudioDecoder: [0x1d59a68]: Stream info: Apr 03 18:54:07 volumio-salon qobuz-connect[1471]: 20260403 18:54:07.159 [1471.10661] INFO AudioDecoder: [0x1d59a68]: Total samples: 17972480 Apr 03 18:54:07 volumio-salon qobuz-connect[1471]: 20260403 18:54:07.159 [1471.10661] INFO AudioDecoder: [0x1d59a68]: Sample rate: 96000 Apr 03 18:54:07 volumio-salon qobuz-connect[1471]: 20260403 18:54:07.159 [1471.10661] INFO AudioDecoder: [0x1d59a68]: Channel count: 2 Apr 03 18:54:07 volumio-salon qobuz-connect[1471]: 20260403 18:54:07.159 [1471.10661] INFO AudioDecoder: [0x1d59a68]: Bits per sample: 24 Apr 03 18:54:07 volumio-salon qobuz-connect[1471]: 20260403 18:54:07.160 [1471.10661] INFO AudioDecoder: [0x1d59a68]: Duration: 187213 Apr 03 18:54:07 volumio-salon qobuz-connect[1471]: 20260403 18:54:07.160 [1471.1471] INFO UrlAudioSource: [0x1730208]: Audio properties have changed Apr 03 18:54:07 volumio-salon kernel: hwmon hwmon1: Undervoltage detected! Apr 03 18:54:11 volumio-salon kernel: hwmon hwmon1: Voltage normalised Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.963 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Received SetState message: Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.963 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Playing state: Playing Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.963 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Playback position: 0 Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.963 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Queue version: 1.2 Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.963 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Current track: TID: 44615376, QID: 24, Context UUID: c525be35-cbe9-4bee-a54b-294b7ef75284 Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.963 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Next track: TID: 292514894, QID: 2, Context UUID: c525be35-cbe9-4bee-a54b-294b7ef75284 Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.963 [1471.1471] INFO MediaEngine: [0x172c548]: Stopping playback, clearing tracks Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.963 [1471.1471] INFO MediaEngine: [0x172c548]: Initiating playback Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.963 [1471.1471] INFO AudioRenderer: [0x172c6b0]: Stopping audio renderer Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.963 [1471.1471] INFO SampleApp: Stopping playback Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.964 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Clearing all streams Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.964 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: [Stream 17]: Disposing of audio stream Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.964 [1471.1471] INFO ContentFetcher: [0x1732180]: Canceling download Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.971 [1471.1471] INFO SampleApp: Playback state changed: Stopped Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.971 [1471.1471] INFO StreamingEventReporter: [0x172ced0]: Reporting streaming ended event Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.971 [1471.1471] INFO HttpDownloader: [0x172cf28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.972 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Need to send state update Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.972 [1471.1471] INFO QConnectMessageSender: [0x18b72a8]: Sending StateUpdated message Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.972 [1471.1471] INFO QwspMessageSender: [0x18b7298]: Sending Payload message Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.973 [1471.1471] INFO SampleApp: [Stream 17]: Audio stream has been disposed of Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.973 [1471.1471] INFO MediaEngine: [0x172c548]: Setting current track: 44615376, initial offset: 0ms Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.973 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Clearing all streams Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.973 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: New stream: 19 Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.973 [1471.1471] INFO HttpDownloader: [0x1766a58]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=7e6998f4929737cde8540f4a02228fd9&request_ts=1775256855&track_id=44615376 Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.973 [1471.1471] INFO HttpDownloader: [0x1741220]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=44615376 Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.973 [1471.1471] INFO HttpDownloader: [0x180e3e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=99ee0e5ded66362b91d2de74e528d2ea&request_ts=1775256855&track_id=44615376 Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.973 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: [Stream 19]: Running audio stream Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.973 [1471.1471] INFO MediaEngine: [0x172c548]: Setting next track: 292514894 Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.973 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: New stream: 20 Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.973 [1471.1471] INFO HttpDownloader: [0x18fe890]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=681981cd9a2aa9b4aa27e11a2fc4d32d&request_ts=1775256855&track_id=292514894 Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.973 [1471.1471] INFO HttpDownloader: [0x1aace20]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=292514894 Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.973 [1471.1471] INFO HttpDownloader: [0x17d3628]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=1c6752f7511a33e20958411eeb2244db&request_ts=1775256855&track_id=292514894 Apr 03 18:54:15 volumio-salon qobuz-connect[1471]: 20260403 18:54:15.973 [1471.1471] INFO MediaEngine: [0x172c548]: Waiting for current stream to start before starting audio renderer Apr 03 18:54:15 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:15 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:54:15 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:54:15 volumio-salon volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 18:54:15 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:54:15 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:15 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:15.983-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PAUSED positionMs=0 volume=100 Apr 03 18:54:15 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:15.983-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title="strange (2023 Remaster)" Apr 03 18:54:15 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:15 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:54:15 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:54:15 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:54:15 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:15 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:15.994-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PAUSED positionMs=0 volume=100 Apr 03 18:54:15 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:15.994-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title="strange (2023 Remaster)" Apr 03 18:54:15 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: pause Apr 03 18:54:15 volumio-salon volumio[889]: info: [GPIO_Control] Handling event: musicPause Apr 03 18:54:15 volumio-salon volumio[889]: info: [GPIO_Control] *** musicPause *** Apr 03 18:54:15 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: pause Apr 03 18:54:16 volumio-salon volumio[889]: info: [ASDebug] CurState: pause PrevState: play Apr 03 18:54:16 volumio-salon volumio[889]: info: [ASDebug] InitTimeout - Amp off in: 60000 ms Apr 03 18:54:16 volumio-salon volumio[889]: info: [ASDebug] CurState: pause PrevState: play Apr 03 18:54:16 volumio-salon volumio[889]: info: [ASDebug] InitTimeout - Amp off in: 60000 ms Apr 03 18:54:16 volumio-salon volumio[889]: info: [GPIO_Control] Turning GPIO 6 Off (musicPause) Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.429 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: [Stream 19]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2487270&eid=44615376&fmt=7&profile=raw&app_id=174516466&cid=1726506&etsp=1775260456&hmac=Jf2RA35WdJUXiMIEF6iHzJBcp_M Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.430 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: [Stream 20]: Metadata became available: Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.430 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Title: Mama kijk ik kan het zelf Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.430 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Artist: Bente Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.430 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Album: drift Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.430 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Album art URL: https://static.qobuz.com/images/covers/3b/xo/bnhay9eg1xo3b_600.jpg Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.590 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: [Stream 20]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2487270&eid=292514894&fmt=7&profile=raw&app_id=174516466&cid=1726506&etsp=1775260456&hmac=ca-UcBlEHwvEkiNYNWtCP981xmg Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.593 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: [Stream 20]: stream information have been fetched Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.793 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: [Stream 19]: stream information have been fetched Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.793 [1471.1471] INFO UrlAudioSource: [0x17309c8]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2487270&eid=44615376&fmt=7&profile=raw&app_id=174516466&cid=1726506&etsp=1775260456&hmac=Jf2RA35WdJUXiMIEF6iHzJBcp_M Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.793 [1471.1471] INFO ContentFetcher: [0x1a22758]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2487270&eid=44615376&fmt=7&profile=raw&app_id=174516466&cid=1726506&etsp=1775260456&hmac=Jf2RA35WdJUXiMIEF6iHzJBcp_M, offset: 0 Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.793 [1471.1471] INFO AudioRenderer: [0x172c6b0]: Starting audio renderer, initial playback state: Playing Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.793 [1471.1471] INFO SampleApp: [Stream 19]: New audio stream (starting from 0ms) Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:16 volumio-salon volumio[889]: info: QobuzConnect: alread currrent service, send playback confirmation Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.795 [1471.1471] INFO SampleApp: Initiating playback, initial state: Playing Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:16 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:16.892-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PAUSED positionMs=0 volume=100 Apr 03 18:54:16 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:16.892-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title= Apr 03 18:54:16 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: pause Apr 03 18:54:16 volumio-salon volumio[889]: info: [ASDebug] CurState: pause PrevState: play Apr 03 18:54:16 volumio-salon volumio[889]: info: [ASDebug] InitTimeout - Amp off in: 60000 ms Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.916 [1471.1471] INFO SampleApp: Playback state changed: Playing Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.916 [1471.1471] INFO StreamingEventReporter: [0x172ced0]: Reporting streaming started event Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.916 [1471.1471] INFO HttpDownloader: [0x172cf28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.916 [1471.1471] INFO ProtocolHandler: [0x172cb20]: Need to send state update Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.916 [1471.1471] INFO QConnectMessageSender: [0x18b72a8]: Sending StateUpdated message Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.916 [1471.1471] INFO QwspMessageSender: [0x18b7298]: Sending Payload message Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.916 [1471.1471] INFO QConnectMessageSender: [0x18b72a8]: Sending FileAudioQualityChanged message Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.917 [1471.1471] INFO QwspMessageSender: [0x18b7298]: Sending Payload message Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.917 [1471.1471] INFO QConnectMessageSender: [0x18b72a8]: Sending DeviceAudioQualityChanged message Apr 03 18:54:16 volumio-salon qobuz-connect[1471]: 20260403 18:54:16.917 [1471.1471] INFO QwspMessageSender: [0x18b7298]: Sending Payload message Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::servicePushState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreStateMachine::pushState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::volumioPushState Apr 03 18:54:16 volumio-salon volumio[889]: info: CoreCommandRouter::volumioGetState Apr 03 18:54:16 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:16.935-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 18:54:16 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:16.935-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title= Apr 03 18:54:16 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:16.935-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 18:54:16 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:16.936-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title= Apr 03 18:54:16 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:16.938-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 18:54:16 volumio-salon volumio5-onboarding[1421]: time=2026-04-03T18:54:16.938-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.170:49396 @ 0x24123f0" id= title= Apr 03 18:54:16 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: play Apr 03 18:54:16 volumio-salon volumio[889]: info: [GPIO_Control] Handling event: musicPlay Apr 03 18:54:16 volumio-salon volumio[889]: info: [GPIO_Control] *** musicPlay *** Apr 03 18:54:16 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: play Apr 03 18:54:16 volumio-salon volumio[889]: info: [GPIO_Control] Status changed: play Apr 03 18:54:16 volumio-salon volumio[889]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Apr 03 18:54:16 volumio-salon volumio[889]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 18:54:16 volumio-salon volumio[889]: TypeError: Cannot read property 'indexOf' of undefined Apr 03 18:54:16 volumio-salon volumio[889]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Apr 03 18:54:16 volumio-salon volumio[889]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Apr 03 18:54:16 volumio-salon volumio[889]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Apr 03 18:54:16 volumio-salon volumio[889]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Apr 03 18:54:16 volumio-salon volumio[889]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Apr 03 18:54:16 volumio-salon volumio[889]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Apr 03 18:54:16 volumio-salon volumio[889]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Apr 03 18:54:16 volumio-salon volumio[889]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Apr 03 18:54:16 volumio-salon volumio[889]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Apr 03 18:54:16 volumio-salon volumio[889]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Apr 03 18:54:16 volumio-salon volumio[889]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Apr 03 18:54:16 volumio-salon volumio[889]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Apr 03 18:54:16 volumio-salon volumio[889]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Apr 03 18:54:16 volumio-salon volumio[889]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Apr 03 18:54:16 volumio-salon volumio[889]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Apr 03 18:54:16 volumio-salon volumio[889]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Apr 03 18:54:16 volumio-salon volumio[889]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.054 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: [Stream 19]: Metadata became available: Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.054 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Title: Burning Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.054 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Artist: Sam Smith Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.054 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Album: The Thrill Of It All Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.054 [1471.1471] INFO AudioStreamManager: [0x172c5f8]: Album art URL: https://static.qobuz.com/images/covers/97/51/0060256715197_600.jpg Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.054 [1471.1471] INFO SampleApp: [Stream 19]: Stream metadata became available: Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.054 [1471.1471] INFO SampleApp: Title: Burning Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.054 [1471.1471] INFO SampleApp: Artist: Sam Smith Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.054 [1471.1471] INFO SampleApp: Album: The Thrill Of It All Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.054 [1471.1471] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/97/51/0060256715197_600.jpg Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.429 [1471.1471] INFO UrlAudioSource: [0x17309c8]: Content info extracted Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.429 [1471.1471] INFO UrlAudioSource: [0x17309c8]: Size: 61627135 Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.429 [1471.10687] INFO AudioDecoder: [0x1d7c830]: Stream info: Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.429 [1471.10687] INFO AudioDecoder: [0x1d7c830]: Total samples: 17930154 Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.429 [1471.10687] INFO AudioDecoder: [0x1d7c830]: Sample rate: 88200 Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.429 [1471.10687] INFO AudioDecoder: [0x1d7c830]: Channel count: 2 Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.429 [1471.10687] INFO AudioDecoder: [0x1d7c830]: Bits per sample: 24 Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.429 [1471.10687] INFO AudioDecoder: [0x1d7c830]: Duration: 203289 Apr 03 18:54:17 volumio-salon qobuz-connect[1471]: 20260403 18:54:17.429 [1471.1471] INFO UrlAudioSource: [0x17309c8]: Audio properties have changed Apr 03 18:54:17 volumio-salon sudo[10689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-03 18:53 Apr 03 18:54:17 volumio-salon sudo[10689]: 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="5c0e72e4b5817fe66c91c9d15d75ff13b8e3f4ba" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 30 Mar 2026 02:51:29 PM CEST" VOLUMIO_VERSION="3.914" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="98fe12c88d8ff391f569279e2f874892"