Dec 21 20:16:02 salon volumio[1092]: info: CoreCommandRouter::volumioGetState Dec 21 20:16:02 salon volumio[1092]: info: CorePlayQueue::getTrack 0 Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=debug msg="handling transfer player command from 82fa07726317b48281d1b5c8b1e8c0253435218d" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=debug msg="resolved context of track" uri="spotify:album:1o31VAqq2nAW0uGAGLs5YD" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=trace msg="fetched new page 0 with 41 items (list: 41)" uri="spotify:album:1o31VAqq2nAW0uGAGLs5YD" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=debug msg="loading track (paused: true, position: 9890ms)" uri="spotify:track:4y8W3knTk7cuIn0LGWHMhr" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=trace msg="emitting websocket event: will_play" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=debug msg="selected format OGG_VORBIS_320 (6eaedf81a332819fec759acd8283a94ef6bf9cd8)" uri="spotify:track:4y8W3knTk7cuIn0LGWHMhr" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=debug msg="requested aes key for file 6eaedf81a332819fec759acd8283a94ef6bf9cd8, gid: 4y8W3knTk7cuIn0LGWHMhr" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 314" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2012" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:4y8W3knTk7cuIn0LGWHMhr" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=debug msg="fetched first chunk of 14, total size is 6818176 bytes" uri="spotify:track:4y8W3knTk7cuIn0LGWHMhr" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=trace msg="seek to 9890ms (diff: 137ms, samples: 436149, bytes: 329596)" uri="spotify:track:4y8W3knTk7cuIn0LGWHMhr" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=debug msg="created new output device" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=info msg="loaded track \"Look Down - Live\" (paused: true, position: 9890ms, duration: 184400ms, prefetched: false)" uri="spotify:track:4y8W3knTk7cuIn0LGWHMhr" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=trace msg="emitting websocket event: metadata" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=trace msg="emitting websocket event: active" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=debug msg="sending successful reply for dealer request" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 21 20:16:03 salon go-librespot[1551]: time="2025-12-21T20:16:03+01:00" level=trace msg="emitting websocket event: paused" Dec 21 20:16:04 salon go-librespot[1551]: time="2025-12-21T20:16:04+01:00" level=debug msg="fetched chunk 1/13, size: 524288" uri="spotify:track:4y8W3knTk7cuIn0LGWHMhr" Dec 21 20:16:04 salon go-librespot[1551]: time="2025-12-21T20:16:04+01:00" level=debug msg="fetched chunk 2/13, size: 524288" uri="spotify:track:4y8W3knTk7cuIn0LGWHMhr" Dec 21 20:16:04 salon go-librespot[1551]: time="2025-12-21T20:16:04+01:00" level=debug msg="fetched chunk 3/13, size: 524288" uri="spotify:track:4y8W3knTk7cuIn0LGWHMhr" Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 21 20:16:04 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 21 20:16:04 salon volumio[1092]: info: Adding plugin bluetooth to MyMusic Plugins Dec 21 20:16:04 salon volumio[1092]: info: Adding plugin multiroom to MyMusic Plugins Dec 21 20:16:04 salon volumio[1092]: info: Adding plugin metavolumio to MyMusic Plugins Dec 21 20:16:04 salon volumio[1092]: info: Adding plugin cd_controller to MyMusic Plugins Dec 21 20:16:05 salon volumio[1092]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 21 20:16:05 salon volumio[1092]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 21 20:16:05 salon volumio[1092]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 21 20:16:05 salon volumio[1092]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 21 20:16:05 salon go-librespot[1551]: time="2025-12-21T20:16:05+01:00" level=trace msg="sent dealer ping" Dec 21 20:16:05 salon go-librespot[1551]: time="2025-12-21T20:16:05+01:00" level=trace msg="received dealer pong" Dec 21 20:16:05 salon go-librespot[1551]: time="2025-12-21T20:16:05+01:00" level=debug msg="handling resume player command from 82fa07726317b48281d1b5c8b1e8c0253435218d" Dec 21 20:16:05 salon go-librespot[1551]: time="2025-12-21T20:16:05+01:00" level=trace msg="seek to 9890ms (diff: 137ms, samples: 436149, bytes: 329596)" uri="spotify:track:4y8W3knTk7cuIn0LGWHMhr" Dec 21 20:16:05 salon go-librespot[1551]: time="2025-12-21T20:16:05+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Dec 21 20:16:05 salon go-librespot[1551]: time="2025-12-21T20:16:05+01:00" level=debug msg="resume track at 9753ms" Dec 21 20:16:05 salon go-librespot[1551]: time="2025-12-21T20:16:05+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 21 20:16:05 salon go-librespot[1551]: time="2025-12-21T20:16:05+01:00" level=trace msg="scheduling prefetch in 144s" Dec 21 20:16:05 salon go-librespot[1551]: time="2025-12-21T20:16:05+01:00" level=debug msg="sending successful reply for dealer request" Dec 21 20:16:05 salon go-librespot[1551]: time="2025-12-21T20:16:05+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 21 20:16:05 salon go-librespot[1551]: time="2025-12-21T20:16:05+01:00" level=trace msg="emitting websocket event: playing" Dec 21 20:16:08 salon go-librespot[1551]: time="2025-12-21T20:16:08+01:00" level=debug msg="handling seek_to player command from 82fa07726317b48281d1b5c8b1e8c0253435218d" Dec 21 20:16:08 salon go-librespot[1551]: time="2025-12-21T20:16:08+01:00" level=debug msg="seek track to 0ms" Dec 21 20:16:08 salon go-librespot[1551]: time="2025-12-21T20:16:08+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:4y8W3knTk7cuIn0LGWHMhr" Dec 21 20:16:08 salon go-librespot[1551]: time="2025-12-21T20:16:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 21 20:16:08 salon go-librespot[1551]: time="2025-12-21T20:16:08+01:00" level=trace msg="scheduling prefetch in 154s" Dec 21 20:16:08 salon go-librespot[1551]: time="2025-12-21T20:16:08+01:00" level=trace msg="emitting websocket event: seek" Dec 21 20:16:08 salon go-librespot[1551]: time="2025-12-21T20:16:08+01:00" level=debug msg="sending successful reply for dealer request" Dec 21 20:16:21 salon volumio[1092]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 21 20:16:21 salon volumio[1092]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 21 20:16:21 salon volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 20:16:21 salon volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 20:16:21 salon volumio[1092]: info: Starting MyVolumio Remote Streaming Endpoints Dec 21 20:16:21 salon volumio[1092]: info: MyVolumio login type: Token Dec 21 20:16:22 salon volumio[1092]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 21 20:16:22 salon volumio[1092]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 21 20:16:23 salon go-librespot[1551]: time="2025-12-21T20:16:23+01:00" level=debug msg="fetched chunk 4/13, size: 524288" uri="spotify:track:4y8W3knTk7cuIn0LGWHMhr" Dec 21 20:16:34 salon volumio[1092]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 21 20:16:34 salon volumio[1092]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 21 20:16:34 salon volumio[1092]: info: Streaming services startup Dec 21 20:16:34 salon volumio[1092]: info: Starting Streaming Daemon Dec 21 20:16:35 salon go-librespot[1551]: time="2025-12-21T20:16:35+01:00" level=trace msg="sent dealer ping" Dec 21 20:16:35 salon go-librespot[1551]: time="2025-12-21T20:16:35+01:00" level=trace msg="received dealer pong" Dec 21 20:16:35 salon volumio[1092]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 21 20:16:35 salon sudo[1758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 21 20:16:35 salon sudo[1758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 20:16:35 salon sudo[1758]: pam_unix(sudo:session): session closed for user root Dec 21 20:16:37 salon ntpd[971]: CLOCK: time stepped by 1.102815 Dec 21 20:16:37 salon ntpd[971]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 21 20:16:38 salon volumio[1092]: info: Adding plugin bluetooth to MyMusic Plugins Dec 21 20:16:38 salon volumio[1092]: info: Adding plugin multiroom to MyMusic Plugins Dec 21 20:16:38 salon volumio[1092]: info: Adding plugin metavolumio to MyMusic Plugins Dec 21 20:16:38 salon volumio[1092]: info: Adding plugin cd_controller to MyMusic Plugins Dec 21 20:16:38 salon volumio[1092]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 21 20:16:38 salon volumio[1092]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 21 20:16:38 salon volumio[1092]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 21 20:16:38 salon volumio[1092]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 21 20:16:38 salon volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 20:16:38 salon volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 20:16:38 salon volumio[1092]: info: Starting MyVolumio Remote Streaming Endpoints Dec 21 20:16:38 salon volumio[1092]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Dec 21 20:16:38 salon volumio[1092]: LoungeScreenDisconnected { Dec 21 20:16:38 salon volumio[1092]: AID: null, Dec 21 20:16:38 salon volumio[1092]: name: 'loungeScreenDisconnected', Dec 21 20:16:38 salon volumio[1092]: payload: {} Dec 21 20:16:38 salon volumio[1092]: } Dec 21 20:16:38 salon volumio[1092]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 21 20:16:38 salon volumio[1092]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 21 20:16:38 salon volumio[1092]: Stack trace: Dec 21 20:16:38 salon volumio[1092]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 21 20:16:38 salon volumio[1092]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 21 20:16:38 salon volumio[1092]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:497:103) Dec 21 20:16:38 salon volumio[1092]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:558:71) Dec 21 20:16:38 salon volumio[1092]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:52:32) Dec 21 20:16:38 salon volumio[1092]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:23) Dec 21 20:16:38 salon volumio[1092]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:345:67 Dec 21 20:16:38 salon volumio[1092]: at new Promise () Dec 21 20:16:38 salon volumio[1092]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:344:16) Dec 21 20:16:38 salon volumio[1092]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:241:28) Dec 21 20:16:38 salon volumio[1092]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:209:26) Dec 21 20:16:38 salon volumio[1092]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 21 20:16:38 salon volumio[1092]: at async Promise.all (index 1) Dec 21 20:16:38 salon volumio[1092]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:13) Dec 21 20:16:38 salon volumio[1092]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:93:13) Dec 21 20:16:38 salon volumio[1092]: error: [yt-cast-receiver] Failed to start receiver: Dec 21 20:16:38 salon volumio[1092]: (AppError) Failed to start YouTubeApp Dec 21 20:16:38 salon volumio[1092]: --->(SessionError) (YouTube Music) Failed to establish session Dec 21 20:16:38 salon volumio[1092]: ------>(ConnectionError) (YouTube Music) Connection error in getting lounge token Dec 21 20:16:38 salon volumio[1092]: Error info: { Dec 21 20:16:38 salon volumio[1092]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Dec 21 20:16:38 salon volumio[1092]: } Dec 21 20:16:38 salon volumio[1092]: --------->(TypeError) fetch failed Dec 21 20:16:38 salon volumio[1092]: Stack trace: Dec 21 20:16:38 salon volumio[1092]: AppError: Failed to start YouTubeApp Dec 21 20:16:38 salon volumio[1092]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:131:19) Dec 21 20:16:38 salon volumio[1092]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 21 20:16:38 salon volumio[1092]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:93:13) Dec 21 20:16:38 salon volumio[1092]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Dec 21 20:16:38 salon volumio[1092]: LoungeScreenDisconnected { Dec 21 20:16:38 salon volumio[1092]: AID: null, Dec 21 20:16:38 salon volumio[1092]: name: 'loungeScreenDisconnected', Dec 21 20:16:38 salon volumio[1092]: payload: {} Dec 21 20:16:38 salon volumio[1092]: } Dec 21 20:16:38 salon volumio[1092]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 21 20:16:38 salon volumio[1092]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 21 20:16:38 salon volumio[1092]: Stack trace: Dec 21 20:16:38 salon volumio[1092]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 21 20:16:38 salon volumio[1092]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 21 20:16:38 salon volumio[1092]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:497:103) Dec 21 20:16:38 salon volumio[1092]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:558:71) Dec 21 20:16:38 salon volumio[1092]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:52:32) Dec 21 20:16:38 salon volumio[1092]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:23) Dec 21 20:16:38 salon volumio[1092]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:345:67 Dec 21 20:16:38 salon volumio[1092]: at new Promise () Dec 21 20:16:38 salon volumio[1092]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:344:16) Dec 21 20:16:38 salon volumio[1092]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:241:28) Dec 21 20:16:38 salon volumio[1092]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:123:39 Dec 21 20:16:38 salon volumio[1092]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:128:19 Dec 21 20:16:38 salon volumio[1092]: at Array.forEach () Dec 21 20:16:38 salon volumio[1092]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:24) Dec 21 20:16:38 salon volumio[1092]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 21 20:16:38 salon volumio[1092]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:93:13) Dec 21 20:16:38 salon volumio[1092]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Dec 21 20:16:38 salon volumio[1092]: LoungeScreenDisconnected { Dec 21 20:16:38 salon volumio[1092]: AID: null, Dec 21 20:16:38 salon volumio[1092]: name: 'loungeScreenDisconnected', Dec 21 20:16:38 salon volumio[1092]: payload: {} Dec 21 20:16:38 salon volumio[1092]: } Dec 21 20:16:38 salon volumio[1092]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 21 20:16:38 salon volumio[1092]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 21 20:16:38 salon volumio[1092]: Stack trace: Dec 21 20:16:38 salon volumio[1092]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 21 20:16:38 salon volumio[1092]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 21 20:16:38 salon volumio[1092]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:497:103) Dec 21 20:16:38 salon volumio[1092]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:558:71) Dec 21 20:16:38 salon volumio[1092]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:52:32) Dec 21 20:16:38 salon volumio[1092]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:242:71) Dec 21 20:16:38 salon volumio[1092]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 21 20:16:38 salon volumio[1092]: error: [ytcr] Failed to start plugin: Dec 21 20:16:38 salon volumio[1092]: (AppError) Failed to start YouTubeApp Dec 21 20:16:38 salon volumio[1092]: --->(SessionError) (YouTube Music) Failed to establish session Dec 21 20:16:38 salon volumio[1092]: ------>(ConnectionError) (YouTube Music) Connection error in getting lounge token Dec 21 20:16:38 salon volumio[1092]: Error info: { Dec 21 20:16:38 salon volumio[1092]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Dec 21 20:16:38 salon volumio[1092]: } Dec 21 20:16:38 salon volumio[1092]: --------->(TypeError) fetch failed Dec 21 20:16:38 salon volumio[1092]: Stack trace: Dec 21 20:16:38 salon volumio[1092]: AppError: Failed to start YouTubeApp Dec 21 20:16:38 salon volumio[1092]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:131:19) Dec 21 20:16:38 salon volumio[1092]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 21 20:16:38 salon volumio[1092]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:93:13) Dec 21 20:16:38 salon volumio[1092]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:album:1o31VAqq2nAW0uGAGLs5YD","uri":"spotify:track:4y8W3knTk7cuIn0LGWHMhr","play_origin":"album"}} Dec 21 20:16:38 salon volumio[1092]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 21 20:16:38 salon volumio[1092]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 21 20:16:38 salon volumio[1092]: info: camilladsp stopping service pid 1481... Dec 21 20:16:39 salon go-librespot[1551]: time="2025-12-21T20:16:39+01:00" level=debug msg="fetched chunk 5/13, size: 524288" uri="spotify:track:4y8W3knTk7cuIn0LGWHMhr" Dec 21 20:16:39 salon volumio[1092]: grep: /proc/1481/cmdline: binary file matches Dec 21 20:16:39 salon volumio[1092]: info: camilladsp service terminated, instance 1 Dec 21 20:16:39 salon volumio[1092]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 21 20:16:39 salon volumio[1092]: info: camilladsp service started and running in background, instance 1 Dec 21 20:16:39 salon volumio[1092]: error: Cannot start Volumio Streaming Daemon Dec 21 20:16:39 salon volumio[1092]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 21 20:16:39 salon volumio[1092]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 21 20:16:39 salon volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 21 20:16:44 salon volumio[1092]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 20:16:44 salon volumio[1092]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 20:16:44 salon volumio[1092]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 21 20:16:44 salon volumio[1092]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 21 20:16:44 salon volumio[1092]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 21 20:16:44 salon volumio[1092]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 21 20:16:44 salon volumio[1092]: info: CoreCommandRouter::volumioGetBrowseSources Dec 21 20:16:44 salon volumio[1092]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 20:16:44 salon volumio[1092]: SPOTIFY: User informations: {"country":"FR","display_name":"Julia Bardinon","email":"jbardinon@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/juliabardinon"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/juliabardinon","id":"juliabardinon","images":[{"height":300,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=10203679643210287&height=300&width=300&ext=1768912315&hash=AT_ILN4vcb2xcRtokaHwKi1s","width":300},{"height":64,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=10203679643210287&height=50&width=50&ext=1768912315&hash=AT91wzLJDyMi2GplkmMazfqn","width":64}],"product":"premium","type":"user","uri":"spotify:user:juliabardinon"} Dec 21 20:16:44 salon volumio[1092]: info: Spotify Successfully logged in Dec 21 20:16:45 salon volumio[1092]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 20:16:45 salon volumio[1092]: info: [1766344605008] CoreMusicLibrary::Adding element Spotify Dec 21 20:16:45 salon volumio[1092]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 20:16:45 salon volumio[1092]: Cannot find translation for source Podcast Dec 21 20:16:45 salon volumio[1092]: Cannot find translation for source LastFM Dec 21 20:16:45 salon volumio[1092]: Cannot find translation for source Spotify Dec 21 20:16:47 salon volumio[1092]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Dec 21 20:16:50 salon volumio[1092]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 21 20:16:50 salon volumio[1092]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Dec 21 20:16:51 salon volumio[1092]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4y8W3knTk7cuIn0LGWHMhr","name":"Look Down - Live","artist_names":["Adam Searles","Michael Ball","Michael Maguire","Les Misérables - 10th Anniversary Cast","The \"Les Misérables\" 10th Anniversary Choir"],"album_name":"Les Misérables (10th Anniversary Concert Live at Royal Albert Hall)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02f1a287ffaa4daa187a7dd732","position":9890,"duration":184400,"release_date":"year:1996 month:1 day:1","track_number":15,"disc_number":1}} Dec 21 20:16:51 salon volumio[1092]: SPOTIFY: received: {"type":"active","data":null} Dec 21 20:16:51 salon volumio[1092]: info: Aligning Spotify Volume to Volumio Volume Dec 21 20:16:51 salon volumio[1092]: info: CoreCommandRouter::volumioGetState Dec 21 20:16:51 salon volumio[1092]: info: CorePlayQueue::getTrack 0 Dec 21 20:16:51 salon volumio[1092]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:album:1o31VAqq2nAW0uGAGLs5YD","uri":"spotify:track:4y8W3knTk7cuIn0LGWHMhr","play_origin":"album"}} Dec 21 20:16:51 salon volumio[1092]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 20:16:51 salon volumio[1092]: TypeError: Cannot read properties of undefined (reading 'service') Dec 21 20:16:51 salon volumio[1092]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Dec 21 20:16:51 salon volumio[1092]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:280:18) Dec 21 20:16:51 salon volumio[1092]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Dec 21 20:16:51 salon volumio[1092]: at WebSocket.emit (node:events:514:28) Dec 21 20:16:51 salon volumio[1092]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Dec 21 20:16:51 salon volumio[1092]: at Receiver.emit (node:events:514:28) Dec 21 20:16:51 salon volumio[1092]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Dec 21 20:16:51 salon volumio[1092]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Dec 21 20:16:51 salon volumio[1092]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Dec 21 20:16:51 salon volumio[1092]: at /data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:531:42 Dec 21 20:16:51 salon volumio[1092]: at /data/plugins/music_service/spop/node_modules/ws/lib/permessage-deflate.js:309:9 Dec 21 20:16:51 salon volumio[1092]: at /data/plugins/music_service/spop/node_modules/ws/lib/permessage-deflate.js:392:7 Dec 21 20:16:51 salon volumio[1092]: at afterWrite (node:internal/streams/writable:507:5) Dec 21 20:16:51 salon volumio[1092]: at onwrite (node:internal/streams/writable:487:7) Dec 21 20:16:51 salon volumio[1092]: at Zlib.cb (node:internal/streams/transform:190:7) Dec 21 20:16:51 salon volumio[1092]: at Zlib.processCallback (node:zlib:613:8) Dec 21 20:16:51 salon volumio[1092]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 20:16:55 salon go-librespot[1551]: time="2025-12-21T20:16:55+01:00" level=debug msg="fetched chunk 6/13, size: 524288" uri="spotify:track:4y8W3knTk7cuIn0LGWHMhr" Dec 21 20:17:06 salon sudo[1829]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-21 20:16' Dec 21 20:17:06 salon sudo[1829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 20:17:06 salon go-librespot[1551]: time="2025-12-21T20:17:06+01:00" level=trace msg="sent dealer ping" Dec 21 20:17:06 salon go-librespot[1551]: time="2025-12-21T20:17:06+01:00" level=trace msg="received dealer pong" PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"