Dec 30 12:49:09 volumio volumio[1383]: info: [yt-cast-receiver] Connecting sender through DIAL... Dec 30 12:49:10 volumio volumio[1383]: info: [yt-cast-receiver] (YouTube) Sender connected: REALME RMX3301 (user: Andrea) Dec 30 12:49:10 volumio volumio[1383]: info: [ytcr] ***** Sender connected ***** Dec 30 12:49:10 volumio volumio[1383]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 12:49:14 volumio volumio[1383]: info: [yt-cast-receiver] Player.play(): zlMK6T7OxIg @ 5s Dec 30 12:49:14 volumio volumio[1383]: info: CoreCommandRouter::volumioGetState Dec 30 12:49:14 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:49:14 volumio volumio[1383]: info: CoreCommandRouter::volumioGetState Dec 30 12:49:14 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:49:14 volumio volumio[1383]: info: CoreCommandRouter::volumioStop Dec 30 12:49:14 volumio volumio[1383]: info: CoreStateMachine::stop Dec 30 12:49:14 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:49:15 volumio volumio[1383]: info: [ytcr] Innertube support service: Start service with Deno: deno 2.6.3 (stable, release, x86_64-unknown-linux-gnu) Dec 30 12:49:15 volumio volumio[1383]: info: CoreCommandRouter::volumioGetState Dec 30 12:49:15 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:49:15 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:49:15 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 30 12:49:15 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 30 12:49:15 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:49:15 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 30 12:49:15 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 30 12:49:15 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 30 12:49:15 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 30 12:49:16 volumio volumio[1383]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Dec 30 12:49:16 volumio volumio[1383]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Dec 30 12:49:16 volumio volumio[1383]: info: CoreCommandRouter::volumioGetState Dec 30 12:49:16 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 30 12:49:16 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 30 12:49:16 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 30 12:49:16 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:49:16 volumio volumio[1383]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received ytcr Dec 30 12:49:16 volumio volumio[1383]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Dec 30 12:49:16 volumio volumio[1383]: info: [yt-cast-receiver] Player.pause() Dec 30 12:49:18 volumio volumio[1383]: info: [ytcr] Innertube support service: result: {"status":"started","server":{"address":"127.0.0.1","port":40313}} Dec 30 12:49:18 volumio volumio[1383]: info: [ytcr] Innertube support service running at http://127.0.0.1:40313 Dec 30 12:49:20 volumio volumio[1383]: info: [ytcr] Obtained session PO token using visitorData (expires in 43199 seconds) Dec 30 12:49:21 volumio volumio[1383]: info: [ytcr] Going to refresh session PO token in 43099 seconds Dec 30 12:49:22 volumio volumio[1383]: info: [ytcr] (zlMK6T7OxIg) fetching player data using TV client... Dec 30 12:49:22 volumio volumio[1383]: info: [ytcr] (Dentro L'aereo Più Grande Del Mondo) validating stream URL "https://rr2---sn-45nufxc-hm2l.googlevideo.com/videoplayback?expire=1767116962&ei=QrxTaeuhJbKPi9oPk_7gyAU&ip=93.38.29.55&id=o-AM-7rDQ7Du5dJwNzjEdQbZpBis9KLklkcezRdhvBAXKR&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=43&met=1767095362%2C&mh=Vq&mm=31%2C26&mn=sn-45nufxc-hm2l%2Csn-hgn7yn7e&ms=au%2Conr&mv=m&mvi=2&pcm2cms=yes&pl=22&rms=au%2Cau&initcwndbps=2836250&siu=1&bui=AYUSA3ClvitZFziSyYAxaGEQFhwKZrPNeAN2iQlSsFotG7Z9cNOjpZOV8UWCAIWCxpB8HxGttw&spc=wH4QqxpFMpoM&vprv=1&svpuc=1&xtags=acont%3Doriginal%3Adrc%3D1%3Alang%3Dit&mime=audio%2Fwebm&ns=_R7qgQzYFoZaYKx85tCK3AUR&rqh=1&gir=yes&clen=9606974&dur=547.641&lmt=1759637637705568&mt=1767095129&fvip=2&keepalive=yes&lmw=1&fexp=51557447%2C51565115%2C51565682%2C51580970&c=TVHTML5&sefc=1&txp=5532534&n=pwDtbJspssPYrA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cxtags%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgeD6WMKhUtCSD_tHlBGP2OelKno1CxqDX5aWo0sVbk_ICID89lQmGu924v8zbAuNyVj4PxqQobfOpAAmuk2wbuoE6&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAL-BnYqY5JBdkSEwSoeyWNgZyCpKEziqh9ihINQTuVSYAiEA6X3w9lnDc_uaOlpmx_pGu_ChKWp-VA0WQjbZlVhLiWg%3D&pot=MlUJIj4vsUagnwkcgZ2kxnq3gAPhiE1CoGwT6dH9_-wrRYj6z3eK2jY0Z56cIn0LcQeyY7MSfFrGDW4xrreE2ghft7rbuEE_aapCct6bgl_EGo5opqyk&cver=7.20250219.14.00"... Dec 30 12:49:22 volumio volumio[1383]: info: [ytcr] (Dentro L'aereo Più Grande Del Mondo) stream validated in 0.032s. Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:22 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:23 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:23 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:23 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:23 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:23 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:23 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:23 volumio volumio[1383]: info: CoreCommandRouter::volumioGetState Dec 30 12:49:23 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 30 12:49:23 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 30 12:49:23 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:49:23 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 30 12:49:23 volumio volumio[1383]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Dec 30 12:49:23 volumio volumio[1383]: info: CoreCommandRouter::volumioGetState Dec 30 12:49:23 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 30 12:49:23 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 30 12:49:23 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 30 12:49:23 volumio volumio[1383]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Dec 30 12:49:25 volumio go-librespot[1946]: time="2025-12-30T12:49:25+01:00" level=trace msg="received accesspoint ping" Dec 30 12:49:25 volumio go-librespot[1946]: time="2025-12-30T12:49:25+01:00" level=trace msg="received accesspoint pong ack" Dec 30 12:49:25 volumio go-librespot[1946]: time="2025-12-30T12:49:25+01:00" level=trace msg="sent dealer ping" Dec 30 12:49:26 volumio go-librespot[1946]: time="2025-12-30T12:49:26+01:00" level=trace msg="received dealer pong" Dec 30 12:49:44 volumio volumio[1383]: info: [yt-cast-receiver] (YouTube) Sender disconnected: REALME RMX3301 Dec 30 12:49:44 volumio volumio[1383]: info: [yt-cast-receiver] Player.reset() Dec 30 12:49:44 volumio volumio[1383]: info: [yt-cast-receiver] Player.stop() Dec 30 12:49:44 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:44 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:44 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:49:44 volumio volumio[1383]: info: CoreCommandRouter::volumioGetState Dec 30 12:49:44 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 30 12:49:44 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 30 12:49:44 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:49:44 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 30 12:49:44 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 30 12:49:44 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 30 12:49:44 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 30 12:49:44 volumio volumio[1383]: info: [ytcr] ***** Sender disconnected ***** Dec 30 12:49:44 volumio volumio[1383]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 12:49:44 volumio volumio[1383]: info: CoreCommandRouter::volumioGetState Dec 30 12:49:44 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 30 12:49:44 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 30 12:49:44 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 30 12:49:44 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 30 12:49:44 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 30 12:49:44 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 30 12:49:44 volumio volumio[1383]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Dec 30 12:49:44 volumio volumio[1383]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Dec 30 12:49:44 volumio volumio[1383]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Dec 30 12:49:44 volumio volumio[1383]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Dec 30 12:49:55 volumio go-librespot[1946]: time="2025-12-30T12:49:55+01:00" level=trace msg="sent dealer ping" Dec 30 12:49:56 volumio go-librespot[1946]: time="2025-12-30T12:49:56+01:00" level=trace msg="received dealer pong" Dec 30 12:50:01 volumio volumio[1383]: info: CoreCommandRouter::volumioGetQueue Dec 30 12:50:01 volumio volumio[1383]: info: CoreStateMachine::getQueue Dec 30 12:50:01 volumio volumio[1383]: info: CorePlayQueue::getQueue Dec 30 12:50:03 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Dec 30 12:50:03 volumio volumio[1383]: info: [youtube2-browse] browseUri: youtube2 Dec 30 12:50:03 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:03 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:06 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Dec 30 12:50:06 volumio volumio[1383]: info: [youtube2-browse] browseUri: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEwhat_to_watch%22%7D%7D Dec 30 12:50:06 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:10 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Dec 30 12:50:10 volumio volumio[1383]: info: [youtube2-browse] browseUri: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC-9-kyTW8ZkZNDHQJ6FgpwQ%22%7D%7D Dec 30 12:50:11 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:11 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:11 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:11 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:11 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:11 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:11 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:11 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:11 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:22 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Dec 30 12:50:22 volumio volumio[1383]: info: [youtube2-browse] browseUri: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEstorefront%22%2C%22params%22%3A%22ogUCKAU%253D%22%7D%7D Dec 30 12:50:22 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:22 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:22 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:25 volumio go-librespot[1946]: time="2025-12-30T12:50:25+01:00" level=trace msg="sent dealer ping" Dec 30 12:50:25 volumio go-librespot[1946]: time="2025-12-30T12:50:25+01:00" level=trace msg="received dealer pong" Dec 30 12:50:29 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:29 volumio volumio[1383]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::ClearQueue Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::stop Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::serviceStop Dec 30 12:50:29 volumio volumio[1383]: info: CoreCommandRouter::serviceStop Dec 30 12:50:29 volumio volumio[1383]: info: CorePlayQueue::clearPlayQueue Dec 30 12:50:29 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 30 12:50:29 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::addQueueItems Dec 30 12:50:29 volumio volumio[1383]: info: CorePlayQueue::addQueueItems Dec 30 12:50:29 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:29 volumio volumio[1383]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Lilo%20%26%20Stitch%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2F2ymtiVB-Ucw%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222ymtiVB-Ucw%22%7D%7D%7D Dec 30 12:50:29 volumio volumio[1383]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Lilo%20%26%20Stitch%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2F2ymtiVB-Ucw%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222ymtiVB-Ucw%22%7D%7D%7D in service youtube2 Dec 30 12:50:29 volumio volumio[1383]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Lilo%20%26%20Stitch%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2F2ymtiVB-Ucw%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222ymtiVB-Ucw%22%7D%7D%7D Dec 30 12:50:29 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:50:29 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::updateTrackBlock Dec 30 12:50:29 volumio volumio[1383]: info: CorePlayQueue::getTrackBlock Dec 30 12:50:29 volumio volumio[1383]: info: CoreCommandRouter::volumioPlay Dec 30 12:50:29 volumio volumio[1383]: verbose: UNSET VOLATILE: Service: ytcr Dec 30 12:50:29 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 30 12:50:29 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 30 12:50:29 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 30 12:50:29 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::play index 0 Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::stop Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::updateTrackBlock Dec 30 12:50:29 volumio volumio[1383]: info: CorePlayQueue::getTrackBlock Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::stPlaybackTimer Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 30 12:50:29 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:29 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:50:29 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::serviceStop Dec 30 12:50:29 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:29 volumio volumio[1383]: info: CoreCommandRouter::serviceStop Dec 30 12:50:29 volumio volumio[1383]: info: [youtube2] (AutoplayManager) Disabled Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 30 12:50:29 volumio volumio[1383]: info: ControllerMpd::stop Dec 30 12:50:29 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 12:50:29 volumio volumio[1383]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Dec 30 12:50:29 volumio volumio[1383]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Dec 30 12:50:29 volumio volumio[1383]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Dec 30 12:50:29 volumio volumio[1383]: info: sendMpdCommand stop took 19 milliseconds Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::play index undefined Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:29 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:29 volumio volumio[1383]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:50:29 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:29 volumio volumio[1383]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222ymtiVB-Ucw%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Lilo%20%26%20Stitch%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2F2ymtiVB-Ucw%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222ymtiVB-Ucw%22%7D%7D%7D Dec 30 12:50:30 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #2ymtiVB-Ucw: MlXH5KP4lR7bXcfaKfmOtA4LYsIcJBGfdWaC3rB0EKX-Ti6Hb6ZAxhVybAIOf8DMP2IyNhkU15jceJL6uHHkOmYEkp1Ngu_xCYEq1HpVhH3Zz-_tvwFt Dec 30 12:50:30 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #2ymtiVB-Ucw: MlWFGwLxCpg6qoUliPARMu_8ID29LY4ZlJHAIRF9jyMfuWx4zq_fQPSFLv2vdl9K3pVwybgdSB49j9AFGXh7vIfz0GLsi3B36HbQJ7ahvpcgLXq5Nw18 Dec 30 12:50:30 volumio volumio[1383]: error: [youtube2] Error in VideoModel.getInfo(2ymtiVB-Ucw): La visione di questo video è a pagamento Error: La visione di questo video è a pagamento Dec 30 12:50:30 volumio volumio[1383]: at VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:78:27) Dec 30 12:50:30 volumio volumio[1383]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 30 12:50:30 volumio volumio[1383]: at async VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:52:24) Dec 30 12:50:30 volumio volumio[1383]: at async PlayController.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:188:19) Dec 30 12:50:30 volumio volumio[1383]: at async PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:116:49) Dec 30 12:50:34 volumio volumio[1383]: info: CoreCommandRouter::volumioPlay Dec 30 12:50:34 volumio volumio[1383]: info: CoreStateMachine::play index undefined Dec 30 12:50:34 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:34 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:34 volumio volumio[1383]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:50:34 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:34 volumio volumio[1383]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222ymtiVB-Ucw%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Lilo%20%26%20Stitch%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2F2ymtiVB-Ucw%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222ymtiVB-Ucw%22%7D%7D%7D Dec 30 12:50:34 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #2ymtiVB-Ucw: MlW08pqx-9qrF7TMELDgcH5BEdQlbX9bBSzxyIk9fmGOBF2RVu8uAmU4HxQ3Nq4ITyhBICBduVysMuHsgTiK_hZO4Yt0y4E1ecuamAfv4f8iYDM9wqW8 Dec 30 12:50:34 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #2ymtiVB-Ucw: MlXMEsm12vmVKcwsQ7TBU0B_aTR2aV54OxKJKNo5X0KwOiVxBesPIVsGZ_RkMo8rcRY5wHNZmH-SDJkM0jyr3ShwmWsnz6AWR_Vfsvipm-pHXAGGN3vN Dec 30 12:50:34 volumio volumio[1383]: error: [youtube2] Error in VideoModel.getInfo(2ymtiVB-Ucw): La visione di questo video è a pagamento Error: La visione di questo video è a pagamento Dec 30 12:50:34 volumio volumio[1383]: at VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:78:27) Dec 30 12:50:34 volumio volumio[1383]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 30 12:50:34 volumio volumio[1383]: at async VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:52:24) Dec 30 12:50:34 volumio volumio[1383]: at async PlayController.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:188:19) Dec 30 12:50:34 volumio volumio[1383]: at async PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:116:49) Dec 30 12:50:42 volumio volumio[1383]: info: CoreCommandRouter::volumioPlay Dec 30 12:50:42 volumio volumio[1383]: info: CoreStateMachine::play index undefined Dec 30 12:50:42 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:42 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:42 volumio volumio[1383]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:50:42 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:42 volumio volumio[1383]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222ymtiVB-Ucw%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Lilo%20%26%20Stitch%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2F2ymtiVB-Ucw%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222ymtiVB-Ucw%22%7D%7D%7D Dec 30 12:50:42 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #2ymtiVB-Ucw: MlXQB99Bf_wNUdA5VUBkVtgHdSFgnft9o2qVPczN-kcoQjlkEx-qJMN-e-Fyxiou6W4l1WWtPXoKdIUZxMgO2LAIhX4xOwUT340e8RdMMGVc86OULUNT Dec 30 12:50:42 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #2ymtiVB-Ucw: MlVDrY2a06bnqEOTB5vIDDL-5osyRlcnSZMGl54WVh3Cu6rOQcQGfimH6EsgHYZ0A5e2fzd2kSDgjRazlhOiglrxFtRj4KlJNXS5R5_8-jl3pQbVFoxw Dec 30 12:50:42 volumio volumio[1383]: error: [youtube2] Error in VideoModel.getInfo(2ymtiVB-Ucw): La visione di questo video è a pagamento Error: La visione di questo video è a pagamento Dec 30 12:50:42 volumio volumio[1383]: at VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:78:27) Dec 30 12:50:42 volumio volumio[1383]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 30 12:50:42 volumio volumio[1383]: at async VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:52:24) Dec 30 12:50:42 volumio volumio[1383]: at async PlayController.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:188:19) Dec 30 12:50:42 volumio volumio[1383]: at async PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:116:49) Dec 30 12:50:43 volumio volumio[1383]: info: CoreCommandRouter::volumioPlay Dec 30 12:50:43 volumio volumio[1383]: info: CoreStateMachine::play index undefined Dec 30 12:50:43 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:43 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:43 volumio volumio[1383]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:50:43 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:43 volumio volumio[1383]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222ymtiVB-Ucw%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Lilo%20%26%20Stitch%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2F2ymtiVB-Ucw%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222ymtiVB-Ucw%22%7D%7D%7D Dec 30 12:50:43 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #2ymtiVB-Ucw: MlUqN-6yWRioQyoJZLNCsn0VjxFRbt2ZBnhvDf0-3KONUMNUIuyMwGZsgdFDNQzKTHzf5VReG56vZn8p9TsoPBUaf04AyCP3ep_lqgHbaY_ar3xFdfRx Dec 30 12:50:43 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #2ymtiVB-Ucw: MlVE0uRPc4x23kTsbk5oJqOI4fRbk_cN2OUB6PfD9jdTza2xKBGmVLjx7zRJyCZekuGxAF6jMQpx-xHM_8YCqMuHEasKNQljpALZxZd-tzjB4kE-jRm_ Dec 30 12:50:43 volumio volumio[1383]: error: [youtube2] Error in VideoModel.getInfo(2ymtiVB-Ucw): La visione di questo video è a pagamento Error: La visione di questo video è a pagamento Dec 30 12:50:43 volumio volumio[1383]: at VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:78:27) Dec 30 12:50:43 volumio volumio[1383]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 30 12:50:43 volumio volumio[1383]: at async VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:52:24) Dec 30 12:50:43 volumio volumio[1383]: at async PlayController.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:188:19) Dec 30 12:50:43 volumio volumio[1383]: at async PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:116:49) Dec 30 12:50:45 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:45 volumio volumio[1383]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 12:50:45 volumio volumio[1383]: info: CoreStateMachine::ClearQueue Dec 30 12:50:45 volumio volumio[1383]: info: CoreStateMachine::stop Dec 30 12:50:45 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:45 volumio volumio[1383]: info: CorePlayQueue::clearPlayQueue Dec 30 12:50:45 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 30 12:50:45 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:50:45 volumio volumio[1383]: info: CoreStateMachine::addQueueItems Dec 30 12:50:45 volumio volumio[1383]: info: CorePlayQueue::addQueueItems Dec 30 12:50:45 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:45 volumio volumio[1383]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Io%20sono%20la%20fine%20del%20mondo%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FlJWV2K_mvag%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D%7D Dec 30 12:50:45 volumio volumio[1383]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Io%20sono%20la%20fine%20del%20mondo%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FlJWV2K_mvag%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D%7D in service youtube2 Dec 30 12:50:45 volumio volumio[1383]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Io%20sono%20la%20fine%20del%20mondo%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FlJWV2K_mvag%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D%7D Dec 30 12:50:45 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:50:45 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 30 12:50:45 volumio volumio[1383]: info: CoreStateMachine::updateTrackBlock Dec 30 12:50:45 volumio volumio[1383]: info: CorePlayQueue::getTrackBlock Dec 30 12:50:45 volumio volumio[1383]: info: CoreCommandRouter::volumioPlay Dec 30 12:50:45 volumio volumio[1383]: info: CoreStateMachine::play index 0 Dec 30 12:50:45 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:45 volumio volumio[1383]: info: CoreStateMachine::stop Dec 30 12:50:45 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:45 volumio volumio[1383]: info: CoreStateMachine::play index undefined Dec 30 12:50:45 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:45 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:45 volumio volumio[1383]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:50:45 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:45 volumio volumio[1383]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Io%20sono%20la%20fine%20del%20mondo%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FlJWV2K_mvag%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D%7D Dec 30 12:50:45 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #lJWV2K_mvag: MlV7xxoNGVPUSnv5kAwC-QEc3uGl0Z3SenE-_QmBnOjxWZKk1lPMixpl0CG3ikyBMHWOFaDhW9XTby7ZAYRod2kTLr70d2O8BpbFrIlT0FaBxp63PNSb Dec 30 12:50:46 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #lJWV2K_mvag: MlWAo6izq4zmdYCdIrKwJjMjJYUXby8NSE7Fmbs_LjfDZmnAZO1-VChaK0UFNP5eAkp1cRJf6QrhUNW9szraqFss1dpGydFjNKk9cikpe3tL5cJZj7nQ Dec 30 12:50:46 volumio volumio[1383]: error: [youtube2] Error in VideoModel.getInfo(lJWV2K_mvag): La visione di questo video è a pagamento Error: La visione di questo video è a pagamento Dec 30 12:50:46 volumio volumio[1383]: at VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:78:27) Dec 30 12:50:46 volumio volumio[1383]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 30 12:50:46 volumio volumio[1383]: at async VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:52:24) Dec 30 12:50:46 volumio volumio[1383]: at async PlayController.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:188:19) Dec 30 12:50:46 volumio volumio[1383]: at async PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:116:49) Dec 30 12:50:47 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:47 volumio volumio[1383]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 12:50:47 volumio volumio[1383]: info: CoreStateMachine::ClearQueue Dec 30 12:50:47 volumio volumio[1383]: info: CoreStateMachine::stop Dec 30 12:50:47 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:47 volumio volumio[1383]: info: CorePlayQueue::clearPlayQueue Dec 30 12:50:47 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 30 12:50:47 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:50:47 volumio volumio[1383]: info: CoreStateMachine::addQueueItems Dec 30 12:50:47 volumio volumio[1383]: info: CorePlayQueue::addQueueItems Dec 30 12:50:47 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:47 volumio volumio[1383]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Io%20sono%20la%20fine%20del%20mondo%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FlJWV2K_mvag%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D%7D Dec 30 12:50:47 volumio volumio[1383]: info: Using cached record of: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Io%20sono%20la%20fine%20del%20mondo%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FlJWV2K_mvag%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D%7D Dec 30 12:50:47 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:50:47 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 30 12:50:47 volumio volumio[1383]: info: CoreStateMachine::updateTrackBlock Dec 30 12:50:47 volumio volumio[1383]: info: CorePlayQueue::getTrackBlock Dec 30 12:50:47 volumio volumio[1383]: info: CoreCommandRouter::volumioPlay Dec 30 12:50:47 volumio volumio[1383]: info: CoreStateMachine::play index 0 Dec 30 12:50:47 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:47 volumio volumio[1383]: info: CoreStateMachine::stop Dec 30 12:50:47 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:47 volumio volumio[1383]: info: CoreStateMachine::play index undefined Dec 30 12:50:47 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:47 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:47 volumio volumio[1383]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:50:47 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:47 volumio volumio[1383]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Io%20sono%20la%20fine%20del%20mondo%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FlJWV2K_mvag%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D%7D Dec 30 12:50:47 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #lJWV2K_mvag: MlVKjFkI162xmkqy0wnMB2TM76rm1FMsH6EPtkqEUhaUiaPvlVYCdX-14Wr0j4J_VaW_XuPklSu2vx-SQoGmiQzDH_W3cq1CY0azYJ8s0iSzkN4ROazG Dec 30 12:50:48 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #lJWV2K_mvag: MlU8O3HlIyZIlzwF--Q4jJ3BmR3OOaen5qx5AWJppp1thNVYvbv2_oa4l93cYnb0rKjJ6csJYaBPsmklamxSAvXOaUKfn1nJmkuJnJCpFMTJdZbR3nE8 Dec 30 12:50:48 volumio volumio[1383]: error: [youtube2] Error in VideoModel.getInfo(lJWV2K_mvag): La visione di questo video è a pagamento Error: La visione di questo video è a pagamento Dec 30 12:50:48 volumio volumio[1383]: at VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:78:27) Dec 30 12:50:48 volumio volumio[1383]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 30 12:50:48 volumio volumio[1383]: at async VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:52:24) Dec 30 12:50:48 volumio volumio[1383]: at async PlayController.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:188:19) Dec 30 12:50:48 volumio volumio[1383]: at async PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:116:49) Dec 30 12:50:48 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:48 volumio volumio[1383]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 12:50:48 volumio volumio[1383]: info: CoreStateMachine::ClearQueue Dec 30 12:50:48 volumio volumio[1383]: info: CoreStateMachine::stop Dec 30 12:50:48 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:48 volumio volumio[1383]: info: CorePlayQueue::clearPlayQueue Dec 30 12:50:48 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 30 12:50:48 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:50:48 volumio volumio[1383]: info: CoreStateMachine::addQueueItems Dec 30 12:50:48 volumio volumio[1383]: info: CorePlayQueue::addQueueItems Dec 30 12:50:48 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:48 volumio volumio[1383]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Io%20sono%20la%20fine%20del%20mondo%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FlJWV2K_mvag%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D%7D Dec 30 12:50:48 volumio volumio[1383]: info: Using cached record of: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Io%20sono%20la%20fine%20del%20mondo%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FlJWV2K_mvag%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D%7D Dec 30 12:50:48 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:50:48 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 30 12:50:48 volumio volumio[1383]: info: CoreStateMachine::updateTrackBlock Dec 30 12:50:48 volumio volumio[1383]: info: CorePlayQueue::getTrackBlock Dec 30 12:50:48 volumio volumio[1383]: info: CoreCommandRouter::volumioPlay Dec 30 12:50:48 volumio volumio[1383]: info: CoreStateMachine::play index 0 Dec 30 12:50:48 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:48 volumio volumio[1383]: info: CoreStateMachine::stop Dec 30 12:50:48 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:48 volumio volumio[1383]: info: CoreStateMachine::play index undefined Dec 30 12:50:48 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:48 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:48 volumio volumio[1383]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:50:48 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:48 volumio volumio[1383]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Io%20sono%20la%20fine%20del%20mondo%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FlJWV2K_mvag%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D%7D Dec 30 12:50:48 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #lJWV2K_mvag: MlVaNeEw6oo8vFoLazHxIOnq_xNe7G4LkocfD_K8bzEZr7NWLW4_UvKT8dNMt79Y2IOv51vcqAw7mQ8r-rmbroHlD0wPSpBl7mAtqYRep8pj1jknpwX5 Dec 30 12:50:49 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #lJWV2K_mvag: MlXE1w4pbCyVFcTphCh3hkBDYfGx9eitOy6B7R2l6ZewBi20wne59Fs6bzGjrjn-cSoxBbTFLqqSMJHJFaAdCChMka7gUxbDR8ktzL4nbaiyLlrgJiQf Dec 30 12:50:49 volumio volumio[1383]: error: [youtube2] Error in VideoModel.getInfo(lJWV2K_mvag): La visione di questo video è a pagamento Error: La visione di questo video è a pagamento Dec 30 12:50:49 volumio volumio[1383]: at VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:78:27) Dec 30 12:50:49 volumio volumio[1383]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 30 12:50:49 volumio volumio[1383]: at async VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:52:24) Dec 30 12:50:49 volumio volumio[1383]: at async PlayController.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:188:19) Dec 30 12:50:49 volumio volumio[1383]: at async PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:116:49) Dec 30 12:50:53 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:53 volumio volumio[1383]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 12:50:53 volumio volumio[1383]: info: CoreStateMachine::ClearQueue Dec 30 12:50:53 volumio volumio[1383]: info: CoreStateMachine::stop Dec 30 12:50:53 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:53 volumio volumio[1383]: info: CorePlayQueue::clearPlayQueue Dec 30 12:50:53 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 30 12:50:53 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:50:53 volumio volumio[1383]: info: CoreStateMachine::addQueueItems Dec 30 12:50:53 volumio volumio[1383]: info: CorePlayQueue::addQueueItems Dec 30 12:50:53 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:53 volumio volumio[1383]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Io%20sono%20la%20fine%20del%20mondo%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FlJWV2K_mvag%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D%7D Dec 30 12:50:53 volumio volumio[1383]: info: Using cached record of: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Io%20sono%20la%20fine%20del%20mondo%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FlJWV2K_mvag%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D%7D Dec 30 12:50:53 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:50:53 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 30 12:50:53 volumio volumio[1383]: info: CoreStateMachine::updateTrackBlock Dec 30 12:50:53 volumio volumio[1383]: info: CorePlayQueue::getTrackBlock Dec 30 12:50:53 volumio volumio[1383]: info: CoreCommandRouter::volumioPlay Dec 30 12:50:53 volumio volumio[1383]: info: CoreStateMachine::play index 0 Dec 30 12:50:53 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:53 volumio volumio[1383]: info: CoreStateMachine::stop Dec 30 12:50:53 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:53 volumio volumio[1383]: info: CoreStateMachine::play index undefined Dec 30 12:50:53 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:53 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:53 volumio volumio[1383]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:50:53 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:53 volumio volumio[1383]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Io%20sono%20la%20fine%20del%20mondo%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FlJWV2K_mvag%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D%7D Dec 30 12:50:53 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #lJWV2K_mvag: MlU68UM9rTHxZDrPyTy2myQyn9f84SmwX19_y1CxKIrUd9OSj2N46T9LkRfuuvjjFVvPI_nR77f2QW_vWLTcFUw9b4itR9feI7j1Fwt_iOAfw2ZpbLm3 Dec 30 12:50:54 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #lJWV2K_mvag: MlX5ffkrLzacgflDcyo0nEnXXFtG96u3Mrq8R-qnqo25khAeNXX67lKuUptUrHrkeL4Mr0PHbbCbpKxj4qJeEiHYrAQXUVXZTl1Yk5qv9iSDbXWIb5Jo Dec 30 12:50:54 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:54 volumio volumio[1383]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::ClearQueue Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::stop Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:54 volumio volumio[1383]: info: CorePlayQueue::clearPlayQueue Dec 30 12:50:54 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 30 12:50:54 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::addQueueItems Dec 30 12:50:54 volumio volumio[1383]: info: CorePlayQueue::addQueueItems Dec 30 12:50:54 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:54 volumio volumio[1383]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Io%20sono%20la%20fine%20del%20mondo%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FlJWV2K_mvag%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D%7D Dec 30 12:50:54 volumio volumio[1383]: info: Using cached record of: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Io%20sono%20la%20fine%20del%20mondo%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FlJWV2K_mvag%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D%7D Dec 30 12:50:54 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:50:54 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::updateTrackBlock Dec 30 12:50:54 volumio volumio[1383]: info: CorePlayQueue::getTrackBlock Dec 30 12:50:54 volumio volumio[1383]: info: CoreCommandRouter::volumioPlay Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::play index 0 Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::stop Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::play index undefined Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:54 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:50:54 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:54 volumio volumio[1383]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Io%20sono%20la%20fine%20del%20mondo%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FlJWV2K_mvag%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lJWV2K_mvag%22%7D%7D%7D Dec 30 12:50:54 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #lJWV2K_mvag: MlV9xaLsIPUdHX37KO07X8hL2OMdMKR0syY4_7FgpU44DpSmbrL1LdMy1iMPa3Un-SKIFxgAYnMaOCjbuWVR0aBEKLxMlloaz8GSau_heSGAuB08ukma Dec 30 12:50:54 volumio volumio[1383]: error: [youtube2] Error in VideoModel.getInfo(lJWV2K_mvag): La visione di questo video è a pagamento Error: La visione di questo video è a pagamento Dec 30 12:50:54 volumio volumio[1383]: at VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:78:27) Dec 30 12:50:54 volumio volumio[1383]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 30 12:50:54 volumio volumio[1383]: at async VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:52:24) Dec 30 12:50:54 volumio volumio[1383]: at async PlayController.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:188:19) Dec 30 12:50:54 volumio volumio[1383]: at async PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:116:49) Dec 30 12:50:54 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #lJWV2K_mvag: MlVfNf4FQVcIAl8LdARa_d1U-hNB2cXWpjkaD-2JxOwtEbZWMluUj8Yt9NNTghSF7D2q50TpA9EPJwor5Ywwc7VbCkwQfzu42t6XCvKYLdVlUw5w8lpe Dec 30 12:50:54 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:54 volumio volumio[1383]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::ClearQueue Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::stop Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:54 volumio volumio[1383]: info: CorePlayQueue::clearPlayQueue Dec 30 12:50:54 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 30 12:50:54 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::addQueueItems Dec 30 12:50:54 volumio volumio[1383]: info: CorePlayQueue::addQueueItems Dec 30 12:50:54 volumio volumio[1383]: info: Preload queue cleared Dec 30 12:50:54 volumio volumio[1383]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22A%20Minecraft%20Movie%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FadsoQOm3qP4%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22adsoQOm3qP4%22%7D%7D%7D Dec 30 12:50:54 volumio volumio[1383]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22A%20Minecraft%20Movie%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FadsoQOm3qP4%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22adsoQOm3qP4%22%7D%7D%7D in service youtube2 Dec 30 12:50:54 volumio volumio[1383]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22A%20Minecraft%20Movie%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FadsoQOm3qP4%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22adsoQOm3qP4%22%7D%7D%7D Dec 30 12:50:54 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:50:54 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::updateTrackBlock Dec 30 12:50:54 volumio volumio[1383]: info: CorePlayQueue::getTrackBlock Dec 30 12:50:54 volumio volumio[1383]: info: CoreCommandRouter::volumioPlay Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::play index 0 Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::stop Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::play index undefined Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:50:54 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:54 volumio volumio[1383]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:50:54 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 30 12:50:54 volumio volumio[1383]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22adsoQOm3qP4%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22A%20Minecraft%20Movie%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FadsoQOm3qP4%2Fmovieposter_it.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22adsoQOm3qP4%22%7D%7D%7D Dec 30 12:50:54 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #adsoQOm3qP4: MlUTd5PK-AWIeRNJGcvjr10vtlEsFnyEJkJWTYBGfb6tavoUX5Qt3UZWuJE-Ta3XbEbmpSkmuoOPXEZpiEOJITUgRg59sILqWqVhSLVz5bpdWG3dZhki Dec 30 12:50:54 volumio volumio[1383]: error: [youtube2] Error in VideoModel.getInfo(lJWV2K_mvag): La visione di questo video è a pagamento Error: La visione di questo video è a pagamento Dec 30 12:50:54 volumio volumio[1383]: at VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:78:27) Dec 30 12:50:54 volumio volumio[1383]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 30 12:50:54 volumio volumio[1383]: at async VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:52:24) Dec 30 12:50:54 volumio volumio[1383]: at async PlayController.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:188:19) Dec 30 12:50:54 volumio volumio[1383]: at async PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:116:49) Dec 30 12:50:54 volumio volumio[1383]: info: [ytmusic] Obtained PO token for video #adsoQOm3qP4: MlUE1vv0n3I9BgTocfWE2OhQofBEKBvzkz1B7Oh4GskYFe21N6pKqvMprzBWc8qg2TnxBEEY3fQ6I1HI4H3uVoBfUa8VjuWd79p72Pvt5bGV8xgZCT_o Dec 30 12:50:55 volumio volumio[1383]: info: [youtube2] VideoModel.getInfo(adsoQOm3qP4): validating stream URL "https://rr4---sn-45nufxc-hm2l.googlevideo.com/videoplayback?expire=1767117054&ei=nrxTaYuNOL_kxN8Plu3g2Ag&ip=93.38.29.55&id=o-AJCJZEw7W57PymFuNcAc_ZXGWVmxXKDlTAvN1jnmlxQs&itag=18&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=0&met=1767095454%2C&mh=S6&mm=31%2C26&mn=sn-45nufxc-hm2l%2Csn-hgn7yn7z&ms=au%2Conr&mv=m&mvi=4&pl=22&rms=au%2Cau&initcwndbps=2478750&bui=AYUSA3CFJN8gsOVI7xgqfDJcDhtO8mC8E_C1UrlRyweNRye45Uy8AIqVgoAe97Dm85J_uF2Qv6VvyVpl&spc=wH4Qq7Fgcu3JLwKDvKgWRoVM0LFBZZwfgOftFVmd9CfaHHpTOpda-0syx7_bjQ&vprv=1&svpuc=1&xtags=acont%3Ddubbed%3Alang%3Dit&mime=video%2Fmp4&ns=AexXQ7CkEosNbRIJ4c54jUAR&rqh=1&gir=yes&clen=12173660&ratebypass=yes&dur=148.282&lmt=1758298401803701&mt=1767094920&fvip=5&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=0000224&n=nxZOPSTgr2AWoA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cxtags%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cratebypass%2Cdur%2Clmt&sig=AJfQdSswRgIhANhGXRx4iO2PdgJBr43eqPT4n5qhvwFUNanAciaXFGReAiEAg2MXOB0X9X_G9t6aMxuutJ_G0DY11LcBCYkVvt951fU%3D&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgH_IuG1DCrQ6862zWGhdcnt8Gxo9zuoDojizAvwwUV40CIAIEO65QYwlaM3gRfL18bUhbtAjpK5JuGPwsg4Qap0Qi&pot=MlUE1vv0n3I9BgTocfWE2OhQofBEKBvzkz1B7Oh4GskYFe21N6pKqvMprzBWc8qg2TnxBEEY3fQ6I1HI4H3uVoBfUa8VjuWd79p72Pvt5bGV8xgZCT_o&cver=1.20250219.01.00"... Dec 30 12:50:55 volumio volumio[1383]: info: [youtube2] VideoModel.getInfo(adsoQOm3qP4): stream validated in 0.032s. Dec 30 12:50:55 volumio volumio[1383]: info: [youtube2] (AutoplayManager) Added volumioStateListener Dec 30 12:50:55 volumio volumio[1383]: info: [youtube2] (AutoplayManager) Enabled Dec 30 12:50:55 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 12:50:55 volumio volumio[1383]: info: sendMpdCommand stop took 1 milliseconds Dec 30 12:50:55 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand clear Dec 30 12:50:55 volumio volumio[1383]: info: Dec 30 12:50:55 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 30 12:50:55 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:50:55 volumio volumio[1383]: info: sendMpdCommand clear took 1 milliseconds Dec 30 12:50:55 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand addid "https://rr4---sn-45nufxc-hm2l.googlevideo.com/videoplayback?expire=1767117054&ei=nrxTaYuNOL_kxN8Plu3g2Ag&ip=93.38.29.55&id=o-AJCJZEw7W57PymFuNcAc_ZXGWVmxXKDlTAvN1jnmlxQs&itag=18&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=0&met=1767095454%2C&mh=S6&mm=31%2C26&mn=sn-45nufxc-hm2l%2Csn-hgn7yn7z&ms=au%2Conr&mv=m&mvi=4&pl=22&rms=au%2Cau&initcwndbps=2478750&bui=AYUSA3CFJN8gsOVI7xgqfDJcDhtO8mC8E_C1UrlRyweNRye45Uy8AIqVgoAe97Dm85J_uF2Qv6VvyVpl&spc=wH4Qq7Fgcu3JLwKDvKgWRoVM0LFBZZwfgOftFVmd9CfaHHpTOpda-0syx7_bjQ&vprv=1&svpuc=1&xtags=acont%3Ddubbed%3Alang%3Dit&mime=video%2Fmp4&ns=AexXQ7CkEosNbRIJ4c54jUAR&rqh=1&gir=yes&clen=12173660&ratebypass=yes&dur=148.282&lmt=1758298401803701&mt=1767094920&fvip=5&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=0000224&n=nxZOPSTgr2AWoA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cxtags%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cratebypass%2Cdur%2Clmt&sig=AJfQdSswRgIhANhGXRx4iO2PdgJBr43eqPT4n5qhvwFUNanAciaXFGReAiEAg2MXOB0X9X_G9t6aMxuutJ_G0DY11LcBCYkVvt951fU%3D&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgH_IuG1DCrQ6862zWGhdcnt8Gxo9zuoDojizAvwwUV40CIAIEO65QYwlaM3gRfL18bUhbtAjpK5JuGPwsg4Qap0Qi&pot=MlUE1vv0n3I9BgTocfWE2OhQofBEKBvzkz1B7Oh4GskYFe21N6pKqvMprzBWc8qg2TnxBEEY3fQ6I1HI4H3uVoBfUa8VjuWd79p72Pvt5bGV8xgZCT_o&cver=1.20250219.01.00&t.YouTube" Dec 30 12:50:55 volumio volumio[1383]: info: Dec 30 12:50:55 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 30 12:50:55 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:50:55 volumio volumio[1383]: info: Dec 30 12:50:55 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 30 12:50:55 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:50:55 volumio volumio[1383]: error: updateQueue error: null Dec 30 12:50:55 volumio volumio[1383]: info: Dec 30 12:50:55 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 30 12:50:55 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:50:55 volumio volumio[1383]: info: ------------------------------ 6ms Dec 30 12:50:55 volumio volumio[1383]: info: sendMpdCommand addid "https://rr4---sn-45nufxc-hm2l.googlevideo.com/videoplayback?expire=1767117054&ei=nrxTaYuNOL_kxN8Plu3g2Ag&ip=93.38.29.55&id=o-AJCJZEw7W57PymFuNcAc_ZXGWVmxXKDlTAvN1jnmlxQs&itag=18&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=0&met=1767095454%2C&mh=S6&mm=31%2C26&mn=sn-45nufxc-hm2l%2Csn-hgn7yn7z&ms=au%2Conr&mv=m&mvi=4&pl=22&rms=au%2Cau&initcwndbps=2478750&bui=AYUSA3CFJN8gsOVI7xgqfDJcDhtO8mC8E_C1UrlRyweNRye45Uy8AIqVgoAe97Dm85J_uF2Qv6VvyVpl&spc=wH4Qq7Fgcu3JLwKDvKgWRoVM0LFBZZwfgOftFVmd9CfaHHpTOpda-0syx7_bjQ&vprv=1&svpuc=1&xtags=acont%3Ddubbed%3Alang%3Dit&mime=video%2Fmp4&ns=AexXQ7CkEosNbRIJ4c54jUAR&rqh=1&gir=yes&clen=12173660&ratebypass=yes&dur=148.282&lmt=1758298401803701&mt=1767094920&fvip=5&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=0000224&n=nxZOPSTgr2AWoA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cxtags%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cratebypass%2Cdur%2Clmt&sig=AJfQdSswRgIhANhGXRx4iO2PdgJBr43eqPT4n5qhvwFUNanAciaXFGReAiEAg2MXOB0X9X_G9t6aMxuutJ_G0DY11LcBCYkVvt951fU%3D&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgH_IuG1DCrQ6862zWGhdcnt8Gxo9zuoDojizAvwwUV40CIAIEO65QYwlaM3gRfL18bUhbtAjpK5JuGPwsg4Qap0Qi&pot=MlUE1vv0n3I9BgTocfWE2OhQofBEKBvzkz1B7Oh4GskYFe21N6pKqvMprzBWc8qg2TnxBEEY3fQ6I1HI4H3uVoBfUa8VjuWd79p72Pvt5bGV8xgZCT_o&cver=1.20250219.01.00&t.YouTube" took 4 milliseconds Dec 30 12:50:55 volumio volumio[1383]: info: ------------------------------ 3ms Dec 30 12:50:55 volumio volumio[1383]: verbose: MPD COMMAND [object Object] Dec 30 12:50:55 volumio volumio[1383]: verbose: MPD COMMAND [object Object] Dec 30 12:50:55 volumio volumio[1383]: verbose: MPD COMMAND [object Object] Dec 30 12:50:55 volumio volumio[1383]: info: Dec 30 12:50:55 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 30 12:50:55 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:50:55 volumio volumio[1383]: info: Dec 30 12:50:55 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 30 12:50:55 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:50:55 volumio volumio[1383]: info: ------------------------------ 11ms Dec 30 12:50:55 volumio volumio[1383]: info: ------------------------------ 10ms Dec 30 12:50:55 volumio volumio[1383]: info: ------------------------------ 6ms Dec 30 12:50:55 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 30 12:50:55 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand play Dec 30 12:50:55 volumio volumio[1383]: info: Dec 30 12:50:55 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 30 12:50:55 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:50:55 volumio volumio[1383]: info: Dec 30 12:50:55 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 30 12:50:55 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 30 12:50:55 volumio volumio[1383]: info: ------------------------------ 5ms Dec 30 12:50:55 volumio volumio[1383]: info: sendMpdCommand play took 4 milliseconds Dec 30 12:50:55 volumio volumio[1383]: info: ------------------------------ 3ms Dec 30 12:50:55 volumio volumio[1383]: info: ------------------------------ 2ms Dec 30 12:50:55 volumio volumio[1383]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 12:50:55 volumio volumio[1383]: InnertubeError: Playback tracking not available Dec 30 12:50:55 volumio volumio[1383]: at _VideoInfo.addToWatchHistory (/data/plugins/music_service/youtube2/node_modules/volumio-yt-support/node_modules/volumio-youtubei.js/bundle/node.cjs:30709:13) Dec 30 12:50:55 volumio volumio[1383]: at _VideoInfo.addToWatchHistory (/data/plugins/music_service/youtube2/node_modules/volumio-yt-support/node_modules/volumio-youtubei.js/bundle/node.cjs:32099:18) Dec 30 12:50:55 volumio volumio[1383]: at Object.addToHistory (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:66:34) Dec 30 12:50:55 volumio volumio[1383]: at PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:141:35) Dec 30 12:50:55 volumio volumio[1383]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) { Dec 30 12:50:55 volumio volumio[1383]: date: 2025-12-30T11:50:55.231Z, Dec 30 12:50:55 volumio volumio[1383]: version: '1.6.0', Dec 30 12:50:55 volumio volumio[1383]: info: undefined Dec 30 12:50:55 volumio volumio[1383]: } Dec 30 12:50:55 volumio volumio[1383]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 12:50:55 volumio sudo[3692]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-30 12:49' Dec 30 12:50:55 volumio sudo[3692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"