-- Logs begin at Thu 2025-04-24 11:37:00 CEST, end at Thu 2025-04-24 12:00:03 CEST. -- Apr 24 11:59:00 volumio volumio[964]: info: Initializing connection to go-librespot Websocket Apr 24 11:59:00 volumio go-librespot[3199]: time="2025-04-24T11:59:00+02:00" level=debug msg="new websocket client" Apr 24 11:59:00 volumio volumio[964]: info: Connection to go-librespot Websocket established Apr 24 11:59:00 volumio volumio[964]: info: go-librespot daemon successfully initialized Apr 24 11:59:03 volumio volumio[964]: info: Getting Spotify volume Apr 24 11:59:03 volumio volumio[964]: info: Spotify volume: 100 Apr 24 11:59:03 volumio volumio[964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 24 11:59:03 volumio volumio[964]: info: Initializing connection to go-librespot Websocket Apr 24 11:59:03 volumio go-librespot[3199]: time="2025-04-24T11:59:03+02:00" level=debug msg="new websocket client" Apr 24 11:59:03 volumio volumio[964]: info: Connection to go-librespot Websocket established Apr 24 11:59:03 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Apr 24 11:59:03 volumio volumio[964]: info: CorePlayQueue::getTrack 4 Apr 24 11:59:03 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Apr 24 11:59:03 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 100 Apr 24 11:59:03 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 56 Apr 24 11:59:03 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 24 11:59:03 volumio volumio[964]: info: Setting Spotify Volume from Volumio: 56 Apr 24 11:59:04 volumio volumio[964]: SPOTIFY: SETTING SPOTIFY VOLUME 56 Apr 24 11:59:04 volumio volumio[964]: info: Sending Spotify command with payload to local API: /player/volume Apr 24 11:59:04 volumio go-librespot[3199]: time="2025-04-24T11:59:04+02:00" level=debug msg="update volume to 36699/65535" Apr 24 11:59:05 volumio go-librespot[3199]: time="2025-04-24T11:59:05+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 24 11:59:05 volumio go-librespot[3199]: time="2025-04-24T11:59:05+02:00" level=trace msg="emitting websocket event: volume" Apr 24 11:59:05 volumio volumio[964]: SPOTIFY: received: {"type":"volume","data":{"value":56,"max":100}} Apr 24 11:59:05 volumio volumio[964]: SPOTIFY: RECEIVED SPOTIFY VOLUME 56 Apr 24 11:59:05 volumio volumio[964]: SPOTIFY: received: {"type":"volume","data":{"value":56,"max":100}} Apr 24 11:59:05 volumio volumio[964]: SPOTIFY: RECEIVED SPOTIFY VOLUME 56 Apr 24 11:59:06 volumio volumio[964]: info: Getting Spotify volume Apr 24 11:59:06 volumio volumio[964]: info: Spotify volume: 56 Apr 24 11:59:06 volumio volumio[964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 24 11:59:06 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Apr 24 11:59:06 volumio volumio[964]: info: CorePlayQueue::getTrack 4 Apr 24 11:59:06 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Apr 24 11:59:28 volumio go-librespot[3199]: time="2025-04-24T11:59:28+02:00" level=trace msg="sent dealer ping" Apr 24 11:59:28 volumio go-librespot[3199]: time="2025-04-24T11:59:28+02:00" level=trace msg="received dealer pong" Apr 24 11:59:58 volumio go-librespot[3199]: time="2025-04-24T11:59:58+02:00" level=trace msg="sent dealer ping" Apr 24 11:59:58 volumio go-librespot[3199]: time="2025-04-24T11:59:58+02:00" level=trace msg="received dealer pong" Apr 24 12:00:01 volumio volumio[964]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 12:00:01 volumio volumio[964]: Error: connect ETIMEDOUT 151.101.134.79:443 Apr 24 12:00:01 volumio volumio[964]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 24 12:00:01 volumio volumio[964]: errno: -110, Apr 24 12:00:01 volumio volumio[964]: code: 'ETIMEDOUT', Apr 24 12:00:01 volumio volumio[964]: syscall: 'connect', Apr 24 12:00:01 volumio volumio[964]: address: '151.101.134.79', Apr 24 12:00:01 volumio volumio[964]: port: 443 Apr 24 12:00:01 volumio volumio[964]: } Apr 24 12:00:01 volumio volumio[964]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 12:00:03 volumio sudo[3267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-24 11:59 Apr 24 12:00:03 volumio sudo[3267]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"