Apr 11 16:42:22 volumio volumio[12258]: info: Apr 11 16:42:22 volumio volumio[12258]: ---------------------------- MPD announces system playlist update Apr 11 16:42:22 volumio volumio[12258]: info: Ignoring MPD Status Update Apr 11 16:42:22 volumio volumio[12258]: info: Apr 11 16:42:22 volumio volumio[12258]: ---------------------------- MPD announces state update: player Apr 11 16:42:22 volumio volumio[12258]: info: ControllerMpd::getState Apr 11 16:42:22 volumio volumio[12258]: verbose: ControllerMpd::sendMpdCommand status Apr 11 16:42:22 volumio volumio[12258]: info: Apr 11 16:42:22 volumio volumio[12258]: ---------------------------- MPD announces system playlist update Apr 11 16:42:22 volumio volumio[12258]: info: Ignoring MPD Status Update Apr 11 16:42:22 volumio volumio[12258]: info: Apr 11 16:42:22 volumio volumio[12258]: ---------------------------- MPD announces state update: player Apr 11 16:42:22 volumio volumio[12258]: info: ControllerMpd::getState Apr 11 16:42:22 volumio volumio[12258]: verbose: ControllerMpd::sendMpdCommand status Apr 11 16:42:22 volumio volumio[12258]: info: Apr 11 16:42:22 volumio volumio[12258]: ---------------------------- MPD announces system playlist update Apr 11 16:42:22 volumio volumio[12258]: info: Ignoring MPD Status Update Apr 11 16:42:22 volumio volumio[12258]: info: ControllerMpd::getState Apr 11 16:42:22 volumio volumio[12258]: verbose: ControllerMpd::sendMpdCommand status Apr 11 16:42:22 volumio volumio[12258]: info: Apr 11 16:42:22 volumio volumio[12258]: ---------------------------- MPD announces state update: player Apr 11 16:42:22 volumio volumio[12258]: error: updateQueue error: null Apr 11 16:42:22 volumio volumio[12258]: error: updateQueue error: null Apr 11 16:42:22 volumio volumio[12258]: info: ControllerMpd::getState Apr 11 16:42:22 volumio volumio[12258]: verbose: ControllerMpd::sendMpdCommand status Apr 11 16:42:22 volumio volumio[12258]: info: ------------------------------ 2ms Apr 11 16:42:22 volumio volumio[12258]: info: sendMpdCommand status took 2 milliseconds Apr 11 16:42:22 volumio volumio[12258]: info: ------------------------------ 2ms Apr 11 16:42:22 volumio volumio[12258]: verbose: ControllerMpd::parseState Apr 11 16:42:22 volumio volumio[12258]: info: ControllerMpd::pushState Apr 11 16:42:22 volumio volumio[12258]: info: CoreCommandRouter::servicePushState Apr 11 16:42:22 volumio volumio[12258]: info: CoreStateMachine::pushState Apr 11 16:42:22 volumio volumio[12258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 16:42:22 volumio volumio[12258]: info: CoreCommandRouter::volumioPushState Apr 11 16:42:22 volumio volumio[12258]: info: CorePlayQueue::getTrack 34 Apr 11 16:42:22 volumio volumio[12258]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 11 16:42:22 volumio volumio[12258]: verbose: CURRENT POSITION 34 Apr 11 16:42:22 volumio volumio[12258]: info: CoreStateMachine::syncState stateService stop Apr 11 16:42:22 volumio volumio[12258]: info: CoreStateMachine::syncState currentStatus play Apr 11 16:42:22 volumio volumio[12258]: info: CoreStateMachine::play index undefined Apr 11 16:42:22 volumio volumio[12258]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 16:42:22 volumio volumio[12258]: info: CoreStateMachine::pushState Apr 11 16:42:22 volumio volumio[12258]: info: CorePlayQueue::getTrack 35 Apr 11 16:42:22 volumio volumio[12258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 16:42:22 volumio volumio[12258]: info: CoreCommandRouter::volumioPushState Apr 11 16:42:22 volumio volumio[12258]: info: CorePlayQueue::getTrack 35 Apr 11 16:42:22 volumio volumio[12258]: info: CoreStateMachine::startPlaybackTimer Apr 11 16:42:22 volumio volumio[12258]: info: CorePlayQueue::getTrack 35 Apr 11 16:42:22 volumio volumio[12258]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Mann%20gegen%20Mann%22%2C%22artist%22%3A%22Rammstein%22%2C%22album%22%3A%22Rosenrot%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FahYAhVBJHd0myXd59PCcblFgaIpknPgJtRnYVmoIBIoGV2wnb4UdOZuvrpWydXNCAosWdRkgPI5guQ-o%3Dw701-h701-s-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22tplSmV03O-A%22%2C%22playlistId%22%3A%22OLAK5uy_kSiNrxH1LMHq_1N0Y0Wu0Y-CpNeOggPYs%22%2C%22params%22%3A%22OAHyAQIIAZIEI1FQLXpndjBwUnJWSm9JdDBmMzVSWEdocWF4YUtKYzdvcC03%22%2C%22index%22%3A35%2C%22playlistSetVideoId%22%3A%2230D9A6E73B0D7561%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kSiNrxH1LMHq_1N0Y0Wu0Y-CpNeOggPYs%22%2C%22params%22%3A%22OAHyAQIIAZIEI1FQLXpndjBwUnJWSm9JdDBmMzVSWEdocWF4YUtKYzdvcC03%22%2C%22videoId%22%3A%22B2m-QELL2IA%22%7D%7D%7D%7D Apr 11 16:42:22 volumio volumio[12258]: verbose: [ytmusic] [yt-dlp] Ready to fetch streaming URL for "https://music.youtube.com/watch?v=tplSmV03O-A" with yt-dlp version 2026.03.17 Apr 11 16:42:22 volumio volumio[12258]: verbose: [ytmusic] [yt-dlp] Going to execute: /data/plugins/music_service/ytmusic/.yt-dlp/installed/2026.03.17/yt-dlp --cookies /data/plugins/music_service/ytmusic/.yt-dlp/cookies/cookies_youtube_com.txt -q --js-runtime node --remote-components ejs:github -f bestaudio/best --get-url https://music.youtube.com/watch?v=tplSmV03O-A Apr 11 16:42:22 volumio volumio[12258]: info: ------------------------------ 20ms Apr 11 16:42:22 volumio volumio[12258]: info: CoreStateMachine::pushState Apr 11 16:42:22 volumio volumio[12258]: info: CorePlayQueue::getTrack 35 Apr 11 16:42:22 volumio volumio[12258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 16:42:22 volumio volumio[12258]: info: CoreCommandRouter::volumioPushState Apr 11 16:42:22 volumio volumio[12258]: error: updateQueue error: null Apr 11 16:42:22 volumio volumio[12258]: info: sendMpdCommand status took 41 milliseconds Apr 11 16:42:22 volumio volumio[12258]: info: sendMpdCommand status took 41 milliseconds Apr 11 16:42:22 volumio volumio[12258]: info: ------------------------------ 42ms Apr 11 16:42:22 volumio volumio[12258]: info: sendMpdCommand status took 41 milliseconds Apr 11 16:42:22 volumio volumio[12258]: verbose: ControllerMpd::parseState Apr 11 16:42:22 volumio volumio[12258]: verbose: ControllerMpd::parseState Apr 11 16:42:22 volumio volumio[12258]: verbose: ControllerMpd::parseState Apr 11 16:42:22 volumio volumio[12258]: info: ControllerMpd::pushState Apr 11 16:42:22 volumio volumio[12258]: info: CoreCommandRouter::servicePushState Apr 11 16:42:22 volumio volumio[12258]: info: CoreStateMachine::pushState Apr 11 16:42:22 volumio volumio[12258]: info: CorePlayQueue::getTrack 35 Apr 11 16:42:22 volumio volumio[12258]: info: CoreCommandRouter::volumioPushState Apr 11 16:42:22 volumio volumio[12258]: info: CorePlayQueue::getTrack 35 Apr 11 16:42:22 volumio volumio[12258]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Apr 11 16:42:22 volumio volumio[12258]: info: [ytmusic] (AutoplayManager) MPD 'stop' event received Apr 11 16:42:22 volumio volumio[12258]: info: [ytmusic] (AutoplayManager) Check if autoplay needed Apr 11 16:42:22 volumio volumio[12258]: info: CorePlayQueue::getTrack 35 Apr 11 16:42:22 volumio volumio[12258]: info: [ytmusic] (AutoplayManager) Autoplay not configured or there is no previous played "ytmusic" track Apr 11 16:42:22 volumio volumio[12258]: info: [ytmusic] (AutoplayManager) Removed mpdStateListener Apr 11 16:42:22 volumio volumio[12258]: info: ControllerMpd::pushState Apr 11 16:42:22 volumio volumio[12258]: info: CoreCommandRouter::servicePushState Apr 11 16:42:22 volumio volumio[12258]: info: CoreStateMachine::pushState Apr 11 16:42:22 volumio volumio[12258]: info: CorePlayQueue::getTrack 35 Apr 11 16:42:22 volumio volumio[12258]: info: CoreCommandRouter::volumioPushState Apr 11 16:42:22 volumio volumio[12258]: info: CorePlayQueue::getTrack 35 Apr 11 16:42:22 volumio volumio[12258]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Apr 11 16:42:22 volumio volumio[12258]: info: ------------------------------ 48ms Apr 11 16:42:22 volumio volumio[12258]: info: ------------------------------ 48ms Apr 11 16:42:23 volumio volumio[12258]: info: [ytmusic] Obtained PO token for video #tplSmV03O-A: MlvlXSsIL0_bX-UZh-x4kUaPNX0u5nHPF4Cu6XEqNnLDe49-t1RveBbXD6xupu5V-sb5byflKAL9E0MAD8frO3pB6DvtGxcKdwdiaGKKr4_vrzoPCVUvrMfiAKKH Apr 11 16:42:36 volumio volumio[12258]: verbose: [ytmusic] [yt-dlp] https://music.youtube.com/watch?v=tplSmV03O-A: https://rr1---sn-uxap5oxunx-hhme.googlevideo.com/videoplayback?expire=1775940147&ei=013aabXdNtXUv_IPxpCV4AU&ip=95.201.183.99&id=o-AO24PKC3ONiBx8VykPaUCYNiU0EiRLtyvrI9dvQTrHA2&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=517&met=1775918547%2C&mh=EG&mm=31%2C29&mn=sn-uxap5oxunx-hhme%2Csn-aj5go5-53&ms=au%2Crdu&mv=m&mvi=1&pl=13&rms=au%2Cau&gcr=se&initcwndbps=2996250&siu=1&bui=AUUZDGK6L4YoneTD0ORAf3V01F4BrFyHtGAjzPaPU1buaaG5-YA1FuCl-XEafzXvw6wuATQBbw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=DMft3fVOdhp3Vr7tyUF5CtcU&rqh=1&gir=yes&clen=3721365&dur=230.581&lmt=1774096991996255&mt=1775918232&fvip=5&keepalive=yes&lmw=1&fexp=51565116%2C51565682%2C51869321&c=TVHTML5&sefc=1&txp=5532534&n=lfzjr9gjXl4LuA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgOCa4KMLHk102wxHqSG1qNpb9mzSE5BfK-1gsdRa4gIkCIQCVt4wTNGWEn8IV1CbLROWZWv3gQtU14bc_SRd1HnTm8w%3D%3D&sig=AHEqNM4wRQIhAPeLFXDlpUGxKEOA0rzN03yWEkeI8EcdxpmkXr3_isO4AiAz_j9z1VJfh4GY4QwOKNNOOzR1z80JbOPVdZIUbJT_Zg%3D%3D Apr 11 16:42:36 volumio volumio[12258]: info: [ytmusic] (AutoplayManager) Enabled Apr 11 16:42:36 volumio volumio[12258]: /bin/sh: 1: mpv: not found Apr 11 16:42:36 volumio volumio[12258]: error: [ytmusic] [ytmusic] [mpv] Failed to get mpv version: Command failed: mpv --version Apr 11 16:42:36 volumio volumio[12258]: /bin/sh: 1: mpv: not found Apr 11 16:42:36 volumio volumio[12258]: info: [ytmusic] [ytmusic] [mpv] mpv process spawned Apr 11 16:42:36 volumio volumio[12258]: info: [ytmusic] [ytmusic] Going to start mpv for playback Apr 11 16:42:36 volumio volumio[12258]: info: [ytmusic] [ytmusic] [mpv] (PID: 20446) /bin/sh: 1: mpv: not found Apr 11 16:42:36 volumio volumio[12258]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 16:42:36 volumio volumio[12258]: TypeError: Cannot read properties of null (reading 'reset') Apr 11 16:42:36 volumio volumio[12258]: at /data/plugins/music_service/ytmusic/node_modules/volumio-ext-players/dist/mpv/MPVService.js:207:48 Apr 11 16:42:36 volumio volumio[12258]: at ChildProcess. (/data/plugins/music_service/ytmusic/node_modules/volumio-ext-players/dist/mpv/MPVService.js:221:19) Apr 11 16:42:36 volumio volumio[12258]: at ChildProcess.emit (node:events:514:28) Apr 11 16:42:36 volumio volumio[12258]: at maybeClose (node:internal/child_process:1105:16) Apr 11 16:42:36 volumio volumio[12258]: at Socket. (node:internal/child_process:457:11) Apr 11 16:42:36 volumio volumio[12258]: at Socket.emit (node:events:514:28) Apr 11 16:42:36 volumio volumio[12258]: at Pipe. (node:net:337:12) Apr 11 16:42:36 volumio volumio[12258]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 16:42:36 volumio sudo[20461]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-11 16:41' Apr 11 16:42:36 volumio sudo[20461]: 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"