Feb 22 10:21:03 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:21:13 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:21:13 volumio volumio[1251]: info: Listing playlists Feb 22 10:21:13 volumio volumio[1251]: info: Listing playlists Feb 22 10:21:23 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:21:33 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:21:33 volumio volumio[1251]: info: Listing playlists Feb 22 10:21:33 volumio volumio[1251]: info: Listing playlists Feb 22 10:21:41 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:41.958+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.183:58253 Feb 22 10:21:42 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 10:21:42 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 10:21:42 volumio volumio[1251]: info: Discovery: Getting this device information Feb 22 10:21:42 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:21:42 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 10:21:42 volumio volumio[1251]: verbose: New Socket.io Connection to 192.168.1.118:3000 from 192.168.1.183 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Feb 22 10:21:42 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 10:21:42 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 10:21:43 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:21:44 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:44.120+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.183:58253 @ 0x1ca2270" latency=3.395245ms platform=PLATFORM_IOS version=5.260120.0 Feb 22 10:21:44 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:44.121+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.183:58253 @ 0x1ca2270" latency=3.753814ms timeout=10s Feb 22 10:21:44 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:44.121+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" Feb 22 10:21:44 volumio volumio[1251]: info: Received Get System Info Feb 22 10:21:44 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 10:21:44 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 10:21:44 volumio volumio[1251]: info: Discovery: Getting this device information Feb 22 10:21:44 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:21:44 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 10:21:44 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:44.123+01:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" name=Volumio Feb 22 10:21:44 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:44.124+01:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" language=es Feb 22 10:21:44 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 22 10:21:44 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:44.125+01:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" timezone=Europe/Madrid Feb 22 10:21:44 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:44.126+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" available=true connected=true macAddress=d8:3a:dd:4c:41:28 ip4Address=192.168.1.118/24 ip6Address= Feb 22 10:21:44 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:44.127+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 22 10:21:44 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:44.127+01:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" setupComplete=true Feb 22 10:21:44 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 22 10:21:44 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 10:21:44 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 10:21:44 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 10:21:44 volumio volumio[1251]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 22 10:21:44 volumio volumio[1251]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 22 10:21:44 volumio volumio[1251]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Feb 22 10:21:44 volumio volumio[1251]: amixer -c 2 info | grep "vc4-hdmi-0" Feb 22 10:21:44 volumio volumio[1251]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Feb 22 10:21:44 volumio volumio[1251]: amixer -c 3 info | grep "vc4-hdmi-1" Feb 22 10:21:44 volumio volumio[1251]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Feb 22 10:21:44 volumio volumio[1251]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 7 Feb 22 10:21:44 volumio volumio[1251]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 22 10:21:44 volumio volumio[1251]: {"cmd":"/usr/local/bin/alsacap -C 7","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 7\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Feb 22 10:21:44 volumio volumio[1251]: amixer -c 7 info | grep "Loopback" Feb 22 10:21:44 volumio volumio[1251]: Card sysdefault:7 'Loopback'/'Loopback 1' Feb 22 10:21:44 volumio volumio[1251]: Mixer name : 'Loopback Mixer' Feb 22 10:21:44 volumio volumio[1251]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 4 Feb 22 10:21:44 volumio volumio[1251]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 22 10:21:44 volumio volumio[1251]: {"cmd":"/usr/local/bin/alsacap -C 4","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 4\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Feb 22 10:21:44 volumio volumio[1251]: amixer -c 4 info | grep "RPi DAC+" Feb 22 10:21:44 volumio volumio[1251]: Card sysdefault:4 'DAC'/'RPi DAC+' Feb 22 10:21:44 volumio volumio[1251]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 4 Feb 22 10:21:44 volumio volumio[1251]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 22 10:21:44 volumio volumio[1251]: {"cmd":"/usr/local/bin/alsacap -C 4","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 4\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Feb 22 10:21:44 volumio volumio[1251]: amixer -c 4 info | grep "Raspberry Pi DAC+" Feb 22 10:21:44 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:44.335+01:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" selectedOutputId=4 Feb 22 10:21:44 volumio volumio[1251]: info: Received Get System Info Feb 22 10:21:44 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 10:21:44 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 10:21:44 volumio volumio[1251]: info: Discovery: Getting this device information Feb 22 10:21:44 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:21:44 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 10:21:44 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:44.346+01:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" currentVersion=4.096 latestVersion=4.096 Feb 22 10:21:44 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:44.346+01:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" status=UPDATE_STATUS_NONE progress=0 Feb 22 10:21:44 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:44.346+01:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" userId=xyp0Z8D3m0XyYaDhjzdlg8wLcoW2 Feb 22 10:21:44 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 10:21:44 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 10:21:44 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 22 10:21:46 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:46.188+01:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory" Feb 22 10:21:46 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:46.189+01:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" providers=9 Feb 22 10:21:46 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 10:21:46 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 10:21:46 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 22 10:21:47 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:47.076+01:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" plugins=60 Feb 22 10:21:47 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:21:47 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:47.079+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" state=STATUS_PAUSED positionMs=185759 volume=90 Feb 22 10:21:47 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:47.079+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" id= title="Bleeding Out" Feb 22 10:21:47 volumio volumio5-onboarding[1474]: time=2026-02-22T10:21:47.110+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.183:58253 @ 0x1ca2270" latency=3.729761ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 22 10:21:48 volumio sudo[19967]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 10:21:48 volumio sudo[19967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:21:48 volumio sudo[19967]: pam_unix(sudo:session): session closed for user root Feb 22 10:21:48 volumio sudo[19969]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 10:21:48 volumio sudo[19969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:21:48 volumio sudo[19969]: pam_unix(sudo:session): session closed for user root Feb 22 10:21:48 volumio volumio[1251]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.183 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Feb 22 10:21:48 volumio sudo[19973]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 10:21:48 volumio sudo[19973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:21:48 volumio sudo[19973]: pam_unix(sudo:session): session closed for user root Feb 22 10:21:48 volumio sudo[19975]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 10:21:48 volumio sudo[19975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:21:48 volumio sudo[19975]: pam_unix(sudo:session): session closed for user root Feb 22 10:21:48 volumio volumio[1251]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.183 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Feb 22 10:21:48 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:21:48 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 10:21:48 volumio volumio[1251]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 22 10:21:48 volumio volumio[1251]: info: Listing playlists Feb 22 10:21:48 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 10:21:48 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 10:21:48 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 10:21:48 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 10:21:53 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:21:53 volumio volumio[1251]: info: Listing playlists Feb 22 10:21:53 volumio volumio[1251]: info: Listing playlists Feb 22 10:22:03 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.179 [2401.2434] INFO SampleApp: API endpoint invoked: get-connect-info Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.357 [2401.2434] INFO SampleApp: API endpoint invoked: connect-to-qconnect Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.357 [2401.2401] INFO EndpointManager: [0x1b2c858]: Updating API endpoint Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.357 [2401.2401] INFO EndpointManager: [0x1b2c858]: Updating QConnect endpoint Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.357 [2401.2401] INFO ActiveStateManager: [0x1b2b870]: Setting new active state: active Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.357 [2401.2401] INFO PlaybackSessionManager: [0x1b2cc48]: Starting playback session maintenance Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.357 [2401.2401] INFO HttpDownloader: [0x1b2ce38]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.357 [2401.2401] INFO CloudClient: [0x1b2d4b8]: Connecting to the cloud Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.357 [2401.2401] INFO SampleApp: Renderer is now active Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.490 [2401.2401] INFO PlaybackSessionManager: [0x1b2cc48]: Playback session has been refreshed Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.508 [2401.2401] INFO CloudClient: [0x1b2d4b8]: Connection established Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.508 [2401.2401] INFO QwspMessageSender: [0x1e76278]: Sending Authenticate message Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.508 [2401.2401] INFO QwspMessageSender: [0x1e76278]: Sending Subscribe message Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.508 [2401.2401] INFO QConnectMessageSender: [0x1bb88b8]: Sending JoinSession message Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.508 [2401.2401] INFO QConnectMessageSender: [0x1bb88b8]: Sending VolumeChanged message Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.508 [2401.2401] INFO QConnectMessageSender: [0x1bb88b8]: Sending VolumeMuted message Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.508 [2401.2401] INFO QConnectMessageSender: [0x1bb88b8]: Sending MaxAudioQualityChanged message Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.508 [2401.2401] INFO QwspMessageSender: [0x1e76278]: Sending Payload message Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO ProtocolHandler: [0x1b2d620]: Received SetActive message: active Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO ProtocolHandler: [0x1b2d620]: Received SetState message: Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO ProtocolHandler: [0x1b2d620]: Playing state: Playing Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO ProtocolHandler: [0x1b2d620]: Playback position: 277799 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO ProtocolHandler: [0x1b2d620]: Queue version: 2.2 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO ProtocolHandler: [0x1b2d620]: Current track: TID: 374793624, QID: 4, Context UUID: abcc806d-f429-462f-92fe-512d82fb3eb6 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO ProtocolHandler: [0x1b2d620]: Next track: TID: 374793625, QID: 5, Context UUID: abcc806d-f429-462f-92fe-512d82fb3eb6 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO MediaEngine: [0x1b2cee0]: Stopping playback, clearing tracks Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO MediaEngine: [0x1b2cee0]: Initiating playback Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO ProtocolHandler: [0x1b2d620]: Received SetLoopMode message: Off Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO PlaybackControlsManager: [0x1b2d2b8]: Setting new loop mode: Off Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO MediaEngine: [0x1b2cee0]: Setting current track: 374793624, initial offset: 277799ms Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: Clearing all streams Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: New stream: 19 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO HttpDownloader: [0x1b30ad0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=eac1a09834dcef2b0234129c77ea6bfe&request_ts=1771752127&track_id=374793624 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO HttpDownloader: [0x1c03720]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=374793624 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO HttpDownloader: [0x1e8f0f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=1e62dc66c5626ad00a047ade221bdfc4&request_ts=1771752127&track_id=374793624 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: [Stream 19]: Running audio stream Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO ProtocolHandler: [0x1b2d620]: Received SetShuffleMode message: disabled Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO PlaybackControlsManager: [0x1b2d2b8]: Setting new shuffle mode: disabled Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO MediaEngine: [0x1b2cee0]: Setting next track: 374793625 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: New stream: 20 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO HttpDownloader: [0x1d7d620]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=72c4981cb306fca1b2a1f54e6d220763&request_ts=1771752127&track_id=374793625 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.557 [2401.2401] INFO HttpDownloader: [0x1d0a8e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=374793625 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.558 [2401.2401] INFO HttpDownloader: [0x1d0ab80]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=de5d866b35997ebdb9f9b32faef97119&request_ts=1771752127&track_id=374793625 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.558 [2401.2401] INFO MediaEngine: [0x1b2cee0]: Waiting for current stream to start before starting audio renderer Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.679 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: [Stream 19]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10802041&eid=374793624&fmt=7&profile=raw&app_id=174516466&cid=3925023&etsp=1771755727&hmac=JTBtS6Tiz8HxLXc54Qyn0B_CagM Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.681 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: [Stream 20]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10802041&eid=374793625&fmt=7&profile=raw&app_id=174516466&cid=3925023&etsp=1771755727&hmac=kpSBoa4WnCAfqm8A2i_yibJaXJc Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.692 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: [Stream 20]: Metadata became available: Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.692 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: Title: Alleycat Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.692 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: Artist: Mumford & Sons Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.692 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: Album: Prizefighter Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.692 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: Album art URL: https://static.qobuz.com/images/covers/83/6e/oyza2e0i46e83_600.jpg Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.714 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: [Stream 20]: stream information have been fetched Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.722 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: [Stream 19]: Metadata became available: Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.722 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: Title: Conversation With My Son (Gangsters & Angels) Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.722 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: Artist: Mumford & Sons Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.722 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: Album: Prizefighter Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.722 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: Album art URL: https://static.qobuz.com/images/covers/83/6e/oyza2e0i46e83_600.jpg Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.731 [2401.2401] INFO AudioStreamManager: [0x1b2cfc8]: [Stream 19]: stream information have been fetched Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.731 [2401.2401] INFO UrlAudioSource: [0x1cbe8d8]: Starting URL audio source, initial position: 277799ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=10802041&eid=374793624&fmt=7&profile=raw&app_id=174516466&cid=3925023&etsp=1771755727&hmac=JTBtS6Tiz8HxLXc54Qyn0B_CagM Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.731 [2401.2401] INFO ContentFetcher: [0x1c76680]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=10802041&eid=374793624&fmt=7&profile=raw&app_id=174516466&cid=3925023&etsp=1771755727&hmac=JTBtS6Tiz8HxLXc54Qyn0B_CagM, offset: 0 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.731 [2401.2401] INFO AudioRenderer: [0x1b2d100]: Starting audio renderer, initial playback state: Playing Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.731 [2401.2401] INFO SampleApp: [Stream 19]: New audio stream (starting from 277799ms) Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.731 [2401.2401] INFO SampleApp: [Stream 19]: Stream metadata became available: Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.731 [2401.2401] INFO SampleApp: Title: Conversation With My Son (Gangsters & Angels) Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.731 [2401.2401] INFO SampleApp: Artist: Mumford & Sons Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.731 [2401.2401] INFO SampleApp: Album: Prizefighter Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.731 [2401.2401] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/83/6e/oyza2e0i46e83_600.jpg Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:22:07 volumio volumio[1251]: info: QobuzConnect: alread currrent service, send playback confirmation Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.733 [2401.2401] INFO SampleApp: Initiating playback, initial state: Playing Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.788 [2401.2401] INFO SampleApp: Playback state changed: Playing Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.788 [2401.2401] INFO StreamingEventReporter: [0x1b2da38]: Reporting streaming started event Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.788 [2401.2401] INFO HttpDownloader: [0x1b2da90]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.788 [2401.2401] INFO ProtocolHandler: [0x1b2d620]: Need to send state update Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.788 [2401.2401] INFO QConnectMessageSender: [0x1bb88b8]: Sending StateUpdated message Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.788 [2401.2401] INFO QwspMessageSender: [0x1e76278]: Sending Payload message Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.788 [2401.2401] INFO QConnectMessageSender: [0x1bb88b8]: Sending FileAudioQualityChanged message Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::servicePushState Feb 22 10:22:07 volumio volumio[1251]: info: CoreStateMachine::pushState Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.789 [2401.2401] INFO QwspMessageSender: [0x1e76278]: Sending Payload message Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.789 [2401.2401] INFO QConnectMessageSender: [0x1bb88b8]: Sending DeviceAudioQualityChanged message Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.789 [2401.2401] INFO QwspMessageSender: [0x1e76278]: Sending Payload message Feb 22 10:22:07 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device Feb 22 10:22:07 volumio volumio[1251]: info: MRS: Pushing multiroomSync output Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:22:07 volumio volumio5-onboarding[1474]: time=2026-02-22T10:22:07.794+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" state=STATUS_PAUSED positionMs=277799 volume=90 Feb 22 10:22:07 volumio volumio5-onboarding[1474]: time=2026-02-22T10:22:07.795+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" id= title="Conversation With My Son (Gangsters & Angels)" Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::servicePushState Feb 22 10:22:07 volumio volumio[1251]: info: CoreStateMachine::pushState Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState Feb 22 10:22:07 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device Feb 22 10:22:07 volumio volumio[1251]: info: MRS: Pushing multiroomSync output Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::servicePushState Feb 22 10:22:07 volumio volumio[1251]: info: CoreStateMachine::pushState Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState Feb 22 10:22:07 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device Feb 22 10:22:07 volumio volumio[1251]: info: MRS: Pushing multiroomSync output Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::servicePushState Feb 22 10:22:07 volumio volumio[1251]: info: CoreStateMachine::pushState Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState Feb 22 10:22:07 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device Feb 22 10:22:07 volumio volumio[1251]: info: MRS: Pushing multiroomSync output Feb 22 10:22:07 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:22:07 volumio volumio5-onboarding[1474]: time=2026-02-22T10:22:07.818+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" state=STATUS_PLAYING positionMs=277799 volume=90 Feb 22 10:22:07 volumio volumio5-onboarding[1474]: time=2026-02-22T10:22:07.819+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" id= title="Conversation With My Son (Gangsters & Angels)" Feb 22 10:22:07 volumio volumio5-onboarding[1474]: time=2026-02-22T10:22:07.820+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" state=STATUS_PLAYING positionMs=277799 volume=90 Feb 22 10:22:07 volumio volumio5-onboarding[1474]: time=2026-02-22T10:22:07.820+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" state=STATUS_PLAYING positionMs=277799 volume=90 Feb 22 10:22:07 volumio volumio5-onboarding[1474]: time=2026-02-22T10:22:07.820+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" id= title="Conversation With My Son (Gangsters & Angels)" Feb 22 10:22:07 volumio volumio5-onboarding[1474]: time=2026-02-22T10:22:07.821+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" id= title="Conversation With My Son (Gangsters & Angels)" Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.866 [2401.2401] INFO UrlAudioSource: [0x1cbe8d8]: Content info extracted Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.866 [2401.2401] INFO UrlAudioSource: [0x1cbe8d8]: Size: 115572038 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.867 [2401.20043] INFO AudioDecoder: [0x1c66cc8]: Stream info: Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.868 [2401.20043] INFO AudioDecoder: [0x1c66cc8]: Total samples: 31674880 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.868 [2401.20043] INFO AudioDecoder: [0x1c66cc8]: Sample rate: 96000 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.868 [2401.20043] INFO AudioDecoder: [0x1c66cc8]: Channel count: 2 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.868 [2401.20043] INFO AudioDecoder: [0x1c66cc8]: Bits per sample: 24 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.868 [2401.20043] INFO AudioDecoder: [0x1c66cc8]: Duration: 329946 Feb 22 10:22:07 volumio volumio[1251]: info: PeppyMeterBasic ---peppymeterbasic status pause Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.870 [2401.2401] INFO UrlAudioSource: [0x1cbe8d8]: Audio properties have changed Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.870 [2401.2401] INFO UrlAudioSource: [0x1cbe8d8]: Seeking input stream to byte offset: 95906143 Feb 22 10:22:07 volumio qobuz-connect[2401]: 20260222 10:22:07.870 [2401.2401] INFO ContentFetcher: [0x1c76680]: Adjusting download offset to: 95906143 Feb 22 10:22:07 volumio volumio[1251]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 22 10:22:07 volumio volumio[1251]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 22 10:22:07 volumio sudo[20045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 22 10:22:07 volumio sudo[20045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:22:07 volumio sudo[20047]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 22 10:22:07 volumio sudo[20047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:22:07 volumio volumio[1251]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 22 10:22:07 volumio sudo[20053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 22 10:22:07 volumio sudo[20053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:22:07 volumio sudo[20049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 22 10:22:07 volumio sudo[20049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:22:08 volumio sudo[20045]: pam_unix(sudo:session): session closed for user root Feb 22 10:22:08 volumio volumio[1251]: info: PeppyMeterBasic ---peppymeterbasic failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppymeterbasic.service Feb 22 10:22:08 volumio volumio[1251]: Job for peppymeterbasic.service canceled. Feb 22 10:22:08 volumio volumio[1251]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 22 10:22:08 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Feb 22 10:22:08 volumio sudo[20053]: pam_unix(sudo:session): session closed for user root Feb 22 10:22:08 volumio sudo[20047]: pam_unix(sudo:session): session closed for user root Feb 22 10:22:08 volumio volumio[1251]: info: peppymeterbasic Daemon Started Feb 22 10:22:08 volumio volumio[1251]: info: peppymeterbasic Daemon Started Feb 22 10:22:08 volumio sudo[20049]: pam_unix(sudo:session): session closed for user root Feb 22 10:22:08 volumio volumio[1251]: info: peppymeterbasic Daemon Started Feb 22 10:22:12 volumio volumio[1251]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.183 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Feb 22 10:22:12 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:22:12 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 10:22:12 volumio volumio[1251]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 22 10:22:12 volumio volumio[1251]: info: Listing playlists Feb 22 10:22:13 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:22:13 volumio volumio[1251]: info: Listing playlists Feb 22 10:22:13 volumio volumio[1251]: info: Listing playlists Feb 22 10:22:17 volumio qobuz-connect[2401]: 20260222 10:22:17.789 [2401.2401] INFO ProtocolHandler: [0x1b2d620]: Need to send state update Feb 22 10:22:17 volumio qobuz-connect[2401]: 20260222 10:22:17.789 [2401.2401] INFO QConnectMessageSender: [0x1bb88b8]: Sending StateUpdated message Feb 22 10:22:17 volumio qobuz-connect[2401]: 20260222 10:22:17.789 [2401.2401] INFO QwspMessageSender: [0x1e76278]: Sending Payload message Feb 22 10:22:17 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:22:17 volumio volumio[1251]: info: CoreCommandRouter::servicePushState Feb 22 10:22:17 volumio volumio[1251]: info: CoreStateMachine::pushState Feb 22 10:22:17 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 10:22:17 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState Feb 22 10:22:17 volumio volumio[1251]: info: MRS: Pushing multiroomSync output update for this device Feb 22 10:22:17 volumio volumio[1251]: info: MRS: Pushing multiroomSync output Feb 22 10:22:17 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:22:17 volumio volumio5-onboarding[1474]: time=2026-02-22T10:22:17.796+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" state=STATUS_PLAYING positionMs=287308 volume=90 Feb 22 10:22:17 volumio volumio5-onboarding[1474]: time=2026-02-22T10:22:17.796+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.183:58253 @ 0x1ca2270" id= title="Conversation With My Son (Gangsters & Angels)" Feb 22 10:22:17 volumio volumio[1251]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 22 10:22:17 volumio sudo[20093]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 22 10:22:17 volumio sudo[20093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:22:17 volumio sudo[20093]: pam_unix(sudo:session): session closed for user root Feb 22 10:22:17 volumio volumio[1251]: info: peppymeterbasic Daemon Started Feb 22 10:22:21 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 10:22:21 volumio volumio[1251]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 22 10:22:21 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 22 10:22:21 volumio volumio[1251]: info: Received Get System Version Feb 22 10:22:21 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 10:22:21 volumio volumio[1251]: info: Received Get System Info Feb 22 10:22:21 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 10:22:21 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 10:22:21 volumio volumio[1251]: info: Discovery: Getting this device information Feb 22 10:22:21 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:22:21 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 10:22:23 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 10:22:26 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Feb 22 10:22:26 volumio volumio[1251]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Feb 22 10:22:26 volumio volumio[1251]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 10:22:26 volumio volumio[1251]: TypeError: Cannot read properties of undefined (reading 'then') Feb 22 10:22:26 volumio volumio[1251]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Feb 22 10:22:26 volumio volumio[1251]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Feb 22 10:22:26 volumio volumio[1251]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Feb 22 10:22:26 volumio volumio[1251]: at Socket.emit (node:events:514:28) Feb 22 10:22:26 volumio volumio[1251]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 22 10:22:26 volumio volumio[1251]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 22 10:22:26 volumio volumio[1251]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 10:22:27 volumio sudo[20144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 10:21' Feb 22 10:22:27 volumio sudo[20144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"