-- Logs begin at Sun 2025-03-16 14:35:51 EDT, end at Sun 2025-03-16 14:37:11 EDT. -- Mar 16 14:36:00 volumio-liva sudo[982]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:00 volumio-liva wireless.js[728]: SETTING APPROPRIATE REG DOMAIN: US Mar 16 14:36:00 volumio-liva sudo[995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 16 14:36:00 volumio-liva sudo[995]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:00 volumio-liva sudo[995]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:00 volumio-liva sudo[997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Mar 16 14:36:00 volumio-liva sudo[997]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:00 volumio-liva sudo[997]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:00 volumio-liva wireless.js[728]: SUCCESSFULLY SET NEW REGDOMAIN: US Mar 16 14:36:01 volumio-liva wireless.js[728]: Start wireless flow Mar 16 14:36:01 volumio-liva wireless.js[728]: Stopped hotspot (if there).. Mar 16 14:36:01 volumio-liva sudo[1002]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 16 14:36:01 volumio-liva sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:01 volumio-liva sudo[1002]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:01 volumio-liva sudo[1004]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 16 14:36:01 volumio-liva sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:01 volumio-liva sudo[1004]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:01 volumio-liva wireless.js[728]: DHCP IP Mar 16 14:36:01 volumio-liva wireless.js[728]: Start ap Mar 16 14:36:01 volumio-liva wpa_supplicant[1006]: Successfully initialized wpa_supplicant Mar 16 14:36:01 volumio-liva sudo[1007]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 16 14:36:01 volumio-liva sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:01 volumio-liva dhcpcd[1008]: sending commands to master dhcpcd process Mar 16 14:36:01 volumio-liva dhcpcd[826]: control command: /sbin/dhcpcd Mar 16 14:36:01 volumio-liva sudo[1007]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:02 volumio-liva wireless.js[728]: trying... Mar 16 14:36:02 volumio-liva sudo[1013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 14:36:02 volumio-liva sudo[1013]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:02 volumio-liva sudo[1013]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:03 volumio-liva wireless.js[728]: trying... Mar 16 14:36:03 volumio-liva sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 14:36:03 volumio-liva sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:03 volumio-liva sudo[1016]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:04 volumio-liva wireless.js[728]: trying... Mar 16 14:36:04 volumio-liva sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 14:36:04 volumio-liva sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:04 volumio-liva sudo[1019]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:04 volumio-liva volumio-time-update[767]: volumio-time-update-util: Fetching time from Volumio... Mar 16 14:36:04 volumio-liva volumio-time-update[767]: volumio-time-update-util: Date not found in response Mar 16 14:36:04 volumio-liva volumio-time-update[767]: volumio-time-update-util: Retrying in 5 seconds... Mar 16 14:36:05 volumio-liva wpa_supplicant[1011]: wlan0: Trying to associate with SSID '(.)(.)' Mar 16 14:36:05 volumio-liva wireless.js[728]: trying... Mar 16 14:36:05 volumio-liva sudo[1027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 14:36:05 volumio-liva sudo[1027]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:05 volumio-liva sudo[1027]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:05 volumio-liva wpa_supplicant[1011]: wlan0: Associated with d4:5d:64:4b:79:e4 Mar 16 14:36:05 volumio-liva wpa_supplicant[1011]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 16 14:36:05 volumio-liva wpa_supplicant[1011]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Mar 16 14:36:05 volumio-liva wpa_supplicant[1011]: wlan0: WPA: Key negotiation completed with d4:5d:64:4b:79:e4 [PTK=CCMP GTK=CCMP] Mar 16 14:36:05 volumio-liva wpa_supplicant[1011]: wlan0: CTRL-EVENT-CONNECTED - Connection to d4:5d:64:4b:79:e4 completed [id=0 id_str=] Mar 16 14:36:05 volumio-liva dhcpcd[826]: wlan0: carrier acquired Mar 16 14:36:05 volumio-liva dhcpcd[826]: wlan0: IAID de:ba:62:fd Mar 16 14:36:05 volumio-liva dhcpcd[826]: wlan0: adding address fe80::2b1f:18b4:3644:959d Mar 16 14:36:05 volumio-liva dhcpcd[826]: ipv6_addaddr1: Permission denied Mar 16 14:36:06 volumio-liva dhcpcd[826]: wlan0: soliciting an IPv6 router Mar 16 14:36:06 volumio-liva wireless.js[728]: trying... Mar 16 14:36:06 volumio-liva sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 14:36:06 volumio-liva sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:06 volumio-liva sudo[1031]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:06 volumio-liva dhcpcd[826]: wlan0: soliciting a DHCP lease Mar 16 14:36:07 volumio-liva volumio-remote-updater[755]: [2025-03-16 14:36:07] [info] asio async_connect error: system:111 (Connection refused) Mar 16 14:36:07 volumio-liva volumio-remote-updater[755]: [2025-03-16 14:36:07] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 16 14:36:07 volumio-liva volumio-remote-updater[755]: [2025-03-16 14:36:07] [error] handle_connect error: Underlying Transport Error Mar 16 14:36:07 volumio-liva wireless.js[728]: trying... Mar 16 14:36:07 volumio-liva sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 14:36:07 volumio-liva sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:07 volumio-liva sudo[1034]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:08 volumio-liva wireless.js[728]: trying... Mar 16 14:36:08 volumio-liva sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 14:36:08 volumio-liva sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:08 volumio-liva sudo[1040]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:09 volumio-liva wireless.js[728]: trying... Mar 16 14:36:09 volumio-liva sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 14:36:09 volumio-liva sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:09 volumio-liva sudo[1043]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:09 volumio-liva volumio-time-update[767]: volumio-time-update-util: Fetching time from Volumio... Mar 16 14:36:09 volumio-liva volumio-time-update[767]: volumio-time-update-util: Date not found in response Mar 16 14:36:09 volumio-liva volumio-time-update[767]: volumio-time-update-util: Retrying in 5 seconds... Mar 16 14:36:10 volumio-liva wireless.js[728]: trying... Mar 16 14:36:10 volumio-liva sudo[1051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 14:36:10 volumio-liva sudo[1051]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:10 volumio-liva sudo[1051]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:11 volumio-liva wireless.js[728]: trying... Mar 16 14:36:11 volumio-liva sudo[1054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 14:36:11 volumio-liva sudo[1054]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:11 volumio-liva sudo[1054]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:11 volumio-liva dhcpcd[826]: wlan0: probing for an IPv4LL address Mar 16 14:36:12 volumio-liva wireless.js[728]: trying... Mar 16 14:36:12 volumio-liva sudo[1057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 14:36:12 volumio-liva sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:12 volumio-liva sudo[1057]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:13 volumio-liva wireless.js[728]: trying... Mar 16 14:36:13 volumio-liva sudo[1060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 14:36:13 volumio-liva sudo[1060]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:13 volumio-liva sudo[1060]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:14 volumio-liva wireless.js[728]: trying... Mar 16 14:36:14 volumio-liva sudo[1063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 14:36:14 volumio-liva sudo[1063]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:14 volumio-liva sudo[1063]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:14 volumio-liva volumio-time-update[767]: volumio-time-update-util: Fetching time from Volumio... Mar 16 14:36:14 volumio-liva volumio-time-update[767]: volumio-time-update-util: Date not found in response Mar 16 14:36:14 volumio-liva volumio-time-update[767]: volumio-time-update-util: Retrying in 5 seconds... Mar 16 14:36:14 volumio-liva dhcpcd[826]: wlan0: offered 192.168.50.71 from 192.168.50.1 Mar 16 14:36:15 volumio-liva dhcpcd[826]: wlan0: probing address 192.168.50.71/24 Mar 16 14:36:15 volumio-liva wireless.js[728]: trying... Mar 16 14:36:15 volumio-liva sudo[1071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 14:36:15 volumio-liva sudo[1071]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:15 volumio-liva sudo[1071]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:16 volumio-liva wireless.js[728]: trying... Mar 16 14:36:16 volumio-liva sudo[1074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 14:36:16 volumio-liva sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:16 volumio-liva sudo[1074]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:16 volumio-liva dhcpcd[826]: wlan0: using IPv4LL address 169.254.156.184 Mar 16 14:36:16 volumio-liva avahi-daemon[754]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.156.184. Mar 16 14:36:16 volumio-liva avahi-daemon[754]: New relevant interface wlan0.IPv4 for mDNS. Mar 16 14:36:16 volumio-liva avahi-daemon[754]: Registering new address record for 169.254.156.184 on wlan0.IPv4. Mar 16 14:36:16 volumio-liva dhcpcd[826]: wlan0: adding route to 169.254.0.0/16 Mar 16 14:36:16 volumio-liva dhcpcd[826]: wlan0: adding default route Mar 16 14:36:17 volumio-liva volumio-remote-updater[755]: [2025-03-16 14:36:17] [info] asio async_connect error: system:111 (Connection refused) Mar 16 14:36:17 volumio-liva volumio-remote-updater[755]: [2025-03-16 14:36:17] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 16 14:36:17 volumio-liva volumio-remote-updater[755]: [2025-03-16 14:36:17] [error] handle_connect error: Underlying Transport Error Mar 16 14:36:17 volumio-liva wireless.js[728]: trying... Mar 16 14:36:17 volumio-liva sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 14:36:17 volumio-liva sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:17 volumio-liva sudo[1086]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:17 volumio-liva wireless.js[728]: Connected to: ----(.)(.) Mar 16 14:36:17 volumio-liva wireless.js[728]: ---- Mar 16 14:36:17 volumio-liva sudo[1089]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 16 14:36:17 volumio-liva sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:17 volumio-liva sudo[1089]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:17 volumio-liva wireless.js[728]: ... joined AP, wlan0 IPv4 is 169.254.156.184, ipV6 is undefined Mar 16 14:36:17 volumio-liva wireless.js[728]: It's done! AP Mar 16 14:36:17 volumio-liva systemd[1]: Started Wireless Services. Mar 16 14:36:17 volumio-liva systemd[1]: Started Volumio Backend Module. Mar 16 14:36:17 volumio-liva systemd[1]: Started Volumio Cpu Tweaker. Mar 16 14:36:17 volumio-liva systemd[1]: Started Intel SST and HDA soundcard init service. Mar 16 14:36:17 volumio-liva volumio-cpu-tweak[1095]: Setting RT Priority for mpd Mar 16 14:36:17 volumio-liva volumio-cpu-tweak[1095]: Not enough cores to set MPD affinity Mar 16 14:36:17 volumio-liva volumio-cpu-tweak[1095]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 16 14:36:17 volumio-liva systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 16 14:36:18 volumio-liva ntpd[935]: Listen normally on 3 wlan0 169.254.156.184:123 Mar 16 14:36:18 volumio-liva ntpd[935]: new interface(s) found: waking up resolver Mar 16 14:36:18 volumio-liva systemd[1]: soundcard-init.service: Succeeded. Mar 16 14:36:19 volumio-liva volumio[1094]: info: ------------------------------------------- Mar 16 14:36:19 volumio-liva volumio[1094]: info: ----- Volumio3 ---- Mar 16 14:36:19 volumio-liva volumio[1094]: info: ------------------------------------------- Mar 16 14:36:19 volumio-liva volumio[1094]: info: ----- System startup ---- Mar 16 14:36:19 volumio-liva volumio[1094]: info: ------------------------------------------- Mar 16 14:36:19 volumio-liva volumio-time-update[767]: volumio-time-update-util: Fetching time from Volumio... Mar 16 14:36:20 volumio-liva volumio[1094]: info: MYVOLUMIO Environment detected Mar 16 14:36:20 volumio-liva volumio[1094]: info: Plugin folders cleanup Mar 16 14:36:20 volumio-liva volumio[1094]: info: Scanning into folder /volumio/app/plugins/ Mar 16 14:36:20 volumio-liva volumio[1094]: info: Scanning category audio_interface Mar 16 14:36:20 volumio-liva volumio[1094]: info: Scanning category miscellanea Mar 16 14:36:20 volumio-liva volumio[1094]: info: Scanning category music_service Mar 16 14:36:20 volumio-liva volumio[1094]: info: Scanning category plugins.json Mar 16 14:36:20 volumio-liva volumio[1094]: info: Scanning category system_controller Mar 16 14:36:20 volumio-liva volumio[1094]: info: Scanning category user_interface Mar 16 14:36:20 volumio-liva volumio[1094]: info: Scanning into folder /data/plugins/ Mar 16 14:36:20 volumio-liva volumio[1094]: info: Scanning category music_service Mar 16 14:36:20 volumio-liva volumio[1094]: info: Scanning category system_controller Mar 16 14:36:20 volumio-liva volumio[1094]: info: Scanning category user_interface Mar 16 14:36:20 volumio-liva volumio[1094]: info: Plugin folders cleanup completed Mar 16 14:36:20 volumio-liva volumio[1094]: info: ------------------------------------------- Mar 16 14:36:20 volumio-liva volumio[1094]: info: ----- Core plugins startup ---- Mar 16 14:36:20 volumio-liva volumio[1094]: info: ------------------------------------------- Mar 16 14:36:20 volumio-liva volumio[1094]: info: Loading plugins from folder /volumio/app/plugins/ Mar 16 14:36:20 volumio-liva volumio[1094]: info: Adding plugin upnp to MyMusic Plugins Mar 16 14:36:20 volumio-liva volumio[1094]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 16 14:36:20 volumio-liva volumio[1094]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 16 14:36:20 volumio-liva volumio[1094]: info: Loading plugins from folder /data/plugins/ Mar 16 14:36:20 volumio-liva volumio[1094]: info: Loading plugin "system"... Mar 16 14:36:20 volumio-liva volumio[1094]: info: Loading plugin "appearance"... Mar 16 14:36:20 volumio-liva dhcpcd[826]: wlan0: leased 192.168.50.71 for 86400 seconds Mar 16 14:36:20 volumio-liva avahi-daemon[754]: Registering new address record for 192.168.50.71 on wlan0.IPv4. Mar 16 14:36:20 volumio-liva dhcpcd[826]: wlan0: adding route to 192.168.50.0/24 Mar 16 14:36:20 volumio-liva dhcpcd[826]: wlan0: changing default route via 192.168.50.1 Mar 16 14:36:20 volumio-liva ntpd[935]: ntpd exiting on signal 15 (Terminated) Mar 16 14:36:20 volumio-liva systemd[1]: Stopping Network Time Service... Mar 16 14:36:20 volumio-liva systemd[1]: ntp.service: Succeeded. Mar 16 14:36:20 volumio-liva systemd[1]: Stopped Network Time Service. Mar 16 14:36:20 volumio-liva systemd[1]: Starting Network Time Service... Mar 16 14:36:21 volumio-liva ntpd[1264]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 16 14:36:21 volumio-liva ntpd[1264]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 16 14:36:21 volumio-liva systemd[1]: Started Network Time Service. Mar 16 14:36:21 volumio-liva ntpd[1270]: proto: precision = 0.192 usec (-22) Mar 16 14:36:21 volumio-liva avahi-daemon[754]: Withdrawing address record for 169.254.156.184 on wlan0. Mar 16 14:36:21 volumio-liva avahi-daemon[754]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.156.184. Mar 16 14:36:21 volumio-liva dhcpcd[826]: wlan0: deleting route to 169.254.0.0/16 Mar 16 14:36:21 volumio-liva avahi-daemon[754]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.50.71. Mar 16 14:36:21 volumio-liva ntpd[1270]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 16 14:36:21 volumio-liva ntpd[1270]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 16 14:36:21 volumio-liva ntpd[1270]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 810 days ago Mar 16 14:36:21 volumio-liva ntpd[1270]: Listen and drop on 0 v6wildcard [::]:123 Mar 16 14:36:21 volumio-liva ntpd[1270]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 16 14:36:21 volumio-liva ntpd[1270]: Listen normally on 2 lo 127.0.0.1:123 Mar 16 14:36:21 volumio-liva ntpd[1270]: Listen normally on 3 wlan0 192.168.50.71:123 Mar 16 14:36:21 volumio-liva ntpd[1270]: Listening on routing socket on fd #20 for interface updates Mar 16 14:36:21 volumio-liva ntpd[1270]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 16 14:36:21 volumio-liva ntpd[1270]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 16 14:36:21 volumio-liva nmbd[907]: [2025/03/16 14:36:21.042672, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 16 14:36:21 volumio-liva nmbd[907]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 16 14:36:21 volumio-liva systemd[1]: Started Samba NMB Daemon. Mar 16 14:36:21 volumio-liva systemd[1]: Starting Samba Winbind Daemon... Mar 16 14:36:21 volumio-liva winbindd[1275]: [2025/03/16 14:36:21.306886, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 16 14:36:21 volumio-liva winbindd[1275]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 16 14:36:21 volumio-liva systemd[1]: Started Samba Winbind Daemon. Mar 16 14:36:21 volumio-liva winbindd[1275]: [2025/03/16 14:36:21.328025, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 16 14:36:21 volumio-liva winbindd[1275]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 16 14:36:21 volumio-liva systemd[1]: Starting Samba SMB Daemon... Mar 16 14:36:21 volumio-liva smbd[1292]: [2025/03/16 14:36:21.774552, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 16 14:36:21 volumio-liva systemd[1]: Started Samba SMB Daemon. Mar 16 14:36:21 volumio-liva smbd[1292]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 16 14:36:21 volumio-liva volumio[1094]: info: Loading plugin "network"... Mar 16 14:36:21 volumio-liva volumio[1094]: info: Refreshing Cached IP Addresses Mar 16 14:36:21 volumio-liva sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 16 14:36:21 volumio-liva sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:21 volumio-liva sudo[1301]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:21 volumio-liva volumio[1094]: info: Loading plugin "services"... Mar 16 14:36:21 volumio-liva volumio[1094]: info: Loading plugin "alsa_controller"... Mar 16 14:36:21 volumio-liva sudo[1303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 16 14:36:21 volumio-liva sudo[1311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 16 14:36:21 volumio-liva sudo[1311]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:21 volumio-liva sudo[1303]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:21 volumio-liva sudo[1303]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:22 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 14:36:22 volumio-liva volumio[1094]: info: Loading plugin "wizard"... Mar 16 14:36:22 volumio-liva volumio[1094]: info: Loading plugin "networkfs"... Mar 16 14:36:22 volumio-liva volumio[1094]: info: Starting Udev Watcher for removable devices Mar 16 14:36:22 volumio-liva sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Music,password=QkPK+myDyxRwA6u,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.15/Music /mnt/NAS/happyshowNAS_Local Mar 16 14:36:22 volumio-liva sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:22 volumio-liva volumio[1094]: info: Ignoring mount for partition: boot Mar 16 14:36:22 volumio-liva volumio[1094]: info: Ignoring mount for partition: volumio Mar 16 14:36:22 volumio-liva volumio[1094]: info: Ignoring mount for partition: volumio_data Mar 16 14:36:22 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 16 14:36:22 volumio-liva volumio[1094]: info: Loading plugin "volumio_command_line_client"... Mar 16 14:36:22 volumio-liva volumio[1094]: info: Loading plugin "upnp"... Mar 16 14:36:22 volumio-liva volumio[1094]: info: [1742150182175] Starting Upmpd Daemon Mar 16 14:36:22 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 16 14:36:22 volumio-liva volumio[1094]: info: Loading plugin "my_music"... Mar 16 14:36:22 volumio-liva volumio[1094]: info: Loading plugin "mpd"... Mar 16 14:36:22 volumio-liva kernel: FS-Cache: Loaded Mar 16 14:36:22 volumio-liva kernel: Key type dns_resolver registered Mar 16 14:36:22 volumio-liva kernel: Key type cifs.spnego registered Mar 16 14:36:22 volumio-liva kernel: Key type cifs.idmap registered Mar 16 14:36:22 volumio-liva 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. Mar 16 14:36:22 volumio-liva kernel: CIFS: Attempting to mount //192.168.50.15/Music Mar 16 14:36:22 volumio-liva volumio[1094]: info: Plugin upnp_browser is not enabled Mar 16 14:36:22 volumio-liva volumio[1094]: info: Loading plugin "alarm-clock"... Mar 16 14:36:22 volumio-liva systemd[1]: systemd-fsckd.service: Succeeded. Mar 16 14:36:22 volumio-liva volumio[1094]: info: Loading plugin "airplay_emulation"... Mar 16 14:36:22 volumio-liva volumio[1094]: info: Starting Shairport Sync Mar 16 14:36:22 volumio-liva volumio[1094]: info: Loading plugin "last_100"... Mar 16 14:36:22 volumio-liva volumio[1094]: info: Loading plugin "webradio"... Mar 16 14:36:22 volumio-liva volumio[1094]: info: Loading plugin "i2s_dacs"... Mar 16 14:36:22 volumio-liva volumio[1094]: info: I2S DAC not set, start Auto-detection Mar 16 14:36:22 volumio-liva volumio[1094]: info: Loading plugin "volumiodiscovery"... Mar 16 14:36:22 volumio-liva volumio[1094]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 16 14:36:22 volumio-liva volumio[1094]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 16 14:36:22 volumio-liva volumio[1094]: *** WARNING *** For more information see Mar 16 14:36:22 volumio-liva node[1094]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 16 14:36:22 volumio-liva volumio[1094]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 16 14:36:22 volumio-liva volumio[1094]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 16 14:36:22 volumio-liva volumio[1094]: *** WARNING *** For more information see Mar 16 14:36:22 volumio-liva node[1094]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 16 14:36:22 volumio-liva node[1094]: *** WARNING *** For more information see Mar 16 14:36:22 volumio-liva node[1094]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 16 14:36:22 volumio-liva node[1094]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 16 14:36:22 volumio-liva node[1094]: *** WARNING *** For more information see Mar 16 14:36:22 volumio-liva volumio[1094]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 16 14:36:22 volumio-liva volumio[1094]: info: Discovery: Started advertising with name: Volumio-Liva Mar 16 14:36:22 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 16 14:36:22 volumio-liva volumio[1094]: info: Loading plugin "spop"... Mar 16 14:36:24 volumio-liva volumio[1094]: info: Loading plugin "ytcr"... Mar 16 14:36:24 volumio-liva sh[654]: timed out Mar 16 14:36:24 volumio-liva sh[654]: dhcpcd exited Mar 16 14:36:24 volumio-liva dhcpcd[705]: timed out Mar 16 14:36:24 volumio-liva dhcpcd[705]: dhcpcd exited Mar 16 14:36:24 volumio-liva sh[654]: ifup: failed to bring up eth0 Mar 16 14:36:24 volumio-liva systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 16 14:36:24 volumio-liva systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 16 14:36:25 volumio-liva systemd[1]: systemd-hostnamed.service: Succeeded. Mar 16 14:36:27 volumio-liva volumio-remote-updater[755]: [2025-03-16 14:36:27] [connect] Successful connection Mar 16 14:36:27 volumio-liva volumio[1094]: info: Loading plugin "ytmusic"... Mar 16 14:36:27 volumio-liva sudo[1311]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:28 volumio-liva sudo[1329]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:28 volumio-liva kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 16 14:36:28 volumio-liva kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Mar 16 14:36:29 volumio-liva volumio[1094]: info: Loading plugin "outputs"... Mar 16 14:36:29 volumio-liva volumio[1094]: info: Loading plugin "albumart"... Mar 16 14:36:29 volumio-liva volumio[1094]: info: Plugin example_plugin is not enabled Mar 16 14:36:29 volumio-liva volumio[1094]: info: Loading plugin "inputs"... Mar 16 14:36:29 volumio-liva volumio[1094]: info: Loading plugin "updater_comm"... Mar 16 14:36:30 volumio-liva volumio[1094]: Forking 1 albumart workers Mar 16 14:36:30 volumio-liva volumio[1094]: info: Plugin mpdemulation is not enabled Mar 16 14:36:30 volumio-liva volumio[1094]: info: Loading plugin "rest_api"... Mar 16 14:36:30 volumio-liva volumio[1094]: info: Loading plugin "websocket"... Mar 16 14:36:30 volumio-liva volumio[1094]: info: Starting Socket.io Server version 2.3.0 Mar 16 14:36:30 volumio-liva volumio[1094]: info: Loading plugin "radio_paradise"... Mar 16 14:36:30 volumio-liva volumio[1094]: info: Applying required configuration parameters for plugin radio_paradise Mar 16 14:36:30 volumio-liva volumio[1094]: info: [1742150190529] [RadioParadise] API delay: 5 Mar 16 14:36:30 volumio-liva volumio[1094]: info: Loading plugin "swarmstation"... Mar 16 14:36:30 volumio-liva volumio[1094]: Starting albumart workers Mar 16 14:36:30 volumio-liva volumio[1094]: info: Applying required configuration parameters for plugin swarmstation Mar 16 14:36:30 volumio-liva volumio[1094]: info: [1742150190903] [Swarmstation] API delay: 5 Mar 16 14:36:30 volumio-liva volumio[1094]: info: Loading plugin "backup_restore"... Mar 16 14:36:31 volumio-liva volumio[1094]: info: Applying required configuration parameters for plugin backup_restore Mar 16 14:36:31 volumio-liva volumio[1094]: info: Loading plugin "Systeminfo"... Mar 16 14:36:31 volumio-liva volumio[1094]: info: Loading plugin "lastfm"... Mar 16 14:36:31 volumio-liva volumio[1094]: info: Loading i18n strings for locale en Mar 16 14:36:31 volumio-liva volumio[1094]: Updating browse sources language Mar 16 14:36:31 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::initPlayerControls Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 14:36:32 volumio-liva volumio[1094]: Express server listening on port 3000 Mar 16 14:36:32 volumio-liva volumio[1094]: [Metrics] WebUI: 13s 57.44ms Mar 16 14:36:32 volumio-liva volumio[1094]: info: Setting Device type: x86 Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreStateMachine::resetVolumioState Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreStateMachine::getcurrentVolume Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::volumioRetrievevolume Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 14:36:32 volumio-liva volumio[1094]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 16 14:36:32 volumio-liva volumio[1094]: info: Completed loading Core Plugins Mar 16 14:36:32 volumio-liva volumio[1094]: info: Preparing to generate the ALSA configuration file Mar 16 14:36:32 volumio-liva volumio[1094]: info: Volumio Network Manager: Network status updated: 2 Mar 16 14:36:32 volumio-liva volumio[1094]: info: Cannot mount NAS happyshowNAS_Local at system boot, trial number 1 ,retrying in 5 seconds Mar 16 14:36:32 volumio-liva volumio[1094]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 16 14:36:32 volumio-liva sudo[1388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 16 14:36:32 volumio-liva sudo[1388]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:32 volumio-liva sudo[1388]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:32 volumio-liva sudo[1391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 16 14:36:32 volumio-liva sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:32 volumio-liva sudo[1391]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:32 volumio-liva volumio[1094]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 16 14:36:32 volumio-liva volumio[1094]: info: Reloading queue from file Mar 16 14:36:32 volumio-liva volumio[1094]: info: VolumeController:: Volume=96 Mute =false Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreStateMachine::pushState Mar 16 14:36:32 volumio-liva volumio[1094]: info: CorePlayQueue::getTrack 0 Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::volumioPushState Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreStateMachine::updateTrackBlock Mar 16 14:36:32 volumio-liva volumio[1094]: info: CorePlayQueue::getTrackBlock Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::volumioRetrievevolume Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreStateMachine::setRepeat null single undefined Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreStateMachine::pushState Mar 16 14:36:32 volumio-liva volumio[1094]: info: CorePlayQueue::getTrack 0 Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::volumioPushState Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreStateMachine::setRandom null Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreStateMachine::pushState Mar 16 14:36:32 volumio-liva volumio[1094]: info: CorePlayQueue::getTrack 0 Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::volumioPushState Mar 16 14:36:32 volumio-liva volumio[1094]: info: Asound.conf file written Mar 16 14:36:32 volumio-liva sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 16 14:36:32 volumio-liva sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:32 volumio-liva sudo[1402]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:32 volumio-liva volumio[1094]: info: Output device has changed, restarting MPD Mar 16 14:36:32 volumio-liva sudo[1407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 16 14:36:32 volumio-liva sudo[1407]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:32 volumio-liva volumio[1094]: info: Output device has changed, restarting Shairport Sync Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 14:36:32 volumio-liva sudo[1407]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:32 volumio-liva sudo[1410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 16 14:36:32 volumio-liva sudo[1410]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:32 volumio-liva systemd[1]: Stopping Music Player Daemon... Mar 16 14:36:32 volumio-liva volumio[1094]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 16 14:36:32 volumio-liva volumio[1094]: info: ___________ START PLUGINS ___________ Mar 16 14:36:32 volumio-liva volumio[1094]: info: ControllerMpd::onStart: Initializing MPD Mar 16 14:36:32 volumio-liva volumio[1094]: info: Creating MPD Configuration file Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 14:36:32 volumio-liva sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 16 14:36:32 volumio-liva sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:32 volumio-liva sudo[1416]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:32 volumio-liva volumio[1094]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 14:36:32 volumio-liva systemd[1]: mpd.service: Succeeded. Mar 16 14:36:32 volumio-liva sudo[1418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 16 14:36:32 volumio-liva sudo[1418]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:32 volumio-liva systemd[1]: Stopped Music Player Daemon. Mar 16 14:36:32 volumio-liva systemd[1]: Starting Music Player Daemon... Mar 16 14:36:32 volumio-liva systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 16 14:36:32 volumio-liva systemd[1]: mpd.service: Succeeded. Mar 16 14:36:32 volumio-liva systemd[1]: Stopped Music Player Daemon. Mar 16 14:36:32 volumio-liva volumio[1094]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 16 14:36:32 volumio-liva systemd[1]: Starting Music Player Daemon... Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 16 14:36:32 volumio-liva volumio[1094]: info: [1742150192826] CoreMusicLibrary::Adding element Last_100 Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 16 14:36:32 volumio-liva volumio[1094]: info: [1742150192830] CoreMusicLibrary::Adding element Webradio Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 14:36:32 volumio-liva volumio[1094]: info: Initializing BBC Radios Mar 16 14:36:32 volumio-liva sudo[1428]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 16 14:36:32 volumio-liva sudo[1428]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:32 volumio-liva sudo[1428]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 14:36:32 volumio-liva volumio[1094]: info: Creating Spotify config file Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 16 14:36:32 volumio-liva volumio[1094]: info: [1742150192991] CoreMusicLibrary::Adding element YouTube Music Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 14:36:32 volumio-liva volumio[1094]: Cannot find translation for source YouTube Music Mar 16 14:36:32 volumio-liva volumio[1094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 16 14:36:33 volumio-liva volumio[1094]: info: [1742150192998] CoreMusicLibrary::Adding element Radio Paradise Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 14:36:33 volumio-liva volumio[1094]: Cannot find translation for source YouTube Music Mar 16 14:36:33 volumio-liva volumio[1094]: Cannot find translation for source Radio Paradise Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 16 14:36:33 volumio-liva volumio[1094]: info: [1742150193005] CoreMusicLibrary::Adding element SWARM Station Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 14:36:33 volumio-liva volumio[1094]: Cannot find translation for source YouTube Music Mar 16 14:36:33 volumio-liva volumio[1094]: Cannot find translation for source Radio Paradise Mar 16 14:36:33 volumio-liva volumio[1094]: Cannot find translation for source SWARM Station Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 16 14:36:33 volumio-liva volumio[1094]: info: [1742150193010] CoreMusicLibrary::Adding element LastFM Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 14:36:33 volumio-liva volumio[1094]: Cannot find translation for source YouTube Music Mar 16 14:36:33 volumio-liva volumio[1094]: Cannot find translation for source Radio Paradise Mar 16 14:36:33 volumio-liva volumio[1094]: Cannot find translation for source SWARM Station Mar 16 14:36:33 volumio-liva volumio[1094]: Cannot find translation for source LastFM Mar 16 14:36:33 volumio-liva volumio[1094]: info: [LastFM] scrobbler initiated! Mar 16 14:36:33 volumio-liva volumio[1094]: info: [LastFM] extended logging: false Mar 16 14:36:33 volumio-liva volumio[1094]: info: [LastFM] try scrobble stream/radio plays: true Mar 16 14:36:33 volumio-liva volumio[1094]: info: [LastFM] Left init routine Mar 16 14:36:33 volumio-liva volumio[1094]: info: [LastFM] Socket already connected: true Mar 16 14:36:33 volumio-liva volumio[1094]: info: Volumio Calling Home Mar 16 14:36:33 volumio-liva sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 16 14:36:33 volumio-liva sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:33 volumio-liva systemd[1]: Started UPnP Renderer front-end to MPD. Mar 16 14:36:33 volumio-liva sudo[1445]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:33 volumio-liva volumio[1094]: info: MPD Permissions set Mar 16 14:36:33 volumio-liva volumio[1094]: info: MPD Permissions set Mar 16 14:36:33 volumio-liva volumio[1094]: info: VolumeController:: Volume=96 Mute =false Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::volumioGetState Mar 16 14:36:33 volumio-liva volumio[1094]: info: CorePlayQueue::getTrack 0 Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreStateMachine::pushState Mar 16 14:36:33 volumio-liva volumio[1094]: info: CorePlayQueue::getTrack 0 Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::volumioPushState Mar 16 14:36:33 volumio-liva volumio[1094]: info: Upmpdcli Daemon Started Mar 16 14:36:33 volumio-liva volumio[1094]: info: Spotify config file written Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 16 14:36:33 volumio-liva sudo[1449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 16 14:36:33 volumio-liva sudo[1449]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:33 volumio-liva systemd[1]: Started go-librespot Daemon. Mar 16 14:36:33 volumio-liva go-librespot[1457]: go-librespot daemon starting... Mar 16 14:36:33 volumio-liva sudo[1449]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 16 14:36:33 volumio-liva volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:33 volumio-liva volumio[1094]: info: No need to fix Spotify hosts Mar 16 14:36:33 volumio-liva volumio[1094]: info: Starting Shairport Sync Mar 16 14:36:33 volumio-liva volumio[1094]: info: Starting Shairport Sync Mar 16 14:36:33 volumio-liva volumio[1094]: info: Starting Shairport Sync Mar 16 14:36:33 volumio-liva sudo[1469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 14:36:33 volumio-liva sudo[1469]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:33 volumio-liva sudo[1472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 14:36:33 volumio-liva sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:33 volumio-liva systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 16 14:36:33 volumio-liva systemd[1]: shairport-sync.service: Succeeded. Mar 16 14:36:33 volumio-liva systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 16 14:36:33 volumio-liva sudo[1476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 14:36:33 volumio-liva sudo[1476]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:33 volumio-liva systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 16 14:36:33 volumio-liva sudo[1469]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:33 volumio-liva go-librespot[1457]: time="2025-03-16T14:36:33-04:00" level=info msg="running go-librespot 0.2.0" Mar 16 14:36:33 volumio-liva go-librespot[1457]: time="2025-03-16T14:36:33-04:00" level=debug msg="app state loaded" Mar 16 14:36:33 volumio-liva go-librespot[1457]: time="2025-03-16T14:36:33-04:00" level=debug msg="stored credentials not found" Mar 16 14:36:33 volumio-liva go-librespot[1457]: time="2025-03-16T14:36:33-04:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 16 14:36:33 volumio-liva volumio[1094]: info: Shairport-Sync Started Mar 16 14:36:33 volumio-liva sudo[1472]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:33 volumio-liva systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 16 14:36:33 volumio-liva systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 16 14:36:33 volumio-liva systemd[1]: shairport-sync.service: Succeeded. Mar 16 14:36:33 volumio-liva systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 16 14:36:33 volumio-liva volumio[1094]: Error adding Membership: Error: addMembership EINVAL Mar 16 14:36:33 volumio-liva volumio[1094]: info: Shairport-Sync Started Mar 16 14:36:33 volumio-liva systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 16 14:36:33 volumio-liva sudo[1476]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:33 volumio-liva volumio[1094]: info: Shairport-Sync Started Mar 16 14:36:33 volumio-liva mpd[1440]: Mar 16 14:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 16 14:36:34 volumio-liva systemd[1]: Started Music Player Daemon. Mar 16 14:36:34 volumio-liva sudo[1418]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:34 volumio-liva sudo[1410]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:34 volumio-liva volumio[1094]: error: MPD error: The expression evaluated to a falsy value: Mar 16 14:36:34 volumio-liva volumio[1094]: assert.ok(self.idling) Mar 16 14:36:34 volumio-liva volumio[1094]: error: The expression evaluated to a falsy value: Mar 16 14:36:34 volumio-liva volumio[1094]: assert.ok(self.idling) Mar 16 14:36:34 volumio-liva volumio[1094]: info: MPD running with PID1440 Mar 16 14:36:34 volumio-liva volumio[1094]: ,establishing connection Mar 16 14:36:34 volumio-liva volumio[1094]: error: updateQueue error: null Mar 16 14:36:34 volumio-liva volumio[1094]: error: updateQueue error: null Mar 16 14:36:34 volumio-liva volumio[1447]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 16 14:36:34 volumio-liva volumio-time-update[767]: volumio-time-update-util: Date not found in response Mar 16 14:36:34 volumio-liva volumio-time-update[767]: volumio-time-update-util: Retrying in 5 seconds... Mar 16 14:36:35 volumio-liva volumio[1447]: ...........................++++ Mar 16 14:36:36 volumio-liva volumio[1094]: info: go-librespot daemon successfully initialized Mar 16 14:36:37 volumio-liva sudo[1503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Music,password=QkPK+myDyxRwA6u,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.15/Music /mnt/NAS/happyshowNAS_Local Mar 16 14:36:37 volumio-liva sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:37 volumio-liva kernel: CIFS: Attempting to mount //192.168.50.15/Music Mar 16 14:36:39 volumio-liva volumio[1094]: info: Initializing connection to go-librespot Websocket Mar 16 14:36:39 volumio-liva ntpd[1270]: Soliciting pool server 74.50.48.56 Mar 16 14:36:39 volumio-liva volumio-time-update[767]: volumio-time-update-util: Fetching time from Volumio... Mar 16 14:36:40 volumio-liva dhcpcd[826]: wlan0: carrier lost Mar 16 14:36:40 volumio-liva wpa_supplicant[1011]: wlan0: CTRL-EVENT-DISCONNECTED bssid=d4:5d:64:4b:79:e4 reason=1 Mar 16 14:36:40 volumio-liva wpa_supplicant[1011]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Mar 16 14:36:40 volumio-liva wpa_supplicant[1011]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Mar 16 14:36:40 volumio-liva avahi-daemon[754]: Withdrawing address record for 192.168.50.71 on wlan0. Mar 16 14:36:40 volumio-liva avahi-daemon[754]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.50.71. Mar 16 14:36:40 volumio-liva avahi-daemon[754]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 16 14:36:40 volumio-liva volumio[1094]: info: Discovery: A device disappeared from network Mar 16 14:36:40 volumio-liva volumio[1094]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Mar 16 14:36:40 volumio-liva dhcpcd[826]: wlan0: deleting route to 192.168.50.0/24 Mar 16 14:36:40 volumio-liva dhcpcd[826]: wlan0: deleting default route via 192.168.50.1 Mar 16 14:36:40 volumio-liva volumio[1094]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 16 14:36:40 volumio-liva wpa_supplicant[1011]: wlan0: Trying to associate with SSID '(.)(.)' Mar 16 14:36:40 volumio-liva wpa_supplicant[1011]: wlan0: Associated with d4:5d:64:4b:6f:54 Mar 16 14:36:40 volumio-liva wpa_supplicant[1011]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 16 14:36:40 volumio-liva wpa_supplicant[1011]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Mar 16 14:36:40 volumio-liva wpa_supplicant[1011]: wlan0: WPA: Key negotiation completed with d4:5d:64:4b:6f:54 [PTK=CCMP GTK=CCMP] Mar 16 14:36:40 volumio-liva wpa_supplicant[1011]: wlan0: CTRL-EVENT-CONNECTED - Connection to d4:5d:64:4b:6f:54 completed [id=0 id_str=] Mar 16 14:36:40 volumio-liva dhcpcd[826]: wlan0: carrier acquired Mar 16 14:36:40 volumio-liva dhcpcd[826]: wlan0: IAID de:ba:62:fd Mar 16 14:36:41 volumio-liva volumio[1447]: ...................................................................................................................................................................................................................................................++++ Mar 16 14:36:41 volumio-liva volumio[1447]: e is 65537 (0x010001) Mar 16 14:36:41 volumio-liva volumio[1447]: writing RSA key Mar 16 14:36:41 volumio-liva volumio[1094]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 14:36:41 volumio-liva volumio[1094]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Mar 16 14:36:41 volumio-liva volumio[1094]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Mar 16 14:36:41 volumio-liva volumio[1094]: errno: -3007, Mar 16 14:36:41 volumio-liva volumio[1094]: code: 'ENOTFOUND', Mar 16 14:36:41 volumio-liva volumio[1094]: syscall: 'getaddrinfo', Mar 16 14:36:41 volumio-liva volumio[1094]: hostname: 'ws.audioscrobbler.com' Mar 16 14:36:41 volumio-liva volumio[1094]: } Mar 16 14:36:41 volumio-liva volumio[1094]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 14:36:41 volumio-liva dhcpcd[826]: wlan0: soliciting an IPv6 router Mar 16 14:36:41 volumio-liva sudo[1552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-16 14:35 Mar 16 14:36:41 volumio-liva sudo[1552]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:41 volumio-liva sudo[1552]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:41 volumio-liva dhcpcd[826]: wlan0: rebinding lease of 192.168.50.71 Mar 16 14:36:41 volumio-liva go-librespot[1457]: time="2025-03-16T14:36:41-04:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 16 14:36:41 volumio-liva systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 16 14:36:41 volumio-liva systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 16 14:36:42 volumio-liva ntpd[1270]: Deleting interface #3 wlan0, 192.168.50.71#123, interface stats: received=0, sent=1, dropped=0, active_time=21 secs Mar 16 14:36:42 volumio-liva volumio-remote-updater[755]: [2025-03-16 14:36:42] [connect] Successful connection Mar 16 14:36:42 volumio-liva sudo[1503]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:42 volumio-liva kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 16 14:36:42 volumio-liva kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 16 14:36:44 volumio-liva ntpd[1270]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 16 14:36:44 volumio-liva ntpd[1270]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 16 14:36:44 volumio-liva ntpd[1270]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 16 14:36:44 volumio-liva volumio-time-update[767]: volumio-time-update-util: Date not found in response Mar 16 14:36:44 volumio-liva volumio-time-update[767]: volumio-time-update-util: Retrying in 5 seconds... Mar 16 14:36:44 volumio-liva systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 16 14:36:44 volumio-liva systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Mar 16 14:36:44 volumio-liva systemd[1]: Stopped go-librespot Daemon. Mar 16 14:36:44 volumio-liva systemd[1]: Started go-librespot Daemon. Mar 16 14:36:44 volumio-liva go-librespot[1574]: go-librespot daemon starting... Mar 16 14:36:44 volumio-liva go-librespot[1574]: time="2025-03-16T14:36:44-04:00" level=info msg="running go-librespot 0.2.0" Mar 16 14:36:44 volumio-liva go-librespot[1574]: time="2025-03-16T14:36:44-04:00" level=debug msg="app state loaded" Mar 16 14:36:44 volumio-liva go-librespot[1574]: time="2025-03-16T14:36:44-04:00" level=debug msg="stored credentials not found" Mar 16 14:36:45 volumio-liva go-librespot[1574]: time="2025-03-16T14:36:45-04:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 16 14:36:45 volumio-liva go-librespot[1574]: time="2025-03-16T14:36:45-04:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 16 14:36:45 volumio-liva systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 16 14:36:45 volumio-liva systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 16 14:36:45 volumio-liva systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 16 14:36:45 volumio-liva volumio-remote-updater[755]: [2025-03-16 14:36:45] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Mar 16 14:36:45 volumio-liva volumio-remote-updater[755]: [2025-03-16 14:36:45] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Mar 16 14:36:45 volumio-liva volumio-remote-updater[755]: [2025-03-16 14:36:45] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 16 14:36:45 volumio-liva systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 16 14:36:45 volumio-liva systemd[1]: Started dynamicswap service. Mar 16 14:36:45 volumio-liva systemd[1]: dynamicswap.service: Succeeded. Mar 16 14:36:45 volumio-liva systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 16 14:36:45 volumio-liva systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Mar 16 14:36:45 volumio-liva systemd[1]: Started dynamicswap service. Mar 16 14:36:45 volumio-liva systemd[1]: Stopped Volumio Backend Module. Mar 16 14:36:45 volumio-liva systemd[1]: Started Volumio Backend Module. Mar 16 14:36:45 volumio-liva systemd[1]: dynamicswap.service: Succeeded. Mar 16 14:36:46 volumio-liva dhcpcd[826]: wlan0: probing for an IPv4LL address Mar 16 14:36:46 volumio-liva dhcpcd[826]: wlan0: DHCP lease expired Mar 16 14:36:46 volumio-liva dhcpcd[826]: wlan0: soliciting a DHCP lease Mar 16 14:36:46 volumio-liva volumio[1587]: info: ------------------------------------------- Mar 16 14:36:46 volumio-liva volumio[1587]: info: ----- Volumio3 ---- Mar 16 14:36:46 volumio-liva volumio[1587]: info: ------------------------------------------- Mar 16 14:36:46 volumio-liva volumio[1587]: info: ----- System startup ---- Mar 16 14:36:46 volumio-liva volumio[1587]: info: ------------------------------------------- Mar 16 14:36:47 volumio-liva volumio[1587]: info: MYVOLUMIO Environment detected Mar 16 14:36:47 volumio-liva volumio[1587]: info: Plugin folders cleanup Mar 16 14:36:47 volumio-liva volumio[1587]: info: Scanning into folder /volumio/app/plugins/ Mar 16 14:36:47 volumio-liva volumio[1587]: info: Scanning category audio_interface Mar 16 14:36:47 volumio-liva volumio[1587]: info: Scanning category miscellanea Mar 16 14:36:47 volumio-liva volumio[1587]: info: Scanning category music_service Mar 16 14:36:47 volumio-liva volumio[1587]: info: Scanning category plugins.json Mar 16 14:36:47 volumio-liva volumio[1587]: info: Scanning category system_controller Mar 16 14:36:47 volumio-liva volumio[1587]: info: Scanning category user_interface Mar 16 14:36:47 volumio-liva volumio[1587]: info: Scanning into folder /data/plugins/ Mar 16 14:36:47 volumio-liva volumio[1587]: info: Scanning category music_service Mar 16 14:36:47 volumio-liva volumio[1587]: info: Scanning category system_controller Mar 16 14:36:47 volumio-liva volumio[1587]: info: Scanning category user_interface Mar 16 14:36:47 volumio-liva volumio[1587]: info: Plugin folders cleanup completed Mar 16 14:36:47 volumio-liva volumio[1587]: info: ------------------------------------------- Mar 16 14:36:47 volumio-liva volumio[1587]: info: ----- Core plugins startup ---- Mar 16 14:36:47 volumio-liva volumio[1587]: info: ------------------------------------------- Mar 16 14:36:47 volumio-liva volumio[1587]: info: Loading plugins from folder /volumio/app/plugins/ Mar 16 14:36:47 volumio-liva volumio[1587]: info: Adding plugin upnp to MyMusic Plugins Mar 16 14:36:47 volumio-liva volumio[1587]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 16 14:36:47 volumio-liva volumio[1587]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 16 14:36:47 volumio-liva volumio[1587]: info: Loading plugins from folder /data/plugins/ Mar 16 14:36:47 volumio-liva volumio[1587]: info: Loading plugin "system"... Mar 16 14:36:47 volumio-liva volumio[1587]: info: Loading plugin "appearance"... Mar 16 14:36:47 volumio-liva volumio[1587]: info: Loading plugin "network"... Mar 16 14:36:47 volumio-liva volumio[1587]: info: Refreshing Cached IP Addresses Mar 16 14:36:47 volumio-liva sudo[1620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 16 14:36:47 volumio-liva sudo[1620]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:47 volumio-liva sudo[1620]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:47 volumio-liva sudo[1622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 16 14:36:47 volumio-liva sudo[1622]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:47 volumio-liva sudo[1622]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:47 volumio-liva volumio[1587]: info: Loading plugin "services"... Mar 16 14:36:47 volumio-liva volumio[1587]: info: Loading plugin "alsa_controller"... Mar 16 14:36:47 volumio-liva sudo[1632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 16 14:36:47 volumio-liva sudo[1632]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:48 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 14:36:48 volumio-liva volumio[1587]: info: Loading plugin "wizard"... Mar 16 14:36:48 volumio-liva volumio[1587]: info: Loading plugin "networkfs"... Mar 16 14:36:48 volumio-liva volumio[1587]: info: Starting Udev Watcher for removable devices Mar 16 14:36:48 volumio-liva sudo[1648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Music,password=QkPK+myDyxRwA6u,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.15/Music /mnt/NAS/happyshowNAS_Local Mar 16 14:36:48 volumio-liva sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:48 volumio-liva volumio[1587]: info: Ignoring mount for partition: boot Mar 16 14:36:48 volumio-liva volumio[1587]: info: Ignoring mount for partition: volumio Mar 16 14:36:48 volumio-liva volumio[1587]: info: Ignoring mount for partition: volumio_data Mar 16 14:36:48 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 16 14:36:48 volumio-liva volumio[1587]: info: Loading plugin "volumio_command_line_client"... Mar 16 14:36:48 volumio-liva volumio[1587]: info: Loading plugin "upnp"... Mar 16 14:36:48 volumio-liva volumio[1587]: info: [1742150208067] Starting Upmpd Daemon Mar 16 14:36:48 volumio-liva kernel: CIFS: Attempting to mount //192.168.50.15/Music Mar 16 14:36:48 volumio-liva kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 16 14:36:48 volumio-liva kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 16 14:36:48 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 16 14:36:48 volumio-liva sudo[1648]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:48 volumio-liva volumio[1587]: info: Loading plugin "my_music"... Mar 16 14:36:48 volumio-liva volumio[1587]: info: Loading plugin "mpd"... Mar 16 14:36:48 volumio-liva systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 16 14:36:48 volumio-liva systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Mar 16 14:36:48 volumio-liva systemd[1]: Stopped go-librespot Daemon. Mar 16 14:36:48 volumio-liva systemd[1]: Started go-librespot Daemon. Mar 16 14:36:48 volumio-liva go-librespot[1652]: go-librespot daemon starting... Mar 16 14:36:48 volumio-liva go-librespot[1652]: time="2025-03-16T14:36:48-04:00" level=info msg="running go-librespot 0.2.0" Mar 16 14:36:48 volumio-liva go-librespot[1652]: time="2025-03-16T14:36:48-04:00" level=debug msg="app state loaded" Mar 16 14:36:48 volumio-liva go-librespot[1652]: time="2025-03-16T14:36:48-04:00" level=debug msg="stored credentials not found" Mar 16 14:36:48 volumio-liva go-librespot[1652]: time="2025-03-16T14:36:48-04:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 16 14:36:48 volumio-liva go-librespot[1652]: time="2025-03-16T14:36:48-04:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 16 14:36:48 volumio-liva systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 16 14:36:48 volumio-liva systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 16 14:36:48 volumio-liva volumio[1587]: info: Plugin upnp_browser is not enabled Mar 16 14:36:48 volumio-liva volumio[1587]: info: Loading plugin "alarm-clock"... Mar 16 14:36:48 volumio-liva volumio[1587]: info: Loading plugin "airplay_emulation"... Mar 16 14:36:48 volumio-liva volumio[1587]: info: Starting Shairport Sync Mar 16 14:36:48 volumio-liva volumio[1587]: info: Loading plugin "last_100"... Mar 16 14:36:48 volumio-liva volumio[1587]: info: Loading plugin "webradio"... Mar 16 14:36:48 volumio-liva volumio[1587]: info: Loading plugin "i2s_dacs"... Mar 16 14:36:48 volumio-liva volumio[1587]: info: I2S DAC not set, start Auto-detection Mar 16 14:36:48 volumio-liva volumio[1587]: info: Loading plugin "volumiodiscovery"... Mar 16 14:36:48 volumio-liva volumio[1587]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 16 14:36:48 volumio-liva volumio[1587]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 16 14:36:48 volumio-liva volumio[1587]: *** WARNING *** For more information see Mar 16 14:36:48 volumio-liva node[1587]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 16 14:36:48 volumio-liva volumio[1587]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 16 14:36:48 volumio-liva volumio[1587]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 16 14:36:48 volumio-liva volumio[1587]: *** WARNING *** For more information see Mar 16 14:36:48 volumio-liva node[1587]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 16 14:36:48 volumio-liva node[1587]: *** WARNING *** For more information see Mar 16 14:36:48 volumio-liva node[1587]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 16 14:36:48 volumio-liva node[1587]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 16 14:36:48 volumio-liva node[1587]: *** WARNING *** For more information see Mar 16 14:36:48 volumio-liva volumio[1587]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 16 14:36:48 volumio-liva volumio[1587]: info: Discovery: Started advertising with name: Volumio-Liva Mar 16 14:36:48 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 16 14:36:48 volumio-liva volumio[1587]: info: Loading plugin "spop"... Mar 16 14:36:49 volumio-liva volumio[1587]: info: Loading plugin "ytcr"... Mar 16 14:36:49 volumio-liva volumio-time-update[767]: volumio-time-update-util: Fetching time from Volumio... Mar 16 14:36:49 volumio-liva volumio-time-update[767]: volumio-time-update-util: Date not found in response Mar 16 14:36:49 volumio-liva volumio-time-update[767]: volumio-time-update-util: Retrying in 5 seconds... Mar 16 14:36:51 volumio-liva systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 16 14:36:51 volumio-liva systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Mar 16 14:36:51 volumio-liva systemd[1]: Stopped go-librespot Daemon. Mar 16 14:36:51 volumio-liva go-librespot[1668]: go-librespot daemon starting... Mar 16 14:36:51 volumio-liva systemd[1]: Started go-librespot Daemon. Mar 16 14:36:51 volumio-liva go-librespot[1668]: time="2025-03-16T14:36:51-04:00" level=info msg="running go-librespot 0.2.0" Mar 16 14:36:51 volumio-liva go-librespot[1668]: time="2025-03-16T14:36:51-04:00" level=debug msg="app state loaded" Mar 16 14:36:51 volumio-liva go-librespot[1668]: time="2025-03-16T14:36:51-04:00" level=debug msg="stored credentials not found" Mar 16 14:36:51 volumio-liva go-librespot[1668]: time="2025-03-16T14:36:51-04:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 16 14:36:51 volumio-liva go-librespot[1668]: time="2025-03-16T14:36:51-04:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 16 14:36:51 volumio-liva systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 16 14:36:51 volumio-liva systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 16 14:36:51 volumio-liva dhcpcd[826]: wlan0: using IPv4LL address 169.254.156.184 Mar 16 14:36:51 volumio-liva avahi-daemon[754]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.156.184. Mar 16 14:36:51 volumio-liva avahi-daemon[754]: New relevant interface wlan0.IPv4 for mDNS. Mar 16 14:36:51 volumio-liva dhcpcd[826]: wlan0: adding route to 169.254.0.0/16 Mar 16 14:36:51 volumio-liva dhcpcd[826]: wlan0: adding default route Mar 16 14:36:51 volumio-liva avahi-daemon[754]: Registering new address record for 169.254.156.184 on wlan0.IPv4. Mar 16 14:36:51 volumio-liva volumio[1587]: info: Loading plugin "ytmusic"... Mar 16 14:36:53 volumio-liva ntpd[1270]: Listen normally on 4 wlan0 169.254.156.184:123 Mar 16 14:36:53 volumio-liva ntpd[1270]: new interface(s) found: waking up resolver Mar 16 14:36:53 volumio-liva volumio[1587]: info: Loading plugin "outputs"... Mar 16 14:36:53 volumio-liva volumio[1587]: info: Loading plugin "albumart"... Mar 16 14:36:53 volumio-liva volumio[1587]: info: Plugin example_plugin is not enabled Mar 16 14:36:53 volumio-liva volumio[1587]: info: Loading plugin "inputs"... Mar 16 14:36:53 volumio-liva volumio[1587]: info: Loading plugin "updater_comm"... Mar 16 14:36:53 volumio-liva sudo[1632]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:53 volumio-liva volumio[1587]: info: Plugin mpdemulation is not enabled Mar 16 14:36:53 volumio-liva volumio[1587]: info: Loading plugin "rest_api"... Mar 16 14:36:53 volumio-liva volumio[1587]: info: Loading plugin "websocket"... Mar 16 14:36:53 volumio-liva volumio[1587]: Forking 1 albumart workers Mar 16 14:36:53 volumio-liva volumio[1587]: info: Starting Socket.io Server version 2.3.0 Mar 16 14:36:53 volumio-liva volumio[1587]: info: Loading plugin "radio_paradise"... Mar 16 14:36:53 volumio-liva volumio[1587]: info: Applying required configuration parameters for plugin radio_paradise Mar 16 14:36:53 volumio-liva volumio[1587]: info: [1742150213982] [RadioParadise] API delay: 5 Mar 16 14:36:53 volumio-liva volumio[1587]: info: Loading plugin "swarmstation"... Mar 16 14:36:54 volumio-liva volumio[1587]: info: Applying required configuration parameters for plugin swarmstation Mar 16 14:36:54 volumio-liva volumio[1587]: info: [1742150214146] [Swarmstation] API delay: 5 Mar 16 14:36:54 volumio-liva volumio[1587]: info: Loading plugin "backup_restore"... Mar 16 14:36:54 volumio-liva volumio[1587]: Starting albumart workers Mar 16 14:36:54 volumio-liva volumio[1587]: info: Applying required configuration parameters for plugin backup_restore Mar 16 14:36:54 volumio-liva volumio[1587]: info: Loading plugin "Systeminfo"... Mar 16 14:36:54 volumio-liva volumio[1587]: info: Loading plugin "lastfm"... Mar 16 14:36:54 volumio-liva systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 16 14:36:54 volumio-liva systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Mar 16 14:36:54 volumio-liva systemd[1]: Stopped go-librespot Daemon. Mar 16 14:36:54 volumio-liva systemd[1]: Started go-librespot Daemon. Mar 16 14:36:54 volumio-liva go-librespot[1712]: go-librespot daemon starting... Mar 16 14:36:54 volumio-liva go-librespot[1712]: time="2025-03-16T14:36:54-04:00" level=info msg="running go-librespot 0.2.0" Mar 16 14:36:54 volumio-liva go-librespot[1712]: time="2025-03-16T14:36:54-04:00" level=debug msg="app state loaded" Mar 16 14:36:54 volumio-liva go-librespot[1712]: time="2025-03-16T14:36:54-04:00" level=debug msg="stored credentials not found" Mar 16 14:36:54 volumio-liva go-librespot[1712]: time="2025-03-16T14:36:54-04:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 16 14:36:54 volumio-liva volumio[1587]: info: Loading i18n strings for locale en Mar 16 14:36:54 volumio-liva volumio[1587]: Updating browse sources language Mar 16 14:36:54 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 14:36:54 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 14:36:54 volumio-liva volumio[1587]: info: CoreCommandRouter::initPlayerControls Mar 16 14:36:54 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:54 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:54 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:54 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:54 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:54 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:54 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:54 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:54 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 14:36:54 volumio-liva volumio[1587]: Express server listening on port 3000 Mar 16 14:36:54 volumio-liva volumio[1587]: [Metrics] WebUI: 8s 601.14ms Mar 16 14:36:54 volumio-liva volumio[1587]: info: Setting Device type: x86 Mar 16 14:36:54 volumio-liva volumio-time-update[767]: volumio-time-update-util: Fetching time from Volumio... Mar 16 14:36:54 volumio-liva volumio[1587]: info: CoreStateMachine::resetVolumioState Mar 16 14:36:54 volumio-liva volumio[1587]: info: CoreStateMachine::getcurrentVolume Mar 16 14:36:54 volumio-liva volumio[1587]: info: CoreCommandRouter::volumioRetrievevolume Mar 16 14:36:54 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 14:36:55 volumio-liva volumio[1587]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 16 14:36:55 volumio-liva volumio[1587]: info: Completed loading Core Plugins Mar 16 14:36:55 volumio-liva volumio[1587]: info: Preparing to generate the ALSA configuration file Mar 16 14:36:55 volumio-liva volumio[1587]: info: Volumio Network Manager: Network status updated: 0 Mar 16 14:36:55 volumio-liva volumio[1587]: info: Cannot mount NAS happyshowNAS_Local at system boot, trial number 1 ,retrying in 5 seconds Mar 16 14:36:55 volumio-liva volumio[1587]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 16 14:36:55 volumio-liva volumio[1587]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 16 14:36:55 volumio-liva volumio[1587]: info: VolumeController:: Volume=96 Mute =false Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreStateMachine::pushState Mar 16 14:36:55 volumio-liva volumio[1587]: info: CorePlayQueue::getTrack 0 Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::volumioPushState Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreStateMachine::updateTrackBlock Mar 16 14:36:55 volumio-liva volumio[1587]: info: CorePlayQueue::getTrackBlock Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::volumioRetrievevolume Mar 16 14:36:55 volumio-liva volumio[1587]: info: Reloading queue from file Mar 16 14:36:55 volumio-liva volumio[1587]: info: Asound.conf file unchanged, so no further update is needed Mar 16 14:36:55 volumio-liva volumio[1587]: info: Output device has changed, restarting MPD Mar 16 14:36:55 volumio-liva sudo[1761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 16 14:36:55 volumio-liva sudo[1761]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:55 volumio-liva sudo[1761]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:55 volumio-liva volumio[1587]: info: Output device has changed, restarting Shairport Sync Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 14:36:55 volumio-liva sudo[1764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 16 14:36:55 volumio-liva sudo[1764]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:55 volumio-liva systemd[1]: Stopping Music Player Daemon... Mar 16 14:36:55 volumio-liva volumio[1587]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 16 14:36:55 volumio-liva volumio[1587]: info: ___________ START PLUGINS ___________ Mar 16 14:36:55 volumio-liva volumio[1587]: info: ControllerMpd::onStart: Initializing MPD Mar 16 14:36:55 volumio-liva volumio[1587]: info: Creating MPD Configuration file Mar 16 14:36:55 volumio-liva sudo[1770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 16 14:36:55 volumio-liva sudo[1770]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:55 volumio-liva sudo[1770]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 14:36:55 volumio-liva systemd[1]: mpd.service: Succeeded. Mar 16 14:36:55 volumio-liva systemd[1]: Stopped Music Player Daemon. Mar 16 14:36:55 volumio-liva sudo[1773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 16 14:36:55 volumio-liva sudo[1773]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:55 volumio-liva systemd[1]: Starting Music Player Daemon... Mar 16 14:36:55 volumio-liva systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 16 14:36:55 volumio-liva systemd[1]: mpd.service: Succeeded. Mar 16 14:36:55 volumio-liva systemd[1]: Stopped Music Player Daemon. Mar 16 14:36:55 volumio-liva systemd[1]: Starting Music Player Daemon... Mar 16 14:36:55 volumio-liva volumio[1587]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 14:36:55 volumio-liva volumio[1587]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 16 14:36:55 volumio-liva volumio[1587]: info: [1742150215477] CoreMusicLibrary::Adding element Last_100 Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 16 14:36:55 volumio-liva volumio[1587]: info: [1742150215479] CoreMusicLibrary::Adding element Webradio Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 14:36:55 volumio-liva volumio[1587]: info: Initializing BBC Radios Mar 16 14:36:55 volumio-liva sudo[1779]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 16 14:36:55 volumio-liva sudo[1779]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:55 volumio-liva sudo[1779]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:55 volumio-liva volumio-remote-updater[755]: [2025-03-16 14:36:55] [connect] Successful connection Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 14:36:55 volumio-liva volumio[1587]: info: Creating Spotify config file Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 16 14:36:55 volumio-liva volumio[1587]: info: [1742150215612] CoreMusicLibrary::Adding element YouTube Music Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 14:36:55 volumio-liva volumio[1587]: Cannot find translation for source YouTube Music Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 16 14:36:55 volumio-liva volumio[1587]: info: [1742150215617] CoreMusicLibrary::Adding element Radio Paradise Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 14:36:55 volumio-liva volumio[1587]: Cannot find translation for source YouTube Music Mar 16 14:36:55 volumio-liva volumio[1587]: Cannot find translation for source Radio Paradise Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 16 14:36:55 volumio-liva volumio[1587]: info: [1742150215622] CoreMusicLibrary::Adding element SWARM Station Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 14:36:55 volumio-liva volumio[1587]: Cannot find translation for source YouTube Music Mar 16 14:36:55 volumio-liva volumio[1587]: Cannot find translation for source Radio Paradise Mar 16 14:36:55 volumio-liva volumio[1587]: Cannot find translation for source SWARM Station Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 16 14:36:55 volumio-liva volumio[1587]: info: [1742150215626] CoreMusicLibrary::Adding element LastFM Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 14:36:55 volumio-liva volumio[1587]: Cannot find translation for source YouTube Music Mar 16 14:36:55 volumio-liva volumio[1587]: Cannot find translation for source Radio Paradise Mar 16 14:36:55 volumio-liva volumio[1587]: Cannot find translation for source SWARM Station Mar 16 14:36:55 volumio-liva volumio[1587]: Cannot find translation for source LastFM Mar 16 14:36:55 volumio-liva volumio[1587]: info: [LastFM] scrobbler initiated! Mar 16 14:36:55 volumio-liva volumio[1587]: info: [LastFM] extended logging: false Mar 16 14:36:55 volumio-liva volumio[1587]: info: [LastFM] try scrobble stream/radio plays: true Mar 16 14:36:55 volumio-liva volumio[1587]: info: [LastFM] Left init routine Mar 16 14:36:55 volumio-liva volumio[1587]: info: [LastFM] Socket already connected: true Mar 16 14:36:55 volumio-liva volumio[1587]: info: Volumio Calling Home Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreStateMachine::setRepeat null single undefined Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreStateMachine::pushState Mar 16 14:36:55 volumio-liva volumio[1587]: info: CorePlayQueue::getTrack 0 Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::volumioPushState Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreStateMachine::setRandom null Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreStateMachine::pushState Mar 16 14:36:55 volumio-liva volumio[1587]: info: CorePlayQueue::getTrack 0 Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::volumioPushState Mar 16 14:36:55 volumio-liva volumio[1587]: info: MPD Permissions set Mar 16 14:36:55 volumio-liva volumio[1587]: info: MPD Permissions set Mar 16 14:36:55 volumio-liva volumio-remote-updater[755]: [2025-03-16 14:36:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1742150215 101 Mar 16 14:36:55 volumio-liva volumio[1587]: 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 Mar 16 14:36:55 volumio-liva volumio[1587]: info: Spotify config file written Mar 16 14:36:55 volumio-liva volumio[1587]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 16 14:36:55 volumio-liva sudo[1802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 16 14:36:55 volumio-liva sudo[1802]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:55 volumio-liva volumio[1587]: info: VolumeController:: Volume=96 Mute =false Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::volumioGetState Mar 16 14:36:55 volumio-liva volumio[1587]: info: CorePlayQueue::getTrack 0 Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreStateMachine::pushState Mar 16 14:36:55 volumio-liva volumio[1587]: info: CorePlayQueue::getTrack 0 Mar 16 14:36:55 volumio-liva volumio[1587]: info: CoreCommandRouter::volumioPushState Mar 16 14:36:56 volumio-liva systemd[1]: Stopping go-librespot Daemon... Mar 16 14:36:56 volumio-liva systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Mar 16 14:36:56 volumio-liva systemd[1]: go-librespot-daemon.service: Succeeded. Mar 16 14:36:56 volumio-liva systemd[1]: Stopped go-librespot Daemon. Mar 16 14:36:56 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:56 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:56 volumio-liva systemd[1]: Started go-librespot Daemon. Mar 16 14:36:56 volumio-liva go-librespot[1804]: go-librespot daemon starting... Mar 16 14:36:56 volumio-liva sudo[1802]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:56 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 14:36:56 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 16 14:36:56 volumio-liva go-librespot[1804]: time="2025-03-16T14:36:56-04:00" level=info msg="running go-librespot 0.2.0" Mar 16 14:36:56 volumio-liva go-librespot[1804]: time="2025-03-16T14:36:56-04:00" level=debug msg="app state loaded" Mar 16 14:36:56 volumio-liva go-librespot[1804]: time="2025-03-16T14:36:56-04:00" level=debug msg="stored credentials not found" Mar 16 14:36:56 volumio-liva go-librespot[1804]: time="2025-03-16T14:36:56-04:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 16 14:36:56 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:56 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:56 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:56 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:56 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 14:36:56 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 16 14:36:56 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 14:36:56 volumio-liva volumio[1587]: info: No need to fix Spotify hosts Mar 16 14:36:56 volumio-liva volumio[1587]: info: Starting Shairport Sync Mar 16 14:36:56 volumio-liva volumio[1587]: info: Starting Shairport Sync Mar 16 14:36:56 volumio-liva sudo[1823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 14:36:56 volumio-liva sudo[1823]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:56 volumio-liva volumio[1587]: info: Starting Shairport Sync Mar 16 14:36:56 volumio-liva sudo[1826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 14:36:56 volumio-liva sudo[1826]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:56 volumio-liva systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 16 14:36:56 volumio-liva systemd[1]: shairport-sync.service: Succeeded. Mar 16 14:36:56 volumio-liva systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 16 14:36:56 volumio-liva sudo[1830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 14:36:56 volumio-liva systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 16 14:36:56 volumio-liva sudo[1830]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:56 volumio-liva sudo[1823]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:56 volumio-liva systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 16 14:36:56 volumio-liva volumio[1587]: info: Shairport-Sync Started Mar 16 14:36:56 volumio-liva systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 16 14:36:56 volumio-liva systemd[1]: shairport-sync.service: Succeeded. Mar 16 14:36:56 volumio-liva systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 16 14:36:56 volumio-liva volumio[1587]: Error adding Membership: Error: addMembership EINVAL Mar 16 14:36:56 volumio-liva systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 16 14:36:56 volumio-liva sudo[1826]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:56 volumio-liva volumio[1587]: info: Shairport-Sync Started Mar 16 14:36:56 volumio-liva systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 16 14:36:56 volumio-liva systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 16 14:36:56 volumio-liva systemd[1]: shairport-sync.service: Succeeded. Mar 16 14:36:56 volumio-liva systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 16 14:36:56 volumio-liva systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 16 14:36:56 volumio-liva sudo[1830]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:56 volumio-liva volumio[1587]: info: Shairport-Sync Started Mar 16 14:36:56 volumio-liva mpd[1787]: Mar 16 14:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 16 14:36:57 volumio-liva systemd[1]: Started Music Player Daemon. Mar 16 14:36:57 volumio-liva sudo[1773]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:57 volumio-liva sudo[1764]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:57 volumio-liva volumio[1587]: error: MPD error: The expression evaluated to a falsy value: Mar 16 14:36:57 volumio-liva volumio[1587]: assert.ok(self.idling) Mar 16 14:36:57 volumio-liva volumio[1587]: error: The expression evaluated to a falsy value: Mar 16 14:36:57 volumio-liva volumio[1587]: assert.ok(self.idling) Mar 16 14:36:57 volumio-liva volumio[1587]: info: MPD running with PID1787 Mar 16 14:36:57 volumio-liva volumio[1587]: ,establishing connection Mar 16 14:36:57 volumio-liva volumio[1587]: error: updateQueue error: null Mar 16 14:36:57 volumio-liva volumio[1587]: error: updateQueue error: null Mar 16 14:36:57 volumio-liva volumio[1587]: info: Volumio Network Manager: Network status updated: 2 Mar 16 14:36:58 volumio-liva sudo[1855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 16 14:36:58 volumio-liva sudo[1855]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:58 volumio-liva sudo[1855]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:58 volumio-liva sudo[1858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 16 14:36:58 volumio-liva sudo[1858]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:58 volumio-liva sudo[1858]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:58 volumio-liva sudo[1861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 16 14:36:58 volumio-liva sudo[1861]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:36:58 volumio-liva sudo[1861]: pam_unix(sudo:session): session closed for user root Mar 16 14:36:58 volumio-liva volumio[1587]: info: Upmpdcli Daemon Started Mar 16 14:36:59 volumio-liva volumio[1587]: info: go-librespot daemon successfully initialized Mar 16 14:37:00 volumio-liva sudo[1867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Music,password=QkPK+myDyxRwA6u,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.15/Music /mnt/NAS/happyshowNAS_Local Mar 16 14:37:00 volumio-liva sudo[1867]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 14:37:00 volumio-liva kernel: CIFS: Attempting to mount //192.168.50.15/Music Mar 16 14:37:00 volumio-liva dhcpcd[826]: wlan0: offered 192.168.50.71 from 192.168.50.1 Mar 16 14:37:00 volumio-liva dhcpcd[826]: wlan0: probing address 192.168.50.71/24 Mar 16 14:37:02 volumio-liva volumio[1587]: info: Initializing connection to go-librespot Websocket Mar 16 14:37:03 volumio-liva volumio[1587]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 16 14:37:05 volumio-liva dhcpcd[826]: wlan0: leased 192.168.50.71 for 86400 seconds Mar 16 14:37:05 volumio-liva avahi-daemon[754]: Registering new address record for 192.168.50.71 on wlan0.IPv4. Mar 16 14:37:05 volumio-liva dhcpcd[826]: wlan0: adding route to 192.168.50.0/24 Mar 16 14:37:05 volumio-liva dhcpcd[826]: wlan0: changing default route via 192.168.50.1 Mar 16 14:37:05 volumio-liva ntpd[1270]: ntpd exiting on signal 15 (Terminated) Mar 16 14:37:05 volumio-liva systemd[1]: Stopping Network Time Service... Mar 16 14:37:05 volumio-liva systemd[1]: ntp.service: Succeeded. Mar 16 14:37:05 volumio-liva systemd[1]: Stopped Network Time Service. Mar 16 14:37:05 volumio-liva systemd[1]: Starting Network Time Service... Mar 16 14:37:05 volumio-liva ntpd[1905]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 16 14:37:05 volumio-liva ntpd[1905]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 16 14:37:05 volumio-liva systemd[1]: Started Network Time Service. Mar 16 14:37:05 volumio-liva ntpd[1911]: proto: precision = 0.192 usec (-22) Mar 16 14:37:05 volumio-liva ntpd[1911]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 16 14:37:05 volumio-liva ntpd[1911]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 16 14:37:05 volumio-liva ntpd[1911]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 810 days ago Mar 16 14:37:05 volumio-liva ntpd[1911]: Listen and drop on 0 v6wildcard [::]:123 Mar 16 14:37:05 volumio-liva ntpd[1911]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 16 14:37:05 volumio-liva ntpd[1911]: Listen normally on 2 lo 127.0.0.1:123 Mar 16 14:37:05 volumio-liva ntpd[1911]: Listen normally on 3 wlan0 169.254.156.184:123 Mar 16 14:37:06 volumio-liva ntpd[1911]: Listen normally on 4 wlan0 192.168.50.71:123 Mar 16 14:37:06 volumio-liva avahi-daemon[754]: Withdrawing address record for 169.254.156.184 on wlan0. Mar 16 14:37:06 volumio-liva avahi-daemon[754]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.156.184. Mar 16 14:37:06 volumio-liva ntpd[1911]: Listening on routing socket on fd #21 for interface updates Mar 16 14:37:06 volumio-liva ntpd[1911]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 16 14:37:06 volumio-liva ntpd[1911]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 16 14:37:06 volumio-liva avahi-daemon[754]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.50.71. Mar 16 14:37:06 volumio-liva dhcpcd[826]: wlan0: deleting route to 169.254.0.0/16 Mar 16 14:37:06 volumio-liva go-librespot[1804]: time="2025-03-16T14:37:06-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Mar 16 14:37:06 volumio-liva go-librespot[1804]: time="2025-03-16T14:37:06-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 16 14:37:06 volumio-liva go-librespot[1804]: time="2025-03-16T14:37:06-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 16 14:37:06 volumio-liva go-librespot[1804]: time="2025-03-16T14:37:06-04:00" level=info msg="zeroconf server listening on port 40323" Mar 16 14:37:06 volumio-liva ntpd[1911]: Deleting interface #3 wlan0, 169.254.156.184#123, interface stats: received=0, sent=0, dropped=0, active_time=1 secs Mar 16 14:37:07 volumio-liva ntpd[1911]: Soliciting pool server 74.50.48.56 Mar 16 14:37:07 volumio-liva volumio[1587]: verbose: New Socket.io Connection to 192.168.50.71:3000 from 192.168.50.21 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 16 14:37:07 volumio-liva ntpd[1911]: Soliciting pool server 216.31.16.12 Mar 16 14:37:08 volumio-liva ntpd[1911]: Soliciting pool server 74.6.168.72 Mar 16 14:37:08 volumio-liva kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 16 14:37:08 volumio-liva kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Mar 16 14:37:08 volumio-liva sudo[1867]: pam_unix(sudo:session): session closed for user root Mar 16 14:37:08 volumio-liva volumio[1587]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 16 14:37:08 volumio-liva ntpd[1911]: Soliciting pool server 149.248.12.167 Mar 16 14:37:08 volumio-liva ntpd[1911]: Soliciting pool server 69.64.225.2 Mar 16 14:37:09 volumio-liva ntpd[1911]: Soliciting pool server 15.204.87.223 Mar 16 14:37:09 volumio-liva volumio[1587]: info: Cannot mount NAS happyshowNAS_Local at system boot, trial number 2 ,retrying in 5 seconds Mar 16 14:37:09 volumio-liva volumio-time-update[767]: volumio-time-update-util: Date not found in response Mar 16 14:37:09 volumio-liva volumio-time-update[767]: volumio-time-update-util: Retrying in 5 seconds... Mar 16 14:37:09 volumio-liva ntpd[1911]: Soliciting pool server 75.72.171.171 Mar 16 14:37:09 volumio-liva ntpd[1911]: Soliciting pool server 38.81.211.177 Mar 16 14:37:09 volumio-liva ntpd[1911]: Soliciting pool server 23.142.248.8 Mar 16 14:37:10 volumio-liva ntpd[1911]: Soliciting pool server 141.11.234.67 Mar 16 14:37:10 volumio-liva volumio[1587]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 14:37:10 volumio-liva volumio[1587]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Mar 16 14:37:10 volumio-liva volumio[1587]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Mar 16 14:37:10 volumio-liva volumio[1587]: errno: -3007, Mar 16 14:37:10 volumio-liva volumio[1587]: code: 'ENOTFOUND', Mar 16 14:37:10 volumio-liva volumio[1587]: syscall: 'getaddrinfo', Mar 16 14:37:10 volumio-liva volumio[1587]: hostname: 'ws.audioscrobbler.com' Mar 16 14:37:10 volumio-liva volumio[1587]: } Mar 16 14:37:10 volumio-liva volumio[1587]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 14:37:10 volumio-liva ntpd[1911]: Soliciting pool server 85.209.17.10 Mar 16 14:37:10 volumio-liva ntpd[1911]: Soliciting pool server 23.150.41.123 Mar 16 14:37:10 volumio-liva ntpd[1911]: Soliciting pool server 45.33.53.84 Mar 16 14:37:11 volumio-liva sudo[1956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-16 14:36 Mar 16 14:37:11 volumio-liva sudo[1956]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"