-- Logs begin at Fri 2026-02-27 11:05:50 CET, end at Sat 2026-04-11 15:56:21 CEST. -- Apr 11 15:55:01 volumio go-librespot[7243]: time="2026-04-11T15:55:01+02:00" level=debug msg="update volume requested to 47840/65535" Apr 11 15:55:01 volumio go-librespot[7243]: time="2026-04-11T15:55:01+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 11 15:55:01 volumio go-librespot[7243]: time="2026-04-11T15:55:01+02:00" level=trace msg="emitting websocket event: volume" Apr 11 15:55:01 volumio volumio[830]: info: Setting Volumio Volume from Spotify: 73 Apr 11 15:55:01 volumio volumio[830]: info: VolumeController::SetAlsaVolume73 Apr 11 15:55:01 volumio volumio[830]: info: CoreStateMachine::pushState Apr 11 15:55:01 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 15:55:01 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:02 volumio go-librespot[7243]: time="2026-04-11T15:55:02+02:00" level=debug msg="update volume requested to 45219/65535" Apr 11 15:55:02 volumio go-librespot[7243]: time="2026-04-11T15:55:02+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 11 15:55:02 volumio go-librespot[7243]: time="2026-04-11T15:55:02+02:00" level=trace msg="emitting websocket event: volume" Apr 11 15:55:02 volumio go-librespot[7243]: time="2026-04-11T15:55:02+02:00" level=debug msg="update volume requested to 42597/65535" Apr 11 15:55:02 volumio volumio[830]: info: Setting Volumio Volume from Spotify: 69 Apr 11 15:55:02 volumio volumio[830]: info: VolumeController::SetAlsaVolume69 Apr 11 15:55:02 volumio volumio[830]: info: CoreStateMachine::pushState Apr 11 15:55:02 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 15:55:02 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:03 volumio go-librespot[7243]: time="2026-04-11T15:55:03+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 11 15:55:03 volumio go-librespot[7243]: time="2026-04-11T15:55:03+02:00" level=trace msg="emitting websocket event: volume" Apr 11 15:55:03 volumio volumio[830]: info: Setting Volumio Volume from Spotify: 65 Apr 11 15:55:03 volumio volumio[830]: info: VolumeController::SetAlsaVolume65 Apr 11 15:55:03 volumio volumio[830]: info: CoreStateMachine::pushState Apr 11 15:55:03 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 15:55:03 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Apr 11 15:55:04 volumio go-librespot[7243]: time="2026-04-11T15:55:04+02:00" level=debug msg="fetched chunk 4/8, size: 524288" uri="spotify:track:0CzGNTUUjcaqJHN7cmkpJF" Apr 11 15:55:09 volumio go-librespot[7243]: time="2026-04-11T15:55:09+02:00" level=trace msg="sent dealer ping" Apr 11 15:55:13 volumio go-librespot[7243]: time="2026-04-11T15:55:13+02:00" level=trace msg="received dealer pong" Apr 11 15:55:20 volumio go-librespot[7243]: time="2026-04-11T15:55:20+02:00" level=debug msg="fetched chunk 5/8, size: 524288" uri="spotify:track:0CzGNTUUjcaqJHN7cmkpJF" Apr 11 15:55:36 volumio go-librespot[7243]: time="2026-04-11T15:55:36+02:00" level=debug msg="fetched chunk 6/8, size: 524288" uri="spotify:track:0CzGNTUUjcaqJHN7cmkpJF" Apr 11 15:55:39 volumio go-librespot[7243]: time="2026-04-11T15:55:39+02:00" level=trace msg="sent dealer ping" Apr 11 15:55:39 volumio go-librespot[7243]: time="2026-04-11T15:55:39+02:00" level=trace msg="received dealer pong" Apr 11 15:55:44 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:44.154+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.50:61240 Apr 11 15:55:44 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:44.310+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.50:61240 @ 0x3001080" latency=163.496403ms platform=PLATFORM_IOS version=5.260331.0 Apr 11 15:55:44 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:44.327+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.50:61240 @ 0x3001080" latency=179.278011ms timeout=10s Apr 11 15:55:44 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:44.341+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" Apr 11 15:55:44 volumio volumio[830]: info: Received Get System Info Apr 11 15:55:44 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 15:55:44 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 15:55:44 volumio volumio[830]: info: Discovery: Getting this device information Apr 11 15:55:44 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 11 15:55:44 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 15:55:44 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:44.417+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" name=Volumio Apr 11 15:55:44 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:44.446+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" language=sv Apr 11 15:55:44 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Apr 11 15:55:44 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:44.478+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" timezone=Europe/Stockholm Apr 11 15:55:44 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:44.516+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" available=true connected=false macAddress= ip4Address= ip6Address= Apr 11 15:55:44 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:44.553+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" available=true connected=true macAddress=c8:d3:a3:0b:fe:fb ip4Address=192.168.1.207/24 ip6Address= ssid=bibot Apr 11 15:55:44 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:44.564+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" setupComplete=true Apr 11 15:55:44 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Apr 11 15:55:45 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 11 15:55:45 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 15:55:45 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 15:55:45 volumio volumio[830]: amixer -c 0 info | grep "bcm2835 ALSA" Apr 11 15:55:46 volumio volumio[830]: amixer -c 1 info | grep "bcm2835 Headphones" Apr 11 15:55:46 volumio volumio[830]: Card hw:1 'Headphones'/'bcm2835 Headphones' Apr 11 15:55:47 volumio volumio[830]: amixer -c 5 info | grep "SMSL DA100" Apr 11 15:55:47 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:47.888+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.50:61240 @ 0x3001080" latency=168.786855ms timeout=10s Apr 11 15:55:47 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:47.904+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" Apr 11 15:55:47 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:47.901+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.50:61240 @ 0x3001080" latency=184.878526ms platform=PLATFORM_IOS version=5.260331.0 Apr 11 15:55:47 volumio volumio[830]: Card hw:5 'DA100'/'SMSL SILICON SMSL DA100 at usb-20980000.usb-1.2, full speed' Apr 11 15:55:47 volumio volumio[830]: info: Received Get System Info Apr 11 15:55:47 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 15:55:47 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 15:55:47 volumio volumio[830]: info: Discovery: Getting this device information Apr 11 15:55:47 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 11 15:55:48 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 15:55:48 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:48.023+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" name=Volumio Apr 11 15:55:48 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:48.054+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" language=sv Apr 11 15:55:48 volumio volumio[830]: amixer -c 5 info | grep "SMSL DA100" Apr 11 15:55:48 volumio volumio[830]: Card hw:5 'DA100'/'SMSL SILICON SMSL DA100 at usb-20980000.usb-1.2, full speed' Apr 11 15:55:48 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Apr 11 15:55:48 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:48.940+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" selectedOutputId=5 Apr 11 15:55:49 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:48.977+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" timezone=Europe/Stockholm Apr 11 15:55:49 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 15:55:49 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 15:55:49 volumio volumio[830]: info: Discovery: Getting this device information Apr 11 15:55:49 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 11 15:55:49 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 15:55:49 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:49.062+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" available=true connected=false macAddress= ip4Address= ip6Address= Apr 11 15:55:49 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:49.109+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" available=true connected=true macAddress=c8:d3:a3:0b:fe:fb ip4Address=192.168.1.207/24 ip6Address= ssid=bibot Apr 11 15:55:49 volumio volumio[830]: info: Received Get System Info Apr 11 15:55:49 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 15:55:49 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 15:55:49 volumio volumio[830]: info: Discovery: Getting this device information Apr 11 15:55:49 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 11 15:55:49 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 15:55:49 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:49.164+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" setupComplete=true Apr 11 15:55:49 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:49.196+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" currentVersion=3.912 latestVersion=3.912 Apr 11 15:55:49 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:49.209+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.50:61240 @ 0x3001080" status=UPDATE_STATUS_NONE progress=0 Apr 11 15:55:49 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:49.230+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" userId= Apr 11 15:55:49 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:49.233+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" providers=3 Apr 11 15:55:49 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:49.235+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" plugins=54 Apr 11 15:55:49 volumio volumio[830]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.50 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Apr 11 15:55:49 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Apr 11 15:55:49 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 11 15:55:49 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 15:55:49 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 15:55:49 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 11 15:55:50 volumio volumio[830]: amixer -c 0 info | grep "bcm2835 ALSA" Apr 11 15:55:51 volumio volumio[830]: amixer -c 1 info | grep "bcm2835 Headphones" Apr 11 15:55:52 volumio volumio[830]: Card hw:1 'Headphones'/'bcm2835 Headphones' Apr 11 15:55:52 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:52.362+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" state=STATUS_PLAYING positionMs=60018 volume=65 Apr 11 15:55:52 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:52.382+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" id=spotify:track:0CzGNTUUjcaqJHN7cmkpJF title="Echoes From Pangea" Apr 11 15:55:52 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 15:55:52 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 15:55:53 volumio volumio[830]: amixer -c 5 info | grep "SMSL DA100" Apr 11 15:55:53 volumio go-librespot[7243]: time="2026-04-11T15:55:53+02:00" level=debug msg="fetched chunk 7/8, size: 524288" uri="spotify:track:0CzGNTUUjcaqJHN7cmkpJF" Apr 11 15:55:53 volumio volumio[830]: Card hw:5 'DA100'/'SMSL SILICON SMSL DA100 at usb-20980000.usb-1.2, full speed' Apr 11 15:55:54 volumio volumio[830]: amixer -c 5 info | grep "SMSL DA100" Apr 11 15:55:54 volumio volumio[830]: Card hw:5 'DA100'/'SMSL SILICON SMSL DA100 at usb-20980000.usb-1.2, full speed' Apr 11 15:55:54 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:54.793+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" selectedOutputId=5 Apr 11 15:55:54 volumio volumio[830]: info: Received Get System Info Apr 11 15:55:54 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 15:55:54 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 15:55:54 volumio volumio[830]: info: Discovery: Getting this device information Apr 11 15:55:54 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 11 15:55:54 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 15:55:54 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:54.887+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" currentVersion=3.912 latestVersion=3.912 Apr 11 15:55:54 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:54.899+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.50:61240 @ 0x3001080" status=UPDATE_STATUS_NONE progress=0 Apr 11 15:55:54 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:54.912+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" userId= Apr 11 15:55:54 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:54.924+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" providers=3 Apr 11 15:55:54 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:54.934+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" plugins=54 Apr 11 15:55:54 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 11 15:55:54 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:54.986+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" state=STATUS_PLAYING positionMs=63018 volume=65 Apr 11 15:55:55 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:55.004+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" id=spotify:track:0CzGNTUUjcaqJHN7cmkpJF title="Echoes From Pangea" Apr 11 15:55:55 volumio volumio5-onboarding[1222]: time=2026-04-11T15:55:55.061+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.50:61240 @ 0x3001080" latency=127.330157ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Apr 11 15:55:56 volumio sudo[23922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 15:55:56 volumio sudo[23922]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 15:55:57 volumio sudo[23922]: pam_unix(sudo:session): session closed for user root Apr 11 15:55:57 volumio sudo[23924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 15:55:57 volumio sudo[23924]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 15:55:57 volumio sudo[23924]: pam_unix(sudo:session): session closed for user root Apr 11 15:55:57 volumio volumio[830]: verbose: New Socket.io Connection to 192.168.1.207 from 192.168.1.50 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Apr 11 15:55:59 volumio sudo[23928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 15:55:59 volumio sudo[23928]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 15:55:59 volumio sudo[23928]: pam_unix(sudo:session): session closed for user root Apr 11 15:56:00 volumio sudo[23930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 15:56:00 volumio sudo[23930]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 15:56:00 volumio sudo[23930]: pam_unix(sudo:session): session closed for user root Apr 11 15:56:00 volumio volumio[830]: verbose: New Socket.io Connection to 192.168.1.207 from 192.168.1.50 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Apr 11 15:56:01 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 11 15:56:01 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 11 15:56:01 volumio volumio[830]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 15:56:01 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 15:56:01 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 11 15:56:01 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 11 15:56:01 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 15:56:01 volumio volumio[830]: info: Received Get System Info Apr 11 15:56:01 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 15:56:01 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 15:56:01 volumio volumio[830]: info: Discovery: Getting this device information Apr 11 15:56:01 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 11 15:56:01 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 15:56:01 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 11 15:56:01 volumio volumio[830]: info: Listing playlists Apr 11 15:56:01 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 11 15:56:02 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 11 15:56:02 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 15:56:02 volumio volumio[830]: info: Received Get System Info Apr 11 15:56:02 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 15:56:02 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 15:56:02 volumio volumio[830]: info: Discovery: Getting this device information Apr 11 15:56:02 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 11 15:56:02 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 15:56:02 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 15:56:02 volumio volumio[830]: info: Received Get System Info Apr 11 15:56:02 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 15:56:02 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 15:56:02 volumio volumio[830]: info: Discovery: Getting this device information Apr 11 15:56:02 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 11 15:56:02 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 15:56:04 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 11 15:56:04 volumio volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 15:56:04 volumio volumio[830]: info: Retrieving Cloud Streaming UI Apr 11 15:56:04 volumio volumio[830]: info: Getting Tidal Cloud Configuration Apr 11 15:56:04 volumio volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 15:56:04 volumio volumio[830]: info: Getting Qobuz Cloud Configuration Apr 11 15:56:04 volumio volumio[830]: info: Asking plugin for UI Config Apr 11 15:56:04 volumio volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 15:56:04 volumio volumio[830]: info: Getting Spotify Cloud Configuration Apr 11 15:56:04 volumio volumio[830]: info: Asking plugin for UI Config Apr 11 15:56:04 volumio volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 15:56:04 volumio volumio[830]: info: Saving Spotify Acccount Apr 11 15:56:04 volumio volumio[830]: info: Got Tidal Cloud Configuration Apr 11 15:56:04 volumio volumio[830]: info: Got it Apr 11 15:56:04 volumio volumio[830]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 11 15:56:04 volumio volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 15:56:04 volumio volumio[830]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 11 15:56:05 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 11 15:56:05 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 11 15:56:05 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 11 15:56:05 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 15:56:05 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 15:56:05 volumio volumio[830]: info: CoreCommandRouter::volumioGetBrowseSources Apr 11 15:56:05 volumio volumio[830]: info: CoreCommandRouter::volumioGetBrowseSources Apr 11 15:56:05 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 15:56:05 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 11 15:56:08 volumio go-librespot[7243]: time="2026-04-11T15:56:08+02:00" level=debug msg="fetched chunk 8/8, size: 302128" uri="spotify:track:0CzGNTUUjcaqJHN7cmkpJF" Apr 11 15:56:09 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 15:56:09 volumio go-librespot[7243]: time="2026-04-11T15:56:09+02:00" level=trace msg="sent dealer ping" Apr 11 15:56:10 volumio go-librespot[7243]: time="2026-04-11T15:56:10+02:00" level=trace msg="received dealer pong" Apr 11 15:56:10 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 11 15:56:11 volumio volumio[830]: info: Received Get System Info Apr 11 15:56:11 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 15:56:11 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 15:56:11 volumio volumio[830]: info: Discovery: Getting this device information Apr 11 15:56:11 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 11 15:56:11 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 15:56:11 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 15:56:11 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 15:56:11 volumio volumio5-onboarding[1222]: time=2026-04-11T15:56:11.398+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.50:61240 @ 0x3001080" available=true connected=true macAddress=c8:d3:a3:0b:fe:fb ip4Address=192.168.1.207/24 ip6Address= ssid=bibot Apr 11 15:56:12 volumio volumio5-onboarding[1222]: time=2026-04-11T15:56:12.475+02:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 11 15:56:13 volumio volumio[830]: info: Disabling MyMusic plugin upnp Apr 11 15:56:13 volumio volumio[830]: info: Enabling MyMusic plugin upnp Apr 11 15:56:13 volumio volumio[830]: info: Enabling plugin upnp Apr 11 15:56:13 volumio volumio[830]: info: Loading plugin "upnp"... Apr 11 15:56:13 volumio volumio[830]: info: [1775915773266] Starting Upmpd Daemon Apr 11 15:56:13 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 15:56:13 volumio volumio[830]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 15:56:13 volumio sudo[23962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Apr 11 15:56:13 volumio sudo[23962]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 15:56:13 volumio volumio[830]: Error: listen EADDRINUSE: address already in use :::6599 Apr 11 15:56:13 volumio volumio[830]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Apr 11 15:56:13 volumio volumio[830]: at listenInCluster (net.js:1366:12) Apr 11 15:56:13 volumio volumio[830]: at Server.listen (net.js:1452:7) Apr 11 15:56:13 volumio volumio[830]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Apr 11 15:56:13 volumio volumio[830]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Apr 11 15:56:13 volumio volumio[830]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 11 15:56:13 volumio volumio[830]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 11 15:56:13 volumio volumio[830]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Apr 11 15:56:13 volumio volumio[830]: code: 'EADDRINUSE', Apr 11 15:56:13 volumio volumio[830]: errno: -98, Apr 11 15:56:13 volumio volumio[830]: syscall: 'listen', Apr 11 15:56:13 volumio volumio[830]: address: '::', Apr 11 15:56:13 volumio volumio[830]: port: 6599 Apr 11 15:56:13 volumio volumio[830]: } Apr 11 15:56:13 volumio volumio[830]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 15:56:13 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Apr 11 15:56:21 volumio sudo[23984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-11 15:55 Apr 11 15:56:21 volumio sudo[23984]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"