-- Logs begin at Sun 2026-03-29 10:39:11 BST, end at Sun 2026-03-29 18:57:51 BST. -- Mar 29 18:57:18 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:57:18 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:57:18 volumio volumio[1478]: info: Discovery: Getting this device information Mar 29 18:57:18 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Mar 29 18:57:18 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:57:18 volumio volumio[1478]: verbose: New Socket.io Connection to 192.168.0.106:3000 from 192.168.0.70 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Mar 29 18:57:18 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 18:57:18 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 18:57:18 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:57:18 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:57:18 volumio volumio[1478]: info: Discovery: Getting this device information Mar 29 18:57:18 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Mar 29 18:57:18 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:57:18 volumio volumio[1478]: verbose: New Socket.io Connection to 192.168.0.85:3000 from 192.168.0.70 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Mar 29 18:57:18 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 18:57:18 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.548 [2478.2478] INFO ProtocolHandler: [0x55be886144d0]: Received SetState message: Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.548 [2478.2478] INFO ProtocolHandler: [0x55be886144d0]: Playing state: Playing Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.548 [2478.2478] INFO ProtocolHandler: [0x55be886144d0]: Playback position: 0 Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.548 [2478.2478] INFO ProtocolHandler: [0x55be886144d0]: Queue version: 3.1 Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.548 [2478.2478] INFO ProtocolHandler: [0x55be886144d0]: Current track: TID: 319836436, QID: 0, Context UUID: 9e5fec92-06e0-4acd-aeff-8a1ccad3752c Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.548 [2478.2478] INFO ProtocolHandler: [0x55be886144d0]: Next track: TID: 4294967295, QID: -1, Context UUID: Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.548 [2478.2478] INFO MediaEngine: [0x55be88613a60]: Stopping playback, clearing tracks Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.548 [2478.2478] INFO MediaEngine: [0x55be88613a60]: Initiating playback Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.548 [2478.2478] INFO AudioRenderer: [0x55be88613ce0]: Stopping audio renderer Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.548 [2478.2478] INFO SampleApp: Stopping playback Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.549 [2478.2478] INFO AudioStreamManager: [0x55be88613b90]: Clearing all streams Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.549 [2478.2478] INFO AudioStreamManager: [0x55be88613b90]: [Stream 2]: Disposing of audio stream Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.549 [2478.2478] INFO ContentFetcher: [0x55be886903b0]: Canceling download Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.549 [2478.2478] INFO SampleApp: Playback state changed: Stopped Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.549 [2478.2478] INFO ProtocolHandler: [0x55be886144d0]: Need to send state update Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.550 [2478.2478] INFO QConnectMessageSender: [0x55be88618ad0]: Sending StateUpdated message Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.550 [2478.2478] INFO QwspMessageSender: [0x55be887457b0]: Sending Payload message Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.550 [2478.2478] INFO SampleApp: [Stream 2]: Audio stream has been disposed of Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.550 [2478.2478] INFO MediaEngine: [0x55be88613a60]: Setting current track: 319836436, initial offset: 0ms Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.550 [2478.2478] INFO AudioStreamManager: [0x55be88613b90]: Clearing all streams Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.550 [2478.2478] INFO AudioStreamManager: [0x55be88613b90]: New stream: 3 Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.550 [2478.2478] INFO HttpDownloader: [0x55be8871c430]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=6908a05dfd095cdf23c9363f755f4274&request_ts=1774807070&track_id=319836436 Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.550 [2478.2478] INFO HttpDownloader: [0x55be88a80130]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=319836436 Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.550 [2478.2478] INFO HttpDownloader: [0x55be88a80910]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=4f6c25da416347df0546ea7bd59b5975&request_ts=1774807070&track_id=319836436 Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.550 [2478.2478] INFO AudioStreamManager: [0x55be88613b90]: [Stream 3]: Running audio stream Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.550 [2478.2478] INFO MediaEngine: [0x55be88613a60]: Waiting for current stream to start before starting audio renderer Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::servicePushState Mar 29 18:57:50 volumio volumio[1478]: info: CoreStateMachine::pushState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioPushState Mar 29 18:57:50 volumio volumio[1478]: info: MRS: Pushing multiroomSync output update for this device Mar 29 18:57:50 volumio volumio[1478]: info: MRS: Pushing multiroomSync output Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::servicePushState Mar 29 18:57:50 volumio volumio[1478]: info: CoreStateMachine::pushState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioPushState Mar 29 18:57:50 volumio volumio[1478]: info: MRS: Pushing multiroomSync output update for this device Mar 29 18:57:50 volumio volumio[1478]: info: MRS: Pushing multiroomSync output Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.559+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.70:44652,192.168.0.70:40012 @ 0xc000512000" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.559+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.70:40012 @ 0xc00039f2c0" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.559+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.70:44652,192.168.0.70:40012 @ 0xc000512000" id= title="Let There Be Rock" Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.560+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.70:40012 @ 0xc00039f2c0" id= title="Let There Be Rock" Mar 29 18:57:50 volumio volumio[1478]: info: PeppySpectrum ---peppyspectrum status pause Mar 29 18:57:50 volumio volumio[1478]: info: PeppySpectrum ---peppyspectrum status pause Mar 29 18:57:50 volumio sudo[8550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 29 18:57:50 volumio sudo[8550]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:57:50 volumio sudo[8550]: pam_unix(sudo:session): session closed for user root Mar 29 18:57:50 volumio sudo[8553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 29 18:57:50 volumio sudo[8553]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:57:50 volumio volumio[1478]: info: peppyspectrum Daemon Stop Mar 29 18:57:50 volumio sudo[8553]: pam_unix(sudo:session): session closed for user root Mar 29 18:57:50 volumio volumio[1478]: info: peppyspectrum Daemon Stop Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.839 [2478.2478] INFO AudioStreamManager: [0x55be88613b90]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2480367&eid=319836436&fmt=7&profile=raw&app_id=174516466&cid=3660752&etsp=1774810670&hmac=HVBnHuiwNb4UOglHF7qDaQWU8v4 Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.847 [2478.2478] INFO AudioStreamManager: [0x55be88613b90]: [Stream 3]: stream information have been fetched Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.847 [2478.2478] INFO UrlAudioSource: [0x55be88843b50]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2480367&eid=319836436&fmt=7&profile=raw&app_id=174516466&cid=3660752&etsp=1774810670&hmac=HVBnHuiwNb4UOglHF7qDaQWU8v4 Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.848 [2478.2478] INFO ContentFetcher: [0x55be88843ce0]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2480367&eid=319836436&fmt=7&profile=raw&app_id=174516466&cid=3660752&etsp=1774810670&hmac=HVBnHuiwNb4UOglHF7qDaQWU8v4, offset: 0 Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.848 [2478.2478] INFO AudioRenderer: [0x55be88613ce0]: Starting audio renderer, initial playback state: Playing Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.848 [2478.2478] INFO SampleApp: [Stream 3]: New audio stream (starting from 0ms) Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Mar 29 18:57:50 volumio volumio[1478]: info: QobuzConnect: alread currrent service, send playback confirmation Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.849 [2478.2478] INFO SampleApp: Initiating playback, initial state: Playing Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.893 [2478.2478] INFO SampleApp: Playback state changed: Playing Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.893 [2478.2478] INFO StreamingEventReporter: [0x55be88614ba0]: Reporting streaming started event Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.893 [2478.2478] INFO HttpDownloader: [0x55be88614c30]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.893 [2478.2478] INFO ProtocolHandler: [0x55be886144d0]: Need to send state update Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.893 [2478.2478] INFO QConnectMessageSender: [0x55be88618ad0]: Sending StateUpdated message Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.893 [2478.2478] INFO QwspMessageSender: [0x55be887457b0]: Sending Payload message Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.893 [2478.2478] INFO QConnectMessageSender: [0x55be88618ad0]: Sending FileAudioQualityChanged message Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.894 [2478.2478] INFO QwspMessageSender: [0x55be887457b0]: Sending Payload message Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.894 [2478.2478] INFO QConnectMessageSender: [0x55be88618ad0]: Sending DeviceAudioQualityChanged message Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.894 [2478.2478] INFO QwspMessageSender: [0x55be887457b0]: Sending Payload message Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::servicePushState Mar 29 18:57:50 volumio volumio[1478]: info: CoreStateMachine::pushState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioPushState Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.895 [2478.2478] INFO AudioStreamManager: [0x55be88613b90]: [Stream 3]: Metadata became available: Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.895 [2478.2478] INFO AudioStreamManager: [0x55be88613b90]: Title: Last Night Of The World Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.895 [2478.2478] INFO AudioStreamManager: [0x55be88613b90]: Artist: Bruce Cockburn Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.895 [2478.2478] INFO AudioStreamManager: [0x55be88613b90]: Album: Greatest Hits (1970-2020) Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.895 [2478.2478] INFO AudioStreamManager: [0x55be88613b90]: Album art URL: https://static.qobuz.com/images/covers/tc/bv/h2xugi8eybvtc_600.jpg Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.895 [2478.2478] INFO SampleApp: [Stream 3]: Stream metadata became available: Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.895 [2478.2478] INFO SampleApp: Title: Last Night Of The World Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.895 [2478.2478] INFO SampleApp: Artist: Bruce Cockburn Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.895 [2478.2478] INFO SampleApp: Album: Greatest Hits (1970-2020) Mar 29 18:57:50 volumio qobuz-connect[2478]: 20260329 18:57:50.895 [2478.2478] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/tc/bv/h2xugi8eybvtc_600.jpg Mar 29 18:57:50 volumio volumio[1478]: info: MRS: Pushing multiroomSync output update for this device Mar 29 18:57:50 volumio volumio[1478]: info: MRS: Pushing multiroomSync output Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::servicePushState Mar 29 18:57:50 volumio volumio[1478]: info: CoreStateMachine::pushState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioPushState Mar 29 18:57:50 volumio volumio[1478]: info: MRS: Pushing multiroomSync output update for this device Mar 29 18:57:50 volumio volumio[1478]: info: MRS: Pushing multiroomSync output Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::servicePushState Mar 29 18:57:50 volumio volumio[1478]: info: CoreStateMachine::pushState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioPushState Mar 29 18:57:50 volumio volumio[1478]: info: MRS: Pushing multiroomSync output update for this device Mar 29 18:57:50 volumio volumio[1478]: info: MRS: Pushing multiroomSync output Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.904+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.70:44652,192.168.0.70:40012 @ 0xc000512000" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.904+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.70:40012 @ 0xc00039f2c0" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.904+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.70:44652,192.168.0.70:40012 @ 0xc000512000" id= title= Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.904+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.70:44652,192.168.0.70:40012 @ 0xc000512000" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.904+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.70:40012 @ 0xc00039f2c0" id= title= Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.904+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.70:40012 @ 0xc00039f2c0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.904+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.70:44652,192.168.0.70:40012 @ 0xc000512000" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.905+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.70:44652,192.168.0.70:40012 @ 0xc000512000" id= title= Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.905+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.70:40012 @ 0xc00039f2c0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.905+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.70:40012 @ 0xc00039f2c0" id= title= Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.905+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.70:44652,192.168.0.70:40012 @ 0xc000512000" id= title= Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.905+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.70:40012 @ 0xc00039f2c0" id= title= Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::servicePushState Mar 29 18:57:50 volumio volumio[1478]: info: CoreStateMachine::pushState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioPushState Mar 29 18:57:50 volumio volumio[1478]: info: MRS: Pushing multiroomSync output update for this device Mar 29 18:57:50 volumio volumio[1478]: info: MRS: Pushing multiroomSync output Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::servicePushState Mar 29 18:57:50 volumio volumio[1478]: info: CoreStateMachine::pushState Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioPushState Mar 29 18:57:50 volumio volumio[1478]: info: MRS: Pushing multiroomSync output update for this device Mar 29 18:57:50 volumio volumio[1478]: info: MRS: Pushing multiroomSync output Mar 29 18:57:50 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.915+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.70:44652,192.168.0.70:40012 @ 0xc000512000" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.916+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.70:40012 @ 0xc00039f2c0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.916+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.70:44652,192.168.0.70:40012 @ 0xc000512000" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.916+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.70:44652,192.168.0.70:40012 @ 0xc000512000" id= title= Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.916+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.70:40012 @ 0xc00039f2c0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.916+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.70:40012 @ 0xc00039f2c0" id= title= Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.916+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.70:44652,192.168.0.70:40012 @ 0xc000512000" id= title="Last Night Of The World" Mar 29 18:57:50 volumio volumio5-onboarding[1901]: time=2026-03-29T18:57:50.916+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.70:40012 @ 0xc00039f2c0" id= title="Last Night Of The World" Mar 29 18:57:50 volumio volumio[1478]: info: PeppySpectrum ---peppyspectrum status pause Mar 29 18:57:50 volumio volumio[1478]: info: PeppySpectrum ---peppyspectrum status play Mar 29 18:57:50 volumio sudo[8559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 29 18:57:50 volumio sudo[8559]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:57:50 volumio sudo[8559]: pam_unix(sudo:session): session closed for user root Mar 29 18:57:50 volumio volumio[1478]: info: PeppySpectrum ---peppyspectrum status play Mar 29 18:57:50 volumio sudo[8563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 29 18:57:50 volumio sudo[8563]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:57:51 volumio volumio[1478]: info: PeppySpectrum ---peppyspectrum status play Mar 29 18:57:51 volumio sudo[8567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 29 18:57:51 volumio sudo[8567]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:57:51 volumio systemd[1]: Started peppyspectrum Daemon. Mar 29 18:57:51 volumio volumio[1478]: info: PeppySpectrum ---peppyspectrum status play Mar 29 18:57:51 volumio sudo[8570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 29 18:57:51 volumio sudo[8570]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:57:51 volumio sudo[8563]: pam_unix(sudo:session): session closed for user root Mar 29 18:57:51 volumio sudo[8567]: pam_unix(sudo:session): session closed for user root Mar 29 18:57:51 volumio sudo[8570]: pam_unix(sudo:session): session closed for user root Mar 29 18:57:51 volumio volumio[1478]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Mar 29 18:57:51 volumio volumio[1478]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 18:57:51 volumio sudo[8574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 29 18:57:51 volumio sudo[8574]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:57:51 volumio volumio[1478]: TypeError: Cannot read property 'indexOf' of undefined Mar 29 18:57:51 volumio volumio[1478]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Mar 29 18:57:51 volumio volumio[1478]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Mar 29 18:57:51 volumio volumio[1478]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Mar 29 18:57:51 volumio volumio[1478]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Mar 29 18:57:51 volumio volumio[1478]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Mar 29 18:57:51 volumio volumio[1478]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Mar 29 18:57:51 volumio volumio[1478]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Mar 29 18:57:51 volumio volumio[1478]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Mar 29 18:57:51 volumio volumio[1478]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Mar 29 18:57:51 volumio volumio[1478]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Mar 29 18:57:51 volumio volumio[1478]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Mar 29 18:57:51 volumio volumio[1478]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Mar 29 18:57:51 volumio volumio[1478]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Mar 29 18:57:51 volumio volumio[1478]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Mar 29 18:57:51 volumio volumio[1478]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Mar 29 18:57:51 volumio volumio[1478]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Mar 29 18:57:51 volumio volumio[1478]: at WS. (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:268:10) Mar 29 18:57:51 volumio volumio[1478]: at WS.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Mar 29 18:57:51 volumio volumio[1478]: at WS.Transport.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/transport.js:145:8) Mar 29 18:57:51 volumio volumio[1478]: at WS.Transport.onData (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/transport.js:137:8) Mar 29 18:57:51 volumio volumio[1478]: at WebSocket.ws.onmessage (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/transports/websocket.js:146:10) Mar 29 18:57:51 volumio volumio[1478]: at WebSocket.onMessage (/data/plugins/user_interface/lastfm/node_modules/ws/lib/WebSocket.js:442:14) Mar 29 18:57:51 volumio volumio[1478]: at WebSocket.emit (events.js:400:28) Mar 29 18:57:51 volumio volumio[1478]: at Receiver.ontext (/data/plugins/user_interface/lastfm/node_modules/ws/lib/WebSocket.js:841:10) Mar 29 18:57:51 volumio volumio[1478]: at /data/plugins/user_interface/lastfm/node_modules/ws/lib/Receiver.js:536:18 Mar 29 18:57:51 volumio volumio[1478]: at Receiver.applyExtensions (/data/plugins/user_interface/lastfm/node_modules/ws/lib/Receiver.js:371:5) Mar 29 18:57:51 volumio volumio[1478]: at /data/plugins/user_interface/lastfm/node_modules/ws/lib/Receiver.js:508:14 Mar 29 18:57:51 volumio volumio[1478]: at Receiver.flush (/data/plugins/user_interface/lastfm/node_modules/ws/lib/Receiver.js:347:3) Mar 29 18:57:51 volumio volumio[1478]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 18:57:51 volumio sudo[8574]: pam_unix(sudo:session): session closed for user root Mar 29 18:57:51 volumio sudo[8585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-29 18:56 Mar 29 18:57:51 volumio sudo[8585]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="3dada8b1e619a5feb94867e0865ace17474d7bce" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="30045b259d1a704a832ca7c1460c1fdfa4b723f9" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Mar 2026 09:58:18 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="54b3518c7dd8feba4753e0ad124ce801"