Aug 25 16:32:21 volumio ntpd[1062]: PROTO: 17.253.18.99 unlink local addr 192.168.1.5 -> Aug 25 16:32:34 volumio ntpd[1062]: PROTO: 103.136.36.100 unlink local addr 192.168.1.5 -> Aug 25 16:33:05 volumio ntpd[1062]: PROTO: 95.216.144.226 unlink local addr 192.168.1.5 -> Aug 25 16:33:15 volumio volumio[1295]: info: [ytcr] Obtained PO token for video #oeyVftchOOQ: MltH8kyM3jkrvUe2g1U6VwUp4vuHj062qi2XE1k9OGl92Sh6igscbYovErnsZQjZJrQIaO8oN9tPUNCa9pxY1k7W4imgwf2MMj7JAVmMswQgsBU0NYYWdLoLpUaG Aug 25 16:33:16 volumio volumio[1295]: info: [ytcr] (oeyVftchOOQ) fetching player data using ANDROID_VR client... Aug 25 16:33:16 volumio volumio[1295]: info: [ytcr] (Uthradapooviliyil | K.J.Yesudas | Kaithapram | Sunny Stephen | Traditional Onam Mood Hit Song) validating stream URL "https://rr3---sn-3xg5a5-jv3e.googlevideo.com/videoplayback?expire=1787677396&ei=dHaNauinE7C54t4PsL3p2Q4&ip=103.182.166.252&id=o-AF2KETJZ02GCN8AbCw8RIwsVQXdpAnsN_tGC1hMCFNOa&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=870&met=1787655796%2C&mh=8o&mm=18%2C29&mn=sn-3xg5a5-jv3e%2Csn-h5576ns6&ms=aub%2Crdu&mv=m&mvi=3&pl=24&rms=aub%2Caub&initcwndbps=2067500&siu=1&bui=AR3QkAlx6SNCIb7E2avhUNDK8z9lfH-zwRU-JHdUhSApV1DInX5aCbX46-hf3J0tpmjsjfNwnQ&vprv=1&svpuc=1&mime=audio%2Fwebm&rqh=1&gir=yes&clen=5259751&dur=315.861&lmt=1755395481519678&mt=1787655415&fvip=4&keepalive=yes&fexp=51565115%2C52135441&c=ANDROID_VR&txp=5532534&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cvprv%2Csvpuc%2Cmime%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AE0s2JYwRAIgamJVfQo3BEXaObn_teVpepiBx2wM-x9t_QNTf-3Fe3cCIANsqA7_ebdzcBqoE1Y4VTmE07OHsvECLlsvd7mg6CUq&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgCP9A9MOQXsDJELrq9hoy2TDj_E6UcPGvyd1Z7nIcQd8CIQCgYh8TXC32GchaN5dvUZAKDssoPqONf2O7-BpbrmmKfw%3D%3D&pot=MltH8kyM3jkrvUe2g1U6VwUp4vuHj062qi2XE1k9OGl92Sh6igscbYovErnsZQjZJrQIaO8oN9tPUNCa9pxY1k7W4imgwf2MMj7JAVmMswQgsBU0NYYWdLoLpUaG"... Aug 25 16:33:16 volumio volumio[1295]: info: [ytcr] (Uthradapooviliyil | K.J.Yesudas | Kaithapram | Sunny Stephen | Traditional Onam Mood Hit Song) stream validated in 0.043s. Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:16 volumio volumio[1295]: info: Ignoring MPD Status Update Aug 25 16:33:22 volumio volumio[1295]: info: Preload queue cleared Aug 25 16:33:22 volumio volumio[1295]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::ClearQueue Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::stop Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::serviceStop Aug 25 16:33:22 volumio volumio[1295]: info: CoreCommandRouter::serviceStop Aug 25 16:33:22 volumio volumio[1295]: info: [yt-cast-receiver] Player.stop() Aug 25 16:33:22 volumio volumio[1295]: info: CorePlayQueue::clearPlayQueue Aug 25 16:33:22 volumio volumio[1295]: info: CorePlayQueue::saveQueue Aug 25 16:33:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushQueue Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::addQueueItems Aug 25 16:33:22 volumio volumio[1295]: info: CorePlayQueue::addQueueItems Aug 25 16:33:22 volumio volumio[1295]: info: Preload queue cleared Aug 25 16:33:22 volumio volumio[1295]: info: Adding Item to queue: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/01. Track 1.wav Aug 25 16:33:22 volumio volumio[1295]: info: Using cached record of: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/01. Track 1.wav Aug 25 16:33:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushQueue Aug 25 16:33:22 volumio volumio[1295]: info: CorePlayQueue::saveQueue Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::updateTrackBlock Aug 25 16:33:22 volumio volumio[1295]: info: CorePlayQueue::getTrackBlock Aug 25 16:33:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPlay Aug 25 16:33:22 volumio volumio[1295]: verbose: UNSET VOLATILE: Service: ytcr Aug 25 16:33:22 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::pushState Aug 25 16:33:22 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 16:33:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Aug 25 16:33:22 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::pushState Aug 25 16:33:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Aug 25 16:33:22 volumio volumio[1295]: info: [yt-cast-receiver] Player.stop() Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::play index 0 Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::addQueueItems Aug 25 16:33:22 volumio volumio[1295]: info: CorePlayQueue::addQueueItems Aug 25 16:33:22 volumio volumio[1295]: info: Preload queue cleared Aug 25 16:33:22 volumio volumio[1295]: info: Adding Item to queue: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/02. Track 2.wav Aug 25 16:33:22 volumio volumio[1295]: info: Using cached record of: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/02. Track 2.wav Aug 25 16:33:22 volumio volumio[1295]: info: Adding Item to queue: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/03. Track 3.wav Aug 25 16:33:22 volumio volumio[1295]: info: Using cached record of: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/03. Track 3.wav Aug 25 16:33:22 volumio volumio[1295]: info: Adding Item to queue: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/04. Track 4.wav Aug 25 16:33:22 volumio volumio[1295]: info: Using cached record of: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/04. Track 4.wav Aug 25 16:33:22 volumio volumio[1295]: info: Adding Item to queue: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/05. Track 5.wav Aug 25 16:33:22 volumio volumio[1295]: info: Using cached record of: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/05. Track 5.wav Aug 25 16:33:22 volumio volumio[1295]: info: Adding Item to queue: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/06. Track 6.wav Aug 25 16:33:22 volumio volumio[1295]: info: Using cached record of: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/06. Track 6.wav Aug 25 16:33:22 volumio volumio[1295]: info: Adding Item to queue: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/07. Track 7.wav Aug 25 16:33:22 volumio volumio[1295]: info: Using cached record of: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/07. Track 7.wav Aug 25 16:33:22 volumio volumio[1295]: info: Adding Item to queue: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/08. Track 8.wav Aug 25 16:33:22 volumio volumio[1295]: info: Using cached record of: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/08. Track 8.wav Aug 25 16:33:22 volumio volumio[1295]: info: Adding Item to queue: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/09. Track 9.wav Aug 25 16:33:22 volumio volumio[1295]: info: Using cached record of: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/09. Track 9.wav Aug 25 16:33:22 volumio volumio[1295]: info: Adding Item to queue: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/10. Track 10.wav Aug 25 16:33:22 volumio volumio[1295]: info: Using cached record of: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/10. Track 10.wav Aug 25 16:33:22 volumio volumio[1295]: info: Adding Item to queue: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/11. Track 11.wav Aug 25 16:33:22 volumio volumio[1295]: info: Using cached record of: music-library/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/11. Track 11.wav Aug 25 16:33:22 volumio volumio5-onboarding[1922]: time=2026-08-25T16:33:22.713+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.6:45166 @ 0x1a78870" state=STATUS_PAUSED positionMs=0 volume=45 Aug 25 16:33:22 volumio volumio5-onboarding[1922]: time=2026-08-25T16:33:22.714+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.6:45166 @ 0x1a78870" state=STATUS_PAUSED positionMs=0 volume=45 Aug 25 16:33:22 volumio volumio5-onboarding[1922]: time=2026-08-25T16:33:22.714+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.6:45166 @ 0x1a78870" id= title= Aug 25 16:33:22 volumio volumio5-onboarding[1922]: time=2026-08-25T16:33:22.714+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.6:45166 @ 0x1a78870" id= title= Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::stop Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::updateTrackBlock Aug 25 16:33:22 volumio volumio[1295]: info: CorePlayQueue::getTrackBlock Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::stPlaybackTimer Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::pushState Aug 25 16:33:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Aug 25 16:33:22 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 16:33:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::serviceStop Aug 25 16:33:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Aug 25 16:33:22 volumio volumio[1295]: info: CoreCommandRouter::serviceStop Aug 25 16:33:22 volumio volumio[1295]: info: ControllerMpd::stop Aug 25 16:33:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 16:33:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushQueue Aug 25 16:33:22 volumio volumio[1295]: info: CorePlayQueue::saveQueue Aug 25 16:33:22 volumio volumio5-onboarding[1922]: time=2026-08-25T16:33:22.727+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.6:45166 @ 0x1a78870" state=STATUS_STOPPED positionMs=0 volume=45 Aug 25 16:33:22 volumio volumio5-onboarding[1922]: time=2026-08-25T16:33:22.727+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.6:45166 @ 0x1a78870" id="mnt/USB/New_Volume/MALAYALAM/ONAM MELODIES/ONAM MELODIES/01. Track 1.wav" title="Track 1" Aug 25 16:33:22 volumio volumio[1295]: info: CoreStateMachine::updateTrackBlock Aug 25 16:33:22 volumio volumio[1295]: info: CorePlayQueue::getTrackBlock Aug 25 16:33:22 volumio volumio[1295]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 16:33:22 volumio volumio[1295]: TypeError: Cannot read properties of null (reading 'mpdSongId') Aug 25 16:33:22 volumio volumio[1295]: at MPDPlayer._MPDPlayer_cancelPrefetch (/data/plugins/music_service/ytcr/dist/lib/MPDPlayer.js:502:172) Aug 25 16:33:22 volumio volumio[1295]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Aug 25 16:33:22 volumio volumio[1295]: at async MPDPlayer.doStop (/data/plugins/music_service/ytcr/dist/lib/MPDPlayer.js:151:9) Aug 25 16:33:22 volumio volumio[1295]: at async MPDPlayer.stop (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/Player.js:121:24) Aug 25 16:33:22 volumio volumio[1295]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 16:33:23 volumio sudo[4553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-25 16:32' Aug 25 16:33:23 volumio sudo[4553]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"