-- Logs begin at Sat 2025-10-25 13:26:55 CEST, end at Sat 2025-10-25 13:49:29 CEST. -- Oct 25 13:48:00 severinelenceinte gpio_veille.sh[899]: 🔴 Mode veille activé Oct 25 13:48:00 severinelenceinte gpio_veille.sh[899]: volume:100% repeat: off random: off single: off consume: off Oct 25 13:48:00 severinelenceinte sudo[1484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 25 13:48:00 severinelenceinte sudo[1484]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:00 severinelenceinte sudo[1484]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:00 severinelenceinte systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 25 13:48:00 severinelenceinte systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Oct 25 13:48:01 severinelenceinte systemd[1]: Stopped go-librespot Daemon. Oct 25 13:48:01 severinelenceinte systemd[1]: Started go-librespot Daemon. Oct 25 13:48:01 severinelenceinte go-librespot[1487]: go-librespot daemon starting... Oct 25 13:48:01 severinelenceinte go-librespot[1487]: time="2025-10-25T13:48:01+02:00" level=info msg="running go-librespot 0.3.2" Oct 25 13:48:01 severinelenceinte go-librespot[1487]: time="2025-10-25T13:48:01+02:00" level=debug msg="app state loaded" Oct 25 13:48:01 severinelenceinte go-librespot[1487]: time="2025-10-25T13:48:01+02:00" level=debug msg="stored credentials not found" Oct 25 13:48:01 severinelenceinte go-librespot[1487]: time="2025-10-25T13:48:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 13:48:01 severinelenceinte go-librespot[1487]: time="2025-10-25T13:48:01+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 25 13:48:01 severinelenceinte systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 13:48:01 severinelenceinte systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 13:48:01 severinelenceinte volumio[866]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 25 13:48:02 severinelenceinte volumio[866]: info: Initializing connection to go-librespot Websocket Oct 25 13:48:02 severinelenceinte volumio[866]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 13:48:02 severinelenceinte volumio[866]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.832&uuid=7c23a07809ce5f6057cd6bb852aacae1" http://updates.volumio.org/downloader-v1/track-device Oct 25 13:48:02 severinelenceinte volumio[866]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 25 13:48:02 severinelenceinte volumio[866]: Dload Upload Total Spent Left Speed Oct 25 13:48:02 severinelenceinte volumio[866]: [132B blob data] Oct 25 13:48:02 severinelenceinte volumio[866]: retrying in 5 seconds, trial 1 Oct 25 13:48:02 severinelenceinte volumio[866]: info: Volumio Calling Home Oct 25 13:48:02 severinelenceinte gpio_veille.sh[899]: 🔴 Mode veille activé Oct 25 13:48:03 severinelenceinte gpio_veille.sh[899]: volume:100% repeat: off random: off single: off consume: off Oct 25 13:48:03 severinelenceinte sudo[1498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 25 13:48:03 severinelenceinte sudo[1498]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:03 severinelenceinte sudo[1498]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:04 severinelenceinte systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 25 13:48:04 severinelenceinte systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Oct 25 13:48:04 severinelenceinte systemd[1]: Stopped go-librespot Daemon. Oct 25 13:48:04 severinelenceinte systemd[1]: Started go-librespot Daemon. Oct 25 13:48:04 severinelenceinte go-librespot[1501]: go-librespot daemon starting... Oct 25 13:48:04 severinelenceinte go-librespot[1501]: time="2025-10-25T13:48:04+02:00" level=info msg="running go-librespot 0.3.2" Oct 25 13:48:04 severinelenceinte go-librespot[1501]: time="2025-10-25T13:48:04+02:00" level=debug msg="app state loaded" Oct 25 13:48:04 severinelenceinte go-librespot[1501]: time="2025-10-25T13:48:04+02:00" level=debug msg="stored credentials not found" Oct 25 13:48:04 severinelenceinte go-librespot[1501]: time="2025-10-25T13:48:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 13:48:04 severinelenceinte go-librespot[1501]: time="2025-10-25T13:48:04+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 25 13:48:04 severinelenceinte systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 13:48:04 severinelenceinte systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 13:48:05 severinelenceinte gpio_veille.sh[899]: 🔴 Mode veille activé Oct 25 13:48:05 severinelenceinte gpio_veille.sh[899]: volume:100% repeat: off random: off single: off consume: off Oct 25 13:48:05 severinelenceinte sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 25 13:48:05 severinelenceinte sudo[1508]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:05 severinelenceinte sudo[1508]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:05 severinelenceinte volumio[866]: info: Initializing connection to go-librespot Websocket Oct 25 13:48:05 severinelenceinte volumio[866]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 13:48:07 severinelenceinte gpio_veille.sh[899]: 🔴 Mode veille activé Oct 25 13:48:07 severinelenceinte gpio_veille.sh[899]: volume:100% repeat: off random: off single: off consume: off Oct 25 13:48:07 severinelenceinte sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 25 13:48:07 severinelenceinte sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:07 severinelenceinte sudo[1513]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:07 severinelenceinte systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 25 13:48:07 severinelenceinte systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Oct 25 13:48:07 severinelenceinte systemd[1]: Stopped go-librespot Daemon. Oct 25 13:48:07 severinelenceinte systemd[1]: Started go-librespot Daemon. Oct 25 13:48:07 severinelenceinte go-librespot[1516]: go-librespot daemon starting... Oct 25 13:48:07 severinelenceinte go-librespot[1516]: time="2025-10-25T13:48:07+02:00" level=info msg="running go-librespot 0.3.2" Oct 25 13:48:07 severinelenceinte go-librespot[1516]: time="2025-10-25T13:48:07+02:00" level=debug msg="app state loaded" Oct 25 13:48:07 severinelenceinte go-librespot[1516]: time="2025-10-25T13:48:07+02:00" level=debug msg="stored credentials not found" Oct 25 13:48:07 severinelenceinte go-librespot[1516]: time="2025-10-25T13:48:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 13:48:07 severinelenceinte go-librespot[1516]: time="2025-10-25T13:48:07+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 25 13:48:07 severinelenceinte systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 13:48:07 severinelenceinte systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 13:48:08 severinelenceinte volumio[866]: info: Initializing connection to go-librespot Websocket Oct 25 13:48:08 severinelenceinte volumio[866]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 13:48:08 severinelenceinte volumio[866]: error: MyVolumio Plugin failed to start in a timely fashion Oct 25 13:48:08 severinelenceinte volumio[866]: [Metrics] CommandRouter: 97s 428.77ms Oct 25 13:48:08 severinelenceinte volumio[866]: info: CoreCommandRouter::volumiosetStartupVolume Oct 25 13:48:08 severinelenceinte volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 13:48:08 severinelenceinte volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 13:48:08 severinelenceinte volumio[866]: info: CoreCommandRouter::Close All Modals sent Oct 25 13:48:08 severinelenceinte volumio[866]: info: CoreCommandRouter::Close All Modals sent Oct 25 13:48:09 severinelenceinte volumio[866]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Oct 25 13:48:09 severinelenceinte volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 13:48:09 severinelenceinte volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 13:48:09 severinelenceinte volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 13:48:09 severinelenceinte volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 25 13:48:09 severinelenceinte gpio_veille.sh[899]: 🔴 Mode veille activé Oct 25 13:48:09 severinelenceinte gpio_veille.sh[899]: volume:100% repeat: off random: off single: off consume: off Oct 25 13:48:09 severinelenceinte sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 25 13:48:09 severinelenceinte sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:09 severinelenceinte sudo[1541]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:09 severinelenceinte volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 25 13:48:09 severinelenceinte volumio[866]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 13:48:09 severinelenceinte volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 25 13:48:10 severinelenceinte systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 25 13:48:10 severinelenceinte systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Oct 25 13:48:10 severinelenceinte systemd[1]: Stopped go-librespot Daemon. Oct 25 13:48:10 severinelenceinte systemd[1]: Started go-librespot Daemon. Oct 25 13:48:10 severinelenceinte go-librespot[1556]: go-librespot daemon starting... Oct 25 13:48:10 severinelenceinte go-librespot[1556]: time="2025-10-25T13:48:10+02:00" level=info msg="running go-librespot 0.3.2" Oct 25 13:48:10 severinelenceinte go-librespot[1556]: time="2025-10-25T13:48:10+02:00" level=debug msg="app state loaded" Oct 25 13:48:10 severinelenceinte go-librespot[1556]: time="2025-10-25T13:48:10+02:00" level=debug msg="stored credentials not found" Oct 25 13:48:10 severinelenceinte go-librespot[1556]: time="2025-10-25T13:48:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 13:48:10 severinelenceinte go-librespot[1556]: time="2025-10-25T13:48:10+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 25 13:48:10 severinelenceinte systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 13:48:10 severinelenceinte systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 13:48:11 severinelenceinte volumio[866]: info: Initializing connection to go-librespot Websocket Oct 25 13:48:11 severinelenceinte volumio[866]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 13:48:11 severinelenceinte gpio_veille.sh[899]: 🔴 Mode veille activé Oct 25 13:48:11 severinelenceinte gpio_veille.sh[899]: volume:100% repeat: off random: off single: off consume: off Oct 25 13:48:11 severinelenceinte sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 25 13:48:11 severinelenceinte sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:11 severinelenceinte sudo[1563]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:12 severinelenceinte volumio[866]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.832&uuid=7c23a07809ce5f6057cd6bb852aacae1" http://updates.volumio.org/downloader-v1/track-device Oct 25 13:48:12 severinelenceinte volumio[866]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 25 13:48:12 severinelenceinte volumio[866]: Dload Upload Total Spent Left Speed Oct 25 13:48:12 severinelenceinte volumio[866]: [132B blob data] Oct 25 13:48:12 severinelenceinte volumio[866]: retrying in 5 seconds, trial 2 Oct 25 13:48:12 severinelenceinte volumio[866]: info: Volumio Calling Home Oct 25 13:48:13 severinelenceinte gpio_veille.sh[899]: 🔴 Mode veille activé Oct 25 13:48:13 severinelenceinte gpio_veille.sh[899]: volume:100% repeat: off random: off single: off consume: off Oct 25 13:48:13 severinelenceinte sudo[1571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 25 13:48:13 severinelenceinte sudo[1571]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:13 severinelenceinte sudo[1571]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:13 severinelenceinte systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 25 13:48:13 severinelenceinte systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Oct 25 13:48:13 severinelenceinte systemd[1]: Stopped go-librespot Daemon. Oct 25 13:48:14 severinelenceinte systemd[1]: Started go-librespot Daemon. Oct 25 13:48:14 severinelenceinte go-librespot[1574]: go-librespot daemon starting... Oct 25 13:48:14 severinelenceinte go-librespot[1574]: time="2025-10-25T13:48:14+02:00" level=info msg="running go-librespot 0.3.2" Oct 25 13:48:14 severinelenceinte go-librespot[1574]: time="2025-10-25T13:48:14+02:00" level=debug msg="app state loaded" Oct 25 13:48:14 severinelenceinte go-librespot[1574]: time="2025-10-25T13:48:14+02:00" level=debug msg="stored credentials not found" Oct 25 13:48:14 severinelenceinte go-librespot[1574]: time="2025-10-25T13:48:14+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 13:48:14 severinelenceinte go-librespot[1574]: time="2025-10-25T13:48:14+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 25 13:48:14 severinelenceinte systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 13:48:14 severinelenceinte systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 13:48:14 severinelenceinte volumio[866]: info: Initializing connection to go-librespot Websocket Oct 25 13:48:14 severinelenceinte volumio[866]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 13:48:15 severinelenceinte gpio_veille.sh[899]: 🔴 Mode veille activé Oct 25 13:48:15 severinelenceinte gpio_veille.sh[899]: volume:100% repeat: off random: off single: off consume: off Oct 25 13:48:15 severinelenceinte sudo[1581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 25 13:48:15 severinelenceinte sudo[1581]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:15 severinelenceinte volumio[866]: info: BOOT COMPLETED Oct 25 13:48:15 severinelenceinte sudo[1581]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:17 severinelenceinte systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 25 13:48:17 severinelenceinte systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Oct 25 13:48:17 severinelenceinte systemd[1]: Stopped go-librespot Daemon. Oct 25 13:48:17 severinelenceinte systemd[1]: Started go-librespot Daemon. Oct 25 13:48:17 severinelenceinte volumio[866]: info: Initializing connection to go-librespot Websocket Oct 25 13:48:17 severinelenceinte volumio[866]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 13:48:17 severinelenceinte go-librespot[1587]: go-librespot daemon starting... Oct 25 13:48:17 severinelenceinte go-librespot[1587]: time="2025-10-25T13:48:17+02:00" level=info msg="running go-librespot 0.3.2" Oct 25 13:48:17 severinelenceinte go-librespot[1587]: time="2025-10-25T13:48:17+02:00" level=debug msg="app state loaded" Oct 25 13:48:17 severinelenceinte go-librespot[1587]: time="2025-10-25T13:48:17+02:00" level=debug msg="stored credentials not found" Oct 25 13:48:17 severinelenceinte go-librespot[1587]: time="2025-10-25T13:48:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 13:48:17 severinelenceinte go-librespot[1587]: time="2025-10-25T13:48:17+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 25 13:48:17 severinelenceinte systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 13:48:17 severinelenceinte systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 13:48:17 severinelenceinte gpio_veille.sh[899]: 🔴 Mode veille activé Oct 25 13:48:17 severinelenceinte gpio_veille.sh[899]: volume:100% repeat: off random: off single: off consume: off Oct 25 13:48:18 severinelenceinte sudo[1594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 25 13:48:18 severinelenceinte sudo[1594]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:18 severinelenceinte sudo[1594]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:20 severinelenceinte gpio_veille.sh[899]: 🔴 Mode veille activé Oct 25 13:48:20 severinelenceinte gpio_veille.sh[899]: volume:100% repeat: off random: off single: off consume: off Oct 25 13:48:20 severinelenceinte sudo[1613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 25 13:48:20 severinelenceinte sudo[1613]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:20 severinelenceinte sudo[1613]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:20 severinelenceinte volumio[866]: info: Initializing connection to go-librespot Websocket Oct 25 13:48:20 severinelenceinte volumio[866]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 13:48:20 severinelenceinte systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 25 13:48:20 severinelenceinte systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Oct 25 13:48:20 severinelenceinte systemd[1]: Stopped go-librespot Daemon. Oct 25 13:48:20 severinelenceinte systemd[1]: Started go-librespot Daemon. Oct 25 13:48:20 severinelenceinte go-librespot[1616]: go-librespot daemon starting... Oct 25 13:48:20 severinelenceinte go-librespot[1616]: time="2025-10-25T13:48:20+02:00" level=info msg="running go-librespot 0.3.2" Oct 25 13:48:20 severinelenceinte go-librespot[1616]: time="2025-10-25T13:48:20+02:00" level=debug msg="app state loaded" Oct 25 13:48:20 severinelenceinte go-librespot[1616]: time="2025-10-25T13:48:20+02:00" level=debug msg="stored credentials not found" Oct 25 13:48:20 severinelenceinte go-librespot[1616]: time="2025-10-25T13:48:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 13:48:20 severinelenceinte go-librespot[1616]: time="2025-10-25T13:48:20+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 25 13:48:20 severinelenceinte systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 13:48:20 severinelenceinte systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 13:48:22 severinelenceinte gpio_veille.sh[899]: 🔴 Mode veille activé Oct 25 13:48:22 severinelenceinte gpio_veille.sh[899]: volume:100% repeat: off random: off single: off consume: off Oct 25 13:48:22 severinelenceinte sudo[1623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 25 13:48:22 severinelenceinte sudo[1623]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:22 severinelenceinte sudo[1623]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:23 severinelenceinte volumio[866]: info: Initializing connection to go-librespot Websocket Oct 25 13:48:23 severinelenceinte volumio[866]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 13:48:23 severinelenceinte systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 25 13:48:23 severinelenceinte systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Oct 25 13:48:23 severinelenceinte systemd[1]: Stopped go-librespot Daemon. Oct 25 13:48:23 severinelenceinte systemd[1]: Started go-librespot Daemon. Oct 25 13:48:23 severinelenceinte go-librespot[1626]: go-librespot daemon starting... Oct 25 13:48:23 severinelenceinte go-librespot[1626]: time="2025-10-25T13:48:23+02:00" level=info msg="running go-librespot 0.3.2" Oct 25 13:48:23 severinelenceinte go-librespot[1626]: time="2025-10-25T13:48:23+02:00" level=debug msg="app state loaded" Oct 25 13:48:23 severinelenceinte go-librespot[1626]: time="2025-10-25T13:48:23+02:00" level=debug msg="stored credentials not found" Oct 25 13:48:23 severinelenceinte go-librespot[1626]: time="2025-10-25T13:48:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 13:48:23 severinelenceinte go-librespot[1626]: time="2025-10-25T13:48:23+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 25 13:48:23 severinelenceinte systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 13:48:23 severinelenceinte systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 13:48:24 severinelenceinte gpio_veille.sh[899]: 🔴 Mode veille activé Oct 25 13:48:24 severinelenceinte gpio_veille.sh[899]: volume:100% repeat: off random: off single: off consume: off Oct 25 13:48:24 severinelenceinte sudo[1633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 25 13:48:24 severinelenceinte sudo[1633]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:24 severinelenceinte sudo[1633]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:26 severinelenceinte volumio[866]: info: Initializing connection to go-librespot Websocket Oct 25 13:48:26 severinelenceinte volumio[866]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 13:48:26 severinelenceinte gpio_veille.sh[899]: 🔴 Mode veille activé Oct 25 13:48:26 severinelenceinte gpio_veille.sh[899]: volume:100% repeat: off random: off single: off consume: off Oct 25 13:48:26 severinelenceinte sudo[1638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 25 13:48:26 severinelenceinte sudo[1638]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:26 severinelenceinte sudo[1638]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:26 severinelenceinte systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 25 13:48:26 severinelenceinte systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Oct 25 13:48:26 severinelenceinte systemd[1]: Stopped go-librespot Daemon. Oct 25 13:48:27 severinelenceinte systemd[1]: Started go-librespot Daemon. Oct 25 13:48:27 severinelenceinte go-librespot[1641]: go-librespot daemon starting... Oct 25 13:48:27 severinelenceinte go-librespot[1641]: time="2025-10-25T13:48:27+02:00" level=info msg="running go-librespot 0.3.2" Oct 25 13:48:27 severinelenceinte go-librespot[1641]: time="2025-10-25T13:48:27+02:00" level=debug msg="app state loaded" Oct 25 13:48:27 severinelenceinte go-librespot[1641]: time="2025-10-25T13:48:27+02:00" level=debug msg="stored credentials not found" Oct 25 13:48:27 severinelenceinte go-librespot[1641]: time="2025-10-25T13:48:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 13:48:27 severinelenceinte go-librespot[1641]: time="2025-10-25T13:48:27+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 25 13:48:27 severinelenceinte systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 13:48:27 severinelenceinte systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 13:48:28 severinelenceinte gpio_veille.sh[899]: 🔴 Mode veille activé Oct 25 13:48:28 severinelenceinte gpio_veille.sh[899]: volume:100% repeat: off random: off single: off consume: off Oct 25 13:48:28 severinelenceinte sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 25 13:48:28 severinelenceinte sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:28 severinelenceinte sudo[1662]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:29 severinelenceinte volumio[866]: info: Initializing connection to go-librespot Websocket Oct 25 13:48:29 severinelenceinte volumio[866]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 13:48:30 severinelenceinte systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 25 13:48:30 severinelenceinte systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Oct 25 13:48:30 severinelenceinte systemd[1]: Stopped go-librespot Daemon. Oct 25 13:48:30 severinelenceinte systemd[1]: Started go-librespot Daemon. Oct 25 13:48:30 severinelenceinte go-librespot[1665]: go-librespot daemon starting... Oct 25 13:48:30 severinelenceinte go-librespot[1665]: time="2025-10-25T13:48:30+02:00" level=info msg="running go-librespot 0.3.2" Oct 25 13:48:30 severinelenceinte go-librespot[1665]: time="2025-10-25T13:48:30+02:00" level=debug msg="app state loaded" Oct 25 13:48:30 severinelenceinte go-librespot[1665]: time="2025-10-25T13:48:30+02:00" level=debug msg="stored credentials not found" Oct 25 13:48:30 severinelenceinte go-librespot[1665]: time="2025-10-25T13:48:30+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 13:48:30 severinelenceinte go-librespot[1665]: time="2025-10-25T13:48:30+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 25 13:48:30 severinelenceinte systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 13:48:30 severinelenceinte systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 13:48:30 severinelenceinte gpio_veille.sh[899]: 🔴 Mode veille activé Oct 25 13:48:30 severinelenceinte gpio_veille.sh[899]: volume:100% repeat: off random: off single: off consume: off Oct 25 13:48:30 severinelenceinte sudo[1672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 25 13:48:30 severinelenceinte sudo[1672]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:30 severinelenceinte sudo[1672]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:32 severinelenceinte volumio[866]: info: Initializing connection to go-librespot Websocket Oct 25 13:48:32 severinelenceinte volumio[866]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 13:48:32 severinelenceinte gpio_veille.sh[899]: 🔴 Mode veille activé Oct 25 13:48:32 severinelenceinte gpio_veille.sh[899]: volume:100% repeat: off random: off single: off consume: off Oct 25 13:48:32 severinelenceinte sudo[1677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 25 13:48:32 severinelenceinte sudo[1677]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:32 severinelenceinte sudo[1677]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:33 severinelenceinte systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 25 13:48:33 severinelenceinte systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Oct 25 13:48:33 severinelenceinte systemd[1]: Stopped go-librespot Daemon. Oct 25 13:48:33 severinelenceinte systemd[1]: Started go-librespot Daemon. Oct 25 13:48:33 severinelenceinte go-librespot[1680]: go-librespot daemon starting... Oct 25 13:48:33 severinelenceinte go-librespot[1680]: time="2025-10-25T13:48:33+02:00" level=info msg="running go-librespot 0.3.2" Oct 25 13:48:33 severinelenceinte go-librespot[1680]: time="2025-10-25T13:48:33+02:00" level=debug msg="app state loaded" Oct 25 13:48:33 severinelenceinte go-librespot[1680]: time="2025-10-25T13:48:33+02:00" level=debug msg="stored credentials not found" Oct 25 13:48:33 severinelenceinte go-librespot[1680]: time="2025-10-25T13:48:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 13:48:33 severinelenceinte go-librespot[1680]: time="2025-10-25T13:48:33+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 25 13:48:33 severinelenceinte systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 13:48:33 severinelenceinte systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 13:48:34 severinelenceinte nmbd[581]: [2025/10/25 13:48:34.194293, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 25 13:48:34 severinelenceinte nmbd[581]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable Oct 25 13:48:34 severinelenceinte nmbd[581]: [2025/10/25 13:48:34.201031, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Oct 25 13:48:34 severinelenceinte nmbd[581]: reload_interfaces: No subnets to listen to. Waiting.. Oct 25 13:48:34 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:48:35 severinelenceinte sudo[1687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:48:35 severinelenceinte sudo[1687]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:35 severinelenceinte kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 25 13:48:35 severinelenceinte sudo[1687]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:35 severinelenceinte volumio[866]: info: Initializing connection to go-librespot Websocket Oct 25 13:48:35 severinelenceinte volumio[866]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 13:48:35 severinelenceinte wpa_supplicant[770]: wlan0: Trying to associate with SSID 'Freebox-0A6219' Oct 25 13:48:35 severinelenceinte wpa_supplicant[770]: wlan0: Associated with 3a:07:16:98:46:54 Oct 25 13:48:35 severinelenceinte wpa_supplicant[770]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3a:07:16:98:46:54 completed [id=0 id_str=] Oct 25 13:48:35 severinelenceinte dhcpcd[792]: wlan0: carrier acquired Oct 25 13:48:35 severinelenceinte wpa_supplicant[770]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 25 13:48:35 severinelenceinte dhcpcd[792]: wlan0: IAID eb:3f:ff:c8 Oct 25 13:48:36 severinelenceinte dhcpcd[792]: wlan0: rebinding lease of 192.168.1.61 Oct 25 13:48:36 severinelenceinte dhcpcd[792]: wlan0: probing address 192.168.1.61/24 Oct 25 13:48:36 severinelenceinte dhcpcd[792]: wlan0: soliciting an IPv6 router Oct 25 13:48:36 severinelenceinte systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 25 13:48:36 severinelenceinte systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Oct 25 13:48:36 severinelenceinte systemd[1]: Stopped go-librespot Daemon. Oct 25 13:48:36 severinelenceinte systemd[1]: Started go-librespot Daemon. Oct 25 13:48:36 severinelenceinte go-librespot[1722]: go-librespot daemon starting... Oct 25 13:48:37 severinelenceinte go-librespot[1722]: time="2025-10-25T13:48:37+02:00" level=info msg="running go-librespot 0.3.2" Oct 25 13:48:37 severinelenceinte go-librespot[1722]: time="2025-10-25T13:48:37+02:00" level=debug msg="app state loaded" Oct 25 13:48:37 severinelenceinte go-librespot[1722]: time="2025-10-25T13:48:37+02:00" level=debug msg="stored credentials not found" Oct 25 13:48:37 severinelenceinte go-librespot[1722]: time="2025-10-25T13:48:37+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 13:48:37 severinelenceinte go-librespot[1722]: time="2025-10-25T13:48:37+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 25 13:48:37 severinelenceinte systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 13:48:37 severinelenceinte systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 13:48:37 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:48:37 severinelenceinte sudo[1738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:48:37 severinelenceinte sudo[1738]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:37 severinelenceinte sudo[1738]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:38 severinelenceinte volumio[866]: info: Initializing connection to go-librespot Websocket Oct 25 13:48:38 severinelenceinte volumio[866]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 13:48:39 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:48:39 severinelenceinte sudo[1756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:48:39 severinelenceinte sudo[1756]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:39 severinelenceinte sudo[1756]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:40 severinelenceinte systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 25 13:48:40 severinelenceinte systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Oct 25 13:48:40 severinelenceinte systemd[1]: Stopped go-librespot Daemon. Oct 25 13:48:40 severinelenceinte systemd[1]: Started go-librespot Daemon. Oct 25 13:48:40 severinelenceinte go-librespot[1759]: go-librespot daemon starting... Oct 25 13:48:40 severinelenceinte go-librespot[1759]: time="2025-10-25T13:48:40+02:00" level=info msg="running go-librespot 0.3.2" Oct 25 13:48:40 severinelenceinte go-librespot[1759]: time="2025-10-25T13:48:40+02:00" level=debug msg="app state loaded" Oct 25 13:48:40 severinelenceinte go-librespot[1759]: time="2025-10-25T13:48:40+02:00" level=debug msg="stored credentials not found" Oct 25 13:48:40 severinelenceinte go-librespot[1759]: time="2025-10-25T13:48:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 13:48:40 severinelenceinte go-librespot[1759]: time="2025-10-25T13:48:40+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 25 13:48:40 severinelenceinte systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 13:48:40 severinelenceinte systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 13:48:40 severinelenceinte dhcpcd[792]: wlan0: leased 192.168.1.61 for 43200 seconds Oct 25 13:48:40 severinelenceinte avahi-daemon[418]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.61. Oct 25 13:48:40 severinelenceinte dhcpcd[792]: wlan0: adding route to 192.168.1.0/24 Oct 25 13:48:40 severinelenceinte avahi-daemon[418]: New relevant interface wlan0.IPv4 for mDNS. Oct 25 13:48:40 severinelenceinte avahi-daemon[418]: Registering new address record for 192.168.1.61 on wlan0.IPv4. Oct 25 13:48:40 severinelenceinte dhcpcd[792]: wlan0: adding default route via 192.168.1.254 Oct 25 13:48:41 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:48:41 severinelenceinte sudo[1787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:48:41 severinelenceinte sudo[1787]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:41 severinelenceinte volumio[866]: info: Initializing connection to go-librespot Websocket Oct 25 13:48:41 severinelenceinte sudo[1787]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:41 severinelenceinte volumio[866]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 13:48:41 severinelenceinte volumio[866]: info: Discovery: adding c0ae6df8-119b-4be7-b5a2-ec051bf8882b Oct 25 13:48:41 severinelenceinte volumio[866]: info: Discovery: Found device Severinelenceinte Oct 25 13:48:41 severinelenceinte volumio[866]: info: CoreCommandRouter::volumioGetState Oct 25 13:48:41 severinelenceinte volumio[866]: info: CorePlayQueue::getTrack 0 Oct 25 13:48:42 severinelenceinte ntpd[575]: Listen normally on 4 wlan0 192.168.1.61:123 Oct 25 13:48:42 severinelenceinte ntpd[575]: new interface(s) found: waking up resolver Oct 25 13:48:42 severinelenceinte volumio[1269]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 25 13:48:43 severinelenceinte systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 25 13:48:43 severinelenceinte systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Oct 25 13:48:43 severinelenceinte systemd[1]: Stopped go-librespot Daemon. Oct 25 13:48:43 severinelenceinte systemd[1]: Started go-librespot Daemon. Oct 25 13:48:43 severinelenceinte go-librespot[1793]: go-librespot daemon starting... Oct 25 13:48:43 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:48:43 severinelenceinte sudo[1796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:48:43 severinelenceinte go-librespot[1793]: time="2025-10-25T13:48:43+02:00" level=info msg="running go-librespot 0.3.2" Oct 25 13:48:43 severinelenceinte go-librespot[1793]: time="2025-10-25T13:48:43+02:00" level=debug msg="app state loaded" Oct 25 13:48:43 severinelenceinte go-librespot[1793]: time="2025-10-25T13:48:43+02:00" level=debug msg="stored credentials not found" Oct 25 13:48:43 severinelenceinte sudo[1796]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:43 severinelenceinte go-librespot[1793]: time="2025-10-25T13:48:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 13:48:43 severinelenceinte sudo[1796]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:44 severinelenceinte volumio[866]: info: Initializing connection to go-librespot Websocket Oct 25 13:48:44 severinelenceinte go-librespot[1793]: time="2025-10-25T13:48:44+02:00" level=debug msg="new websocket client" Oct 25 13:48:44 severinelenceinte volumio[866]: info: Connection to go-librespot Websocket established Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 25 13:48:45 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 25 13:48:45 severinelenceinte volumio[866]: info: Adding plugin bluetooth to MyMusic Plugins Oct 25 13:48:45 severinelenceinte volumio[866]: info: Adding plugin multiroom to MyMusic Plugins Oct 25 13:48:45 severinelenceinte volumio[866]: info: Adding plugin metavolumio to MyMusic Plugins Oct 25 13:48:45 severinelenceinte volumio[866]: info: Adding plugin cd_controller to MyMusic Plugins Oct 25 13:48:46 severinelenceinte volumio[866]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 25 13:48:46 severinelenceinte volumio[866]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 25 13:48:46 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:48:46 severinelenceinte volumio[866]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 25 13:48:46 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 25 13:48:46 severinelenceinte go-librespot[1793]: time="2025-10-25T13:48:46+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 25 13:48:46 severinelenceinte go-librespot[1793]: time="2025-10-25T13:48:46+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 25 13:48:46 severinelenceinte go-librespot[1793]: time="2025-10-25T13:48:46+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 25 13:48:46 severinelenceinte sudo[1805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:48:46 severinelenceinte sudo[1805]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:46 severinelenceinte go-librespot[1793]: time="2025-10-25T13:48:46+02:00" level=info msg="zeroconf server listening on port 38699" Oct 25 13:48:46 severinelenceinte sudo[1805]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:48 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:48:48 severinelenceinte sudo[1809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:48:48 severinelenceinte sudo[1809]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:48 severinelenceinte sudo[1809]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:50 severinelenceinte volumio[1269]: ............++++ Oct 25 13:48:50 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:48:50 severinelenceinte sudo[1827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:48:50 severinelenceinte sudo[1827]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:51 severinelenceinte sudo[1827]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:53 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:48:53 severinelenceinte sudo[1831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:48:53 severinelenceinte sudo[1831]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:53 severinelenceinte sudo[1831]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:55 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:48:55 severinelenceinte sudo[1835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:48:55 severinelenceinte sudo[1835]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:55 severinelenceinte sudo[1835]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:57 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:48:57 severinelenceinte sudo[1842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:48:58 severinelenceinte sudo[1842]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:48:58 severinelenceinte sudo[1842]: pam_unix(sudo:session): session closed for user root Oct 25 13:48:58 severinelenceinte ntpd[575]: Soliciting pool server 195.154.185.215 Oct 25 13:48:58 severinelenceinte ntpd[575]: Soliciting pool server 162.159.200.1 Oct 25 13:48:59 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 25 13:48:59 severinelenceinte ntpd[575]: Soliciting pool server 195.35.29.178 Oct 25 13:48:59 severinelenceinte ntpd[575]: Soliciting pool server 82.65.248.56 Oct 25 13:48:59 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 25 13:48:59 severinelenceinte volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 13:48:59 severinelenceinte volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 13:48:59 severinelenceinte volumio[866]: info: Starting MyVolumio Remote Streaming Endpoints Oct 25 13:48:59 severinelenceinte volumio[866]: info: MyVolumio login type: Token Oct 25 13:49:00 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 25 13:49:00 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 25 13:49:00 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:49:00 severinelenceinte sudo[1850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:49:00 severinelenceinte ntpd[575]: Soliciting pool server 212.85.158.10 Oct 25 13:49:00 severinelenceinte ntpd[575]: Soliciting pool server 38.143.19.16 Oct 25 13:49:00 severinelenceinte sudo[1850]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:49:00 severinelenceinte sudo[1850]: pam_unix(sudo:session): session closed for user root Oct 25 13:49:01 severinelenceinte ntpd[575]: Soliciting pool server 62.210.129.125 Oct 25 13:49:01 severinelenceinte ntpd[575]: Soliciting pool server 82.64.42.185 Oct 25 13:49:01 severinelenceinte volumio-remote-updater[410]: [2025-10-25 13:49:01] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Oct 25 13:49:02 severinelenceinte ntpd[575]: Soliciting pool server 45.140.164.53 Oct 25 13:49:02 severinelenceinte ntpd[575]: Soliciting pool server 217.154.21.219 Oct 25 13:49:02 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:49:02 severinelenceinte sudo[1864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:49:02 severinelenceinte sudo[1864]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:49:02 severinelenceinte sudo[1864]: pam_unix(sudo:session): session closed for user root Oct 25 13:49:03 severinelenceinte ntpd[575]: Soliciting pool server 51.158.153.13 Oct 25 13:49:03 severinelenceinte ntpd[575]: Soliciting pool server 172.232.44.156 Oct 25 13:49:04 severinelenceinte ntpd[575]: Soliciting pool server 82.67.126.242 Oct 25 13:49:04 severinelenceinte ntpd[575]: Soliciting pool server 51.68.44.27 Oct 25 13:49:04 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:49:05 severinelenceinte sudo[1868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:49:05 severinelenceinte sudo[1868]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:49:05 severinelenceinte sudo[1868]: pam_unix(sudo:session): session closed for user root Oct 25 13:49:05 severinelenceinte ntpd[575]: Soliciting pool server 109.190.177.203 Oct 25 13:49:05 severinelenceinte ntpd[575]: Soliciting pool server 82.67.41.119 Oct 25 13:49:11 severinelenceinte ntpd[575]: receive: Unexpected origin timestamp 0xeca737b1.55e87e69 does not match aorg 0000000000.00000000 from server@217.154.21.219 xmt 0xeca737b7.3072ea12 Oct 25 13:49:11 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:11+02:00" level=debug msg="obtained new client token: AAARIHhg7hkAPUiYBOUq6vF38hATE4M+otscpkcdERsJhAuXFVd6ArL4QQw80Zhtihdr2c1j4Ay8K2cRZDTxsTJp5+v5obiKpoeberIxzu1mVIi54y8U2fw9bOhT3gajk9ePh7o0P1f+x50uef5hgM/V5eBcC/dCdvsU/B6WbCp88ElLfUMqpZNsVJ/tbtZ9WTwJy/XWA88TwBfhTkVepvg8B0FVMIcmMo5izD33fH9BHDcTfqRVPdA=" Oct 25 13:49:11 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:11+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 25 13:49:11 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:11+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 25 13:49:11 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:11+02:00" level=debug msg="completed keyexchange" Oct 25 13:49:11 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:11+02:00" level=debug msg="completed challenge" Oct 25 13:49:11 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:11+02:00" level=info msg="authenticated AP" username="at*********************s9" Oct 25 13:49:11 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:11+02:00" level=info msg="authenticated Login5" username="at*********************s9" Oct 25 13:49:11 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:11+02:00" level=info msg="accepted zeroconf from iPhone" username="at*********************s9" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=debug msg="dealer connection opened" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=trace msg="starting accesspoint recv loop" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=trace msg="starting dealer recv loop" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=trace msg="received accesspoint ping" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=debug msg="received connection id: YWFmZWU4Y2YtNmNj...M0JDRDUzREIwMA==" Oct 25 13:49:12 severinelenceinte ntpd[575]: Soliciting pool server 2001:bc8:255e:200::1 Oct 25 13:49:12 severinelenceinte volumio-remote-updater[410]: [2025-10-25 13:49:12] [connect] Successful connection Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=debug msg="put connect state because NEW_DEVICE" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=debug msg="update volume requested to 65535/65535" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=trace msg="received accesspoint pong ack" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=trace msg="emitting websocket event: volume" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=debug msg="handling transfer player command from b6a45996bb13cdea5d5824cac941f0a2c1aa9c1b" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1EVHGWrwldPRtj" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1EVHGWrwldPRtj" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=debug msg="shuffled context with seed 14881383369933829074 (len: 50, keep: 14)" uri="spotify:playlist:37i9dQZF1EVHGWrwldPRtj" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=debug msg="loading track (paused: false, position: 107273ms)" uri="spotify:track:3FAclTFfvUuQYnEsptbK8w" Oct 25 13:49:12 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:12+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 25 13:49:13 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:13+02:00" level=trace msg="emitting websocket event: will_play" Oct 25 13:49:13 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:13+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1133" Oct 25 13:49:13 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:13+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 391" Oct 25 13:49:13 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:49:13 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:13+02:00" level=debug msg="selected format OGG_VORBIS_320 (44b49ff58b10b13fda4029fdd81ed813a6ddcd2d)" uri="spotify:track:3FAclTFfvUuQYnEsptbK8w" Oct 25 13:49:13 severinelenceinte sudo[1872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:49:13 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:13+02:00" level=debug msg="requested aes key for file 44b49ff58b10b13fda4029fdd81ed813a6ddcd2d, gid: 3FAclTFfvUuQYnEsptbK8w" Oct 25 13:49:13 severinelenceinte sudo[1872]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:49:13 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:13+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:3FAclTFfvUuQYnEsptbK8w" Oct 25 13:49:13 severinelenceinte sudo[1872]: pam_unix(sudo:session): session closed for user root Oct 25 13:49:13 severinelenceinte volumio[1269]: ............................++++ Oct 25 13:49:13 severinelenceinte volumio[1269]: e is 65537 (0x010001) Oct 25 13:49:13 severinelenceinte volumio[1269]: writing RSA key Oct 25 13:49:14 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:14+02:00" level=debug msg="fetched first chunk of 16, total size is 7907960 bytes" uri="spotify:track:3FAclTFfvUuQYnEsptbK8w" Oct 25 13:49:15 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:49:15 severinelenceinte sudo[1891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:49:15 severinelenceinte sudo[1891]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:49:15 severinelenceinte sudo[1891]: pam_unix(sudo:session): session closed for user root Oct 25 13:49:18 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:49:18 severinelenceinte sudo[1909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:49:18 severinelenceinte sudo[1909]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:49:18 severinelenceinte sudo[1909]: pam_unix(sudo:session): session closed for user root Oct 25 13:49:18 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:18+02:00" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:3FAclTFfvUuQYnEsptbK8w" Oct 25 13:49:18 severinelenceinte volumio[866]: info: Starting Streaming Service Transparent Proxy Oct 25 13:49:19 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 25 13:49:19 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 25 13:49:19 severinelenceinte volumio[866]: info: Streaming services startup Oct 25 13:49:19 severinelenceinte volumio[866]: info: Starting Streaming Daemon Oct 25 13:49:19 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:19+02:00" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:3FAclTFfvUuQYnEsptbK8w" Oct 25 13:49:19 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:19+02:00" level=debug msg="fetched chunk 7/15, size: 524288" uri="spotify:track:3FAclTFfvUuQYnEsptbK8w" Oct 25 13:49:19 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:19+02:00" level=debug msg="fetched chunk 9/15, size: 524288" uri="spotify:track:3FAclTFfvUuQYnEsptbK8w" Oct 25 13:49:19 severinelenceinte sudo[1919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 25 13:49:19 severinelenceinte sudo[1919]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:49:19 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:19+02:00" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:3FAclTFfvUuQYnEsptbK8w" Oct 25 13:49:19 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:19+02:00" level=debug msg="fetched chunk 8/15, size: 524288" uri="spotify:track:3FAclTFfvUuQYnEsptbK8w" Oct 25 13:49:19 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:19+02:00" level=debug msg="fetched chunk 6/15, size: 524288" uri="spotify:track:3FAclTFfvUuQYnEsptbK8w" Oct 25 13:49:19 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:19+02:00" level=trace msg="seek to 107273ms (diff: 138ms, samples: 4730739, bytes: 3380216)" uri="spotify:track:3FAclTFfvUuQYnEsptbK8w" Oct 25 13:49:19 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:19+02:00" level=debug msg="created new output device" Oct 25 13:49:20 severinelenceinte sudo[1919]: pam_unix(sudo:session): session closed for user root Oct 25 13:49:20 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:20+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Oct 25 13:49:20 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:20+02:00" level=info msg="loaded track \"Back To Black\" (paused: false, position: 107273ms, duration: 241293ms, prefetched: false)" uri="spotify:track:3FAclTFfvUuQYnEsptbK8w" Oct 25 13:49:20 severinelenceinte volumio[866]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 25 13:49:20 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:20+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 25 13:49:20 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:20+02:00" level=trace msg="scheduling prefetch in 104s" Oct 25 13:49:20 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:20+02:00" level=trace msg="emitting websocket event: metadata" Oct 25 13:49:20 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:20+02:00" level=trace msg="emitting websocket event: active" Oct 25 13:49:20 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:20+02:00" level=debug msg="sending successful reply for dealer request" Oct 25 13:49:20 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:20+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Oct 25 13:49:20 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:20+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Oct 25 13:49:20 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:20+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 25 13:49:20 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:20+02:00" level=trace msg="emitting websocket event: playing" Oct 25 13:49:20 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:49:20 severinelenceinte sudo[1927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:49:20 severinelenceinte sudo[1927]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:49:20 severinelenceinte sudo[1927]: pam_unix(sudo:session): session closed for user root Oct 25 13:49:21 severinelenceinte volumio[866]: info: Getting Spotify volume Oct 25 13:49:21 severinelenceinte volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 25 13:49:21 severinelenceinte volumio[866]: info: Volumio Network Manager: Network status updated: 2 Oct 25 13:49:21 severinelenceinte volumio[866]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Oct 25 13:49:21 severinelenceinte volumio[866]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Oct 25 13:49:21 severinelenceinte volumio[866]: info: Setting Volumio Volume from Spotify: 100 Oct 25 13:49:21 severinelenceinte volumio[866]: info: VolumeController::SetAlsaVolume100 Oct 25 13:49:21 severinelenceinte volumio[866]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3FAclTFfvUuQYnEsptbK8w","play_origin":"com.spotify.service.carplayv2"}} Oct 25 13:49:21 severinelenceinte volumio[866]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3FAclTFfvUuQYnEsptbK8w","name":"Back To Black","artist_names":["Amy Winehouse"],"album_name":"Back To Black (Deluxe Edition)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0276ffb5b5ab045d22c81235c1","position":107273,"duration":241293,"release_date":"year:2006","track_number":5,"disc_number":1}} Oct 25 13:49:21 severinelenceinte volumio[866]: SPOTIFY: received: {"type":"active","data":null} Oct 25 13:49:21 severinelenceinte volumio[866]: info: Aligning Spotify Volume to Volumio Volume Oct 25 13:49:21 severinelenceinte volumio[866]: info: CoreCommandRouter::volumioGetState Oct 25 13:49:21 severinelenceinte volumio[866]: info: CorePlayQueue::getTrack 0 Oct 25 13:49:21 severinelenceinte volumio[866]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:3FAclTFfvUuQYnEsptbK8w","resume":false,"play_origin":"com.spotify.service.carplayv2"}} Oct 25 13:49:21 severinelenceinte volumio[866]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 13:49:21 severinelenceinte volumio[866]: TypeError: Cannot read property 'service' of undefined Oct 25 13:49:21 severinelenceinte volumio[866]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Oct 25 13:49:21 severinelenceinte volumio[866]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:272:18) Oct 25 13:49:21 severinelenceinte volumio[866]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Oct 25 13:49:21 severinelenceinte volumio[866]: at WebSocket.emit (events.js:315:20) Oct 25 13:49:21 severinelenceinte volumio[866]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Oct 25 13:49:21 severinelenceinte volumio[866]: at Receiver.emit (events.js:315:20) Oct 25 13:49:21 severinelenceinte volumio[866]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Oct 25 13:49:21 severinelenceinte volumio[866]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Oct 25 13:49:21 severinelenceinte volumio[866]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Oct 25 13:49:21 severinelenceinte volumio[866]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Oct 25 13:49:21 severinelenceinte volumio[866]: at writeOrBuffer (internal/streams/writable.js:358:12) Oct 25 13:49:21 severinelenceinte volumio[866]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Oct 25 13:49:21 severinelenceinte volumio[866]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Oct 25 13:49:21 severinelenceinte volumio[866]: at Socket.emit (events.js:315:20) Oct 25 13:49:21 severinelenceinte volumio[866]: at addChunk (internal/streams/readable.js:309:12) Oct 25 13:49:21 severinelenceinte volumio[866]: at readableAddChunk (internal/streams/readable.js:284:9) Oct 25 13:49:21 severinelenceinte volumio[866]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 13:49:22 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:49:22 severinelenceinte sudo[1942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:49:22 severinelenceinte sudo[1942]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:49:23 severinelenceinte sudo[1942]: pam_unix(sudo:session): session closed for user root Oct 25 13:49:24 severinelenceinte volumio-remote-updater[410]: [2025-10-25 13:49:24] [connect] Successful connection Oct 25 13:49:25 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:49:25 severinelenceinte sudo[1948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:49:25 severinelenceinte sudo[1948]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:49:25 severinelenceinte sudo[1948]: pam_unix(sudo:session): session closed for user root Oct 25 13:49:27 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:49:27 severinelenceinte sudo[1952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:49:27 severinelenceinte sudo[1952]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:49:27 severinelenceinte sudo[1952]: pam_unix(sudo:session): session closed for user root Oct 25 13:49:29 severinelenceinte go-librespot[1793]: time="2025-10-25T13:49:29+02:00" level=debug msg="fetched chunk 10/15, size: 524288" uri="spotify:track:3FAclTFfvUuQYnEsptbK8w" Oct 25 13:49:29 severinelenceinte gpio_veille.sh[899]: 🟢 Mode actif Oct 25 13:49:29 severinelenceinte sudo[1970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-25 13:48 Oct 25 13:49:29 severinelenceinte sudo[1970]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:49:29 severinelenceinte sudo[1972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 13:49:29 severinelenceinte sudo[1972]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 13:49:29 severinelenceinte sudo[1972]: pam_unix(sudo:session): session closed for user root PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"