Aug 27 02:02:01 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:01 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:01 syrin-office systemd[1]: newrelic-infra.service: Scheduled restart job, restart counter is at 94976. Aug 27 02:02:01 syrin-office systemd[1]: Stopped newrelic-infra.service - New Relic Infrastructure Agent. Aug 27 02:02:01 syrin-office systemd[1]: Started newrelic-infra.service - New Relic Infrastructure Agent. Aug 27 02:02:01 syrin-office newrelic-infra-service[3239]: time="2026-08-27T02:02:01-07:00" level=info msg="Creating service..." Aug 27 02:02:01 syrin-office newrelic-infra-service[3247]: time="2026-08-27T02:02:01-07:00" level=error msg="Can't open log file." action=configureLogRedirection component="New Relic Infrastructure Agent" error="open /var/log/newrelic-infra/newrelic-infra.log: no such file or directory" Aug 27 02:02:01 syrin-office newrelic-infra-service[3239]: time="2026-08-27T02:02:01-07:00" level=info msg="child process exited" exit_code=1 Aug 27 02:02:01 syrin-office systemd[1]: newrelic-infra.service: Main process exited, code=exited, status=1/FAILURE Aug 27 02:02:01 syrin-office systemd[1]: newrelic-infra.service: Failed with result 'exit-code'. Aug 27 02:02:04 syrin-office wpa_supplicant[1009]: wlan0: Failed to initiate sched scan Aug 27 02:02:04 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.37 Aug 27 02:02:05 syrin-office volumio[1052]: info: Received Get System Info Aug 27 02:02:05 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 02:02:05 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 02:02:05 syrin-office volumio[1052]: info: Discovery: Getting this device information Aug 27 02:02:05 syrin-office volumio[1052]: info: CoreCommandRouter::volumioGetState Aug 27 02:02:05 syrin-office volumio[1052]: info: CorePlayQueue::getTrack 0 Aug 27 02:02:05 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 02:02:05 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 02:02:05 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 02:02:06 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:06 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:06 syrin-office nmbd[922]: [2026/08/27 02:02:06.455016, 0] ../../source3/nmbd/nmbd.c:359(reload_interfaces) Aug 27 02:02:06 syrin-office nmbd[922]: reload_interfaces: No subnets to listen to. Waiting.. Aug 27 02:02:09 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.37 Aug 27 02:02:10 syrin-office wpa_supplicant[1009]: wlan0: Failed to initiate sched scan Aug 27 02:02:10 syrin-office volumio[1052]: info: Received Get System Info Aug 27 02:02:10 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 02:02:10 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 02:02:10 syrin-office volumio[1052]: info: Discovery: Getting this device information Aug 27 02:02:10 syrin-office volumio[1052]: info: CoreCommandRouter::volumioGetState Aug 27 02:02:10 syrin-office volumio[1052]: info: CorePlayQueue::getTrack 0 Aug 27 02:02:10 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 02:02:10 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 02:02:10 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 02:02:11 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:11 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:14 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.37 Aug 27 02:02:15 syrin-office wpa_supplicant[1009]: wlan0: Failed to initiate sched scan Aug 27 02:02:16 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:16 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:16 syrin-office volumio[1052]: info: Received Get System Info Aug 27 02:02:16 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 02:02:16 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 02:02:16 syrin-office volumio[1052]: info: Discovery: Getting this device information Aug 27 02:02:16 syrin-office volumio[1052]: info: CoreCommandRouter::volumioGetState Aug 27 02:02:16 syrin-office volumio[1052]: info: CorePlayQueue::getTrack 0 Aug 27 02:02:16 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 02:02:16 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 02:02:16 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 02:02:19 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.37 Aug 27 02:02:21 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:21 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:21 syrin-office wpa_supplicant[1009]: wlan0: Failed to initiate sched scan Aug 27 02:02:21 syrin-office systemd[1]: newrelic-infra.service: Scheduled restart job, restart counter is at 94977. Aug 27 02:02:21 syrin-office systemd[1]: Stopped newrelic-infra.service - New Relic Infrastructure Agent. Aug 27 02:02:21 syrin-office systemd[1]: Started newrelic-infra.service - New Relic Infrastructure Agent. Aug 27 02:02:21 syrin-office newrelic-infra-service[3284]: time="2026-08-27T02:02:21-07:00" level=info msg="Creating service..." Aug 27 02:02:21 syrin-office newrelic-infra-service[3291]: time="2026-08-27T02:02:21-07:00" level=error msg="Can't open log file." action=configureLogRedirection component="New Relic Infrastructure Agent" error="open /var/log/newrelic-infra/newrelic-infra.log: no such file or directory" Aug 27 02:02:21 syrin-office newrelic-infra-service[3284]: time="2026-08-27T02:02:21-07:00" level=info msg="child process exited" exit_code=1 Aug 27 02:02:21 syrin-office systemd[1]: newrelic-infra.service: Main process exited, code=exited, status=1/FAILURE Aug 27 02:02:21 syrin-office systemd[1]: newrelic-infra.service: Failed with result 'exit-code'. Aug 27 02:02:21 syrin-office volumio[1052]: info: Received Get System Info Aug 27 02:02:21 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 02:02:21 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 02:02:21 syrin-office volumio[1052]: info: Discovery: Getting this device information Aug 27 02:02:21 syrin-office volumio[1052]: info: CoreCommandRouter::volumioGetState Aug 27 02:02:21 syrin-office volumio[1052]: info: CorePlayQueue::getTrack 0 Aug 27 02:02:21 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 02:02:21 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 02:02:21 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 02:02:24 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.37 Aug 27 02:02:26 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:26 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:26 syrin-office wpa_supplicant[1009]: wlan0: Failed to initiate sched scan Aug 27 02:02:27 syrin-office volumio[1052]: info: Received Get System Info Aug 27 02:02:27 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 02:02:27 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 02:02:27 syrin-office volumio[1052]: info: Discovery: Getting this device information Aug 27 02:02:27 syrin-office volumio[1052]: info: CoreCommandRouter::volumioGetState Aug 27 02:02:27 syrin-office volumio[1052]: info: CorePlayQueue::getTrack 0 Aug 27 02:02:27 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 02:02:27 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 02:02:27 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 02:02:29 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.37 Aug 27 02:02:31 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:31 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:32 syrin-office wpa_supplicant[1009]: wlan0: Failed to initiate sched scan Aug 27 02:02:32 syrin-office go-librespot[7667]: time="2026-08-27T02:02:32-07:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 27 02:02:32 syrin-office go-librespot[7667]: time="2026-08-27T02:02:32-07:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Aug 27 02:02:32 syrin-office go-librespot[7667]: time="2026-08-27T02:02:32-07:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 27 02:02:32 syrin-office go-librespot[7667]: time="2026-08-27T02:02:32-07:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Aug 27 02:02:32 syrin-office go-librespot[7667]: time="2026-08-27T02:02:32-07:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 27 02:02:32 syrin-office go-librespot[7667]: time="2026-08-27T02:02:32-07:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Aug 27 02:02:32 syrin-office go-librespot[7667]: time="2026-08-27T02:02:32-07:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 27 02:02:32 syrin-office go-librespot[7667]: time="2026-08-27T02:02:32-07:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Aug 27 02:02:32 syrin-office go-librespot[7667]: time="2026-08-27T02:02:32-07:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 27 02:02:32 syrin-office go-librespot[7667]: time="2026-08-27T02:02:32-07:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Aug 27 02:02:32 syrin-office go-librespot[7667]: time="2026-08-27T02:02:32-07:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 27 02:02:32 syrin-office volumio[1052]: info: Received Get System Info Aug 27 02:02:32 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 02:02:32 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 02:02:32 syrin-office volumio[1052]: info: Discovery: Getting this device information Aug 27 02:02:32 syrin-office volumio[1052]: info: CoreCommandRouter::volumioGetState Aug 27 02:02:32 syrin-office volumio[1052]: info: CorePlayQueue::getTrack 0 Aug 27 02:02:32 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 02:02:32 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 02:02:32 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 02:02:34 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.37 Aug 27 02:02:36 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:36 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:37 syrin-office wpa_supplicant[1009]: wlan0: Trying to associate with 54:07:7d:43:9e:ce (SSID='Jobe-2g' freq=2462 MHz) Aug 27 02:02:37 syrin-office wpa_supplicant[1009]: wlan0: Associated with 54:07:7d:43:9e:ce Aug 27 02:02:37 syrin-office wpa_supplicant[1009]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 27 02:02:37 syrin-office wpa_supplicant[1009]: wlan0: WPA: Key negotiation completed with 54:07:7d:43:9e:ce [PTK=CCMP GTK=CCMP] Aug 27 02:02:37 syrin-office wpa_supplicant[1009]: wlan0: CTRL-EVENT-CONNECTED - Connection to 54:07:7d:43:9e:ce completed [id=0 id_str=] Aug 27 02:02:37 syrin-office dhcpcd[639]: wlan0: carrier acquired Aug 27 02:02:37 syrin-office dhcpcd[639]: wlan0: IAID 9e:e1:d2:b5 Aug 27 02:02:38 syrin-office volumio[1052]: info: Received Get System Info Aug 27 02:02:38 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 02:02:38 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 02:02:38 syrin-office volumio[1052]: info: Discovery: Getting this device information Aug 27 02:02:38 syrin-office volumio[1052]: info: CoreCommandRouter::volumioGetState Aug 27 02:02:38 syrin-office volumio[1052]: info: CorePlayQueue::getTrack 0 Aug 27 02:02:38 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 02:02:38 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 02:02:38 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 02:02:38 syrin-office dhcpcd[639]: wlan0: soliciting an IPv6 router Aug 27 02:02:39 syrin-office dhcpcd[639]: wlan0: rebinding lease of 192.168.0.33 Aug 27 02:02:39 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.37 Aug 27 02:02:41 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:41 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:41 syrin-office systemd[1]: newrelic-infra.service: Scheduled restart job, restart counter is at 94978. Aug 27 02:02:41 syrin-office systemd[1]: Stopped newrelic-infra.service - New Relic Infrastructure Agent. Aug 27 02:02:41 syrin-office systemd[1]: Started newrelic-infra.service - New Relic Infrastructure Agent. Aug 27 02:02:41 syrin-office newrelic-infra-service[3333]: time="2026-08-27T02:02:41-07:00" level=info msg="Creating service..." Aug 27 02:02:41 syrin-office newrelic-infra-service[3339]: time="2026-08-27T02:02:41-07:00" level=error msg="Can't open log file." action=configureLogRedirection component="New Relic Infrastructure Agent" error="open /var/log/newrelic-infra/newrelic-infra.log: no such file or directory" Aug 27 02:02:41 syrin-office newrelic-infra-service[3333]: time="2026-08-27T02:02:41-07:00" level=info msg="child process exited" exit_code=1 Aug 27 02:02:41 syrin-office systemd[1]: newrelic-infra.service: Main process exited, code=exited, status=1/FAILURE Aug 27 02:02:41 syrin-office systemd[1]: newrelic-infra.service: Failed with result 'exit-code'. Aug 27 02:02:44 syrin-office dhcpcd[639]: wlan0: DHCP lease expired Aug 27 02:02:44 syrin-office dhcpcd[639]: wlan0: soliciting a DHCP lease Aug 27 02:02:45 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.37 Aug 27 02:02:45 syrin-office dhcpcd[639]: wlan0: offered 192.168.0.33 from 192.168.0.1 Aug 27 02:02:46 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:46 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:50 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.37 Aug 27 02:02:50 syrin-office dhcpcd[639]: wlan0: probing address 192.168.0.33/24 Aug 27 02:02:51 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:51 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.36 Aug 27 02:02:55 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.37 Aug 27 02:02:55 syrin-office dhcpcd[639]: wlan0: leased 192.168.0.33 for 600 seconds Aug 27 02:02:55 syrin-office avahi-daemon[1206]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.33. Aug 27 02:02:55 syrin-office dhcpcd[639]: wlan0: adding route to 192.168.0.0/24 Aug 27 02:02:55 syrin-office avahi-daemon[1206]: New relevant interface wlan0.IPv4 for mDNS. Aug 27 02:02:55 syrin-office avahi-daemon[1206]: Registering new address record for 192.168.0.33 on wlan0.IPv4. Aug 27 02:02:55 syrin-office systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 27 02:02:55 syrin-office systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Aug 27 02:02:55 syrin-office systemd[1]: welcome.service: Deactivated successfully. Aug 27 02:02:55 syrin-office systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 27 02:02:55 syrin-office systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 27 02:02:55 syrin-office dhcpcd[639]: wlan0: adding default route via 192.168.0.1 Aug 27 02:02:55 syrin-office systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 27 02:02:55 syrin-office welcome[3406]: Resolved ip:[1] 192.168.0.33 Aug 27 02:02:55 syrin-office systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 27 02:02:55 syrin-office systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 27 02:02:56 syrin-office volumio[1052]: info: Discovery: adding c00df1c3-8140-474c-b29d-4062ba3aee5e Aug 27 02:02:56 syrin-office volumio[1052]: info: Discovery: Found device Jubilee Aug 27 02:02:56 syrin-office volumio[1052]: info: Discovery: Connecting to remote: 192.168.0.36 Aug 27 02:02:56 syrin-office volumio[1052]: verbose: New Socket.io Connection to 192.168.0.33:3000 from 192.168.0.36 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 27 02:02:56 syrin-office volumio[1052]: info: Received Get System Info Aug 27 02:02:56 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 02:02:56 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 02:02:56 syrin-office volumio[1052]: info: Discovery: Getting this device information Aug 27 02:02:56 syrin-office volumio[1052]: info: CoreCommandRouter::volumioGetState Aug 27 02:02:56 syrin-office volumio[1052]: info: CorePlayQueue::getTrack 0 Aug 27 02:02:56 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 02:02:56 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 02:02:56 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 02:02:56 syrin-office volumio[1052]: info: Discovery: Connected to remote: 192.168.0.36 Aug 27 02:02:56 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Aug 27 02:02:56 syrin-office volumio[1052]: info: CoreCommandRouter::volumioGetState Aug 27 02:02:56 syrin-office volumio[1052]: info: CorePlayQueue::getTrack 0 Aug 27 02:02:56 syrin-office volumio[1052]: info: Discovery: adding 78045b7f-7ad1-4bd2-8b76-4df785daa570 Aug 27 02:02:56 syrin-office volumio[1052]: info: Discovery: Found device Syrin-office Aug 27 02:02:56 syrin-office volumio[1052]: info: CoreCommandRouter::volumioGetState Aug 27 02:02:56 syrin-office volumio[1052]: info: CorePlayQueue::getTrack 0 Aug 27 02:02:57 syrin-office ntpd[776]: IO: Listen normally on 5 wlan0 192.168.0.33:123 Aug 27 02:02:57 syrin-office ntpd[776]: IO: new interface(s) found: waking up resolver Aug 27 02:02:59 syrin-office go-librespot[7667]: time="2026-08-27T02:02:59-07:00" level=error msg="did not receive last pong ack from accesspoint, 283s passed" Aug 27 02:02:59 syrin-office go-librespot[7667]: panic: runtime error: invalid memory address or nil pointer dereference Aug 27 02:02:59 syrin-office go-librespot[7667]: [signal SIGSEGV: segmentation violation code=0x1 addr=0xc pc=0x4f7ef0] Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 62 gp=0x24fa908 m=8 mp=0x2107008 [running]: Aug 27 02:02:59 syrin-office go-librespot[7667]: panic({0x841d88, 0xfa0250}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/panic.go:802 +0x148 fp=0x229ff20 sp=0x229fecc pc=0xa92ec Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.panicmem(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/panic.go:262 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.sigpanic() Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/signal_unix.go:925 +0x384 fp=0x229ff50 sp=0x229ff20 pc=0xabca4 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x20d38c8) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/ap/ap.go:380 +0x284 fp=0x229ffe4 sp=0x229ff54 pc=0x4f7ef0 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/ap/ap.go:277 +0x28 fp=0x229ffec sp=0x229ffe4 pc=0x4f73c4 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x229ffec sp=0x229ffec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 30 Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/ap/ap.go:277 +0x15c Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 1 gp=0x2002128 m=nil [select, 24310 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x205dce0 sp=0x205dccc pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.selectgo(0x205ddf0, 0x205ddbc, 0x0, 0x0, 0x2, 0x1) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x205dd90 sp=0x205dce0 pc=0x85000 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x20ec000, 0x205de90) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/zeroconf/zeroconf.go:300 +0x228 fp=0x205de38 sp=0x205dd90 pc=0x62f43c Aug 27 02:02:59 syrin-office go-librespot[7667]: main.(*App).withAppPlayer(0x20cc300, {0xa3da1c, 0x1005090}, 0x209d360) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/cmd/daemon/main.go:340 +0x5dc fp=0x205ded0 sp=0x205de38 pc=0x6bcc60 Aug 27 02:02:59 syrin-office go-librespot[7667]: main.(*App).withCredentials(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/cmd/daemon/main.go:209 Aug 27 02:02:59 syrin-office go-librespot[7667]: main.(*App).SpotifyToken(0x20cc300, {0xa3da1c, 0x1005090}, {0x20ac640, 0x19}, {0x20aa140, 0x13b}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x205df00 sp=0x205ded0 pc=0x6bc02c Aug 27 02:02:59 syrin-office go-librespot[7667]: main.main() Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/cmd/daemon/main.go:620 +0x660 fp=0x205dfa8 sp=0x205df00 pc=0x6bf56c Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.main() Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x205dfec sp=0x205dfa8 pc=0x6f390 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x205dfec sp=0x205dfec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 2 gp=0x20027e8 m=nil [force gc (idle), 3 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x97746c, 0xff8fa8, 0x11, 0xa, 0x1) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2048fd4 sp=0x2048fc0 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goparkunlock(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:466 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.forcegchelper() Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x2048fec sp=0x2048fd4 pc=0x6f7f4 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2048fec sp=0x2048fec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by runtime.init.6 in goroutine 1 Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:361 +0x1c Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 3 gp=0x2002b48 m=nil [GC sweep wait]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x97746c, 0xff9850, 0xc, 0x9, 0x1) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20497c4 sp=0x20497b0 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goparkunlock(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:466 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.bgsweep(0x202e050) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x20497e4 sp=0x20497c4 pc=0x5768c Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gcenable.gowrap1() Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x20497ec sp=0x20497e4 pc=0x46c3c Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20497ec sp=0x20497ec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by runtime.gcenable in goroutine 1 Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgc.go:212 +0x74 Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 4 gp=0x2002c68 m=nil [GC scavenge wait]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x97746c, 0xffa170, 0xd, 0xa, 0x2) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2049fb4 sp=0x2049fa0 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goparkunlock(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:466 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.(*scavengerState).park(0xffa170) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2049fc8 sp=0x2049fb4 pc=0x54a28 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.bgscavenge(0x202e050) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2049fe4 sp=0x2049fc8 pc=0x5516c Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gcenable.gowrap2() Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x2049fec sp=0x2049fe4 pc=0x46be8 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2049fec sp=0x2049fec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by runtime.gcenable in goroutine 1 Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgc.go:213 +0xbc Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 18 gp=0x2102008 m=nil [GOMAXPROCS updater (idle), 24310 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x97746c, 0xff95e8, 0x12, 0xa, 0x1) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20447a4 sp=0x2044790 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goparkunlock(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:466 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.updateMaxProcsGoroutine() Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x20447ec sp=0x20447a4 pc=0x7f174 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20447ec sp=0x20447ec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:6708 +0x40 Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 19 gp=0x2102368 m=nil [finalizer wait, 24308 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x977314, 0x1005180, 0x10, 0xa, 0x1) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2044f88 sp=0x2044f74 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.runFinalizers() Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x2044fec sp=0x2044f88 pc=0x45888 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2044fec sp=0x2044fec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by runtime.createfing in goroutine 1 Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 20 gp=0x21e18c8 m=nil [cleanup wait, 985 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x97746c, 0xffa348, 0x2e, 0xa, 0x1) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20487a4 sp=0x2048790 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goparkunlock(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:466 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.(*cleanupQueue).dequeue(0xffa2e8) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x20487c4 sp=0x20487a4 pc=0x41cb0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.runCleanups() Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x20487ec sp=0x20487c4 pc=0x4284c Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20487ec sp=0x20487ec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 36 gp=0x2083328 m=nil [IO wait, 24310 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x97745c, 0x76097e10, 0x2, 0x2, 0x5) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x205bcf0 sp=0x205bcdc pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.netpollblock(0x76097e00, 0x72, 0x0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x205bd08 sp=0x205bcf0 pc=0x675a0 Aug 27 02:02:59 syrin-office go-librespot[7667]: internal/poll.runtime_pollWait(0x76097e00, 0x72) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x205bd1c sp=0x205bd08 pc=0xa8864 Aug 27 02:02:59 syrin-office go-librespot[7667]: internal/poll.(*pollDesc).wait(0x20bb1e8, 0x72, 0x0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x205bd30 sp=0x205bd1c pc=0x136044 Aug 27 02:02:59 syrin-office go-librespot[7667]: internal/poll.(*pollDesc).waitRead(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Aug 27 02:02:59 syrin-office go-librespot[7667]: internal/poll.(*FD).Accept(0x20bb1d0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x205bd78 sp=0x205bd30 pc=0x13a800 Aug 27 02:02:59 syrin-office go-librespot[7667]: net.(*netFD).accept(0x20bb1d0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x205bde0 sp=0x205bd78 pc=0x1abc28 Aug 27 02:02:59 syrin-office go-librespot[7667]: net.(*TCPListener).accept(0x20be360) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x205be34 sp=0x205bde0 pc=0x1c3554 Aug 27 02:02:59 syrin-office go-librespot[7667]: net.(*TCPListener).Accept(0x20be360) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x205be50 sp=0x205be34 pc=0x1c259c Aug 27 02:02:59 syrin-office go-librespot[7667]: net/http.(*onceCloseListener).Accept(0x206e0a0) Aug 27 02:02:59 syrin-office go-librespot[7667]: :1 +0x34 fp=0x205be68 sp=0x205be50 pc=0x367f9c Aug 27 02:02:59 syrin-office go-librespot[7667]: net/http.(*Server).Serve(0x230c008, {0xa3d218, 0x20be360}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x205bf00 sp=0x205be68 pc=0x341154 Aug 27 02:02:59 syrin-office go-librespot[7667]: net/http.Serve(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/http/server.go:2971 Aug 27 02:02:59 syrin-office go-librespot[7667]: main.(*ConcreteApiServer).serve(0x20c02a0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x205bfe4 sp=0x205bf00 pc=0x6b17bc Aug 27 02:02:59 syrin-office go-librespot[7667]: main.NewApiServer.gowrap1() Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x205bfec sp=0x205bfe4 pc=0x6b04f4 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x205bfec sp=0x205bfec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by main.NewApiServer in goroutine 1 Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/cmd/daemon/api_server.go:332 +0x310 Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 45 gp=0x2083448 m=nil [IO wait, 185 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x97745c, 0x76097a10, 0x2, 0x2, 0x5) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x22875ac sp=0x2287598 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.netpollblock(0x76097a00, 0x72, 0x0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x22875c4 sp=0x22875ac pc=0x675a0 Aug 27 02:02:59 syrin-office go-librespot[7667]: internal/poll.runtime_pollWait(0x76097a00, 0x72) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x22875d8 sp=0x22875c4 pc=0xa8864 Aug 27 02:02:59 syrin-office go-librespot[7667]: internal/poll.(*pollDesc).wait(0x20c6068, 0x72, 0x0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x22875ec sp=0x22875d8 pc=0x136044 Aug 27 02:02:59 syrin-office go-librespot[7667]: internal/poll.(*pollDesc).waitRead(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Aug 27 02:02:59 syrin-office go-librespot[7667]: internal/poll.(*FD).Accept(0x20c6050) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x2287634 sp=0x22875ec pc=0x13a800 Aug 27 02:02:59 syrin-office go-librespot[7667]: net.(*netFD).accept(0x20c6050) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x228769c sp=0x2287634 pc=0x1abc28 Aug 27 02:02:59 syrin-office go-librespot[7667]: net.(*TCPListener).accept(0x2014060) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x22876f0 sp=0x228769c pc=0x1c3554 Aug 27 02:02:59 syrin-office go-librespot[7667]: net.(*TCPListener).Accept(0x2014060) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x228770c sp=0x22876f0 pc=0x1c259c Aug 27 02:02:59 syrin-office go-librespot[7667]: net/http.(*onceCloseListener).Accept(0x206f5e0) Aug 27 02:02:59 syrin-office go-librespot[7667]: :1 +0x34 fp=0x2287724 sp=0x228770c pc=0x367f9c Aug 27 02:02:59 syrin-office go-librespot[7667]: net/http.(*Server).Serve(0x230c378, {0xa3d218, 0x2014060}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x22877bc sp=0x2287724 pc=0x341154 Aug 27 02:02:59 syrin-office go-librespot[7667]: net/http.Serve(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/http/server.go:2971 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/zeroconf/zeroconf.go:297 +0x8c fp=0x22877ec sp=0x22877bc pc=0x62f538 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x22877ec sp=0x22877ec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/zeroconf/zeroconf.go:297 +0x160 Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 46 gp=0x2083688 m=nil [IO wait, 24310 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x97745c, 0x76074e10, 0x2, 0x2, 0x5) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20dd908 sp=0x20dd8f4 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.netpollblock(0x76074e00, 0x72, 0x0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x20dd920 sp=0x20dd908 pc=0x675a0 Aug 27 02:02:59 syrin-office go-librespot[7667]: internal/poll.runtime_pollWait(0x76074e00, 0x72) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x20dd934 sp=0x20dd920 pc=0xa8864 Aug 27 02:02:59 syrin-office go-librespot[7667]: internal/poll.(*pollDesc).wait(0x2400bf8, 0x72, 0x0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20dd948 sp=0x20dd934 pc=0x136044 Aug 27 02:02:59 syrin-office go-librespot[7667]: internal/poll.(*pollDesc).waitRead(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Aug 27 02:02:59 syrin-office go-librespot[7667]: internal/poll.(*FD).Read(0x2400be0, {0x2205000, 0x1000, 0x1000}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x20dd990 sp=0x20dd948 pc=0x1371c4 Aug 27 02:02:59 syrin-office go-librespot[7667]: net.(*netFD).Read(0x2400be0, {0x2205000, 0x1000, 0x1000}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x20dd9bc sp=0x20dd990 pc=0x1a9e78 Aug 27 02:02:59 syrin-office go-librespot[7667]: net.(*conn).Read(0x23d4000, {0x2205000, 0x1000, 0x1000}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x20dd9e8 sp=0x20dd9bc pc=0x1b967c Aug 27 02:02:59 syrin-office go-librespot[7667]: io.(*multiReader).Read(0x241e070, {0x2205000, 0x1000, 0x1000}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x20dda14 sp=0x20dd9e8 pc=0xefb88 Aug 27 02:02:59 syrin-office go-librespot[7667]: bufio.(*Reader).fill(0x2292060) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x20dda38 sp=0x20dda14 pc=0x2b6c1c Aug 27 02:02:59 syrin-office go-librespot[7667]: bufio.(*Reader).ReadByte(0x2292060) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x20dda44 sp=0x20dda38 pc=0x2b7498 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/coder/websocket.readFrameHeader(0x2292060, {0x217ec38, 0x8, 0x8}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x20dda84 sp=0x20dda44 pc=0x372a08 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/coder/websocket.(*Conn).readFrameHeader(0x217ec08, {0xa3da00, 0x1005090}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x20ddb08 sp=0x20dda84 pc=0x375bd4 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/coder/websocket.(*Conn).readLoop(0x217ec08, {0xa3da00, 0x1005090}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x20ddbcc sp=0x20ddb08 pc=0x375390 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/coder/websocket.(*Conn).reader(0x217ec08, {0xa3da00, 0x1005090}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x20ddc50 sp=0x20ddbcc pc=0x376798 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/coder/websocket.(*Conn).Reader(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/coder/websocket.(*Conn).Read(0x217ec08, {0xa3da00, 0x1005090}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x20ddc78 sp=0x20ddc50 pc=0x374944 Aug 27 02:02:59 syrin-office go-librespot[7667]: main.(*ConcreteApiServer).serve.func17({0xa3d2d8, 0x2016218}, 0x2016008) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/cmd/daemon/api_server.go:636 +0x3b4 fp=0x20ddcfc sp=0x20ddc78 pc=0x6b2040 Aug 27 02:02:59 syrin-office go-librespot[7667]: net/http.HandlerFunc.ServeHTTP(0x20321a8, {0xa3d2d8, 0x2016218}, 0x2016008) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x20ddd0c sp=0x20ddcfc pc=0x33d7d8 Aug 27 02:02:59 syrin-office go-librespot[7667]: net/http.(*ServeMux).ServeHTTP(0x207a000, {0xa3d2d8, 0x2016218}, 0x2016008) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x20ddd4c sp=0x20ddd0c pc=0x33f6c8 Aug 27 02:02:59 syrin-office go-librespot[7667]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d2d8, 0x2016218}, 0x2016008) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x20ddd74 sp=0x20ddd4c pc=0x6b19b8 Aug 27 02:02:59 syrin-office go-librespot[7667]: net/http.HandlerFunc.ServeHTTP(0x2010170, {0xa3d2d8, 0x2016218}, 0x2016008) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x20ddd84 sp=0x20ddd74 pc=0x33d7d8 Aug 27 02:02:59 syrin-office go-librespot[7667]: net/http.serverHandler.ServeHTTP({0x230c008}, {0xa3d2d8, 0x2016218}, 0x2016008) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x20ddda0 sp=0x20ddd84 pc=0x35cb44 Aug 27 02:02:59 syrin-office go-librespot[7667]: net/http.(*conn).serve(0x20ec240, {0xa3da38, 0x20281f8}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x20ddfdc sp=0x20ddda0 pc=0x33b894 Aug 27 02:02:59 syrin-office go-librespot[7667]: net/http.(*Server).Serve.gowrap3() Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x20ddfec sp=0x20ddfdc pc=0x3415cc Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20ddfec sp=0x20ddfec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by net/http.(*Server).Serve in goroutine 36 Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/http/server.go:3493 +0x42c Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 5 gp=0x2003208 m=nil [GC worker (idle), 23021 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x977320, 0x2484200, 0x1c, 0xa, 0x0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24dcf88 sp=0x24dcf74 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gcBgMarkWorker(0x2301a80) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x24dcfe4 sp=0x24dcf88 pc=0x49f34 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gcBgMarkStartWorkers.gowrap1() Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x24dcfec sp=0x24dcfe4 pc=0x49e14 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24dcfec sp=0x24dcfec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by runtime.gcBgMarkStartWorkers in goroutine 21 Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 6 gp=0x2003328 m=nil [GC worker (idle), 15929 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x977320, 0x2484400, 0x1c, 0xa, 0x0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24de788 sp=0x24de774 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gcBgMarkWorker(0x2301a80) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x24de7e4 sp=0x24de788 pc=0x49f34 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gcBgMarkStartWorkers.gowrap1() Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x24de7ec sp=0x24de7e4 pc=0x49e14 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24de7ec sp=0x24de7ec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by runtime.gcBgMarkStartWorkers in goroutine 21 Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 7 gp=0x2003448 m=nil [GC worker (idle), 3 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x977320, 0x2484600, 0x1c, 0xa, 0x0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x22c3f88 sp=0x22c3f74 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gcBgMarkWorker(0x2301a80) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x22c3fe4 sp=0x22c3f88 pc=0x49f34 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gcBgMarkStartWorkers.gowrap1() Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x22c3fec sp=0x22c3fe4 pc=0x49e14 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x22c3fec sp=0x22c3fec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by runtime.gcBgMarkStartWorkers in goroutine 21 Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 8 gp=0x2003568 m=nil [GC worker (idle), 23021 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x977320, 0x2484800, 0x1c, 0xa, 0x0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2289f88 sp=0x2289f74 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gcBgMarkWorker(0x2301a80) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x2289fe4 sp=0x2289f88 pc=0x49f34 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gcBgMarkStartWorkers.gowrap1() Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x2289fec sp=0x2289fe4 pc=0x49e14 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2289fec sp=0x2289fec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by runtime.gcBgMarkStartWorkers in goroutine 21 Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 23 gp=0x2082248 m=nil [chan receive, 24310 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x9772f4, 0x2422978, 0xe, 0x7, 0x2) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2288778 sp=0x2288764 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.chanrecv(0x2422940, 0x0, 0x1) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x22887c0 sp=0x2288778 pc=0x3414c Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.chanrecv1(0x2422940, 0x0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/chan.go:509 +0x20 fp=0x22887d4 sp=0x22887c0 pc=0x33bcc Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/godbus/dbus/v5.newConn.func1() Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:303 +0x40 fp=0x22887ec sp=0x22887d4 pc=0x4aa8ec Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x22887ec sp=0x22887ec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by github.com/godbus/dbus/v5.newConn in goroutine 1 Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:302 +0x52c Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 24 gp=0x2082368 m=nil [IO wait, 24310 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x97745c, 0x76097810, 0x2, 0x2, 0x5) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2056cbc sp=0x2056ca8 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.netpollblock(0x76097800, 0x72, 0x0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2056cd4 sp=0x2056cbc pc=0x675a0 Aug 27 02:02:59 syrin-office go-librespot[7667]: internal/poll.runtime_pollWait(0x76097800, 0x72) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2056ce8 sp=0x2056cd4 pc=0xa8864 Aug 27 02:02:59 syrin-office go-librespot[7667]: internal/poll.(*pollDesc).wait(0x20c60b8, 0x72, 0x0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2056cfc sp=0x2056ce8 pc=0x136044 Aug 27 02:02:59 syrin-office go-librespot[7667]: internal/poll.(*pollDesc).waitRead(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Aug 27 02:02:59 syrin-office go-librespot[7667]: internal/poll.(*FD).ReadMsg(0x20c60a0, {0x2013bf0, 0x10, 0x10}, {0x218c018, 0x1000, 0x1000}, 0x40000000) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/internal/poll/fd_unix.go:295 +0x2c0 fp=0x2056d6c sp=0x2056cfc pc=0x1381bc Aug 27 02:02:59 syrin-office go-librespot[7667]: net.(*netFD).readMsg(0x20c60a0, {0x2013bf0, 0x10, 0x10}, {0x218c018, 0x1000, 0x1000}, 0x40000000) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/fd_posix.go:91 +0x58 fp=0x2056dc8 sp=0x2056d6c pc=0x1aa2c8 Aug 27 02:02:59 syrin-office go-librespot[7667]: net.(*UnixConn).readMsg(0x2094258, {0x2013bf0, 0x10, 0x10}, {0x218c018, 0x1000, 0x1000}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/unixsock_posix.go:115 +0x58 fp=0x2056e28 sp=0x2056dc8 pc=0x1c9d80 Aug 27 02:02:59 syrin-office go-librespot[7667]: net.(*UnixConn).ReadMsgUnix(0x2094258, {0x2013bf0, 0x10, 0x10}, {0x218c018, 0x1000, 0x1000}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/net/unixsock.go:143 +0x58 fp=0x2056e78 sp=0x2056e28 pc=0x1c820c Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/godbus/dbus/v5.(*oobReader).Read(0x218c008, {0x2013bf0, 0x10, 0x10}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:41 +0x50 fp=0x2056ec8 sp=0x2056e78 pc=0x4c2af0 Aug 27 02:02:59 syrin-office go-librespot[7667]: io.ReadAtLeast({0xa39d58, 0x218c008}, {0x2013bf0, 0x10, 0x10}, 0x10) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2056ef4 sp=0x2056ec8 pc=0xee954 Aug 27 02:02:59 syrin-office go-librespot[7667]: io.ReadFull(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/io/io.go:354 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/godbus/dbus/v5.(*unixTransport).ReadMessage(0x2010110) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:124 +0x224 fp=0x2056fa0 sp=0x2056ef4 pc=0x4c32c4 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/godbus/dbus/v5.(*Conn).inWorker(0x20cc180) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:390 +0x44 fp=0x2056fe4 sp=0x2056fa0 pc=0x4aaef4 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/godbus/dbus/v5.(*Conn).Auth.gowrap1() Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x28 fp=0x2056fec sp=0x2056fe4 pc=0x4a8318 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2056fec sp=0x2056fec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by github.com/godbus/dbus/v5.(*Conn).Auth in goroutine 1 Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x77c Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 31 gp=0x2082488 m=nil [chan receive, 24310 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x9772f4, 0x20d0178, 0xe, 0x7, 0x2) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2287f3c sp=0x2287f28 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.chanrecv(0x20d0140, 0x2287fd0, 0x1) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x2287f84 sp=0x2287f3c pc=0x3414c Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.chanrecv2(0x20d0140, 0x2287fd0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x2287f98 sp=0x2287f84 pc=0x33bf0 Aug 27 02:02:59 syrin-office go-librespot[7667]: main.(*App).withAppPlayer.func1() Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/cmd/daemon/main.go:284 +0x68 fp=0x2287fec sp=0x2287f98 pc=0x6bd918 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2287fec sp=0x2287fec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by main.(*App).withAppPlayer in goroutine 1 Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/cmd/daemon/main.go:281 +0x4f8 Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 30 gp=0x21e1e68 m=nil [runnable]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.selectgo(0x20f1f70, 0x20f1b20, 0x0, 0x0, 0xa, 0x1) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/select.go:122 +0x14ac fp=0x20f19e0 sp=0x20f19e0 pc=0x85860 Aug 27 02:02:59 syrin-office go-librespot[7667]: main.(*AppPlayer).Run(0x240fd10, {0xa3da1c, 0x1005090}, 0x21046c0, 0x2104700) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/cmd/daemon/player.go:661 +0x368 fp=0x20f1fd4 sp=0x20f19e0 pc=0x6c5950 Aug 27 02:02:59 syrin-office go-librespot[7667]: main.(*App).withAppPlayer.gowrap1() Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/cmd/daemon/main.go:274 +0x48 fp=0x20f1fec sp=0x20f1fd4 pc=0x6bd9f0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20f1fec sp=0x20f1fec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by main.(*App).withAppPlayer in goroutine 1 Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/cmd/daemon/main.go:274 +0x46c Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 29 gp=0x24fa128 m=nil [select, 24310 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2284674 sp=0x2284660 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.selectgo(0x2284780, 0x228474c, 0x0, 0x0, 0x3, 0x1) Aug 27 02:02:59 syrin-office volumio[1052]: info: Connection to go-librespot Websocket closed Aug 27 02:02:59 syrin-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2284724 sp=0x2284674 pc=0x85000 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x20c6d70) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/player/player.go:215 +0x1f4 fp=0x22847e4 sp=0x2284724 pc=0x582954 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/player/player.go:197 +0x28 fp=0x22847ec sp=0x22847e4 pc=0x58253c Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x22847ec sp=0x22847ec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/player/player.go:197 +0x220 Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 32 gp=0x20825a8 m=nil [chan receive, 24310 minutes]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x9772f4, 0x20d0138, 0xe, 0x7, 0x2) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2284f40 sp=0x2284f2c pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.chanrecv(0x20d0100, 0x2284fe0, 0x1) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x2284f88 sp=0x2284f40 pc=0x3414c Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.chanrecv2(0x20d0100, 0x2284fe0) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x2284f9c sp=0x2284f88 pc=0x33bf0 Aug 27 02:02:59 syrin-office go-librespot[7667]: main.(*App).withAppPlayer.func2() Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/cmd/daemon/main.go:300 +0x6c fp=0x2284fec sp=0x2284f9c pc=0x6bd47c Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2284fec sp=0x2284fec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by main.(*App).withAppPlayer in goroutine 1 Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/cmd/daemon/main.go:297 +0x598 Aug 27 02:02:59 syrin-office go-librespot[7667]: goroutine 2770 gp=0x2392908 m=nil [select]: Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x229ad78 sp=0x229ad64 pc=0xa97c0 Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.selectgo(0x229ae7c, 0x229ae50, 0x0, 0x0, 0x2, 0x1) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x229ae28 sp=0x229ad78 pc=0x85000 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x229aecc, {0xa3c8c0, 0x202a960}, 0x0, {0x0, 0x0}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:112 +0x244 fp=0x229aea4 sp=0x229ae28 pc=0x4eb7bc Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x229af5c, {0xa3c8c0, 0x202a960}, 0x0, {0x0, 0x0}) Aug 27 02:02:59 syrin-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x5c fp=0x229aed4 sp=0x229aea4 pc=0x4eb180 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/cenkalti/backoff/v4.RetryNotify(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/cenkalti/backoff/v4.Retry(...) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x20d38c8) Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/ap/ap.go:335 +0x434 fp=0x229afe4 sp=0x229aed4 pc=0x4f7878 Aug 27 02:02:59 syrin-office go-librespot[7667]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/ap/ap.go:403 +0x28 fp=0x229afec sp=0x229afe4 pc=0x4f81cc Aug 27 02:02:59 syrin-office go-librespot[7667]: runtime.goexit({}) Aug 27 02:02:59 syrin-office go-librespot[7667]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x229afec sp=0x229afec pc=0xb0874 Aug 27 02:02:59 syrin-office go-librespot[7667]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 2742 Aug 27 02:02:59 syrin-office go-librespot[7667]: /src/ap/ap.go:403 +0x120 Aug 27 02:02:59 syrin-office systemd[1]: go-librespot-daemon.service: Consumed 2w 1d 23h 36min 40.896s CPU time. Aug 27 02:02:59 syrin-office go-librespot[7666]: Aborted Aug 27 02:03:01 syrin-office systemd[1]: newrelic-infra.service: Scheduled restart job, restart counter is at 94979. Aug 27 02:03:01 syrin-office systemd[1]: Stopped newrelic-infra.service - New Relic Infrastructure Agent. Aug 27 02:03:02 syrin-office systemd[1]: Started newrelic-infra.service - New Relic Infrastructure Agent. Aug 27 02:03:02 syrin-office newrelic-infra-service[3420]: time="2026-08-27T02:03:02-07:00" level=info msg="Creating service..." Aug 27 02:03:02 syrin-office newrelic-infra-service[3426]: time="2026-08-27T02:03:02-07:00" level=error msg="Can't open log file." action=configureLogRedirection component="New Relic Infrastructure Agent" error="open /var/log/newrelic-infra/newrelic-infra.log: no such file or directory" Aug 27 02:03:02 syrin-office newrelic-infra-service[3420]: time="2026-08-27T02:03:02-07:00" level=info msg="child process exited" exit_code=1 Aug 27 02:03:02 syrin-office systemd[1]: newrelic-infra.service: Main process exited, code=exited, status=1/FAILURE Aug 27 02:03:02 syrin-office systemd[1]: newrelic-infra.service: Failed with result 'exit-code'. Aug 27 02:03:02 syrin-office volumio[1052]: info: Initializing connection to go-librespot Websocket Aug 27 02:03:02 syrin-office volumio[1052]: info: Volumio Network Manager: Network status updated: 2 Aug 27 02:03:02 syrin-office volumio[1052]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 02:03:02 syrin-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Aug 27 02:03:02 syrin-office systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 02:03:02 syrin-office systemd[1]: go-librespot-daemon.service: Consumed 2w 1d 23h 36min 40.896s CPU time. Aug 27 02:03:03 syrin-office systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 02:03:03 syrin-office go-librespot[3452]: go-librespot daemon starting... Aug 27 02:03:03 syrin-office volumio[1052]: info: Discovery: Remote connection error: 192.168.0.37 Aug 27 02:03:03 syrin-office go-librespot[3453]: time="2026-08-27T02:03:03-07:00" level=info msg="running go-librespot 0.7.1" Aug 27 02:03:03 syrin-office go-librespot[3453]: time="2026-08-27T02:03:03-07:00" level=debug msg="app state loaded" Aug 27 02:03:03 syrin-office go-librespot[3453]: time="2026-08-27T02:03:03-07:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 02:03:03 syrin-office go-librespot[3453]: time="2026-08-27T02:03:03-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 02:03:03 syrin-office go-librespot[3453]: time="2026-08-27T02:03:03-07:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 02:03:03 syrin-office go-librespot[3453]: time="2026-08-27T02:03:03-07:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 02:03:03 syrin-office go-librespot[3453]: time="2026-08-27T02:03:03-07:00" level=info msg="zeroconf server listening on port 33373" Aug 27 02:03:03 syrin-office go-librespot[3453]: time="2026-08-27T02:03:03-07:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 02:03:04 syrin-office go-librespot[3453]: time="2026-08-27T02:03:04-07:00" level=debug msg="obtained new client token: AAGuHy+dw+IngRWOE1FJvBX2iiA4mGPfx7FBVVgz4P3Rai8cH4U3L3V0D9xde4Z+Bxgo4Rdy/Ad1JDeF92l5P1RVzNWH50vva+9kCmm6cCZuMYtyJU/DPj61dN3vInfEiUclkfZjUIagAqlVjiCcDwFy1pVDQoTiU5SeJQyg8jgpoMLE7PF9if+QSO2d+PbmFJPNVk9JEfUbrqhiDUYlZ8cYFwHvZCURmRXUZsoWM2+2H0Iz/d83" Aug 27 02:03:04 syrin-office go-librespot[3453]: time="2026-08-27T02:03:04-07:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Aug 27 02:03:04 syrin-office go-librespot[3453]: time="2026-08-27T02:03:04-07:00" level=debug msg="completed keyexchange" Aug 27 02:03:04 syrin-office go-librespot[3453]: time="2026-08-27T02:03:04-07:00" level=debug msg="completed challenge" Aug 27 02:03:04 syrin-office go-librespot[3453]: time="2026-08-27T02:03:04-07:00" level=info msg="authenticated AP" username="l3*********************iy" Aug 27 02:03:04 syrin-office go-librespot[3453]: time="2026-08-27T02:03:04-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 02:03:04 syrin-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 02:03:04 syrin-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 02:03:05 syrin-office volumio[1052]: info: Initializing connection to go-librespot Websocket Aug 27 02:03:05 syrin-office volumio[1052]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 02:03:07 syrin-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Aug 27 02:03:07 syrin-office systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 02:03:08 syrin-office systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 02:03:08 syrin-office go-librespot[3462]: go-librespot daemon starting... Aug 27 02:03:08 syrin-office go-librespot[3463]: time="2026-08-27T02:03:08-07:00" level=info msg="running go-librespot 0.7.1" Aug 27 02:03:08 syrin-office go-librespot[3463]: time="2026-08-27T02:03:08-07:00" level=debug msg="app state loaded" Aug 27 02:03:08 syrin-office go-librespot[3463]: time="2026-08-27T02:03:08-07:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 02:03:08 syrin-office go-librespot[3463]: time="2026-08-27T02:03:08-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 02:03:08 syrin-office go-librespot[3463]: time="2026-08-27T02:03:08-07:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 02:03:08 syrin-office go-librespot[3463]: time="2026-08-27T02:03:08-07:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 02:03:08 syrin-office go-librespot[3463]: time="2026-08-27T02:03:08-07:00" level=info msg="zeroconf server listening on port 44729" Aug 27 02:03:08 syrin-office go-librespot[3463]: time="2026-08-27T02:03:08-07:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 02:03:08 syrin-office volumio[1052]: info: Initializing connection to go-librespot Websocket Aug 27 02:03:08 syrin-office go-librespot[3463]: time="2026-08-27T02:03:08-07:00" level=debug msg="new websocket client" Aug 27 02:03:08 syrin-office volumio[1052]: info: Connection to go-librespot Websocket established Aug 27 02:03:08 syrin-office go-librespot[3463]: time="2026-08-27T02:03:08-07:00" level=debug msg="obtained new client token: AAHNVVmJaVIeHTcxo0yO1bUeY2mFydlqMeNq2Nxp4ew7GtQagScllV3inGJhxncw6L2prFeaJYXBTX9VY6+47lc3Re+ZFcBRgHkedVgZuMzex8p2BebXri4PuCUJQ/78tFhD7jK5C09PCF3Eob0uvEpkdErSUf3AeCShCJ8bl6plrSAmVz7sY1YtO1EOjvj1LUOfq+y7SMXSatq4sjtGnMich1qxiwCcoRqVnLFmTavnuhlAQ42rhfk=" Aug 27 02:03:08 syrin-office go-librespot[3463]: time="2026-08-27T02:03:08-07:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Aug 27 02:03:09 syrin-office go-librespot[3463]: time="2026-08-27T02:03:09-07:00" level=debug msg="completed keyexchange" Aug 27 02:03:09 syrin-office go-librespot[3463]: time="2026-08-27T02:03:09-07:00" level=debug msg="completed challenge" Aug 27 02:03:09 syrin-office go-librespot[3463]: time="2026-08-27T02:03:09-07:00" level=info msg="authenticated AP" username="l3*********************iy" Aug 27 02:03:09 syrin-office go-librespot[3463]: time="2026-08-27T02:03:09-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 02:03:09 syrin-office volumio[1052]: info: Connection to go-librespot Websocket closed Aug 27 02:03:09 syrin-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 02:03:09 syrin-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 02:03:10 syrin-office volumio[1052]: verbose: New Socket.io Connection to 192.168.0.33:3000 from 192.168.0.37 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 27 02:03:10 syrin-office volumio[1052]: info: Discovery: Connected to remote: 192.168.0.37 Aug 27 02:03:10 syrin-office volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Aug 27 02:03:10 syrin-office volumio[1052]: info: CoreCommandRouter::volumioGetState Aug 27 02:03:10 syrin-office volumio[1052]: info: CorePlayQueue::getTrack 0 Aug 27 02:03:11 syrin-office volumio[1052]: info: Discovery: adding 41662e3a-3331-4e1c-8719-4c1d5f8f0d66 Aug 27 02:03:11 syrin-office volumio[1052]: info: Discovery: Found device Jarvis Aug 27 02:03:11 syrin-office volumio[1052]: info: Getting Spotify volume Aug 27 02:03:11 syrin-office volumio[1052]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 02:03:11 syrin-office volumio[1052]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 02:03:11 syrin-office volumio[1052]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 27 02:03:11 syrin-office volumio[1052]: errno: -111, Aug 27 02:03:11 syrin-office volumio[1052]: code: 'ECONNREFUSED', Aug 27 02:03:11 syrin-office volumio[1052]: syscall: 'connect', Aug 27 02:03:11 syrin-office volumio[1052]: address: '127.0.0.1', Aug 27 02:03:11 syrin-office volumio[1052]: port: 9879, Aug 27 02:03:11 syrin-office volumio[1052]: response: undefined Aug 27 02:03:11 syrin-office volumio[1052]: } Aug 27 02:03:11 syrin-office volumio[1052]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 02:03:12 syrin-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Aug 27 02:03:12 syrin-office systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 02:03:12 syrin-office systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 02:03:12 syrin-office go-librespot[3477]: go-librespot daemon starting... Aug 27 02:03:12 syrin-office go-librespot[3482]: time="2026-08-27T02:03:12-07:00" level=info msg="running go-librespot 0.7.1" Aug 27 02:03:12 syrin-office go-librespot[3482]: time="2026-08-27T02:03:12-07:00" level=debug msg="app state loaded" Aug 27 02:03:12 syrin-office go-librespot[3482]: time="2026-08-27T02:03:12-07:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 02:03:13 syrin-office go-librespot[3482]: time="2026-08-27T02:03:13-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 27 02:03:13 syrin-office go-librespot[3482]: time="2026-08-27T02:03:13-07:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 27 02:03:13 syrin-office go-librespot[3482]: time="2026-08-27T02:03:13-07:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 27 02:03:13 syrin-office go-librespot[3482]: time="2026-08-27T02:03:13-07:00" level=info msg="zeroconf server listening on port 37929" Aug 27 02:03:13 syrin-office go-librespot[3482]: time="2026-08-27T02:03:13-07:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 02:03:13 syrin-office go-librespot[3482]: time="2026-08-27T02:03:13-07:00" level=debug msg="obtained new client token: AAGqkVKB/z3ocwy4/OXyLFg87bchivUh0KLlBuPNNtAg0hdnKlLaPscwosh2rJPuiQLQVMLdiYdBRGmXI/k44sle4XdiiIkaInOSTfC06v3ECzx1AXevrD4QUzNByeUopkC2GbcyAVaCM0ZJFZMQ/CuG4sh+nWozrj17URNm9s9vMVdSANRFr7UOYRizMI9LbK3/gJtpjRvFYQGmIvWQ29EEEkm8nC9O72QUw+cHhBbX4dtNHAYbpX0=" Aug 27 02:03:13 syrin-office go-librespot[3482]: time="2026-08-27T02:03:13-07:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Aug 27 02:03:13 syrin-office go-librespot[3482]: time="2026-08-27T02:03:13-07:00" level=debug msg="completed keyexchange" Aug 27 02:03:13 syrin-office go-librespot[3482]: time="2026-08-27T02:03:13-07:00" level=debug msg="completed challenge" Aug 27 02:03:13 syrin-office go-librespot[3482]: time="2026-08-27T02:03:13-07:00" level=info msg="authenticated AP" username="l3*********************iy" Aug 27 02:03:14 syrin-office go-librespot[3482]: time="2026-08-27T02:03:14-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 02:03:14 syrin-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 02:03:14 syrin-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"