-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Wed 2025-05-14 19:02:52 CST. -- May 14 19:01:00 volumio volumio[845]: info: May 14 19:01:00 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 14 19:01:00 volumio volumio[845]: info: Ignoring MPD Status Update May 14 19:01:00 volumio volumio[845]: info: May 14 19:01:00 volumio volumio[845]: ---------------------------- MPD announces state update: player May 14 19:01:00 volumio volumio[845]: info: ControllerMpd::getState May 14 19:01:00 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 14 19:01:00 volumio volumio[845]: info: May 14 19:01:00 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 14 19:01:00 volumio volumio[845]: info: Ignoring MPD Status Update May 14 19:01:00 volumio volumio[845]: info: May 14 19:01:00 volumio volumio[845]: ---------------------------- MPD announces state update: player May 14 19:01:00 volumio volumio[845]: info: ControllerMpd::getState May 14 19:01:00 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 14 19:01:00 volumio volumio[845]: info: May 14 19:01:00 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 14 19:01:00 volumio volumio[845]: info: Ignoring MPD Status Update May 14 19:01:00 volumio volumio[845]: info: May 14 19:01:00 volumio volumio[845]: ---------------------------- MPD announces state update: player May 14 19:01:00 volumio volumio[845]: info: ControllerMpd::getState May 14 19:01:00 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 14 19:01:00 volumio volumio[845]: info: ------------------------------ 11ms May 14 19:01:00 volumio volumio[845]: info: sendMpdCommand status took 6 milliseconds May 14 19:01:00 volumio volumio[845]: info: ------------------------------ 5ms May 14 19:01:00 volumio volumio[845]: info: sendMpdCommand status took 3 milliseconds May 14 19:01:00 volumio volumio[845]: info: ------------------------------ 2ms May 14 19:01:00 volumio volumio[845]: info: sendMpdCommand status took 2 milliseconds May 14 19:01:00 volumio volumio[845]: verbose: ControllerMpd::parseState May 14 19:01:00 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 19:01:00 volumio volumio[845]: verbose: ControllerMpd::parseState May 14 19:01:00 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 19:01:00 volumio volumio[845]: verbose: ControllerMpd::parseState May 14 19:01:00 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 19:01:00 volumio volumio[845]: info: sendMpdCommand playlistinfo took 2 milliseconds May 14 19:01:00 volumio volumio[845]: info: sendMpdCommand playlistinfo took 2 milliseconds May 14 19:01:00 volumio volumio[845]: info: sendMpdCommand playlistinfo took 2 milliseconds May 14 19:01:00 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 14 19:01:00 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 14 19:01:00 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 14 19:01:00 volumio volumio[845]: info: ControllerMpd::pushState May 14 19:01:00 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 14 19:01:00 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 14 19:01:00 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3923,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Rock Nonstop","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} May 14 19:01:00 volumio volumio[845]: verbose: CURRENT POSITION 0 May 14 19:01:00 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 14 19:01:00 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 14 19:01:00 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 14 19:01:00 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 14 19:01:00 volumio volumio[845]: info: CoreStateMachine::pushState May 14 19:01:00 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 19:01:00 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 14 19:01:00 volumio volumio[845]: info: CoreStateMachine::pushState May 14 19:01:00 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 14 19:01:00 volumio volumio[845]: info: ControllerMpd::pushState May 14 19:01:00 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 14 19:01:00 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 14 19:01:00 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3923,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Rock Nonstop","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} May 14 19:01:00 volumio volumio[845]: verbose: CURRENT POSITION 0 May 14 19:01:00 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 14 19:01:00 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 14 19:01:00 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 14 19:01:00 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 14 19:01:00 volumio volumio[845]: info: CoreStateMachine::pushState May 14 19:01:00 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 14 19:01:00 volumio volumio[845]: info: CoreStateMachine::pushState May 14 19:01:00 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 14 19:01:00 volumio volumio[845]: info: ControllerMpd::pushState May 14 19:01:00 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 14 19:01:00 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 14 19:01:00 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3923,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Rock Nonstop","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} May 14 19:01:00 volumio volumio[845]: verbose: CURRENT POSITION 0 May 14 19:01:00 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 14 19:01:00 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 14 19:01:00 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 14 19:01:00 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 14 19:01:00 volumio volumio[845]: info: CoreStateMachine::pushState May 14 19:01:00 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 14 19:01:00 volumio volumio[845]: info: CoreStateMachine::pushState May 14 19:01:00 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 14 19:01:00 volumio volumio[845]: info: ------------------------------ 109ms May 14 19:01:00 volumio volumio[845]: info: ------------------------------ 103ms May 14 19:01:00 volumio volumio[845]: info: ------------------------------ 102ms May 14 19:01:00 volumio volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 May 14 19:01:00 volumio volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 May 14 19:01:00 volumio volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 May 14 19:01:00 volumio volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 May 14 19:01:00 volumio volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 May 14 19:01:00 volumio volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 May 14 19:01:02 volumio volumio[845]: info: Getting Spotify volume May 14 19:01:02 volumio volumio[845]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 May 14 19:01:02 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 14 19:01:02 volumio volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 May 14 19:01:09 volumio ntpd[658]: Soliciting pool server 193.182.111.141 May 14 19:01:11 volumio ntpd[658]: Soliciting pool server 193.182.111.14 May 14 19:01:12 volumio ntpd[658]: Soliciting pool server 211.68.71.26 May 14 19:01:18 volumio ntpd[658]: Soliciting pool server 116.203.151.74 May 14 19:01:19 volumio ntpd[658]: Soliciting pool server 193.182.111.142 May 14 19:01:21 volumio ntpd[658]: Soliciting pool server 2a03:8600::ee May 14 19:01:27 volumio go-librespot[2336]: time="2025-05-14T19:01:27+08:00" 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\": dial tcp 35.186.224.24:443: i/o timeout" May 14 19:01:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 14 19:01:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 14 19:01:27 volumio volumio[845]: (node:845) UnhandledPromiseRejectionWarning: Error: socket hang up May 14 19:01:27 volumio volumio[845]: at connResetException (internal/errors.js:607:14) May 14 19:01:27 volumio volumio[845]: at Socket.socketOnEnd (_http_client.js:493:23) May 14 19:01:27 volumio volumio[845]: at Socket.emit (events.js:327:22) May 14 19:01:27 volumio volumio[845]: at endReadableNT (internal/streams/readable.js:1327:12) May 14 19:01:27 volumio volumio[845]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 14 19:01:27 volumio volumio[845]: (node:845) 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: 12) May 14 19:01:27 volumio volumio[845]: info: Connection to go-librespot Websocket closed May 14 19:01:30 volumio volumio[845]: info: Initializing connection to go-librespot Websocket May 14 19:01:30 volumio volumio[845]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 14 19:01:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 14 19:01:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. May 14 19:01:30 volumio systemd[1]: Stopped go-librespot Daemon. May 14 19:01:30 volumio systemd[1]: Started go-librespot Daemon. May 14 19:01:30 volumio go-librespot[2433]: Librespot-go daemon starting... May 14 19:01:30 volumio go-librespot[2433]: time="2025-05-14T19:01:30+08:00" level=info msg="generated new device id: 37715c89cb5a1779143b32e30509d2e6d6313062" May 14 19:01:30 volumio go-librespot[2433]: time="2025-05-14T19:01:30+08:00" level=debug msg="stored credentials not found" May 14 19:01:33 volumio volumio[845]: info: Initializing connection to go-librespot Websocket May 14 19:01:33 volumio go-librespot[2433]: time="2025-05-14T19:01:33+08:00" level=debug msg="new websocket client" May 14 19:01:33 volumio volumio[845]: info: Connection to go-librespot Websocket established May 14 19:01:36 volumio volumio[845]: info: Getting Spotify volume May 14 19:01:36 volumio volumio[845]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 May 14 19:01:36 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 14 19:01:36 volumio volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 May 14 19:02:00 volumio go-librespot[2433]: time="2025-05-14T19:02:00+08:00" 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\": dial tcp 35.186.224.24:443: i/o timeout" May 14 19:02:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 14 19:02:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 14 19:02:00 volumio volumio[845]: (node:845) UnhandledPromiseRejectionWarning: Error: socket hang up May 14 19:02:00 volumio volumio[845]: at connResetException (internal/errors.js:607:14) May 14 19:02:00 volumio volumio[845]: at Socket.socketOnEnd (_http_client.js:493:23) May 14 19:02:00 volumio volumio[845]: at Socket.emit (events.js:327:22) May 14 19:02:00 volumio volumio[845]: at endReadableNT (internal/streams/readable.js:1327:12) May 14 19:02:00 volumio volumio[845]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 14 19:02:00 volumio volumio[845]: (node:845) 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: 13) May 14 19:02:00 volumio volumio[845]: info: Connection to go-librespot Websocket closed May 14 19:02:03 volumio volumio[845]: info: Initializing connection to go-librespot Websocket May 14 19:02:03 volumio volumio[845]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 14 19:02:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 14 19:02:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. May 14 19:02:03 volumio systemd[1]: Stopped go-librespot Daemon. May 14 19:02:03 volumio systemd[1]: Started go-librespot Daemon. May 14 19:02:03 volumio go-librespot[2551]: Librespot-go daemon starting... May 14 19:02:03 volumio go-librespot[2551]: time="2025-05-14T19:02:03+08:00" level=info msg="generated new device id: 1d5ca022aa294ade1ded324982f2489c668fc56c" May 14 19:02:03 volumio go-librespot[2551]: time="2025-05-14T19:02:03+08:00" level=debug msg="stored credentials not found" May 14 19:02:06 volumio volumio[845]: info: Initializing connection to go-librespot Websocket May 14 19:02:06 volumio go-librespot[2551]: time="2025-05-14T19:02:06+08:00" level=debug msg="new websocket client" May 14 19:02:06 volumio volumio[845]: info: Connection to go-librespot Websocket established May 14 19:02:09 volumio volumio[845]: info: Getting Spotify volume May 14 19:02:09 volumio volumio[845]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 May 14 19:02:09 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 14 19:02:09 volumio volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 May 14 19:02:31 volumio volumio[845]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: May 14 19:02:31 volumio volumio[845]: LoungeScreenDisconnected { May 14 19:02:31 volumio volumio[845]: AID: null, May 14 19:02:31 volumio volumio[845]: name: 'loungeScreenDisconnected', May 14 19:02:31 volumio volumio[845]: payload: {} May 14 19:02:31 volumio volumio[845]: } May 14 19:02:31 volumio volumio[845]: (IncompleteAPIDataError) Missing data required to construct query string from bind params May 14 19:02:31 volumio volumio[845]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } May 14 19:02:31 volumio volumio[845]: Stack trace: May 14 19:02:31 volumio volumio[845]: IncompleteAPIDataError: Missing data required to construct query string from bind params May 14 19:02:31 volumio volumio[845]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) May 14 19:02:31 volumio volumio[845]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) May 14 19:02:31 volumio volumio[845]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) May 14 19:02:31 volumio volumio[845]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) May 14 19:02:31 volumio volumio[845]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) May 14 19:02:31 volumio volumio[845]: at runMicrotasks () May 14 19:02:31 volumio volumio[845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 14 19:02:31 volumio volumio[845]: (node:845) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined May 14 19:02:31 volumio volumio[845]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) May 14 19:02:31 volumio volumio[845]: at runMicrotasks () May 14 19:02:31 volumio volumio[845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 14 19:02:31 volumio volumio[845]: (node:845) 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: 15) May 14 19:02:31 volumio volumio[845]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: May 14 19:02:31 volumio volumio[845]: LoungeScreenDisconnected { May 14 19:02:31 volumio volumio[845]: AID: null, May 14 19:02:31 volumio volumio[845]: name: 'loungeScreenDisconnected', May 14 19:02:31 volumio volumio[845]: payload: {} May 14 19:02:31 volumio volumio[845]: } May 14 19:02:31 volumio volumio[845]: (IncompleteAPIDataError) Missing data required to construct query string from bind params May 14 19:02:31 volumio volumio[845]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } May 14 19:02:31 volumio volumio[845]: Stack trace: May 14 19:02:31 volumio volumio[845]: IncompleteAPIDataError: Missing data required to construct query string from bind params May 14 19:02:31 volumio volumio[845]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) May 14 19:02:31 volumio volumio[845]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) May 14 19:02:31 volumio volumio[845]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) May 14 19:02:31 volumio volumio[845]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) May 14 19:02:31 volumio volumio[845]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) May 14 19:02:31 volumio volumio[845]: at runMicrotasks () May 14 19:02:31 volumio volumio[845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 14 19:02:31 volumio volumio[845]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 May 14 19:02:31 volumio volumio[845]: (node:845) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined May 14 19:02:31 volumio volumio[845]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) May 14 19:02:31 volumio volumio[845]: at runMicrotasks () May 14 19:02:31 volumio volumio[845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 14 19:02:31 volumio volumio[845]: (node:845) 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: 17) May 14 19:02:31 volumio volumio[845]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: May 14 19:02:31 volumio volumio[845]: LoungeScreenDisconnected { May 14 19:02:31 volumio volumio[845]: AID: null, May 14 19:02:31 volumio volumio[845]: name: 'loungeScreenDisconnected', May 14 19:02:31 volumio volumio[845]: payload: {} May 14 19:02:31 volumio volumio[845]: } May 14 19:02:31 volumio volumio[845]: (IncompleteAPIDataError) Missing data required to construct query string from bind params May 14 19:02:31 volumio volumio[845]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } May 14 19:02:31 volumio volumio[845]: Stack trace: May 14 19:02:31 volumio volumio[845]: IncompleteAPIDataError: Missing data required to construct query string from bind params May 14 19:02:31 volumio volumio[845]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) May 14 19:02:31 volumio volumio[845]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) May 14 19:02:31 volumio volumio[845]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) May 14 19:02:31 volumio volumio[845]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) May 14 19:02:31 volumio volumio[845]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) May 14 19:02:31 volumio volumio[845]: at runMicrotasks () May 14 19:02:31 volumio volumio[845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 14 19:02:31 volumio volumio[845]: (node:845) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined May 14 19:02:31 volumio volumio[845]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) May 14 19:02:31 volumio volumio[845]: at runMicrotasks () May 14 19:02:31 volumio volumio[845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 14 19:02:31 volumio volumio[845]: (node:845) 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: 19) May 14 19:02:31 volumio volumio[845]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: May 14 19:02:31 volumio volumio[845]: LoungeScreenDisconnected { May 14 19:02:31 volumio volumio[845]: AID: null, May 14 19:02:31 volumio volumio[845]: name: 'loungeScreenDisconnected', May 14 19:02:31 volumio volumio[845]: payload: {} May 14 19:02:31 volumio volumio[845]: } May 14 19:02:31 volumio volumio[845]: (IncompleteAPIDataError) Missing data required to construct query string from bind params May 14 19:02:31 volumio volumio[845]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } May 14 19:02:31 volumio volumio[845]: Stack trace: May 14 19:02:31 volumio volumio[845]: IncompleteAPIDataError: Missing data required to construct query string from bind params May 14 19:02:31 volumio volumio[845]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) May 14 19:02:31 volumio volumio[845]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) May 14 19:02:31 volumio volumio[845]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) May 14 19:02:31 volumio volumio[845]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) May 14 19:02:31 volumio volumio[845]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) May 14 19:02:31 volumio volumio[845]: at runMicrotasks () May 14 19:02:31 volumio volumio[845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 14 19:02:31 volumio volumio[845]: (node:845) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined May 14 19:02:31 volumio volumio[845]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) May 14 19:02:31 volumio volumio[845]: at runMicrotasks () May 14 19:02:31 volumio volumio[845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 14 19:02:31 volumio volumio[845]: (node:845) 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: 21) May 14 19:02:33 volumio go-librespot[2551]: time="2025-05-14T19:02:33+08:00" 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\": dial tcp 35.186.224.24:443: i/o timeout" May 14 19:02:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 14 19:02:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 14 19:02:33 volumio volumio[845]: (node:845) UnhandledPromiseRejectionWarning: Error: socket hang up May 14 19:02:33 volumio volumio[845]: at connResetException (internal/errors.js:607:14) May 14 19:02:33 volumio volumio[845]: at Socket.socketOnEnd (_http_client.js:493:23) May 14 19:02:33 volumio volumio[845]: at Socket.emit (events.js:327:22) May 14 19:02:33 volumio volumio[845]: at endReadableNT (internal/streams/readable.js:1327:12) May 14 19:02:33 volumio volumio[845]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 14 19:02:33 volumio volumio[845]: (node:845) 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: 22) May 14 19:02:33 volumio volumio[845]: info: Connection to go-librespot Websocket closed May 14 19:02:36 volumio volumio[845]: info: Initializing connection to go-librespot Websocket May 14 19:02:36 volumio volumio[845]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 14 19:02:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 14 19:02:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. May 14 19:02:36 volumio systemd[1]: Stopped go-librespot Daemon. May 14 19:02:36 volumio systemd[1]: Started go-librespot Daemon. May 14 19:02:36 volumio go-librespot[2628]: Librespot-go daemon starting... May 14 19:02:36 volumio go-librespot[2628]: time="2025-05-14T19:02:36+08:00" level=info msg="generated new device id: 04df3a86ccc3fcc55ad9aa2f5c216c919f78cec7" May 14 19:02:36 volumio go-librespot[2628]: time="2025-05-14T19:02:36+08:00" level=debug msg="stored credentials not found" May 14 19:02:39 volumio volumio[845]: info: Initializing connection to go-librespot Websocket May 14 19:02:39 volumio go-librespot[2628]: time="2025-05-14T19:02:39+08:00" level=debug msg="new websocket client" May 14 19:02:39 volumio volumio[845]: info: Connection to go-librespot Websocket established May 14 19:02:42 volumio volumio[845]: info: Getting Spotify volume May 14 19:02:42 volumio volumio[845]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 May 14 19:02:42 volumio volumio[845]: info: CoreCommandRouter::volumioGetState May 14 19:02:42 volumio volumio[845]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 May 14 19:02:51 volumio volumio[845]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 19:02:51 volumio volumio[845]: Error: connect ETIMEDOUT 108.160.165.189:80 May 14 19:02:51 volumio volumio[845]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 14 19:02:51 volumio volumio[845]: errno: -110, May 14 19:02:51 volumio volumio[845]: code: 'ETIMEDOUT', May 14 19:02:51 volumio volumio[845]: syscall: 'connect', May 14 19:02:51 volumio volumio[845]: address: '108.160.165.189', May 14 19:02:51 volumio volumio[845]: port: 80 May 14 19:02:51 volumio volumio[845]: } May 14 19:02:51 volumio volumio[845]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 19:02:52 volumio sudo[2719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-14 19:01 May 14 19:02:52 volumio sudo[2719]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"