Mar 04 07:56:02 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:02.050+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.67:37782 Mar 04 07:56:03 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 07:56:03 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 07:56:03 volumio-2 volumio[2144]: info: Discovery: Getting this device information Mar 04 07:56:03 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:56:03 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 07:56:03 volumio-2 volumio[2144]: verbose: New Socket.io Connection to 192.168.2.135:3000 from 192.168.2.67 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 04 07:56:03 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 04 07:56:03 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 04 07:56:03 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:03.349+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.2.67:37782 Mar 04 07:56:03 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:03.349+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.2.67:37782 Mar 04 07:56:03 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:03.360+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.67:37808 Mar 04 07:56:03 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:03.975+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.2.67:37808 @ 0xc0004286c0" latency=658.704317ms timeout=10s Mar 04 07:56:03 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:03.975+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.2.67:37808 @ 0xc0004286c0" Mar 04 07:56:03 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:03.975+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.2.67:37808 @ 0xc0004286c0" latency=659.777783ms platform=PLATFORM_ANDROID version=5.260120.0 Mar 04 07:56:03 volumio-2 volumio[2144]: info: Received Get System Info Mar 04 07:56:03 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 07:56:03 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 07:56:03 volumio-2 volumio[2144]: info: Discovery: Getting this device information Mar 04 07:56:03 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:56:03 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 07:56:03 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:03.976+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.2.67:37808 @ 0xc0004286c0" name=Volumio-2 Mar 04 07:56:03 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:03.976+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.2.67:37808 @ 0xc0004286c0" language=en Mar 04 07:56:03 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 04 07:56:03 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:03.977+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.2.67:37808 @ 0xc0004286c0" timezone=Africa/Johannesburg Mar 04 07:56:03 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:03.977+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.2.67:37808 @ 0xc0004286c0" available=true connected=false macAddress= ip4Address= ip6Address= Mar 04 07:56:03 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:03.978+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.2.67:37808 @ 0xc0004286c0" available=true connected=true macAddress=c8:3a:35:cf:1d:49 ip4Address=192.168.2.135/24 ip6Address= ssid=yoda Mar 04 07:56:03 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:03.978+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.2.67:37808 @ 0xc0004286c0" setupComplete=true Mar 04 07:56:03 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 04 07:56:03 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 04 07:56:03 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 07:56:03 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 07:56:03 volumio-2 volumio[2144]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Mar 04 07:56:03 volumio-2 volumio[2144]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 04 07:56:03 volumio-2 volumio[2144]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\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)"} Mar 04 07:56:03 volumio-2 volumio[2144]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Mar 04 07:56:03 volumio-2 volumio[2144]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 04 07:56:03 volumio-2 volumio[2144]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\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)"} Mar 04 07:56:03 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:03.990+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.2.67:37808 @ 0xc0004286c0" selectedOutputId=0,0 Mar 04 07:56:04 volumio-2 volumio[2144]: info: Received Get System Info Mar 04 07:56:04 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 07:56:04 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 07:56:04 volumio-2 volumio[2144]: info: Discovery: Getting this device information Mar 04 07:56:04 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:56:04 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 07:56:04 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:04.044+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.2.67:37808 @ 0xc0004286c0" currentVersion=4.096 latestVersion=4.096 Mar 04 07:56:04 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:04.044+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.2.67:37808 @ 0xc0004286c0" status=UPDATE_STATUS_NONE progress=0 Mar 04 07:56:04 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:04.044+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.2.67:37808 @ 0xc0004286c0" userId=9tEtEJZLeUSBs7HWxj17bLuBTci1 Mar 04 07:56:04 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:04.044+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.2.67:37808 @ 0xc0004286c0" providers=9 Mar 04 07:56:04 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:04.044+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.2.67:37808 @ 0xc0004286c0" plugins=45 Mar 04 07:56:04 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:56:04 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:04.045+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:37808 @ 0xc0004286c0" state=STATUS_UNSPECIFIED positionMs= volume=43 Mar 04 07:56:04 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:04.045+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:37808 @ 0xc0004286c0" id= title= Mar 04 07:56:04 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:04.052+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.2.67:37808 Mar 04 07:56:04 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:04.052+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.2.67:37808 Mar 04 07:56:04 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:04.066+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.67:37826 Mar 04 07:56:14 volumio-2 nmbd[1096]: [2026/03/04 07:56:14.759869, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 04 07:56:14 volumio-2 nmbd[1096]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.135 for name WORKGROUP<1d>. Mar 04 07:56:14 volumio-2 nmbd[1096]: This response was from IP 192.168.2.8, reporting an IP address of 192.168.2.8. Mar 04 07:56:14 volumio-2 nmbd[1096]: [2026/03/04 07:56:14.759970, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 04 07:56:14 volumio-2 nmbd[1096]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.2.135 for name WORKGROUP<1d>. Mar 04 07:56:14 volumio-2 nmbd[1096]: This response was from IP 192.168.2.7, reporting an IP address of 192.168.2.8. Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.570+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.2.67:37826 @ 0xc000467e00" latency=620.742688ms platform=PLATFORM_ANDROID version=5.260120.0 Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.582+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.2.67:37826 @ 0xc000467e00" latency=630.474622ms timeout=10s Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.582+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.2.67:37826 @ 0xc000467e00" Mar 04 07:56:17 volumio-2 volumio[2144]: info: Received Get System Info Mar 04 07:56:17 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 07:56:17 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 07:56:17 volumio-2 volumio[2144]: info: Discovery: Getting this device information Mar 04 07:56:17 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:56:17 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.583+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.2.67:37826 @ 0xc000467e00" name=Volumio-2 Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.583+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.2.67:37826 @ 0xc000467e00" language=en Mar 04 07:56:17 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.583+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.2.67:37826 @ 0xc000467e00" timezone=Africa/Johannesburg Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.584+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.2.67:37826 @ 0xc000467e00" available=true connected=false macAddress= ip4Address= ip6Address= Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.584+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.2.67:37826 @ 0xc000467e00" available=true connected=true macAddress=c8:3a:35:cf:1d:49 ip4Address=192.168.2.135/24 ip6Address= ssid=yoda Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.584+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.2.67:37826 @ 0xc000467e00" setupComplete=true Mar 04 07:56:17 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 04 07:56:17 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 04 07:56:17 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 07:56:17 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 07:56:17 volumio-2 volumio[2144]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Mar 04 07:56:17 volumio-2 volumio[2144]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 04 07:56:17 volumio-2 volumio[2144]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\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)"} Mar 04 07:56:17 volumio-2 volumio[2144]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Mar 04 07:56:17 volumio-2 volumio[2144]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 04 07:56:17 volumio-2 volumio[2144]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\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)"} Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.595+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.2.67:37826 @ 0xc000467e00" selectedOutputId=0,0 Mar 04 07:56:17 volumio-2 volumio[2144]: info: Received Get System Info Mar 04 07:56:17 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 07:56:17 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 07:56:17 volumio-2 volumio[2144]: info: Discovery: Getting this device information Mar 04 07:56:17 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:56:17 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.616+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.2.67:37826 @ 0xc000467e00" currentVersion=4.096 latestVersion=4.096 Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.616+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.2.67:37826 @ 0xc000467e00" status=UPDATE_STATUS_NONE progress=0 Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.616+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.2.67:37826 @ 0xc000467e00" userId=9tEtEJZLeUSBs7HWxj17bLuBTci1 Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.617+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.2.67:37826 @ 0xc000467e00" providers=9 Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.617+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.2.67:37826 @ 0xc000467e00" plugins=45 Mar 04 07:56:17 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.617+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:37826 @ 0xc000467e00" state=STATUS_UNSPECIFIED positionMs= volume=43 Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.617+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:37826 @ 0xc000467e00" id= title= Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.622+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.2.67:37826 Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.622+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.2.67:37826 Mar 04 07:56:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:17.640+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.67:47692 Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.859+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.2.67:47692 @ 0xc00040ad80" latency=773.204003ms timeout=10s Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.859+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.2.67:47692 @ 0xc00040ad80" Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.859+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.2.67:47692 @ 0xc00040ad80" latency=775.22242ms platform=PLATFORM_ANDROID version=5.260120.0 Mar 04 07:56:38 volumio-2 volumio[2144]: info: Received Get System Info Mar 04 07:56:38 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 07:56:38 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 07:56:38 volumio-2 volumio[2144]: info: Discovery: Getting this device information Mar 04 07:56:38 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:56:38 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.860+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.2.67:47692 @ 0xc00040ad80" name=Volumio-2 Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.860+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.2.67:47692 @ 0xc00040ad80" language=en Mar 04 07:56:38 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.860+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.2.67:47692 @ 0xc00040ad80" timezone=Africa/Johannesburg Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.860+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.2.67:47692 @ 0xc00040ad80" available=true connected=false macAddress= ip4Address= ip6Address= Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.861+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.2.67:47692 @ 0xc00040ad80" available=true connected=true macAddress=c8:3a:35:cf:1d:49 ip4Address=192.168.2.135/24 ip6Address= ssid=yoda Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.861+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.2.67:47692 @ 0xc00040ad80" setupComplete=true Mar 04 07:56:38 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 04 07:56:38 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 04 07:56:38 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 07:56:38 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 07:56:38 volumio-2 volumio[2144]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Mar 04 07:56:38 volumio-2 volumio[2144]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 04 07:56:38 volumio-2 volumio[2144]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\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)"} Mar 04 07:56:38 volumio-2 volumio[2144]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Mar 04 07:56:38 volumio-2 volumio[2144]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 04 07:56:38 volumio-2 volumio[2144]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\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)"} Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.873+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.2.67:47692 @ 0xc00040ad80" selectedOutputId=0,0 Mar 04 07:56:38 volumio-2 volumio[2144]: info: Received Get System Info Mar 04 07:56:38 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 07:56:38 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 07:56:38 volumio-2 volumio[2144]: info: Discovery: Getting this device information Mar 04 07:56:38 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:56:38 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.895+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.2.67:47692 @ 0xc00040ad80" currentVersion=4.096 latestVersion=4.096 Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.895+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.2.67:47692 @ 0xc00040ad80" status=UPDATE_STATUS_NONE progress=0 Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.895+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.2.67:47692 @ 0xc00040ad80" userId=9tEtEJZLeUSBs7HWxj17bLuBTci1 Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.895+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.2.67:47692 @ 0xc00040ad80" providers=9 Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.895+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.2.67:47692 @ 0xc00040ad80" plugins=45 Mar 04 07:56:38 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.896+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:47692 @ 0xc00040ad80" state=STATUS_UNSPECIFIED positionMs= volume=43 Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.896+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:47692 @ 0xc00040ad80" id= title= Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.996+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.2.67:47692 Mar 04 07:56:38 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:38.997+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.2.67:47692 Mar 04 07:56:39 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:56:39.023+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.67:43858 Mar 04 07:56:54 volumio-2 sudo[192658]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 07:56:54 volumio-2 sudo[192658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 07:56:54 volumio-2 sudo[192660]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 07:56:54 volumio-2 sudo[192658]: pam_unix(sudo:session): session closed for user root Mar 04 07:56:54 volumio-2 sudo[192660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 07:56:54 volumio-2 sudo[192660]: pam_unix(sudo:session): session closed for user root Mar 04 07:56:54 volumio-2 volumio[2144]: verbose: New Socket.io Connection to 192.168.2.135 from 192.168.2.67 UA: Mozilla/5.0 (Linux; Android 15; 2312DRA50G Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetVisibleSources Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 04 07:56:55 volumio-2 volumio[2144]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 04 07:56:55 volumio-2 volumio[2144]: info: Received Get System Info Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 07:56:55 volumio-2 volumio[2144]: info: Discovery: Getting this device information Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:56:55 volumio-2 volumio[2144]: info: Listing playlists Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Mar 04 07:56:55 volumio-2 volumio[2144]: info: Received Get System Version Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 07:56:55 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 04 07:56:56 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 04 07:56:56 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 07:56:56 volumio-2 volumio[2144]: info: Received Get System Info Mar 04 07:56:56 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 07:56:56 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 07:56:56 volumio-2 volumio[2144]: info: Discovery: Getting this device information Mar 04 07:56:56 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:56:56 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 07:56:56 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 04 07:56:59 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 07:56:59 volumio-2 volumio[2144]: info: Received Get System Info Mar 04 07:56:59 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 07:56:59 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 07:56:59 volumio-2 volumio[2144]: info: Discovery: Getting this device information Mar 04 07:56:59 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:56:59 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 07:57:00 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 07:57:00 volumio-2 volumio[2144]: info: Received Get System Info Mar 04 07:57:00 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 07:57:00 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 07:57:00 volumio-2 volumio[2144]: info: Discovery: Getting this device information Mar 04 07:57:00 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:00 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 07:57:01 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 07:57:01 volumio-2 volumio[2144]: info: Received Get System Info Mar 04 07:57:01 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 07:57:01 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 07:57:01 volumio-2 volumio[2144]: info: Discovery: Getting this device information Mar 04 07:57:01 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:01 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 07:57:01 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioVolatilePlay Mar 04 07:57:01 volumio-2 volumio[2144]: ------------------------------------ BT MESSAGE: [FUNC] play Mar 04 07:57:01 volumio-2 volumio[2144]: ------------------------------------ BT MESSAGE: sendPlay skipped: activePlayer not bound or invalid Mar 04 07:57:04 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 04 07:57:08 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetQueue Mar 04 07:57:08 volumio-2 volumio[2144]: info: CoreStateMachine::getQueue Mar 04 07:57:08 volumio-2 volumio[2144]: info: CorePlayQueue::getQueue Mar 04 07:57:10 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 04 07:57:10 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 07:57:10 volumio-2 volumio[2144]: info: Preload queue cleared Mar 04 07:57:13 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 07:57:13 volumio-2 volumio[2144]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 04 07:57:13 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 04 07:57:13 volumio-2 volumio[2144]: info: Received Get System Version Mar 04 07:57:13 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 07:57:13 volumio-2 volumio[2144]: info: Received Get System Info Mar 04 07:57:13 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 07:57:13 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 07:57:13 volumio-2 volumio[2144]: info: Discovery: Getting this device information Mar 04 07:57:13 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:13 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 07:57:14 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 04 07:57:15 volumio-2 volumio[2144]: info: Preload queue cleared Mar 04 07:57:17 volumio-2 volumio[2144]: info: Preload queue cleared Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::ClearQueue Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::stop Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::serviceStop Mar 04 07:57:17 volumio-2 volumio[2144]: info: Received STOP, but no service to execute it Mar 04 07:57:17 volumio-2 volumio[2144]: info: CorePlayQueue::clearPlayQueue Mar 04 07:57:17 volumio-2 volumio[2144]: info: CorePlayQueue::saveQueue Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushQueue Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::addQueueItems Mar 04 07:57:17 volumio-2 volumio[2144]: info: CorePlayQueue::addQueueItems Mar 04 07:57:17 volumio-2 volumio[2144]: info: Preload queue cleared Mar 04 07:57:17 volumio-2 volumio[2144]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushQueue Mar 04 07:57:17 volumio-2 volumio[2144]: info: CorePlayQueue::saveQueue Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::updateTrackBlock Mar 04 07:57:17 volumio-2 volumio[2144]: info: CorePlayQueue::getTrackBlock Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPlay Mar 04 07:57:17 volumio-2 volumio[2144]: verbose: UNSET VOLATILE: Service: undefined Mar 04 07:57:17 volumio-2 volumio[2144]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Mar 04 07:57:17 volumio-2 volumio[2144]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Mar 04 07:57:17 volumio-2 volumio[2144]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::play index 0 Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 07:57:17 volumio-2 volumio[2144]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::stop Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::resetVolumioState Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::getcurrentVolume Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioRetrievevolume Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::play index undefined Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioStop Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::stop Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 07:57:17 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::startPlaybackTimer Mar 04 07:57:17 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetVisibleSources Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 04 07:57:17 volumio-2 volumio[2144]: info: [1772603837713] ControllerWebradio::clearAddPlayTrack Mar 04 07:57:17 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 07:57:17 volumio-2 volumio[2144]: info: sendMpdCommand stop took 1 milliseconds Mar 04 07:57:17 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 07:57:17 volumio-2 volumio[2144]: info: Mar 04 07:57:17 volumio-2 volumio[2144]: ---------------------------- MPD announces system playlist update Mar 04 07:57:17 volumio-2 volumio[2144]: info: Ignoring MPD Status Update Mar 04 07:57:17 volumio-2 volumio[2144]: info: sendMpdCommand clear took 0 milliseconds Mar 04 07:57:17 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Mar 04 07:57:17 volumio-2 volumio[2144]: info: VolumeController:: Volume=43 Mute =false Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:17 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:17 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:17 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:17 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::updateTrackBlock Mar 04 07:57:17 volumio-2 volumio[2144]: info: CorePlayQueue::getTrackBlock Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioRetrievevolume Mar 04 07:57:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:17.727+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_STOPPED positionMs=0 volume=43 Mar 04 07:57:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:17.727+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" title="ANTENNE BAYERN" Mar 04 07:57:17 volumio-2 volumio[2144]: info: VolumeController:: Volume=43 Mute =false Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:17 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:17 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:17 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:17 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:17 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:17.740+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_STOPPED positionMs=0 volume=43 Mar 04 07:57:17 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:17.740+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" title="ANTENNE BAYERN" Mar 04 07:57:18 volumio-2 volumio[2144]: error: updateQueue error: null Mar 04 07:57:18 volumio-2 volumio[2144]: info: Mar 04 07:57:18 volumio-2 volumio[2144]: ---------------------------- MPD announces system playlist update Mar 04 07:57:18 volumio-2 volumio[2144]: info: Ignoring MPD Status Update Mar 04 07:57:18 volumio-2 volumio[2144]: info: ------------------------------ 463ms Mar 04 07:57:18 volumio-2 volumio[2144]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 463 milliseconds Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 07:57:18 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand play Mar 04 07:57:18 volumio-2 volumio[2144]: info: ------------------------------ 0ms Mar 04 07:57:18 volumio-2 volumio[2144]: info: sendMpdCommand play took 0 milliseconds Mar 04 07:57:18 volumio-2 volumio[2144]: info: Preload queue cleared Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreStateMachine::ClearQueue Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreStateMachine::stop Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 07:57:18 volumio-2 volumio[2144]: info: CorePlayQueue::clearPlayQueue Mar 04 07:57:18 volumio-2 volumio[2144]: info: CorePlayQueue::saveQueue Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushQueue Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreStateMachine::addQueueItems Mar 04 07:57:18 volumio-2 volumio[2144]: info: CorePlayQueue::addQueueItems Mar 04 07:57:18 volumio-2 volumio[2144]: info: Preload queue cleared Mar 04 07:57:18 volumio-2 volumio[2144]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushQueue Mar 04 07:57:18 volumio-2 volumio[2144]: info: CorePlayQueue::saveQueue Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreStateMachine::updateTrackBlock Mar 04 07:57:18 volumio-2 volumio[2144]: info: CorePlayQueue::getTrackBlock Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:18 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPlay Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreStateMachine::play index 0 Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreStateMachine::stop Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreStateMachine::play index undefined Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 07:57:18 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreStateMachine::startPlaybackTimer Mar 04 07:57:18 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetVisibleSources Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 04 07:57:18 volumio-2 volumio[2144]: info: [1772603838438] ControllerWebradio::clearAddPlayTrack Mar 04 07:57:18 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 07:57:18 volumio-2 volumio[2144]: info: Mar 04 07:57:18 volumio-2 volumio[2144]: ---------------------------- MPD announces state update: player Mar 04 07:57:18 volumio-2 volumio[2144]: info: sendMpdCommand stop took 1 milliseconds Mar 04 07:57:18 volumio-2 volumio[2144]: info: ControllerMpd::getState Mar 04 07:57:18 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand status Mar 04 07:57:18 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 07:57:18 volumio-2 volumio[2144]: info: Mar 04 07:57:18 volumio-2 volumio[2144]: ---------------------------- MPD announces system playlist update Mar 04 07:57:18 volumio-2 volumio[2144]: info: Ignoring MPD Status Update Mar 04 07:57:18 volumio-2 volumio[2144]: info: sendMpdCommand status took 3 milliseconds Mar 04 07:57:18 volumio-2 volumio[2144]: info: sendMpdCommand clear took 4 milliseconds Mar 04 07:57:18 volumio-2 volumio[2144]: verbose: ControllerMpd::parseState Mar 04 07:57:18 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 07:57:18 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Mar 04 07:57:18 volumio-2 volumio[2144]: error: updateQueue error: null Mar 04 07:57:18 volumio-2 volumio[2144]: info: ------------------------------ 1ms Mar 04 07:57:18 volumio-2 volumio[2144]: info: Mar 04 07:57:18 volumio-2 volumio[2144]: ---------------------------- MPD announces system playlist update Mar 04 07:57:18 volumio-2 volumio[2144]: info: Ignoring MPD Status Update Mar 04 07:57:18 volumio-2 volumio[2144]: info: sendMpdCommand playlistinfo took 227 milliseconds Mar 04 07:57:18 volumio-2 volumio[2144]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 227 milliseconds Mar 04 07:57:18 volumio-2 volumio[2144]: verbose: ControllerMpd::parseTrackInfo Mar 04 07:57:18 volumio-2 volumio[2144]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 07:57:18 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand play Mar 04 07:57:18 volumio-2 volumio[2144]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 04 07:57:18 volumio-2 volumio[2144]: info: ------------------------------ 232ms Mar 04 07:57:18 volumio-2 volumio[2144]: info: ------------------------------ 1ms Mar 04 07:57:18 volumio-2 volumio[2144]: info: sendMpdCommand play took 0 milliseconds Mar 04 07:57:19 volumio-2 volumio[2144]: info: Executing endpoint metavolumio Mar 04 07:57:19 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 07:57:19 volumio-2 volumio[2144]: info: Executing endpoint metavolumio Mar 04 07:57:19 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 07:57:20 volumio-2 volumio[2144]: info: Mar 04 07:57:20 volumio-2 volumio[2144]: ---------------------------- MPD announces state update: player Mar 04 07:57:20 volumio-2 volumio[2144]: info: ControllerMpd::getState Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand status Mar 04 07:57:20 volumio-2 volumio[2144]: info: Mar 04 07:57:20 volumio-2 volumio[2144]: ---------------------------- MPD announces state update: player Mar 04 07:57:20 volumio-2 volumio[2144]: info: sendMpdCommand status took 25 milliseconds Mar 04 07:57:20 volumio-2 volumio[2144]: info: ControllerMpd::getState Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand status Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: ControllerMpd::parseState Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 07:57:20 volumio-2 volumio[2144]: info: sendMpdCommand status took 1 milliseconds Mar 04 07:57:20 volumio-2 volumio[2144]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: ControllerMpd::parseState Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: ControllerMpd::parseTrackInfo Mar 04 07:57:20 volumio-2 volumio[2144]: info: ControllerMpd::pushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::servicePushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 5842/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: CURRENT POSITION 0 Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreStateMachine::syncState stateService play Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreStateMachine::syncState currentStatus stop Mar 04 07:57:20 volumio-2 volumio[2144]: info: ------------------------------ 27ms Mar 04 07:57:20 volumio-2 volumio[2144]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: ControllerMpd::parseTrackInfo Mar 04 07:57:20 volumio-2 volumio[2144]: info: ControllerMpd::pushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::servicePushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 5842/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: CURRENT POSITION 0 Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreStateMachine::syncState stateService play Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreStateMachine::syncState currentStatus play Mar 04 07:57:20 volumio-2 volumio[2144]: info: Received an update from plugin. extracting info from payload Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:20 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:20 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:20 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:20.521+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_PLAYING positionMs=0 volume=43 Mar 04 07:57:20 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:20.521+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_PLAYING positionMs=0 volume=43 Mar 04 07:57:20 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:20.521+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id=http://stream.antenne.de:80/antenne title="ANTENNE BAYERN" Mar 04 07:57:20 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:20.521+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id=http://stream.antenne.de:80/antenne title="ANTENNE BAYERN" Mar 04 07:57:20 volumio-2 volumio[2144]: info: ------------------------------ 5ms Mar 04 07:57:20 volumio-2 volumio[2144]: info: Mar 04 07:57:20 volumio-2 volumio[2144]: ---------------------------- MPD announces system playlist update Mar 04 07:57:20 volumio-2 volumio[2144]: info: Ignoring MPD Status Update Mar 04 07:57:20 volumio-2 volumio[2144]: info: Mar 04 07:57:20 volumio-2 volumio[2144]: ---------------------------- MPD announces state update: player Mar 04 07:57:20 volumio-2 volumio[2144]: info: ControllerMpd::getState Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand status Mar 04 07:57:20 volumio-2 volumio[2144]: info: Mar 04 07:57:20 volumio-2 volumio[2144]: ---------------------------- MPD announces system playlist update Mar 04 07:57:20 volumio-2 volumio[2144]: info: Ignoring MPD Status Update Mar 04 07:57:20 volumio-2 volumio[2144]: info: Mar 04 07:57:20 volumio-2 volumio[2144]: ---------------------------- MPD announces state update: player Mar 04 07:57:20 volumio-2 volumio[2144]: info: ControllerMpd::getState Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand status Mar 04 07:57:20 volumio-2 volumio[2144]: info: ------------------------------ 0ms Mar 04 07:57:20 volumio-2 volumio[2144]: info: sendMpdCommand status took 1 milliseconds Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: ControllerMpd::parseState Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 07:57:20 volumio-2 volumio[2144]: info: ------------------------------ 2ms Mar 04 07:57:20 volumio-2 volumio[2144]: info: sendMpdCommand status took 2 milliseconds Mar 04 07:57:20 volumio-2 volumio[2144]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: ControllerMpd::parseState Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: ControllerMpd::parseTrackInfo Mar 04 07:57:20 volumio-2 volumio[2144]: info: ControllerMpd::pushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::servicePushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1112,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"David Guetta & OneRepublic - I Don't Wanna Wait","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: CURRENT POSITION 0 Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreStateMachine::syncState stateService play Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreStateMachine::syncState currentStatus play Mar 04 07:57:20 volumio-2 volumio[2144]: info: Received an update from plugin. extracting info from payload Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:20 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:20 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:20 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:20.671+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_PLAYING positionMs=0 volume=43 Mar 04 07:57:20 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:20.671+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_PLAYING positionMs=0 volume=43 Mar 04 07:57:20 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:20.671+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id=http://stream.antenne.de:80/antenne title="David Guetta & OneRepublic - I Don't Wanna Wait" Mar 04 07:57:20 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:20.671+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id=http://stream.antenne.de:80/antenne title="David Guetta & OneRepublic - I Don't Wanna Wait" Mar 04 07:57:20 volumio-2 volumio[2144]: info: ------------------------------ 6ms Mar 04 07:57:20 volumio-2 volumio[2144]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: ControllerMpd::parseTrackInfo Mar 04 07:57:20 volumio-2 volumio[2144]: info: ControllerMpd::pushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::servicePushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1112,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"David Guetta & OneRepublic - I Don't Wanna Wait","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Mar 04 07:57:20 volumio-2 volumio[2144]: verbose: CURRENT POSITION 0 Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreStateMachine::syncState stateService play Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreStateMachine::syncState currentStatus play Mar 04 07:57:20 volumio-2 volumio[2144]: info: Received an update from plugin. extracting info from payload Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:20 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:20 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:20 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:20 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:20 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:20.674+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_PLAYING positionMs=0 volume=43 Mar 04 07:57:20 volumio-2 volumio[2144]: info: ------------------------------ 8ms Mar 04 07:57:20 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:20.674+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_PLAYING positionMs=0 volume=43 Mar 04 07:57:20 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:20.675+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id=http://stream.antenne.de:80/antenne title="David Guetta & OneRepublic - I Don't Wanna Wait" Mar 04 07:57:20 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:20.675+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id=http://stream.antenne.de:80/antenne title="David Guetta & OneRepublic - I Don't Wanna Wait" Mar 04 07:57:22 volumio-2 volumio[2144]: info: Executing endpoint metavolumio Mar 04 07:57:22 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 07:57:22 volumio-2 volumio[2144]: info: Executing endpoint metavolumio Mar 04 07:57:22 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 07:57:28 volumio-2 volumio[2144]: info: Preload queue cleared Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreStateMachine::ClearQueue Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreStateMachine::stop Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreStateMachine::stPlaybackTimer Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreStateMachine::updateTrackBlock Mar 04 07:57:28 volumio-2 volumio[2144]: info: CorePlayQueue::getTrackBlock Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:28 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:28 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:28 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:28 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreStateMachine::serviceStop Mar 04 07:57:28 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreCommandRouter::serviceStop Mar 04 07:57:28 volumio-2 volumio[2144]: info: [1772603848932] ControllerWebradio::stop Mar 04 07:57:28 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 07:57:28 volumio-2 volumio[2144]: info: CorePlayQueue::clearPlayQueue Mar 04 07:57:28 volumio-2 volumio[2144]: info: CorePlayQueue::saveQueue Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushQueue Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreStateMachine::addQueueItems Mar 04 07:57:28 volumio-2 volumio[2144]: info: CorePlayQueue::addQueueItems Mar 04 07:57:28 volumio-2 volumio[2144]: info: Preload queue cleared Mar 04 07:57:28 volumio-2 volumio[2144]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 04 07:57:28 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:28.933+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_STOPPED positionMs=0 volume=43 Mar 04 07:57:28 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:28.933+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" title="ANTENNE BAYERN" Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushQueue Mar 04 07:57:28 volumio-2 volumio[2144]: info: CorePlayQueue::saveQueue Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreStateMachine::updateTrackBlock Mar 04 07:57:28 volumio-2 volumio[2144]: info: CorePlayQueue::getTrackBlock Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:28 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPlay Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreStateMachine::play index 0 Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreStateMachine::stop Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreStateMachine::play index undefined Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 07:57:28 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreStateMachine::startPlaybackTimer Mar 04 07:57:28 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetVisibleSources Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 07:57:28 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 04 07:57:28 volumio-2 volumio[2144]: info: [1772603848937] ControllerWebradio::clearAddPlayTrack Mar 04 07:57:28 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 07:57:28 volumio-2 volumio[2144]: info: Mar 04 07:57:28 volumio-2 volumio[2144]: ---------------------------- MPD announces state update: player Mar 04 07:57:28 volumio-2 volumio[2144]: info: sendMpdCommand stop took 6 milliseconds Mar 04 07:57:28 volumio-2 volumio[2144]: info: ControllerMpd::getState Mar 04 07:57:28 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand status Mar 04 07:57:28 volumio-2 volumio[2144]: info: sendMpdCommand stop took 2 milliseconds Mar 04 07:57:28 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 07:57:28 volumio-2 volumio[2144]: info: Mar 04 07:57:28 volumio-2 volumio[2144]: ---------------------------- MPD announces system playlist update Mar 04 07:57:28 volumio-2 volumio[2144]: info: Ignoring MPD Status Update Mar 04 07:57:28 volumio-2 volumio[2144]: info: sendMpdCommand status took 0 milliseconds Mar 04 07:57:28 volumio-2 volumio[2144]: info: sendMpdCommand clear took 0 milliseconds Mar 04 07:57:28 volumio-2 volumio[2144]: verbose: ControllerMpd::parseState Mar 04 07:57:28 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 07:57:28 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Mar 04 07:57:28 volumio-2 volumio[2144]: error: updateQueue error: null Mar 04 07:57:28 volumio-2 volumio[2144]: info: ------------------------------ 0ms Mar 04 07:57:28 volumio-2 volumio[2144]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 04 07:57:28 volumio-2 volumio[2144]: verbose: ControllerMpd::parseTrackInfo Mar 04 07:57:28 volumio-2 volumio[2144]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 04 07:57:28 volumio-2 volumio[2144]: info: ------------------------------ 2ms Mar 04 07:57:29 volumio-2 volumio[2144]: info: Mar 04 07:57:29 volumio-2 volumio[2144]: ---------------------------- MPD announces system playlist update Mar 04 07:57:29 volumio-2 volumio[2144]: info: Ignoring MPD Status Update Mar 04 07:57:29 volumio-2 volumio[2144]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 226 milliseconds Mar 04 07:57:29 volumio-2 volumio[2144]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 07:57:29 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand play Mar 04 07:57:29 volumio-2 volumio[2144]: info: ------------------------------ 1ms Mar 04 07:57:29 volumio-2 volumio[2144]: info: sendMpdCommand play took 1 milliseconds Mar 04 07:57:30 volumio-2 volumio[2144]: info: Mar 04 07:57:30 volumio-2 volumio[2144]: ---------------------------- MPD announces state update: player Mar 04 07:57:30 volumio-2 volumio[2144]: info: ControllerMpd::getState Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand status Mar 04 07:57:30 volumio-2 volumio[2144]: info: Mar 04 07:57:30 volumio-2 volumio[2144]: ---------------------------- MPD announces state update: player Mar 04 07:57:30 volumio-2 volumio[2144]: info: sendMpdCommand status took 23 milliseconds Mar 04 07:57:30 volumio-2 volumio[2144]: info: ControllerMpd::getState Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand status Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::parseState Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 07:57:30 volumio-2 volumio[2144]: info: sendMpdCommand status took 1 milliseconds Mar 04 07:57:30 volumio-2 volumio[2144]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::parseState Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::parseTrackInfo Mar 04 07:57:30 volumio-2 volumio[2144]: info: ControllerMpd::pushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::servicePushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5971/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: CURRENT POSITION 0 Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::syncState stateService play Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::syncState currentStatus stop Mar 04 07:57:30 volumio-2 volumio[2144]: info: ------------------------------ 25ms Mar 04 07:57:30 volumio-2 volumio[2144]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::parseTrackInfo Mar 04 07:57:30 volumio-2 volumio[2144]: info: ControllerMpd::pushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::servicePushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5971/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: CURRENT POSITION 0 Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::syncState stateService play Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::syncState currentStatus play Mar 04 07:57:30 volumio-2 volumio[2144]: info: Received an update from plugin. extracting info from payload Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:30 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:30 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:30 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:30.248+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_PLAYING positionMs=0 volume=43 Mar 04 07:57:30 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:30.249+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 04 07:57:30 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:30.249+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_PLAYING positionMs=0 volume=43 Mar 04 07:57:30 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:30.249+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 04 07:57:30 volumio-2 volumio[2144]: info: ------------------------------ 5ms Mar 04 07:57:30 volumio-2 volumio[2144]: info: Mar 04 07:57:30 volumio-2 volumio[2144]: ---------------------------- MPD announces system playlist update Mar 04 07:57:30 volumio-2 volumio[2144]: info: Ignoring MPD Status Update Mar 04 07:57:30 volumio-2 volumio[2144]: info: Mar 04 07:57:30 volumio-2 volumio[2144]: ---------------------------- MPD announces state update: player Mar 04 07:57:30 volumio-2 volumio[2144]: info: ControllerMpd::getState Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand status Mar 04 07:57:30 volumio-2 volumio[2144]: info: Mar 04 07:57:30 volumio-2 volumio[2144]: ---------------------------- MPD announces system playlist update Mar 04 07:57:30 volumio-2 volumio[2144]: info: Ignoring MPD Status Update Mar 04 07:57:30 volumio-2 volumio[2144]: info: Mar 04 07:57:30 volumio-2 volumio[2144]: ---------------------------- MPD announces state update: player Mar 04 07:57:30 volumio-2 volumio[2144]: info: ControllerMpd::getState Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand status Mar 04 07:57:30 volumio-2 volumio[2144]: info: ------------------------------ 2ms Mar 04 07:57:30 volumio-2 volumio[2144]: info: sendMpdCommand status took 1 milliseconds Mar 04 07:57:30 volumio-2 volumio[2144]: info: ------------------------------ 1ms Mar 04 07:57:30 volumio-2 volumio[2144]: info: sendMpdCommand status took 1 milliseconds Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::parseState Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::parseState Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 07:57:30 volumio-2 volumio[2144]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 04 07:57:30 volumio-2 volumio[2144]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::parseTrackInfo Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::parseTrackInfo Mar 04 07:57:30 volumio-2 volumio[2144]: info: ControllerMpd::pushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::servicePushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1089,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: CURRENT POSITION 0 Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::syncState stateService play Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::syncState currentStatus play Mar 04 07:57:30 volumio-2 volumio[2144]: info: Received an update from plugin. extracting info from payload Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:30 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:30 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:30 volumio-2 volumio[2144]: info: ControllerMpd::pushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::servicePushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1089,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: CURRENT POSITION 0 Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::syncState stateService play Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::syncState currentStatus play Mar 04 07:57:30 volumio-2 volumio[2144]: info: Received an update from plugin. extracting info from payload Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:30 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:30 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:30 volumio-2 volumio[2144]: info: ------------------------------ 6ms Mar 04 07:57:30 volumio-2 volumio[2144]: info: ------------------------------ 6ms Mar 04 07:57:30 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:30.411+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_PLAYING positionMs=0 volume=43 Mar 04 07:57:30 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:30.411+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_PLAYING positionMs=0 volume=43 Mar 04 07:57:30 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:30.411+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 04 07:57:30 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:30.411+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_PLAYING positionMs=0 volume=43 Mar 04 07:57:30 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:30.411+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 04 07:57:30 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:30.411+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_PLAYING positionMs=0 volume=43 Mar 04 07:57:30 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:30.411+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 04 07:57:30 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:30.411+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioStop Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::stop Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::stPlaybackTimer Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::updateTrackBlock Mar 04 07:57:30 volumio-2 volumio[2144]: info: CorePlayQueue::getTrackBlock Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:30 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::serviceStop Mar 04 07:57:30 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::serviceStop Mar 04 07:57:30 volumio-2 volumio[2144]: info: [1772603850805] ControllerWebradio::stop Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 07:57:30 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:30.805+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_STOPPED positionMs=0 volume=43 Mar 04 07:57:30 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:30.805+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Mar 04 07:57:30 volumio-2 volumio[2144]: info: Mar 04 07:57:30 volumio-2 volumio[2144]: ---------------------------- MPD announces state update: player Mar 04 07:57:30 volumio-2 volumio[2144]: info: sendMpdCommand stop took 3 milliseconds Mar 04 07:57:30 volumio-2 volumio[2144]: info: ControllerMpd::getState Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand status Mar 04 07:57:30 volumio-2 volumio[2144]: info: sendMpdCommand status took 0 milliseconds Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::parseState Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 07:57:30 volumio-2 volumio[2144]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 04 07:57:30 volumio-2 volumio[2144]: verbose: ControllerMpd::parseTrackInfo Mar 04 07:57:30 volumio-2 volumio[2144]: info: ControllerMpd::pushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::servicePushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreStateMachine::pushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioPushState Mar 04 07:57:30 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output update for this device Mar 04 07:57:30 volumio-2 volumio[2144]: info: MRS: Pushing multiroomSync output Mar 04 07:57:30 volumio-2 volumio[2144]: info: CoreCommandRouter::volumioGetState Mar 04 07:57:30 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:30 volumio-2 volumio[2144]: info: CorePlayQueue::getTrack 0 Mar 04 07:57:30 volumio-2 volumio[2144]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 04 07:57:30 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:30.810+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" state=STATUS_STOPPED positionMs=0 volume=43 Mar 04 07:57:30 volumio-2 volumio5-onboarding[2870]: time=2026-03-04T07:57:30.810+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.67:43858 @ 0xc000218ed0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Mar 04 07:57:30 volumio-2 volumio[2144]: info: ------------------------------ 2ms Mar 04 07:57:31 volumio-2 volumio[2144]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 07:57:31 volumio-2 volumio[2144]: TypeError: Cannot read properties of null (reading 'slice') Mar 04 07:57:31 volumio-2 volumio[2144]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 04 07:57:31 volumio-2 volumio[2144]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 04 07:57:31 volumio-2 volumio[2144]: at Object.onceWrapper (node:events:629:26) Mar 04 07:57:31 volumio-2 volumio[2144]: at ClientRequest.emit (node:events:514:28) Mar 04 07:57:31 volumio-2 volumio[2144]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Mar 04 07:57:31 volumio-2 volumio[2144]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Mar 04 07:57:31 volumio-2 volumio[2144]: at TLSSocket.socketOnData (node:_http_client:535:22) Mar 04 07:57:31 volumio-2 volumio[2144]: at TLSSocket.emit (node:events:514:28) Mar 04 07:57:31 volumio-2 volumio[2144]: at addChunk (node:internal/streams/readable:343:12) Mar 04 07:57:31 volumio-2 volumio[2144]: at readableAddChunk (node:internal/streams/readable:316:9) Mar 04 07:57:31 volumio-2 volumio[2144]: at Readable.push (node:internal/streams/readable:253:10) Mar 04 07:57:31 volumio-2 volumio[2144]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Mar 04 07:57:31 volumio-2 volumio[2144]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 07:57:31 volumio-2 sudo[192808]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 07:56' Mar 04 07:57:31 volumio-2 sudo[192808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"