Dec 06 20:07:04 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 58. Dec 06 20:07:04 volumio systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Dec 06 20:07:04 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 06 20:07:04 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 06 20:07:04 volumio systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Dec 06 20:07:04 volumio systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Dec 06 20:07:04 volumio upmpdcli[3312]: Could not open config: /tmp/upmpdcli.conf Dec 06 20:07:04 volumio systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Dec 06 20:07:04 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 06 20:07:04 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 06 20:07:19 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 59. Dec 06 20:07:19 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 06 20:07:19 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 06 20:07:19 volumio upmpdcli[3342]: Could not open config: /tmp/upmpdcli.conf Dec 06 20:07:19 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 06 20:07:19 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 06 20:07:29 volumio go-librespot[1528]: time="2025-12-06T20:07:29+01:00" level=error msg="did not receive last pong from dealer, 240s passed" Dec 06 20:07:35 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 60. Dec 06 20:07:35 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 06 20:07:35 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 06 20:07:35 volumio upmpdcli[3361]: Could not open config: /tmp/upmpdcli.conf Dec 06 20:07:35 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 06 20:07:35 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 06 20:07:50 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 61. Dec 06 20:07:50 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 06 20:07:50 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 06 20:07:50 volumio upmpdcli[3394]: Could not open config: /tmp/upmpdcli.conf Dec 06 20:07:50 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 06 20:07:50 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 06 20:07:59 volumio go-librespot[1528]: time="2025-12-06T20:07:59+01:00" level=error msg="did not receive last pong from dealer, 270s passed" Dec 06 20:08:05 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 62. Dec 06 20:08:05 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 06 20:08:05 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 06 20:08:05 volumio upmpdcli[3412]: Could not open config: /tmp/upmpdcli.conf Dec 06 20:08:05 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 06 20:08:05 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 06 20:08:11 volumio ntpd[1011]: PROTO: 81.26.20.2 unlink local addr 192.168.1.100 -> Dec 06 20:08:20 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 63. Dec 06 20:08:20 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 06 20:08:20 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 06 20:08:20 volumio upmpdcli[3442]: Could not open config: /tmp/upmpdcli.conf Dec 06 20:08:20 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 06 20:08:20 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 06 20:08:29 volumio go-librespot[1528]: time="2025-12-06T20:08:29+01:00" level=error msg="did not receive last pong from dealer, 300s passed" Dec 06 20:08:32 volumio volumio[1250]: info: [yt-cast-receiver] Connecting sender through DIAL... Dec 06 20:08:36 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 64. Dec 06 20:08:36 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 06 20:08:36 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 06 20:08:36 volumio upmpdcli[3460]: Could not open config: /tmp/upmpdcli.conf Dec 06 20:08:36 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 06 20:08:36 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 06 20:08:36 volumio volumio[1250]: info: [yt-cast-receiver] (YouTube) Sender connected: XIAOMI 2306EPN60G (user: Kanstantsin) Dec 06 20:08:36 volumio volumio[1250]: info: [ytcr] ***** Sender connected ***** Dec 06 20:08:36 volumio volumio[1250]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 20:08:41 volumio volumio[1250]: info: [yt-cast-receiver] Player.resume() Dec 06 20:08:41 volumio volumio[1250]: info: [yt-cast-receiver] Player.play(): p8ug6mn93xk @ 0s Dec 06 20:08:41 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Dec 06 20:08:41 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Dec 06 20:08:41 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Dec 06 20:08:41 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Dec 06 20:08:41 volumio volumio[1250]: info: CoreCommandRouter::volumioStop Dec 06 20:08:41 volumio volumio[1250]: info: CoreStateMachine::stop Dec 06 20:08:41 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 20:08:41 volumio volumio[1250]: info: CoreStateMachine::stPlaybackTimer Dec 06 20:08:41 volumio volumio[1250]: info: CoreStateMachine::updateTrackBlock Dec 06 20:08:41 volumio volumio[1250]: info: CorePlayQueue::getTrackBlock Dec 06 20:08:41 volumio volumio[1250]: info: CoreStateMachine::pushState Dec 06 20:08:41 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Dec 06 20:08:41 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 20:08:41 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Dec 06 20:08:41 volumio volumio[1250]: info: CoreStateMachine::serviceStop Dec 06 20:08:41 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Dec 06 20:08:41 volumio volumio[1250]: info: CoreCommandRouter::serviceStop Dec 06 20:08:41 volumio volumio[1250]: info: Spotify Stop Dec 06 20:08:41 volumio volumio[1250]: SPOTIFY: SPOTIFY STOP Dec 06 20:08:41 volumio volumio[1250]: SPOTIFY: {"status":"play","position":0,"title":"Save Me - Remastered 2011","artist":"Queen","album":"The Game (2011 Remaster)","albumart":"https://i.scdn.co/image/ab67616d0000b273056e90910cbaf5c5b892aeba","uri":"spotify:track:1pBFEy8cz0Fq4Pru0c4awd","trackType":"spotify","codec":"ogg","seek":0,"duration":229,"samplerate":"320 kbps","bitdepth":"16 bit","random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":"","dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","updatedb":false,"volatile":false,"service":"spop"} Dec 06 20:08:41 volumio volumio[1250]: info: Sending Spotify command to local API: /player/pause Dec 06 20:08:43 volumio go-librespot[1528]: time="2025-12-06T20:08:43+01:00" level=trace msg="received accesspoint ping" Dec 06 20:08:43 volumio go-librespot[1528]: time="2025-12-06T20:08:43+01:00" level=trace msg="received accesspoint pong ack" Dec 06 20:08:50 volumio volumio[1250]: npm notice Dec 06 20:08:50 volumio volumio[1250]: npm notice New major version of npm available! 9.8.0 -> 11.6.4 Dec 06 20:08:50 volumio volumio[1250]: npm notice Changelog: Dec 06 20:08:50 volumio volumio[1250]: npm notice Run `npm install -g npm@11.6.4` to update! Dec 06 20:08:50 volumio volumio[1250]: npm notice Dec 06 20:08:50 volumio volumio[1250]: info: [ytcr] Innertube support service: Deno not installed or otherwise failed to start: Command failed: npx --no-install --yes deno --version Dec 06 20:08:50 volumio volumio[1250]: npm notice Dec 06 20:08:50 volumio volumio[1250]: npm notice New major version of npm available! 9.8.0 -> 11.6.4 Dec 06 20:08:50 volumio volumio[1250]: npm notice Changelog: Dec 06 20:08:50 volumio volumio[1250]: npm notice Run `npm install -g npm@11.6.4` to update! Dec 06 20:08:50 volumio volumio[1250]: npm notice Dec 06 20:08:50 volumio volumio[1250]: info: [ytcr] Innertube support service: Start service with Node Dec 06 20:08:50 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Dec 06 20:08:50 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Dec 06 20:08:50 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 20:08:50 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Dec 06 20:08:50 volumio volumio[1250]: info: CoreStateMachine::pushState Dec 06 20:08:50 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 20:08:50 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Dec 06 20:08:50 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Dec 06 20:08:50 volumio volumio[1250]: info: CoreStateMachine::pushState Dec 06 20:08:50 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Dec 06 20:08:50 volumio volumio[1250]: error: [ytcr] Failed to obtain manual pairing code: timeout. Dec 06 20:08:50 volumio volumio[1250]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 20:08:50 volumio volumio[1250]: ConnectionError: Connection error in fetching pairing code Dec 06 20:08:50 volumio volumio[1250]: at PairingCodeRequestService._PairingCodeRequestService_getCodeAndEmit (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/PairingCodeRequestService.js:126:17) Dec 06 20:08:50 volumio volumio[1250]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 06 20:08:50 volumio volumio[1250]: at runNextTicks (node:internal/process/task_queues:64:3) Dec 06 20:08:50 volumio volumio[1250]: at process.processImmediate (node:internal/timers:449:9) { Dec 06 20:08:50 volumio volumio[1250]: cause: TypeError: fetch failed Dec 06 20:08:50 volumio volumio[1250]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Dec 06 20:08:50 volumio volumio[1250]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 06 20:08:50 volumio volumio[1250]: at runNextTicks (node:internal/process/task_queues:64:3) Dec 06 20:08:50 volumio volumio[1250]: at process.processImmediate (node:internal/timers:449:9) Dec 06 20:08:50 volumio volumio[1250]: at async PairingCodeRequestService._PairingCodeRequestService_getCodeAndEmit (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/PairingCodeRequestService.js:120:20) { Dec 06 20:08:50 volumio volumio[1250]: cause: ConnectTimeoutError: Connect Timeout Error Dec 06 20:08:50 volumio volumio[1250]: at onConnectTimeout (node:internal/deps/undici/undici:8522:28) Dec 06 20:08:50 volumio volumio[1250]: at node:internal/deps/undici/undici:8480:50 Dec 06 20:08:50 volumio volumio[1250]: at Immediate._onImmediate (node:internal/deps/undici/undici:8511:13) Dec 06 20:08:50 volumio volumio[1250]: at process.processImmediate (node:internal/timers:478:21) { Dec 06 20:08:50 volumio volumio[1250]: code: 'UND_ERR_CONNECT_TIMEOUT' Dec 06 20:08:50 volumio volumio[1250]: } Dec 06 20:08:50 volumio volumio[1250]: }, Dec 06 20:08:50 volumio volumio[1250]: info: { Dec 06 20:08:50 volumio volumio[1250]: url: 'https://www.youtube.com/api/lounge/pairing/get_pairing_code?ctx=pair' Dec 06 20:08:50 volumio volumio[1250]: } Dec 06 20:08:50 volumio volumio[1250]: } Dec 06 20:08:50 volumio volumio[1250]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 20:08:51 volumio sudo[3567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 20:07' Dec 06 20:08:51 volumio sudo[3567]: 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="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"