Mar 04 18:06:01 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 04 18:06:03 volumio volumio[2118]: error: [squeezelite_mc] Failed to get players on server pCP (192.168.0.146): fetch failed TypeError: fetch failed Mar 04 18:06:03 volumio volumio[2118]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Mar 04 18:06:03 volumio volumio[2118]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 04 18:06:03 volumio volumio[2118]: at async sendRpcRequest (/data/plugins/music_service/squeezelite_mc/dist/lib/RPC.js:23:26) Mar 04 18:06:03 volumio volumio[2118]: at async PlayerFinder._PlayerFinder_getPlayersOnServer (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerFinder.js:73:30) Mar 04 18:06:03 volumio volumio[2118]: at async /data/plugins/music_service/squeezelite_mc/dist/lib/PlayerFinder.js:116:29 Mar 04 18:06:03 volumio volumio[2118]: error: [squeezelite_mc] An error occurred while processing discovered server: fetch failed TypeError: fetch failed Mar 04 18:06:03 volumio volumio[2118]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Mar 04 18:06:03 volumio volumio[2118]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 04 18:06:03 volumio volumio[2118]: at async sendRpcRequest (/data/plugins/music_service/squeezelite_mc/dist/lib/RPC.js:23:26) Mar 04 18:06:03 volumio volumio[2118]: at async PlayerFinder._PlayerFinder_getPlayersOnServer (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerFinder.js:73:30) Mar 04 18:06:03 volumio volumio[2118]: at async /data/plugins/music_service/squeezelite_mc/dist/lib/PlayerFinder.js:116:29 Mar 04 18:06:04 volumio volumio[2118]: info: CoreCommandRouter::volumioGetState Mar 04 18:06:04 volumio volumio[2118]: info: CorePlayQueue::getTrack 0 Mar 04 18:06:04 volumio volumio[2118]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 18:06:04 volumio volumio[2118]: SPOTIFY: SPOTIFY VOLUME undefined Mar 04 18:06:04 volumio volumio[2118]: SPOTIFY: VOLUMIO VOLUME 100 Mar 04 18:06:04 volumio volumio[2118]: info: Aligning Spotify Volume to Volumio Volume Mar 04 18:06:04 volumio volumio[2118]: info: CoreCommandRouter::volumioGetState Mar 04 18:06:04 volumio volumio[2118]: info: CorePlayQueue::getTrack 0 Mar 04 18:06:04 volumio volumio[2118]: info: Setting Spotify Volume from Volumio: 100 Mar 04 18:06:05 volumio volumio[2118]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Mar 04 18:06:05 volumio volumio[2118]: info: Sending Spotify command with payload to local API: /player/volume Mar 04 18:06:08 volumio volumio[2118]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Mar 04 18:06:08 volumio volumio[2118]: LoungeScreenDisconnected { Mar 04 18:06:08 volumio volumio[2118]: AID: null, Mar 04 18:06:08 volumio volumio[2118]: name: 'loungeScreenDisconnected', Mar 04 18:06:08 volumio volumio[2118]: payload: {} Mar 04 18:06:08 volumio volumio[2118]: } Mar 04 18:06:08 volumio volumio[2118]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Mar 04 18:06:08 volumio volumio[2118]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Mar 04 18:06:08 volumio volumio[2118]: Stack trace: Mar 04 18:06:08 volumio volumio[2118]: IncompleteAPIDataError: Missing data required to construct query string from bind params Mar 04 18:06:08 volumio volumio[2118]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/BindParams.js:120:19) Mar 04 18:06:08 volumio volumio[2118]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:497:103) Mar 04 18:06:08 volumio volumio[2118]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:558:71) Mar 04 18:06:08 volumio volumio[2118]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:52:32) Mar 04 18:06:08 volumio volumio[2118]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:39:23) Mar 04 18:06:08 volumio volumio[2118]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:345:67 Mar 04 18:06:08 volumio volumio[2118]: at new Promise () Mar 04 18:06:08 volumio volumio[2118]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:344:16) Mar 04 18:06:08 volumio volumio[2118]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:241:28) Mar 04 18:06:08 volumio volumio[2118]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:209:26) Mar 04 18:06:08 volumio volumio[2118]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 04 18:06:08 volumio volumio[2118]: at async Promise.all (index 1) Mar 04 18:06:08 volumio volumio[2118]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:114:13) Mar 04 18:06:08 volumio volumio[2118]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/YouTubeCastReceiver.js:93:13) Mar 04 18:06:08 volumio volumio[2118]: error: [yt-cast-receiver] Failed to start receiver: Mar 04 18:06:08 volumio volumio[2118]: (AppError) Failed to start YouTubeApp Mar 04 18:06:08 volumio volumio[2118]: --->(SessionError) (YouTube Music) Failed to establish session Mar 04 18:06:08 volumio volumio[2118]: ------>(ConnectionError) (YouTube Music) Connection error in generating screen Id Mar 04 18:06:08 volumio volumio[2118]: Error info: { Mar 04 18:06:08 volumio volumio[2118]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Mar 04 18:06:08 volumio volumio[2118]: } Mar 04 18:06:08 volumio volumio[2118]: --------->(TypeError) fetch failed Mar 04 18:06:08 volumio volumio[2118]: Stack trace: Mar 04 18:06:08 volumio volumio[2118]: AppError: Failed to start YouTubeApp Mar 04 18:06:08 volumio volumio[2118]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:131:19) Mar 04 18:06:08 volumio volumio[2118]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 04 18:06:08 volumio volumio[2118]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/YouTubeCastReceiver.js:93:13) Mar 04 18:06:08 volumio volumio[2118]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Mar 04 18:06:08 volumio volumio[2118]: LoungeScreenDisconnected { Mar 04 18:06:08 volumio volumio[2118]: AID: null, Mar 04 18:06:08 volumio volumio[2118]: name: 'loungeScreenDisconnected', Mar 04 18:06:08 volumio volumio[2118]: payload: {} Mar 04 18:06:08 volumio volumio[2118]: } Mar 04 18:06:08 volumio volumio[2118]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Mar 04 18:06:08 volumio volumio[2118]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Mar 04 18:06:08 volumio volumio[2118]: Stack trace: Mar 04 18:06:08 volumio volumio[2118]: IncompleteAPIDataError: Missing data required to construct query string from bind params Mar 04 18:06:08 volumio volumio[2118]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/BindParams.js:120:19) Mar 04 18:06:08 volumio volumio[2118]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:497:103) Mar 04 18:06:08 volumio volumio[2118]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:558:71) Mar 04 18:06:08 volumio volumio[2118]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:52:32) Mar 04 18:06:08 volumio volumio[2118]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:242:71) Mar 04 18:06:08 volumio volumio[2118]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 04 18:06:08 volumio volumio[2118]: error: [ytcr] Failed to start plugin: Mar 04 18:06:08 volumio volumio[2118]: (AppError) Failed to start YouTubeApp Mar 04 18:06:08 volumio volumio[2118]: --->(SessionError) (YouTube Music) Failed to establish session Mar 04 18:06:08 volumio volumio[2118]: ------>(ConnectionError) (YouTube Music) Connection error in generating screen Id Mar 04 18:06:08 volumio volumio[2118]: Error info: { Mar 04 18:06:08 volumio volumio[2118]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Mar 04 18:06:08 volumio volumio[2118]: } Mar 04 18:06:08 volumio volumio[2118]: --------->(TypeError) fetch failed Mar 04 18:06:08 volumio volumio[2118]: Stack trace: Mar 04 18:06:08 volumio volumio[2118]: AppError: Failed to start YouTubeApp Mar 04 18:06:08 volumio volumio[2118]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:131:19) Mar 04 18:06:08 volumio volumio[2118]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 04 18:06:08 volumio volumio[2118]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/YouTubeCastReceiver.js:93:13) Mar 04 18:06:08 volumio volumio[2118]: info: Completed starting Core Plugins Mar 04 18:06:08 volumio volumio[2118]: info: ------------------------------------------- Mar 04 18:06:08 volumio volumio[2118]: info: ----- MyVolumio plugins startup ---- Mar 04 18:06:08 volumio volumio[2118]: info: ------------------------------------------- Mar 04 18:06:08 volumio volumio[2118]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 04 18:06:18 volumio volumio[2118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 18:06:18 volumio volumio[2118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 18:06:18 volumio volumio[2118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 04 18:06:19 volumio volumio[2118]: info: [squeezelite_mc] 'client' notification received from pCP (192.168.0.146); type is 'connect' Mar 04 18:06:19 volumio volumio[2118]: info: [squeezelite_mc] Getting players connected to pCP (192.168.0.146) Mar 04 18:06:19 volumio volumio[2118]: error: [yt-cast-receiver] Caught error refreshing lounge token: Mar 04 18:06:19 volumio volumio[2118]: (TypeError) Cannot read properties of undefined (reading 'removeAllListeners') Mar 04 18:06:19 volumio volumio[2118]: TypeError: Cannot read properties of undefined (reading 'removeAllListeners') Mar 04 18:06:19 volumio volumio[2118]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:377:16) Mar 04 18:06:19 volumio volumio[2118]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 04 18:06:21 volumio volumio[2118]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.0.146","name":"pCP","ver":"9.0.3","uuid":"9d7f1583-04e3-4f08-8ae2-5daed7767ea3","jsonPort":"9000","cliPort":"9090"} Mar 04 18:06:21 volumio volumio[2118]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.0.114","name":"mits","ver":"9.0.3","uuid":"8c807fff-4fa8-4664-abbd-95c40dd968be","jsonPort":"9000","cliPort":"9090"} Mar 04 18:06:21 volumio volumio[2118]: info: [squeezelite_mc] Server lost: {"ip":"192.168.0.114","name":"mits","ver":"9.0.3","uuid":"8c807fff-4fa8-4664-abbd-95c40dd968be","jsonPort":"9000","cliPort":"9090"} Mar 04 18:06:21 volumio volumio[2118]: info: [squeezelite_mc] Server lost: {"ip":"192.168.0.146","name":"pCP","ver":"9.0.3","uuid":"9d7f1583-04e3-4f08-8ae2-5daed7767ea3","jsonPort":"9000","cliPort":"9090"} Mar 04 18:06:22 volumio volumio[2118]: error: [squeezelite_mc] Failed to start notification listener on pCP (192.168.0.146): Cannot connect Error: Cannot connect Mar 04 18:06:22 volumio volumio[2118]: at Socket. (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:131:35) Mar 04 18:06:22 volumio volumio[2118]: at Object.onceWrapper (node:events:628:28) Mar 04 18:06:22 volumio volumio[2118]: at Socket.emit (node:events:514:28) Mar 04 18:06:22 volumio volumio[2118]: at Socket._onTimeout (node:net:589:8) Mar 04 18:06:22 volumio volumio[2118]: at listOnTimeout (node:internal/timers:573:17) Mar 04 18:06:22 volumio volumio[2118]: at process.processTimers (node:internal/timers:514:7) Mar 04 18:06:22 volumio volumio[2118]: error: [squeezelite_mc] An error occurred while processing discovered server: Cannot connect Error: Cannot connect Mar 04 18:06:22 volumio volumio[2118]: at Socket. (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:131:35) Mar 04 18:06:22 volumio volumio[2118]: at Object.onceWrapper (node:events:628:28) Mar 04 18:06:22 volumio volumio[2118]: at Socket.emit (node:events:514:28) Mar 04 18:06:22 volumio volumio[2118]: at Socket._onTimeout (node:net:589:8) Mar 04 18:06:22 volumio volumio[2118]: at listOnTimeout (node:internal/timers:573:17) Mar 04 18:06:22 volumio volumio[2118]: at process.processTimers (node:internal/timers:514:7) Mar 04 18:06:22 volumio volumio[2118]: info: [squeezelite_mc] Server lost: {"ip":"192.168.0.146","name":"pCP","ver":"9.0.3","uuid":"9d7f1583-04e3-4f08-8ae2-5daed7767ea3","jsonPort":"9000","cliPort":"9090"} Mar 04 18:06:22 volumio volumio[2118]: error: [squeezelite_mc] Failed to start notification listener on mits (192.168.0.114): Cannot connect Error: Cannot connect Mar 04 18:06:22 volumio volumio[2118]: at Socket. (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:131:35) Mar 04 18:06:22 volumio volumio[2118]: at Object.onceWrapper (node:events:628:28) Mar 04 18:06:22 volumio volumio[2118]: at Socket.emit (node:events:514:28) Mar 04 18:06:22 volumio volumio[2118]: at Socket._onTimeout (node:net:589:8) Mar 04 18:06:22 volumio volumio[2118]: at listOnTimeout (node:internal/timers:573:17) Mar 04 18:06:22 volumio volumio[2118]: at process.processTimers (node:internal/timers:514:7) Mar 04 18:06:22 volumio volumio[2118]: error: [squeezelite_mc] An error occurred while processing discovered server: Cannot connect Error: Cannot connect Mar 04 18:06:22 volumio volumio[2118]: at Socket. (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:131:35) Mar 04 18:06:22 volumio volumio[2118]: at Object.onceWrapper (node:events:628:28) Mar 04 18:06:22 volumio volumio[2118]: at Socket.emit (node:events:514:28) Mar 04 18:06:22 volumio volumio[2118]: at Socket._onTimeout (node:net:589:8) Mar 04 18:06:22 volumio volumio[2118]: at listOnTimeout (node:internal/timers:573:17) Mar 04 18:06:22 volumio volumio[2118]: at process.processTimers (node:internal/timers:514:7) Mar 04 18:06:22 volumio volumio[2118]: info: [squeezelite_mc] Server lost: {"ip":"192.168.0.114","name":"mits","ver":"9.0.3","uuid":"8c807fff-4fa8-4664-abbd-95c40dd968be","jsonPort":"9000","cliPort":"9090"} Mar 04 18:06:23 volumio volumio[2118]: error: [yt-cast-receiver] Caught error refreshing lounge token: Mar 04 18:06:23 volumio volumio[2118]: (TypeError) Cannot read properties of undefined (reading 'removeAllListeners') Mar 04 18:06:23 volumio volumio[2118]: TypeError: Cannot read properties of undefined (reading 'removeAllListeners') Mar 04 18:06:23 volumio volumio[2118]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:377:16) Mar 04 18:06:23 volumio volumio[2118]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 04 18:06:27 volumio volumio[2118]: info: [squeezelite_mc] Players connected to pCP (192.168.0.146): [{"id":"bb:bb:9e:4b:2a:04","uuid":null,"ip":"192.168.0.146","name":"Volumio-UPnP/AV","server":{"ip":"192.168.0.146","name":"pCP","ver":"9.0.3","uuid":"9d7f1583-04e3-4f08-8ae2-5daed7767ea3","jsonPort":"9000","cliPort":"9090"}},{"id":"88:a2:9e:4b:2a:04","uuid":null,"ip":"192.168.0.112","name":"volumio","server":{"ip":"192.168.0.146","name":"pCP","ver":"9.0.3","uuid":"9d7f1583-04e3-4f08-8ae2-5daed7767ea3","jsonPort":"9000","cliPort":"9090"}},{"id":"d8:3a:dd:e2:86:5d","uuid":null,"ip":"192.168.0.146","name":"piCorePlayer","server":{"ip":"192.168.0.146","name":"pCP","ver":"9.0.3","uuid":"9d7f1583-04e3-4f08-8ae2-5daed7767ea3","jsonPort":"9000","cliPort":"9090"}}] Mar 04 18:06:27 volumio volumio[2118]: info: [squeezelite_mc] Player found: {"id":"88:a2:9e:4b:2a:04","uuid":null,"ip":"192.168.0.112","name":"volumio","server":{"ip":"192.168.0.146","name":"pCP","ver":"9.0.3","uuid":"9d7f1583-04e3-4f08-8ae2-5daed7767ea3","jsonPort":"9000","cliPort":"9090"}} Mar 04 18:06:28 volumio volumio[2118]: error: MyVolumio Plugin failed to start in a timely fashion Mar 04 18:06:28 volumio volumio[2118]: [Metrics] CommandRouter: 47s 722.46ms Mar 04 18:06:28 volumio volumio[2118]: info: CoreCommandRouter::volumiosetStartupVolume Mar 04 18:06:28 volumio volumio[2118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 18:06:28 volumio volumio[2118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 18:06:28 volumio volumio[2118]: info: CoreCommandRouter::Close All Modals sent Mar 04 18:06:28 volumio volumio[2118]: info: CoreCommandRouter::Close All Modals sent Mar 04 18:06:28 volumio volumio[2118]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 04 18:06:28 volumio volumio[2118]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 04 18:06:28 volumio volumio[2118]: info: camilladsp stopping service pid 2466... Mar 04 18:06:29 volumio volumio[2118]: grep: /proc/2466/cmdline: binary file matches Mar 04 18:06:29 volumio volumio[2118]: grep: /proc/2466/cmdline: binary file matches Mar 04 18:06:29 volumio volumio[2118]: grep: /proc/2466/cmdline: binary file matches Mar 04 18:06:29 volumio volumio[2118]: grep: /proc/2466/cmdline: binary file matches Mar 04 18:06:29 volumio volumio[2118]: grep: /proc/2466/cmdline: binary file matches Mar 04 18:06:29 volumio volumio[2118]: info: camilladsp service terminated, instance 1 Mar 04 18:06:29 volumio volumio[2118]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 18:06:29 volumio volumio[2118]: info: camilladsp service started and running in background, instance 1 Mar 04 18:06:29 volumio volumio[2118]: info: camilladsp respawn in 100 ms (attempt 1/10) Mar 04 18:06:29 volumio volumio[2118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 04 18:06:29 volumio volumio[2118]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 18:06:29 volumio volumio[2118]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 04 18:06:29 volumio volumio[2118]: info: CoreCommandRouter::volumioGetState Mar 04 18:06:29 volumio volumio[2118]: info: CorePlayQueue::getTrack 0 Mar 04 18:06:33 volumio volumio[2118]: info: camilladsp respawn in 200 ms (attempt 2/10) Mar 04 18:06:35 volumio volumio[2118]: info: BOOT COMPLETED Mar 04 18:07:11 volumio bash[2590]: setdatetime-helper: all HTTPS Date fallbacks failed Mar 04 18:07:11 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 04 18:07:11 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 04 18:07:22 volumio volumio[2118]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.0.146","name":"pCP","ver":"9.0.3","uuid":"9d7f1583-04e3-4f08-8ae2-5daed7767ea3","jsonPort":"9000","cliPort":"9090"} Mar 04 18:07:22 volumio volumio[2118]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.0.114","name":"mits","ver":"9.0.3","uuid":"8c807fff-4fa8-4664-abbd-95c40dd968be","jsonPort":"9000","cliPort":"9090"} Mar 04 18:07:22 volumio volumio[2118]: info: [squeezelite_mc] Server lost: {"ip":"192.168.0.114","name":"mits","ver":"9.0.3","uuid":"8c807fff-4fa8-4664-abbd-95c40dd968be","jsonPort":"9000","cliPort":"9090"} Mar 04 18:07:22 volumio volumio[2118]: info: [squeezelite_mc] Server lost: {"ip":"192.168.0.146","name":"pCP","ver":"9.0.3","uuid":"9d7f1583-04e3-4f08-8ae2-5daed7767ea3","jsonPort":"9000","cliPort":"9090"} Mar 04 18:07:22 volumio volumio[2118]: info: CoreCommandRouter::volumioGetState Mar 04 18:07:22 volumio volumio[2118]: info: CorePlayQueue::getTrack 0 Mar 04 18:07:23 volumio volumio[2118]: error: [squeezelite_mc] Failed to start notification listener on pCP (192.168.0.146): Cannot connect Error: Cannot connect Mar 04 18:07:23 volumio volumio[2118]: at Socket. (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:131:35) Mar 04 18:07:23 volumio volumio[2118]: at Object.onceWrapper (node:events:628:28) Mar 04 18:07:23 volumio volumio[2118]: at Socket.emit (node:events:514:28) Mar 04 18:07:23 volumio volumio[2118]: at Socket._onTimeout (node:net:589:8) Mar 04 18:07:23 volumio volumio[2118]: at listOnTimeout (node:internal/timers:573:17) Mar 04 18:07:23 volumio volumio[2118]: at process.processTimers (node:internal/timers:514:7) Mar 04 18:07:23 volumio volumio[2118]: error: [squeezelite_mc] An error occurred while processing discovered server: Cannot connect Error: Cannot connect Mar 04 18:07:23 volumio volumio[2118]: at Socket. (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:131:35) Mar 04 18:07:23 volumio volumio[2118]: at Object.onceWrapper (node:events:628:28) Mar 04 18:07:23 volumio volumio[2118]: at Socket.emit (node:events:514:28) Mar 04 18:07:23 volumio volumio[2118]: at Socket._onTimeout (node:net:589:8) Mar 04 18:07:23 volumio volumio[2118]: at listOnTimeout (node:internal/timers:573:17) Mar 04 18:07:23 volumio volumio[2118]: at process.processTimers (node:internal/timers:514:7) Mar 04 18:07:23 volumio volumio[2118]: info: [squeezelite_mc] Server lost: {"ip":"192.168.0.146","name":"pCP","ver":"9.0.3","uuid":"9d7f1583-04e3-4f08-8ae2-5daed7767ea3","jsonPort":"9000","cliPort":"9090"} Mar 04 18:07:23 volumio volumio[2118]: error: [squeezelite_mc] Failed to start notification listener on mits (192.168.0.114): Cannot connect Error: Cannot connect Mar 04 18:07:23 volumio volumio[2118]: at Socket. (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:131:35) Mar 04 18:07:23 volumio volumio[2118]: at Object.onceWrapper (node:events:628:28) Mar 04 18:07:23 volumio volumio[2118]: at Socket.emit (node:events:514:28) Mar 04 18:07:23 volumio volumio[2118]: at Socket._onTimeout (node:net:589:8) Mar 04 18:07:23 volumio volumio[2118]: at listOnTimeout (node:internal/timers:573:17) Mar 04 18:07:23 volumio volumio[2118]: at process.processTimers (node:internal/timers:514:7) Mar 04 18:07:23 volumio volumio[2118]: error: [squeezelite_mc] An error occurred while processing discovered server: Cannot connect Error: Cannot connect Mar 04 18:07:23 volumio volumio[2118]: at Socket. (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:131:35) Mar 04 18:07:23 volumio volumio[2118]: at Object.onceWrapper (node:events:628:28) Mar 04 18:07:23 volumio volumio[2118]: at Socket.emit (node:events:514:28) Mar 04 18:07:23 volumio volumio[2118]: at Socket._onTimeout (node:net:589:8) Mar 04 18:07:23 volumio volumio[2118]: at listOnTimeout (node:internal/timers:573:17) Mar 04 18:07:23 volumio volumio[2118]: at process.processTimers (node:internal/timers:514:7) Mar 04 18:07:23 volumio volumio[2118]: info: [squeezelite_mc] Server lost: {"ip":"192.168.0.114","name":"mits","ver":"9.0.3","uuid":"8c807fff-4fa8-4664-abbd-95c40dd968be","jsonPort":"9000","cliPort":"9090"} Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 04 18:07:34 volumio volumio[2118]: info: Adding plugin bluetooth to MyMusic Plugins Mar 04 18:07:34 volumio volumio[2118]: info: Adding plugin multiroom to MyMusic Plugins Mar 04 18:07:34 volumio volumio[2118]: info: Adding plugin metavolumio to MyMusic Plugins Mar 04 18:07:34 volumio volumio[2118]: info: Adding plugin cd_controller to MyMusic Plugins Mar 04 18:07:34 volumio volumio[2118]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 04 18:07:34 volumio volumio[2118]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 04 18:07:34 volumio volumio[2118]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 04 18:07:34 volumio volumio[2118]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 04 18:07:34 volumio volumio[2118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 18:07:34 volumio volumio[2118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 18:07:35 volumio volumio[2118]: info: Starting MyVolumio Remote Streaming Endpoints Mar 04 18:07:35 volumio volumio[2118]: info: MyVolumio login type: Token Mar 04 18:07:35 volumio volumio[2118]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 04 18:07:35 volumio volumio[2118]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 04 18:07:35 volumio volumio[2118]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 04 18:07:35 volumio volumio[2118]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 04 18:07:35 volumio volumio[2118]: info: Streaming services startup Mar 04 18:07:35 volumio volumio[2118]: info: Starting Streaming Daemon Mar 04 18:07:35 volumio sudo[2822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 04 18:07:35 volumio sudo[2822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 18:07:35 volumio volumio[2118]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 04 18:07:35 volumio sudo[2822]: pam_unix(sudo:session): session closed for user root Mar 04 18:07:35 volumio volumio[2118]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 18:07:35 volumio volumio[2118]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Mar 04 18:07:35 volumio volumio[2118]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Mar 04 18:07:35 volumio volumio[2118]: at new NodeError (node:internal/errors:405:5) Mar 04 18:07:35 volumio volumio[2118]: at assert (node:internal/assert:14:11) Mar 04 18:07:35 volumio volumio[2118]: at internalConnectMultiple (node:net:1118:3) Mar 04 18:07:35 volumio volumio[2118]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Mar 04 18:07:35 volumio volumio[2118]: at listOnTimeout (node:internal/timers:575:11) Mar 04 18:07:35 volumio volumio[2118]: at process.processTimers (node:internal/timers:514:7) { Mar 04 18:07:35 volumio volumio[2118]: code: 'ERR_INTERNAL_ASSERTION' Mar 04 18:07:35 volumio volumio[2118]: } Mar 04 18:07:35 volumio volumio[2118]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 18:07:36 volumio sudo[2842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 18:06' Mar 04 18:07:36 volumio sudo[2842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"