-- Logs begin at Tue 2024-06-04 19:46:45 BST, end at Tue 2024-06-04 19:48:05 BST. -- Jun 04 19:47:00 volumio wireless.js[596]: trying... Jun 04 19:47:00 volumio sudo[895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:00 volumio sudo[895]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:00 volumio sudo[895]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:01 volumio wireless.js[596]: trying... Jun 04 19:47:01 volumio sudo[898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:01 volumio sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:01 volumio sudo[898]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:02 volumio wireless.js[596]: trying... Jun 04 19:47:02 volumio sudo[901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:02 volumio sudo[901]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:02 volumio sudo[901]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:03 volumio wireless.js[596]: trying... Jun 04 19:47:03 volumio sudo[904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:03 volumio sudo[904]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:03 volumio sudo[904]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:03 volumio sh[504]: eth0: probing for an IPv4LL address Jun 04 19:47:03 volumio dhcpcd[550]: eth0: probing for an IPv4LL address Jun 04 19:47:03 volumio sh[504]: eth0: DHCP lease expired Jun 04 19:47:03 volumio dhcpcd[550]: eth0: DHCP lease expired Jun 04 19:47:03 volumio sh[504]: eth0: soliciting a DHCP lease Jun 04 19:47:03 volumio dhcpcd[550]: eth0: soliciting a DHCP lease Jun 04 19:47:03 volumio dhcpcd[866]: eth0: probing for an IPv4LL address Jun 04 19:47:03 volumio dhcpcd[866]: eth0: DHCP lease expired Jun 04 19:47:03 volumio dhcpcd[866]: eth0: soliciting a DHCP lease Jun 04 19:47:04 volumio wireless.js[596]: trying... Jun 04 19:47:04 volumio sudo[927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:04 volumio sudo[927]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:04 volumio sudo[927]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:04 volumio volumio-remote-updater[630]: [2024-06-04 19:47:04] [info] asio async_connect error: system:111 (Connection refused) Jun 04 19:47:04 volumio volumio-remote-updater[630]: [2024-06-04 19:47:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 04 19:47:04 volumio volumio-remote-updater[630]: [2024-06-04 19:47:04] [error] handle_connect error: Underlying Transport Error Jun 04 19:47:05 volumio wireless.js[596]: trying... Jun 04 19:47:05 volumio sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:05 volumio sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:05 volumio sudo[930]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:06 volumio wireless.js[596]: trying... Jun 04 19:47:06 volumio sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:06 volumio sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:06 volumio sudo[933]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:07 volumio wireless.js[596]: trying... Jun 04 19:47:07 volumio sudo[936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:07 volumio sudo[936]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:07 volumio sudo[936]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:08 volumio wireless.js[596]: trying... Jun 04 19:47:08 volumio sudo[939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:08 volumio sudo[939]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:08 volumio sudo[939]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:08 volumio sh[504]: eth0: using IPv4LL address 169.254.226.44 Jun 04 19:47:08 volumio sh[504]: eth0: adding route to 169.254.0.0/16 Jun 04 19:47:08 volumio sh[504]: eth0: adding default route Jun 04 19:47:08 volumio dhcpcd[550]: eth0: using IPv4LL address 169.254.226.44 Jun 04 19:47:08 volumio dhcpcd[550]: eth0: adding route to 169.254.0.0/16 Jun 04 19:47:08 volumio dhcpcd[550]: eth0: adding default route Jun 04 19:47:08 volumio avahi-daemon[619]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.226.44. Jun 04 19:47:08 volumio avahi-daemon[619]: New relevant interface eth0.IPv4 for mDNS. Jun 04 19:47:08 volumio avahi-daemon[619]: Registering new address record for 169.254.226.44 on eth0.IPv4. Jun 04 19:47:08 volumio sh[504]: forked to background, child pid 952 Jun 04 19:47:08 volumio dhcpcd[550]: forked to background, child pid 952 Jun 04 19:47:08 volumio ifplugd(eth0)[761]: client: ifup: interface eth0 already configured Jun 04 19:47:08 volumio sh[504]: eth0=eth0 Jun 04 19:47:08 volumio ifplugd(eth0)[761]: Program executed successfully. Jun 04 19:47:08 volumio ifplugd[708]: start eth0...done. Jun 04 19:47:08 volumio systemd[1]: Started LSB: Brings up/down network automatically. Jun 04 19:47:08 volumio nmbd[724]: [2024/06/04 19:47:08.501709, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 04 19:47:08 volumio systemd[1]: Started Samba NMB Daemon. Jun 04 19:47:08 volumio nmbd[724]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 04 19:47:08 volumio systemd[1]: Starting Samba Winbind Daemon... Jun 04 19:47:08 volumio winbindd[978]: [2024/06/04 19:47:08.886631, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 04 19:47:08 volumio winbindd[978]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 04 19:47:08 volumio winbindd[978]: [2024/06/04 19:47:08.922514, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 04 19:47:08 volumio systemd[1]: Started Samba Winbind Daemon. Jun 04 19:47:08 volumio winbindd[978]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 04 19:47:09 volumio systemd[1]: Starting Samba SMB Daemon... Jun 04 19:47:09 volumio wireless.js[596]: trying... Jun 04 19:47:09 volumio sudo[985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:09 volumio sudo[985]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:09 volumio sudo[985]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:09 volumio smbd[983]: [2024/06/04 19:47:09.530253, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 04 19:47:09 volumio smbd[983]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 04 19:47:09 volumio systemd[1]: Started Samba SMB Daemon. Jun 04 19:47:09 volumio dhcpcd[866]: eth0: using IPv4LL address 169.254.226.44 Jun 04 19:47:09 volumio dhcpcd[866]: eth0: adding route to 169.254.0.0/16 Jun 04 19:47:09 volumio dhcpcd[866]: eth0: adding default route Jun 04 19:47:09 volumio ntpd[779]: Listen normally on 3 eth0 169.254.226.44:123 Jun 04 19:47:09 volumio ntpd[779]: new interface(s) found: waking up resolver Jun 04 19:47:09 volumio dhcpcd[866]: forked to background, child pid 1006 Jun 04 19:47:09 volumio sudo[865]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:10 volumio wireless.js[596]: trying... Jun 04 19:47:10 volumio sudo[1008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:10 volumio sudo[1008]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:10 volumio sudo[1008]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:11 volumio wireless.js[596]: trying... Jun 04 19:47:11 volumio sudo[1011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:11 volumio sudo[1011]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:11 volumio sudo[1011]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:12 volumio wireless.js[596]: trying... Jun 04 19:47:12 volumio sudo[1014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:12 volumio sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:12 volumio sudo[1014]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:13 volumio wireless.js[596]: trying... Jun 04 19:47:13 volumio sudo[1017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:13 volumio sudo[1017]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:13 volumio sudo[1017]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:14 volumio wireless.js[596]: trying... Jun 04 19:47:14 volumio sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:14 volumio sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:14 volumio sudo[1020]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:14 volumio volumio-remote-updater[630]: [2024-06-04 19:47:14] [info] asio async_connect error: system:111 (Connection refused) Jun 04 19:47:14 volumio volumio-remote-updater[630]: [2024-06-04 19:47:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 04 19:47:14 volumio volumio-remote-updater[630]: [2024-06-04 19:47:14] [error] handle_connect error: Underlying Transport Error Jun 04 19:47:15 volumio wireless.js[596]: trying... Jun 04 19:47:15 volumio sudo[1023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:15 volumio sudo[1023]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:15 volumio sudo[1023]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:15 volumio dhcpcd[952]: eth0: offered 192.168.178.62 from 192.168.178.1 Jun 04 19:47:15 volumio dhcpcd[952]: eth0: probing address 192.168.178.62/24 Jun 04 19:47:16 volumio wpa_supplicant[879]: wlan0: Failed to initiate sched scan Jun 04 19:47:16 volumio wireless.js[596]: trying... Jun 04 19:47:16 volumio sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:16 volumio sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:16 volumio sudo[1026]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:16 volumio dhcpcd[1006]: eth0: offered 192.168.178.62 from 192.168.178.1 Jun 04 19:47:16 volumio dhcpcd[1006]: eth0: probing address 192.168.178.62/24 Jun 04 19:47:17 volumio wireless.js[596]: trying... Jun 04 19:47:17 volumio sudo[1029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:17 volumio sudo[1029]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:17 volumio sudo[1029]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:17 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 04 19:47:18 volumio wireless.js[596]: trying... Jun 04 19:47:18 volumio sudo[1032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:47:18 volumio sudo[1032]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:18 volumio sudo[1032]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:19 volumio wireless.js[596]: Overtime, starting plan B Jun 04 19:47:19 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 04 19:47:19 volumio systemd[1]: Started Wireless Services. Jun 04 19:47:19 volumio systemd[1]: Started Volumio Backend Module. Jun 04 19:47:19 volumio systemd[1]: Started Volumio Cpu Tweaker. Jun 04 19:47:19 volumio systemd[1]: Reached target Multi-User System. Jun 04 19:47:19 volumio systemd[1]: Reached target Graphical Interface. Jun 04 19:47:19 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 04 19:47:19 volumio volumio-cpu-tweak[1038]: Setting RT Priority for mpd Jun 04 19:47:19 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 04 19:47:19 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 04 19:47:19 volumio systemd[1]: Startup finished in 15.722s (kernel) + 36.337s (userspace) = 52.059s. Jun 04 19:47:19 volumio volumio-cpu-tweak[1038]: pid 35's current scheduling policy: SCHED_OTHER Jun 04 19:47:19 volumio volumio-cpu-tweak[1038]: pid 35's current scheduling priority: 0 Jun 04 19:47:19 volumio volumio-cpu-tweak[1038]: Setting MPD Affinity Jun 04 19:47:19 volumio volumio-cpu-tweak[1038]: pid 3's current affinity mask: f Jun 04 19:47:19 volumio volumio-cpu-tweak[1038]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jun 04 19:47:19 volumio volumio-cpu-tweak[1038]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 04 19:47:19 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Jun 04 19:47:20 volumio dhcpcd[952]: eth0: leased 192.168.178.62 for 864000 seconds Jun 04 19:47:20 volumio dhcpcd[952]: eth0: adding route to 192.168.178.0/24 Jun 04 19:47:20 volumio dhcpcd[952]: eth0: changing default route via 192.168.178.1 Jun 04 19:47:20 volumio dhcpcd[1006]: eth0: pid 952 deleted default route Jun 04 19:47:20 volumio avahi-daemon[619]: Registering new address record for 192.168.178.62 on eth0.IPv4. Jun 04 19:47:20 volumio avahi-daemon[619]: Withdrawing address record for 169.254.226.44 on eth0. Jun 04 19:47:20 volumio avahi-daemon[619]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.226.44. Jun 04 19:47:20 volumio dhcpcd[1006]: eth0: pid 952 deleted IP address 169.254.226.44/16 Jun 04 19:47:20 volumio dhcpcd[1006]: eth0: deleting route to 169.254.0.0/16 Jun 04 19:47:20 volumio dhcpcd[952]: eth0: deleting route to 169.254.0.0/16 Jun 04 19:47:20 volumio avahi-daemon[619]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.62. Jun 04 19:47:20 volumio dhcpcd[952]: eth0: pid 952 deleted default route via 192.168.178.1 Jun 04 19:47:20 volumio dhcpcd[1006]: eth0: probing for an IPv4LL address Jun 04 19:47:21 volumio volumio[1037]: info: ------------------------------------------- Jun 04 19:47:21 volumio volumio[1037]: info: ----- Volumio3 ---- Jun 04 19:47:21 volumio volumio[1037]: info: ------------------------------------------- Jun 04 19:47:21 volumio volumio[1037]: info: ----- System startup ---- Jun 04 19:47:21 volumio volumio[1037]: info: ------------------------------------------- Jun 04 19:47:21 volumio dhcpcd[1006]: eth0: leased 192.168.178.62 for 864000 seconds Jun 04 19:47:21 volumio dhcpcd[1006]: eth0: adding route to 192.168.178.0/24 Jun 04 19:47:21 volumio dhcpcd[1006]: eth0: adding default route via 192.168.178.1 Jun 04 19:47:22 volumio volumio[1037]: info: MYVOLUMIO Environment detected Jun 04 19:47:23 volumio volumio[1037]: info: Plugin folders cleanup Jun 04 19:47:23 volumio volumio[1037]: info: Scanning into folder /volumio/app/plugins/ Jun 04 19:47:23 volumio volumio[1037]: info: Scanning category audio_interface Jun 04 19:47:23 volumio volumio[1037]: info: Scanning category miscellanea Jun 04 19:47:23 volumio volumio[1037]: info: Scanning category music_service Jun 04 19:47:23 volumio volumio[1037]: info: Scanning category plugins.json Jun 04 19:47:23 volumio volumio[1037]: info: Scanning category system_controller Jun 04 19:47:23 volumio volumio[1037]: info: Scanning category user_interface Jun 04 19:47:23 volumio volumio[1037]: info: Scanning into folder /data/plugins/ Jun 04 19:47:23 volumio volumio[1037]: info: Scanning category music_service Jun 04 19:47:23 volumio volumio[1037]: info: Scanning category system_controller Jun 04 19:47:23 volumio volumio[1037]: info: Scanning category user_interface Jun 04 19:47:23 volumio volumio[1037]: info: Plugin folders cleanup completed Jun 04 19:47:23 volumio volumio[1037]: info: ------------------------------------------- Jun 04 19:47:23 volumio volumio[1037]: info: ----- Core plugins startup ---- Jun 04 19:47:23 volumio volumio[1037]: info: ------------------------------------------- Jun 04 19:47:23 volumio volumio[1037]: info: Loading plugins from folder /volumio/app/plugins/ Jun 04 19:47:23 volumio volumio[1037]: info: Adding plugin upnp to MyMusic Plugins Jun 04 19:47:23 volumio volumio[1037]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 04 19:47:23 volumio volumio[1037]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 04 19:47:23 volumio volumio[1037]: info: Loading plugins from folder /data/plugins/ Jun 04 19:47:23 volumio volumio[1037]: info: Loading plugin "system"... Jun 04 19:47:23 volumio volumio[1037]: info: Loading plugin "appearance"... Jun 04 19:47:23 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 04 19:47:23 volumio wpa_supplicant[879]: wlan0: Failed to initiate sched scan Jun 04 19:47:24 volumio volumio-remote-updater[630]: [2024-06-04 19:47:24] [connect] Successful connection Jun 04 19:47:24 volumio ntpd[779]: Listen normally on 4 eth0 192.168.178.62:123 Jun 04 19:47:24 volumio ntpd[779]: Deleting interface #3 eth0, 169.254.226.44#123, interface stats: received=0, sent=0, dropped=0, active_time=15 secs Jun 04 19:47:24 volumio ntpd[779]: new interface(s) found: waking up resolver Jun 04 19:47:24 volumio volumio[1037]: info: Loading plugin "network"... Jun 04 19:47:24 volumio volumio[1037]: info: Refreshing Cached IP Addresses Jun 04 19:47:24 volumio sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 04 19:47:24 volumio sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:24 volumio sudo[1155]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:24 volumio sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 19:47:24 volumio volumio[1037]: info: Loading plugin "services"... Jun 04 19:47:24 volumio sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:24 volumio sudo[1157]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:24 volumio volumio[1037]: info: Loading plugin "alsa_controller"... Jun 04 19:47:24 volumio sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 04 19:47:24 volumio sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:24 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 19:47:24 volumio volumio[1037]: info: Loading plugin "wizard"... Jun 04 19:47:24 volumio volumio[1037]: info: Loading plugin "networkfs"... Jun 04 19:47:24 volumio volumio[1037]: info: Starting Udev Watcher for removable devices Jun 04 19:47:24 volumio sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=osmc,password=osmc,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.64/Seagate Expansion Drive\\music /mnt/NAS/Seagate_Expansion_Drive Jun 04 19:47:24 volumio sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:24 volumio volumio[1037]: info: Ignoring mount for partition: boot Jun 04 19:47:24 volumio volumio[1037]: info: Ignoring mount for partition: volumio Jun 04 19:47:24 volumio volumio[1037]: info: Ignoring mount for partition: volumio_data Jun 04 19:47:24 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 04 19:47:24 volumio volumio[1037]: info: Loading plugin "volumio_command_line_client"... Jun 04 19:47:24 volumio volumio[1037]: info: Loading plugin "upnp"... Jun 04 19:47:24 volumio volumio[1037]: info: [1717526844972] Starting Upmpd Daemon Jun 04 19:47:24 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 04 19:47:24 volumio volumio[1037]: info: Loading plugin "my_music"... Jun 04 19:47:24 volumio volumio[1037]: info: Loading plugin "mpd"... Jun 04 19:47:25 volumio kernel: Key type cifs.spnego registered Jun 04 19:47:25 volumio kernel: Key type cifs.idmap registered Jun 04 19:47:25 volumio 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. Jun 04 19:47:25 volumio kernel: CIFS: Attempting to mount \\192.168.178.64\Seagate Expansion Drive Jun 04 19:47:25 volumio sudo[1168]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:25 volumio volumio[1037]: info: Loading plugin "upnp_browser"... Jun 04 19:47:25 volumio volumio[1037]: info: Loading plugin "alarm-clock"... Jun 04 19:47:26 volumio volumio[1037]: info: Loading plugin "airplay_emulation"... Jun 04 19:47:26 volumio volumio[1037]: info: Starting Shairport Sync Jun 04 19:47:26 volumio volumio[1037]: info: Loading plugin "last_100"... Jun 04 19:47:26 volumio volumio[1037]: info: Loading plugin "webradio"... Jun 04 19:47:26 volumio volumio[1037]: info: Loading plugin "i2s_dacs"... Jun 04 19:47:26 volumio volumio[1037]: info: Loading plugin "volumiodiscovery"... Jun 04 19:47:26 volumio volumio[1037]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 04 19:47:26 volumio node[1037]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 04 19:47:26 volumio volumio[1037]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 19:47:26 volumio node[1037]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 19:47:26 volumio volumio[1037]: *** WARNING *** For more information see Jun 04 19:47:26 volumio node[1037]: *** WARNING *** For more information see Jun 04 19:47:26 volumio volumio[1037]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 04 19:47:26 volumio node[1037]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 04 19:47:26 volumio volumio[1037]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 19:47:26 volumio node[1037]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 19:47:26 volumio volumio[1037]: *** WARNING *** For more information see Jun 04 19:47:26 volumio node[1037]: *** WARNING *** For more information see Jun 04 19:47:26 volumio volumio[1037]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 04 19:47:26 volumio volumio[1037]: info: Discovery: Started advertising with name: Volumio Jun 04 19:47:26 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 04 19:47:26 volumio volumio[1037]: info: Loading plugin "bandcamp"... Jun 04 19:47:27 volumio sudo[1160]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:27 volumio volumio[1037]: info: Loading plugin "mixcloud"... Jun 04 19:47:28 volumio volumio[1037]: info: Loading plugin "spop"... Jun 04 19:47:30 volumio volumio[1037]: info: Loading plugin "now_playing"... Jun 04 19:47:31 volumio wpa_supplicant[879]: wlan0: Trying to associate with SSID 'FRITZ!Box 7530 JN' Jun 04 19:47:32 volumio volumio[1037]: info: Loading plugin "outputs"... Jun 04 19:47:32 volumio volumio[1037]: info: Loading plugin "albumart"... Jun 04 19:47:32 volumio volumio[1037]: info: Plugin example_plugin is not enabled Jun 04 19:47:32 volumio volumio[1037]: info: Loading plugin "inputs"... Jun 04 19:47:32 volumio volumio[1037]: info: Loading plugin "updater_comm"... Jun 04 19:47:32 volumio volumio[1037]: info: Plugin mpdemulation is not enabled Jun 04 19:47:32 volumio volumio[1037]: info: Loading plugin "rest_api"... Jun 04 19:47:32 volumio volumio[1037]: info: Loading plugin "websocket"... Jun 04 19:47:32 volumio volumio[1037]: info: Starting Socket.io Server version 2.3.0 Jun 04 19:47:32 volumio volumio[1037]: info: Loading plugin "backup_restore"... Jun 04 19:47:32 volumio volumio[1037]: Forking 3 albumart workers Jun 04 19:47:33 volumio volumio[1037]: info: Applying required configuration parameters for plugin backup_restore Jun 04 19:47:33 volumio volumio[1037]: info: Loading plugin "Systeminfo"... Jun 04 19:47:33 volumio volumio[1037]: Starting albumart workers Jun 04 19:47:33 volumio volumio[1037]: Starting albumart workers Jun 04 19:47:33 volumio volumio[1037]: Starting albumart workers Jun 04 19:47:33 volumio volumio[1037]: info: Loading plugin "lastfm"... Jun 04 19:47:33 volumio dhcpcd[1006]: wlan0: carrier acquired Jun 04 19:47:33 volumio dhcpcd[1006]: wlan0: connected to Access Point `FRITZ!Box 7530 JN' Jun 04 19:47:33 volumio wpa_supplicant[879]: wlan0: Associated with 3c:a6:2f:a4:4d:85 Jun 04 19:47:33 volumio wpa_supplicant[879]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:a6:2f:a4:4d:85 completed [id=0 id_str=] Jun 04 19:47:33 volumio wpa_supplicant[879]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 04 19:47:33 volumio wpa_supplicant[879]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=GB Jun 04 19:47:33 volumio dhcpcd[1006]: wlan0: IAID eb:b8:38:3a Jun 04 19:47:33 volumio dhcpcd[1006]: wlan0: adding address fe80::982d:bb5d:3fef:9d72 Jun 04 19:47:33 volumio dhcpcd[1006]: ipv6_addaddr1: Permission denied Jun 04 19:47:33 volumio dhcpcd[1006]: wlan0: carrier lost Jun 04 19:47:33 volumio dhcpcd[1006]: wlan0: carrier acquired Jun 04 19:47:33 volumio dhcpcd[1006]: wlan0: IAID eb:b8:38:3a Jun 04 19:47:34 volumio dhcpcd[1006]: wlan0: soliciting an IPv6 router Jun 04 19:47:34 volumio volumio[1037]: info: Loading plugin "randomizer"... Jun 04 19:47:34 volumio dhcpcd[1006]: wlan0: rebinding lease of 192.168.178.75 Jun 04 19:47:34 volumio dhcpcd[1006]: wlan0: probing address 192.168.178.75/24 Jun 04 19:47:35 volumio volumio[1037]: info: Applying required configuration parameters for plugin randomizer Jun 04 19:47:35 volumio volumio[1037]: info: Loading i18n strings for locale en Jun 04 19:47:35 volumio volumio[1037]: Updating browse sources language Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::initPlayerControls Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 19:47:35 volumio volumio[1037]: Express server listening on port 3000 Jun 04 19:47:35 volumio volumio[1037]: [Metrics] WebUI: 14s 625.92ms Jun 04 19:47:35 volumio volumio[1037]: info: CoreStateMachine::resetVolumioState Jun 04 19:47:35 volumio volumio[1037]: info: CoreStateMachine::getcurrentVolume Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::volumioRetrievevolume Jun 04 19:47:35 volumio sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 04 19:47:35 volumio sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:35 volumio sudo[1272]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:35 volumio sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 19:47:35 volumio sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:35 volumio sudo[1274]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:35 volumio volumio[1037]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 04 19:47:35 volumio volumio[1037]: info: VolumeController:: Volume=74 Mute =false Jun 04 19:47:35 volumio volumio[1037]: info: CoreStateMachine::pushState Jun 04 19:47:35 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Jun 04 19:47:35 volumio volumio[1037]: info: CoreStateMachine::updateTrackBlock Jun 04 19:47:35 volumio volumio[1037]: info: CorePlayQueue::getTrackBlock Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::volumioRetrievevolume Jun 04 19:47:35 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:47:35 volumio volumio[1037]: info: Reloading queue from file Jun 04 19:47:36 volumio volumio[1037]: info: CoreStateMachine::setRepeat null single undefined Jun 04 19:47:36 volumio volumio[1037]: info: CoreStateMachine::pushState Jun 04 19:47:36 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Jun 04 19:47:36 volumio volumio[1037]: info: CoreStateMachine::setRandom null Jun 04 19:47:36 volumio volumio[1037]: info: CoreStateMachine::pushState Jun 04 19:47:36 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Jun 04 19:47:36 volumio volumio[1037]: info: Setting Device type: Raspberry PI Jun 04 19:47:36 volumio volumio[1037]: info: Completed loading Core Plugins Jun 04 19:47:36 volumio volumio[1037]: info: Preparing to generate the ALSA configuration file Jun 04 19:47:36 volumio sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 04 19:47:36 volumio sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:36 volumio volumio[1037]: info: VolumeController:: Volume=74 Mute =false Jun 04 19:47:36 volumio volumio[1037]: info: CoreStateMachine::pushState Jun 04 19:47:36 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Jun 04 19:47:36 volumio volumio[1037]: info: Asound.conf file unchanged, so no further update is needed Jun 04 19:47:36 volumio volumio[1037]: info: Output device has changed, restarting MPD Jun 04 19:47:36 volumio sudo[1290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 04 19:47:36 volumio sudo[1290]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:36 volumio volumio[1037]: info: Output device has changed, restarting Shairport Sync Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:47:36 volumio sudo[1290]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:36 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 04 19:47:36 volumio sudo[1286]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:36 volumio sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 04 19:47:36 volumio sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:36 volumio volumio[1037]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 19:47:36 volumio volumio[1037]: info: ___________ START PLUGINS ___________ Jun 04 19:47:36 volumio systemd[1]: Listening on mpd.socket. Jun 04 19:47:36 volumio systemd[1]: Starting Music Player Daemon... Jun 04 19:47:36 volumio volumio[1037]: info: ControllerMpd::onStart: Initializing MPD Jun 04 19:47:36 volumio volumio[1037]: info: Creating MPD Configuration file Jun 04 19:47:36 volumio sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 04 19:47:36 volumio sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 19:47:36 volumio sudo[1301]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:36 volumio volumio[1037]: info: [1717526856333] CoreMusicLibrary::Adding element Media Servers Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:47:36 volumio sudo[1303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 04 19:47:36 volumio sudo[1303]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:47:36 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 04 19:47:36 volumio systemd[1]: mpd.service: Succeeded. Jun 04 19:47:36 volumio systemd[1]: Stopped Music Player Daemon. Jun 04 19:47:36 volumio systemd[1]: Starting Music Player Daemon... Jun 04 19:47:36 volumio volumio[1037]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:47:36 volumio volumio[1037]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 19:47:36 volumio volumio[1037]: info: [1717526856457] CoreMusicLibrary::Adding element Last_100 Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 19:47:36 volumio volumio[1037]: info: [1717526856462] CoreMusicLibrary::Adding element Webradio Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 19:47:36 volumio volumio[1037]: info: Initializing BBC Radios Jun 04 19:47:36 volumio sudo[1307]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 04 19:47:36 volumio sudo[1307]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:36 volumio sudo[1307]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 04 19:47:36 volumio sudo[1307]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 19:47:36 volumio volumio[1037]: info: [1717526856548] CoreMusicLibrary::Adding element Bandcamp Discover Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:47:36 volumio volumio[1037]: Cannot find translation for source Bandcamp Discover Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 19:47:36 volumio volumio[1037]: info: [1717526856559] CoreMusicLibrary::Adding element Mixcloud Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:47:36 volumio volumio[1037]: Cannot find translation for source Bandcamp Discover Jun 04 19:47:36 volumio volumio[1037]: Cannot find translation for source Mixcloud Jun 04 19:47:36 volumio volumio[1037]: info: Creating Spotify config file Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:36 volumio volumio[1037]: info: [now-playing] ConfigUpdater: config is up to date. Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 19:47:36 volumio volumio[1037]: info: [1717526856633] CoreMusicLibrary::Adding element LastFM Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:47:36 volumio volumio[1037]: Cannot find translation for source Bandcamp Discover Jun 04 19:47:36 volumio volumio[1037]: Cannot find translation for source Mixcloud Jun 04 19:47:36 volumio volumio[1037]: Cannot find translation for source LastFM Jun 04 19:47:36 volumio volumio[1037]: info: [LastFM] scrobbler initiated! Jun 04 19:47:36 volumio volumio[1037]: info: [LastFM] extended logging: false Jun 04 19:47:36 volumio volumio[1037]: info: [LastFM] try scrobble stream/radio plays: true Jun 04 19:47:36 volumio volumio[1037]: info: [LastFM] Left init routine Jun 04 19:47:36 volumio volumio[1037]: info: [LastFM] Socket already connected: true Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 19:47:36 volumio volumio[1037]: info: [1717526856669] CoreMusicLibrary::Adding element Randomizer Jun 04 19:47:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:47:36 volumio volumio[1037]: Cannot find translation for source Bandcamp Discover Jun 04 19:47:36 volumio volumio[1037]: Cannot find translation for source Mixcloud Jun 04 19:47:36 volumio volumio[1037]: Cannot find translation for source LastFM Jun 04 19:47:36 volumio volumio[1037]: Cannot find translation for source Randomizer Jun 04 19:47:36 volumio volumio[1037]: info: Volumio Calling Home Jun 04 19:47:36 volumio volumio[1037]: info: [now-playing] App is listening on port 4004. Jun 04 19:47:36 volumio volumio[1037]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jun 04 19:47:36 volumio volumio[1037]: info: MPD Permissions set Jun 04 19:47:36 volumio volumio[1037]: info: MPD Permissions set Jun 04 19:47:36 volumio volumio[1037]: info: Upmpdcli Daemon Started Jun 04 19:47:36 volumio volumio[1037]: info: Spotify config file written Jun 04 19:47:36 volumio sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 04 19:47:36 volumio sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:47:37 volumio systemd[1]: Started go-librespot Daemon. Jun 04 19:47:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:47:37 volumio go-librespot[1335]: Librespot-go daemon starting... Jun 04 19:47:37 volumio sudo[1329]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:37 volumio volumio[1037]: info: Starting Shairport Sync Jun 04 19:47:37 volumio volumio[1037]: info: Starting Shairport Sync Jun 04 19:47:37 volumio volumio[1037]: info: Starting Shairport Sync Jun 04 19:47:37 volumio sudo[1342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 19:47:37 volumio sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 04 19:47:37 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 04 19:47:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 04 19:47:37 volumio sudo[1344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 19:47:37 volumio sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:37 volumio sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 19:47:37 volumio sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:47:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 04 19:47:37 volumio sudo[1342]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:37 volumio volumio[1037]: info: Shairport-Sync Started Jun 04 19:47:37 volumio volumio[1037]: Error adding Membership: Error: addMembership EINVAL Jun 04 19:47:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 04 19:47:37 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 04 19:47:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 04 19:47:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 04 19:47:37 volumio sudo[1348]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:37 volumio sudo[1344]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:37 volumio volumio[1037]: info: Shairport-Sync Started Jun 04 19:47:37 volumio volumio[1037]: info: Shairport-Sync Started Jun 04 19:47:37 volumio nmbd[724]: [2024/06/04 19:47:37.642193, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jun 04 19:47:37 volumio nmbd[724]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Jun 04 19:47:37 volumio nmbd[724]: [2024/06/04 19:47:37.645753, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jun 04 19:47:37 volumio nmbd[724]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Jun 04 19:47:37 volumio go-librespot[1335]: time="2024-06-04T19:47:37+01:00" level=info msg="generated new device id: da2189c4815f1db6c6ee04e608bab712c2a53aac" Jun 04 19:47:39 volumio dhcpcd[1006]: wlan0: leased 192.168.178.75 for 864000 seconds Jun 04 19:47:39 volumio avahi-daemon[619]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.75. Jun 04 19:47:39 volumio dhcpcd[1006]: wlan0: adding route to 192.168.178.0/24 Jun 04 19:47:39 volumio dhcpcd[1006]: wlan0: adding default route via 192.168.178.1 Jun 04 19:47:39 volumio avahi-daemon[619]: New relevant interface wlan0.IPv4 for mDNS. Jun 04 19:47:39 volumio avahi-daemon[619]: Registering new address record for 192.168.178.75 on wlan0.IPv4. Jun 04 19:47:39 volumio volumio-remote-updater[630]: [2024-06-04 19:47:39] [connect] Successful connection Jun 04 19:47:39 volumio volumio-remote-updater[630]: [2024-06-04 19:47:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1717526859 101 Jun 04 19:47:39 volumio volumio[1037]: 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: 2 Jun 04 19:47:39 volumio mpd[1316]: Jun 04 19:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 04 19:47:40 volumio volumio[1037]: info: go-librespot daemon successfully initialized Jun 04 19:47:40 volumio systemd[1]: Started Music Player Daemon. Jun 04 19:47:40 volumio sudo[1303]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:40 volumio sudo[1292]: pam_unix(sudo:session): session closed for user root Jun 04 19:47:40 volumio volumio[1037]: Upnp client error: Error: This socket has been ended by the other party Jun 04 19:47:40 volumio volumio[1037]: info: Completed starting Core Plugins Jun 04 19:47:40 volumio volumio[1037]: info: ------------------------------------------- Jun 04 19:47:40 volumio volumio[1037]: info: ----- MyVolumio plugins startup ---- Jun 04 19:47:40 volumio volumio[1037]: info: ------------------------------------------- Jun 04 19:47:40 volumio volumio[1037]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 04 19:47:40 volumio volumio[1037]: info: MPD running with PID1316 Jun 04 19:47:40 volumio volumio[1037]: ,establishing connection Jun 04 19:47:40 volumio volumio[1037]: error: MPD error: The expression evaluated to a falsy value: Jun 04 19:47:40 volumio volumio[1037]: assert.ok(self.idling) Jun 04 19:47:40 volumio volumio[1037]: error: The expression evaluated to a falsy value: Jun 04 19:47:40 volumio volumio[1037]: assert.ok(self.idling) Jun 04 19:47:40 volumio volumio[1037]: error: MPD error: The expression evaluated to a falsy value: Jun 04 19:47:40 volumio volumio[1037]: assert.ok(self.idling) Jun 04 19:47:40 volumio volumio[1037]: error: The expression evaluated to a falsy value: Jun 04 19:47:40 volumio volumio[1037]: assert.ok(self.idling) Jun 04 19:47:40 volumio volumio[1037]: error: updateQueue error: null Jun 04 19:47:40 volumio volumio[1288]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 04 19:47:40 volumio ntpd[779]: Listen normally on 5 wlan0 192.168.178.75:123 Jun 04 19:47:40 volumio ntpd[779]: new interface(s) found: waking up resolver Jun 04 19:47:43 volumio volumio[1037]: info: Initializing connection to go-librespot Websocket Jun 04 19:47:45 volumio volumio[1037]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 04 19:47:46 volumio volumio[1288]: ......................................................++++ Jun 04 19:47:48 volumio volumio[1037]: Cannot compose Albumart path Jun 04 19:47:48 volumio volumio[1037]: Cannot compose Albumart path Jun 04 19:48:00 volumio volumio[1037]: error: MyVolumio Plugin failed to start in a timely fashion Jun 04 19:48:00 volumio volumio[1037]: info: BOOT COMPLETED Jun 04 19:48:00 volumio volumio[1037]: [Metrics] CommandRouter: 38s 396.21ms Jun 04 19:48:00 volumio volumio[1037]: info: CoreCommandRouter::volumiosetStartupVolume Jun 04 19:48:00 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:48:00 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:48:00 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Jun 04 19:48:00 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Jun 04 19:48:00 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jun 04 19:48:00 volumio volumio[1288]: .........................................................................................................................++++ Jun 04 19:48:00 volumio volumio[1288]: e is 65537 (0x010001) Jun 04 19:48:00 volumio volumio[1288]: writing RSA key Jun 04 19:48:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 04 19:48:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 04 19:48:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 04 19:48:04 volumio volumio[1037]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 19:48:04 volumio volumio[1037]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Jun 04 19:48:04 volumio volumio[1037]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jun 04 19:48:04 volumio volumio[1037]: errno: -3007, Jun 04 19:48:04 volumio volumio[1037]: code: 'ENOTFOUND', Jun 04 19:48:04 volumio volumio[1037]: syscall: 'getaddrinfo', Jun 04 19:48:04 volumio volumio[1037]: hostname: 'ws.audioscrobbler.com' Jun 04 19:48:04 volumio volumio[1037]: } Jun 04 19:48:04 volumio volumio[1037]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 19:48:05 volumio sudo[1467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-06-04 19:47 Jun 04 19:48:05 volumio sudo[1467]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"