-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Fri 2024-08-23 10:37:09 CEST. -- Aug 23 10:36:00 volumio ntpd[769]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 23 10:36:00 volumio ntpd[769]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 23 10:36:00 volumio dhcpcd[1042]: eth0: probing for an IPv4LL address Aug 23 10:36:01 volumio volumio[1045]: info: Cannot mount NAS Musica_NAS at system boot, trial number 4 ,retrying in 5 seconds Aug 23 10:36:01 volumio volumio[1045]: error: MyVolumio Plugin failed to start in a timely fashion Aug 23 10:36:01 volumio volumio[1045]: info: BOOT COMPLETED Aug 23 10:36:01 volumio volumio[1045]: [Metrics] CommandRouter: 38s 603.78ms Aug 23 10:36:01 volumio volumio[1045]: info: CoreCommandRouter::volumiosetStartupVolume Aug 23 10:36:01 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 10:36:01 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 10:36:01 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Aug 23 10:36:01 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Aug 23 10:36:01 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Aug 23 10:36:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 10:36:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 23 10:36:01 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 10:36:02 volumio systemd[1]: Started go-librespot Daemon. Aug 23 10:36:02 volumio go-librespot[1474]: Librespot-go daemon starting... Aug 23 10:36:02 volumio go-librespot[1474]: time="2024-08-23T10:36:02+02:00" level=info msg="generated new device id: c78242bf046c7d353855d043637b033e2304abc3" Aug 23 10:36:02 volumio go-librespot[1474]: time="2024-08-23T10:36:02+02:00" level=debug msg="stored credentials found for jemperboyd" Aug 23 10:36:02 volumio go-librespot[1474]: time="2024-08-23T10:36:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Aug 23 10:36:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 10:36:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 10:36:03 volumio volumio[1045]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Aug 23 10:36:03 volumio volumio[1045]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Aug 23 10:36:03 volumio volumio[1045]: aplay: main:828: audio open error: No such device Aug 23 10:36:03 volumio volumio[1045]: info: Cannot mount NAS Apple_Music at system boot, trial number 3 ,retrying in 5 seconds Aug 23 10:36:03 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 23 10:36:03 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 23 10:36:03 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Aug 23 10:36:03 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 23 10:36:04 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 10:36:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 10:36:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 23 10:36:05 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 10:36:05 volumio systemd[1]: Started go-librespot Daemon. Aug 23 10:36:05 volumio go-librespot[1483]: Librespot-go daemon starting... Aug 23 10:36:05 volumio go-librespot[1483]: time="2024-08-23T10:36:05+02:00" level=info msg="generated new device id: fc8296a232056206c5e470ba0019a1675a8f47e3" Aug 23 10:36:05 volumio go-librespot[1483]: time="2024-08-23T10:36:05+02:00" level=debug msg="stored credentials found for jemperboyd" Aug 23 10:36:05 volumio go-librespot[1483]: time="2024-08-23T10:36:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Aug 23 10:36:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 10:36:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 10:36:05 volumio wpa_supplicant[867]: wlan0: Authentication with 3c:9e:c7:d2:25:a5 timed out. Aug 23 10:36:05 volumio wpa_supplicant[867]: wlan0: CTRL-EVENT-DISCONNECTED bssid=3c:9e:c7:d2:25:a5 reason=3 locally_generated=1 Aug 23 10:36:05 volumio wpa_supplicant[867]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="SKYWIFI_FYW9Y" auth_failures=1 duration=10 reason=CONN_FAILED Aug 23 10:36:05 volumio wpa_supplicant[867]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 23 10:36:05 volumio wpa_supplicant[867]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=IT Aug 23 10:36:05 volumio dhcpcd[1042]: eth0: using IPv4LL address 169.254.202.237 Aug 23 10:36:05 volumio dhcpcd[1042]: eth0: adding route to 169.254.0.0/16 Aug 23 10:36:05 volumio avahi-daemon[617]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.202.237. Aug 23 10:36:05 volumio avahi-daemon[617]: New relevant interface eth0.IPv4 for mDNS. Aug 23 10:36:05 volumio dhcpcd[1042]: eth0: adding default route Aug 23 10:36:05 volumio avahi-daemon[617]: Registering new address record for 169.254.202.237 on eth0.IPv4. Aug 23 10:36:06 volumio volumio[1045]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.742&uuid=4fb8e0534542ae96e06f76f0d185c3f4" http://updates.volumio.org/downloader-v1/track-device Aug 23 10:36:06 volumio volumio[1045]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 23 10:36:06 volumio volumio[1045]: Dload Upload Total Spent Left Speed Aug 23 10:36:06 volumio volumio[1045]: [1.2K blob data] Aug 23 10:36:06 volumio volumio[1045]: retrying in 5 seconds, trial 0 Aug 23 10:36:06 volumio volumio[1045]: info: Volumio Calling Home Aug 23 10:36:07 volumio sudo[1506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=password,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.126/Music /mnt/NAS/Musica_NAS Aug 23 10:36:07 volumio sudo[1506]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 10:36:07 volumio kernel: CIFS: Attempting to mount \\192.168.0.126\Music Aug 23 10:36:07 volumio ntpd[769]: Listen normally on 4 eth0 169.254.202.237:123 Aug 23 10:36:07 volumio ntpd[769]: new interface(s) found: waking up resolver Aug 23 10:36:07 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Aug 23 10:36:08 volumio wpa_supplicant[867]: wlan0: Failed to initiate sched scan Aug 23 10:36:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 10:36:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Aug 23 10:36:08 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 10:36:08 volumio systemd[1]: Started go-librespot Daemon. Aug 23 10:36:08 volumio go-librespot[1510]: Librespot-go daemon starting... Aug 23 10:36:08 volumio go-librespot[1510]: time="2024-08-23T10:36:08+02:00" level=info msg="generated new device id: 0c968b5e260293539ccf3760a58df65c59a87bf1" Aug 23 10:36:08 volumio go-librespot[1510]: time="2024-08-23T10:36:08+02:00" level=debug msg="stored credentials found for jemperboyd" Aug 23 10:36:08 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 10:36:08 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 10:36:08 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 10:36:08 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 23 10:36:13 volumio sudo[1520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.59/Macintosh HD/Utenti/flavianoraschietti/Musica/Music/File multimediali /mnt/NAS/Apple_Music Aug 23 10:36:13 volumio sudo[1520]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 10:36:13 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Aug 23 10:36:13 volumio sudo[1506]: pam_unix(sudo:session): session closed for user root Aug 23 10:36:13 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 23 10:36:13 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Aug 23 10:36:13 volumio kernel: CIFS: Attempting to mount \\192.168.0.59\Macintosh HD Aug 23 10:36:15 volumio wpa_supplicant[867]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="SKYWIFI_FYW9Y" Aug 23 10:36:15 volumio wpa_supplicant[867]: wlan0: Trying to associate with SSID 'SKYWIFI_FYW9Y' Aug 23 10:36:19 volumio volumio[1045]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Aug 23 10:36:19 volumio sudo[1520]: pam_unix(sudo:session): session closed for user root Aug 23 10:36:19 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 23 10:36:19 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Aug 23 10:36:20 volumio volumio[1045]: info: Cannot mount NAS Apple_Music at system boot, trial number 4 ,retrying in 5 seconds Aug 23 10:36:22 volumio dhcpcd[1042]: wlan0: carrier acquired Aug 23 10:36:22 volumio dhcpcd[1042]: wlan0: connected to Access Point `SKYWIFI_FYW9Y' Aug 23 10:36:22 volumio wpa_supplicant[867]: wlan0: Associated with 3c:9e:c7:d2:25:a5 Aug 23 10:36:22 volumio wpa_supplicant[867]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:9e:c7:d2:25:a5 completed [id=0 id_str=] Aug 23 10:36:22 volumio wpa_supplicant[867]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 23 10:36:22 volumio dhcpcd[1042]: wlan0: IAID 32:a8:e9:24 Aug 23 10:36:22 volumio dhcpcd[1042]: wlan0: adding address fe80::6eab:aa8c:8a6e:8a50 Aug 23 10:36:22 volumio dhcpcd[1042]: ipv6_addaddr1: Permission denied Aug 23 10:36:22 volumio dhcpcd[1042]: wlan0: carrier lost Aug 23 10:36:22 volumio dhcpcd[1042]: wlan0: carrier acquired Aug 23 10:36:22 volumio dhcpcd[1042]: wlan0: IAID 32:a8:e9:24 Aug 23 10:36:22 volumio dhcpcd[1042]: wlan0: rebinding lease of 192.168.0.17 Aug 23 10:36:22 volumio dhcpcd[1042]: wlan0: probing address 192.168.0.17/24 Aug 23 10:36:22 volumio dhcpcd[1042]: wlan0: soliciting an IPv6 router Aug 23 10:36:25 volumio dhcpcd[1042]: eth0: carrier lost Aug 23 10:36:25 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Aug 23 10:36:25 volumio dhcpcd[1042]: eth0: deleting route to 169.254.0.0/16 Aug 23 10:36:25 volumio avahi-daemon[617]: Withdrawing address record for 169.254.202.237 on eth0. Aug 23 10:36:25 volumio avahi-daemon[617]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.202.237. Aug 23 10:36:25 volumio avahi-daemon[617]: Interface eth0.IPv4 no longer relevant for mDNS. Aug 23 10:36:25 volumio dhcpcd[1042]: eth0: deleting default route Aug 23 10:36:26 volumio sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.59/Macintosh HD/Utenti/flavianoraschietti/Musica/Music/File multimediali /mnt/NAS/Apple_Music Aug 23 10:36:26 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 10:36:26 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root Aug 23 10:36:26 volumio kernel: CIFS: Attempting to mount \\192.168.0.59\Macintosh HD Aug 23 10:36:26 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 23 10:36:26 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Aug 23 10:36:26 volumio ifplugd(eth0)[774]: Link beat lost. Aug 23 10:36:26 volumio volumio[1045]: info: Discovery: A device disappeared from network Aug 23 10:36:26 volumio volumio[1045]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Aug 23 10:36:27 volumio ntpd[769]: Deleting interface #4 eth0, 169.254.202.237#123, interface stats: received=0, sent=0, dropped=0, active_time=20 secs Aug 23 10:36:27 volumio volumio[1045]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Aug 23 10:36:27 volumio dhcpcd[1042]: wlan0: leased 192.168.0.17 for 172800 seconds Aug 23 10:36:27 volumio avahi-daemon[617]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.17. Aug 23 10:36:27 volumio dhcpcd[1042]: wlan0: adding route to 192.168.0.0/24 Aug 23 10:36:27 volumio dhcpcd[1042]: wlan0: adding default route via 192.168.0.1 Aug 23 10:36:27 volumio avahi-daemon[617]: New relevant interface wlan0.IPv4 for mDNS. Aug 23 10:36:27 volumio avahi-daemon[617]: Registering new address record for 192.168.0.17 on wlan0.IPv4. Aug 23 10:36:28 volumio go-librespot[1510]: time="2024-08-23T10:36:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Aug 23 10:36:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 10:36:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 10:36:29 volumio ntpd[769]: Listen normally on 5 wlan0 192.168.0.17:123 Aug 23 10:36:29 volumio ntpd[769]: new interface(s) found: waking up resolver Aug 23 10:36:29 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 10:36:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 10:36:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Aug 23 10:36:31 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 10:36:31 volumio systemd[1]: Started go-librespot Daemon. Aug 23 10:36:31 volumio go-librespot[1588]: Librespot-go daemon starting... Aug 23 10:36:31 volumio go-librespot[1588]: time="2024-08-23T10:36:31+02:00" level=info msg="generated new device id: 961348a79669c8c5f8f5f11fffc36b29a52bdc67" Aug 23 10:36:31 volumio go-librespot[1588]: time="2024-08-23T10:36:31+02:00" level=debug msg="stored credentials found for jemperboyd" Aug 23 10:36:32 volumio dhcpcd[1042]: eth0: carrier acquired Aug 23 10:36:32 volumio dhcpcd[1042]: eth0: IAID 32:a8:e9:23 Aug 23 10:36:32 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Aug 23 10:36:33 volumio dhcpcd[1042]: eth0: soliciting a DHCP lease Aug 23 10:36:33 volumio dhcpcd[1042]: eth0: offered 192.168.0.16 from 192.168.0.1 Aug 23 10:36:33 volumio dhcpcd[1042]: eth0: probing address 192.168.0.16/24 Aug 23 10:36:33 volumio nmbd[730]: [2024/08/23 10:36:33.286803, 0] ../source3/libsmb/nmblib.c:917(send_udp) Aug 23 10:36:33 volumio nmbd[730]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Aug 23 10:36:33 volumio dhcpcd[1042]: eth0: soliciting an IPv6 router Aug 23 10:36:33 volumio ifplugd(eth0)[774]: Link beat detected. Aug 23 10:36:37 volumio volumio[1045]: info: Discovery: adding 30390755-d839-487c-90d7-97ae26ff5ff0 Aug 23 10:36:37 volumio volumio[1045]: info: Discovery: Found device Volumio Aug 23 10:36:37 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Aug 23 10:36:37 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 23 10:36:37 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Aug 23 10:36:37 volumio volumio[1045]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.742&uuid=4fb8e0534542ae96e06f76f0d185c3f4" http://updates.volumio.org/downloader-v1/track-device Aug 23 10:36:37 volumio volumio[1045]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 23 10:36:37 volumio volumio[1045]: Dload Upload Total Spent Left Speed Aug 23 10:36:37 volumio volumio[1045]: [1.5K blob data] Aug 23 10:36:37 volumio volumio[1045]: retrying in 5 seconds, trial 1 Aug 23 10:36:37 volumio volumio[1045]: info: Volumio Calling Home Aug 23 10:36:38 volumio dhcpcd[1042]: eth0: leased 192.168.0.16 for 172800 seconds Aug 23 10:36:38 volumio dhcpcd[1042]: eth0: adding route to 192.168.0.0/24 Aug 23 10:36:38 volumio dhcpcd[1042]: eth0: adding default route via 192.168.0.1 Aug 23 10:36:38 volumio avahi-daemon[617]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.16. Aug 23 10:36:38 volumio avahi-daemon[617]: New relevant interface eth0.IPv4 for mDNS. Aug 23 10:36:38 volumio avahi-daemon[617]: Registering new address record for 192.168.0.16 on eth0.IPv4. Aug 23 10:36:40 volumio go-librespot[1588]: time="2024-08-23T10:36:40+02:00" level=debug msg="obtained new client token: AAD68b4WsRg5m5fei563gKF8olwkuIpQp23sTYJ6s6O+rYqMEPltllE/NGyNZB6T9gJ3xlAZIPO8ua+6TEoS1ZTzLLIr3JxuZ8zBWPD3DeA3dfLGb1wVkJ5Poxs1ot1INNMbuc6MHMldjdcrMha6EFULJdc7HyGJ+0pSuh2oX7NCQDY28rT/viPFIZ56WkjkT57liHlCEoa8nu3f0RnHxn2usb7sXrAbx65sTVF96Loo7eB9/mwfFGONq3M7okKq" Aug 23 10:36:40 volumio go-librespot[1588]: time="2024-08-23T10:36:40+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 23 10:36:40 volumio ntpd[769]: Listen normally on 6 eth0 192.168.0.16:123 Aug 23 10:36:40 volumio ntpd[769]: new interface(s) found: waking up resolver Aug 23 10:36:40 volumio go-librespot[1588]: time="2024-08-23T10:36:40+02:00" level=debug msg="completed keyexchange" Aug 23 10:36:40 volumio go-librespot[1588]: time="2024-08-23T10:36:40+02:00" level=debug msg="completed challenge" Aug 23 10:36:40 volumio go-librespot[1588]: time="2024-08-23T10:36:40+02:00" level=debug msg="authenticated as jemperboyd" Aug 23 10:36:40 volumio go-librespot[1588]: time="2024-08-23T10:36:40+02:00" level=debug msg="authenticated as jemperboyd" Aug 23 10:36:40 volumio go-librespot[1588]: time="2024-08-23T10:36:40+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 23 10:36:40 volumio go-librespot[1588]: time="2024-08-23T10:36:40+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 23 10:36:41 volumio go-librespot[1588]: time="2024-08-23T10:36:41+02:00" level=debug msg="dealer connection opened" Aug 23 10:36:41 volumio go-librespot[1588]: time="2024-08-23T10:36:41+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Aug 23 10:36:41 volumio go-librespot[1588]: time="2024-08-23T10:36:41+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Aug 23 10:36:41 volumio go-librespot[1588]: time="2024-08-23T10:36:41+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Aug 23 10:36:41 volumio go-librespot[1588]: time="2024-08-23T10:36:41+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Aug 23 10:36:41 volumio go-librespot[1588]: time="2024-08-23T10:36:41+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Aug 23 10:36:41 volumio go-librespot[1588]: time="2024-08-23T10:36:41+02:00" level=debug msg="received connection id: YmJkMjg5MGItMzA2OS00ZThjLWFkYmItMWE1NmJjYTIyOGQyK2RlYWxlcit0Y3A6Ly8wYWNhNTg1Ny5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNjk1OTdFRUNBQTExQThENzY2QjFFMjYzRjhFNjRDRTUzMzNGRjBBMDYwRTEyMDA3MEE3QzZEMzdDNTQwQzUxMA==" Aug 23 10:36:41 volumio go-librespot[1588]: time="2024-08-23T10:36:41+02:00" level=debug msg="put connect state because NEW_DEVICE" Aug 23 10:36:41 volumio volumio[1045]: info: Discovery: this is already registered, 30390755-d839-487c-90d7-97ae26ff5ff0 Aug 23 10:36:41 volumio volumio[1045]: info: Discovery: Found device Volumio Aug 23 10:36:41 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Aug 23 10:36:41 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 23 10:36:41 volumio go-librespot[1588]: time="2024-08-23T10:36:41+02:00" level=debug msg="new websocket client" Aug 23 10:36:41 volumio volumio[1045]: info: Connection to go-librespot Websocket established Aug 23 10:36:41 volumio volumio[1045]: info: Discovery: this is already registered, 30390755-d839-487c-90d7-97ae26ff5ff0 Aug 23 10:36:41 volumio volumio[1045]: info: Discovery: Found device Volumio Aug 23 10:36:41 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Aug 23 10:36:41 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 23 10:36:42 volumio volumio[1045]: info: Volumio called home Aug 23 10:36:44 volumio volumio[1045]: info: Getting Spotify volume Aug 23 10:36:44 volumio volumio[1045]: info: Spotify volume: 100 Aug 23 10:36:44 volumio volumio[1045]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 23 10:36:44 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Aug 23 10:36:44 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 23 10:36:57 volumio dhcpcd[1042]: eth0: carrier lost Aug 23 10:36:57 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Aug 23 10:36:57 volumio avahi-daemon[617]: Withdrawing address record for 192.168.0.16 on eth0. Aug 23 10:36:57 volumio dhcpcd[1042]: eth0: deleting route to 192.168.0.0/24 Aug 23 10:36:57 volumio dhcpcd[1042]: eth0: deleting default route via 192.168.0.1 Aug 23 10:36:57 volumio avahi-daemon[617]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.16. Aug 23 10:36:57 volumio avahi-daemon[617]: Interface eth0.IPv4 no longer relevant for mDNS. Aug 23 10:36:57 volumio volumio[1045]: info: Discovery: A device disappeared from network Aug 23 10:36:57 volumio volumio[1045]: info: Discovery: Device volumio disappeared from network Aug 23 10:36:58 volumio ifplugd(eth0)[774]: Link beat lost. Aug 23 10:36:59 volumio ntpd[769]: Deleting interface #6 eth0, 192.168.0.16#123, interface stats: received=0, sent=0, dropped=0, active_time=19 secs Aug 23 10:37:01 volumio wpa_supplicant[867]: wlan0: CTRL-EVENT-DISCONNECTED bssid=3c:9e:c7:d2:25:a5 reason=4 Aug 23 10:37:01 volumio dhcpcd[1042]: wlan0: carrier lost Aug 23 10:37:01 volumio wpa_supplicant[867]: wlan0: Trying to associate with SSID 'SKYWIFI_FYW9Y' Aug 23 10:37:01 volumio wpa_supplicant[867]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 23 10:37:01 volumio wpa_supplicant[867]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=IT Aug 23 10:37:01 volumio avahi-daemon[617]: Withdrawing address record for 192.168.0.17 on wlan0. Aug 23 10:37:01 volumio avahi-daemon[617]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.17. Aug 23 10:37:01 volumio avahi-daemon[617]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 23 10:37:01 volumio dhcpcd[1042]: wlan0: deleting route to 192.168.0.0/24 Aug 23 10:37:01 volumio dhcpcd[1042]: wlan0: deleting default route via 192.168.0.1 Aug 23 10:37:01 volumio volumio[1045]: info: Discovery: A device disappeared from network Aug 23 10:37:03 volumio ntpd[769]: Deleting interface #5 wlan0, 192.168.0.17#123, interface stats: received=0, sent=0, dropped=0, active_time=34 secs Aug 23 10:37:03 volumio ntpd[769]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 23 10:37:03 volumio ntpd[769]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 23 10:37:04 volumio dhcpcd[1042]: wlan0: carrier acquired Aug 23 10:37:04 volumio wpa_supplicant[867]: wlan0: Associated with 3c:9e:c7:d2:25:a5 Aug 23 10:37:04 volumio wpa_supplicant[867]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:9e:c7:d2:25:a5 completed [id=0 id_str=] Aug 23 10:37:04 volumio wpa_supplicant[867]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 23 10:37:04 volumio dhcpcd[1042]: wlan0: IAID 32:a8:e9:24 Aug 23 10:37:04 volumio dhcpcd[1042]: wlan0: carrier lost Aug 23 10:37:04 volumio dhcpcd[1042]: wlan0: carrier acquired Aug 23 10:37:04 volumio dhcpcd[1042]: wlan0: IAID 32:a8:e9:24 Aug 23 10:37:04 volumio dhcpcd[1042]: wlan0: soliciting an IPv6 router Aug 23 10:37:04 volumio dhcpcd[1042]: wlan0: rebinding lease of 192.168.0.17 Aug 23 10:37:04 volumio dhcpcd[1042]: wlan0: probing address 192.168.0.17/24 Aug 23 10:37:04 volumio dhcpcd[1042]: eth0: carrier acquired Aug 23 10:37:04 volumio dhcpcd[1042]: eth0: IAID 32:a8:e9:23 Aug 23 10:37:04 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Aug 23 10:37:05 volumio ntpd[769]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 23 10:37:05 volumio ifplugd(eth0)[774]: Link beat detected. Aug 23 10:37:05 volumio dhcpcd[1042]: eth0: soliciting an IPv6 router Aug 23 10:37:05 volumio dhcpcd[1042]: eth0: rebinding lease of 192.168.0.16 Aug 23 10:37:05 volumio dhcpcd[1042]: eth0: probing address 192.168.0.16/24 Aug 23 10:37:07 volumio ntpd[769]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 23 10:37:08 volumio volumio[1045]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 10:37:08 volumio volumio[1045]: Error: send ENETUNREACH 255.255.255.255:3483 Aug 23 10:37:08 volumio volumio[1045]: at doSend (dgram.js:692:16) Aug 23 10:37:08 volumio volumio[1045]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Aug 23 10:37:08 volumio volumio[1045]: at afterDns (dgram.js:638:5) Aug 23 10:37:08 volumio volumio[1045]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 23 10:37:08 volumio volumio[1045]: errno: -101, Aug 23 10:37:08 volumio volumio[1045]: code: 'ENETUNREACH', Aug 23 10:37:08 volumio volumio[1045]: syscall: 'send', Aug 23 10:37:08 volumio volumio[1045]: address: '255.255.255.255', Aug 23 10:37:08 volumio volumio[1045]: port: 3483 Aug 23 10:37:08 volumio volumio[1045]: } Aug 23 10:37:08 volumio volumio[1045]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 10:37:08 volumio dhcpcd[1042]: wlan0: leased 192.168.0.17 for 172800 seconds Aug 23 10:37:08 volumio avahi-daemon[617]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.17. Aug 23 10:37:08 volumio dhcpcd[1042]: wlan0: adding route to 192.168.0.0/24 Aug 23 10:37:08 volumio dhcpcd[1042]: wlan0: adding default route via 192.168.0.1 Aug 23 10:37:08 volumio avahi-daemon[617]: New relevant interface wlan0.IPv4 for mDNS. Aug 23 10:37:08 volumio avahi-daemon[617]: Registering new address record for 192.168.0.17 on wlan0.IPv4. Aug 23 10:37:09 volumio sudo[1768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 10:36 Aug 23 10:37:09 volumio sudo[1768]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"