Mar 26 12:10:03 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:03.032+09:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.43:59990 Mar 26 12:10:03 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:03.056+09:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.43:59990 @ 0x255f4d0" latency=-21.133644ms timeout=10s Mar 26 12:10:03 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:03.056+09:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" Mar 26 12:10:03 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:03.057+09:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.0.43:59990 @ 0x255f4d0" latency=-20.083519ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 26 12:10:03 volumio volumio[1250]: info: Received Get System Info Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:10:03 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:10:03 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:03.060+09:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" name=volumio Mar 26 12:10:03 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:03.063+09:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" language=ko Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 26 12:10:03 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:03.098+09:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" timezone=Asia/Seoul Mar 26 12:10:03 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:03.102+09:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" available=true connected=true macAddress=dc:a6:32:70:92:0b ip4Address=192.168.0.8/24 ip6Address= Mar 26 12:10:03 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:03.103+09:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 26 12:10:03 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:03.103+09:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" setupComplete=true Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 26 12:10:03 volumio volumio[1250]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 26 12:10:03 volumio volumio[1250]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 26 12:10:03 volumio volumio[1250]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Mar 26 12:10:03 volumio volumio[1250]: amixer -c 2 info | grep "vc4-hdmi-0" Mar 26 12:10:03 volumio volumio[1250]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Mar 26 12:10:03 volumio volumio[1250]: amixer -c 3 info | grep "vc4-hdmi-1" Mar 26 12:10:03 volumio volumio[1250]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Mar 26 12:10:03 volumio volumio[1250]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Mar 26 12:10:03 volumio volumio[1250]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 26 12:10:03 volumio volumio[1250]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\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 26 12:10:03 volumio volumio[1250]: amixer -c 5 info | grep "USB/BT DAC-1" Mar 26 12:10:03 volumio volumio[1250]: Card sysdefault:5 'DAC1'/'HiFi Digi Club USB/BT DAC-1 at usb-0000:01:00.0-1.1, high speed' Mar 26 12:10:03 volumio volumio[1250]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Mar 26 12:10:03 volumio volumio[1250]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 26 12:10:03 volumio volumio[1250]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\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 26 12:10:03 volumio volumio[1250]: amixer -c 5 info | grep "USB/BT DAC-1" Mar 26 12:10:03 volumio volumio[1250]: Card sysdefault:5 'DAC1'/'HiFi Digi Club USB/BT DAC-1 at usb-0000:01:00.0-1.1, high speed' Mar 26 12:10:03 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:03.865+09:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" selectedOutputId=5 Mar 26 12:10:03 volumio volumio[1250]: info: Received Get System Info Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:10:03 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:10:03 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:03.902+09:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" currentVersion=4.103 latestVersion=4.103 Mar 26 12:10:03 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:03.902+09:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" status=UPDATE_STATUS_NONE progress=0 Mar 26 12:10:03 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:03.902+09:00 level=INFO msg="emitting user changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" userId= Mar 26 12:10:03 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:03.902+09:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" providers=3 Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 12:10:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 26 12:10:04 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:04.484+09:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.43:59990 @ 0x255f4d0" latency=-14.396696ms timeout=10s Mar 26 12:10:04 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:04.484+09:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" Mar 26 12:10:04 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:04.484+09:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.0.43:59990 @ 0x255f4d0" latency=-13.387974ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 26 12:10:04 volumio volumio[1250]: info: Received Get System Info Mar 26 12:10:04 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:10:04 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:10:04 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:10:04 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:04 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:10:04 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:04.512+09:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" name=volumio Mar 26 12:10:04 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:04.512+09:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" language=ko Mar 26 12:10:04 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 26 12:10:04 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:04.536+09:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" timezone=Asia/Seoul Mar 26 12:10:04 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:04.537+09:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" available=true connected=true macAddress=dc:a6:32:70:92:0b ip4Address=192.168.0.8/24 ip6Address= Mar 26 12:10:04 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:04.539+09:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 26 12:10:04 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:04.539+09:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" setupComplete=true Mar 26 12:10:04 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 26 12:10:04 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 26 12:10:04 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 12:10:04 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 26 12:10:04 volumio volumio[1250]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 26 12:10:04 volumio volumio[1250]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 26 12:10:04 volumio volumio[1250]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Mar 26 12:10:04 volumio volumio[1250]: amixer -c 2 info | grep "vc4-hdmi-0" Mar 26 12:10:04 volumio volumio[1250]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Mar 26 12:10:04 volumio volumio[1250]: amixer -c 3 info | grep "vc4-hdmi-1" Mar 26 12:10:05 volumio volumio[1250]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Mar 26 12:10:05 volumio volumio[1250]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Mar 26 12:10:05 volumio volumio[1250]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 26 12:10:05 volumio volumio[1250]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\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 26 12:10:05 volumio volumio[1250]: amixer -c 5 info | grep "USB/BT DAC-1" Mar 26 12:10:05 volumio volumio[1250]: Card sysdefault:5 'DAC1'/'HiFi Digi Club USB/BT DAC-1 at usb-0000:01:00.0-1.1, high speed' Mar 26 12:10:05 volumio volumio[1250]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Mar 26 12:10:05 volumio volumio[1250]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 26 12:10:05 volumio volumio[1250]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\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 26 12:10:05 volumio volumio[1250]: amixer -c 5 info | grep "USB/BT DAC-1" Mar 26 12:10:05 volumio volumio[1250]: Card sysdefault:5 'DAC1'/'HiFi Digi Club USB/BT DAC-1 at usb-0000:01:00.0-1.1, high speed' Mar 26 12:10:05 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:05.327+09:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" selectedOutputId=5 Mar 26 12:10:05 volumio volumio[1250]: info: Received Get System Info Mar 26 12:10:05 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:10:05 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:10:05 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:10:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:05 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:10:05 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:05.428+09:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" currentVersion=4.103 latestVersion=4.103 Mar 26 12:10:05 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:05.428+09:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" status=UPDATE_STATUS_NONE progress=0 Mar 26 12:10:05 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:05.428+09:00 level=INFO msg="emitting user changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" userId= Mar 26 12:10:05 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:05.428+09:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" providers=3 Mar 26 12:10:05 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:05.940+09:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" plugins=62 Mar 26 12:10:05 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:05.940+09:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" plugins=62 Mar 26 12:10:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:05 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:05.968+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_PLAYING positionMs=58658 volume=63 Mar 26 12:10:05 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:05.968+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=https://media-ssl.musicradio.com/ClassicFM title="Wolfgang Amadeus Mozart, Michael Collins - Clarinet Concerto in A major (2)" Mar 26 12:10:05 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:05.968+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_PLAYING positionMs=58658 volume=63 Mar 26 12:10:05 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:05.968+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=https://media-ssl.musicradio.com/ClassicFM title="Wolfgang Amadeus Mozart, Michael Collins - Clarinet Concerto in A major (2)" Mar 26 12:10:05 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:05.972+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_PLAYING positionMs=58658 volume=63 Mar 26 12:10:05 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:05.973+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=https://media-ssl.musicradio.com/ClassicFM title="Wolfgang Amadeus Mozart, Michael Collins - Clarinet Concerto in A major (2)" Mar 26 12:10:06 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:06.079+09:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.43:59990 @ 0x255f4d0" latency=20.565254ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 26 12:10:06 volumio sudo[2354]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 12:10:06 volumio sudo[2354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 12:10:06 volumio sudo[2356]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 12:10:06 volumio sudo[2356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 12:10:06 volumio sudo[2354]: pam_unix(sudo:session): session closed for user root Mar 26 12:10:06 volumio sudo[2356]: pam_unix(sudo:session): session closed for user root Mar 26 12:10:06 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.43 UA: Mozilla/5.0 (Linux; Android 16; SM-S948N Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 26 12:10:07 volumio volumio[1250]: info: Received Get System Info Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:10:07 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:07 volumio volumio[1250]: info: Listing playlists Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 12:10:07 volumio sudo[2363]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 12:10:07 volumio sudo[2363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 12:10:07 volumio sudo[2363]: pam_unix(sudo:session): session closed for user root Mar 26 12:10:07 volumio sudo[2365]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 12:10:07 volumio sudo[2365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 12:10:07 volumio sudo[2365]: pam_unix(sudo:session): session closed for user root Mar 26 12:10:07 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.43 UA: Mozilla/5.0 (Linux; Android 16; SM-S948N Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetQueue Mar 26 12:10:07 volumio volumio[1250]: info: CoreStateMachine::getQueue Mar 26 12:10:07 volumio volumio[1250]: info: CorePlayQueue::getQueue Mar 26 12:10:07 volumio volumio[1250]: info: Listing playlists Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 26 12:10:07 volumio volumio[1250]: info: Received Get System Info Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:10:07 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 26 12:10:07 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 12:10:08 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 12:10:09 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 26 12:10:09 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 12:10:09 volumio volumio[1250]: info: Received Get System Info Mar 26 12:10:09 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:10:09 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:10:09 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:10:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:09 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:10:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 12:10:10 volumio volumio[1250]: info: Received Get System Info Mar 26 12:10:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:10:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:10:10 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:10:10 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:10:15 volumio volumio[1250]: info: Mar 26 12:10:15 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Mar 26 12:10:15 volumio volumio[1250]: info: Ignoring MPD Status Update Mar 26 12:10:15 volumio volumio[1250]: info: Mar 26 12:10:15 volumio volumio[1250]: ---------------------------- MPD announces state update: player Mar 26 12:10:15 volumio volumio[1250]: info: ControllerMpd::getState Mar 26 12:10:15 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Mar 26 12:10:15 volumio volumio[1250]: info: Mar 26 12:10:15 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Mar 26 12:10:15 volumio volumio[1250]: info: Ignoring MPD Status Update Mar 26 12:10:15 volumio volumio[1250]: info: Mar 26 12:10:15 volumio volumio[1250]: ---------------------------- MPD announces state update: player Mar 26 12:10:15 volumio volumio[1250]: info: ControllerMpd::getState Mar 26 12:10:15 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Mar 26 12:10:15 volumio volumio[1250]: info: Mar 26 12:10:15 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Mar 26 12:10:15 volumio volumio[1250]: info: Ignoring MPD Status Update Mar 26 12:10:15 volumio volumio[1250]: info: Mar 26 12:10:15 volumio volumio[1250]: ---------------------------- MPD announces state update: player Mar 26 12:10:15 volumio volumio[1250]: info: ControllerMpd::getState Mar 26 12:10:15 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Mar 26 12:10:15 volumio volumio[1250]: info: ------------------------------ 32ms Mar 26 12:10:15 volumio volumio[1250]: info: sendMpdCommand status took 25 milliseconds Mar 26 12:10:15 volumio volumio[1250]: info: ------------------------------ 25ms Mar 26 12:10:15 volumio volumio[1250]: verbose: ControllerMpd::parseState Mar 26 12:10:15 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 12:10:15 volumio volumio[1250]: info: sendMpdCommand status took 8 milliseconds Mar 26 12:10:15 volumio volumio[1250]: info: ------------------------------ 7ms Mar 26 12:10:15 volumio volumio[1250]: info: sendMpdCommand status took 2 milliseconds Mar 26 12:10:15 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 26 12:10:15 volumio volumio[1250]: verbose: ControllerMpd::parseState Mar 26 12:10:15 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 12:10:15 volumio volumio[1250]: verbose: ControllerMpd::parseState Mar 26 12:10:15 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 12:10:15 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Mar 26 12:10:15 volumio volumio[1250]: info: ControllerMpd::pushState Mar 26 12:10:15 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Mar 26 12:10:15 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:15 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69234,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"ClassicFM","artist":"Classic FM","album":null,"uri":"https://media-ssl.musicradio.com/ClassicFM","trackType":""} Mar 26 12:10:15 volumio volumio[1250]: verbose: CURRENT POSITION 0 Mar 26 12:10:15 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Mar 26 12:10:15 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Mar 26 12:10:15 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Mar 26 12:10:15 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:15 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:15 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 12:10:15 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:15 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:15 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:15 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:15.849+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_PLAYING positionMs=68390 volume=63 Mar 26 12:10:15 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:15.850+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=https://media-ssl.musicradio.com/ClassicFM title="Music Classic FM" Mar 26 12:10:15 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:15.852+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_PLAYING positionMs=68390 volume=63 Mar 26 12:10:15 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:15.852+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=https://media-ssl.musicradio.com/ClassicFM title="Music Classic FM" Mar 26 12:10:15 volumio volumio[1250]: info: ------------------------------ 151ms Mar 26 12:10:15 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 123 milliseconds Mar 26 12:10:15 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 122 milliseconds Mar 26 12:10:15 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Mar 26 12:10:15 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Mar 26 12:10:15 volumio volumio[1250]: info: ControllerMpd::pushState Mar 26 12:10:15 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Mar 26 12:10:15 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:15 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69234,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"ClassicFM","artist":"Classic FM","album":null,"uri":"https://media-ssl.musicradio.com/ClassicFM","trackType":""} Mar 26 12:10:15 volumio volumio[1250]: verbose: CURRENT POSITION 0 Mar 26 12:10:15 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Mar 26 12:10:15 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Mar 26 12:10:15 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Mar 26 12:10:15 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:15 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:15 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:15 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:15 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:15 volumio volumio[1250]: info: ControllerMpd::pushState Mar 26 12:10:15 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Mar 26 12:10:15 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:15 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69234,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"ClassicFM","artist":"Classic FM","album":null,"uri":"https://media-ssl.musicradio.com/ClassicFM","trackType":""} Mar 26 12:10:15 volumio volumio[1250]: verbose: CURRENT POSITION 0 Mar 26 12:10:15 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Mar 26 12:10:15 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Mar 26 12:10:15 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Mar 26 12:10:15 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:15 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:15 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:15 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:15 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:15 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:15.973+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_PLAYING positionMs=68390 volume=63 Mar 26 12:10:15 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:15.974+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=https://media-ssl.musicradio.com/ClassicFM title="Music Classic FM" Mar 26 12:10:15 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:15.989+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_PLAYING positionMs=68390 volume=63 Mar 26 12:10:15 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:15.990+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=https://media-ssl.musicradio.com/ClassicFM title="Music Classic FM" Mar 26 12:10:15 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:15.995+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_PLAYING positionMs=68390 volume=63 Mar 26 12:10:15 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:15.996+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=https://media-ssl.musicradio.com/ClassicFM title="Music Classic FM" Mar 26 12:10:16 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:15.999+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_PLAYING positionMs=68390 volume=63 Mar 26 12:10:16 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:16.000+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=https://media-ssl.musicradio.com/ClassicFM title="Music Classic FM" Mar 26 12:10:16 volumio volumio[1250]: info: ------------------------------ 325ms Mar 26 12:10:16 volumio volumio[1250]: info: ------------------------------ 313ms Mar 26 12:10:16 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Mar 26 12:10:16 volumio volumio[1250]: info: [ytmusic-browse] browseUri: ytmusic Mar 26 12:10:16 volumio volumio[1250]: info: Preload queue cleared Mar 26 12:10:17 volumio volumio[1250]: info: Mar 26 12:10:17 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Mar 26 12:10:17 volumio volumio[1250]: info: Ignoring MPD Status Update Mar 26 12:10:17 volumio volumio[1250]: info: Mar 26 12:10:17 volumio volumio[1250]: ---------------------------- MPD announces state update: player Mar 26 12:10:17 volumio volumio[1250]: info: ControllerMpd::getState Mar 26 12:10:17 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Mar 26 12:10:17 volumio volumio[1250]: info: Mar 26 12:10:17 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Mar 26 12:10:17 volumio volumio[1250]: info: Ignoring MPD Status Update Mar 26 12:10:17 volumio volumio[1250]: info: Mar 26 12:10:17 volumio volumio[1250]: ---------------------------- MPD announces state update: player Mar 26 12:10:17 volumio volumio[1250]: info: ControllerMpd::getState Mar 26 12:10:17 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Mar 26 12:10:17 volumio volumio[1250]: info: Mar 26 12:10:17 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Mar 26 12:10:17 volumio volumio[1250]: info: Ignoring MPD Status Update Mar 26 12:10:17 volumio volumio[1250]: info: Mar 26 12:10:17 volumio volumio[1250]: ---------------------------- MPD announces state update: player Mar 26 12:10:17 volumio volumio[1250]: info: ControllerMpd::getState Mar 26 12:10:17 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Mar 26 12:10:17 volumio volumio[1250]: info: ------------------------------ 26ms Mar 26 12:10:17 volumio volumio[1250]: info: sendMpdCommand status took 26 milliseconds Mar 26 12:10:17 volumio volumio[1250]: info: ------------------------------ 25ms Mar 26 12:10:17 volumio volumio[1250]: info: sendMpdCommand status took 24 milliseconds Mar 26 12:10:17 volumio volumio[1250]: info: ------------------------------ 26ms Mar 26 12:10:17 volumio volumio[1250]: info: sendMpdCommand status took 26 milliseconds Mar 26 12:10:17 volumio volumio[1250]: verbose: ControllerMpd::parseState Mar 26 12:10:17 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 12:10:17 volumio volumio[1250]: verbose: ControllerMpd::parseState Mar 26 12:10:17 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 12:10:17 volumio volumio[1250]: verbose: ControllerMpd::parseState Mar 26 12:10:17 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 12:10:17 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 26 12:10:17 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 26 12:10:17 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 26 12:10:17 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Mar 26 12:10:17 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Mar 26 12:10:17 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Mar 26 12:10:17 volumio volumio[1250]: info: ControllerMpd::pushState Mar 26 12:10:17 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Mar 26 12:10:17 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:17 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":70736,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"John Rutter - The Lord Bless You & Keep You","artist":"Classic FM","album":null,"uri":"https://media-ssl.musicradio.com/ClassicFM","trackType":""} Mar 26 12:10:17 volumio volumio[1250]: verbose: CURRENT POSITION 0 Mar 26 12:10:17 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Mar 26 12:10:17 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Mar 26 12:10:17 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Mar 26 12:10:17 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 12:10:17 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:17 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:17 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:17 volumio volumio[1250]: info: ControllerMpd::pushState Mar 26 12:10:17 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Mar 26 12:10:17 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:17 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":70736,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"John Rutter - The Lord Bless You & Keep You","artist":"Classic FM","album":null,"uri":"https://media-ssl.musicradio.com/ClassicFM","trackType":""} Mar 26 12:10:17 volumio volumio[1250]: verbose: CURRENT POSITION 0 Mar 26 12:10:17 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Mar 26 12:10:17 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Mar 26 12:10:17 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Mar 26 12:10:17 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:17 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:17 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:17 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:17 volumio volumio[1250]: info: ControllerMpd::pushState Mar 26 12:10:17 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Mar 26 12:10:17 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:17 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":70736,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"John Rutter - The Lord Bless You & Keep You","artist":"Classic FM","album":null,"uri":"https://media-ssl.musicradio.com/ClassicFM","trackType":""} Mar 26 12:10:17 volumio volumio[1250]: verbose: CURRENT POSITION 0 Mar 26 12:10:17 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Mar 26 12:10:17 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Mar 26 12:10:17 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Mar 26 12:10:17 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:17 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:17 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:17 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:17 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:17.320+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_PLAYING positionMs=69738 volume=63 Mar 26 12:10:17 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:17.320+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=https://media-ssl.musicradio.com/ClassicFM title="John Rutter - The Lord Bless You & Keep You" Mar 26 12:10:17 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:17.322+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_PLAYING positionMs=69738 volume=63 Mar 26 12:10:17 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:17.322+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_PLAYING positionMs=69738 volume=63 Mar 26 12:10:17 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:17.322+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=https://media-ssl.musicradio.com/ClassicFM title="John Rutter - The Lord Bless You & Keep You" Mar 26 12:10:17 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:17.322+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=https://media-ssl.musicradio.com/ClassicFM title="John Rutter - The Lord Bless You & Keep You" Mar 26 12:10:17 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:17.341+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_PLAYING positionMs=69738 volume=63 Mar 26 12:10:17 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:17.341+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=https://media-ssl.musicradio.com/ClassicFM title="John Rutter - The Lord Bless You & Keep You" Mar 26 12:10:17 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:17.341+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_PLAYING positionMs=69738 volume=63 Mar 26 12:10:17 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:17.341+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=https://media-ssl.musicradio.com/ClassicFM title="John Rutter - The Lord Bless You & Keep You" Mar 26 12:10:17 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:17.342+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_PLAYING positionMs=69738 volume=63 Mar 26 12:10:17 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:17.342+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=https://media-ssl.musicradio.com/ClassicFM title="John Rutter - The Lord Bless You & Keep You" Mar 26 12:10:17 volumio volumio[1250]: info: ------------------------------ 284ms Mar 26 12:10:17 volumio volumio[1250]: info: ------------------------------ 284ms Mar 26 12:10:17 volumio volumio[1250]: info: ------------------------------ 282ms Mar 26 12:10:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 26 12:10:21 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Mar 26 12:10:21 volumio volumio[1250]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_home%22%7D%7D Mar 26 12:10:41 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:41.514+09:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.43:59990 @ 0x255f4d0" latency=-10.942712ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 26 12:10:49 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:49.714+09:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.43:59990 @ 0x255f4d0" latency=-12.03673ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 26 12:10:52 volumio volumio[1250]: info: [ytmusic] Innertube support service: Deno not installed or otherwise failed to start: Command failed: npx --no-install --yes deno --version Mar 26 12:10:52 volumio volumio[1250]: info: [ytmusic] Innertube support service: Start service with Node Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 12:10:52 volumio volumio[1250]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 26 12:10:52 volumio volumio[1250]: info: Received Get System Version Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 12:10:52 volumio volumio[1250]: info: Received Get System Info Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:10:52 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Mar 26 12:10:52 volumio volumio[1250]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_library_landing%22%7D%7D Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Mar 26 12:10:52 volumio volumio[1250]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_library_landing%22%7D%7D Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Mar 26 12:10:52 volumio volumio[1250]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_explore%22%7D%7D Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Mar 26 12:10:52 volumio volumio[1250]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_explore%22%7D%7D Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::volumioStop Mar 26 12:10:52 volumio volumio[1250]: info: CoreStateMachine::stop Mar 26 12:10:52 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 12:10:52 volumio volumio[1250]: info: CoreStateMachine::stPlaybackTimer Mar 26 12:10:52 volumio volumio[1250]: info: CoreStateMachine::updateTrackBlock Mar 26 12:10:52 volumio volumio[1250]: info: CorePlayQueue::getTrackBlock Mar 26 12:10:52 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:52 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:52 volumio volumio[1250]: info: CoreStateMachine::serviceStop Mar 26 12:10:52 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::serviceStop Mar 26 12:10:52 volumio volumio[1250]: info: ControllerMpd::stop Mar 26 12:10:52 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::volumioStop Mar 26 12:10:52 volumio volumio[1250]: info: CoreStateMachine::stop Mar 26 12:10:52 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 12:10:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Mar 26 12:10:52 volumio volumio[1250]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_history%22%7D%7D Mar 26 12:10:52 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:52.872+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_STOPPED positionMs=0 volume=63 Mar 26 12:10:52 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:52.872+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=webcarrot/155 title="Music Classic FM" Mar 26 12:10:53 volumio volumio[1250]: info: Mar 26 12:10:53 volumio volumio[1250]: ---------------------------- MPD announces state update: player Mar 26 12:10:53 volumio volumio[1250]: info: sendMpdCommand stop took 861 milliseconds Mar 26 12:10:53 volumio volumio[1250]: info: ControllerMpd::getState Mar 26 12:10:53 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Mar 26 12:10:53 volumio volumio[1250]: info: ControllerMpd::getState Mar 26 12:10:53 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Mar 26 12:10:53 volumio volumio[1250]: info: Mar 26 12:10:53 volumio volumio[1250]: ---------------------------- MPD announces state update: player Mar 26 12:10:53 volumio volumio[1250]: info: ControllerMpd::getState Mar 26 12:10:53 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Mar 26 12:10:53 volumio volumio[1250]: info: Mar 26 12:10:53 volumio volumio[1250]: ---------------------------- MPD announces state update: player Mar 26 12:10:53 volumio volumio[1250]: info: ControllerMpd::getState Mar 26 12:10:53 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Mar 26 12:10:53 volumio volumio[1250]: Upnp client error: Error: This socket has been ended by the other party Mar 26 12:10:53 volumio volumio[1250]: info: sendMpdCommand status took 82 milliseconds Mar 26 12:10:53 volumio volumio[1250]: info: sendMpdCommand status took 98 milliseconds Mar 26 12:10:53 volumio volumio[1250]: info: sendMpdCommand status took 78 milliseconds Mar 26 12:10:53 volumio volumio[1250]: info: sendMpdCommand status took 72 milliseconds Mar 26 12:10:53 volumio volumio[1250]: verbose: ControllerMpd::parseState Mar 26 12:10:53 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 12:10:53 volumio volumio[1250]: verbose: ControllerMpd::parseState Mar 26 12:10:53 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 12:10:53 volumio volumio[1250]: verbose: ControllerMpd::parseState Mar 26 12:10:53 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 12:10:53 volumio volumio[1250]: verbose: ControllerMpd::parseState Mar 26 12:10:53 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 12:10:53 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 61 milliseconds Mar 26 12:10:53 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 84 milliseconds Mar 26 12:10:53 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 82 milliseconds Mar 26 12:10:53 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 74 milliseconds Mar 26 12:10:53 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Mar 26 12:10:53 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Mar 26 12:10:53 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Mar 26 12:10:53 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Mar 26 12:10:53 volumio volumio[1250]: info: ControllerMpd::pushState Mar 26 12:10:53 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Mar 26 12:10:53 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:53 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:53 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 12:10:53 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:53 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:53 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 12:10:53 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:53 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:53 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:53 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:53 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"John Rutter - The Lord Bless You & Keep You","artist":"Classic FM","album":null,"uri":"https://media-ssl.musicradio.com/ClassicFM","trackType":""} Mar 26 12:10:53 volumio volumio[1250]: verbose: CURRENT POSITION 0 Mar 26 12:10:53 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Mar 26 12:10:53 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Mar 26 12:10:53 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:53 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:53 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:53 volumio volumio[1250]: info: No code Mar 26 12:10:53 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:53 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:53 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:53 volumio volumio[1250]: info: ControllerMpd::pushState Mar 26 12:10:53 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Mar 26 12:10:53 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:53 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:53 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:53 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:53 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 12:10:53 volumio volumio[1250]: info: ControllerMpd::pushState Mar 26 12:10:53 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Mar 26 12:10:53 volumio volumio[1250]: info: CoreStateMachine::pushState Mar 26 12:10:53 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:53 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Mar 26 12:10:54 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:54 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 12:10:54 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:54.057+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_STOPPED positionMs=1066 volume=63 Mar 26 12:10:54 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:54.057+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=webcarrot/155 title="Music Classic FM" Mar 26 12:10:54 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:54.058+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_STOPPED positionMs=1066 volume=63 Mar 26 12:10:54 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:54.059+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=webcarrot/155 title="Music Classic FM" Mar 26 12:10:54 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:54.066+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_STOPPED positionMs=1066 volume=63 Mar 26 12:10:54 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:54.066+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=webcarrot/155 title="Music Classic FM" Mar 26 12:10:54 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:54.079+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_STOPPED positionMs=1066 volume=63 Mar 26 12:10:54 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:54.079+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=webcarrot/155 title="Music Classic FM" Mar 26 12:10:54 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:54.083+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_STOPPED positionMs=1066 volume=63 Mar 26 12:10:54 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:54.083+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=webcarrot/155 title="Music Classic FM" Mar 26 12:10:54 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:54.095+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" state=STATUS_STOPPED positionMs=1066 volume=63 Mar 26 12:10:54 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:54.095+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.43:59990 @ 0x255f4d0" id=webcarrot/155 title="Music Classic FM" Mar 26 12:10:54 volumio volumio[1250]: info: ------------------------------ 587ms Mar 26 12:10:54 volumio volumio[1250]: info: ------------------------------ 570ms Mar 26 12:10:54 volumio volumio[1250]: info: ------------------------------ 550ms Mar 26 12:10:54 volumio volumio5-onboarding[1575]: time=2026-03-26T12:10:54.837+09:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.43:59990 @ 0x255f4d0" latency=-11.779505ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 26 12:10:56 volumio sudo[2536]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 12:10:56 volumio sudo[2536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 12:10:56 volumio sudo[2538]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 12:10:56 volumio sudo[2536]: pam_unix(sudo:session): session closed for user root Mar 26 12:10:56 volumio sudo[2538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 12:10:56 volumio sudo[2538]: pam_unix(sudo:session): session closed for user root Mar 26 12:10:56 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.43 UA: Mozilla/5.0 (Linux; Android 16; SM-S948N Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 26 12:10:56 volumio sudo[2544]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 12:10:56 volumio sudo[2542]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 12:10:56 volumio sudo[2542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 12:10:56 volumio sudo[2542]: pam_unix(sudo:session): session closed for user root Mar 26 12:10:56 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 12:10:56 volumio sudo[2544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 12:10:56 volumio sudo[2544]: pam_unix(sudo:session): session closed for user root Mar 26 12:10:57 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 12:10:57 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.43 UA: Mozilla/5.0 (Linux; Android 16; SM-S948N Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 26 12:10:57 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 12:10:57 volumio sudo[2548]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 12:10:57 volumio sudo[2548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 12:10:57 volumio sudo[2548]: pam_unix(sudo:session): session closed for user root Mar 26 12:10:57 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 12:10:57 volumio sudo[2550]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 12:10:57 volumio sudo[2550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 12:10:57 volumio sudo[2550]: pam_unix(sudo:session): session closed for user root Mar 26 12:10:57 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.43 UA: Mozilla/5.0 (Linux; Android 16; SM-S948N Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 26 12:10:58 volumio sudo[2554]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 12:10:58 volumio sudo[2554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 12:10:58 volumio sudo[2554]: pam_unix(sudo:session): session closed for user root Mar 26 12:10:58 volumio sudo[2556]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 12:10:58 volumio sudo[2556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 12:10:58 volumio sudo[2556]: pam_unix(sudo:session): session closed for user root Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::volumioGetQueue Mar 26 12:10:58 volumio volumio[1250]: info: CoreStateMachine::getQueue Mar 26 12:10:58 volumio volumio[1250]: info: CorePlayQueue::getQueue Mar 26 12:10:58 volumio volumio[1250]: info: Listing playlists Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 26 12:10:58 volumio volumio[1250]: info: Received Get System Info Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:10:58 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 12:10:58 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.43 UA: Mozilla/5.0 (Linux; Android 16; SM-S948N Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::volumioGetQueue Mar 26 12:10:58 volumio volumio[1250]: info: CoreStateMachine::getQueue Mar 26 12:10:58 volumio volumio[1250]: info: CorePlayQueue::getQueue Mar 26 12:10:58 volumio volumio[1250]: info: Listing playlists Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 26 12:10:58 volumio volumio[1250]: info: Received Get System Info Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:10:58 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 26 12:10:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 12:10:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 12:10:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 12:10:59 volumio volumio[1250]: info: Received Get System Info Mar 26 12:10:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:10:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:10:59 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:10:59 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:10:59 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:10:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:10:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 12:10:59 volumio volumio[1250]: info: Received Get System Info Mar 26 12:10:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:10:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:10:59 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:10:59 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:11:00 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:11:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:11:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 26 12:11:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 26 12:11:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 26 12:11:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 12:11:01 volumio volumio[1250]: info: Received Get System Info Mar 26 12:11:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:11:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:11:01 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:11:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:11:01 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:11:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:11:02 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 12:11:02 volumio volumio[1250]: info: Received Get System Info Mar 26 12:11:02 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:11:02 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:11:02 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:11:02 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:11:03 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:11:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:11:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 12:11:03 volumio volumio[1250]: info: Received Get System Info Mar 26 12:11:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:11:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:11:03 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:11:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:11:03 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:11:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:11:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 12:11:03 volumio volumio[1250]: info: Received Get System Info Mar 26 12:11:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:11:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:11:03 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:11:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:11:03 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:11:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:11:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 12:11:03 volumio volumio[1250]: info: Received Get System Info Mar 26 12:11:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:11:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:11:03 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:11:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:11:03 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:11:03 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:11:04 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 12:11:04 volumio volumio[1250]: info: Received Get System Info Mar 26 12:11:04 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:11:04 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:11:04 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:11:04 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:11:04 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:11:04 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:11:08 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 26 12:11:08 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 26 12:11:09 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: carrot_radio , handleBrowseUri Mar 26 12:11:09 volumio volumio[1250]: info: Preload queue cleared Mar 26 12:11:09 volumio volumio[1250]: info: Preloading song: webcarrot/155 Mar 26 12:11:09 volumio volumio[1250]: info: Preloading song: webcarrot/156 Mar 26 12:11:09 volumio volumio[1250]: info: Preloading song: webcarrot/158 Mar 26 12:11:09 volumio volumio[1250]: info: Preloading song: webcarrot/159 Mar 26 12:11:09 volumio volumio[1250]: info: Preloading song: webcarrot/1 Mar 26 12:11:09 volumio volumio[1250]: info: Preloading song: webcarrot/3 Mar 26 12:11:09 volumio volumio[1250]: info: Preloading song: webcarrot/6 Mar 26 12:11:09 volumio volumio[1250]: info: Preloading song: webcarrot/7 Mar 26 12:11:09 volumio volumio[1250]: info: Preloading song: webcarrot/9 Mar 26 12:11:09 volumio volumio[1250]: info: Preloading song: webcarrot/10 Mar 26 12:11:09 volumio volumio[1250]: info: Preloading song: webcarrot/15 Mar 26 12:11:09 volumio volumio[1250]: info: Preloading song: webcarrot/16 Mar 26 12:11:09 volumio volumio[1250]: info: Preloading song: webcarrot/18 Mar 26 12:11:09 volumio volumio[1250]: info: Preloading song: webcarrot/19 Mar 26 12:11:09 volumio volumio[1250]: info: Preloading song: webcarrot/139 Mar 26 12:11:09 volumio volumio[1250]: info: Exploding uri webcarrot/155 in service carrot_radio Mar 26 12:11:09 volumio volumio[1250]: info: [explodeUri] currentStation set: Music Classic FM program=no Mar 26 12:11:09 volumio volumio[1250]: info: Exploding uri webcarrot/156 in service carrot_radio Mar 26 12:11:09 volumio volumio[1250]: info: [explodeUri] currentStation set: LINN Classic program=no Mar 26 12:11:09 volumio volumio[1250]: info: Exploding uri webcarrot/158 in service carrot_radio Mar 26 12:11:09 volumio volumio[1250]: info: [explodeUri] currentStation set: Sveriges Radio P2 program=no Mar 26 12:11:09 volumio volumio[1250]: info: Exploding uri webcarrot/159 in service carrot_radio Mar 26 12:11:09 volumio volumio[1250]: info: [explodeUri] currentStation set: Naim Classical program=no Mar 26 12:11:09 volumio volumio[1250]: info: Exploding uri webcarrot/1 in service carrot_radio Mar 26 12:11:09 volumio volumio[1250]: info: [explodeUri] currentStation set: KBS HappyFM program=yes Mar 26 12:11:09 volumio volumio[1250]: info: Exploding uri webcarrot/3 in service carrot_radio Mar 26 12:11:09 volumio volumio[1250]: info: [explodeUri] currentStation set: KBS ClassicFM program=yes Mar 26 12:11:09 volumio volumio[1250]: info: Exploding uri webcarrot/6 in service carrot_radio Mar 26 12:11:09 volumio volumio[1250]: info: [explodeUri] currentStation set: MBC 표준FM program=yes Mar 26 12:11:09 volumio volumio[1250]: info: Exploding uri webcarrot/7 in service carrot_radio Mar 26 12:11:09 volumio volumio[1250]: info: [explodeUri] currentStation set: MBC FM4U program=yes Mar 26 12:11:09 volumio volumio[1250]: info: Exploding uri webcarrot/9 in service carrot_radio Mar 26 12:11:09 volumio volumio[1250]: info: [explodeUri] currentStation set: SBS 러브FM program=yes Mar 26 12:11:09 volumio volumio[1250]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=kbs&ch=2radio Mar 26 12:11:09 volumio volumio[1250]: info: Exploding uri webcarrot/10 in service carrot_radio Mar 26 12:11:09 volumio volumio[1250]: info: [explodeUri] currentStation set: SBS 파워FM program=yes Mar 26 12:11:09 volumio volumio[1250]: info: Exploding uri webcarrot/15 in service carrot_radio Mar 26 12:11:09 volumio volumio[1250]: info: [explodeUri] currentStation set: TBS FM program=no Mar 26 12:11:09 volumio volumio[1250]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=mbc&ch=sfm Mar 26 12:11:10 volumio volumio[1250]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=mbc&ch=fm4u Mar 26 12:11:10 volumio volumio[1250]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=sbs&ch=powerfm Mar 26 12:11:10 volumio volumio[1250]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=sbs&ch=lovefm Mar 26 12:11:10 volumio volumio[1250]: info: Exploding uri webcarrot/16 in service carrot_radio Mar 26 12:11:10 volumio volumio[1250]: info: [explodeUri] currentStation set: TBS eFM program=no Mar 26 12:11:10 volumio volumio[1250]: info: Exploding uri webcarrot/18 in service carrot_radio Mar 26 12:11:10 volumio volumio[1250]: info: [explodeUri] currentStation set: CBS 표준FM program=yes Mar 26 12:11:10 volumio volumio[1250]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=kbs&ch=1fm Mar 26 12:11:10 volumio volumio[1250]: info: Exploding uri webcarrot/19 in service carrot_radio Mar 26 12:11:10 volumio volumio[1250]: info: [explodeUri] currentStation set: CBS 음악FM program=yes Mar 26 12:11:10 volumio volumio[1250]: info: Exploding uri webcarrot/139 in service carrot_radio Mar 26 12:11:10 volumio volumio[1250]: info: [explodeUri] currentStation set: TBN 강원교통방송 program=no Mar 26 12:11:10 volumio volumio[1250]: info: [_resolveRecursive] Content-Type: audio/mpeg url: http://radio.linn.co.uk:8004/autodj Mar 26 12:11:10 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: http://radio.linn.co.uk:8004/autodj Mar 26 12:11:10 volumio volumio[1250]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=tbs&ch=efm Mar 26 12:11:10 volumio volumio[1250]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=cbs&ch=sfm Mar 26 12:11:10 volumio volumio[1250]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=tbn&city=gangwon Mar 26 12:11:10 volumio volumio[1250]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=cbs&ch=mfm Mar 26 12:11:11 volumio volumio[1250]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=tbs&ch=fm Mar 26 12:11:11 volumio volumio[1250]: info: [_resolveRecursive] 302 -> https://radiolive.sbs.co.kr/powerpc/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NzQ1Mzc4NzEsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjU4ZWFjY2IxLWEyMjAtNGE3My1iYTJjLWJhNzhlZWQ5ZmE1YiIsImlhdCI6MTc3NDQ5NDY3MX0.PGD_U7vkk9zu6YHYQHBKalCjuR8KPPLn5yM7XBkAWDE Mar 26 12:11:11 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://radiolive.sbs.co.kr/powerpc/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NzQ1Mzc4NzEsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjU4ZWFjY2IxLWEyMjAtNGE3My1iYTJjLWJhNzhlZWQ5ZmE1YiIsImlhdCI6MTc3NDQ5NDY3MX0.PGD_U7vkk9zu6YHYQHBKalCjuR8KPPLn5yM7XBkAWDE Mar 26 12:11:11 volumio volumio[1250]: info: [_resolveRecursive] 302 -> https://minisw.imbc.com/dsfm/_definst_/sfm.stream/playlist.m3u8?_lsu_sa_=6B417212236C3DB4124C05AF3DF1A641A5BB34053C05127F3BC04Da3763F31B6DBa9C30B3792B241E04434018Db2E17D38DC51DD6282E8B142881FD4ED5FCB5330A1B4187F4AB0C9596142800C960DBC0AF1AAF94A6532181D98B8A16008252EB11CED51E81CE4D68A7C8C58909B4302 Mar 26 12:11:11 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://minisw.imbc.com/dsfm/_definst_/sfm.stream/playlist.m3u8?_lsu_sa_=6B417212236C3DB4124C05AF3DF1A641A5BB34053C05127F3BC04Da3763F31B6DBa9C30B3792B241E04434018Db2E17D38DC51DD6282E8B142881FD4ED5FCB5330A1B4187F4AB0C9596142800C960DBC0AF1AAF94A6532181D98B8A16008252EB11CED51E81CE4D68A7C8C58909B4302 Mar 26 12:11:11 volumio volumio[1250]: info: [_resolveRecursive] 302 -> https://minimw.imbc.com/dmfm/_definst_/mfm.stream/playlist.m3u8?_lsu_sa_=61719516D3EE36343949D51438F17345E5BF3AF5D00EC2C939D0C9a4F6E13DC6E8aA03BB3402FE41909F3AB17Fb291C7A73C736A2738E34D97D22281A19E04453391B95DC23A4D2FA5C8048E36C4CCC882131D012E2008E242AC6F29C2BA382681853886988322B427F15A2117F9D92F Mar 26 12:11:11 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://minimw.imbc.com/dmfm/_definst_/mfm.stream/playlist.m3u8?_lsu_sa_=61719516D3EE36343949D51438F17345E5BF3AF5D00EC2C939D0C9a4F6E13DC6E8aA03BB3402FE41909F3AB17Fb291C7A73C736A2738E34D97D22281A19E04453391B95DC23A4D2FA5C8048E36C4CCC882131D012E2008E242AC6F29C2BA382681853886988322B427F15A2117F9D92F Mar 26 12:11:11 volumio volumio[1250]: info: [_resolveRecursive] Content-Type: audio/aac url: https://media-ssl.musicradio.com/ClassicFM Mar 26 12:11:11 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://media-ssl.musicradio.com/ClassicFM Mar 26 12:11:11 volumio volumio[1250]: info: [_resolveRecursive] 302 -> https://2radio-ad.gscdn.kbs.co.kr/2radio_ad_192_1.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8ycmFkaW8tYWQuZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQ2Njc0MDR9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=EsZKeCbmbWOfDBnDJKxYmGIg9GqSHeA10z-IWsO2y~F~4Uuxp5VaCHO~IoQ6pSnrCG7g04O67gKUqs5rbLW~IzaMk6ATtjkRnM61yg1EXKnNizthyvae67DoPj6c5pyZX9VzZ1Nr2dxZdp2wUXW4Rtfz9dDjEHOO67S-c9aBRF-N-hgmoLVIpaAb~btg3enQZlFvMumQrWICIJpge04XKrRMmW4kBw9fqWmSTyxjiuEQhpxF1baINvmqkkELW9nJtqH589QUnaaQe0pmtbeb4XRyTkwr5lNGUNeCrC3Xbo71Xc-Uq~-8Hl4qna8urL2gf7JYiLSOcooU3DihYMf3Vg__ Mar 26 12:11:11 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://2radio-ad.gscdn.kbs.co.kr/2radio_ad_192_1.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8ycmFkaW8tYWQuZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQ2Njc0MDR9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=EsZKeCbmbWOfDBnDJKxYmGIg9GqSHeA10z-IWsO2y~F~4Uuxp5VaCHO~IoQ6pSnrCG7g04O67gKUqs5rbLW~IzaMk6ATtjkRnM61yg1EXKnNizthyvae67DoPj6c5pyZX9VzZ1Nr2dxZdp2wUXW4Rtfz9dDjEHOO67S-c9aBRF-N-hgmoLVIpaAb~btg3enQZlFvMumQrWICIJpge04XKrRMmW4kBw9fqWmSTyxjiuEQhpxF1baINvmqkkELW9nJtqH589QUnaaQe0pmtbeb4XRyTkwr5lNGUNeCrC3Xbo71Xc-Uq~-8Hl4qna8urL2gf7JYiLSOcooU3DihYMf3Vg__ Mar 26 12:11:11 volumio volumio[1250]: info: [_resolveRecursive] 302 -> https://edge1.sr.se/p2-flac Mar 26 12:11:11 volumio volumio[1250]: info: [_resolveRecursive] Content-Type: audio/ogg url: https://mscp3.live-streams.nl:8252/class-flac.flac Mar 26 12:11:11 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://mscp3.live-streams.nl:8252/class-flac.flac Mar 26 12:11:12 volumio volumio[1250]: info: [_resolveRecursive] 302 -> https://radiolive.sbs.co.kr/lovepc/lovefm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NzQ1Mzc4NzEsInBhdGgiOiIvbG92ZWZtLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiYjlmMmY5ZGEtYTIyNy00YTU0LWJhZDMtMDQyYzFjMjJmOWNhIiwiaWF0IjoxNzc0NDk0NjcxfQ.Wlq6yOkZ-gbBQoRvsNxF_sxc1ewKPloL-JOB0vt6rOE Mar 26 12:11:12 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://radiolive.sbs.co.kr/lovepc/lovefm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NzQ1Mzc4NzEsInBhdGgiOiIvbG92ZWZtLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiYjlmMmY5ZGEtYTIyNy00YTU0LWJhZDMtMDQyYzFjMjJmOWNhIiwiaWF0IjoxNzc0NDk0NjcxfQ.Wlq6yOkZ-gbBQoRvsNxF_sxc1ewKPloL-JOB0vt6rOE Mar 26 12:11:12 volumio volumio[1250]: info: [_resolveRecursive] 302 -> https://cdnefm.tbs.seoul.kr/tbs/_definst_/tbs_efm_web_360.smil/playlist.m3u8 Mar 26 12:11:12 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://cdnefm.tbs.seoul.kr/tbs/_definst_/tbs_efm_web_360.smil/playlist.m3u8 Mar 26 12:11:12 volumio volumio[1250]: info: [_resolveRecursive] 302 -> https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQ2Njc0Mjh9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Ls3bAE17VaRsc-diMhK2sp4-akB2BWNuzF7JFT8jtpnu2KOymX~CBeDmh2r24dtl~ZqMjgup923RnLSRBzlYYWA0lZJiaG6F5Ww1b3gyA2RU9p5mQBgmu~Mtbpg9wR273pLqoAQ8oFTrrPCNplhcl-xzQzDhENTDTIAxqd48iMvADxB5xe~HjB4psjVK1mvRO2awo4qfXIN8MlS1lj0g9oGQPGzv4RyAYTKpaPR2Q9G5-3uTLwmL9qqjIWL-eKTTOvtMVk1GV7N38tGfmaiZ-T19UGMPxUXoW9Lg7JgRg03art~zGYeqperuYPD-olJ3UifJqX7nrt00nVjubEXPxw__ Mar 26 12:11:12 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQ2Njc0Mjh9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Ls3bAE17VaRsc-diMhK2sp4-akB2BWNuzF7JFT8jtpnu2KOymX~CBeDmh2r24dtl~ZqMjgup923RnLSRBzlYYWA0lZJiaG6F5Ww1b3gyA2RU9p5mQBgmu~Mtbpg9wR273pLqoAQ8oFTrrPCNplhcl-xzQzDhENTDTIAxqd48iMvADxB5xe~HjB4psjVK1mvRO2awo4qfXIN8MlS1lj0g9oGQPGzv4RyAYTKpaPR2Q9G5-3uTLwmL9qqjIWL-eKTTOvtMVk1GV7N38tGfmaiZ-T19UGMPxUXoW9Lg7JgRg03art~zGYeqperuYPD-olJ3UifJqX7nrt00nVjubEXPxw__ Mar 26 12:11:12 volumio volumio[1250]: info: [_resolveRecursive] 302 -> https://m-aac.cbs.co.kr/mweb_cbs981/_definst_/cbs981.stream/playlist.m3u8 Mar 26 12:11:12 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://m-aac.cbs.co.kr/mweb_cbs981/_definst_/cbs981.stream/playlist.m3u8 Mar 26 12:11:12 volumio volumio[1250]: info: [_resolveRecursive] 302 -> https://5cc6beb04faf6.streamlock.net/gangwon/myStream/playlist.m3u8 Mar 26 12:11:12 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://5cc6beb04faf6.streamlock.net/gangwon/myStream/playlist.m3u8 Mar 26 12:11:12 volumio volumio[1250]: info: [_resolveRecursive] 302 -> https://cdnfm.tbs.seoul.kr/tbs/_definst_/tbs_fm_web_360.smil/playlist.m3u8 Mar 26 12:11:12 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://cdnfm.tbs.seoul.kr/tbs/_definst_/tbs_fm_web_360.smil/playlist.m3u8 Mar 26 12:11:12 volumio volumio[1250]: info: [_resolveRecursive] 302 -> https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8 Mar 26 12:11:12 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8 Mar 26 12:11:13 volumio volumio[1250]: info: [ytmusic] Innertube support service: result: {"status":"started","server":{"address":"127.0.0.1","port":42923}} Mar 26 12:11:13 volumio volumio[1250]: info: [ytmusic] Innertube support service running at http://127.0.0.1:42923 Mar 26 12:11:13 volumio volumio[1250]: info: [_resolveRecursive] Content-Type: application/ogg url: https://edge1.sr.se/p2-flac Mar 26 12:11:13 volumio volumio[1250]: info: [_parsePlaylistBody] unrecognized content, using: https://edge1.sr.se/p2-flac Mar 26 12:11:13 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://edge1.sr.se/p2-flac Mar 26 12:11:16 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 12:11:16 volumio volumio[1250]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 26 12:11:16 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 26 12:11:16 volumio volumio[1250]: info: Received Get System Version Mar 26 12:11:16 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 12:11:16 volumio volumio[1250]: info: Received Get System Info Mar 26 12:11:16 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:11:16 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:11:16 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:11:16 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:11:16 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:11:16 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:11:16 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 12:11:17 volumio volumio[1250]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 26 12:11:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 26 12:11:17 volumio volumio[1250]: info: Received Get System Version Mar 26 12:11:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 12:11:17 volumio volumio[1250]: info: Received Get System Info Mar 26 12:11:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 12:11:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 12:11:17 volumio volumio[1250]: info: Discovery: Getting this device information Mar 26 12:11:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Mar 26 12:11:17 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Mar 26 12:11:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 12:11:44 volumio volumio[1250]: [YOUTUBEJS][Player]: Failed to extract signature decipher function. Mar 26 12:11:44 volumio volumio[1250]: [YOUTUBEJS][Player]: Failed to extract n decipher function. Mar 26 12:11:44 volumio volumio[1250]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 12:11:44 volumio volumio[1250]: Error: Unable to resolve or reject the same promise twice Mar 26 12:11:44 volumio volumio[1250]: at Promise.reject (/data/plugins/music_service/carrot_radio/node_modules/kew/kew.js:189:43) Mar 26 12:11:44 volumio volumio[1250]: at ClientRequest. (/data/plugins/music_service/carrot_radio/index.js:737:56) Mar 26 12:11:44 volumio volumio[1250]: at Object.onceWrapper (node:events:628:28) Mar 26 12:11:44 volumio volumio[1250]: at ClientRequest.emit (node:events:514:28) Mar 26 12:11:44 volumio volumio[1250]: at TLSSocket.emitRequestTimeout (node:_http_client:840:9) Mar 26 12:11:44 volumio volumio[1250]: at Object.onceWrapper (node:events:628:28) Mar 26 12:11:44 volumio volumio[1250]: at TLSSocket.emit (node:events:526:35) Mar 26 12:11:44 volumio volumio[1250]: at Socket._onTimeout (node:net:589:8) Mar 26 12:11:44 volumio volumio[1250]: at listOnTimeout (node:internal/timers:573:17) Mar 26 12:11:44 volumio volumio[1250]: at process.processTimers (node:internal/timers:514:7) Mar 26 12:11:44 volumio volumio[1250]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 12:11:48 volumio sudo[2645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 12:10' Mar 26 12:11:48 volumio sudo[2645]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"