-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Mon 2026-05-25 18:52:48 UTC. -- May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.481Z level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.0.101:57858 error="read tcp 192.168.0.226:7331->192.168.0.101:57858: read: connection reset by peer" May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.481Z level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.0.101:46640 error="read tcp 192.168.0.225:7331->192.168.0.101:46640: read: connection reset by peer" May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.482Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.101:46640 May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.482Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.101:46640 May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.481Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.101:57858 May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.482Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.101:57858 May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.481Z level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.0.101:34958 error="read tcp 192.168.0.226:7331->192.168.0.101:34958: read: connection reset by peer" May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.482Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.101:34958 May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.482Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.101:34958 May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.481Z level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.0.101:35150 error="read tcp 192.168.0.226:7331->192.168.0.101:35150: read: connection reset by peer" May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.482Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.101:35150 May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.482Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.101:35150 May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.481Z level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.0.101:57846 error="read tcp 192.168.0.226:7331->192.168.0.101:57846: read: connection reset by peer" May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.482Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.101:57846 May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.482Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.101:57846 May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.482Z level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.0.101:45188 error="read tcp 192.168.0.225:7331->192.168.0.101:45188: read: connection reset by peer" May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.483Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.101:45188 May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.483Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.101:45188 May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.482Z level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.0.101:56304 error="read tcp 192.168.0.225:7331->192.168.0.101:56304: read: connection reset by peer" May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.483Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.101:56304 May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.483Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.101:56304 May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.482Z level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.0.101:46650 error="read tcp 192.168.0.225:7331->192.168.0.101:46650: read: connection reset by peer" May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.483Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.101:46650 May 25 18:51:01 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:01.483Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.101:46650 May 25 18:51:09 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:51:09Z" level=trace msg="sent dealer ping" May 25 18:51:09 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:51:09Z" level=trace msg="received dealer pong" May 25 18:51:09 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:51:09Z" level=debug msg="handling play player command from 51e72356c70e195cd380bb3c897f43cf0357e991" May 25 18:51:09 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:51:09Z" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1E4ymRlEImstPi" May 25 18:51:09 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:51:09Z" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1E4ymRlEImstPi" May 25 18:51:09 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:51:09Z" level=debug msg="shuffled context with seed 11824327239502239971 (len: 50, keep: 42)" uri="spotify:playlist:37i9dQZF1E4ymRlEImstPi" May 25 18:51:31 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:51:31Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 306" May 25 18:51:31 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:51:31Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 458" May 25 18:51:31 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:51:31Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2173" May 25 18:51:32 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:51:32Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1618" May 25 18:51:39 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:51:39Z" level=trace msg="sent dealer ping" May 25 18:51:40 rotten-pie-dac volumiologrotate[612]: ls: cannot access '/var/log/samba/log.wb-ROTTEN': No such file or directory May 25 18:51:40 rotten-pie-dac volumiologrotate[612]: ls: cannot access 'PIE': No such file or directory May 25 18:51:40 rotten-pie-dac volumiologrotate[612]: ls: cannot access 'DAC': No such file or directory May 25 18:51:59 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:59.448Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.101:60140 May 25 18:51:59 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:51:59.448Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.101:43312 May 25 18:52:00 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:00.691Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.101:56188 May 25 18:52:00 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:00.695Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.101:35060 May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 18:52:01 rotten-pie-dac volumio[1105]: info: Discovery: Getting this device information May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::volumioGetState May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 18:52:01 rotten-pie-dac volumio[1105]: verbose: New Socket.io Connection to 192.168.0.225:3000 from 192.168.0.101 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 18:52:01 rotten-pie-dac volumio[1105]: info: Discovery: Getting this device information May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::volumioGetState May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 18:52:01 rotten-pie-dac volumio[1105]: verbose: New Socket.io Connection to 192.168.0.226:3000 from 192.168.0.101 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 18:52:01 rotten-pie-dac volumio[1105]: info: Discovery: Getting this device information May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::volumioGetState May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 18:52:01 rotten-pie-dac volumio[1105]: verbose: New Socket.io Connection to 192.168.0.225:3000 from 192.168.0.101 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 18:52:01 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 18:52:05 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:05.783Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.101:50238 May 25 18:52:05 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:05.784Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.101:53902 May 25 18:52:06 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:06.859Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.101:56196 May 25 18:52:06 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:06.859Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.101:35066 May 25 18:52:07 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:07.762Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" latency=156.429254ms platform=PLATFORM_ANDROID version=5.260413.0 May 25 18:52:07 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:07.764Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" latency=155.463484ms timeout=10s May 25 18:52:07 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:07.764Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" May 25 18:52:07 rotten-pie-dac volumio[1105]: info: Received Get System Info May 25 18:52:07 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 18:52:07 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 18:52:07 rotten-pie-dac volumio[1105]: info: Discovery: Getting this device information May 25 18:52:07 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::volumioGetState May 25 18:52:07 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 18:52:07 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:07.767Z level=INFO msg="emitting device name changed event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" name="Rotten Pie DAC" May 25 18:52:07 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:07.768Z level=INFO msg="emitting device language changed event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" language=en May 25 18:52:07 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 25 18:52:07 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:07.771Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" timezone=UTC May 25 18:52:07 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:07.772Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" available=true connected=true macAddress=dc:a6:32:08:d4:c5 ip4Address=192.168.0.225/24 ip6Address= May 25 18:52:07 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:07.774Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" available=true connected=true macAddress=dc:a6:32:08:d4:c6 ip4Address=192.168.0.226/24 ip6Address= ssid=TP-Link_D05F_5G May 25 18:52:07 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:07.774Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" setupComplete=true May 25 18:52:07 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices May 25 18:52:07 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 25 18:52:07 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 18:52:07 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 25 18:52:07 rotten-pie-dac volumio[1105]: amixer -c 0 info | grep "bcm2835 ALSA" May 25 18:52:07 rotten-pie-dac volumio[1105]: amixer -c 1 info | grep "bcm2835 Headphones" May 25 18:52:07 rotten-pie-dac volumio[1105]: Card hw:1 'Headphones'/'bcm2835 Headphones' May 25 18:52:07 rotten-pie-dac volumio[1105]: amixer -c 5 info | grep "D10s" May 25 18:52:07 rotten-pie-dac volumio[1105]: Card hw:5 'D10s'/'Topping D10s at usb-0000:01:00.0-1.2, high speed' May 25 18:52:08 rotten-pie-dac volumio[1105]: amixer -c 5 info | grep "D10s" May 25 18:52:08 rotten-pie-dac volumio[1105]: Card hw:5 'D10s'/'Topping D10s at usb-0000:01:00.0-1.2, high speed' May 25 18:52:08 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:08.045Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" selectedOutputId=5 May 25 18:52:08 rotten-pie-dac volumio[1105]: info: Received Get System Info May 25 18:52:08 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 18:52:08 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 18:52:08 rotten-pie-dac volumio[1105]: info: Discovery: Getting this device information May 25 18:52:08 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::volumioGetState May 25 18:52:08 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 18:52:08 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:08.053Z level=INFO msg="emitting software info changed event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" currentVersion=3.912 latestVersion=3.912 May 25 18:52:08 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:08.054Z level=INFO msg="emitting software update progress event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" status=UPDATE_STATUS_NONE progress=0 May 25 18:52:08 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:08.054Z level=INFO msg="emitting user changed event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" userId= May 25 18:52:08 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:08.054Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" providers=3 May 25 18:52:08 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 18:52:08 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 18:52:08 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 25 18:52:08 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:08.554Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" plugins=54 May 25 18:52:08 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::volumioGetState May 25 18:52:08 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:08.557Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" state=STATUS_PLAYING positionMs=163001 volume=100 May 25 18:52:08 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:08.558Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" id=spotify:track:3p9Qh1FmFUQvqmlKNB4CIw title="PIE N MASH" May 25 18:52:08 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:08.575Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" latency=157.272938ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE May 25 18:52:09 rotten-pie-dac sudo[2334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 18:52:09 rotten-pie-dac sudo[2334]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 18:52:09 rotten-pie-dac sudo[2334]: pam_unix(sudo:session): session closed for user root May 25 18:52:09 rotten-pie-dac sudo[2336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 18:52:09 rotten-pie-dac sudo[2336]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 18:52:09 rotten-pie-dac sudo[2336]: pam_unix(sudo:session): session closed for user root May 25 18:52:09 rotten-pie-dac volumio[1105]: verbose: New Socket.io Connection to 192.168.0.225 from 192.168.0.101 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 25 18:52:09 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:52:09Z" level=trace msg="received accesspoint ping" May 25 18:52:09 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:52:09Z" level=trace msg="received accesspoint pong ack" May 25 18:52:09 rotten-pie-dac sudo[2340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 18:52:09 rotten-pie-dac sudo[2340]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 18:52:09 rotten-pie-dac sudo[2340]: pam_unix(sudo:session): session closed for user root May 25 18:52:09 rotten-pie-dac sudo[2343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 18:52:09 rotten-pie-dac sudo[2343]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 18:52:09 rotten-pie-dac sudo[2343]: pam_unix(sudo:session): session closed for user root May 25 18:52:09 rotten-pie-dac volumio[1105]: verbose: New Socket.io Connection to 192.168.0.225 from 192.168.0.101 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 25 18:52:09 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:52:09Z" level=error msg="did not receive last pong from dealer, 60s passed" May 25 18:52:09 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 18:52:09 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::volumioGetVisibleSources May 25 18:52:09 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 18:52:09 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::volumioGetState May 25 18:52:09 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 25 18:52:09 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 25 18:52:09 rotten-pie-dac volumio[1105]: info: Received Get System Info May 25 18:52:09 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 18:52:09 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 18:52:09 rotten-pie-dac volumio[1105]: info: Discovery: Getting this device information May 25 18:52:09 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::volumioGetState May 25 18:52:09 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 18:52:09 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::volumioGetState May 25 18:52:09 rotten-pie-dac volumio[1105]: info: Listing playlists May 25 18:52:09 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 18:52:09 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 18:52:09 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:52:09 rotten-pie-dac volumio[1105]: info: FusionDsp - no preset used May 25 18:52:09 rotten-pie-dac volumio[1105]: info: FusionDsp - undefined May 25 18:52:09 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 25 18:52:11 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 25 18:52:11 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 18:52:11 rotten-pie-dac volumio[1105]: info: Received Get System Info May 25 18:52:11 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 18:52:11 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 18:52:11 rotten-pie-dac volumio[1105]: info: Discovery: Getting this device information May 25 18:52:11 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::volumioGetState May 25 18:52:11 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 18:52:11 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:11.930Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.101:52326 May 25 18:52:11 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:11.930Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.101:39640 May 25 18:52:12 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 18:52:12 rotten-pie-dac volumio[1105]: info: Received Get System Info May 25 18:52:12 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 18:52:12 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 18:52:12 rotten-pie-dac volumio[1105]: info: Discovery: Getting this device information May 25 18:52:12 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::volumioGetState May 25 18:52:12 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 18:52:12 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::volumioSeek May 25 18:52:12 rotten-pie-dac volumio[1105]: info: CoreStateMachine::seek May 25 18:52:12 rotten-pie-dac volumio[1105]: info: Spotify seek to: 51000 May 25 18:52:12 rotten-pie-dac volumio[1105]: info: Sending Spotify command with payload to local API: /player/seek May 25 18:52:12 rotten-pie-dac volumio[1105]: info: CoreStateMachine::startPlaybackTimer May 25 18:52:12 rotten-pie-dac volumio[1105]: info: CorePlayQueue::getTrack 0 May 25 18:52:12 rotten-pie-dac volumio[1105]: info: CoreStateMachine::pushState May 25 18:52:12 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 18:52:12 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::volumioPushState May 25 18:52:12 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:12.774Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" state=STATUS_PLAYING positionMs=167001 volume=100 May 25 18:52:12 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:12.774Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:60140,192.168.0.101:43312 @ 0x22d4120" id=spotify:track:3p9Qh1FmFUQvqmlKNB4CIw title="PIE N MASH" May 25 18:52:12 rotten-pie-dac volumio[1105]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 25 18:52:16 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:52:16Z" level=debug msg="prefetching next track" uri="spotify:track:06DUezT8lAj9sYLOGcXjug" May 25 18:52:16 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:52:16Z" level=debug msg="selected format OGG_VORBIS_320 (91c06d00cff05f016139124bfb5aa9aa1e75007e)" uri="spotify:track:06DUezT8lAj9sYLOGcXjug" May 25 18:52:16 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:52:16Z" level=debug msg="requested aes key for file 91c06d00cff05f016139124bfb5aa9aa1e75007e, gid: 06DUezT8lAj9sYLOGcXjug" May 25 18:52:16 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:52:16Z" level=trace msg="found 2 cdn urls" uri="spotify:track:06DUezT8lAj9sYLOGcXjug" May 25 18:52:16 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:52:16Z" level=debug msg="fetched first chunk of 16, total size is 8042668 bytes" uri="spotify:track:06DUezT8lAj9sYLOGcXjug" May 25 18:52:16 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:52:16Z" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:06DUezT8lAj9sYLOGcXjug" May 25 18:52:16 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:52:16Z" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:06DUezT8lAj9sYLOGcXjug" May 25 18:52:16 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:52:16Z" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:06DUezT8lAj9sYLOGcXjug" May 25 18:52:18 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:18.074Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.101:39652 May 25 18:52:18 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:18.074Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.101:52334 May 25 18:52:19 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 25 18:52:27 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 18:52:27 rotten-pie-dac volumio[1105]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 25 18:52:27 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 25 18:52:27 rotten-pie-dac volumio[1105]: info: Received Get System Version May 25 18:52:27 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 18:52:27 rotten-pie-dac volumio[1105]: info: Received Get System Info May 25 18:52:27 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 18:52:27 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 18:52:27 rotten-pie-dac volumio[1105]: info: Discovery: Getting this device information May 25 18:52:27 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::volumioGetState May 25 18:52:27 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 18:52:29 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:52:29 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 25 18:52:29 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 25 18:52:29 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 25 18:52:29 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 25 18:52:29 rotten-pie-dac volumio[1105]: info: Received Get System Version May 25 18:52:29 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 18:52:34 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 18:52:34 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 25 18:52:34 rotten-pie-dac volumio[1105]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 18:52:34 rotten-pie-dac volumio[1105]: info: FusionDsp - no preset used May 25 18:52:34 rotten-pie-dac volumio[1105]: info: FusionDsp - undefined May 25 18:52:36 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:52:36Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 306" May 25 18:52:39 rotten-pie-dac go-librespot[1290]: time="2026-05-25T18:52:39Z" level=error msg="did not receive last pong from dealer, 90s passed" May 25 18:52:40 rotten-pie-dac volumiologrotate[612]: ls: cannot access '/var/log/samba/log.wb-ROTTEN': No such file or directory May 25 18:52:40 rotten-pie-dac volumiologrotate[612]: ls: cannot access 'PIE': No such file or directory May 25 18:52:40 rotten-pie-dac volumiologrotate[612]: ls: cannot access 'DAC': No such file or directory May 25 18:52:46 rotten-pie-dac volumio[1105]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf May 25 18:52:46 rotten-pie-dac volumio5-onboarding[1254]: time=2026-05-25T18:52:46.471Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 18:52:48 rotten-pie-dac volumio[1105]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf May 25 18:52:48 rotten-pie-dac volumio[1105]: info: FusionDsp - Stopping FusionDsp service May 25 18:52:48 rotten-pie-dac volumio[1105]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 18:52:48 rotten-pie-dac volumio[1105]: TypeError: Cannot read property 'stop' of null May 25 18:52:48 rotten-pie-dac volumio[1105]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) May 25 18:52:48 rotten-pie-dac volumio[1105]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 25 18:52:48 rotten-pie-dac volumio[1105]: at /volumio/app/pluginmanager.js:956:12 May 25 18:52:48 rotten-pie-dac volumio[1105]: at ChildProcess.exithandler (child_process.js:299:7) May 25 18:52:48 rotten-pie-dac volumio[1105]: at ChildProcess.emit (events.js:315:20) May 25 18:52:48 rotten-pie-dac volumio[1105]: at maybeClose (internal/child_process.js:1048:16) May 25 18:52:48 rotten-pie-dac volumio[1105]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) May 25 18:52:48 rotten-pie-dac volumio[1105]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 18:52:48 rotten-pie-dac sudo[2472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-25 18:51 May 25 18:52:48 rotten-pie-dac sudo[2472]: 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"