Oct 16 19:32:41 shedmedia ntpd[1032]: CLOCK: time stepped by 904748.995273 Oct 16 19:32:41 shedmedia ntpd[1032]: CLOCK: time changed from 2026-10-06 to 2026-10-16 Oct 16 19:32:41 shedmedia ntpd[1032]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 16 19:32:43 shedmedia volumio[1143]: info: Loading i18n strings for locale en Oct 16 19:32:43 shedmedia volumio[1143]: Updating browse sources language Oct 16 19:32:43 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 19:32:43 shedmedia volumio[1411]: Starting albumart workers Oct 16 19:32:44 shedmedia tailscaled[644]: control: trying bootstrapDNS("derp9e.tailscale.com", "192.73.248.83") for "controlplane.tailscale.com" ... Oct 16 19:32:44 shedmedia tailscaled[644]: dns udp query: waiting for response or error from [100.122.232.82]: context deadline exceeded Oct 16 19:32:44 shedmedia tailscaled[644]: dns udp query: waiting for response or error from [100.122.232.82]: context deadline exceeded Oct 16 19:32:44 shedmedia tailscaled[644]: control: bootstrapDNS("derp9e.tailscale.com", "192.73.248.83") for "controlplane.tailscale.com" = [2606:b740:49::115 192.200.0.106 2606:b740:49::114 2606:b740:49::104 2606:b740:49::107 2606:b740:49::112 192.200.0.113 192.200.0.112 192.200.0.108 192.200.0.114 192.200.0.102 2606:b740:49::113 192.200.0.110 2606:b740:49::110 192.200.0.107 2606:b740:49::116] Oct 16 19:32:44 shedmedia volumio[1412]: Starting albumart workers Oct 16 19:32:44 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 19:32:44 shedmedia volumio[1143]: info: CoreCommandRouter::initPlayerControls Oct 16 19:32:44 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:44 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:44 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:44 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:44 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:44 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:44 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:44 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:44 shedmedia tailscaled[644]: control: control server key from https://controlplane.tailscale.com: ts2021=[fSeS+], legacy=[nlFWp] Oct 16 19:32:44 shedmedia tailscaled[644]: control: RegisterReq: onode= node=[H5mI7] fup=false nks=false Oct 16 19:32:44 shedmedia volumio[1413]: Starting albumart workers Oct 16 19:32:44 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 19:32:44 shedmedia volumio[1143]: Express server listening on port 3000 Oct 16 19:32:44 shedmedia volumio[1143]: [Metrics] WebUI: 13s 280.90ms Oct 16 19:32:45 shedmedia volumio[1143]: info: CoreStateMachine::resetVolumioState Oct 16 19:32:45 shedmedia volumio[1143]: info: CoreStateMachine::getcurrentVolume Oct 16 19:32:45 shedmedia volumio[1143]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 19:32:45 shedmedia volumio[1143]: info: CoreStateMachine::pushState Oct 16 19:32:45 shedmedia volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 16 19:32:45 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:32:45 shedmedia volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 16 19:32:45 shedmedia tailscaled[644]: control: RegisterReq: got response; nodeKeyExpired=false, machineAuthorized=true; authURL=false Oct 16 19:32:45 shedmedia tailscaled[644]: control: netmap: got new dial plan from control Oct 16 19:32:45 shedmedia tailscaled[644]: active login: shedmedia.rattlesnake-toad.ts.net Oct 16 19:32:45 shedmedia tailscaled[644]: wgengine: Reconfig: configuring router Oct 16 19:32:45 shedmedia tailscaled[644]: wgengine: Reconfig: user dialer Oct 16 19:32:45 shedmedia tailscaled[644]: tsdial: bart table size: 7 Oct 16 19:32:45 shedmedia tailscaled[644]: wgengine: Reconfig: configuring DNS Oct 16 19:32:45 shedmedia tailscaled[644]: dns: Set: {DefaultResolvers:[] Routes:{musclebyte.com.:[100.122.232.82] rattlesnake-toad.ts.net.:[] ts.net.:[199.247.155.53 2620:111:8007::53]}+65arpa SearchDomains:[rattlesnake-toad.ts.net. musclebyte.com.] Hosts:0} Oct 16 19:32:45 shedmedia tailscaled[644]: dns: Resolvercfg: {Routes:{.:[10.20.30.254 208.67.222.222 208.67.220.220] musclebyte.com.:[100.122.232.82] ts.net.:[199.247.155.53 2620:111:8007::53]} Hosts:0 LocalDomains:[rattlesnake-toad.ts.net.]+65arpa} Oct 16 19:32:45 shedmedia tailscaled[644]: dns: OScfg: {Nameservers:[100.100.100.100 fd7a:115c:a1e0::53] SearchDomains:[rattlesnake-toad.ts.net. musclebyte.com.] } Oct 16 19:32:45 shedmedia volumio[1143]: info: Volumio Network Manager: Network status updated: 1 Oct 16 19:32:45 shedmedia systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Oct 16 19:32:45 shedmedia systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Oct 16 19:32:45 shedmedia systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Oct 16 19:32:45 shedmedia tailscaled[644]: magicsock: home DERP changing from derp-27 [0ms] to derp-1 [22ms] (forced=false) Oct 16 19:32:45 shedmedia tailscaled[644]: magicsock: home is now derp-1 (nyc) Oct 16 19:32:45 shedmedia tailscaled[644]: magicsock: adding connection to derp-1 for home-keep-alive Oct 16 19:32:45 shedmedia tailscaled[644]: magicsock: 2 active derp conns: derp-1=cr0s,wr0s derp-27=cr11s,wr11s Oct 16 19:32:45 shedmedia tailscaled[644]: updating netmap in disk cache Oct 16 19:32:45 shedmedia tailscaled[644]: derphttp.Client.Recv: connecting to derp-1 (nyc) Oct 16 19:32:45 shedmedia tailscaled[644]: control: NetInfo: NetInfo{varies=false ipv6=false ipv6os=false udp=true icmpv4=false derp=#1 portmap= link="" firewallmode="ipt-default"} Oct 16 19:32:45 shedmedia systemd[1]: dpkg-db-backup.service: Deactivated successfully. Oct 16 19:32:45 shedmedia systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Oct 16 19:32:45 shedmedia tailscaled[644]: magicsock: derp-1 connected; connGen=1 Oct 16 19:32:45 shedmedia volumio[1143]: verbose: New Socket.io Connection to 10.20.30.247:3000 from 10.20.30.248 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 16 19:32:46 shedmedia sudo[1480]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 19:32:46 shedmedia sudo[1480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 19:32:46 shedmedia sudo[1480]: pam_unix(sudo:session): session closed for user root Oct 16 19:32:46 shedmedia sudo[1482]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 19:32:46 shedmedia sudo[1482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 19:32:46 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 19:32:46 shedmedia volumio[1143]: info: Reloading queue from file Oct 16 19:32:46 shedmedia sudo[1482]: pam_unix(sudo:session): session closed for user root Oct 16 19:32:46 shedmedia volumio[1143]: verbose: New Socket.io Connection to 10.20.30.247:3000 from 10.20.30.248 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 16 19:32:46 shedmedia volumio[1143]: info: CoreStateMachine::setRepeat null single undefined Oct 16 19:32:46 shedmedia volumio[1143]: info: CoreStateMachine::pushState Oct 16 19:32:46 shedmedia volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 16 19:32:46 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:32:46 shedmedia volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 16 19:32:46 shedmedia volumio[1143]: info: CoreStateMachine::setRandom null Oct 16 19:32:46 shedmedia volumio[1143]: info: CoreStateMachine::pushState Oct 16 19:32:46 shedmedia volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 16 19:32:46 shedmedia volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 16 19:32:46 shedmedia volumio[1143]: info: Setting Device type: Raspberry PI Oct 16 19:32:46 shedmedia volumio[1143]: verbose: New Socket.io Connection to 10.20.30.247:3000 from 10.20.30.248 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 16 19:32:46 shedmedia volumio[1143]: info: Completed loading Core Plugins Oct 16 19:32:46 shedmedia volumio[1143]: info: Preparing to generate the ALSA configuration file Oct 16 19:32:47 shedmedia volumio[1143]: verbose: New Socket.io Connection to 10.20.30.247:3000 from 10.20.30.248 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 16 19:32:47 shedmedia volumio[1143]: info: Discovery: A device disappeared from network Oct 16 19:32:47 shedmedia volumio[1143]: info: Discovery: adding bada191f-236e-4a13-b30e-0e79da097469 Oct 16 19:32:47 shedmedia volumio[1143]: info: Discovery: Found device GStereo Oct 16 19:32:47 shedmedia volumio[1143]: info: Discovery: Connecting to remote: 10.20.30.248 Oct 16 19:32:47 shedmedia volumio[1143]: info: Discovery: adding 77f77db2-a528-4d1a-9f25-633964ad2151 Oct 16 19:32:47 shedmedia volumio[1143]: info: Discovery: Found device ShedMedia Oct 16 19:32:47 shedmedia volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 16 19:32:47 shedmedia volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 16 19:32:47 shedmedia volumio[1143]: info: Discovery: this is already registered, 77f77db2-a528-4d1a-9f25-633964ad2151 Oct 16 19:32:47 shedmedia volumio[1143]: info: Discovery: Found device ShedMedia Oct 16 19:32:47 shedmedia volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 16 19:32:47 shedmedia volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 16 19:32:47 shedmedia volumio[1143]: info: Discovery: A device disappeared from network Oct 16 19:32:47 shedmedia volumio[1143]: info: Asound.conf file unchanged, so no further update is needed Oct 16 19:32:47 shedmedia volumio[1143]: info: Output device has changed, restarting MPD Oct 16 19:32:47 shedmedia volumio[1143]: info: ___________ START PLUGINS ___________ Oct 16 19:32:47 shedmedia sudo[1506]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 19:32:47 shedmedia volumio[1143]: info: ControllerMpd::onStart: Initializing MPD Oct 16 19:32:47 shedmedia volumio[1143]: info: Creating MPD Configuration file Oct 16 19:32:47 shedmedia sudo[1508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 19:32:47 shedmedia sudo[1508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 19:32:47 shedmedia sudo[1506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 19:32:47 shedmedia sudo[1510]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 16 19:32:47 shedmedia sudo[1510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 19:32:47 shedmedia sudo[1506]: pam_unix(sudo:session): session closed for user root Oct 16 19:32:47 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 19:32:47 shedmedia volumio[1143]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 19:32:47 shedmedia volumio[1143]: info: [1792193567819] CoreMusicLibrary::Adding element Media Servers Oct 16 19:32:47 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 19:32:47 shedmedia volumio[1143]: info: UPNP Browser: Client initialized successfully Oct 16 19:32:47 shedmedia volumio[1143]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 19:32:47 shedmedia volumio[1143]: info: [1792193567910] CoreMusicLibrary::Adding element Last_100 Oct 16 19:32:47 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 19:32:47 shedmedia volumio[1143]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 19:32:47 shedmedia volumio[1143]: info: [1792193567915] CoreMusicLibrary::Adding element Webradio Oct 16 19:32:47 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 19:32:47 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 19:32:47 shedmedia volumio[1143]: info: Initializing BBC Radios Oct 16 19:32:47 shedmedia sudo[1517]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 19:32:47 shedmedia sudo[1517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 19:32:47 shedmedia systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 16 19:32:47 shedmedia systemd[1]: Starting mpd.service - Music Player Daemon... Oct 16 19:32:48 shedmedia sudo[1516]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 19:32:48 shedmedia sudo[1516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 19:32:48 shedmedia systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 16 19:32:48 shedmedia sudo[1516]: pam_unix(sudo:session): session closed for user root Oct 16 19:32:48 shedmedia systemd[1]: mpd.service: Deactivated successfully. Oct 16 19:32:48 shedmedia systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 16 19:32:48 shedmedia systemd[1]: mpd.socket: Deactivated successfully. Oct 16 19:32:48 shedmedia systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 16 19:32:48 shedmedia systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 16 19:32:48 shedmedia systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 16 19:32:48 shedmedia systemd[1]: Starting mpd.service - Music Player Daemon... Oct 16 19:32:48 shedmedia sudo[1510]: pam_unix(sudo:session): session closed for user root Oct 16 19:32:48 shedmedia sudo[1532]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 16 19:32:48 shedmedia sudo[1532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 19:32:48 shedmedia sudo[1538]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 16 19:32:48 shedmedia sudo[1532]: pam_unix(sudo:session): session closed for user root Oct 16 19:32:48 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 19:32:48 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 19:32:48 shedmedia volumio[1143]: info: [RpiEepromConfig] Starting Raspberry Pi EEPROM Configuration Manager Oct 16 19:32:48 shedmedia volumio[1143]: info: [RpiEepromConfig] Detected full model string: Raspberry Pi 4 Model B Rev 1.5 Oct 16 19:32:48 shedmedia volumio[1143]: info: [RpiEepromConfig] Matched to capability profile: Raspberry Pi 4 Model B Oct 16 19:32:48 shedmedia volumio[1143]: info: [RpiEepromConfig] Detected model: Raspberry Pi 4 Model B Oct 16 19:32:48 shedmedia volumio[1143]: info: [RpiEepromConfig] Boot modes: sd, usb Oct 16 19:32:48 shedmedia volumio[1143]: info: [RpiEepromConfig] Using rpi-eeprom-config at: /usr/bin/rpi-eeprom-config Oct 16 19:32:48 shedmedia volumio5-onboarding[1529]: time=2026-10-16T19:32:48.493-04:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 16 19:32:48 shedmedia volumio[1143]: info: [RpiEepromConfig] Backup directory ensured: /data/configuration/system_controller/rpi_eeprom_config/backup Oct 16 19:32:48 shedmedia volumio[1143]: info: [RpiEepromConfig] Plugin started successfully Oct 16 19:32:49 shedmedia tailscaled[644]: dns udp query: waiting for response or error from [100.122.232.82]: context deadline exceeded Oct 16 19:32:49 shedmedia tailscaled[644]: dns udp query: waiting for response or error from [100.122.232.82]: context deadline exceeded Oct 16 19:32:49 shedmedia volumio[1143]: info: [RpiEepromUpdater] Plugin started successfully Oct 16 19:32:49 shedmedia volumio[1143]: info: Cannot retrieve data for calling home Oct 16 19:32:51 shedmedia volumio[1143]: info: Discovery: A device disappeared from network Oct 16 19:32:51 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 16 19:32:51 shedmedia volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 16 19:32:51 shedmedia volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 16 19:32:51 shedmedia volumio[1143]: info: MPD Permissions set Oct 16 19:32:51 shedmedia volumio[1143]: info: MPD Permissions set Oct 16 19:32:51 shedmedia sudo[1571]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 16 19:32:51 shedmedia sudo[1571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 19:32:51 shedmedia volumio[1143]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 16 19:32:51 shedmedia volumio[1143]: info: Discovery: Connected to remote: 10.20.30.248 Oct 16 19:32:51 shedmedia volumio[1143]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 16 19:32:51 shedmedia volumio[1143]: info: Received Get System Info Oct 16 19:32:51 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 19:32:51 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 19:32:51 shedmedia volumio[1143]: info: Discovery: Getting this device information Oct 16 19:32:51 shedmedia volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 16 19:32:51 shedmedia volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 16 19:32:51 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 19:32:51 shedmedia volumio5-onboarding[1529]: time=2026-10-16T19:32:51.894-04:00 level=INFO msg="system info for 7a2770baa355cb9d46d93f8f9b7afa81" deviceName=ShedMedia deviceVariant="" deviceModel= softwareVersion="" Oct 16 19:32:51 shedmedia volumio5-onboarding[1529]: time=2026-10-16T19:32:51.922-04:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 16 19:32:52 shedmedia volumio[1143]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia mpd[1540]: 2026-10-16T19:32:52 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:32:52 shedmedia volumio[1143]: info: Received Get System Info Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 19:32:52 shedmedia volumio[1143]: info: Discovery: Getting this device information Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 16 19:32:52 shedmedia volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 19:32:52 shedmedia volumio[1143]: info: Discovery: adding 77f77db2-a528-4d1a-9f25-633964ad2151 Oct 16 19:32:52 shedmedia volumio[1143]: info: Discovery: Found device ShedMedia Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 16 19:32:52 shedmedia volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 16 19:32:52 shedmedia volumio[1143]: info: Discovery: this is already registered, 77f77db2-a528-4d1a-9f25-633964ad2151 Oct 16 19:32:52 shedmedia volumio[1143]: info: Discovery: Found device ShedMedia Oct 16 19:32:52 shedmedia volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 16 19:32:52 shedmedia volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 16 19:32:52 shedmedia systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Oct 16 19:32:52 shedmedia systemd[1]: Started mpd.service - Music Player Daemon. Oct 16 19:32:52 shedmedia sudo[1517]: pam_unix(sudo:session): session closed for user root Oct 16 19:32:52 shedmedia sudo[1508]: pam_unix(sudo:session): session closed for user root Oct 16 19:32:52 shedmedia systemd[1]: e2scrub_all.service: Deactivated successfully. Oct 16 19:32:52 shedmedia systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Oct 16 19:32:52 shedmedia volumio-remote-updater[655]: [2026-10-16 19:32:52] [connect] Successful connection Oct 16 19:32:52 shedmedia systemd[1]: systemd-fsckd.service: Deactivated successfully. Oct 16 19:32:52 shedmedia volumio[1143]: info: Completed starting Core Plugins Oct 16 19:32:52 shedmedia volumio[1143]: info: ------------------------------------------- Oct 16 19:32:52 shedmedia volumio[1143]: info: ----- MyVolumio plugins startup ---- Oct 16 19:32:52 shedmedia volumio[1143]: info: ------------------------------------------- Oct 16 19:32:52 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 16 19:32:53 shedmedia volumio[1143]: error: MPD error: The expression evaluated to a falsy value: Oct 16 19:32:53 shedmedia volumio[1143]: assert.ok(self.idling) Oct 16 19:32:53 shedmedia volumio[1143]: error: The expression evaluated to a falsy value: Oct 16 19:32:53 shedmedia volumio[1143]: assert.ok(self.idling) Oct 16 19:32:53 shedmedia volumio[1143]: info: Discovery: adding bada191f-236e-4a13-b30e-0e79da097469 Oct 16 19:32:53 shedmedia volumio[1143]: info: Discovery: Found device GStereo Oct 16 19:32:53 shedmedia volumio[1143]: error: updateQueue error: null Oct 16 19:32:53 shedmedia volumio-remote-updater[655]: [2026-10-16 19:32:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1792193572 101 Oct 16 19:32:53 shedmedia volumio[1143]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 7 Oct 16 19:32:53 shedmedia volumio[1143]: info: MPD running with PID1540 Oct 16 19:32:53 shedmedia volumio[1143]: ,establishing connection Oct 16 19:32:53 shedmedia volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 16 19:32:53 shedmedia volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 16 19:32:53 shedmedia volumio[1143]: error: updateQueue error: null Oct 16 19:32:55 shedmedia tailscaled[644]: monitor: time jump detected (slept 251h19m24s), probably wake from sleep Oct 16 19:32:55 shedmedia tailscaled[644]: LinkChange: major, rebinding: old: interfaces.State{defaultRoute=eth0 ifs={eth0:[10.20.30.247/24] tailscale0:[100.97.214.74/32]} v4=true v6=false} new: interfaces.State{defaultRoute=eth0 ifs={eth0:[10.20.30.247/24] tailscale0:[100.97.214.74/32]} v4=true v6=false} rebind-reason=[time-jumped(251h19m24s)] Oct 16 19:32:55 shedmedia tailscaled[644]: dns: Set: {DefaultResolvers:[] Routes:{musclebyte.com.:[100.122.232.82] rattlesnake-toad.ts.net.:[] ts.net.:[199.247.155.53 2620:111:8007::53]}+65arpa SearchDomains:[rattlesnake-toad.ts.net. musclebyte.com.] Hosts:0} Oct 16 19:32:55 shedmedia tailscaled[644]: dns: Resolvercfg: {Routes:{.:[10.20.30.254 208.67.222.222 208.67.220.220] musclebyte.com.:[100.122.232.82] ts.net.:[199.247.155.53 2620:111:8007::53]} Hosts:0 LocalDomains:[rattlesnake-toad.ts.net.]+65arpa} Oct 16 19:32:55 shedmedia tailscaled[644]: dns: OScfg: {Nameservers:[100.100.100.100 fd7a:115c:a1e0::53] SearchDomains:[rattlesnake-toad.ts.net. musclebyte.com.] } Oct 16 19:32:55 shedmedia systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Oct 16 19:32:55 shedmedia systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 19:32:55 shedmedia systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 19:32:55 shedmedia tailscaled[644]: wgengine: set DNS config again after major link change Oct 16 19:32:55 shedmedia tailscaled[644]: router: portUpdate(port=41641, network=udp6) Oct 16 19:32:55 shedmedia tailscaled[644]: router: portUpdate(port=41641, network=udp4) Oct 16 19:32:55 shedmedia tailscaled[644]: Rebind; defIf="eth0", ips=[10.20.30.247/24] Oct 16 19:32:55 shedmedia tailscaled[644]: magicsock: 2 active derp conns: derp-1=cr10s,wr10s derp-27=cr22s,wr22s Oct 16 19:32:55 shedmedia tailscaled[644]: post-rebind ping of DERP region 27 okay Oct 16 19:32:55 shedmedia tailscaled[644]: post-rebind ping of DERP region 1 okay Oct 16 19:32:55 shedmedia sudo[1571]: pam_unix(sudo:session): session closed for user root Oct 16 19:32:56 shedmedia volumio[1143]: info: Upmpdcli Daemon Started Oct 16 19:32:56 shedmedia systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 16 19:32:58 shedmedia volumio[1143]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 16 19:33:03 shedmedia upmpdcli[1631]: writing RSA key Oct 16 19:33:05 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 19:33:05 shedmedia volumio[1143]: info: Systeminfo: --[object Object] Oct 16 19:33:05 shedmedia volumio[1143]: info: Systeminfo web UI listening on port 12334 Oct 16 19:33:12 shedmedia systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 16 19:33:16 shedmedia systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 16 19:33:16 shedmedia systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 16 19:33:19 shedmedia volumio[1143]: error: MyVolumio Plugin failed to start in a timely fashion Oct 16 19:33:19 shedmedia volumio[1143]: [Metrics] CommandRouter: 47s 114.60ms Oct 16 19:33:19 shedmedia volumio[1143]: info: CoreCommandRouter::volumiosetStartupVolume Oct 16 19:33:19 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 19:33:19 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 19:33:19 shedmedia volumio[1143]: info: CoreCommandRouter::Close All Modals sent Oct 16 19:33:19 shedmedia volumio[1143]: info: CoreCommandRouter::Close All Modals sent Oct 16 19:33:19 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 19:33:19 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 19:33:19 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 19:33:19 shedmedia tailscaled[644]: logtail: upload succeeded after 1 failures and 55s Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 16 19:33:20 shedmedia volumio[1143]: info: Adding plugin bluetooth to MyMusic Plugins Oct 16 19:33:20 shedmedia volumio[1143]: info: Adding plugin multiroom to MyMusic Plugins Oct 16 19:33:20 shedmedia volumio[1143]: info: Adding plugin metavolumio to MyMusic Plugins Oct 16 19:33:20 shedmedia volumio[1143]: info: Adding plugin cd_controller to MyMusic Plugins Oct 16 19:33:20 shedmedia volumio[1143]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 16 19:33:20 shedmedia volumio[1143]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 16 19:33:20 shedmedia volumio[1143]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 16 19:33:20 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 16 19:33:21 shedmedia volumio5-onboarding[1529]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Oct 16 19:33:21 shedmedia systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 16 19:33:21 shedmedia systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 16 19:33:22 shedmedia systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Oct 16 19:33:22 shedmedia systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 16 19:33:22 shedmedia systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 16 19:33:22 shedmedia volumio5-onboarding[1699]: time=2026-10-16T19:33:22.120-04:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 16 19:33:23 shedmedia volumio[1143]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 16 19:33:23 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 16 19:33:23 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 19:33:23 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 19:33:23 shedmedia volumio[1143]: info: Starting MyVolumio Remote Streaming Endpoints Oct 16 19:33:23 shedmedia volumio[1143]: info: MyVolumio login type: Token Oct 16 19:33:23 shedmedia volumio[1143]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 16 19:33:23 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 16 19:33:26 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 16 19:33:26 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 16 19:33:26 shedmedia volumio[1143]: info: Streaming services startup Oct 16 19:33:26 shedmedia volumio[1143]: info: Starting Streaming Daemon Oct 16 19:33:26 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 16 19:33:26 shedmedia sudo[1709]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 16 19:33:26 shedmedia sudo[1709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 19:33:26 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 16 19:33:26 shedmedia sudo[1709]: pam_unix(sudo:session): session closed for user root Oct 16 19:33:26 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 19:33:26 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 16 19:33:26 shedmedia volumio[1143]: info: BOOT COMPLETED Oct 16 19:33:26 shedmedia volumio[1143]: error: Cannot start Volumio Streaming Daemon Oct 16 19:33:26 shedmedia volumio[1143]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 16 19:33:26 shedmedia volumio[1143]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 16 19:33:27 shedmedia volumio[1143]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Oct 16 19:33:27 shedmedia volumio[1143]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Oct 16 19:33:27 shedmedia volumio[1143]: info: Received Get System Info Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 19:33:27 shedmedia volumio[1143]: info: Discovery: Getting this device information Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 16 19:33:27 shedmedia volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 19:33:27 shedmedia volumio5-onboarding[1699]: time=2026-10-16T19:33:27.306-04:00 level=INFO msg="system info for 7a2770baa355cb9d46d93f8f9b7afa81" deviceName=ShedMedia deviceVariant="" deviceModel= softwareVersion="" Oct 16 19:33:27 shedmedia volumio5-onboarding[1699]: time=2026-10-16T19:33:27.327-04:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 16 19:33:27 shedmedia volumio[1143]: error: MyVolumio Custom Token format not valid, refreshing it Oct 16 19:33:27 shedmedia volumio[1143]: info: Received Get System Info Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 19:33:27 shedmedia volumio[1143]: info: Discovery: Getting this device information Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 16 19:33:27 shedmedia volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 19:33:27 shedmedia volumio-remote-updater[655]: Test mode disabled Oct 16 19:33:27 shedmedia volumio-remote-updater[655]: Alpha mode disabled Oct 16 19:33:27 shedmedia volumio-remote-updater[655]: Alpha legacy test mode disabled Oct 16 19:33:27 shedmedia volumio[1143]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 19:33:27 shedmedia volumio[1143]: info: Received Get System Info Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 19:33:27 shedmedia volumio[1143]: info: Discovery: Getting this device information Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 16 19:33:27 shedmedia volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 19:33:27 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 19:33:27 shedmedia volumio5-onboarding[1699]: time=2026-10-16T19:33:27.881-04:00 level=INFO msg="enabling local network discovery" Oct 16 19:33:27 shedmedia volumio5-onboarding[1699]: time=2026-10-16T19:33:27.903-04:00 level=INFO msg="enabling BLE discovery" Oct 16 19:33:27 shedmedia volumio[1143]: info: MyVolumio login type: Token Oct 16 19:33:28 shedmedia volumio[1143]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 16 19:33:28 shedmedia volumio5-onboarding[1699]: time=2026-10-16T19:33:28.934-04:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 16 19:33:28 shedmedia volumio[1143]: info: MyVolumio token set successfully Oct 16 19:33:28 shedmedia volumio[1143]: info: MYVOLUMIO: Adding device Oct 16 19:33:28 shedmedia volumio[1143]: info: MYVOLUMIO: Evaluating Server Oct 16 19:33:29 shedmedia volumio[1143]: info: MyVolumio Plan changed: premium Oct 16 19:33:29 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 16 19:33:29 shedmedia volumio[1143]: info: Removing browser output: myVolumio user plan is not superstar Oct 16 19:33:29 shedmedia volumio[1143]: info: Removing audio output: Oct 16 19:33:29 shedmedia volumio[1143]: info: MYVOLUMIO: Adding device Oct 16 19:33:29 shedmedia volumio[1143]: info: MYVOLUMIO: Evaluating Server Oct 16 19:33:30 shedmedia volumio[1143]: info: Remote config written successfully Oct 16 19:33:30 shedmedia volumio[1143]: info: Starting Tunnel 1 Oct 16 19:33:30 shedmedia volumio[1143]: info: Starting Tunnel Connection Checker Oct 16 19:33:30 shedmedia volumio[1143]: info: Setting Geolocation for MyVolumio to us1 Oct 16 19:33:30 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 19:33:30 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 19:33:30 shedmedia volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 19:33:30 shedmedia volumio[1143]: info: MYVolumio Device enabled Oct 16 19:33:30 shedmedia volumio[1143]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 16 19:33:30 shedmedia volumio[1143]: [2026-10-16T23:33:30.185Z] @firebase/database: FIREBASE WARNING: Exception was thrown by user callback. TypeError: Cannot read properties of null (reading 'replace') Oct 16 19:33:30 shedmedia volumio[1143]: at MyVolumioPluginManager.fetchPluginKeys (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:8044) Oct 16 19:33:30 shedmedia volumio[1143]: at MyVolumioPluginManager.loadMyVolumioPlugins (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:11563) Oct 16 19:33:30 shedmedia volumio[1143]: at MyVolumioPluginManager.myVolumioDeviceStatusChanged (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5527) Oct 16 19:33:30 shedmedia volumio[1143]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6807 Oct 16 19:33:30 shedmedia volumio[1143]: at /volumio/node_modules/v-conf/index.js:166:13 Oct 16 19:33:30 shedmedia volumio[1143]: at /volumio/node_modules/multimap/index.js:140:9 Oct 16 19:33:30 shedmedia volumio[1143]: at Array.forEach () Oct 16 19:33:30 shedmedia volumio[1143]: at /volumio/node_modules/multimap/index.js:139:13 Oct 16 19:33:30 shedmedia volumio[1143]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) Oct 16 19:33:30 shedmedia volumio[1143]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5) Oct 16 19:33:30 shedmedia volumio[1143]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10) Oct 16 19:33:30 shedmedia volumio[1143]: at Config.set (/volumio/node_modules/v-conf/index.js:163:20) Oct 16 19:33:30 shedmedia volumio[1143]: at myVolumio.togglePremiumPlugins (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:36229) Oct 16 19:33:30 shedmedia volumio[1143]: at /myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:17831 Oct 16 19:33:30 shedmedia volumio[1143]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:4549:22 Oct 16 19:33:30 shedmedia volumio[1143]: at exceptionGuard (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:698:9) Oct 16 19:33:30 shedmedia volumio[1143]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 19:33:30 shedmedia volumio[1143]: TypeError: Cannot read properties of null (reading 'replace') Oct 16 19:33:30 shedmedia volumio[1143]: at MyVolumioPluginManager.fetchPluginKeys (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:8044) Oct 16 19:33:30 shedmedia volumio[1143]: at MyVolumioPluginManager.loadMyVolumioPlugins (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:11563) Oct 16 19:33:30 shedmedia volumio[1143]: at MyVolumioPluginManager.myVolumioDeviceStatusChanged (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5527) Oct 16 19:33:30 shedmedia volumio[1143]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6807 Oct 16 19:33:30 shedmedia volumio[1143]: at /volumio/node_modules/v-conf/index.js:166:13 Oct 16 19:33:30 shedmedia volumio[1143]: at /volumio/node_modules/multimap/index.js:140:9 Oct 16 19:33:30 shedmedia volumio[1143]: at Array.forEach () Oct 16 19:33:30 shedmedia volumio[1143]: at /volumio/node_modules/multimap/index.js:139:13 Oct 16 19:33:30 shedmedia volumio[1143]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) Oct 16 19:33:30 shedmedia volumio[1143]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5) Oct 16 19:33:30 shedmedia volumio[1143]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10) Oct 16 19:33:30 shedmedia volumio[1143]: at Config.set (/volumio/node_modules/v-conf/index.js:163:20) Oct 16 19:33:30 shedmedia volumio[1143]: at myVolumio.togglePremiumPlugins (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:36229) Oct 16 19:33:30 shedmedia volumio[1143]: at /myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:17831 Oct 16 19:33:30 shedmedia volumio[1143]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:4549:22 Oct 16 19:33:30 shedmedia volumio[1143]: at exceptionGuard (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:698:9) Oct 16 19:33:30 shedmedia volumio[1143]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 19:33:33 shedmedia sudo[1786]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-16 19:32' Oct 16 19:33:33 shedmedia sudo[1786]: 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"