-- Logs begin at Sat 2025-06-28 06:56:30 UTC, end at Sat 2025-06-28 09:49:21 UTC. -- Jun 28 09:48:02 volumio volumio[1171]: info: Initializing connection to go-librespot Websocket Jun 28 09:48:02 volumio volumio[1171]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 28 09:48:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 28 09:48:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 183. Jun 28 09:48:03 volumio systemd[1]: Stopped go-librespot Daemon. Jun 28 09:48:03 volumio systemd[1]: Started go-librespot Daemon. Jun 28 09:48:03 volumio go-librespot[14437]: Librespot-go daemon starting... Jun 28 09:48:03 volumio go-librespot[14437]: time="2025-06-28T09:48:03Z" level=info msg="generated new device id: 0e7ccb316c2312dcb2c0bc04b5d410a2052c2199" Jun 28 09:48:03 volumio go-librespot[14437]: time="2025-06-28T09:48:03Z" level=debug msg="stored credentials found for 31nk55nngtdiv4nzr64jrii67iwy" Jun 28 09:48:05 volumio volumio[1171]: info: Initializing connection to go-librespot Websocket Jun 28 09:48:05 volumio go-librespot[14437]: time="2025-06-28T09:48:05Z" level=debug msg="new websocket client" Jun 28 09:48:05 volumio volumio[1171]: info: Connection to go-librespot Websocket established Jun 28 09:48:08 volumio volumio[1171]: info: Getting Spotify volume Jun 28 09:48:08 volumio volumio[1171]: 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 28 09:48:08 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:48:08 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:48:08 volumio volumio[1171]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jun 28 09:48:08 volumio volumio[1171]: SPOTIFY: SPOTIFY VOLUME 59 Jun 28 09:48:08 volumio volumio[1171]: SPOTIFY: VOLUMIO VOLUME 60 Jun 28 09:48:08 volumio volumio[1171]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 28 09:48:13 volumio go-librespot[14437]: time="2025-06-28T09:48:13Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Jun 28 09:48:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 28 09:48:13 volumio volumio[1171]: (node:1171) UnhandledPromiseRejectionWarning: Error: socket hang up Jun 28 09:48:13 volumio volumio[1171]: at connResetException (internal/errors.js:639:14) Jun 28 09:48:13 volumio volumio[1171]: at Socket.socketOnEnd (_http_client.js:499:23) Jun 28 09:48:13 volumio volumio[1171]: at Socket.emit (events.js:412:35) Jun 28 09:48:13 volumio volumio[1171]: at endReadableNT (internal/streams/readable.js:1333:12) Jun 28 09:48:13 volumio volumio[1171]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 28 09:48:13 volumio volumio[1171]: (node:1171) 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: 222) Jun 28 09:48:13 volumio volumio[1171]: info: Connection to go-librespot Websocket closed Jun 28 09:48:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 28 09:48:16 volumio volumio[1171]: info: Initializing connection to go-librespot Websocket Jun 28 09:48:16 volumio volumio[1171]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 28 09:48:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 28 09:48:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 184. Jun 28 09:48:16 volumio systemd[1]: Stopped go-librespot Daemon. Jun 28 09:48:16 volumio systemd[1]: Started go-librespot Daemon. Jun 28 09:48:16 volumio go-librespot[14487]: Librespot-go daemon starting... Jun 28 09:48:16 volumio go-librespot[14487]: time="2025-06-28T09:48:16Z" level=info msg="generated new device id: 34b28e4140d7e2e400e2cfb324316db25a822c28" Jun 28 09:48:16 volumio go-librespot[14487]: time="2025-06-28T09:48:16Z" level=debug msg="stored credentials found for 31nk55nngtdiv4nzr64jrii67iwy" Jun 28 09:48:19 volumio volumio[1171]: info: Initializing connection to go-librespot Websocket Jun 28 09:48:19 volumio go-librespot[14487]: time="2025-06-28T09:48:19Z" level=debug msg="new websocket client" Jun 28 09:48:19 volumio volumio[1171]: info: Connection to go-librespot Websocket established Jun 28 09:48:20 volumio volumio[1171]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jun 28 09:48:20 volumio volumio[1171]: LoungeScreenDisconnected { Jun 28 09:48:20 volumio volumio[1171]: AID: null, Jun 28 09:48:20 volumio volumio[1171]: name: 'loungeScreenDisconnected', Jun 28 09:48:20 volumio volumio[1171]: payload: {} Jun 28 09:48:20 volumio volumio[1171]: } Jun 28 09:48:20 volumio volumio[1171]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jun 28 09:48:20 volumio volumio[1171]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jun 28 09:48:20 volumio volumio[1171]: Stack trace: Jun 28 09:48:20 volumio volumio[1171]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jun 28 09:48:20 volumio volumio[1171]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jun 28 09:48:20 volumio volumio[1171]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jun 28 09:48:20 volumio volumio[1171]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jun 28 09:48:20 volumio volumio[1171]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jun 28 09:48:20 volumio volumio[1171]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jun 28 09:48:20 volumio volumio[1171]: at runMicrotasks () Jun 28 09:48:20 volumio volumio[1171]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Jun 28 09:48:22 volumio volumio[1171]: info: Getting Spotify volume Jun 28 09:48:22 volumio volumio[1171]: 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 28 09:48:22 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:48:22 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:48:22 volumio volumio[1171]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jun 28 09:48:22 volumio volumio[1171]: SPOTIFY: SPOTIFY VOLUME 59 Jun 28 09:48:22 volumio volumio[1171]: SPOTIFY: VOLUMIO VOLUME 60 Jun 28 09:48:22 volumio volumio[1171]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 28 09:48:26 volumio go-librespot[14487]: time="2025-06-28T09:48:26Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Jun 28 09:48:26 volumio volumio[1171]: (node:1171) UnhandledPromiseRejectionWarning: Error: socket hang up Jun 28 09:48:26 volumio volumio[1171]: at connResetException (internal/errors.js:639:14) Jun 28 09:48:26 volumio volumio[1171]: at Socket.socketOnEnd (_http_client.js:499:23) Jun 28 09:48:26 volumio volumio[1171]: at Socket.emit (events.js:412:35) Jun 28 09:48:26 volumio volumio[1171]: at endReadableNT (internal/streams/readable.js:1333:12) Jun 28 09:48:26 volumio volumio[1171]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 28 09:48:26 volumio volumio[1171]: (node:1171) 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: 225) Jun 28 09:48:26 volumio volumio[1171]: info: Connection to go-librespot Websocket closed Jun 28 09:48:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 28 09:48:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 28 09:48:29 volumio volumio[1171]: info: Initializing connection to go-librespot Websocket Jun 28 09:48:29 volumio volumio[1171]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 28 09:48:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 28 09:48:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 185. Jun 28 09:48:30 volumio systemd[1]: Stopped go-librespot Daemon. Jun 28 09:48:30 volumio systemd[1]: Started go-librespot Daemon. Jun 28 09:48:30 volumio go-librespot[14550]: Librespot-go daemon starting... Jun 28 09:48:30 volumio go-librespot[14550]: time="2025-06-28T09:48:30Z" level=info msg="generated new device id: 042c4453ac1d6a9afaa3547e781c457ff8f59e08" Jun 28 09:48:30 volumio go-librespot[14550]: time="2025-06-28T09:48:30Z" level=debug msg="stored credentials found for 31nk55nngtdiv4nzr64jrii67iwy" Jun 28 09:48:32 volumio volumio[1171]: info: Initializing connection to go-librespot Websocket Jun 28 09:48:32 volumio volumio[1171]: info: Connection to go-librespot Websocket established Jun 28 09:48:32 volumio go-librespot[14550]: time="2025-06-28T09:48:32Z" level=debug msg="new websocket client" Jun 28 09:48:35 volumio volumio[1171]: info: Getting Spotify volume Jun 28 09:48:35 volumio volumio[1171]: 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 28 09:48:35 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:48:35 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:48:35 volumio volumio[1171]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jun 28 09:48:35 volumio volumio[1171]: SPOTIFY: SPOTIFY VOLUME 59 Jun 28 09:48:35 volumio volumio[1171]: SPOTIFY: VOLUMIO VOLUME 60 Jun 28 09:48:35 volumio volumio[1171]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 28 09:48:40 volumio go-librespot[14550]: time="2025-06-28T09:48:40Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Jun 28 09:48:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 28 09:48:40 volumio volumio[1171]: (node:1171) UnhandledPromiseRejectionWarning: Error: socket hang up Jun 28 09:48:40 volumio volumio[1171]: at connResetException (internal/errors.js:639:14) Jun 28 09:48:40 volumio volumio[1171]: at Socket.socketOnEnd (_http_client.js:499:23) Jun 28 09:48:40 volumio volumio[1171]: at Socket.emit (events.js:412:35) Jun 28 09:48:40 volumio volumio[1171]: at endReadableNT (internal/streams/readable.js:1333:12) Jun 28 09:48:40 volumio volumio[1171]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 28 09:48:40 volumio volumio[1171]: (node:1171) 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: 226) Jun 28 09:48:40 volumio volumio[1171]: info: Connection to go-librespot Websocket closed Jun 28 09:48:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 28 09:48:42 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 09:48:42 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 09:48:42 volumio volumio[1171]: info: Discovery: Getting this device information Jun 28 09:48:42 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:48:42 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:48:42 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 09:48:42 volumio volumio[1171]: verbose: New Socket.io Connection to 192.168.1.36:3000 from 192.168.1.33 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jun 28 09:48:42 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:48:42 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:48:42 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 28 09:48:42 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 28 09:48:43 volumio volumio[1171]: info: Initializing connection to go-librespot Websocket Jun 28 09:48:43 volumio volumio[1171]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 28 09:48:43 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 09:48:43 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 09:48:43 volumio volumio[1171]: info: Discovery: Getting this device information Jun 28 09:48:43 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:48:43 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:48:43 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 09:48:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 28 09:48:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 186. Jun 28 09:48:43 volumio systemd[1]: Stopped go-librespot Daemon. Jun 28 09:48:43 volumio systemd[1]: Started go-librespot Daemon. Jun 28 09:48:43 volumio go-librespot[14644]: Librespot-go daemon starting... Jun 28 09:48:43 volumio go-librespot[14644]: time="2025-06-28T09:48:43Z" level=info msg="generated new device id: bca1ca109a5b542e4eb583ddd9c6dfd7def74c46" Jun 28 09:48:43 volumio go-librespot[14644]: time="2025-06-28T09:48:43Z" level=debug msg="stored credentials found for 31nk55nngtdiv4nzr64jrii67iwy" Jun 28 09:48:44 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:48:44 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:48:46 volumio volumio[1171]: info: Initializing connection to go-librespot Websocket Jun 28 09:48:46 volumio go-librespot[14644]: time="2025-06-28T09:48:46Z" level=debug msg="new websocket client" Jun 28 09:48:46 volumio volumio[1171]: info: Connection to go-librespot Websocket established Jun 28 09:48:47 volumio sudo[14675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 09:48:47 volumio sudo[14673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 28 09:48:47 volumio sudo[14673]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 09:48:47 volumio sudo[14675]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 09:48:47 volumio sudo[14673]: pam_unix(sudo:session): session closed for user root Jun 28 09:48:47 volumio sudo[14675]: pam_unix(sudo:session): session closed for user root Jun 28 09:48:47 volumio volumio[1171]: verbose: New Socket.io Connection to 192.168.1.36 from 192.168.1.33 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 Jun 28 09:48:48 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 28 09:48:48 volumio sudo[14681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 28 09:48:48 volumio sudo[14681]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 09:48:48 volumio sudo[14681]: pam_unix(sudo:session): session closed for user root Jun 28 09:48:48 volumio sudo[14684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 09:48:48 volumio sudo[14684]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 09:48:48 volumio sudo[14684]: pam_unix(sudo:session): session closed for user root Jun 28 09:48:48 volumio volumio[1171]: verbose: New Socket.io Connection to 192.168.1.36 from 192.168.1.33 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 8 Jun 28 09:48:48 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 28 09:48:48 volumio volumio[1171]: info: CoreCommandRouter::volumioGetVisibleSources Jun 28 09:48:48 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 09:48:48 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:48:48 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:48:48 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 28 09:48:48 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 28 09:48:48 volumio volumio[1171]: info: Received Get System Info Jun 28 09:48:48 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 09:48:48 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 09:48:48 volumio volumio[1171]: info: Discovery: Getting this device information Jun 28 09:48:48 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:48:48 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:48:48 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 09:48:48 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:48:48 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:48:48 volumio volumio[1171]: info: Listing playlists Jun 28 09:48:48 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 28 09:48:48 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 28 09:48:49 volumio volumio[1171]: info: Getting Spotify volume Jun 28 09:48:49 volumio volumio[1171]: 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 28 09:48:49 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:48:49 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:48:49 volumio volumio[1171]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jun 28 09:48:49 volumio volumio[1171]: SPOTIFY: SPOTIFY VOLUME 59 Jun 28 09:48:49 volumio volumio[1171]: SPOTIFY: VOLUMIO VOLUME 60 Jun 28 09:48:49 volumio volumio[1171]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 28 09:48:49 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:48:49 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:48:50 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 28 09:48:50 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 28 09:48:50 volumio volumio[1171]: info: Received Get System Info Jun 28 09:48:50 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 09:48:50 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 09:48:50 volumio volumio[1171]: info: Discovery: Getting this device information Jun 28 09:48:50 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:48:50 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:48:50 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 09:48:50 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 28 09:48:53 volumio go-librespot[14644]: time="2025-06-28T09:48:53Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Jun 28 09:48:53 volumio volumio[1171]: (node:1171) UnhandledPromiseRejectionWarning: Error: socket hang up Jun 28 09:48:53 volumio volumio[1171]: at connResetException (internal/errors.js:639:14) Jun 28 09:48:53 volumio volumio[1171]: at Socket.socketOnEnd (_http_client.js:499:23) Jun 28 09:48:53 volumio volumio[1171]: at Socket.emit (events.js:412:35) Jun 28 09:48:53 volumio volumio[1171]: at endReadableNT (internal/streams/readable.js:1333:12) Jun 28 09:48:53 volumio volumio[1171]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 28 09:48:53 volumio volumio[1171]: (node:1171) 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: 228) Jun 28 09:48:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 28 09:48:53 volumio volumio[1171]: info: Connection to go-librespot Websocket closed Jun 28 09:48:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 28 09:48:54 volumio volumio[1171]: info: CoreCommandRouter::volumioGetQueue Jun 28 09:48:54 volumio volumio[1171]: info: CoreStateMachine::getQueue Jun 28 09:48:54 volumio volumio[1171]: info: CorePlayQueue::getQueue Jun 28 09:48:56 volumio volumio[1171]: info: Initializing connection to go-librespot Websocket Jun 28 09:48:56 volumio volumio[1171]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 28 09:48:56 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 28 09:48:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 187. Jun 28 09:48:56 volumio systemd[1]: Stopped go-librespot Daemon. Jun 28 09:48:56 volumio systemd[1]: Started go-librespot Daemon. Jun 28 09:48:56 volumio go-librespot[14704]: Librespot-go daemon starting... Jun 28 09:48:56 volumio go-librespot[14704]: time="2025-06-28T09:48:56Z" level=info msg="generated new device id: 848d3fc5495075416971e78805b4d85b58402bd2" Jun 28 09:48:56 volumio go-librespot[14704]: time="2025-06-28T09:48:56Z" level=debug msg="stored credentials found for 31nk55nngtdiv4nzr64jrii67iwy" Jun 28 09:48:56 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 28 09:48:56 volumio volumio[1171]: info: CURURI: music-library Jun 28 09:48:56 volumio volumio[1171]: info: Preload queue cleared Jun 28 09:48:58 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 28 09:48:59 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 28 09:48:59 volumio volumio[1171]: info: CURURI: music-library/INTERNAL Jun 28 09:48:59 volumio volumio[1171]: info: Preload queue cleared Jun 28 09:48:59 volumio volumio[1171]: info: Initializing connection to go-librespot Websocket Jun 28 09:48:59 volumio go-librespot[14704]: time="2025-06-28T09:48:59Z" level=debug msg="new websocket client" Jun 28 09:48:59 volumio volumio[1171]: info: Connection to go-librespot Websocket established Jun 28 09:49:02 volumio volumio[1171]: info: Getting Spotify volume Jun 28 09:49:02 volumio volumio[1171]: 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 28 09:49:02 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:49:02 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:49:02 volumio volumio[1171]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jun 28 09:49:02 volumio volumio[1171]: SPOTIFY: SPOTIFY VOLUME 59 Jun 28 09:49:02 volumio volumio[1171]: SPOTIFY: VOLUMIO VOLUME 60 Jun 28 09:49:02 volumio volumio[1171]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 28 09:49:06 volumio go-librespot[14704]: time="2025-06-28T09:49:06Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Jun 28 09:49:06 volumio volumio[1171]: (node:1171) UnhandledPromiseRejectionWarning: Error: socket hang up Jun 28 09:49:06 volumio volumio[1171]: at connResetException (internal/errors.js:639:14) Jun 28 09:49:06 volumio volumio[1171]: at Socket.socketOnEnd (_http_client.js:499:23) Jun 28 09:49:06 volumio volumio[1171]: at Socket.emit (events.js:412:35) Jun 28 09:49:06 volumio volumio[1171]: at endReadableNT (internal/streams/readable.js:1333:12) Jun 28 09:49:06 volumio volumio[1171]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 28 09:49:06 volumio volumio[1171]: (node:1171) 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: 229) Jun 28 09:49:06 volumio volumio[1171]: info: Connection to go-librespot Websocket closed Jun 28 09:49:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 28 09:49:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 28 09:49:07 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 28 09:49:07 volumio volumio[1171]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 28 09:49:07 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 28 09:49:07 volumio volumio[1171]: info: Received Get System Version Jun 28 09:49:07 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 28 09:49:07 volumio volumio[1171]: info: Received Get System Info Jun 28 09:49:07 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 09:49:07 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 09:49:07 volumio volumio[1171]: info: Discovery: Getting this device information Jun 28 09:49:07 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:49:07 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:49:07 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 09:49:07 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 09:49:07 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 09:49:07 volumio volumio[1171]: info: Discovery: Getting this device information Jun 28 09:49:07 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:49:07 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:49:07 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 09:49:09 volumio volumio[1171]: info: Initializing connection to go-librespot Websocket Jun 28 09:49:09 volumio volumio[1171]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 28 09:49:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 28 09:49:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 188. Jun 28 09:49:10 volumio systemd[1]: Stopped go-librespot Daemon. Jun 28 09:49:10 volumio systemd[1]: Started go-librespot Daemon. Jun 28 09:49:10 volumio go-librespot[14769]: Librespot-go daemon starting... Jun 28 09:49:10 volumio go-librespot[14769]: time="2025-06-28T09:49:10Z" level=info msg="generated new device id: ca15dc9149f148d17b4e844841f5bfe1f9703977" Jun 28 09:49:10 volumio go-librespot[14769]: time="2025-06-28T09:49:10Z" level=debug msg="stored credentials found for 31nk55nngtdiv4nzr64jrii67iwy" Jun 28 09:49:12 volumio volumio[1171]: info: Initializing connection to go-librespot Websocket Jun 28 09:49:12 volumio go-librespot[14769]: time="2025-06-28T09:49:12Z" level=debug msg="new websocket client" Jun 28 09:49:12 volumio volumio[1171]: info: Connection to go-librespot Websocket established Jun 28 09:49:15 volumio volumio[1171]: info: Getting Spotify volume Jun 28 09:49:15 volumio volumio[1171]: 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 28 09:49:15 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 28 09:49:15 volumio volumio[1171]: info: CorePlayQueue::getTrack 4 Jun 28 09:49:15 volumio volumio[1171]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jun 28 09:49:15 volumio volumio[1171]: SPOTIFY: SPOTIFY VOLUME 59 Jun 28 09:49:15 volumio volumio[1171]: SPOTIFY: VOLUMIO VOLUME 60 Jun 28 09:49:15 volumio volumio[1171]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 28 09:49:20 volumio go-librespot[14769]: time="2025-06-28T09:49:20Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Jun 28 09:49:20 volumio volumio[1171]: (node:1171) UnhandledPromiseRejectionWarning: Error: socket hang up Jun 28 09:49:20 volumio volumio[1171]: at connResetException (internal/errors.js:639:14) Jun 28 09:49:20 volumio volumio[1171]: at Socket.socketOnEnd (_http_client.js:499:23) Jun 28 09:49:20 volumio volumio[1171]: at Socket.emit (events.js:412:35) Jun 28 09:49:20 volumio volumio[1171]: at endReadableNT (internal/streams/readable.js:1333:12) Jun 28 09:49:20 volumio volumio[1171]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 28 09:49:20 volumio volumio[1171]: (node:1171) 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: 230) Jun 28 09:49:20 volumio volumio[1171]: info: Connection to go-librespot Websocket closed Jun 28 09:49:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 28 09:49:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 28 09:49:20 volumio volumio[1171]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 09:49:20 volumio volumio[1171]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Jun 28 09:49:20 volumio volumio[1171]: code: 'auth/network-request-failed', Jun 28 09:49:20 volumio volumio[1171]: a: null Jun 28 09:49:20 volumio volumio[1171]: } Jun 28 09:49:20 volumio volumio[1171]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 09:49:21 volumio sudo[14822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-28 09:48 Jun 28 09:49:21 volumio sudo[14822]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"