Apr 12 18:42:00 iain-room volumio[1154]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 12 18:42:00 iain-room volumio[1154]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 12 18:42:07 iain-room volumio[1154]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 12 18:42:07 iain-room volumio[1154]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 12 18:42:07 iain-room volumio[1154]: info: Streaming services startup Apr 12 18:42:07 iain-room volumio[1154]: info: Starting Streaming Daemon Apr 12 18:42:07 iain-room sudo[1750]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 12 18:42:07 iain-room sudo[1750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:42:07 iain-room volumio[1154]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 12 18:42:07 iain-room sudo[1750]: pam_unix(sudo:session): session closed for user root Apr 12 18:42:08 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 12 18:42:08 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:42:08 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 18:42:08 iain-room volumio[1154]: error: Cannot start Volumio Streaming Daemon Apr 12 18:42:08 iain-room volumio[1154]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 12 18:42:08 iain-room volumio[1154]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 12 18:42:08 iain-room volumio[1154]: error: MyVolumio Custom Token format not valid, refreshing it Apr 12 18:42:10 iain-room volumio-remote-updater[671]: Test mode disabled Apr 12 18:42:10 iain-room volumio-remote-updater[671]: Alpha mode disabled Apr 12 18:42:10 iain-room volumio-remote-updater[671]: Alpha legacy test mode disabled Apr 12 18:42:10 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 12 18:42:10 iain-room volumio[1154]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 12 18:42:11 iain-room volumio[1154]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

NEW ADDITIONS

","title":"Update v4.119","updateavailable":true} Apr 12 18:42:11 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 12 18:42:12 iain-room volumio[1154]: info: MyVolumio login type: Token Apr 12 18:42:14 iain-room volumio[1154]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 12 18:42:14 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 12 18:42:15 iain-room volumio[1154]: info: MyVolumio token set successfully Apr 12 18:42:15 iain-room volumio[1154]: info: MYVOLUMIO: Adding device Apr 12 18:42:15 iain-room volumio[1154]: info: MYVOLUMIO: Evaluating Server Apr 12 18:42:15 iain-room volumio[1154]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 12 18:42:17 iain-room volumio[1154]: info: MyVolumio Plan changed: premium Apr 12 18:42:17 iain-room volumio[1154]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Apr 12 18:42:18 iain-room volumio[1154]: info: Removing browser output: myVolumio user plan is not superstar Apr 12 18:42:18 iain-room volumio[1154]: info: Removing audio output: Apr 12 18:42:18 iain-room volumio[1154]: info: MYVOLUMIO: Adding device Apr 12 18:42:18 iain-room volumio[1154]: info: MYVOLUMIO: Evaluating Server Apr 12 18:42:18 iain-room volumio[1154]: info: Setting Geolocation for MyVolumio to eu3 Apr 12 18:42:18 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:42:18 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:42:18 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:42:20 iain-room volumio[1154]: info: Updating MyVolumio device info Apr 12 18:42:20 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:42:20 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:42:20 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:42:20 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:42:20 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:42:20 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 12 18:42:20 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 12 18:42:20 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 12 18:42:20 iain-room volumio[1154]: info: CoreCommandRouter::volumioGetBrowseSources Apr 12 18:42:20 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 12 18:42:20 iain-room volumio[1154]: info: Successfully Added MyVolumio device Apr 12 18:42:21 iain-room volumio[1154]: info: Remote config written successfully Apr 12 18:42:21 iain-room volumio[1154]: info: Starting Tunnel 1 Apr 12 18:42:21 iain-room volumio[1154]: info: Starting Tunnel Connection Checker Apr 12 18:42:21 iain-room volumio[1154]: info: Completed starting MyVolumio Plugin Apr 12 18:42:21 iain-room volumio[1154]: info: Successfully Updated MyVolumio device Apr 12 18:42:21 iain-room volumio[1154]: info: Setting Geolocation for MyVolumio to eu3 Apr 12 18:42:21 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:42:21 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:42:21 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:42:21 iain-room volumio[1154]: info: MYVolumio Device enabled Apr 12 18:42:21 iain-room volumio[1154]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 12 18:42:21 iain-room volumio[1154]: info: MyVolumio status changed Apr 12 18:42:22 iain-room volumio[1154]: info: Streaming services startup Apr 12 18:42:22 iain-room volumio[1154]: info: Starting Streaming Daemon Apr 12 18:42:22 iain-room sudo[1830]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 12 18:42:22 iain-room sudo[1830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:42:22 iain-room volumio[1154]: info: Successfully Added MyVolumio device Apr 12 18:42:22 iain-room volumio[1154]: info: Updating MyVolumio device info Apr 12 18:42:22 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:42:22 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:42:22 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:42:22 iain-room volumio[1154]: info: CoreCommandRouter::volumioGetState Apr 12 18:42:22 iain-room volumio[1154]: info: CorePlayQueue::getTrack 0 Apr 12 18:42:22 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 12 18:42:22 iain-room volumio[1154]: error: [MyVolumio PluginManager] Cache data is invalid! Apr 12 18:42:22 iain-room sudo[1830]: pam_unix(sudo:session): session closed for user root Apr 12 18:42:23 iain-room volumio[1154]: error: Cannot start Volumio Streaming Daemon Apr 12 18:42:23 iain-room volumio[1154]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 12 18:42:23 iain-room volumio[1154]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 12 18:42:23 iain-room volumio[1154]: info: Successfully Updated MyVolumio device Apr 12 18:42:24 iain-room volumio[1154]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 12 18:42:28 iain-room volumio[1154]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Apr 12 18:42:28 iain-room volumio[1154]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 12 18:42:30 iain-room ifplugd(eth0)[850]: Link beat detected. Apr 12 18:42:30 iain-room ifplugd(eth0)[850]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 12 18:42:30 iain-room dhcpcd[772]: eth0: carrier acquired Apr 12 18:42:30 iain-room kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control off Apr 12 18:42:30 iain-room dhcpcd[772]: eth0: IAID eb:b8:2d:d5 Apr 12 18:42:30 iain-room dhcpcd[772]: eth0: adding address fe80::c229:1cdc:b973:a171 Apr 12 18:42:30 iain-room dhcpcd[772]: ipv6_addaddr1: Permission denied Apr 12 18:42:30 iain-room ifplugd(eth0)[850]: client: sending commands to dhcpcd process Apr 12 18:42:30 iain-room dhcpcd[772]: ps_ctl_dispatch: cannot handle another client Apr 12 18:42:30 iain-room dhcpcd[772]: control_free: No such file or directory Apr 12 18:42:30 iain-room dhcpcd[772]: eth0: soliciting an IPv6 router Apr 12 18:42:31 iain-room ifplugd(eth0)[850]: Program executed successfully. Apr 12 18:42:31 iain-room wireless.js[675]: WIRELESS.JS - INFO: === SNM TRANSITION === Apr 12 18:42:31 iain-room wireless.js[675]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Apr 12 18:42:31 iain-room wireless.js[675]: WIRELESS.JS - INFO: New ethernet state: connected Apr 12 18:42:31 iain-room wireless.js[675]: WIRELESS.JS - INFO: Single Network Mode: enabled Apr 12 18:42:31 iain-room wireless.js[675]: WIRELESS.JS - INFO: First start: no Apr 12 18:42:31 iain-room wireless.js[675]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Apr 12 18:42:31 iain-room wireless.js[675]: WIRELESS.JS - INFO: === END TRANSITION === Apr 12 18:42:31 iain-room wireless.js[675]: WIRELESS.JS - INFO: SNM: Ethernet connected, switching to ethernet (WiFi scan mode) Apr 12 18:42:31 iain-room dhcpcd[772]: eth0: rebinding lease of 10.0.0.47 Apr 12 18:42:31 iain-room sudo[1900]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd -k wlan0 Apr 12 18:42:31 iain-room sudo[1900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 18:42:31 iain-room dhcpcd[1903]: dhcpcd not running Apr 12 18:42:31 iain-room sudo[1900]: pam_unix(sudo:session): session closed for user root Apr 12 18:42:31 iain-room wireless.js[675]: dhcpcd not running Apr 12 18:42:31 iain-room wireless.js[675]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Apr 12 18:42:31 iain-room dhcpcd[772]: eth0: probing address 10.0.0.47/24 Apr 12 18:42:32 iain-room wpa_supplicant[1112]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Apr 12 18:42:32 iain-room wpa_supplicant[1112]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Apr 12 18:42:32 iain-room wpa_supplicant[1112]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Apr 12 18:42:32 iain-room wpa_supplicant[1112]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Apr 12 18:42:32 iain-room wpa_supplicant[1112]: wlan0: CTRL-EVENT-DISCONNECTED bssid=66:22:32:73:0f:f6 reason=3 locally_generated=1 Apr 12 18:42:32 iain-room dhcpcd[772]: wlan0: carrier lost - roaming Apr 12 18:42:32 iain-room dhcpcd[772]: wlan0: changing route to 10.0.0.0/24 Apr 12 18:42:32 iain-room dhcpcd[772]: wlan0: changing default route via 10.0.0.1 Apr 12 18:42:32 iain-room wpa_supplicant[1112]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Apr 12 18:42:32 iain-room wpa_supplicant[1112]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Apr 12 18:42:32 iain-room wpa_supplicant[1112]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Apr 12 18:42:32 iain-room sudo[1922]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 12 18:42:32 iain-room sudo[1922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 18:42:32 iain-room avahi-daemon[1257]: Withdrawing address record for 10.0.0.51 on wlan0. Apr 12 18:42:32 iain-room avahi-daemon[1257]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.0.0.51. Apr 12 18:42:32 iain-room dhcpcd[772]: wlan0: pid 1927 deleted IP address 10.0.0.51/24 Apr 12 18:42:32 iain-room dhcpcd[772]: wlan0: deleting route to 10.0.0.0/24 Apr 12 18:42:32 iain-room sudo[1922]: pam_unix(sudo:session): session closed for user root Apr 12 18:42:32 iain-room avahi-daemon[1257]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 12 18:42:32 iain-room dhcpcd[772]: wlan0: deleting default route via 10.0.0.1 Apr 12 18:42:32 iain-room dhcpcd[772]: wlan0: waiting for carrier Apr 12 18:42:32 iain-room dhcpcd[772]: wlan0: carrier acquired Apr 12 18:42:32 iain-room systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Apr 12 18:42:32 iain-room systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Apr 12 18:42:32 iain-room systemd[1]: welcome.service: Deactivated successfully. Apr 12 18:42:32 iain-room systemd[1]: Stopped welcome.service - Show a welcome message on console. Apr 12 18:42:32 iain-room systemd[1]: Stopping welcome.service - Show a welcome message on console... Apr 12 18:42:32 iain-room systemd[1]: Starting welcome.service - Show a welcome message on console... Apr 12 18:42:33 iain-room wpa_supplicant[1112]: wlan0: CTRL-EVENT-TERMINATING Apr 12 18:42:33 iain-room welcome[1940]: Resolved ip:[0] Apr 12 18:42:33 iain-room dhcpcd[772]: wlan0: IAID eb:ed:78:80 Apr 12 18:42:33 iain-room dhcpcd[772]: wlan0: carrier lost Apr 12 18:42:33 iain-room sudo[1929]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 12 18:42:33 iain-room sudo[1929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 18:42:33 iain-room systemd[1]: Finished welcome.service - Show a welcome message on console. Apr 12 18:42:33 iain-room systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Apr 12 18:42:33 iain-room sudo[1929]: pam_unix(sudo:session): session closed for user root Apr 12 18:42:33 iain-room wireless.js[675]: WIRELESS.JS - INFO: Cleaning previous... Apr 12 18:42:33 iain-room sudo[1956]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 12 18:42:33 iain-room sudo[1956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 18:42:33 iain-room kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 12 18:42:33 iain-room sudo[1956]: pam_unix(sudo:session): session closed for user root Apr 12 18:42:33 iain-room wireless.js[675]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Apr 12 18:42:33 iain-room wireless.js[675]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 12ms Apr 12 18:42:33 iain-room wireless.js[675]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:ed:78:80) Apr 12 18:42:33 iain-room sudo[1969]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 12 18:42:33 iain-room sudo[1969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:42:33 iain-room sudo[1969]: pam_unix(sudo:session): session closed for user root Apr 12 18:42:33 iain-room sudo[1977]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 12 18:42:33 iain-room sudo[1977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:42:35 iain-room ntpd[832]: IO: Deleting interface #4 wlan0, 10.0.0.51#123, interface stats: received=39, sent=42, dropped=4, active_time=66 secs Apr 12 18:42:35 iain-room ntpd[832]: PROTO: 85.199.214.99 unlink local addr 10.0.0.51 -> Apr 12 18:42:35 iain-room ntpd[832]: PROTO: 143.20.69.40 unlink local addr 10.0.0.51 -> Apr 12 18:42:35 iain-room ntpd[832]: PROTO: 178.62.68.79 unlink local addr 10.0.0.51 -> Apr 12 18:42:35 iain-room ntpd[832]: PROTO: 194.213.3.203 unlink local addr 10.0.0.51 -> Apr 12 18:42:35 iain-room ntpd[832]: PROTO: 176.58.109.184 unlink local addr 10.0.0.51 -> Apr 12 18:42:35 iain-room ntpd[832]: PROTO: 45.154.197.21 unlink local addr 10.0.0.51 -> Apr 12 18:42:35 iain-room ntpd[832]: PROTO: 85.199.214.101 unlink local addr 10.0.0.51 -> Apr 12 18:42:35 iain-room ntpd[832]: PROTO: 13.42.123.235 unlink local addr 10.0.0.51 -> Apr 12 18:42:35 iain-room ntpd[832]: PROTO: 131.111.8.61 unlink local addr 10.0.0.51 -> Apr 12 18:42:35 iain-room ntpd[832]: PROTO: 83.151.207.133 unlink local addr 10.0.0.51 -> Apr 12 18:42:35 iain-room ntpd[832]: PROTO: 162.159.200.123 unlink local addr 10.0.0.51 -> Apr 12 18:42:35 iain-room ntpd[832]: PROTO: 139.162.255.65 unlink local addr 10.0.0.51 -> Apr 12 18:42:35 iain-room ntpd[832]: PROTO: 131.111.8.63 unlink local addr 10.0.0.51 -> Apr 12 18:42:35 iain-room ntpd[832]: PROTO: 185.57.191.230 unlink local addr 10.0.0.51 -> Apr 12 18:42:35 iain-room ntpd[832]: PROTO: 139.162.242.115 unlink local addr 10.0.0.51 -> Apr 12 18:42:36 iain-room sudo[1977]: pam_unix(sudo:session): session closed for user root Apr 12 18:42:36 iain-room wireless.js[675]: WIRELESS.JS - INFO: Regdomain already correct: GB Apr 12 18:42:36 iain-room wireless.js[675]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Apr 12 18:42:36 iain-room wireless.js[675]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Apr 12 18:42:36 iain-room wireless.js[675]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Apr 12 18:42:36 iain-room dhcpcd[772]: eth0: leased 10.0.0.47 for 86400 seconds Apr 12 18:42:36 iain-room avahi-daemon[1257]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.0.0.47. Apr 12 18:42:36 iain-room avahi-daemon[1257]: New relevant interface eth0.IPv4 for mDNS. Apr 12 18:42:36 iain-room dhcpcd[772]: eth0: adding route to 10.0.0.0/24 Apr 12 18:42:36 iain-room avahi-daemon[1257]: Registering new address record for 10.0.0.47 on eth0.IPv4. Apr 12 18:42:36 iain-room dhcpcd[772]: eth0: adding default route via 10.0.0.1 Apr 12 18:42:37 iain-room systemd[1]: welcome.service: Deactivated successfully. Apr 12 18:42:37 iain-room systemd[1]: Stopped welcome.service - Show a welcome message on console. Apr 12 18:42:37 iain-room systemd[1]: Stopping welcome.service - Show a welcome message on console... Apr 12 18:42:37 iain-room systemd[1]: Starting welcome.service - Show a welcome message on console... Apr 12 18:42:37 iain-room welcome[1995]: Resolved ip:[1] 10.0.0.47 Apr 12 18:42:37 iain-room systemd[1]: Finished welcome.service - Show a welcome message on console. Apr 12 18:42:37 iain-room systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Apr 12 18:42:37 iain-room sudo[2010]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 12 18:42:37 iain-room sudo[2010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 18:42:37 iain-room sudo[2010]: pam_unix(sudo:session): session closed for user root Apr 12 18:42:37 iain-room sudo[2013]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 12 18:42:37 iain-room sudo[2013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 18:42:37 iain-room sudo[2013]: pam_unix(sudo:session): session closed for user root Apr 12 18:42:37 iain-room wpa_supplicant[2016]: Successfully initialized wpa_supplicant Apr 12 18:42:38 iain-room wpa_supplicant[2016]: nl80211: kernel reports: Registration to specific type not supported Apr 12 18:42:38 iain-room wpa_supplicant[2019]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Apr 12 18:42:38 iain-room wireless.js[675]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 6854ms Apr 12 18:42:38 iain-room wireless.js[675]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Apr 12 18:42:38 iain-room wireless.js[675]: WIRELESS.JS - INFO: Notified systemd about wireless ready Apr 12 18:42:39 iain-room ntpd[832]: IO: Listen normally on 5 eth0 10.0.0.47:123 Apr 12 18:42:39 iain-room ntpd[832]: IO: new interface(s) found: waking up resolver Apr 12 18:42:40 iain-room volumio5-onboarding[1625]: time=2026-04-12T18:42:40.971+01:00 level=ERROR msg="failed to update discovery on Ethernet info change" error="failed to get system info: could not get system info: context deadline exceeded" Apr 12 18:42:43 iain-room volumio5-onboarding[1625]: time=2026-04-12T18:42:43.135+01:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Apr 12 18:42:43 iain-room volumio5-onboarding[1625]: time=2026-04-12T18:42:43.840+01:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Apr 12 18:42:46 iain-room volumio5-onboarding[1625]: time=2026-04-12T18:42:46.812+01:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Apr 12 18:42:47 iain-room volumio5-onboarding[1625]: time=2026-04-12T18:42:47.483+01:00 level=ERROR msg="failed to update discovery on Ethernet info change" error="failed to get system info: could not get system info: context deadline exceeded" Apr 12 18:42:48 iain-room volumio5-onboarding[1625]: time=2026-04-12T18:42:48.314+01:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Apr 12 18:42:51 iain-room volumio5-onboarding[1625]: time=2026-04-12T18:42:51.235+01:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Apr 12 18:42:53 iain-room volumio[1154]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Apr 12 18:42:54 iain-room sudo[2066]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 12 18:42:54 iain-room sudo[2066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:42:54 iain-room sudo[2066]: pam_unix(sudo:session): session closed for user root Apr 12 18:42:55 iain-room volumio[1154]: info: MRS: MultiRoom plugin initialized Apr 12 18:42:55 iain-room volumio[1154]: info: MRS: STOPPING SNAPCLIENT Apr 12 18:42:55 iain-room volumio[1154]: info: MRS: Snap server stop Apr 12 18:42:55 iain-room volumio[1154]: info: MRS: STOPPING volumioStreaming Apr 12 18:42:55 iain-room sudo[2083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 12 18:42:55 iain-room sudo[2083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:42:55 iain-room sudo[2085]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 12 18:42:55 iain-room volumio[1154]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 12 18:42:55 iain-room sudo[2085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:42:55 iain-room sudo[2087]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 12 18:42:55 iain-room sudo[2087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:42:55 iain-room sudo[2083]: pam_unix(sudo:session): session closed for user root Apr 12 18:42:56 iain-room sudo[2090]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 12 18:42:56 iain-room sudo[2090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:42:56 iain-room volumio[1154]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 12 18:42:56 iain-room sudo[2085]: pam_unix(sudo:session): session closed for user root Apr 12 18:42:56 iain-room sudo[2087]: pam_unix(sudo:session): session closed for user root Apr 12 18:42:56 iain-room sudo[2090]: pam_unix(sudo:session): session closed for user root Apr 12 18:42:56 iain-room volumio[1154]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 12 18:42:56 iain-room volumio[1154]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 12 18:42:56 iain-room volumio[1154]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 12 18:42:57 iain-room volumio[1154]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Apr 12 18:42:57 iain-room volumio[1154]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 12 18:42:57 iain-room volumio[1154]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 12 18:42:58 iain-room volumio5-onboarding[1625]: time=2026-04-12T18:42:58.170+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=10.0.0.29:46772 Apr 12 18:42:58 iain-room dbus-daemon[649]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30000ms) Apr 12 18:42:58 iain-room volumio[1154]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 12 18:42:58 iain-room volumio[1154]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Apr 12 18:42:59 iain-room volumio[1154]: info: Preparing to generate the ALSA configuration file Apr 12 18:43:00 iain-room volumio[1154]: info: Discovery: Disconnected from remote: 10.0.0.35 Apr 12 18:43:00 iain-room volumio[1154]: info: MRS: Pushing multiroomSync output for this device Apr 12 18:43:00 iain-room volumio[1154]: info: MRS: Pushing multiroomSync output Apr 12 18:43:00 iain-room volumio[1154]: info: Adding audio output: Apr 12 18:43:00 iain-room volumio[1154]: info: Adding audio output: Apr 12 18:43:01 iain-room volumio[1154]: error: MyVolumio Plugins failed to start in a timely fashion Apr 12 18:43:01 iain-room volumio[1154]: [Metrics] CommandRouter: 124s 142.32ms Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::volumiosetStartupVolume Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::Close All Modals sent Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::Close All Modals sent Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 12 18:43:01 iain-room volumio[1154]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 12 18:43:01 iain-room volumio[1154]: info: Reading ALSA contributions from plugins. Apr 12 18:43:01 iain-room volumio5-onboarding[1625]: time=2026-04-12T18:43:01.671+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=10.0.0.29:46772 Apr 12 18:43:01 iain-room volumio5-onboarding[1625]: time=2026-04-12T18:43:01.671+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=10.0.0.29:46772 Apr 12 18:43:01 iain-room volumio5-onboarding[1625]: time=2026-04-12T18:43:01.676+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=10.0.0.29:46784 Apr 12 18:43:01 iain-room volumio[1154]: info: Received Get System Info Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 18:43:01 iain-room volumio[1154]: info: Discovery: Getting this device information Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::volumioGetState Apr 12 18:43:01 iain-room volumio[1154]: info: CorePlayQueue::getTrack 0 Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 18:43:01 iain-room volumio[1154]: info: Received Get System Info Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 18:43:01 iain-room volumio[1154]: info: Discovery: Getting this device information Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::volumioGetState Apr 12 18:43:01 iain-room volumio[1154]: info: CorePlayQueue::getTrack 0 Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 18:43:01 iain-room volumio[1154]: info: Received Get System Info Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 18:43:01 iain-room volumio[1154]: info: Discovery: Getting this device information Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::volumioGetState Apr 12 18:43:01 iain-room volumio[1154]: info: CorePlayQueue::getTrack 0 Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 18:43:01 iain-room volumio[1154]: info: Received Get System Info Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 18:43:01 iain-room volumio[1154]: info: Discovery: Getting this device information Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::volumioGetState Apr 12 18:43:01 iain-room volumio[1154]: info: CorePlayQueue::getTrack 0 Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 18:43:01 iain-room volumio[1154]: info: Received Get System Info Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 18:43:01 iain-room volumio[1154]: info: Discovery: Getting this device information Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::volumioGetState Apr 12 18:43:01 iain-room volumio[1154]: info: CorePlayQueue::getTrack 0 Apr 12 18:43:01 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 18:43:02 iain-room volumio[1154]: info: Received Get System Info Apr 12 18:43:02 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 18:43:02 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 18:43:02 iain-room volumio[1154]: info: Discovery: Getting this device information Apr 12 18:43:02 iain-room volumio[1154]: info: CoreCommandRouter::volumioGetState Apr 12 18:43:02 iain-room volumio[1154]: info: CorePlayQueue::getTrack 0 Apr 12 18:43:02 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 18:43:02 iain-room volumio[1154]: info: Received Get System Info Apr 12 18:43:02 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 18:43:02 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 18:43:02 iain-room volumio[1154]: info: Discovery: Getting this device information Apr 12 18:43:02 iain-room volumio[1154]: info: CoreCommandRouter::volumioGetState Apr 12 18:43:02 iain-room volumio[1154]: info: CorePlayQueue::getTrack 0 Apr 12 18:43:02 iain-room volumio[1154]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 18:43:02 iain-room volumio[1154]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 18:43:02 iain-room volumio[1154]: Error: write EPIPE Apr 12 18:43:02 iain-room volumio[1154]: at afterWriteDispatched (node:internal/stream_base_commons:160:15) Apr 12 18:43:02 iain-room volumio[1154]: at writeGeneric (node:internal/stream_base_commons:151:3) Apr 12 18:43:02 iain-room volumio[1154]: at Socket._writeGeneric (node:net:952:11) Apr 12 18:43:02 iain-room volumio[1154]: at Socket._write (node:net:964:8) Apr 12 18:43:02 iain-room volumio[1154]: at writeOrBuffer (node:internal/streams/writable:399:12) Apr 12 18:43:02 iain-room volumio[1154]: at _write (node:internal/streams/writable:340:10) Apr 12 18:43:02 iain-room volumio[1154]: at Writable.write (node:internal/streams/writable:344:10) Apr 12 18:43:02 iain-room volumio[1154]: at auth (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/handshake.js:67:10) Apr 12 18:43:02 iain-room volumio[1154]: at Socket. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/connection.js:137:32) Apr 12 18:43:02 iain-room volumio[1154]: at Object.onceWrapper (node:events:628:28) Apr 12 18:43:02 iain-room volumio[1154]: at Socket.emit (node:events:514:28) Apr 12 18:43:02 iain-room volumio[1154]: at PipeConnectWrap.afterConnect [as oncomplete] (node:net:1580:10) { Apr 12 18:43:02 iain-room volumio[1154]: errno: -32, Apr 12 18:43:02 iain-room volumio[1154]: code: 'EPIPE', Apr 12 18:43:02 iain-room volumio[1154]: syscall: 'write' Apr 12 18:43:02 iain-room volumio[1154]: } Apr 12 18:43:02 iain-room volumio[1154]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 18:43:14 iain-room upmpdcli[2136]: writing RSA key Apr 12 18:43:16 iain-room volumio5-onboarding[1625]: time=2026-04-12T18:43:16.261+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="10.0.0.29:46784 @ 0x2a54660" latency=72.138564ms platform=PLATFORM_ANDROID version=5.260331.0 Apr 12 18:43:16 iain-room volumio5-onboarding[1625]: time=2026-04-12T18:43:16.262+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="10.0.0.29:46784 @ 0x2a54660" latency=72.099733ms timeout=10s Apr 12 18:43:16 iain-room volumio5-onboarding[1625]: time=2026-04-12T18:43:16.262+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="10.0.0.29:46784 @ 0x2a54660" Apr 12 18:43:17 iain-room sudo[2140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-12 18:42' Apr 12 18:43:17 iain-room sudo[2140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"