-- Logs begin at Mon 2025-06-16 22:58:03 +03, end at Tue 2025-06-17 11:06:42 +03. -- Jun 17 11:05:04 emirs-speakers ntpd[30814]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 17 11:05:06 emirs-speakers volumiologrotate[809]: ls: cannot access "/var/log/samba/log.wb-EMIR'S": No such file or directory Jun 17 11:05:06 emirs-speakers volumiologrotate[809]: ls: cannot access 'SPEAKERS': No such file or directory Jun 17 11:05:20 emirs-speakers go-librespot[6941]: time="2025-06-17T11:05:20+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: i/o timeout" Jun 17 11:05:20 emirs-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 17 11:05:20 emirs-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 17 11:05:23 emirs-speakers systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 17 11:05:23 emirs-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Jun 17 11:05:23 emirs-speakers systemd[1]: Stopped go-librespot Daemon. Jun 17 11:05:23 emirs-speakers systemd[1]: Started go-librespot Daemon. Jun 17 11:05:23 emirs-speakers go-librespot[7067]: Librespot-go daemon starting... Jun 17 11:05:23 emirs-speakers go-librespot[7067]: time="2025-06-17T11:05:23+03:00" level=info msg="generated new device id: dc2017b3c4a435443ee1595a54cb00fdcea89217" Jun 17 11:05:23 emirs-speakers go-librespot[7067]: time="2025-06-17T11:05:23+03:00" level=debug msg="stored credentials found for 11142120369" Jun 17 11:05:28 emirs-speakers volumio[15339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 17 11:05:53 emirs-speakers go-librespot[7067]: time="2025-06-17T11:05:53+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: i/o timeout" Jun 17 11:05:53 emirs-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 17 11:05:53 emirs-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 17 11:05:56 emirs-speakers systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 17 11:05:56 emirs-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Jun 17 11:05:56 emirs-speakers systemd[1]: Stopped go-librespot Daemon. Jun 17 11:05:56 emirs-speakers systemd[1]: Started go-librespot Daemon. Jun 17 11:05:56 emirs-speakers go-librespot[7125]: Librespot-go daemon starting... Jun 17 11:05:56 emirs-speakers go-librespot[7125]: time="2025-06-17T11:05:56+03:00" level=info msg="generated new device id: 2eedc503449d1f1c1019cdcc070fc6674a9e8199" Jun 17 11:05:56 emirs-speakers go-librespot[7125]: time="2025-06-17T11:05:56+03:00" level=debug msg="stored credentials found for 11142120369" Jun 17 11:06:00 emirs-speakers ntpd[30814]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 17 11:06:05 emirs-speakers dhcpcd[1158]: wlan0: carrier lost Jun 17 11:06:05 emirs-speakers kernel: wlan0: disassociated from f4:92:bf:aa:14:95 (Reason: 34=DISASSOC_LOW_ACK) Jun 17 11:06:05 emirs-speakers avahi-daemon[825]: Withdrawing address record for 192.168.1.150 on wlan0. Jun 17 11:06:05 emirs-speakers avahi-daemon[825]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.150. Jun 17 11:06:05 emirs-speakers dhcpcd[1158]: wlan0: deleting route to 192.168.1.0/24 Jun 17 11:06:05 emirs-speakers dhcpcd[1158]: wlan0: deleting default route via 192.168.1.1 Jun 17 11:06:05 emirs-speakers avahi-daemon[825]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 17 11:06:05 emirs-speakers wpa_supplicant[1069]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f4:92:bf:aa:14:95 reason=34 Jun 17 11:06:05 emirs-speakers wpa_supplicant[1069]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 17 11:06:05 emirs-speakers wpa_supplicant[1069]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=TR Jun 17 11:06:05 emirs-speakers volumio[15339]: info: Discovery: A device disappeared from network Jun 17 11:06:06 emirs-speakers volumio[15339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 17 11:06:06 emirs-speakers go-librespot[7125]: time="2025-06-17T11:06:06+03:00" level=debug msg="new websocket client" Jun 17 11:06:06 emirs-speakers volumio[15339]: info: Connection to go-librespot Websocket established Jun 17 11:06:06 emirs-speakers volumio[15339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 17 11:06:06 emirs-speakers volumio[15339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 17 11:06:06 emirs-speakers volumio[15339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 17 11:06:06 emirs-speakers volumio[15339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 17 11:06:06 emirs-speakers volumio[15339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jun 17 11:06:06 emirs-speakers volumio[15339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jun 17 11:06:06 emirs-speakers volumio[15339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jun 17 11:06:06 emirs-speakers volumio[15339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jun 17 11:06:06 emirs-speakers volumio[15339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Jun 17 11:06:06 emirs-speakers volumio[15339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Jun 17 11:06:06 emirs-speakers volumio[15339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Jun 17 11:06:06 emirs-speakers volumio[15339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Jun 17 11:06:06 emirs-speakers volumio[15339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Jun 17 11:06:06 emirs-speakers volumio[15339]: info: CoreCommandRouter::volumioGetState Jun 17 11:06:06 emirs-speakers volumio[15339]: info: CorePlayQueue::getTrack 0 Jun 17 11:06:06 emirs-speakers volumio[15339]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Jun 17 11:06:06 emirs-speakers volumio[15339]: SPOTIFY: SPOTIFY VOLUME 100 Jun 17 11:06:06 emirs-speakers volumio[15339]: SPOTIFY: VOLUMIO VOLUME 26 Jun 17 11:06:06 emirs-speakers volumio[15339]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 17 11:06:06 emirs-speakers volumio[15339]: info: Setting Spotify Volume from Volumio: 26 Jun 17 11:06:06 emirs-speakers volumiologrotate[809]: ls: cannot access "/var/log/samba/log.wb-EMIR'S": No such file or directory Jun 17 11:06:06 emirs-speakers volumiologrotate[809]: ls: cannot access 'SPEAKERS': No such file or directory Jun 17 11:06:06 emirs-speakers wpa_supplicant[1069]: FT: Invalid key management type (2) Jun 17 11:06:06 emirs-speakers wpa_supplicant[1069]: wlan0: SME: Trying to authenticate with 60:22:32:66:d2:7a (SSID='Beykoz' freq=2472 MHz) Jun 17 11:06:06 emirs-speakers kernel: wlan0: authenticate with 60:22:32:66:d2:7a Jun 17 11:06:06 emirs-speakers kernel: wlan0: 80 MHz not supported, disabling VHT Jun 17 11:06:06 emirs-speakers kernel: wlan0: send auth to 60:22:32:66:d2:7a (try 1/3) Jun 17 11:06:06 emirs-speakers kernel: wlan0: send auth to 60:22:32:66:d2:7a (try 2/3) Jun 17 11:06:07 emirs-speakers kernel: wlan0: send auth to 60:22:32:66:d2:7a (try 3/3) Jun 17 11:06:07 emirs-speakers kernel: wlan0: authentication with 60:22:32:66:d2:7a timed out Jun 17 11:06:07 emirs-speakers volumio[15339]: SPOTIFY: SETTING SPOTIFY VOLUME 26 Jun 17 11:06:07 emirs-speakers volumio[15339]: info: Sending Spotify command with payload to local API: /player/volume Jun 17 11:06:08 emirs-speakers ntpd[30814]: Deleting interface #3 wlan0, 192.168.1.150#123, interface stats: received=245, sent=440, dropped=0, active_time=3318 secs Jun 17 11:06:08 emirs-speakers ntpd[30814]: 163.123.152.14 local addr 192.168.1.150 -> Jun 17 11:06:08 emirs-speakers ntpd[30814]: 23.150.40.242 local addr 192.168.1.150 -> Jun 17 11:06:08 emirs-speakers ntpd[30814]: 23.186.168.130 local addr 192.168.1.150 -> Jun 17 11:06:08 emirs-speakers ntpd[30814]: 23.150.41.123 local addr 192.168.1.150 -> Jun 17 11:06:08 emirs-speakers ntpd[30814]: 15.204.246.57 local addr 192.168.1.150 -> Jun 17 11:06:08 emirs-speakers ntpd[30814]: 72.30.35.88 local addr 192.168.1.150 -> Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers ntpd[30814]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 17 11:06:08 emirs-speakers wpa_supplicant[1069]: FT: Invalid key management type (2) Jun 17 11:06:08 emirs-speakers wpa_supplicant[1069]: wlan0: SME: Trying to authenticate with f4:92:bf:aa:14:95 (SSID='Beykoz' freq=2432 MHz) Jun 17 11:06:08 emirs-speakers kernel: wlan0: authenticate with f4:92:bf:aa:14:95 Jun 17 11:06:08 emirs-speakers kernel: wlan0: No basic rates, using min rate instead Jun 17 11:06:08 emirs-speakers kernel: wlan0: 80 MHz not supported, disabling VHT Jun 17 11:06:08 emirs-speakers kernel: wlan0: send auth to f4:92:bf:aa:14:95 (try 1/3) Jun 17 11:06:08 emirs-speakers wpa_supplicant[1069]: wlan0: Trying to associate with f4:92:bf:aa:14:95 (SSID='Beykoz' freq=2432 MHz) Jun 17 11:06:08 emirs-speakers kernel: wlan0: authenticated Jun 17 11:06:08 emirs-speakers kernel: wlan0: associate with f4:92:bf:aa:14:95 (try 1/3) Jun 17 11:06:08 emirs-speakers wpa_supplicant[1069]: wlan0: Associated with f4:92:bf:aa:14:95 Jun 17 11:06:08 emirs-speakers wpa_supplicant[1069]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 17 11:06:08 emirs-speakers wpa_supplicant[1069]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=TR Jun 17 11:06:08 emirs-speakers kernel: wlan0: RX AssocResp from f4:92:bf:aa:14:95 (capab=0x1011 status=0 aid=10) Jun 17 11:06:08 emirs-speakers kernel: wlan0: No basic rates, using min rate instead Jun 17 11:06:08 emirs-speakers kernel: wlan0: associated Jun 17 11:06:08 emirs-speakers kernel: wlan0: Limiting TX power to 20 (20 - 0) dBm as advertised by f4:92:bf:aa:14:95 Jun 17 11:06:09 emirs-speakers volumio[15339]: info: Getting Spotify volume Jun 17 11:06:09 emirs-speakers volumio[15339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Jun 17 11:06:09 emirs-speakers volumio[15339]: info: CoreCommandRouter::volumioGetState Jun 17 11:06:09 emirs-speakers volumio[15339]: info: CorePlayQueue::getTrack 0 Jun 17 11:06:09 emirs-speakers volumio[15339]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Jun 17 11:06:10 emirs-speakers go-librespot[7125]: time="2025-06-17T11:06:10+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 17 11:06:10 emirs-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 17 11:06:10 emirs-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 17 11:06:10 emirs-speakers volumio[15339]: (node:15339) UnhandledPromiseRejectionWarning: Error: socket hang up Jun 17 11:06:10 emirs-speakers volumio[15339]: at connResetException (internal/errors.js:639:14) Jun 17 11:06:10 emirs-speakers volumio[15339]: at Socket.socketOnEnd (_http_client.js:499:23) Jun 17 11:06:10 emirs-speakers volumio[15339]: at Socket.emit (events.js:412:35) Jun 17 11:06:10 emirs-speakers volumio[15339]: at endReadableNT (internal/streams/readable.js:1333:12) Jun 17 11:06:10 emirs-speakers volumio[15339]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 17 11:06:10 emirs-speakers volumio[15339]: (node:15339) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 11) Jun 17 11:06:10 emirs-speakers volumio[15339]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Jun 17 11:06:10 emirs-speakers volumio[15339]: info: Connection to go-librespot Websocket closed Jun 17 11:06:11 emirs-speakers ntpd[30814]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 17 11:06:11 emirs-speakers volumio[15339]: info: Volumio Network Manager: Network status updated: 0 Jun 17 11:06:12 emirs-speakers kernel: wlan0: deauthenticated from f4:92:bf:aa:14:95 (Reason: 2=PREV_AUTH_NOT_VALID) Jun 17 11:06:12 emirs-speakers wpa_supplicant[1069]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f4:92:bf:aa:14:95 reason=2 Jun 17 11:06:12 emirs-speakers wpa_supplicant[1069]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 17 11:06:12 emirs-speakers wpa_supplicant[1069]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=TR Jun 17 11:06:13 emirs-speakers volumio[15339]: info: Initializing connection to go-librespot Websocket Jun 17 11:06:13 emirs-speakers volumio[15339]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 17 11:06:13 emirs-speakers systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 17 11:06:13 emirs-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Jun 17 11:06:13 emirs-speakers systemd[1]: Stopped go-librespot Daemon. Jun 17 11:06:13 emirs-speakers systemd[1]: Started go-librespot Daemon. Jun 17 11:06:14 emirs-speakers go-librespot[7245]: Librespot-go daemon starting... Jun 17 11:06:14 emirs-speakers go-librespot[7245]: time="2025-06-17T11:06:14+03:00" level=info msg="generated new device id: 89ec30fa66957d7d82bfb3d5ec33638ed6dd8c8b" Jun 17 11:06:14 emirs-speakers go-librespot[7245]: time="2025-06-17T11:06:14+03:00" level=debug msg="stored credentials found for 11142120369" Jun 17 11:06:14 emirs-speakers go-librespot[7245]: time="2025-06-17T11:06:14+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 17 11:06:14 emirs-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 17 11:06:14 emirs-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 17 11:06:14 emirs-speakers wpa_supplicant[1069]: FT: Invalid key management type (2) Jun 17 11:06:14 emirs-speakers wpa_supplicant[1069]: wlan0: SME: Trying to authenticate with 60:22:32:66:d2:7a (SSID='Beykoz' freq=2472 MHz) Jun 17 11:06:14 emirs-speakers kernel: wlan0: authenticate with 60:22:32:66:d2:7a Jun 17 11:06:14 emirs-speakers kernel: wlan0: 80 MHz not supported, disabling VHT Jun 17 11:06:14 emirs-speakers kernel: wlan0: send auth to 60:22:32:66:d2:7a (try 1/3) Jun 17 11:06:14 emirs-speakers kernel: wlan0: send auth to 60:22:32:66:d2:7a (try 2/3) Jun 17 11:06:14 emirs-speakers kernel: wlan0: send auth to 60:22:32:66:d2:7a (try 3/3) Jun 17 11:06:14 emirs-speakers kernel: wlan0: authentication with 60:22:32:66:d2:7a timed out Jun 17 11:06:16 emirs-speakers wpa_supplicant[1069]: FT: Invalid key management type (2) Jun 17 11:06:16 emirs-speakers wpa_supplicant[1069]: wlan0: SME: Trying to authenticate with ac:8b:a9:3c:24:ee (SSID='Beykoz' freq=2432 MHz) Jun 17 11:06:16 emirs-speakers kernel: wlan0: authenticate with ac:8b:a9:3c:24:ee Jun 17 11:06:16 emirs-speakers kernel: wlan0: 80 MHz not supported, disabling VHT Jun 17 11:06:16 emirs-speakers kernel: wlan0: send auth to ac:8b:a9:3c:24:ee (try 1/3) Jun 17 11:06:16 emirs-speakers kernel: wlan0: authenticated Jun 17 11:06:16 emirs-speakers wpa_supplicant[1069]: wlan0: Trying to associate with ac:8b:a9:3c:24:ee (SSID='Beykoz' freq=2432 MHz) Jun 17 11:06:16 emirs-speakers kernel: wlan0: associate with ac:8b:a9:3c:24:ee (try 1/3) Jun 17 11:06:16 emirs-speakers wpa_supplicant[1069]: wlan0: Associated with ac:8b:a9:3c:24:ee Jun 17 11:06:16 emirs-speakers wpa_supplicant[1069]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 17 11:06:16 emirs-speakers wpa_supplicant[1069]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=TR Jun 17 11:06:16 emirs-speakers kernel: wlan0: RX AssocResp from ac:8b:a9:3c:24:ee (capab=0x1031 status=0 aid=9) Jun 17 11:06:16 emirs-speakers kernel: wlan0: associated Jun 17 11:06:16 emirs-speakers volumio[15339]: info: Initializing connection to go-librespot Websocket Jun 17 11:06:16 emirs-speakers volumio[15339]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 17 11:06:16 emirs-speakers wpa_supplicant[1069]: wlan0: WPA: Key negotiation completed with ac:8b:a9:3c:24:ee [PTK=CCMP GTK=CCMP] Jun 17 11:06:16 emirs-speakers wpa_supplicant[1069]: wlan0: CTRL-EVENT-CONNECTED - Connection to ac:8b:a9:3c:24:ee completed [id=1 id_str=] Jun 17 11:06:16 emirs-speakers dhcpcd[1158]: wlan0: carrier acquired Jun 17 11:06:16 emirs-speakers dhcpcd[1158]: wlan0: IAID d0:de:7f:0b Jun 17 11:06:17 emirs-speakers dhcpcd[1158]: wlan0: soliciting an IPv6 router Jun 17 11:06:17 emirs-speakers systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 17 11:06:17 emirs-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Jun 17 11:06:17 emirs-speakers systemd[1]: Stopped go-librespot Daemon. Jun 17 11:06:17 emirs-speakers systemd[1]: Started go-librespot Daemon. Jun 17 11:06:17 emirs-speakers go-librespot[7253]: Librespot-go daemon starting... Jun 17 11:06:17 emirs-speakers go-librespot[7253]: time="2025-06-17T11:06:17+03:00" level=info msg="generated new device id: 8587502f16cd94bf5023b4a22ff1b6e9d1c16218" Jun 17 11:06:17 emirs-speakers go-librespot[7253]: time="2025-06-17T11:06:17+03:00" level=debug msg="stored credentials found for 11142120369" Jun 17 11:06:17 emirs-speakers go-librespot[7253]: time="2025-06-17T11:06:17+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 17 11:06:17 emirs-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 17 11:06:17 emirs-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 17 11:06:17 emirs-speakers dhcpcd[1158]: wlan0: rebinding lease of 192.168.1.150 Jun 17 11:06:17 emirs-speakers kernel: wlan0: Limiting TX power to 20 (20 - 0) dBm as advertised by ac:8b:a9:3c:24:ee Jun 17 11:06:19 emirs-speakers volumio[15339]: info: Initializing connection to go-librespot Websocket Jun 17 11:06:19 emirs-speakers volumio[15339]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 17 11:06:20 emirs-speakers systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 17 11:06:20 emirs-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Jun 17 11:06:20 emirs-speakers systemd[1]: Stopped go-librespot Daemon. Jun 17 11:06:20 emirs-speakers systemd[1]: Started go-librespot Daemon. Jun 17 11:06:20 emirs-speakers go-librespot[7260]: Librespot-go daemon starting... Jun 17 11:06:20 emirs-speakers go-librespot[7260]: time="2025-06-17T11:06:20+03:00" level=info msg="generated new device id: e329a8913c6b9da943be93002ff98109da9b58cb" Jun 17 11:06:20 emirs-speakers go-librespot[7260]: time="2025-06-17T11:06:20+03:00" level=debug msg="stored credentials found for 11142120369" Jun 17 11:06:20 emirs-speakers go-librespot[7260]: time="2025-06-17T11:06:20+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 17 11:06:20 emirs-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 17 11:06:20 emirs-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 17 11:06:22 emirs-speakers dhcpcd[1158]: wlan0: probing for an IPv4LL address Jun 17 11:06:22 emirs-speakers dhcpcd[1158]: wlan0: DHCP lease expired Jun 17 11:06:22 emirs-speakers dhcpcd[1158]: wlan0: soliciting a DHCP lease Jun 17 11:06:22 emirs-speakers volumio[15339]: info: Initializing connection to go-librespot Websocket Jun 17 11:06:22 emirs-speakers volumio[15339]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 17 11:06:23 emirs-speakers nmbd[972]: [2025/06/17 11:06:23.212709, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jun 17 11:06:23 emirs-speakers nmbd[972]: Packet send failed to 192.168.1.255(137) ERRNO=Network is unreachable Jun 17 11:06:23 emirs-speakers nmbd[972]: [2025/06/17 11:06:23.212850, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 17 11:06:23 emirs-speakers nmbd[972]: send_netbios_packet: send_packet() to IP 192.168.1.255 port 137 failed Jun 17 11:06:23 emirs-speakers nmbd[972]: [2025/06/17 11:06:23.212920, 0] ../source3/nmbd/nmbd_namequery.c:245(query_name) Jun 17 11:06:23 emirs-speakers nmbd[972]: query_name: Failed to send packet trying to query name WORKGROUP<1d> Jun 17 11:06:23 emirs-speakers systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 17 11:06:23 emirs-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Jun 17 11:06:23 emirs-speakers systemd[1]: Stopped go-librespot Daemon. Jun 17 11:06:23 emirs-speakers systemd[1]: Started go-librespot Daemon. Jun 17 11:06:23 emirs-speakers go-librespot[7289]: Librespot-go daemon starting... Jun 17 11:06:23 emirs-speakers go-librespot[7289]: time="2025-06-17T11:06:23+03:00" level=info msg="generated new device id: 319f63c8b72110d24d6b0d42b74142fa9dc27313" Jun 17 11:06:23 emirs-speakers go-librespot[7289]: time="2025-06-17T11:06:23+03:00" level=debug msg="stored credentials found for 11142120369" Jun 17 11:06:23 emirs-speakers go-librespot[7289]: time="2025-06-17T11:06:23+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 17 11:06:23 emirs-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 17 11:06:23 emirs-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 17 11:06:25 emirs-speakers volumio[15339]: info: Initializing connection to go-librespot Websocket Jun 17 11:06:25 emirs-speakers volumio[15339]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 17 11:06:26 emirs-speakers systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 17 11:06:26 emirs-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Jun 17 11:06:26 emirs-speakers systemd[1]: Stopped go-librespot Daemon. Jun 17 11:06:27 emirs-speakers systemd[1]: Started go-librespot Daemon. Jun 17 11:06:27 emirs-speakers go-librespot[7296]: Librespot-go daemon starting... Jun 17 11:06:27 emirs-speakers go-librespot[7296]: time="2025-06-17T11:06:27+03:00" level=info msg="generated new device id: 1b9365d113e63b3bf6e0d017439a216e332076df" Jun 17 11:06:27 emirs-speakers go-librespot[7296]: time="2025-06-17T11:06:27+03:00" level=debug msg="stored credentials found for 11142120369" Jun 17 11:06:27 emirs-speakers go-librespot[7296]: time="2025-06-17T11:06:27+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 17 11:06:27 emirs-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 17 11:06:27 emirs-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 17 11:06:27 emirs-speakers volumio[15339]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 11:06:27 emirs-speakers volumio[15339]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 17 11:06:27 emirs-speakers volumio[15339]: at doSend (dgram.js:714:16) Jun 17 11:06:27 emirs-speakers volumio[15339]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Jun 17 11:06:27 emirs-speakers volumio[15339]: at afterDns (dgram.js:660:5) Jun 17 11:06:27 emirs-speakers volumio[15339]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Jun 17 11:06:27 emirs-speakers volumio[15339]: errno: -101, Jun 17 11:06:27 emirs-speakers volumio[15339]: code: 'ENETUNREACH', Jun 17 11:06:27 emirs-speakers volumio[15339]: syscall: 'send', Jun 17 11:06:27 emirs-speakers volumio[15339]: address: '255.255.255.255', Jun 17 11:06:27 emirs-speakers volumio[15339]: port: 3483 Jun 17 11:06:27 emirs-speakers volumio[15339]: } Jun 17 11:06:27 emirs-speakers volumio[15339]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 11:06:27 emirs-speakers dhcpcd[1158]: wlan0: using IPv4LL address 169.254.239.74 Jun 17 11:06:27 emirs-speakers dhcpcd[1158]: wlan0: adding route to 169.254.0.0/16 Jun 17 11:06:27 emirs-speakers dhcpcd[1158]: wlan0: adding default route Jun 17 11:06:27 emirs-speakers avahi-daemon[825]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.239.74. Jun 17 11:06:27 emirs-speakers avahi-daemon[825]: New relevant interface wlan0.IPv4 for mDNS. Jun 17 11:06:27 emirs-speakers avahi-daemon[825]: Registering new address record for 169.254.239.74 on wlan0.IPv4. Jun 17 11:06:29 emirs-speakers ntpd[30814]: Listen normally on 4 wlan0 169.254.239.74:123 Jun 17 11:06:29 emirs-speakers ntpd[30814]: new interface(s) found: waking up resolver Jun 17 11:06:30 emirs-speakers systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 17 11:06:30 emirs-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Jun 17 11:06:30 emirs-speakers systemd[1]: Stopped go-librespot Daemon. Jun 17 11:06:30 emirs-speakers systemd[1]: Started go-librespot Daemon. Jun 17 11:06:30 emirs-speakers go-librespot[7322]: Librespot-go daemon starting... Jun 17 11:06:30 emirs-speakers go-librespot[7322]: time="2025-06-17T11:06:30+03:00" level=info msg="generated new device id: 12f6e88e9cdbc946f152328239fab0af128c5072" Jun 17 11:06:30 emirs-speakers go-librespot[7322]: time="2025-06-17T11:06:30+03:00" level=debug msg="stored credentials found for 11142120369" Jun 17 11:06:34 emirs-speakers dhcpcd[1158]: wlan0: offered 192.168.1.150 from 192.168.1.1 Jun 17 11:06:34 emirs-speakers dhcpcd[1158]: wlan0: probing address 192.168.1.150/24 Jun 17 11:06:40 emirs-speakers dhcpcd[1158]: wlan0: leased 192.168.1.150 for 86400 seconds Jun 17 11:06:40 emirs-speakers dhcpcd[1158]: wlan0: adding route to 192.168.1.0/24 Jun 17 11:06:40 emirs-speakers dhcpcd[1158]: wlan0: changing default route via 192.168.1.1 Jun 17 11:06:40 emirs-speakers avahi-daemon[825]: Registering new address record for 192.168.1.150 on wlan0.IPv4. Jun 17 11:06:40 emirs-speakers ntpd[30814]: ntpd exiting on signal 15 (Terminated) Jun 17 11:06:40 emirs-speakers ntpd[30814]: 72.30.35.88 local addr 169.254.239.74 -> Jun 17 11:06:40 emirs-speakers ntpd[30814]: 15.204.246.57 local addr 169.254.239.74 -> Jun 17 11:06:40 emirs-speakers ntpd[30814]: 23.150.41.123 local addr 169.254.239.74 -> Jun 17 11:06:40 emirs-speakers ntpd[30814]: 23.186.168.130 local addr 169.254.239.74 -> Jun 17 11:06:40 emirs-speakers systemd[1]: Stopping Network Time Service... Jun 17 11:06:40 emirs-speakers ntpd[30814]: 23.150.40.242 local addr 169.254.239.74 -> Jun 17 11:06:40 emirs-speakers ntpd[30814]: 163.123.152.14 local addr 169.254.239.74 -> Jun 17 11:06:40 emirs-speakers systemd[1]: ntp.service: Succeeded. Jun 17 11:06:40 emirs-speakers systemd[1]: Stopped Network Time Service. Jun 17 11:06:40 emirs-speakers systemd[1]: Starting Network Time Service... Jun 17 11:06:40 emirs-speakers ntpd[7380]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 17 11:06:40 emirs-speakers ntpd[7380]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 17 11:06:40 emirs-speakers systemd[1]: Started Network Time Service. Jun 17 11:06:40 emirs-speakers ntpd[7386]: proto: precision = 0.244 usec (-22) Jun 17 11:06:40 emirs-speakers ntpd[7386]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 17 11:06:40 emirs-speakers ntpd[7386]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 17 11:06:40 emirs-speakers ntpd[7386]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 903 days ago Jun 17 11:06:40 emirs-speakers avahi-daemon[825]: Withdrawing address record for 169.254.239.74 on wlan0. Jun 17 11:06:40 emirs-speakers avahi-daemon[825]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.239.74. Jun 17 11:06:40 emirs-speakers ntpd[7386]: Listen and drop on 0 v6wildcard [::]:123 Jun 17 11:06:40 emirs-speakers ntpd[7386]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 17 11:06:40 emirs-speakers dhcpcd[1158]: wlan0: deleting route to 169.254.0.0/16 Jun 17 11:06:40 emirs-speakers avahi-daemon[825]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.150. Jun 17 11:06:40 emirs-speakers ntpd[7386]: Listen normally on 2 lo 127.0.0.1:123 Jun 17 11:06:40 emirs-speakers ntpd[7386]: Listen normally on 3 wlan0 192.168.1.150:123 Jun 17 11:06:40 emirs-speakers ntpd[7386]: Listening on routing socket on fd #20 for interface updates Jun 17 11:06:40 emirs-speakers ntpd[7386]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jun 17 11:06:40 emirs-speakers ntpd[7386]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jun 17 11:06:42 emirs-speakers ntpd[7386]: Soliciting pool server 162.244.81.139 Jun 17 11:06:42 emirs-speakers ntpd[7386]: Soliciting pool server 74.208.25.46 Jun 17 11:06:42 emirs-speakers sudo[7314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-17 11:05 Jun 17 11:06:42 emirs-speakers sudo[7314]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"