-- Logs begin at Thu 2019-02-14 05:12:00 EST, end at Sun 2025-01-12 12:18:36 EST. -- Jan 12 12:17:00 bedroom systemd[1]: Starting Hostname Service... Jan 12 12:17:00 bedroom systemd[1]: systemd-rfkill.service: Succeeded. Jan 12 12:17:00 bedroom dbus-daemon[507]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 12 12:17:00 bedroom systemd[1]: Started Hostname Service. Jan 12 12:17:00 bedroom exim4[657]: Starting MTA: exim4. Jan 12 12:17:00 bedroom systemd[1]: Started LSB: exim Mail Transport Agent. Jan 12 12:17:00 bedroom exim[968]: 2025-01-12 12:17:00 socket bind() to port 25 for address ::1 failed: Cannot assign requested address: waiting 30s before trying again (9 more tries) Jan 12 12:17:00 bedroom exim[968]: 2025-01-12 12:17:00 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=0 egid=124 Jan 12 12:17:00 bedroom exim[968]: exim: could not open panic log - aborting: see message(s) above Jan 12 12:17:00 bedroom ntpd[697]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 12 12:17:01 bedroom nmbd[678]: [2025/01/12 12:17:01.104681, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 12 12:17:01 bedroom nmbd[678]: started asyncdns process 971 Jan 12 12:17:01 bedroom nmbd[678]: [2025/01/12 12:17:01.108476, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 12 12:17:01 bedroom nmbd[678]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 12 12:17:01 bedroom nmbd[678]: [2025/01/12 12:17:01.108796, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 12 12:17:01 bedroom nmbd[678]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 12 12:17:01 bedroom CRON[972]: pam_unix(cron:session): session opened for user root by (uid=0) Jan 12 12:17:01 bedroom CRON[973]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) Jan 12 12:17:01 bedroom CRON[972]: pam_unix(cron:session): session closed for user root Jan 12 12:17:01 bedroom wireless.js[521]: Cleaning previous... Jan 12 12:17:01 bedroom ntpd[697]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 12 12:17:02 bedroom sudo[987]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 12 12:17:02 bedroom sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:02 bedroom sudo[987]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:02 bedroom sudo[989]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 12 12:17:02 bedroom sudo[989]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:02 bedroom volumio-remote-updater[505]: [2025-01-12 12:17:02] [info] asio async_connect error: system:111 (Connection refused) Jan 12 12:17:02 bedroom volumio-remote-updater[505]: [2025-01-12 12:17:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 12 12:17:02 bedroom volumio-remote-updater[505]: [2025-01-12 12:17:02] [error] handle_connect error: Underlying Transport Error Jan 12 12:17:02 bedroom sudo[989]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:02 bedroom volumio-time-update[500]: volumio-time-update-util: Fetching time from Volumio... Jan 12 12:17:02 bedroom wireless.js[521]: Stopped aP Jan 12 12:17:02 bedroom ntpd[697]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 12 12:17:03 bedroom volumio-time-update[500]: volumio-time-update-util: Date not found in response Jan 12 12:17:03 bedroom volumio-time-update[500]: volumio-time-update-util: Retrying in 5 seconds... Jan 12 12:17:03 bedroom sudo[1000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 12 12:17:03 bedroom sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:03 bedroom sudo[1000]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:03 bedroom kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 12 12:17:03 bedroom sudo[1003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 12 12:17:03 bedroom sudo[1003]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:03 bedroom sudo[1003]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:03 bedroom sudo[1010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 12 12:17:03 bedroom sudo[1010]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:03 bedroom sudo[1010]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:03 bedroom sudo[1012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 12 12:17:03 bedroom sudo[1012]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:05 bedroom mpd[707]: Jan 12 12:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 12 12:17:05 bedroom systemd[1]: Started Music Player Daemon. Jan 12 12:17:05 bedroom mpd_monitor.sh[530]: MPD restarted due to no mpc output. Jan 12 12:17:06 bedroom sudo[1012]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:06 bedroom wireless.js[521]: SETTING APPROPRIATE REG DOMAIN: US Jan 12 12:17:06 bedroom sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 12 12:17:06 bedroom sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:06 bedroom sudo[1020]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:06 bedroom sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Jan 12 12:17:06 bedroom sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:06 bedroom sudo[1022]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:06 bedroom wireless.js[521]: SUCCESSFULLY SET NEW REGDOMAIN: US Jan 12 12:17:06 bedroom wireless.js[521]: Start wireless flow Jan 12 12:17:06 bedroom wireless.js[521]: Stopped hotspot (if there).. Jan 12 12:17:06 bedroom sudo[1027]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 12 12:17:06 bedroom sudo[1027]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:06 bedroom sudo[1027]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:06 bedroom sudo[1029]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 12 12:17:06 bedroom sudo[1029]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:06 bedroom sudo[1029]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:06 bedroom wireless.js[521]: DHCP IP Jan 12 12:17:06 bedroom wireless.js[521]: Start ap Jan 12 12:17:06 bedroom wpa_supplicant[1031]: Successfully initialized wpa_supplicant Jan 12 12:17:06 bedroom sudo[1032]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jan 12 12:17:06 bedroom sudo[1032]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:06 bedroom kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 12 12:17:06 bedroom dhcpcd[1033]: dev: loaded udev Jan 12 12:17:07 bedroom kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 12 12:17:07 bedroom dhcpcd[1033]: wlan0: connected to Access Point `' Jan 12 12:17:07 bedroom dhcpcd[1033]: no interfaces have a carrier Jan 12 12:17:07 bedroom dhcpcd[1059]: wlan0: waiting for carrier Jan 12 12:17:07 bedroom dhcpcd[1033]: forked to background, child pid 1059 Jan 12 12:17:07 bedroom sudo[1032]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:07 bedroom wireless.js[521]: trying... Jan 12 12:17:07 bedroom sudo[1061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:07 bedroom sudo[1061]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:07 bedroom sudo[1061]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:08 bedroom volumio-time-update[500]: volumio-time-update-util: Fetching time from Volumio... Jan 12 12:17:08 bedroom volumio-time-update[500]: volumio-time-update-util: Date not found in response Jan 12 12:17:08 bedroom volumio-time-update[500]: volumio-time-update-util: Retrying in 5 seconds... Jan 12 12:17:08 bedroom wireless.js[521]: trying... Jan 12 12:17:08 bedroom sudo[1069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:08 bedroom sudo[1069]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:08 bedroom sudo[1069]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:09 bedroom wireless.js[521]: trying... Jan 12 12:17:09 bedroom sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:09 bedroom sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:09 bedroom sudo[1072]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:10 bedroom volumio-remote-updater[505]: [2025-01-12 12:17:10] [info] asio async_connect error: system:111 (Connection refused) Jan 12 12:17:10 bedroom volumio-remote-updater[505]: [2025-01-12 12:17:10] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 12 12:17:10 bedroom volumio-remote-updater[505]: [2025-01-12 12:17:10] [error] handle_connect error: Underlying Transport Error Jan 12 12:17:10 bedroom wireless.js[521]: trying... Jan 12 12:17:10 bedroom sudo[1075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:10 bedroom sudo[1075]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:10 bedroom sudo[1075]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:11 bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jan 12 12:17:11 bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jan 12 12:17:11 bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jan 12 12:17:11 bedroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jan 12 12:17:11 bedroom wireless.js[521]: trying... Jan 12 12:17:11 bedroom sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:11 bedroom sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:11 bedroom sudo[1078]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:12 bedroom wireless.js[521]: trying... Jan 12 12:17:12 bedroom sudo[1081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:12 bedroom sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:12 bedroom sudo[1081]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:13 bedroom volumio-time-update[500]: volumio-time-update-util: Fetching time from Volumio... Jan 12 12:17:13 bedroom volumio-time-update[500]: volumio-time-update-util: Date not found in response Jan 12 12:17:13 bedroom volumio-time-update[500]: volumio-time-update-util: Retrying in 5 seconds... Jan 12 12:17:13 bedroom wpa_supplicant[1045]: wlan0: Trying to associate with SSID 'Grove' Jan 12 12:17:13 bedroom wireless.js[521]: trying... Jan 12 12:17:13 bedroom sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:13 bedroom sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:13 bedroom sudo[1089]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:13 bedroom wpa_supplicant[1045]: wlan0: Associated with f6:92:bf:e8:c0:66 Jan 12 12:17:13 bedroom wpa_supplicant[1045]: wlan0: CTRL-EVENT-CONNECTED - Connection to f6:92:bf:e8:c0:66 completed [id=0 id_str=] Jan 12 12:17:13 bedroom dhcpcd[1059]: wlan0: carrier acquired Jan 12 12:17:13 bedroom wpa_supplicant[1045]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 12 12:17:13 bedroom dhcpcd[1059]: wlan0: connected to Access Point `Grove' Jan 12 12:17:13 bedroom wpa_supplicant[1045]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jan 12 12:17:14 bedroom dhcpcd[1059]: DUID 00:01:00:01:2a:c9:72:e9:b8:27:eb:0d:1b:2d Jan 12 12:17:14 bedroom dhcpcd[1059]: wlan0: IAID eb:0d:1b:2d Jan 12 12:17:14 bedroom dhcpcd[1059]: wlan0: adding address fe80::ea19:e21a:de1d:4330 Jan 12 12:17:14 bedroom dhcpcd[1059]: ipv6_addaddr1: Permission denied Jan 12 12:17:14 bedroom dhcpcd[1059]: wlan0: soliciting an IPv6 router Jan 12 12:17:14 bedroom wireless.js[521]: trying... Jan 12 12:17:14 bedroom sudo[1094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:14 bedroom sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:14 bedroom sudo[1094]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:14 bedroom dhcpcd[1059]: wlan0: soliciting a DHCP lease Jan 12 12:17:15 bedroom wireless.js[521]: trying... Jan 12 12:17:15 bedroom sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:15 bedroom sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:15 bedroom sudo[1100]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:16 bedroom wireless.js[521]: trying... Jan 12 12:17:16 bedroom sudo[1103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:16 bedroom sudo[1103]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:16 bedroom sudo[1103]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:17 bedroom wireless.js[521]: trying... Jan 12 12:17:17 bedroom sudo[1106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:17 bedroom sudo[1106]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:17 bedroom sudo[1106]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:18 bedroom volumio-time-update[500]: volumio-time-update-util: Fetching time from Volumio... Jan 12 12:17:18 bedroom volumio-time-update[500]: volumio-time-update-util: Date not found in response Jan 12 12:17:18 bedroom volumio-time-update[500]: volumio-time-update-util: Retrying in 5 seconds... Jan 12 12:17:18 bedroom wireless.js[521]: trying... Jan 12 12:17:18 bedroom sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:18 bedroom sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:18 bedroom sudo[1114]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:19 bedroom dhcpcd[1059]: wlan0: offered 192.168.25.32 from 192.168.25.1 Jan 12 12:17:19 bedroom dhcpcd[1059]: wlan0: probing address 192.168.25.32/24 Jan 12 12:17:19 bedroom wireless.js[521]: trying... Jan 12 12:17:19 bedroom sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:19 bedroom sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:19 bedroom sudo[1117]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:20 bedroom volumio-remote-updater[505]: [2025-01-12 12:17:20] [info] asio async_connect error: system:111 (Connection refused) Jan 12 12:17:20 bedroom volumio-remote-updater[505]: [2025-01-12 12:17:20] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 12 12:17:20 bedroom volumio-remote-updater[505]: [2025-01-12 12:17:20] [error] handle_connect error: Underlying Transport Error Jan 12 12:17:20 bedroom wireless.js[521]: trying... Jan 12 12:17:20 bedroom sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:20 bedroom sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:20 bedroom sudo[1120]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:21 bedroom wireless.js[521]: trying... Jan 12 12:17:21 bedroom sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:21 bedroom sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:21 bedroom sudo[1123]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:22 bedroom wireless.js[521]: trying... Jan 12 12:17:22 bedroom sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:22 bedroom sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:22 bedroom sudo[1126]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:23 bedroom volumio-time-update[500]: volumio-time-update-util: Fetching time from Volumio... Jan 12 12:17:23 bedroom volumio-time-update[500]: volumio-time-update-util: Date not found in response Jan 12 12:17:23 bedroom volumio-time-update[500]: volumio-time-update-util: Retrying in 5 seconds... Jan 12 12:17:23 bedroom systemd[1]: systemd-fsckd.service: Succeeded. Jan 12 12:17:23 bedroom wireless.js[521]: trying... Jan 12 12:17:23 bedroom sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:23 bedroom sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:23 bedroom sudo[1134]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:24 bedroom wireless.js[521]: trying... Jan 12 12:17:24 bedroom sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:24 bedroom sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:24 bedroom sudo[1137]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:25 bedroom dhcpcd[1059]: wlan0: leased 192.168.25.32 for 7200 seconds Jan 12 12:17:25 bedroom avahi-daemon[538]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.25.32. Jan 12 12:17:25 bedroom dhcpcd[1059]: wlan0: adding route to 192.168.25.0/24 Jan 12 12:17:25 bedroom dhcpcd[1059]: wlan0: adding default route via 192.168.25.1 Jan 12 12:17:25 bedroom avahi-daemon[538]: New relevant interface wlan0.IPv4 for mDNS. Jan 12 12:17:25 bedroom avahi-daemon[538]: Registering new address record for 192.168.25.32 on wlan0.IPv4. Jan 12 12:17:25 bedroom wireless.js[521]: trying... Jan 12 12:17:25 bedroom sudo[1163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 12:17:25 bedroom sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:25 bedroom sudo[1163]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:25 bedroom wireless.js[521]: Connected to: ----Grove Jan 12 12:17:25 bedroom wireless.js[521]: ---- Jan 12 12:17:25 bedroom sudo[1166]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 12:17:25 bedroom sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:25 bedroom sudo[1166]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:25 bedroom wireless.js[521]: ... joined AP, wlan0 IPv4 is 192.168.25.32, ipV6 is undefined Jan 12 12:17:25 bedroom wireless.js[521]: It's done! AP Jan 12 12:17:26 bedroom nmbd[678]: [2025/01/12 12:17:26.120505, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 12 12:17:26 bedroom systemd[1]: Started Samba NMB Daemon. Jan 12 12:17:26 bedroom nmbd[678]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 12 12:17:26 bedroom kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 12 12:17:26 bedroom systemd[1]: Starting Samba Winbind Daemon... Jan 12 12:17:26 bedroom systemd[1]: Started Wireless Services. Jan 12 12:17:26 bedroom systemd[1]: Started Volumio Backend Module. Jan 12 12:17:26 bedroom systemd[1]: Started Volumio Cpu Tweaker. Jan 12 12:17:26 bedroom volumio-cpu-tweak[1174]: Setting RT Priority for mpd Jan 12 12:17:26 bedroom volumio-cpu-tweak[1174]: Setting MPD Affinity Jan 12 12:17:26 bedroom volumio-cpu-tweak[1174]: pid 707's current affinity mask: f Jan 12 12:17:26 bedroom volumio-cpu-tweak[1174]: pid 707's new affinity mask: 3 Jan 12 12:17:26 bedroom volumio-cpu-tweak[1174]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jan 12 12:17:26 bedroom volumio-cpu-tweak[1174]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 12 12:17:26 bedroom systemd[1]: volumio_cpu_tweak.service: Succeeded. Jan 12 12:17:26 bedroom winbindd[1171]: [2025/01/12 12:17:26.763242, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 12 12:17:26 bedroom winbindd[1171]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 12 12:17:26 bedroom winbindd[1171]: [2025/01/12 12:17:26.794107, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 12 12:17:26 bedroom systemd[1]: Started Samba Winbind Daemon. Jan 12 12:17:26 bedroom winbindd[1171]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 12 12:17:26 bedroom systemd[1]: Starting Samba SMB Daemon... Jan 12 12:17:26 bedroom ntpd[697]: Listen normally on 3 wlan0 192.168.25.32:123 Jan 12 12:17:26 bedroom ntpd[697]: new interface(s) found: waking up resolver Jan 12 12:17:27 bedroom smbd[1193]: [2025/01/12 12:17:27.795843, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 12 12:17:27 bedroom systemd[1]: Started Samba SMB Daemon. Jan 12 12:17:27 bedroom smbd[1193]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 12 12:17:28 bedroom volumio-time-update[500]: volumio-time-update-util: Fetching time from Volumio... Jan 12 12:17:28 bedroom volumio-time-update[500]: volumio-time-update-util: Setting system time to: 2025-01-12 12:17:52 Jan 12 12:17:28 bedroom sudo[1206]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-01-12 12:17:52 Jan 12 12:17:28 bedroom sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:17:28 bedroom dbus-daemon[507]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.8' (uid=0 pid=1207 comm="timedatectl set-time 2025-01-12 12:17:52 ") Jan 12 12:17:28 bedroom systemd[1]: Starting Time & Date Service... Jan 12 12:17:28 bedroom dbus-daemon[507]: [system] Successfully activated service 'org.freedesktop.timedate1' Jan 12 12:17:28 bedroom systemd[1]: Started Time & Date Service. Jan 12 12:17:52 bedroom systemd-timedated[1208]: Changed local time to Sun Jan 12 12:17:52 2025 Jan 12 12:17:52 bedroom sudo[1206]: pam_unix(sudo:session): session closed for user root Jan 12 12:17:52 bedroom volumio-time-update[500]: volumio-time-update-util: System time updated successfully. Jan 12 12:17:52 bedroom systemd[1]: Started Volumio Time Update Utility. Jan 12 12:17:52 bedroom systemd[1]: Reached target Multi-User System. Jan 12 12:17:52 bedroom systemd[1]: Reached target Graphical Interface. Jan 12 12:17:52 bedroom systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 12 12:17:52 bedroom systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 12 12:17:52 bedroom systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 12 12:17:52 bedroom systemd[1]: Startup finished in 15.602s (kernel) + 40.433s (userspace) = 56.035s. Jan 12 12:17:53 bedroom volumio-remote-updater[505]: [2025-01-12 12:17:53] [connect] Successful connection Jan 12 12:17:53 bedroom systemd[1]: systemd-hostnamed.service: Succeeded. Jan 12 12:17:54 bedroom volumio[1173]: info: ------------------------------------------- Jan 12 12:17:54 bedroom volumio[1173]: info: ----- Volumio3 ---- Jan 12 12:17:54 bedroom volumio[1173]: info: ------------------------------------------- Jan 12 12:17:54 bedroom volumio[1173]: info: ----- System startup ---- Jan 12 12:17:54 bedroom volumio[1173]: info: ------------------------------------------- Jan 12 12:17:56 bedroom volumio[1173]: info: MYVOLUMIO Environment detected Jan 12 12:17:57 bedroom volumio[1173]: info: Plugin folders cleanup Jan 12 12:17:57 bedroom volumio[1173]: info: Scanning into folder /volumio/app/plugins/ Jan 12 12:17:57 bedroom volumio[1173]: info: Scanning category audio_interface Jan 12 12:17:57 bedroom volumio[1173]: info: Scanning category miscellanea Jan 12 12:17:57 bedroom volumio[1173]: info: Scanning category music_service Jan 12 12:17:57 bedroom volumio[1173]: info: Scanning category plugins.json Jan 12 12:17:57 bedroom volumio[1173]: info: Scanning category system_controller Jan 12 12:17:57 bedroom volumio[1173]: info: Scanning category user_interface Jan 12 12:17:57 bedroom volumio[1173]: info: Scanning into folder /data/plugins/ Jan 12 12:17:57 bedroom volumio[1173]: info: Plugin folders cleanup completed Jan 12 12:17:57 bedroom volumio[1173]: info: ------------------------------------------- Jan 12 12:17:57 bedroom volumio[1173]: info: ----- Core plugins startup ---- Jan 12 12:17:57 bedroom volumio[1173]: info: ------------------------------------------- Jan 12 12:17:57 bedroom volumio[1173]: info: Loading plugins from folder /volumio/app/plugins/ Jan 12 12:17:57 bedroom volumio[1173]: info: Adding plugin upnp to MyMusic Plugins Jan 12 12:17:57 bedroom volumio[1173]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 12 12:17:57 bedroom volumio[1173]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 12 12:17:57 bedroom volumio[1173]: info: Loading plugins from folder /data/plugins/ Jan 12 12:17:57 bedroom volumio[1173]: info: Loading plugin "system"... Jan 12 12:17:57 bedroom volumio[1173]: info: Loading plugin "appearance"... Jan 12 12:18:00 bedroom volumio[1173]: info: Loading plugin "network"... Jan 12 12:18:00 bedroom volumio[1173]: info: Refreshing Cached IP Addresses Jan 12 12:18:00 bedroom volumio[1173]: info: Loading plugin "services"... Jan 12 12:18:00 bedroom sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 12:18:00 bedroom sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:00 bedroom volumio[1173]: info: Loading plugin "alsa_controller"... Jan 12 12:18:00 bedroom sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 12:18:00 bedroom sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:00 bedroom sudo[1232]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:00 bedroom sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 12 12:18:00 bedroom sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:00 bedroom sudo[1230]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:00 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 12:18:00 bedroom volumio[1173]: info: Loading plugin "wizard"... Jan 12 12:18:00 bedroom volumio[1173]: info: Loading plugin "networkfs"... Jan 12 12:18:00 bedroom volumio[1173]: info: Starting Udev Watcher for removable devices Jan 12 12:18:00 bedroom sudo[1260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio2,password=Po6AoZwE#7o&X@m1oPqvBpU841J$iM9b,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.5.6/music /mnt/NAS/Excelsior Jan 12 12:18:00 bedroom sudo[1260]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:00 bedroom volumio[1173]: info: Ignoring mount for partition: boot Jan 12 12:18:00 bedroom volumio[1173]: info: Ignoring mount for partition: volumio Jan 12 12:18:00 bedroom volumio[1173]: info: Ignoring mount for partition: volumio_data Jan 12 12:18:00 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 12:18:00 bedroom volumio[1173]: info: Loading plugin "volumio_command_line_client"... Jan 12 12:18:00 bedroom volumio[1173]: info: Plugin upnp is not enabled Jan 12 12:18:00 bedroom volumio[1173]: info: Loading plugin "my_music"... Jan 12 12:18:00 bedroom volumio[1173]: info: Loading plugin "mpd"... Jan 12 12:18:01 bedroom kernel: Key type cifs.spnego registered Jan 12 12:18:01 bedroom kernel: Key type cifs.idmap registered Jan 12 12:18:01 bedroom kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jan 12 12:18:01 bedroom kernel: CIFS: Attempting to mount //192.168.5.6/music Jan 12 12:18:01 bedroom volumio[1173]: info: Plugin upnp_browser is not enabled Jan 12 12:18:01 bedroom volumio[1173]: info: Loading plugin "alarm-clock"... Jan 12 12:18:02 bedroom volumio[1173]: info: Plugin airplay_emulation is not enabled Jan 12 12:18:02 bedroom volumio[1173]: info: Loading plugin "last_100"... Jan 12 12:18:02 bedroom volumio[1173]: info: Loading plugin "webradio"... Jan 12 12:18:02 bedroom volumio[1173]: info: Loading plugin "i2s_dacs"... Jan 12 12:18:02 bedroom volumio[1173]: info: Loading plugin "volumiodiscovery"... Jan 12 12:18:02 bedroom volumio[1173]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 12 12:18:02 bedroom volumio[1173]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 12:18:02 bedroom volumio[1173]: *** WARNING *** For more information see Jan 12 12:18:02 bedroom volumio[1173]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 12 12:18:02 bedroom volumio[1173]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 12:18:02 bedroom volumio[1173]: *** WARNING *** For more information see Jan 12 12:18:02 bedroom node[1173]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 12 12:18:02 bedroom node[1173]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 12:18:02 bedroom node[1173]: *** WARNING *** For more information see Jan 12 12:18:02 bedroom node[1173]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 12 12:18:02 bedroom node[1173]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 12:18:02 bedroom node[1173]: *** WARNING *** For more information see Jan 12 12:18:02 bedroom volumio[1173]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 12 12:18:02 bedroom volumio[1173]: info: Discovery: Started advertising with name: Bedroom Jan 12 12:18:02 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 12:18:02 bedroom volumio[1173]: info: Loading plugin "outputs"... Jan 12 12:18:02 bedroom volumio[1173]: info: Loading plugin "albumart"... Jan 12 12:18:03 bedroom volumio[1173]: info: Plugin example_plugin is not enabled Jan 12 12:18:03 bedroom volumio[1173]: info: Loading plugin "inputs"... Jan 12 12:18:03 bedroom volumio[1173]: info: Loading plugin "updater_comm"... Jan 12 12:18:03 bedroom volumio[1173]: info: Plugin mpdemulation is not enabled Jan 12 12:18:03 bedroom volumio[1173]: info: Loading plugin "rest_api"... Jan 12 12:18:03 bedroom volumio[1173]: info: Loading plugin "websocket"... Jan 12 12:18:03 bedroom volumio[1173]: info: Starting Socket.io Server version 2.3.0 Jan 12 12:18:03 bedroom volumio[1173]: info: Loading i18n strings for locale en Jan 12 12:18:03 bedroom volumio[1173]: Updating browse sources language Jan 12 12:18:03 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 12:18:03 bedroom volumio[1173]: Forking 3 albumart workers Jan 12 12:18:04 bedroom sudo[1239]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:04 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 12:18:04 bedroom volumio[1173]: info: CoreCommandRouter::initPlayerControls Jan 12 12:18:04 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:04 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:04 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:04 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:04 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:04 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:04 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:04 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:04 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 12:18:04 bedroom volumio[1173]: Express server listening on port 3000 Jan 12 12:18:04 bedroom volumio[1173]: [Metrics] WebUI: 11s 589.43ms Jan 12 12:18:04 bedroom volumio[1173]: info: CoreStateMachine::resetVolumioState Jan 12 12:18:04 bedroom volumio[1173]: info: CoreStateMachine::getcurrentVolume Jan 12 12:18:04 bedroom volumio[1173]: info: CoreCommandRouter::volumioRetrievevolume Jan 12 12:18:04 bedroom volumio[1173]: info: Volumio Network Manager: Network status updated: 2 Jan 12 12:18:04 bedroom volumio[1173]: verbose: New Socket.io Connection to 192.168.25.32 from 192.168.24.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 1 Jan 12 12:18:04 bedroom volumio[1173]: verbose: New Socket.io Connection to 192.168.25.32 from 192.168.24.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 2 Jan 12 12:18:04 bedroom volumio[1173]: verbose: New Socket.io Connection to 192.168.25.32 from 192.168.24.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 3 Jan 12 12:18:04 bedroom volumio[1173]: verbose: New Socket.io Connection to 192.168.25.32 from 192.168.24.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 4 Jan 12 12:18:05 bedroom volumio[1173]: verbose: New Socket.io Connection to 192.168.25.32:3000 from 192.168.25.35 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 12 12:18:05 bedroom volumio[1173]: verbose: New Socket.io Connection to 192.168.25.32:3000 from 192.168.25.34 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 12 12:18:05 bedroom volumio[1173]: verbose: New Socket.io Connection to 192.168.25.32:3000 from 192.168.25.33 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 12 12:18:05 bedroom volumio[1173]: verbose: New Socket.io Connection to 192.168.25.32:3000 from 192.168.25.31 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 12 12:18:05 bedroom volumio[1173]: info: Reloading queue from file Jan 12 12:18:05 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:05 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 12 12:18:05 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 12 12:18:05 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 12 12:18:05 bedroom volumio[1173]: info: VolumeController:: Volume=90 Mute =false Jan 12 12:18:05 bedroom volumio[1173]: info: CoreStateMachine::pushState Jan 12 12:18:05 bedroom volumio[1173]: info: CorePlayQueue::getTrack 0 Jan 12 12:18:05 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 12:18:05 bedroom volumio[1173]: info: CoreCommandRouter::volumioPushState Jan 12 12:18:05 bedroom volumio[1173]: info: CoreStateMachine::updateTrackBlock Jan 12 12:18:05 bedroom volumio[1173]: info: CorePlayQueue::getTrackBlock Jan 12 12:18:05 bedroom volumio[1173]: info: CoreCommandRouter::volumioRetrievevolume Jan 12 12:18:05 bedroom volumio[1173]: info: CoreStateMachine::setRepeat true single undefined Jan 12 12:18:05 bedroom volumio[1173]: info: CoreStateMachine::pushState Jan 12 12:18:05 bedroom volumio[1173]: info: CorePlayQueue::getTrack 0 Jan 12 12:18:05 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 12:18:05 bedroom volumio[1173]: info: CoreCommandRouter::volumioPushState Jan 12 12:18:05 bedroom volumio[1173]: info: CoreStateMachine::setRandom null Jan 12 12:18:05 bedroom volumio[1173]: info: CoreStateMachine::pushState Jan 12 12:18:05 bedroom volumio[1173]: info: CorePlayQueue::getTrack 0 Jan 12 12:18:05 bedroom volumio[1173]: info: CoreCommandRouter::volumioPushState Jan 12 12:18:05 bedroom volumio[1173]: info: Setting Device type: Raspberry PI Jan 12 12:18:05 bedroom kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 12 12:18:05 bedroom kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 12 12:18:05 bedroom sudo[1260]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:05 bedroom volumio[1173]: info: CoreCommandRouter::volumioGetState Jan 12 12:18:05 bedroom volumio[1173]: info: CorePlayQueue::getTrack 0 Jan 12 12:18:05 bedroom volumio[1173]: Starting albumart workers Jan 12 12:18:05 bedroom volumio[1173]: info: CoreCommandRouter::volumioGetState Jan 12 12:18:05 bedroom volumio[1173]: info: CorePlayQueue::getTrack 0 Jan 12 12:18:05 bedroom volumio[1173]: info: CoreCommandRouter::volumioGetState Jan 12 12:18:05 bedroom volumio[1173]: info: CorePlayQueue::getTrack 0 Jan 12 12:18:05 bedroom volumio[1173]: Starting albumart workers Jan 12 12:18:05 bedroom volumio[1173]: Starting albumart workers Jan 12 12:18:05 bedroom volumio[1173]: info: Cannot mount NAS Excelsior at system boot, trial number 1 ,retrying in 5 seconds Jan 12 12:18:05 bedroom volumio[1173]: info: Discovery: adding 7518afee-3559-416a-bc11-c5f5d5eba21e Jan 12 12:18:05 bedroom volumio[1173]: info: Discovery: Found device Kitchen Jan 12 12:18:05 bedroom volumio[1173]: info: Discovery: Connecting to remote: 192.168.25.33 Jan 12 12:18:05 bedroom volumio[1173]: info: Discovery: adding bdc8a1f3-8418-4997-902e-af944c6f7d1c Jan 12 12:18:05 bedroom volumio[1173]: info: Discovery: Found device Bathroom Jan 12 12:18:05 bedroom volumio[1173]: info: Discovery: Connecting to remote: 192.168.25.31 Jan 12 12:18:05 bedroom volumio[1173]: info: VolumeController:: Volume=90 Mute =false Jan 12 12:18:05 bedroom volumio[1173]: info: CoreStateMachine::pushState Jan 12 12:18:05 bedroom volumio[1173]: info: CorePlayQueue::getTrack 0 Jan 12 12:18:05 bedroom volumio[1173]: info: CoreCommandRouter::volumioPushState Jan 12 12:18:05 bedroom volumio[1173]: info: Discovery: adding 296feba7-58fc-4fd3-a19c-bd4ee7ea707b Jan 12 12:18:05 bedroom volumio[1173]: info: Discovery: Found device Livingroom Jan 12 12:18:05 bedroom volumio[1173]: info: Discovery: Connecting to remote: 192.168.25.34 Jan 12 12:18:05 bedroom volumio[1173]: info: Discovery: adding d42a6775-b82f-409a-b76d-9ad106617ccb Jan 12 12:18:05 bedroom volumio[1173]: info: Discovery: Found device Volumio Jan 12 12:18:05 bedroom volumio[1173]: info: Discovery: Connecting to remote: 192.168.25.35 Jan 12 12:18:06 bedroom volumio[1173]: info: Discovery: Connected to remote: 192.168.25.33 Jan 12 12:18:06 bedroom volumio[1173]: info: Completed loading Core Plugins Jan 12 12:18:06 bedroom volumio[1173]: info: Preparing to generate the ALSA configuration file Jan 12 12:18:06 bedroom volumio[1173]: info: Discovery: Connected to remote: 192.168.25.31 Jan 12 12:18:06 bedroom volumio[1173]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jan 12 12:18:06 bedroom volumio[1173]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 12 12:18:06 bedroom volumio[1173]: info: Reading ALSA contributions from plugins. Jan 12 12:18:06 bedroom volumio[1173]: info: Discovery: adding e4ab5a5b-27bb-493b-961b-19e9cf330f71 Jan 12 12:18:06 bedroom volumio[1173]: info: Discovery: Found device Bedroom Jan 12 12:18:06 bedroom volumio[1173]: info: CoreCommandRouter::volumioGetState Jan 12 12:18:06 bedroom volumio[1173]: info: CorePlayQueue::getTrack 0 Jan 12 12:18:06 bedroom volumio[1173]: info: Discovery: Connected to remote: 192.168.25.35 Jan 12 12:18:06 bedroom volumio[1173]: info: Asound.conf file unchanged, so no further update is needed Jan 12 12:18:06 bedroom volumio[1173]: info: Output device has changed, restarting MPD Jan 12 12:18:06 bedroom volumio[1173]: info: ___________ START PLUGINS ___________ Jan 12 12:18:06 bedroom volumio[1173]: info: ControllerMpd::onStart: Initializing MPD Jan 12 12:18:06 bedroom volumio[1173]: info: Creating MPD Configuration file Jan 12 12:18:06 bedroom volumio[1173]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 12:18:06 bedroom volumio[1173]: info: [1736702286672] CoreMusicLibrary::Adding element Last_100 Jan 12 12:18:06 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 12:18:06 bedroom volumio[1173]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 12:18:06 bedroom volumio[1173]: info: [1736702286690] CoreMusicLibrary::Adding element Webradio Jan 12 12:18:06 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 12:18:06 bedroom sudo[1324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 12:18:06 bedroom sudo[1324]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:06 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 12:18:06 bedroom sudo[1322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 12:18:06 bedroom sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 12:18:06 bedroom sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:06 bedroom sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:06 bedroom volumio[1173]: info: Initializing BBC Radios Jan 12 12:18:06 bedroom sudo[1326]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:06 bedroom sudo[1322]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:06 bedroom sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 12:18:06 bedroom sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:06 bedroom systemd[1]: Stopping Music Player Daemon... Jan 12 12:18:06 bedroom volumio[1173]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jan 12 12:18:06 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: Volumio Calling Home Jan 12 12:18:07 bedroom systemd[1]: mpd.service: Succeeded. Jan 12 12:18:07 bedroom systemd[1]: Stopped Music Player Daemon. Jan 12 12:18:07 bedroom systemd[1]: Starting Music Player Daemon... Jan 12 12:18:07 bedroom volumio[1173]: info: MPD Permissions set Jan 12 12:18:07 bedroom volumio[1173]: info: MPD Permissions set Jan 12 12:18:07 bedroom volumio[1173]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:07 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 12:18:07 bedroom sudo[1370]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 12:18:07 bedroom sudo[1370]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:07 bedroom sudo[1370]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:07 bedroom volumio[1173]: info: Volumio called home Jan 12 12:18:08 bedroom volumio[1173]: info: CoreCommandRouter::volumioGetState Jan 12 12:18:08 bedroom volumio[1173]: info: CorePlayQueue::getTrack 0 Jan 12 12:18:08 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 12 12:18:08 bedroom volumio[1173]: info: Discovery: Connected to remote: 192.168.25.34 Jan 12 12:18:08 bedroom volumio[1173]: info: CoreCommandRouter::volumioGetState Jan 12 12:18:08 bedroom volumio[1173]: info: CorePlayQueue::getTrack 0 Jan 12 12:18:08 bedroom volumio-remote-updater[505]: [2025-01-12 12:18:08] [connect] Successful connection Jan 12 12:18:08 bedroom volumio-remote-updater[505]: [2025-01-12 12:18:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736702288 101 Jan 12 12:18:08 bedroom volumio[1173]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 10 Jan 12 12:18:09 bedroom volumio[1173]: verbose: New Socket.io Connection to 192.168.25.32 from 192.168.24.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 11 Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 12:18:09 bedroom volumio[1173]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 12 12:18:09 bedroom volumio[1173]: info: Received Get System Version Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 12:18:09 bedroom volumio[1173]: info: Received Get System Info Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 12:18:09 bedroom volumio[1173]: info: Discovery: Getting this device information Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::volumioGetState Jan 12 12:18:09 bedroom volumio[1173]: info: CorePlayQueue::getTrack 0 Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::volumioGetState Jan 12 12:18:09 bedroom volumio[1173]: info: CorePlayQueue::getTrack 0 Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::volumioGetState Jan 12 12:18:09 bedroom volumio[1173]: info: CorePlayQueue::getTrack 0 Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 12:18:09 bedroom volumio[1173]: info: Received Get System Info Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 12:18:09 bedroom volumio[1173]: info: Discovery: Getting this device information Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::volumioGetState Jan 12 12:18:09 bedroom volumio[1173]: info: CorePlayQueue::getTrack 0 Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 12:18:09 bedroom volumio[1173]: info: CoreCommandRouter::volumioGetState Jan 12 12:18:09 bedroom volumio[1173]: info: CorePlayQueue::getTrack 0 Jan 12 12:18:09 bedroom volumio[1173]: info: Listing playlists Jan 12 12:18:10 bedroom sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio2,password=Po6AoZwE#7o&X@m1oPqvBpU841J$iM9b,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.5.6/music /mnt/NAS/Excelsior Jan 12 12:18:10 bedroom sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:10 bedroom kernel: CIFS: Attempting to mount //192.168.5.6/music Jan 12 12:18:10 bedroom systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 12 12:18:10 bedroom systemd[1]: mpd.service: Failed with result 'protocol'. Jan 12 12:18:10 bedroom systemd[1]: Failed to start Music Player Daemon. Jan 12 12:18:10 bedroom sudo[1324]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:10 bedroom sudo[1328]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:10 bedroom volumio[1173]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jan 12 12:18:10 bedroom volumio[1173]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Jan 12 12:18:10 bedroom volumio[1173]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jan 12 12:18:10 bedroom volumio[1173]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jan 12 12:18:10 bedroom volumio[1173]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Jan 12 12:18:10 bedroom volumio[1173]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jan 12 12:18:10 bedroom volumio[1173]: info: Completed starting Core Plugins Jan 12 12:18:10 bedroom volumio[1173]: info: ------------------------------------------- Jan 12 12:18:10 bedroom volumio[1173]: info: ----- MyVolumio plugins startup ---- Jan 12 12:18:10 bedroom volumio[1173]: info: ------------------------------------------- Jan 12 12:18:10 bedroom volumio[1173]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 12 12:18:11 bedroom systemd[1]: Starting Music Player Daemon... Jan 12 12:18:11 bedroom sudo[1405]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 12:18:11 bedroom sudo[1405]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:11 bedroom sudo[1405]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:13 bedroom mpd[1407]: Jan 12 12:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 12 12:18:13 bedroom systemd[1]: Started Music Player Daemon. Jan 12 12:18:13 bedroom mpd_monitor.sh[530]: MPD restarted due to no mpc output. Jan 12 12:18:14 bedroom volumio[1173]: error: MPD error: The expression evaluated to a falsy value: Jan 12 12:18:14 bedroom volumio[1173]: assert.ok(self.idling) Jan 12 12:18:14 bedroom volumio[1173]: error: The expression evaluated to a falsy value: Jan 12 12:18:14 bedroom volumio[1173]: assert.ok(self.idling) Jan 12 12:18:14 bedroom volumio[1173]: error: updateQueue error: null Jan 12 12:18:14 bedroom sudo[1399]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:14 bedroom kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 12 12:18:14 bedroom kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 12 12:18:14 bedroom volumio[1173]: info: Cannot mount NAS Excelsior at system boot, trial number 2 ,retrying in 5 seconds Jan 12 12:18:19 bedroom volumio[1173]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 12 12:18:19 bedroom volumio[1173]: info: Adding plugin bluetooth to MyMusic Plugins Jan 12 12:18:19 bedroom volumio[1173]: info: Adding plugin multiroom to MyMusic Plugins Jan 12 12:18:19 bedroom volumio[1173]: info: Adding plugin metavolumio to MyMusic Plugins Jan 12 12:18:19 bedroom volumio[1173]: info: Adding plugin cd_controller to MyMusic Plugins Jan 12 12:18:19 bedroom volumio[1173]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 12 12:18:19 bedroom volumio[1173]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 12 12:18:19 bedroom volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 12 12:18:22 bedroom systemd[1]: systemd-timedated.service: Succeeded. Jan 12 12:18:22 bedroom volumio[1173]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 12 12:18:22 bedroom volumio[1173]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 12 12:18:22 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:22 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:22 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:22 bedroom volumio[1173]: info: Starting MyVolumio Remote Streaming Endpoints Jan 12 12:18:22 bedroom volumio[1173]: info: MyVolumio login type: Token Jan 12 12:18:22 bedroom volumio[1173]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 12 12:18:22 bedroom volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 12 12:18:25 bedroom volumio[1173]: info: Starting Streaming Service Transparent Proxy Jan 12 12:18:25 bedroom volumio[1173]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 12 12:18:25 bedroom volumio[1173]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 12 12:18:25 bedroom volumio[1173]: info: Streaming services startup Jan 12 12:18:25 bedroom volumio[1173]: info: Starting Streaming Daemon Jan 12 12:18:25 bedroom sudo[1475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 12:18:25 bedroom sudo[1475]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:25 bedroom volumio[1173]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 12 12:18:25 bedroom sudo[1475]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:25 bedroom sudo[1483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio2,password=Po6AoZwE#7o&X@m1oPqvBpU841J$iM9b,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.5.6/music /mnt/NAS/Excelsior Jan 12 12:18:25 bedroom sudo[1483]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:25 bedroom kernel: CIFS: Attempting to mount //192.168.5.6/music Jan 12 12:18:26 bedroom volumio[1173]: error: Cannot start Volumio Streaming Daemon Jan 12 12:18:26 bedroom volumio[1173]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 12:18:26 bedroom volumio[1173]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 12:18:27 bedroom volumio[1173]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 12 12:18:27 bedroom volumio[1173]: info: MyVolumio token set successfully Jan 12 12:18:27 bedroom volumio[1173]: info: MYVOLUMIO: Adding device Jan 12 12:18:27 bedroom volumio[1173]: info: MYVOLUMIO: Evaluating Server Jan 12 12:18:28 bedroom ntpd[697]: Soliciting pool server 149.248.12.167 Jan 12 12:18:28 bedroom ntpd[697]: Soliciting pool server 208.67.72.43 Jan 12 12:18:28 bedroom volumio[1173]: info: MyVolumio Plan changed: premium Jan 12 12:18:28 bedroom volumio[1173]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jan 12 12:18:28 bedroom volumio[1173]: info: Removing browser output: myVolumio user plan is not superstar Jan 12 12:18:28 bedroom volumio[1173]: info: Removing audio output: Jan 12 12:18:28 bedroom volumio[1173]: info: MYVOLUMIO: Adding device Jan 12 12:18:28 bedroom volumio[1173]: info: MYVOLUMIO: Evaluating Server Jan 12 12:18:28 bedroom volumio[1173]: info: Remote config written successfully Jan 12 12:18:28 bedroom volumio[1173]: info: Starting Tunnel 1 Jan 12 12:18:28 bedroom volumio[1173]: info: Starting Tunnel Connection Checker Jan 12 12:18:28 bedroom volumio[1173]: info: Completed starting MyVolumio Plugin Jan 12 12:18:28 bedroom volumio[1173]: STREAMING PROXY: Starting server on port 3245 Jan 12 12:18:28 bedroom volumio[1173]: Node JS runtime: 14 Jan 12 12:18:28 bedroom volumio[1173]: info: MYVolumio Device enabled Jan 12 12:18:28 bedroom volumio[1173]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 12 12:18:28 bedroom volumio[1173]: info: MyVolumio status changed Jan 12 12:18:28 bedroom volumio[1173]: info: Streaming services startup Jan 12 12:18:28 bedroom volumio[1173]: info: Starting Streaming Daemon Jan 12 12:18:28 bedroom sudo[1520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 12:18:28 bedroom sudo[1520]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:28 bedroom volumio[1173]: info: Setting Geolocation for MyVolumio to us1 Jan 12 12:18:28 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:28 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:28 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:28 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:28 bedroom sudo[1520]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:28 bedroom volumio[1173]: error: Cannot start Volumio Streaming Daemon Jan 12 12:18:28 bedroom volumio[1173]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 12:18:28 bedroom volumio[1173]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 12:18:28 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 12 12:18:28 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:28 bedroom volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 12 12:18:28 bedroom ntpd[697]: Soliciting pool server 51.81.226.229 Jan 12 12:18:28 bedroom ntpd[697]: Soliciting pool server 172.234.37.140 Jan 12 12:18:29 bedroom volumio[1173]: info: [1736702309358] Starting BluetoothController Jan 12 12:18:29 bedroom volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 12 12:18:29 bedroom ntpd[697]: Soliciting pool server 66.205.249.28 Jan 12 12:18:29 bedroom ntpd[697]: Soliciting pool server 45.83.234.123 Jan 12 12:18:30 bedroom ntpd[697]: Soliciting pool server 204.2.134.163 Jan 12 12:18:30 bedroom kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 12 12:18:30 bedroom kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 12 12:18:30 bedroom sudo[1483]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:30 bedroom ntpd[697]: Soliciting pool server 173.249.203.72 Jan 12 12:18:30 bedroom ntpd[697]: Soliciting pool server 104.152.220.10 Jan 12 12:18:30 bedroom ntpd[697]: Soliciting pool server 74.6.168.72 Jan 12 12:18:31 bedroom ntpd[697]: Soliciting pool server 147.135.4.214 Jan 12 12:18:31 bedroom ntpd[697]: Soliciting pool server 72.30.35.89 Jan 12 12:18:31 bedroom ntpd[697]: Soliciting pool server 24.144.88.190 Jan 12 12:18:32 bedroom volumio[1173]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jan 12 12:18:32 bedroom ntpd[697]: Soliciting pool server 45.79.13.206 Jan 12 12:18:33 bedroom sudo[1537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 12 12:18:33 bedroom sudo[1537]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:33 bedroom sudo[1537]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:33 bedroom volumio[1173]: info: MRS: MultiRoom plugin initialized Jan 12 12:18:33 bedroom volumio[1173]: info: MRS: STOPPING SNAPCLIENT Jan 12 12:18:33 bedroom volumio[1173]: info: MRS: Snap server stop Jan 12 12:18:33 bedroom sudo[1553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 12 12:18:33 bedroom sudo[1553]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:33 bedroom volumio[1173]: info: MRS: STOPPING volumioStreaming Jan 12 12:18:33 bedroom sudo[1555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 12 12:18:33 bedroom sudo[1555]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:33 bedroom sudo[1559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 12 12:18:33 bedroom sudo[1559]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:33 bedroom volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 12 12:18:33 bedroom sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 12 12:18:33 bedroom sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 12:18:33 bedroom sudo[1561]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:33 bedroom volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 12 12:18:33 bedroom sudo[1559]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:33 bedroom sudo[1553]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:33 bedroom sudo[1555]: pam_unix(sudo:session): session closed for user root Jan 12 12:18:33 bedroom volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 12 12:18:33 bedroom volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 12 12:18:33 bedroom volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 12 12:18:33 bedroom volumio[1173]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jan 12 12:18:33 bedroom volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 12 12:18:33 bedroom volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 12 12:18:33 bedroom volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 12 12:18:33 bedroom ntpd[697]: Soliciting pool server 67.217.246.204 Jan 12 12:18:34 bedroom volumio[1173]: info: Preparing to generate the ALSA configuration file Jan 12 12:18:34 bedroom volumio[1173]: info: Updating MyVolumio device info Jan 12 12:18:34 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:34 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:34 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:34 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:34 bedroom volumio[1173]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jan 12 12:18:34 bedroom volumio[1173]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 12 12:18:34 bedroom volumio[1173]: info: Reading ALSA contributions from plugins. Jan 12 12:18:34 bedroom volumio[1173]: info: Setting Geolocation for MyVolumio to us1 Jan 12 12:18:34 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:34 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:34 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:34 bedroom volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 12:18:34 bedroom volumio[1173]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 12:18:34 bedroom volumio[1173]: TypeError: Cannot read property 'id' of null Jan 12 12:18:34 bedroom volumio[1173]: at multiroom.enableMultiroomSyncOutput (/myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:16665) Jan 12 12:18:34 bedroom volumio[1173]: at CoreCommandRouter.enableMultiroomSyncOutput (/volumio/app/index.js:1289:23) Jan 12 12:18:34 bedroom volumio[1173]: at Socket. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:282:28) Jan 12 12:18:34 bedroom volumio[1173]: at Socket.Emitter.emit (/volumio/node_modules/socket.io-client/node_modules/component-emitter/index.js:133:20) Jan 12 12:18:34 bedroom volumio[1173]: at Socket.onevent (/volumio/node_modules/socket.io-client/lib/socket.js:278:10) Jan 12 12:18:34 bedroom volumio[1173]: at Socket.onpacket (/volumio/node_modules/socket.io-client/lib/socket.js:236:12) Jan 12 12:18:34 bedroom volumio[1173]: at Manager. (/volumio/node_modules/component-bind/index.js:21:15) Jan 12 12:18:34 bedroom volumio[1173]: at Manager.Emitter.emit (/volumio/node_modules/socket.io-client/node_modules/component-emitter/index.js:133:20) Jan 12 12:18:34 bedroom volumio[1173]: at Manager.ondecoded (/volumio/node_modules/socket.io-client/lib/manager.js:345:8) Jan 12 12:18:34 bedroom volumio[1173]: at Decoder. (/volumio/node_modules/component-bind/index.js:21:15) Jan 12 12:18:34 bedroom volumio[1173]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 12:18:34 bedroom ntpd[697]: Soliciting pool server 2607:f1c0:f06b:5000::1 Jan 12 12:18:35 bedroom ntpd[697]: receive: Unexpected origin timestamp 0xeb2e77ea.ff229834 does not match aorg 0000000000.00000000 from server@173.249.203.72 xmt 0xeb2e77eb.e0b91705 Jan 12 12:18:36 bedroom sudo[1576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-12 12:17 Jan 12 12:18:36 bedroom sudo[1576]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"