Dec 23 01:00:00 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 23 01:00:00 volumio volumio[3468]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 23 01:00:00 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 23 01:00:00 volumio volumio[3468]: info: Received Get System Version Dec 23 01:00:00 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 23 01:00:00 volumio volumio[3468]: info: Received Get System Info Dec 23 01:00:00 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 01:00:00 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 01:00:00 volumio volumio[3468]: info: Discovery: Getting this device information Dec 23 01:00:00 volumio volumio[3468]: info: CoreCommandRouter::volumioGetState Dec 23 01:00:00 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 01:00:12 volumio volumio[3468]: info: Starting Uninstall of plugin music_service - youtube2 Dec 23 01:00:25 volumio volumio[3468]: info: Enabling plugin ytmusic Dec 23 01:00:25 volumio volumio[3468]: info: Loading plugin "ytmusic"... Dec 23 01:00:27 volumio volumio[3468]: info: PLUGIN START: ytmusic Dec 23 01:00:27 volumio volumio[3468]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 01:00:27 volumio volumio[3468]: info: [1766480427250] CoreMusicLibrary::Adding element YouTube Music Dec 23 01:00:27 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 01:00:27 volumio volumio[3468]: Cannot find translation for source YouTube Music Dec 23 01:00:27 volumio volumio[3468]: info: Done. Dec 23 01:00:27 volumio volumio[3468]: info: Enabling plugin ytmusic Dec 23 01:00:27 volumio volumio[3468]: info: Loading plugin "ytmusic"... Dec 23 01:00:27 volumio volumio[3468]: info: PLUGIN START: ytmusic Dec 23 01:00:27 volumio volumio[3468]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 01:00:27 volumio volumio[3468]: info: [1766480427308] CoreMusicLibrary::Adding element YouTube Music Dec 23 01:00:27 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 01:00:27 volumio volumio[3468]: Cannot find translation for source YouTube Music Dec 23 01:00:27 volumio volumio[3468]: info: Done. Dec 23 01:00:28 volumio volumio[3468]: info: Enabling plugin youtube2 Dec 23 01:00:28 volumio volumio[3468]: info: Loading plugin "youtube2"... Dec 23 01:00:29 volumio volumio[3468]: info: PLUGIN START: youtube2 Dec 23 01:00:29 volumio volumio[3468]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 01:00:30 volumio volumio[3468]: info: [1766480429999] CoreMusicLibrary::Adding element YouTube2 Dec 23 01:00:30 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 01:00:30 volumio volumio[3468]: Cannot find translation for source YouTube Music Dec 23 01:00:30 volumio volumio[3468]: Cannot find translation for source YouTube2 Dec 23 01:00:30 volumio volumio[3468]: info: Done. Dec 23 01:00:30 volumio volumio[3468]: info: Enabling plugin youtube2 Dec 23 01:00:30 volumio volumio[3468]: info: Loading plugin "youtube2"... Dec 23 01:00:30 volumio volumio[3468]: info: PLUGIN START: youtube2 Dec 23 01:00:30 volumio volumio[3468]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 01:00:30 volumio volumio[3468]: info: [1766480430036] CoreMusicLibrary::Adding element YouTube2 Dec 23 01:00:30 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 01:00:30 volumio volumio[3468]: Cannot find translation for source YouTube Music Dec 23 01:00:30 volumio volumio[3468]: Cannot find translation for source YouTube2 Dec 23 01:00:30 volumio volumio[3468]: info: Done. Dec 23 01:00:31 volumio volumio[3468]: info: Starting Uninstall of plugin music_service - youtube2 Dec 23 01:00:31 volumio volumio[3468]: info: Uninstalling plugin youtube2 Dec 23 01:00:31 volumio volumio[3468]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesYouTube2 Dec 23 01:00:31 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 01:00:31 volumio volumio[3468]: Cannot find translation for source YouTube Music Dec 23 01:00:31 volumio volumio[3468]: info: [youtube2] (AutoplayManager) Disabled Dec 23 01:00:31 volumio volumio[3468]: info: Disabling plugin youtube2 Dec 23 01:00:31 volumio volumio[3468]: info: Checking if uninstall.sh is present Dec 23 01:00:31 volumio volumio[3468]: info: Executing uninstall.sh Dec 23 01:00:31 volumio sudo[7994]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/youtube2/uninstall.sh Dec 23 01:00:31 volumio sudo[7994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 01:00:31 volumio sudo[7994]: pam_unix(sudo:session): session closed for user root Dec 23 01:00:31 volumio volumio[3468]: info: Uninstall script completed Dec 23 01:00:31 volumio volumio[3468]: info: Removing plugin youtube2 from configuration Dec 23 01:00:31 volumio volumio[3468]: info: Successfully removed youtube2 configuration files Dec 23 01:00:31 volumio volumio[3468]: info: Plugin folders cleanup Dec 23 01:00:31 volumio volumio[3468]: info: Scanning into folder /volumio/app/plugins/ Dec 23 01:00:31 volumio volumio[3468]: info: Scanning category audio_interface Dec 23 01:00:31 volumio volumio[3468]: info: Scanning category miscellanea Dec 23 01:00:31 volumio volumio[3468]: info: Scanning category music_service Dec 23 01:00:31 volumio volumio[3468]: info: Scanning category plugins.json Dec 23 01:00:31 volumio volumio[3468]: info: Scanning category system_controller Dec 23 01:00:31 volumio volumio[3468]: info: Scanning category user_interface Dec 23 01:00:31 volumio volumio[3468]: info: Scanning into folder /data/plugins/ Dec 23 01:00:31 volumio volumio[3468]: info: Scanning category music_service Dec 23 01:00:31 volumio volumio[3468]: info: Cleaning folder for youtube2 Dec 23 01:00:35 volumio volumio[3468]: info: Plugin folders cleanup completed Dec 23 01:00:35 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 23 01:00:35 volumio volumio[3468]: info: [{"prettyName":"YouTube Cast Receiver","name":"ytcr","category":"music_service","version":"2.1.0","icon":"fa-youtube","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"2.4.0","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true}] Dec 23 01:00:35 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 23 01:00:43 volumio volumio[3468]: info: Starting Uninstall of plugin music_service - ytmusic Dec 23 01:00:43 volumio volumio[3468]: info: Uninstalling plugin ytmusic Dec 23 01:00:43 volumio volumio[3468]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesYouTube Music Dec 23 01:00:43 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 01:00:43 volumio volumio[3468]: info: [ytmusic] (AutoplayManager) Disabled Dec 23 01:00:43 volumio volumio[3468]: info: Disabling plugin ytmusic Dec 23 01:00:43 volumio volumio[3468]: info: Checking if uninstall.sh is present Dec 23 01:00:43 volumio volumio[3468]: info: Executing uninstall.sh Dec 23 01:00:43 volumio sudo[8021]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/ytmusic/uninstall.sh Dec 23 01:00:43 volumio sudo[8021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 01:00:43 volumio sudo[8021]: pam_unix(sudo:session): session closed for user root Dec 23 01:00:43 volumio volumio[3468]: info: Uninstall script completed Dec 23 01:00:43 volumio volumio[3468]: info: Removing plugin ytmusic from configuration Dec 23 01:00:43 volumio volumio[3468]: info: Successfully removed ytmusic configuration files Dec 23 01:00:43 volumio volumio[3468]: info: Plugin folders cleanup Dec 23 01:00:43 volumio volumio[3468]: info: Scanning into folder /volumio/app/plugins/ Dec 23 01:00:43 volumio volumio[3468]: info: Scanning category audio_interface Dec 23 01:00:43 volumio volumio[3468]: info: Scanning category miscellanea Dec 23 01:00:43 volumio volumio[3468]: info: Scanning category music_service Dec 23 01:00:43 volumio volumio[3468]: info: Scanning category plugins.json Dec 23 01:00:43 volumio volumio[3468]: info: Scanning category system_controller Dec 23 01:00:43 volumio volumio[3468]: info: Scanning category user_interface Dec 23 01:00:43 volumio volumio[3468]: info: Scanning into folder /data/plugins/ Dec 23 01:00:43 volumio volumio[3468]: info: Scanning category music_service Dec 23 01:00:43 volumio volumio[3468]: info: Cleaning folder for ytmusic Dec 23 01:00:47 volumio volumio[3468]: info: Plugin folders cleanup completed Dec 23 01:00:47 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 23 01:00:47 volumio volumio[3468]: info: [{"prettyName":"YouTube Cast Receiver","name":"ytcr","category":"music_service","version":"2.1.0","icon":"fa-youtube","isManuallyInstalled":false,"enabled":true,"active":true}] Dec 23 01:00:47 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 23 01:00:56 volumio volumio[3468]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 01:01:29 volumio volumio[3468]: info: CALLMETHOD: music_service ytcr configSaveOther [object Object] Dec 23 01:01:29 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: ytcr , configSaveOther Dec 23 01:01:29 volumio volumio[3468]: verbose: [ytcr] Going to prefetch sb27EfioKKw in 13.782999999999987s Dec 23 01:01:30 volumio volumio[3468]: verbose: ----------------------------------- Dec 23 01:01:30 volumio volumio[3468]: verbose: [yt-cast-receiver] (AID: 70) (YouTube Music) Incoming message: 'noop' Dec 23 01:01:30 volumio volumio[3468]: verbose: [yt-cast-receiver] (AID: 70) (YouTube Music) Not handled: 'noop' Dec 23 01:01:30 volumio volumio[3468]: verbose: [yt-cast-receiver] RPC connection disconnected. Reconnecting... Dec 23 01:01:30 volumio volumio[3468]: verbose: [yt-cast-receiver] Connecting to RPC URL: https://www.youtube.com/api/lounge/bc/bind?AID=70&CI=0&RID=rpc&SID=559E8909CAD76D39&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=pdPjOZXjVBdYL7V8BWkoHg2PBHxe2Ov8&id=ff018052-2f40-441d-829a-a8d58c7af047&loungeIdToken=AGdO5p8h4thhMaZ4Ffz90CmLTtjx-pzpoiLoSYFtsS0YtD5m0VWFezQqxcvZGi085Z66myOV8coOe5T9i4d2cwlHNGfRshj1vYurEGokVtij00xXY1mZOWc&mdxVersion=2&name=YouTube%20on%20Volumio&obfuscatedGaiaId=&t=1&theme=m&v=2&zx=84005ef02d64 Dec 23 01:01:30 volumio volumio[3468]: verbose: [yt-cast-receiver] RPC connection established. Dec 23 01:01:30 volumio volumio[3468]: verbose: ----------------------------------- Dec 23 01:01:30 volumio volumio[3468]: verbose: [yt-cast-receiver] (AID: 71) (YouTube Music) Incoming message: 'noop' Dec 23 01:01:30 volumio volumio[3468]: verbose: [yt-cast-receiver] (AID: 71) (YouTube Music) Not handled: 'noop' Dec 23 01:01:32 volumio volumio[3468]: verbose: ----------------------------------- Dec 23 01:01:32 volumio volumio[3468]: verbose: [yt-cast-receiver] (AID: 135) (YouTube) Incoming message: 'noop' Dec 23 01:01:32 volumio volumio[3468]: verbose: [yt-cast-receiver] (AID: 135) (YouTube) Not handled: 'noop' Dec 23 01:01:43 volumio volumio[3468]: verbose: [ytcr] Begin prefetching sb27EfioKKw... Dec 23 01:01:43 volumio volumio[3468]: verbose: [ytcr] VideoLoader.getInfo: sb27EfioKKw Dec 23 01:01:44 volumio volumio[3468]: info: [ytcr] (sb27EfioKKw) fetching player data using YTMUSIC client... Dec 23 01:01:45 volumio volumio[3468]: verbose: [ytcr] Preferred format is Opus Dec 23 01:01:45 volumio volumio[3468]: info: [ytcr] (LAST WALTZ) validating stream URL "https://rr1---sn-ni5f-t8gz.googlevideo.com/videoplayback?expire=1766502105&ei=eFpKabPXPJTBsfIP8q_B0A4&ip=70.70.229.40&id=o-AILcDJl6a0oMWkjzMtrShCJke34gcDFjvXckPzemfDp7&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=24&met=1766480505%2C&mh=tv&mm=31%2C26&mn=sn-ni5f-t8gz%2Csn-vgqskn6s&ms=au%2Conr&mv=m&mvi=1&pl=22&rms=au%2Cau&ctier=A&pfa=5&gcr=ca&initcwndbps=4051250&hightc=yes&siu=1&bui=AYUSA3AOVVKqyio2SrfwhsKcIEVUoqv5UpwIk0HBSmqTIdPnV0aaRry7JPAi9K7M1VRIZbUNgQ&spc=wH4QqwLo4PZElXJBNRg91Mbn4yA1KKKzk6_Ux555nTtQ1wJPNw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=CdTG7kQlYhd3DA2fnvso0UsR&rqh=1&gir=yes&clen=5796093&dur=170.581&lmt=1714873520938770&mt=1766480025&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=5532434&n=pYDYzRwZWX_wZQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgQnfj1LGwuQYeIDO0CoY3VatyxOdygvCDhyd3Lcdv6OoCIQCEom1wZZrp5nUnKw8GF-sn6jKnA0slHqkXcsNfb-sy2Q%3D%3D&sig=AJfQdSswRgIhANp7qOK70ib4K_2BbKYmSuan_D0seUMUDwnsA7duDN95AiEA-TIcWbkLu65P8IINYUpCXXnDw2LPlssUEJ3j4ETHaA8%3D&pot=MlVFuKAevu0U-UWGZ4bJCyL2lgp6oRBP47kEaV-jT3AdhXiJX3g1F9Cnuk0v8qaBpy8f6a9W0Zt-bgw6mxc-Tu2MP40z5gLbjazga1JgToE5Cf_v2UGU&cver=1.20250219.01.00"... Dec 23 01:01:45 volumio volumio[3468]: info: [ytcr] (LAST WALTZ) stream validated in 0.143s. Dec 23 01:01:45 volumio volumio[3468]: verbose: [ytcr] Prefetched info for sb27EfioKKw: Dec 23 01:01:45 volumio volumio[3468]: { Dec 23 01:01:45 volumio volumio[3468]: id: 'sb27EfioKKw', Dec 23 01:01:45 volumio volumio[3468]: src: 'ytmusic', Dec 23 01:01:45 volumio volumio[3468]: title: 'LAST WALTZ', Dec 23 01:01:45 volumio volumio[3468]: artist: 'TWICE', Dec 23 01:01:45 volumio volumio[3468]: album: 'Formula of Love: O+T=<3', Dec 23 01:01:45 volumio volumio[3468]: errMsg: undefined, Dec 23 01:01:45 volumio volumio[3468]: thumbnail: 'https://lh3.googleusercontent.com/VPPfJl0x2QzxrpxPfLnv7zLEyB2RGJYEjPuUAvuD00RXm9tYK1U_ssjxQefKDMHLzmNRnuNLXGC1HRX5=w544-h544-l90-rj', Dec 23 01:01:45 volumio volumio[3468]: isLive: false, Dec 23 01:01:45 volumio volumio[3468]: streamUrl: 'https://rr1---sn-ni5f-t8gz.googlevideo.com/videoplayback?expire=1766502105&ei=eFpKabPXPJTBsfIP8q_B0A4&ip=70.70.229.40&id=o-AILcDJl6a0oMWkjzMtrShCJke34gcDFjvXckPzemfDp7&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=24&met=1766480505%2C&mh=tv&mm=31%2C26&mn=sn-ni5f-t8gz%2Csn-vgqskn6s&ms=au%2Conr&mv=m&mvi=1&pl=22&rms=au%2Cau&ctier=A&pfa=5&gcr=ca&initcwndbps=4051250&hightc=yes&siu=1&bui=AYUSA3AOVVKqyio2SrfwhsKcIEVUoqv5UpwIk0HBSmqTIdPnV0aaRry7JPAi9K7M1VRIZbUNgQ&spc=wH4QqwLo4PZElXJBNRg91Mbn4yA1KKKzk6_Ux555nTtQ1wJPNw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=CdTG7kQlYhd3DA2fnvso0UsR&rqh=1&gir=yes&clen=5796093&dur=170.581&lmt=1714873520938770&mt=1766480025&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=5532434&n=pYDYzRwZWX_wZQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgQnfj1LGwuQYeIDO0CoY3VatyxOdygvCDhyd3Lcdv6OoCIQCEom1wZZrp5nUnKw8GF-sn6jKnA0slHqkXcsNfb-sy2Q%3D%3D&sig=AJfQdSswRgIhANp7qOK70ib4K_2BbKYmSuan_D0seUMUDwnsA7duDN95AiEA-TIcWbkLu65P8IINYUpCXXnDw2LPlssUEJ3j4ETHaA8%3D&pot=MlVFuKAevu0U-UWGZ4bJCyL2lgp6oRBP47kEaV-jT3AdhXiJX3g1F9Cnuk0v8qaBpy8f6a9W0Zt-bgw6mxc-Tu2MP40z5gLbjazga1JgToE5Cf_v2UGU&cver=1.20250219.01.00', Dec 23 01:01:45 volumio volumio[3468]: duration: 171, Dec 23 01:01:45 volumio volumio[3468]: bitrate: 'VBR 256 kbps', Dec 23 01:01:45 volumio volumio[3468]: samplerate: 48000, Dec 23 01:01:45 volumio volumio[3468]: channels: 2, Dec 23 01:01:45 volumio volumio[3468]: streamExpires: 2025-12-23T15:00:45.155Z Dec 23 01:01:45 volumio volumio[3468]: } Dec 23 01:01:45 volumio volumio[3468]: info: Ignoring MPD Status Update Dec 23 01:01:45 volumio volumio[3468]: info: Ignoring MPD Status Update Dec 23 01:01:45 volumio volumio[3468]: info: Ignoring MPD Status Update Dec 23 01:01:45 volumio volumio[3468]: info: Ignoring MPD Status Update Dec 23 01:01:45 volumio volumio[3468]: info: Ignoring MPD Status Update Dec 23 01:01:45 volumio volumio[3468]: info: Ignoring MPD Status Update Dec 23 01:01:45 volumio volumio[3468]: info: Ignoring MPD Status Update Dec 23 01:01:45 volumio volumio[3468]: info: Ignoring MPD Status Update Dec 23 01:01:45 volumio volumio[3468]: verbose: [ytcr] Prefetched video sb27EfioKKw added to MPD queue with song Id: 14 Dec 23 01:01:45 volumio volumio[3468]: verbose: [ytcr] Stream URL of prefetched video sb27EfioKKw is going to expire in 21539.717s Dec 23 01:01:53 volumio volumio[3468]: info: Ignoring MPD Status Update Dec 23 01:01:53 volumio volumio[3468]: info: Ignoring MPD Status Update Dec 23 01:01:53 volumio volumio[3468]: info: Ignoring MPD Status Update Dec 23 01:01:53 volumio volumio[3468]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Dec 23 01:01:53 volumio volumio[3468]: verbose: [ytcr] MPDPlayer received external MPD event for subsystem: player. Dec 23 01:01:53 volumio volumio[3468]: verbose: [ytcr] MPD status for subsystem event: Dec 23 01:01:53 volumio volumio[3468]: { Dec 23 01:01:53 volumio volumio[3468]: volume: 86, Dec 23 01:01:53 volumio volumio[3468]: repeat: false, Dec 23 01:01:53 volumio volumio[3468]: random: false, Dec 23 01:01:53 volumio volumio[3468]: single: false, Dec 23 01:01:53 volumio volumio[3468]: consume: true, Dec 23 01:01:53 volumio volumio[3468]: partition: 'default', Dec 23 01:01:53 volumio volumio[3468]: playlist: 70, Dec 23 01:01:53 volumio volumio[3468]: playlistlength: 1, Dec 23 01:01:53 volumio volumio[3468]: mixrampdb: 0, Dec 23 01:01:53 volumio volumio[3468]: state: 'play', Dec 23 01:01:53 volumio volumio[3468]: lastloadedplaylist: '', Dec 23 01:01:53 volumio volumio[3468]: song: 0, Dec 23 01:01:53 volumio volumio[3468]: songid: 14, Dec 23 01:01:53 volumio volumio[3468]: time: { elapsed: 0, total: 171 }, Dec 23 01:01:53 volumio volumio[3468]: elapsed: 0.112, Dec 23 01:01:53 volumio volumio[3468]: bitrate: '0', Dec 23 01:01:53 volumio volumio[3468]: duration: 170.581, Dec 23 01:01:53 volumio volumio[3468]: audio: { Dec 23 01:01:53 volumio volumio[3468]: sample_rate: 48000, Dec 23 01:01:53 volumio volumio[3468]: bits: 'f', Dec 23 01:01:53 volumio volumio[3468]: channels: 2, Dec 23 01:01:53 volumio volumio[3468]: sample_rate_short: { value: 48, unit: 'kHz' }, Dec 23 01:01:53 volumio volumio[3468]: original_value: '48000:f:2' Dec 23 01:01:53 volumio volumio[3468]: } Dec 23 01:01:53 volumio volumio[3468]: } Dec 23 01:01:53 volumio volumio[3468]: verbose: [ytcr] Playback of prefetched video started Dec 23 01:01:53 volumio volumio[3468]: verbose: [yt-cast-receiver] DefaultPlaylistRequestHandler.getPreviousNextVideos: Dec 23 01:01:53 volumio volumio[3468]: { Dec 23 01:01:53 volumio volumio[3468]: id: 'sb27EfioKKw', Dec 23 01:01:53 volumio volumio[3468]: client: { key: 'YT', theme: 'cl', name: 'YouTube' }, Dec 23 01:01:53 volumio volumio[3468]: context: { Dec 23 01:01:53 volumio volumio[3468]: index: 1, Dec 23 01:01:53 volumio volumio[3468]: playlistId: 'RQjay6viPqvzQg9Xp9UuPnuKN7iIE', Dec 23 01:01:53 volumio volumio[3468]: params: 'EAIYATgBYgt6ZkVTbFU4ZGlEMGgAkgQdUlFqYXk2dmlQcXZ6UWc5WHA5VXVQbnVLTjdpSUU%3D', Dec 23 01:01:53 volumio volumio[3468]: ctt: 'APmki7TXYjr2GO-gKXQXbz5AfJOCN2BsDR2c2PYN5aHChmtGIQcQuZL2jtdFoMcCF-Y7tB_QF62QYKf9cy8NWY3tdXQjNWl0eDttwSx8pSQphzOU2fiOiSPhZ7vl2Mzz9b7iH5PZ5BTS' Dec 23 01:01:53 volumio volumio[3468]: } Dec 23 01:01:53 volumio volumio[3468]: } Dec 23 01:01:53 volumio volumio[3468]: verbose: [yt-cast-receiver] Marking video sb27EfioKKw as watched (1st run)... Dec 23 01:01:54 volumio volumio[3468]: verbose: [yt-cast-receiver] Marking video sb27EfioKKw as watched (2nd run - without playlist info)... Dec 23 01:01:54 volumio volumio[3468]: info: Ignoring MPD Status Update Dec 23 01:01:54 volumio volumio[3468]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Dec 23 01:01:54 volumio volumio[3468]: verbose: [ytcr] MPDPlayer received external MPD event for subsystem: player. Dec 23 01:01:54 volumio volumio[3468]: verbose: [ytcr] MPD status for subsystem event: Dec 23 01:01:54 volumio volumio[3468]: { Dec 23 01:01:54 volumio volumio[3468]: volume: 86, Dec 23 01:01:54 volumio volumio[3468]: repeat: false, Dec 23 01:01:54 volumio volumio[3468]: random: false, Dec 23 01:01:54 volumio volumio[3468]: single: false, Dec 23 01:01:54 volumio volumio[3468]: consume: true, Dec 23 01:01:54 volumio volumio[3468]: partition: 'default', Dec 23 01:01:54 volumio volumio[3468]: playlist: 70, Dec 23 01:01:54 volumio volumio[3468]: playlistlength: 1, Dec 23 01:01:54 volumio volumio[3468]: mixrampdb: 0, Dec 23 01:01:54 volumio volumio[3468]: state: 'pause', Dec 23 01:01:54 volumio volumio[3468]: lastloadedplaylist: '', Dec 23 01:01:54 volumio volumio[3468]: song: 0, Dec 23 01:01:54 volumio volumio[3468]: songid: 14, Dec 23 01:01:54 volumio volumio[3468]: time: { elapsed: 1, total: 171 }, Dec 23 01:01:54 volumio volumio[3468]: elapsed: 1.417, Dec 23 01:01:54 volumio volumio[3468]: bitrate: '0', Dec 23 01:01:54 volumio volumio[3468]: duration: 170.581, Dec 23 01:01:54 volumio volumio[3468]: audio: { Dec 23 01:01:54 volumio volumio[3468]: sample_rate: 48000, Dec 23 01:01:54 volumio volumio[3468]: bits: 'f', Dec 23 01:01:54 volumio volumio[3468]: channels: 2, Dec 23 01:01:54 volumio volumio[3468]: sample_rate_short: { value: 48, unit: 'kHz' }, Dec 23 01:01:54 volumio volumio[3468]: original_value: '48000:f:2' Dec 23 01:01:54 volumio volumio[3468]: }, Dec 23 01:01:54 volumio volumio[3468]: error: 'Failed to open audio output' Dec 23 01:01:54 volumio volumio[3468]: } Dec 23 01:01:54 volumio volumio[3468]: verbose: [ytcr] Cancelling prefetch (abortIfPrefetching: false, clearIfPrefetched: false) Dec 23 01:01:54 volumio volumio[3468]: info: CoreCommandRouter::volumioGetState Dec 23 01:01:54 volumio volumio[3468]: verbose: [ytcr] Received state change event from MPDPlayer: Dec 23 01:01:54 volumio volumio[3468]: { Dec 23 01:01:54 volumio volumio[3468]: status: 2, Dec 23 01:01:54 volumio volumio[3468]: queue: { Dec 23 01:01:54 volumio volumio[3468]: id: 'RQjay6viPqvzQg9Xp9UuPnuKN7iIE', Dec 23 01:01:54 volumio volumio[3468]: videoIds: [ Dec 23 01:01:54 volumio volumio[3468]: 'zfESlU8diD0', 'sb27EfioKKw', '7inO3vut90w', 'w9eOsEFpM6A', '3uy8HXhcqBA', Dec 23 01:01:54 volumio volumio[3468]: '4-wFebOZAeA', 'Wk3Vxt3knnI', 'RwrZPCKLs2E', 'i4xeIUEG_pA', 'D2lV5RujuNw', Dec 23 01:01:54 volumio volumio[3468]: '5_IYVichXbA', 'aAw01FtfFuQ', 'QVM00XI_dU8', 'qa2s73u1HoQ', 'WPjsnH2mVD8', Dec 23 01:01:54 volumio volumio[3468]: 'GsuYdc2rX4k', '3fqDjzbtbG4', '48EscKGkgHU', 'YAViX1za5ys', 'JkyxS0syfPU', Dec 23 01:01:54 volumio volumio[3468]: 'rbhw4wgCyYY', 'pZnHampBN0U', 'nGlY7Iln7Iw', 'jr-Fpfe3OMw', 'aRo4NWeEB3U', Dec 23 01:01:54 volumio volumio[3468]: 'FxuVJeeKK_4', 'WBHgJxfRdtY', 'XT3qFS_c-w0', 'RmN-N_v-y6U', 'iYzZocktZjk', Dec 23 01:01:54 volumio volumio[3468]: 'D4d-75RKP6M', 'gOF4C6C9XeE', 'yhtaMiavRIY', 'B1DSBdomLNc', 'VIgjbMKOkHA', Dec 23 01:01:54 volumio volumio[3468]: 'CPwdylMjgQ4', '4gt45q8Az0E', 'jq9zy4TBygU', 'OPqR0g1LelQ', 'LP9sF1v-vz4', Dec 23 01:01:54 volumio volumio[3468]: 'FVDmPknIFJg', '7wJsfALKRR8', 'ClVqBH3arZ8', 'm8fD4_I-uFs', 'h5XU0WN0Mj4', Dec 23 01:01:54 volumio volumio[3468]: 'tp7IRa7q_5g', 'TnsMqfilV74', 'HP8qpxRpIW8', 'tO_ppAsQ20A', 'Y3hhIOz7iI4', Dec 23 01:01:54 volumio volumio[3468]: 'ru8HikpOd1o', '0O18GnTW1CU', 'QOW8me2l-yQ', '2Kff0U8w-aU', 'gxkav18iOG4', Dec 23 01:01:54 volumio volumio[3468]: '3gWWzcjw-z4', 'BZqfL_2CzKg', '6og5c9438Ko', 'F1elF699k9g', '6AY7hV_qGKQ', Dec 23 01:01:54 volumio volumio[3468]: 'JkSORHKdxKw', 'S-6oQyf7oG8', '8yTxNtVr_d8', 'lClnSwRxFoo', 'DR9jlJ3sT4c', Dec 23 01:01:54 volumio volumio[3468]: 'UoKt7iCmwYE', 'kgARov3n6Sk', 'WTRm-Yr5Foo', 'yWkZfKjPDbQ', 'glptno5ohjc', Dec 23 01:01:54 volumio volumio[3468]: 'MgEmFu-QRDg', 'eDK3qibyoDg', 'PFvAWiIQncI', 'mlfmt04tXjU', 'R8CinGnUZog', Dec 23 01:01:54 volumio volumio[3468]: 'v4HY9XqcPLk', 'bLfU4eeqOCo', 'izOGip7r6Zg', 'afOCMMP562Q', 'cO--M-DaMP0', Dec 23 01:01:54 volumio volumio[3468]: 'fhxq4s36-I4', 'Uxc0EZt8raU', '_msvYg42lew', '8_7Y7PE6oWg', 'V4QB1mJoafo', Dec 23 01:01:54 volumio volumio[3468]: 'SYtQV-Mz_8w', 'PLblcSm_4y8', 'TAMmJfnUiBE', 'SB6VJXA5cYw', 'YSEwEFuMPPM', Dec 23 01:01:54 volumio volumio[3468]: 'oGucn9Sj_N4', 'b8wtjkKW9yA', 'S6IB_RyX1dQ', 'V6TEcoNUmc8', '0WLpDoPcDAA', Dec 23 01:01:54 volumio volumio[3468]: 'gXfbXklqCfc', 'sqX8_BMehY4', 'yOw3lOR15bw', '33hE0Z0iNys', 't2c2pA_edgo' Dec 23 01:01:54 volumio volumio[3468]: ], Dec 23 01:01:54 volumio volumio[3468]: previous: null, Dec 23 01:01:54 volumio volumio[3468]: current: { Dec 23 01:01:54 volumio volumio[3468]: id: 'sb27EfioKKw', Dec 23 01:01:54 volumio volumio[3468]: client: { key: 'YT', theme: 'cl', name: 'YouTube' }, Dec 23 01:01:54 volumio volumio[3468]: context: { Dec 23 01:01:54 volumio volumio[3468]: index: 1, Dec 23 01:01:54 volumio volumio[3468]: playlistId: 'RQjay6viPqvzQg9Xp9UuPnuKN7iIE', Dec 23 01:01:54 volumio volumio[3468]: params: 'EAIYATgBYgt6ZkVTbFU4ZGlEMGgAkgQdUlFqYXk2dmlQcXZ6UWc5WHA5VXVQbnVLTjdpSUU%3D', Dec 23 01:01:54 volumio volumio[3468]: ctt: 'APmki7TXYjr2GO-gKXQXbz5AfJOCN2BsDR2c2PYN5aHChmtGIQcQuZL2jtdFoMcCF-Y7tB_QF62QYKf9cy8NWY3tdXQjNWl0eDttwSx8pSQphzOU2fiOiSPhZ7vl2Mzz9b7iH5PZ5BTS' Dec 23 01:01:54 volumio volumio[3468]: } Dec 23 01:01:54 volumio volumio[3468]: }, Dec 23 01:01:54 volumio volumio[3468]: next: null, Dec 23 01:01:54 volumio volumio[3468]: autoplay: null Dec 23 01:01:54 volumio volumio[3468]: }, Dec 23 01:01:54 volumio volumio[3468]: position: 1.417, Dec 23 01:01:54 volumio volumio[3468]: duration: 170.581, Dec 23 01:01:54 volumio volumio[3468]: volume: { level: 100, muted: false }, Dec 23 01:01:54 volumio volumio[3468]: cpn: 'c9b6fdd407cb4519' Dec 23 01:01:54 volumio volumio[3468]: } Dec 23 01:01:54 volumio volumio[3468]: verbose: [yt-cast-receiver] Player state changed from: Dec 23 01:01:54 volumio volumio[3468]: { Dec 23 01:01:54 volumio volumio[3468]: status: 1, Dec 23 01:01:54 volumio volumio[3468]: queue: { Dec 23 01:01:54 volumio volumio[3468]: id: 'RQjay6viPqvzQg9Xp9UuPnuKN7iIE', Dec 23 01:01:54 volumio volumio[3468]: videoIds: [ Dec 23 01:01:54 volumio volumio[3468]: 'zfESlU8diD0', 'sb27EfioKKw', '7inO3vut90w', 'w9eOsEFpM6A', '3uy8HXhcqBA', Dec 23 01:01:54 volumio volumio[3468]: '4-wFebOZAeA', 'Wk3Vxt3knnI', 'RwrZPCKLs2E', 'i4xeIUEG_pA', 'D2lV5RujuNw', Dec 23 01:01:54 volumio volumio[3468]: '5_IYVichXbA', 'aAw01FtfFuQ', 'QVM00XI_dU8', 'qa2s73u1HoQ', 'WPjsnH2mVD8', Dec 23 01:01:54 volumio volumio[3468]: 'GsuYdc2rX4k', '3fqDjzbtbG4', '48EscKGkgHU', 'YAViX1za5ys', 'JkyxS0syfPU', Dec 23 01:01:54 volumio volumio[3468]: 'rbhw4wgCyYY', 'pZnHampBN0U', 'nGlY7Iln7Iw', 'jr-Fpfe3OMw', 'aRo4NWeEB3U', Dec 23 01:01:54 volumio volumio[3468]: 'FxuVJeeKK_4', 'WBHgJxfRdtY', 'XT3qFS_c-w0', 'RmN-N_v-y6U', 'iYzZocktZjk', Dec 23 01:01:54 volumio volumio[3468]: 'D4d-75RKP6M', 'gOF4C6C9XeE', 'yhtaMiavRIY', 'B1DSBdomLNc', 'VIgjbMKOkHA', Dec 23 01:01:54 volumio volumio[3468]: 'CPwdylMjgQ4', '4gt45q8Az0E', 'jq9zy4TBygU', 'OPqR0g1LelQ', 'LP9sF1v-vz4', Dec 23 01:01:54 volumio volumio[3468]: 'FVDmPknIFJg', '7wJsfALKRR8', 'ClVqBH3arZ8', 'm8fD4_I-uFs', 'h5XU0WN0Mj4', Dec 23 01:01:54 volumio volumio[3468]: 'tp7IRa7q_5g', 'TnsMqfilV74', 'HP8qpxRpIW8', 'tO_ppAsQ20A', 'Y3hhIOz7iI4', Dec 23 01:01:54 volumio volumio[3468]: 'ru8HikpOd1o', '0O18GnTW1CU', 'QOW8me2l-yQ', '2Kff0U8w-aU', 'gxkav18iOG4', Dec 23 01:01:54 volumio volumio[3468]: '3gWWzcjw-z4', 'BZqfL_2CzKg', '6og5c9438Ko', 'F1elF699k9g', '6AY7hV_qGKQ', Dec 23 01:01:54 volumio volumio[3468]: 'JkSORHKdxKw', 'S-6oQyf7oG8', '8yTxNtVr_d8', 'lClnSwRxFoo', 'DR9jlJ3sT4c', Dec 23 01:01:54 volumio volumio[3468]: 'UoKt7iCmwYE', 'kgARov3n6Sk', 'WTRm-Yr5Foo', 'yWkZfKjPDbQ', 'glptno5ohjc', Dec 23 01:01:54 volumio volumio[3468]: 'MgEmFu-QRDg', 'eDK3qibyoDg', 'PFvAWiIQncI', 'mlfmt04tXjU', 'R8CinGnUZog', Dec 23 01:01:54 volumio volumio[3468]: 'v4HY9XqcPLk', 'bLfU4eeqOCo', 'izOGip7r6Zg', 'afOCMMP562Q', 'cO--M-DaMP0', Dec 23 01:01:54 volumio volumio[3468]: 'fhxq4s36-I4', 'Uxc0EZt8raU', '_msvYg42lew', '8_7Y7PE6oWg', 'V4QB1mJoafo', Dec 23 01:01:54 volumio volumio[3468]: 'SYtQV-Mz_8w', 'PLblcSm_4y8', 'TAMmJfnUiBE', 'SB6VJXA5cYw', 'YSEwEFuMPPM', Dec 23 01:01:54 volumio volumio[3468]: 'oGucn9Sj_N4', 'b8wtjkKW9yA', 'S6IB_RyX1dQ', 'V6TEcoNUmc8', '0WLpDoPcDAA', Dec 23 01:01:54 volumio volumio[3468]: 'gXfbXklqCfc', 'sqX8_BMehY4', 'yOw3lOR15bw', '33hE0Z0iNys', 't2c2pA_edgo' Dec 23 01:01:54 volumio volumio[3468]: ], Dec 23 01:01:54 volumio volumio[3468]: previous: null, Dec 23 01:01:54 volumio volumio[3468]: current: { Dec 23 01:01:54 volumio volumio[3468]: id: 'zfESlU8diD0', Dec 23 01:01:54 volumio volumio[3468]: client: { key: 'YT', theme: 'cl', name: 'YouTube' }, Dec 23 01:01:54 volumio volumio[3468]: context: { Dec 23 01:01:54 volumio volumio[3468]: playlistId: 'RQjay6viPqvzQg9Xp9UuPnuKN7iIE', Dec 23 01:01:54 volumio volumio[3468]: index: 0, Dec 23 01:01:54 volumio volumio[3468]: ctt: 'APmki7RMRjGlLhpN9_OPuflPuZ6WLLYNRBqxJqYSrbdRwJifSynasxwkf-LzKjXioc93hI3fksPPq_QWEa1Y8ZT4ShEMkVMDC4rUi0T1lKvisUTwB3T0cDvS0upV6SCDZgJrFr8TOxYd', Dec 23 01:01:54 volumio volumio[3468]: params: 'OAHyAQIIAZIEHVJRamF5NnZpUHF2elFnOVhwOVV1UG51S043aUlF' Dec 23 01:01:54 volumio volumio[3468]: } Dec 23 01:01:54 volumio volumio[3468]: }, Dec 23 01:01:54 volumio volumio[3468]: next: { Dec 23 01:01:54 volumio volumio[3468]: id: 'sb27EfioKKw', Dec 23 01:01:54 volumio volumio[3468]: client: { key: 'YT', theme: 'cl', name: 'YouTube' }, Dec 23 01:01:54 volumio volumio[3468]: context: { Dec 23 01:01:54 volumio volumio[3468]: index: 1, Dec 23 01:01:54 volumio volumio[3468]: playlistId: 'RQjay6viPqvzQg9Xp9UuPnuKN7iIE', Dec 23 01:01:54 volumio volumio[3468]: params: 'EAIYATgBYgt6ZkVTbFU4ZGlEMGgAkgQdUlFqYXk2dmlQcXZ6UWc5WHA5VXVQbnVLTjdpSUU%3D', Dec 23 01:01:54 volumio volumio[3468]: ctt: 'APmki7TXYjr2GO-gKXQXbz5AfJOCN2BsDR2c2PYN5aHChmtGIQcQuZL2jtdFoMcCF-Y7tB_QF62QYKf9cy8NWY3tdXQjNWl0eDttwSx8pSQphzOU2fiOiSPhZ7vl2Mzz9b7iH5PZ5BTS' Dec 23 01:01:54 volumio volumio[3468]: } Dec 23 01:01:54 volumio volumio[3468]: }, Dec 23 01:01:54 volumio volumio[3468]: autoplay: null Dec 23 01:01:54 volumio volumio[3468]: }, Dec 23 01:01:54 volumio volumio[3468]: position: 0, Dec 23 01:01:54 volumio volumio[3468]: duration: 146.021, Dec 23 01:01:54 volumio volumio[3468]: volume: { level: 100, muted: false }, Dec 23 01:01:54 volumio volumio[3468]: cpn: 'c9b6fdd407cb4519' Dec 23 01:01:54 volumio volumio[3468]: } Dec 23 01:01:54 volumio kernel: usb 1-1.3: reset full-speed USB device number 7 using dwc_otg Dec 23 01:01:54 volumio volumio[3468]: verbose: To: Dec 23 01:01:54 volumio volumio[3468]: { Dec 23 01:01:54 volumio volumio[3468]: status: 2, Dec 23 01:01:54 volumio volumio[3468]: queue: { Dec 23 01:01:54 volumio volumio[3468]: id: 'RQjay6viPqvzQg9Xp9UuPnuKN7iIE', Dec 23 01:01:54 volumio volumio[3468]: videoIds: [ Dec 23 01:01:54 volumio volumio[3468]: 'zfESlU8diD0', 'sb27EfioKKw', '7inO3vut90w', 'w9eOsEFpM6A', '3uy8HXhcqBA', Dec 23 01:01:54 volumio volumio[3468]: '4-wFebOZAeA', 'Wk3Vxt3knnI', 'RwrZPCKLs2E', 'i4xeIUEG_pA', 'D2lV5RujuNw', Dec 23 01:01:54 volumio volumio[3468]: '5_IYVichXbA', 'aAw01FtfFuQ', 'QVM00XI_dU8', 'qa2s73u1HoQ', 'WPjsnH2mVD8', Dec 23 01:01:54 volumio volumio[3468]: 'GsuYdc2rX4k', '3fqDjzbtbG4', '48EscKGkgHU', 'YAViX1za5ys', 'JkyxS0syfPU', Dec 23 01:01:54 volumio volumio[3468]: 'rbhw4wgCyYY', 'pZnHampBN0U', 'nGlY7Iln7Iw', 'jr-Fpfe3OMw', 'aRo4NWeEB3U', Dec 23 01:01:54 volumio volumio[3468]: 'FxuVJeeKK_4', 'WBHgJxfRdtY', 'XT3qFS_c-w0', 'RmN-N_v-y6U', 'iYzZocktZjk', Dec 23 01:01:54 volumio volumio[3468]: 'D4d-75RKP6M', 'gOF4C6C9XeE', 'yhtaMiavRIY', 'B1DSBdomLNc', 'VIgjbMKOkHA', Dec 23 01:01:54 volumio volumio[3468]: 'CPwdylMjgQ4', '4gt45q8Az0E', 'jq9zy4TBygU', 'OPqR0g1LelQ', 'LP9sF1v-vz4', Dec 23 01:01:54 volumio volumio[3468]: 'FVDmPknIFJg', '7wJsfALKRR8', 'ClVqBH3arZ8', 'm8fD4_I-uFs', 'h5XU0WN0Mj4', Dec 23 01:01:54 volumio volumio[3468]: 'tp7IRa7q_5g', 'TnsMqfilV74', 'HP8qpxRpIW8', 'tO_ppAsQ20A', 'Y3hhIOz7iI4', Dec 23 01:01:54 volumio volumio[3468]: 'ru8HikpOd1o', '0O18GnTW1CU', 'QOW8me2l-yQ', '2Kff0U8w-aU', 'gxkav18iOG4', Dec 23 01:01:54 volumio volumio[3468]: '3gWWzcjw-z4', 'BZqfL_2CzKg', '6og5c9438Ko', 'F1elF699k9g', '6AY7hV_qGKQ', Dec 23 01:01:54 volumio volumio[3468]: 'JkSORHKdxKw', 'S-6oQyf7oG8', '8yTxNtVr_d8', 'lClnSwRxFoo', 'DR9jlJ3sT4c', Dec 23 01:01:54 volumio volumio[3468]: 'UoKt7iCmwYE', 'kgARov3n6Sk', 'WTRm-Yr5Foo', 'yWkZfKjPDbQ', 'glptno5ohjc', Dec 23 01:01:54 volumio volumio[3468]: 'MgEmFu-QRDg', 'eDK3qibyoDg', 'PFvAWiIQncI', 'mlfmt04tXjU', 'R8CinGnUZog', Dec 23 01:01:54 volumio volumio[3468]: 'v4HY9XqcPLk', 'bLfU4eeqOCo', 'izOGip7r6Zg', 'afOCMMP562Q', 'cO--M-DaMP0', Dec 23 01:01:54 volumio volumio[3468]: 'fhxq4s36-I4', 'Uxc0EZt8raU', '_msvYg42lew', '8_7Y7PE6oWg', 'V4QB1mJoafo', Dec 23 01:01:54 volumio volumio[3468]: 'SYtQV-Mz_8w', 'PLblcSm_4y8', 'TAMmJfnUiBE', 'SB6VJXA5cYw', 'YSEwEFuMPPM', Dec 23 01:01:54 volumio volumio[3468]: 'oGucn9Sj_N4', 'b8wtjkKW9yA', 'S6IB_RyX1dQ', 'V6TEcoNUmc8', '0WLpDoPcDAA', Dec 23 01:01:54 volumio volumio[3468]: 'gXfbXklqCfc', 'sqX8_BMehY4', 'yOw3lOR15bw', '33hE0Z0iNys', 't2c2pA_edgo' Dec 23 01:01:54 volumio volumio[3468]: ], Dec 23 01:01:54 volumio volumio[3468]: previous: null, Dec 23 01:01:54 volumio volumio[3468]: current: { Dec 23 01:01:54 volumio volumio[3468]: id: 'sb27EfioKKw', Dec 23 01:01:54 volumio volumio[3468]: client: { key: 'YT', theme: 'cl', name: 'YouTube' }, Dec 23 01:01:54 volumio volumio[3468]: context: { Dec 23 01:01:54 volumio volumio[3468]: index: 1, Dec 23 01:01:54 volumio volumio[3468]: playlistId: 'RQjay6viPqvzQg9Xp9UuPnuKN7iIE', Dec 23 01:01:54 volumio volumio[3468]: params: 'EAIYATgBYgt6ZkVTbFU4ZGlEMGgAkgQdUlFqYXk2dmlQcXZ6UWc5WHA5VXVQbnVLTjdpSUU%3D', Dec 23 01:01:54 volumio volumio[3468]: ctt: 'APmki7TXYjr2GO-gKXQXbz5AfJOCN2BsDR2c2PYN5aHChmtGIQcQuZL2jtdFoMcCF-Y7tB_QF62QYKf9cy8NWY3tdXQjNWl0eDttwSx8pSQphzOU2fiOiSPhZ7vl2Mzz9b7iH5PZ5BTS' Dec 23 01:01:54 volumio volumio[3468]: } Dec 23 01:01:54 volumio volumio[3468]: }, Dec 23 01:01:54 volumio volumio[3468]: next: null, Dec 23 01:01:54 volumio volumio[3468]: autoplay: null Dec 23 01:01:54 volumio volumio[3468]: }, Dec 23 01:01:54 volumio volumio[3468]: position: 1.417, Dec 23 01:01:54 volumio volumio[3468]: duration: 170.581, Dec 23 01:01:54 volumio volumio[3468]: volume: { level: 100, muted: false }, Dec 23 01:01:54 volumio volumio[3468]: cpn: 'c9b6fdd407cb4519' Dec 23 01:01:54 volumio volumio[3468]: } Dec 23 01:01:54 volumio volumio[3468]: verbose: [yt-cast-receiver] (YouTube) Sending messages 'onStateChange + nowPlaying + onHasPreviousNextChanged' with payload: Dec 23 01:01:54 volumio volumio[3468]: { Dec 23 01:01:54 volumio volumio[3468]: count: 3, Dec 23 01:01:54 volumio volumio[3468]: ofs: '226', Dec 23 01:01:54 volumio volumio[3468]: req0__sc: 'onStateChange', Dec 23 01:01:54 volumio volumio[3468]: req0_state: 2, Dec 23 01:01:54 volumio volumio[3468]: req0_currentTime: 1.417, Dec 23 01:01:54 volumio volumio[3468]: req0_duration: 170.581, Dec 23 01:01:54 volumio volumio[3468]: req0_loadedTime: 170.581, Dec 23 01:01:54 volumio volumio[3468]: req0_seekableStartTime: 0, Dec 23 01:01:54 volumio volumio[3468]: req0_seekableEndTime: 170.581, Dec 23 01:01:54 volumio volumio[3468]: req0_cpn: 'c9b6fdd407cb4519', Dec 23 01:01:54 volumio volumio[3468]: req1__sc: 'nowPlaying', Dec 23 01:01:54 volumio volumio[3468]: req1_currentTime: 1.417, Dec 23 01:01:54 volumio volumio[3468]: req1_duration: 170.581, Dec 23 01:01:54 volumio volumio[3468]: req1_cpn: 'c9b6fdd407cb4519', Dec 23 01:01:54 volumio volumio[3468]: req1_loadedTime: 170.581, Dec 23 01:01:54 volumio volumio[3468]: req1_videoId: 'sb27EfioKKw', Dec 23 01:01:54 volumio volumio[3468]: req1_state: 2, Dec 23 01:01:54 volumio volumio[3468]: req1_seekableStartTime: 0, Dec 23 01:01:54 volumio volumio[3468]: req1_seekableEndTime: 170.581, Dec 23 01:01:54 volumio volumio[3468]: req1_listId: 'RQjay6viPqvzQg9Xp9UuPnuKN7iIE', Dec 23 01:01:54 volumio volumio[3468]: req1_currentIndex: 1, Dec 23 01:01:54 volumio volumio[3468]: req1_ctt: 'APmki7TXYjr2GO-gKXQXbz5AfJOCN2BsDR2c2PYN5aHChmtGIQcQuZL2jtdFoMcCF-Y7tB_QF62QYKf9cy8NWY3tdXQjNWl0eDttwSx8pSQphzOU2fiOiSPhZ7vl2Mzz9b7iH5PZ5BTS', Dec 23 01:01:54 volumio volumio[3468]: req1_params: 'EAIYATgBYgt6ZkVTbFU4ZGlEMGgAkgQdUlFqYXk2dmlQcXZ6UWc5WHA5VXVQbnVLTjdpSUU%3D', Dec 23 01:01:54 volumio volumio[3468]: req2__sc: 'onHasPreviousNextChanged', Dec 23 01:01:54 volumio volumio[3468]: req2_hasPrevious: false, Dec 23 01:01:54 volumio volumio[3468]: req2_hasNext: false Dec 23 01:01:54 volumio volumio[3468]: } Dec 23 01:01:54 volumio volumio[3468]: verbose: [ytcr] pushState(): Dec 23 01:01:54 volumio volumio[3468]: { Dec 23 01:01:54 volumio volumio[3468]: status: 'pause', Dec 23 01:01:54 volumio volumio[3468]: service: 'ytcr', Dec 23 01:01:54 volumio volumio[3468]: albumart: 'https://lh3.googleusercontent.com/VPPfJl0x2QzxrpxPfLnv7zLEyB2RGJYEjPuUAvuD00RXm9tYK1U_ssjxQefKDMHLzmNRnuNLXGC1HRX5=w544-h544-l90-rj', Dec 23 01:01:54 volumio volumio[3468]: uri: '', Dec 23 01:01:54 volumio volumio[3468]: trackType: 'YouTube', Dec 23 01:01:54 volumio volumio[3468]: seek: 1417, Dec 23 01:01:54 volumio volumio[3468]: duration: 171, Dec 23 01:01:54 volumio volumio[3468]: volume: 100, Dec 23 01:01:54 volumio volumio[3468]: mute: false, Dec 23 01:01:54 volumio volumio[3468]: title: 'LAST WALTZ', Dec 23 01:01:54 volumio volumio[3468]: artist: 'TWICE', Dec 23 01:01:54 volumio volumio[3468]: album: 'Formula of Love: O+T=<3', Dec 23 01:01:54 volumio volumio[3468]: channels: 2, Dec 23 01:01:54 volumio volumio[3468]: isStreaming: false, Dec 23 01:01:54 volumio volumio[3468]: samplerate: 'VBR 256 kbps - YouTube Cast' Dec 23 01:01:54 volumio volumio[3468]: } Dec 23 01:01:54 volumio volumio[3468]: info: CoreCommandRouter::servicePushState Dec 23 01:01:54 volumio volumio[3468]: info: CoreStateMachine::pushState Dec 23 01:01:54 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 01:01:54 volumio volumio[3468]: info: CoreCommandRouter::volumioPushState Dec 23 01:01:55 volumio volumio[3468]: info: Dec 23 01:01:55 volumio volumio[3468]: ---------------------------- USB Audio Device Detached Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 23 01:01:55 volumio volumio[3468]: verbose: [yt-cast-receiver] (YouTube) Response received for sent messages 'onStateChange + nowPlaying + onHasPreviousNextChanged'. Status: 200 Dec 23 01:01:55 volumio volumio[3468]: info: Dec 23 01:01:55 volumio volumio[3468]: ---------------------------- USB Audio Device Attached Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::Close All Modals sent Dec 23 01:01:55 volumio volumio[3468]: info: Preparing to save Alsa Options, stopping services first Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::volumioGetState Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::volumioPause Dec 23 01:01:55 volumio volumio[3468]: info: CoreStateMachine::pause Dec 23 01:01:55 volumio volumio[3468]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"FiiO USB DAC-E18","alsacard":"DACE18"},"i2s":false} Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 01:01:55 volumio volumio[3468]: info: Setting mixer PCM for card FiiO USB DAC-E18 Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 23 01:01:55 volumio volumio[3468]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO USB DAC-E18 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 23 01:01:55 volumio volumio[3468]: info: Disabling external Volume Control Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 01:01:55 volumio volumio[3468]: info: Preparing to generate the ALSA configuration file Dec 23 01:01:55 volumio volumio[3468]: verbose: ----------------------------------- Dec 23 01:01:55 volumio volumio[3468]: verbose: [yt-cast-receiver] (AID: 72) (YouTube Music) Incoming message: 'noop' Dec 23 01:01:55 volumio volumio[3468]: verbose: [yt-cast-receiver] (AID: 72) (YouTube Music) Not handled: 'noop' Dec 23 01:01:55 volumio volumio[3468]: verbose: ----------------------------------- Dec 23 01:01:55 volumio volumio[3468]: verbose: [yt-cast-receiver] (AID: 136) (YouTube) Incoming message: 'setSubtitlesTrack' Dec 23 01:01:55 volumio volumio[3468]: verbose: [yt-cast-receiver] (AID: 136) (YouTube) Not handled: 'setSubtitlesTrack' Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 23 01:01:55 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 23 01:01:55 volumio volumio[3468]: info: Asound.conf file unchanged, so no further update is needed Dec 23 01:01:55 volumio volumio[3468]: info: Output device has changed, restarting MPD Dec 23 01:01:56 volumio volumio[3468]: info: Output device has changed, restarting Shairport Sync Dec 23 01:01:56 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 01:01:56 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 01:01:56 volumio sudo[8188]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 01:01:56 volumio sudo[8188]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 01:01:56 volumio sudo[8188]: pam_unix(sudo:session): session closed for user root Dec 23 01:01:56 volumio sudo[8190]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 01:01:56 volumio sudo[8190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 01:01:56 volumio volumio[3468]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 23 01:01:56 volumio volumio[3468]: info: MPD Permissions set Dec 23 01:01:56 volumio volumio[3468]: info: VolumeController:: Volume=100 Mute =false Dec 23 01:01:56 volumio volumio[3468]: info: CoreCommandRouter::volumioGetState Dec 23 01:01:56 volumio volumio[3468]: verbose: [ytcr] Captured change in Volumio's volume: Dec 23 01:01:56 volumio volumio[3468]: { vol: 100, mute: false, disableVolumeControl: false } Dec 23 01:01:56 volumio volumio[3468]: info: CoreStateMachine::pushState Dec 23 01:01:56 volumio volumio[3468]: info: CoreCommandRouter::volumioPushState Dec 23 01:01:56 volumio volumio[3468]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 23 01:01:56 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 23 01:01:56 volumio volumio[3468]: verbose: [ytcr] pushState(): Dec 23 01:01:56 volumio volumio[3468]: { Dec 23 01:01:56 volumio volumio[3468]: status: 'pause', Dec 23 01:01:56 volumio volumio[3468]: service: 'ytcr', Dec 23 01:01:56 volumio volumio[3468]: albumart: 'https://lh3.googleusercontent.com/VPPfJl0x2QzxrpxPfLnv7zLEyB2RGJYEjPuUAvuD00RXm9tYK1U_ssjxQefKDMHLzmNRnuNLXGC1HRX5=w544-h544-l90-rj', Dec 23 01:01:56 volumio volumio[3468]: uri: '', Dec 23 01:01:56 volumio volumio[3468]: trackType: 'YouTube', Dec 23 01:01:56 volumio volumio[3468]: seek: 1417, Dec 23 01:01:56 volumio volumio[3468]: duration: 171, Dec 23 01:01:56 volumio volumio[3468]: volume: 100, Dec 23 01:01:56 volumio volumio[3468]: mute: false, Dec 23 01:01:56 volumio volumio[3468]: title: 'LAST WALTZ', Dec 23 01:01:56 volumio volumio[3468]: artist: 'TWICE', Dec 23 01:01:56 volumio volumio[3468]: album: 'Formula of Love: O+T=<3', Dec 23 01:01:56 volumio volumio[3468]: channels: 2, Dec 23 01:01:56 volumio volumio[3468]: isStreaming: false, Dec 23 01:01:56 volumio volumio[3468]: samplerate: 'VBR 256 kbps - YouTube Cast' Dec 23 01:01:56 volumio volumio[3468]: } Dec 23 01:01:56 volumio volumio[3468]: info: CoreCommandRouter::servicePushState Dec 23 01:01:56 volumio volumio[3468]: info: CoreStateMachine::pushState Dec 23 01:01:56 volumio volumio[3468]: info: CoreCommandRouter::volumioPushState Dec 23 01:01:56 volumio volumio[3468]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 01:01:56 volumio volumio[3468]: MPDError: Not connected Dec 23 01:01:56 volumio volumio[3468]: at MPDClient.send (/data/plugins/music_service/ytcr/node_modules/mpd2/lib/index.js:101:13) Dec 23 01:01:56 volumio volumio[3468]: at MPDClient.sendCommand (/data/plugins/music_service/ytcr/node_modules/mpd2/lib/index.js:65:10) Dec 23 01:01:56 volumio volumio[3468]: at Object.get (/data/plugins/music_service/ytcr/node_modules/mpd-api/lib/api/index.js:60:16) Dec 23 01:01:56 volumio volumio[3468]: at MPDPlayer.getVolumioState (/data/plugins/music_service/ytcr/dist/lib/MPDPlayer.js:297:100) Dec 23 01:01:56 volumio volumio[3468]: at ControllerYTCR.pushState (/data/plugins/music_service/ytcr/dist/index.js:566:104) Dec 23 01:01:56 volumio volumio[3468]: at /data/plugins/music_service/ytcr/dist/index.js:310:28 Dec 23 01:01:56 volumio volumio[3468]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) { Dec 23 01:01:56 volumio volumio[3468]: code: 'ENOTCONNECTED' Dec 23 01:01:56 volumio volumio[3468]: } Dec 23 01:01:56 volumio volumio[3468]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 01:01:56 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 23 01:01:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 23 01:01:56 volumio systemd[1]: mpd.service: Consumed 54.965s CPU time. Dec 23 01:01:56 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 23 01:01:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 23 01:01:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 23 01:01:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 23 01:01:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 23 01:01:56 volumio sudo[8209]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 23 01:01:56 volumio sudo[8209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 23 01:01:56 volumio sudo[8209]: pam_unix(sudo:session): session closed for user root Dec 23 01:01:58 volumio sudo[8229]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-23 01:00' Dec 23 01:01:58 volumio sudo[8229]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"