Sep 16 16:30:08 volumio-sala-rp2 volumio[6494]: info: [now-playing] App is listening on port 4004. Sep 16 16:30:09 volumio-sala-rp2 sudo[6861]: pam_unix(sudo:session): session closed for user root Sep 16 16:30:10 volumio-sala-rp2 volumio[6494]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Sep 16 16:30:10 volumio-sala-rp2 volumio-remote-updater[498]: [2025-09-16 16:30:10] [connect] Successful connection Sep 16 16:30:11 volumio-sala-rp2 volumio[6494]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 16 16:30:11 volumio-sala-rp2 volumio[6494]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 16 16:30:11 volumio-sala-rp2 volumio[6494]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 16 16:30:11 volumio-sala-rp2 volumio[6494]: info: camilladsp service started and running in background, instance 1 Sep 16 16:30:11 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:30:11 volumio-sala-rp2 volumio[6494]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Sep 16 16:30:11 volumio-sala-rp2 volumio[6494]: info: FusionDsp loaded Sep 16 16:30:11 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 16:30:11 volumio-sala-rp2 volumio[6494]: error: FusionDsp - Socket connection not established Sep 16 16:30:11 volumio-sala-rp2 volumio[6494]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 16 16:30:11 volumio-sala-rp2 volumio[6494]: info: Adding Signal Path Element [object Object] Sep 16 16:30:11 volumio-sala-rp2 volumio[6494]: info: Adding fusiondspeq DSP Signal Path Element Sep 16 16:30:12 volumio-sala-rp2 volumio[6494]: info: FusionDsp - ---- installed callbackRead Sep 16 16:30:12 volumio-sala-rp2 volumio[6494]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 16 16:30:12 volumio-sala-rp2 sudo[6923]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 16 16:30:12 volumio-sala-rp2 sudo[6923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 16:30:13 volumio-sala-rp2 volumio[6494]: info: Discovery: adding 9ec3bfb0-83e4-4019-81ce-c27075e77eee Sep 16 16:30:13 volumio-sala-rp2 volumio[6494]: info: Discovery: Found device Volumio Sala RP2 Sep 16 16:30:13 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::volumioGetState Sep 16 16:30:13 volumio-sala-rp2 volumio[6494]: info: CorePlayQueue::getTrack 0 Sep 16 16:30:13 volumio-sala-rp2 volumio[6494]: info: Discovery: this is already registered, 9ec3bfb0-83e4-4019-81ce-c27075e77eee Sep 16 16:30:13 volumio-sala-rp2 volumio[6494]: info: Discovery: Found device Volumio Sala RP2 Sep 16 16:30:13 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::volumioGetState Sep 16 16:30:13 volumio-sala-rp2 volumio[6494]: info: CorePlayQueue::getTrack 0 Sep 16 16:30:13 volumio-sala-rp2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 16 16:30:14 volumio-sala-rp2 sudo[6923]: pam_unix(sudo:session): session closed for user root Sep 16 16:30:15 volumio-sala-rp2 dbus-daemon[474]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2660' (uid=0 pid=6926 comm="timedatectl show --property=NTPSynchronized --valu") Sep 16 16:30:15 volumio-sala-rp2 nmbd[913]: [2025/09/16 16:30:15.945826, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 16 16:30:15 volumio-sala-rp2 nmbd[913]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.54 for name WORKGROUP<1d>. Sep 16 16:30:15 volumio-sala-rp2 nmbd[913]: This response was from IP 192.168.1.45, reporting an IP address of 192.168.1.45. Sep 16 16:30:15 volumio-sala-rp2 nmbd[913]: [2025/09/16 16:30:15.946821, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 16 16:30:15 volumio-sala-rp2 nmbd[913]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.1.54 for name WORKGROUP<1d>. Sep 16 16:30:15 volumio-sala-rp2 nmbd[913]: This response was from IP 192.168.1.46, reporting an IP address of 192.168.1.45. Sep 16 16:30:15 volumio-sala-rp2 nmbd[913]: [2025/09/16 16:30:15.947320, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 16 16:30:15 volumio-sala-rp2 nmbd[913]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.1.54 for name WORKGROUP<1d>. Sep 16 16:30:15 volumio-sala-rp2 nmbd[913]: This response was from IP 192.168.1.46, reporting an IP address of 192.168.1.45. Sep 16 16:30:15 volumio-sala-rp2 nmbd[913]: [2025/09/16 16:30:15.947732, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 16 16:30:15 volumio-sala-rp2 nmbd[913]: query_name_response: Multiple (5) responses received for a query on subnet 192.168.1.54 for name WORKGROUP<1d>. Sep 16 16:30:15 volumio-sala-rp2 nmbd[913]: This response was from IP 192.168.1.46, reporting an IP address of 192.168.1.45. Sep 16 16:30:15 volumio-sala-rp2 nmbd[913]: [2025/09/16 16:30:15.948174, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 16 16:30:15 volumio-sala-rp2 nmbd[913]: query_name_response: Multiple (6) responses received for a query on subnet 192.168.1.54 for name WORKGROUP<1d>. Sep 16 16:30:15 volumio-sala-rp2 nmbd[913]: This response was from IP 192.168.1.45, reporting an IP address of 192.168.1.45. Sep 16 16:30:16 volumio-sala-rp2 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 16 16:30:17 volumio-sala-rp2 dbus-daemon[474]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 16 16:30:17 volumio-sala-rp2 systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 16 16:30:18 volumio-sala-rp2 setdatetime-helper.sh[6925]: Time is already synchronized. Sep 16 16:30:18 volumio-sala-rp2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 16 16:30:18 volumio-sala-rp2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 16 16:30:19 volumio-sala-rp2 volumio[6494]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 16 16:30:19 volumio-sala-rp2 volumio[6494]: info: Reading ALSA contributions from plugins. Sep 16 16:30:24 volumio-sala-rp2 volumio[6494]: info: MPD Permissions set Sep 16 16:30:24 volumio-sala-rp2 volumio[6494]: info: MPD Permissions set Sep 16 16:30:24 volumio-sala-rp2 volumio[6494]: info: Upmpdcli Daemon Started Sep 16 16:30:24 volumio-sala-rp2 sudo[6946]: volumio : PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Sep 16 16:30:24 volumio-sala-rp2 sudo[6946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 16:30:26 volumio-sala-rp2 volumio-remote-updater[498]: [2025-09-16 16:30:26] [connect] Successful connection Sep 16 16:30:29 volumio-sala-rp2 volumio[6494]: error: FusionDsp - Reload WebSocket error: [object Object] Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (spna7l1b5p34c8p4u7jnkljo0e): Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: (ConnectionError) (YouTube) Connection error in getting lounge token Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: Error info: { Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: } Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: --->(TypeError) fetch failed Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: Stack trace: Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: ConnectionError: (YouTube) Connection error in getting lounge token Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:440:15) Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: at runNextTicks (node:internal/process/task_queues:64:3) Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: at process.processImmediate (node:internal/timers:449:9) Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:130:31) Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: at async Promise.all (index 0) Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:13) Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:93:13) Sep 16 16:30:34 volumio-sala-rp2 volumio[6494]: Going to generate fresh screen Id and try again... Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (eb98o8fbq7c5odu8gofeu7020i): Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: Error info: { Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: } Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: --->(TypeError) fetch failed Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: Stack trace: Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: ConnectionError: (YouTube Music) Connection error in getting lounge token Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:440:15) Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: at runNextTicks (node:internal/process/task_queues:64:3) Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: at process.processImmediate (node:internal/timers:449:9) Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:130:31) Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: at async Promise.all (index 1) Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:13) Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:93:13) Sep 16 16:30:39 volumio-sala-rp2 volumio[6494]: Going to generate fresh screen Id and try again... Sep 16 16:30:41 volumio-sala-rp2 volumio-remote-updater[498]: [2025-09-16 16:30:41] [connect] Successful connection Sep 16 16:30:45 volumio-sala-rp2 volumio[6494]: info: Volumio called home Sep 16 16:30:46 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 16:30:46 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 16:30:46 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 16 16:30:46 volumio-sala-rp2 volumio[6494]: error: Plugin music_service mpd failed to complete 'onStart' in a timely fashion Sep 16 16:30:46 volumio-sala-rp2 volumio[6494]: error: Plugin music_service ytcr failed to complete 'onStart' in a timely fashion Sep 16 16:30:46 volumio-sala-rp2 volumio[6494]: info: ------------------------------------------- Sep 16 16:30:46 volumio-sala-rp2 volumio[6494]: info: ----- MyVolumio plugins startup ---- Sep 16 16:30:46 volumio-sala-rp2 volumio[6494]: info: ------------------------------------------- Sep 16 16:30:46 volumio-sala-rp2 volumio[6494]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 16 16:30:48 volumio-sala-rp2 volumio[6494]: error: error Sep 16 16:30:49 volumio-sala-rp2 systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 16 16:30:49 volumio-sala-rp2 systemd[1]: systemd-timedated.service: Consumed 1.483s CPU time. Sep 16 16:30:57 volumio-sala-rp2 volumio-remote-updater[498]: [2025-09-16 16:30:57] [connect] Successful connection Sep 16 16:30:58 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:30:58 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:30:58 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:30:58 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:30:58 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:30:58 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:30:58 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:30:58 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:30:59 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 16:31:00 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:31:00 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:31:00 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:31:00 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:31:00 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:31:00 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:31:00 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:31:00 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:31:00 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:31:00 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 16:31:00 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: LoungeScreenDisconnected { Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: AID: null, Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: name: 'loungeScreenDisconnected', Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: payload: {} Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: } Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: Stack trace: Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: IncompleteAPIDataError: Missing data required to construct query string from bind params Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:497:103) Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:558:71) Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:52:32) Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:23) Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:345:67 Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: at new Promise () Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:344:16) Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:241:28) Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:209:26) Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: at runNextTicks (node:internal/process/task_queues:64:3) Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: at process.processImmediate (node:internal/timers:449:9) Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: at async Promise.all (index 0) Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:13) Sep 16 16:31:07 volumio-sala-rp2 volumio[6494]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:93:13) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: error: [yt-cast-receiver] Failed to start receiver: Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: (AppError) Failed to start YouTubeApp Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: --->(SessionError) (YouTube) Failed to establish session Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: Error info: { Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: } Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: --------->(TypeError) fetch failed Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: Stack trace: Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: AppError: Failed to start YouTubeApp Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:131:19) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at runNextTicks (node:internal/process/task_queues:64:3) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at process.processImmediate (node:internal/timers:449:9) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:93:13) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: LoungeScreenDisconnected { Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: AID: null, Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: name: 'loungeScreenDisconnected', Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: payload: {} Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: } Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: Stack trace: Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: IncompleteAPIDataError: Missing data required to construct query string from bind params Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:497:103) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:558:71) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:52:32) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:23) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:345:67 Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at new Promise () Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:344:16) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:241:28) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:123:39 Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:128:19 Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at Array.forEach () Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:24) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at runNextTicks (node:internal/process/task_queues:64:3) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at process.processImmediate (node:internal/timers:449:9) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: LoungeScreenDisconnected { Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: AID: null, Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: name: 'loungeScreenDisconnected', Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: payload: {} Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: } Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: Stack trace: Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: IncompleteAPIDataError: Missing data required to construct query string from bind params Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:497:103) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:558:71) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:52:32) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:242:71) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at runNextTicks (node:internal/process/task_queues:64:3) Sep 16 16:31:09 volumio-sala-rp2 volumio[6494]: at process.processImmediate (node:internal/timers:449:9) Sep 16 16:31:10 volumio-sala-rp2 volumio[6494]: error: [ytcr] Failed to start plugin: Sep 16 16:31:10 volumio-sala-rp2 volumio[6494]: (AppError) Failed to start YouTubeApp Sep 16 16:31:10 volumio-sala-rp2 volumio[6494]: --->(SessionError) (YouTube) Failed to establish session Sep 16 16:31:10 volumio-sala-rp2 volumio[6494]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Sep 16 16:31:10 volumio-sala-rp2 volumio[6494]: Error info: { Sep 16 16:31:10 volumio-sala-rp2 volumio[6494]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Sep 16 16:31:10 volumio-sala-rp2 volumio[6494]: } Sep 16 16:31:10 volumio-sala-rp2 volumio[6494]: --------->(TypeError) fetch failed Sep 16 16:31:10 volumio-sala-rp2 volumio[6494]: Stack trace: Sep 16 16:31:10 volumio-sala-rp2 volumio[6494]: AppError: Failed to start YouTubeApp Sep 16 16:31:10 volumio-sala-rp2 volumio[6494]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:131:19) Sep 16 16:31:10 volumio-sala-rp2 volumio[6494]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 16 16:31:10 volumio-sala-rp2 volumio[6494]: at runNextTicks (node:internal/process/task_queues:64:3) Sep 16 16:31:10 volumio-sala-rp2 volumio[6494]: at process.processImmediate (node:internal/timers:449:9) Sep 16 16:31:10 volumio-sala-rp2 volumio[6494]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:93:13) Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: LoungeScreenDisconnected { Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: AID: null, Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: name: 'loungeScreenDisconnected', Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: payload: {} Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: } Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: Stack trace: Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: IncompleteAPIDataError: Missing data required to construct query string from bind params Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:497:103) Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:558:71) Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:52:32) Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:242:71) Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: at runNextTicks (node:internal/process/task_queues:64:3) Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: at process.processImmediate (node:internal/timers:449:9) Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:123:25 Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: error: MyVolumio Plugin failed to start in a timely fashion Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: [Metrics] CommandRouter: 171s 540.26ms Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::volumiosetStartupVolume Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: info: VolumeController:: Setting startup Volume 5 Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: info: VolumeController::SetAlsaVolume5 Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::Close All Modals sent Sep 16 16:31:11 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::Close All Modals sent Sep 16 16:31:12 volumio-sala-rp2 volumio-remote-updater[498]: [2025-09-16 16:31:12] [connect] Successful connection Sep 16 16:31:12 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::volumioGetState Sep 16 16:31:12 volumio-sala-rp2 volumio[6494]: info: CorePlayQueue::getTrack 0 Sep 16 16:31:13 volumio-sala-rp2 volumio[6494]: info: CoreStateMachine::pushState Sep 16 16:31:13 volumio-sala-rp2 volumio[6494]: info: CorePlayQueue::getTrack 0 Sep 16 16:31:13 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::volumioPushState Sep 16 16:31:14 volumio-sala-rp2 volumio[6494]: error: error Sep 16 16:31:15 volumio-sala-rp2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 16 16:31:17 volumio-sala-rp2 dbus-daemon[474]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2662' (uid=0 pid=7052 comm="timedatectl show --property=NTPSynchronized --valu") Sep 16 16:31:18 volumio-sala-rp2 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 16 16:31:19 volumio-sala-rp2 dbus-daemon[474]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 16 16:31:19 volumio-sala-rp2 systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 16 16:31:20 volumio-sala-rp2 setdatetime-helper.sh[7051]: Time is already synchronized. Sep 16 16:31:20 volumio-sala-rp2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 16 16:31:20 volumio-sala-rp2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 16 16:31:20 volumio-sala-rp2 volumio[6494]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 16 16:31:20 volumio-sala-rp2 volumio[6494]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 16 16:31:20 volumio-sala-rp2 volumio[6494]: info: camilladsp stopping service pid 6916... Sep 16 16:31:20 volumio-sala-rp2 volumio[6494]: info: camilladsp service terminated, instance 1 Sep 16 16:31:20 volumio-sala-rp2 volumio[6494]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 16 16:31:21 volumio-sala-rp2 volumio[6494]: info: camilladsp service started and running in background, instance 1 Sep 16 16:31:22 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 16 16:31:22 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 16 16:31:22 volumio-sala-rp2 volumio[6494]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 16 16:31:24 volumio-sala-rp2 volumio[6494]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 16:31:25 volumio-sala-rp2 volumio[6494]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Sep 16 16:31:25 volumio-sala-rp2 volumio[6494]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Sep 16 16:31:25 volumio-sala-rp2 volumio[6494]: at new NodeError (node:internal/errors:405:5) Sep 16 16:31:25 volumio-sala-rp2 volumio[6494]: at assert (node:internal/assert:14:11) Sep 16 16:31:25 volumio-sala-rp2 volumio[6494]: at internalConnectMultiple (node:net:1118:3) Sep 16 16:31:25 volumio-sala-rp2 volumio[6494]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Sep 16 16:31:25 volumio-sala-rp2 volumio[6494]: at listOnTimeout (node:internal/timers:575:11) Sep 16 16:31:25 volumio-sala-rp2 volumio[6494]: at process.processTimers (node:internal/timers:514:7) { Sep 16 16:31:25 volumio-sala-rp2 volumio[6494]: code: 'ERR_INTERNAL_ASSERTION' Sep 16 16:31:25 volumio-sala-rp2 volumio[6494]: } Sep 16 16:31:25 volumio-sala-rp2 volumio[6494]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 16:31:27 volumio-sala-rp2 systemd[1]: mpd.service: start operation timed out. Terminating. Sep 16 16:31:27 volumio-sala-rp2 systemd[1]: mpd.service: Failed with result 'timeout'. Sep 16 16:31:27 volumio-sala-rp2 systemd[1]: Failed to start mpd.service - Music Player Daemon. Sep 16 16:31:27 volumio-sala-rp2 systemd[1]: mpd.service: Consumed 37.134s CPU time. Sep 16 16:31:27 volumio-sala-rp2 systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Sep 16 16:31:27 volumio-sala-rp2 systemctl[6821]: Job for mpd.service failed because a timeout was exceeded. Sep 16 16:31:27 volumio-sala-rp2 systemctl[6821]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. Sep 16 16:31:28 volumio-sala-rp2 mpd_monitor.sh[478]: MPD Monitor Service: MPD restarted due to no mpc output. Sep 16 16:31:28 volumio-sala-rp2 volumio-remote-updater[498]: [2025-09-16 16:31:28] [connect] Successful connection Sep 16 16:31:28 volumio-sala-rp2 sudo[6763]: pam_unix(sudo:session): session closed for user root Sep 16 16:31:28 volumio-sala-rp2 sudo[6759]: pam_unix(sudo:session): session closed for user root Sep 16 16:31:31 volumio-sala-rp2 bash[7097]: cset: --> shielding system active with Sep 16 16:31:31 volumio-sala-rp2 bash[7097]: cset: "system" cpuset of CPUSPEC(1-3) with 146 tasks running Sep 16 16:31:31 volumio-sala-rp2 bash[7097]: cset: "user" cpuset of CPUSPEC(0) with 0 tasks running Sep 16 16:31:32 volumio-sala-rp2 systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Sep 16 16:31:32 volumio-sala-rp2 systemd[1]: Reloading. Sep 16 16:31:34 volumio-sala-rp2 sudo[7135]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-16 16:30' Sep 16 16:31:34 volumio-sala-rp2 sudo[7135]: 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="dfc7bb04e09bf2260691765a2340814af1fa2971" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="30d63a8c53d2826bd7f262e22b4e4511a4cc9d01" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Aug 30 06:39:32 UTC 2025" VOLUMIO_VERSION="4.022" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e7b0bd0cb5661ea77402e5bd121ec64a"