Jun 30 17:44:00 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 30 17:44:00 volumio-4 volumio[1366]: info: Listing playlists Jun 30 17:44:00 volumio-4 volumio[1366]: info: Listing playlists Jun 30 17:44:01 volumio-4 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 30 17:44:01 volumio-4 dbus-daemon[939]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.39' (uid=0 pid=2828 comm="timedatectl show --property=NTPSynchronized --valu") Jun 30 17:44:01 volumio-4 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 30 17:44:01 volumio-4 dbus-daemon[939]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 30 17:44:01 volumio-4 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 30 17:44:01 volumio-4 setdatetime-helper.sh[2827]: Time is not synchronized. Attempting to sync... Jun 30 17:44:04 volumio-4 sudo[2836]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Mon, 30 Jun 2025 15:44:04 GMT#015' Jun 30 17:44:04 volumio-4 sudo[2836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 30 17:44:04 volumio-4 setdatetime-helper.sh[2837]: Mon Jun 30 17:44:04 CEST 2025 Jun 30 17:44:04 volumio-4 systemd-journald[424]: Time jumped backwards, rotating. Jun 30 17:44:04 volumio-4 sudo[2836]: pam_unix(sudo:session): session closed for user root Jun 30 17:44:04 volumio-4 setdatetime-helper.sh[2827]: Time synchronized successfully. Jun 30 17:44:04 volumio-4 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 30 17:44:04 volumio-4 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 30 17:44:10 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioSeek Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::seek Jun 30 17:44:14 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:44:14 volumio-4 volumio[1366]: info: TRACKBLOCK {"service":"ytmusic","uri":"ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22The%20Best%20of%20Mozart%22%2C%22artist%22%3A%22ALIBI%20Music%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FRb0UmrCXxVA%2Fhqdefault.jpg%3Fsqp%3D-oaymwEWCMACELQBIAQqCghQEJADGFogjgJIWg%26rs%3DAMzJL3kiTeiRgxasqznYegA9R9ghoRO21A%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%7D%7D","albumart":"https://i.ytimg.com/vi/Rb0UmrCXxVA/hqdefault.jpg?sqp=-oaymwEWCMACELQBIAQqCghQEJADGFogjgJIWg&rs=AOn4CLB36o21c1Kbv4HAecgzC5NeoI00xw","artist":"ALIBI Music","album":"","name":"The Best of Mozart","title":"The Best of Mozart","duration":6961,"samplerate":"VBR 160 kbps"} Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::startPlaybackTimer Jun 30 17:44:14 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 30 17:44:14 volumio-4 volumio[1366]: info: ControllerMpd::seek Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:44:14 volumio-4 volumio[1366]: error: null Jun 30 17:44:14 volumio-4 volumio[1366]: info: Jun 30 17:44:14 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:44:14 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:44:14 volumio-4 volumio[1366]: info: Jun 30 17:44:14 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:44:14 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:44:14 volumio-4 volumio[1366]: info: Jun 30 17:44:14 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:44:14 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:44:14 volumio-4 volumio[1366]: info: sendMpdCommand status took 5 milliseconds Jun 30 17:44:14 volumio-4 volumio[1366]: info: sendMpdCommand status took 3 milliseconds Jun 30 17:44:14 volumio-4 volumio[1366]: info: sendMpdCommand status took 3 milliseconds Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:44:14 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 30 17:44:14 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 30 17:44:14 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:44:14 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319835&ei=u7BiaJbxC9elzPsPjoGw0As&ip=46.11.14.15&id=o-AHO28dBf5GkB8I3kfYGfsGvLzw_ELebT9yQQYj7PJspK&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298235%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLMG3uiGye8VHa5oSoZ9tdpCI5_oOpY3Y05sUczpmk-lFUfeTrGe7BzpKJcQdXdEQHFSJJVtiyNK&spc=l3OVKajNrL0smFjMjQEw6zTdNkHOXGBhvp4KTUyVwkhbHq9QS_3vaTWLEGxkWAygpdo&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=nncTvuXEtWhdQC3UOHCynQ4Q&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=CuJjqS3DY333FA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgIjXUHepK3UofRO5RKwwtuaTytG34sshmA2tVsTfd-6ECIDW5KKAp-7Koq-FLimjRJixHewBLPfbpsk4BDj3lnRCE&sig=AJfQdSswRQIgGl_GqN0dCoavqqj7j3fOgmlnsWitoO02Ev0YYC-Sav0CIQDt2jhGjOkZ4NCvcksabMFRp30FpdBSo0pvyESY-stoug%3D%3D&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319835&ei=u7BiaJbxC9elzPsPjoGw0As&ip=46.11.14.15&id=o-AHO28dBf5GkB8I3kfYGfsGvLzw_ELebT9yQQYj7PJspK&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298235%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLMG3uiGye8VHa5oSoZ9tdpCI5_oOpY3Y05sUczpmk-lFUfeTrGe7BzpKJcQdXdEQHFSJJVtiyNK&spc=l3OVKajNrL0smFjMjQEw6zTdNkHOXGBhvp4KTUyVwkhbHq9QS_3vaTWLEGxkWAygpdo&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=nncTvuXEtWhdQC3UOHCynQ4Q&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=CuJjqS3DY333FA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgIjXUHepK3UofRO5RKwwtuaTytG34sshmA2tVsTfd-6ECIDW5KKAp-7Koq-FLimjRJixHewBLPfbpsk4BDj3lnRCE&sig=AJfQdSswRQIgGl_GqN0dCoavqqj7j3fOgmlnsWitoO02Ev0YYC-Sav0CIQDt2jhGjOkZ4NCvcksabMFRp30FpdBSo0pvyESY-stoug%3D%3D&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319835&ei=u7BiaJbxC9elzPsPjoGw0As&ip=46.11.14.15&id=o-AHO28dBf5GkB8I3kfYGfsGvLzw_ELebT9yQQYj7PJspK&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298235%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLMG3uiGye8VHa5oSoZ9tdpCI5_oOpY3Y05sUczpmk-lFUfeTrGe7BzpKJcQdXdEQHFSJJVtiyNK&spc=l3OVKajNrL0smFjMjQEw6zTdNkHOXGBhvp4KTUyVwkhbHq9QS_3vaTWLEGxkWAygpdo&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=nncTvuXEtWhdQC3UOHCynQ4Q&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=CuJjqS3DY333FA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgIjXUHepK3UofRO5RKwwtuaTytG34sshmA2tVsTfd-6ECIDW5KKAp-7Koq-FLimjRJixHewBLPfbpsk4BDj3lnRCE&sig=AJfQdSswRQIgGl_GqN0dCoavqqj7j3fOgmlnsWitoO02Ev0YYC-Sav0CIQDt2jhGjOkZ4NCvcksabMFRp30FpdBSo0pvyESY-stoug%3D%3D&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: ------------------------------ 22ms Jun 30 17:44:14 volumio-4 volumio[1366]: info: ------------------------------ 20ms Jun 30 17:44:14 volumio-4 volumio[1366]: info: ------------------------------ 21ms Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioSeek Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::seek Jun 30 17:44:14 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:44:14 volumio-4 volumio[1366]: info: TRACKBLOCK {"service":"ytmusic","uri":"ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22The%20Best%20of%20Mozart%22%2C%22artist%22%3A%22ALIBI%20Music%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FRb0UmrCXxVA%2Fhqdefault.jpg%3Fsqp%3D-oaymwEWCMACELQBIAQqCghQEJADGFogjgJIWg%26rs%3DAMzJL3kiTeiRgxasqznYegA9R9ghoRO21A%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%7D%7D","albumart":"https://i.ytimg.com/vi/Rb0UmrCXxVA/hqdefault.jpg?sqp=-oaymwEWCMACELQBIAQqCghQEJADGFogjgJIWg&rs=AOn4CLB36o21c1Kbv4HAecgzC5NeoI00xw","artist":"ALIBI Music","album":"","name":"The Best of Mozart","title":"The Best of Mozart","duration":6961,"samplerate":"VBR 160 kbps"} Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::startPlaybackTimer Jun 30 17:44:14 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 30 17:44:14 volumio-4 volumio[1366]: info: ControllerMpd::seek Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:44:14 volumio-4 volumio[1366]: error: null Jun 30 17:44:14 volumio-4 volumio[1366]: info: Jun 30 17:44:14 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:44:14 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:44:14 volumio-4 volumio[1366]: info: Jun 30 17:44:14 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:44:14 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:44:14 volumio-4 volumio[1366]: info: Jun 30 17:44:14 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:44:14 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:44:14 volumio-4 volumio[1366]: info: sendMpdCommand status took 4 milliseconds Jun 30 17:44:14 volumio-4 volumio[1366]: info: sendMpdCommand status took 4 milliseconds Jun 30 17:44:14 volumio-4 volumio[1366]: info: sendMpdCommand status took 4 milliseconds Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:44:14 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 30 17:44:14 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 30 17:44:14 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:44:14 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319835&ei=u7BiaJbxC9elzPsPjoGw0As&ip=46.11.14.15&id=o-AHO28dBf5GkB8I3kfYGfsGvLzw_ELebT9yQQYj7PJspK&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298235%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLMG3uiGye8VHa5oSoZ9tdpCI5_oOpY3Y05sUczpmk-lFUfeTrGe7BzpKJcQdXdEQHFSJJVtiyNK&spc=l3OVKajNrL0smFjMjQEw6zTdNkHOXGBhvp4KTUyVwkhbHq9QS_3vaTWLEGxkWAygpdo&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=nncTvuXEtWhdQC3UOHCynQ4Q&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=CuJjqS3DY333FA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgIjXUHepK3UofRO5RKwwtuaTytG34sshmA2tVsTfd-6ECIDW5KKAp-7Koq-FLimjRJixHewBLPfbpsk4BDj3lnRCE&sig=AJfQdSswRQIgGl_GqN0dCoavqqj7j3fOgmlnsWitoO02Ev0YYC-Sav0CIQDt2jhGjOkZ4NCvcksabMFRp30FpdBSo0pvyESY-stoug%3D%3D&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319835&ei=u7BiaJbxC9elzPsPjoGw0As&ip=46.11.14.15&id=o-AHO28dBf5GkB8I3kfYGfsGvLzw_ELebT9yQQYj7PJspK&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298235%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLMG3uiGye8VHa5oSoZ9tdpCI5_oOpY3Y05sUczpmk-lFUfeTrGe7BzpKJcQdXdEQHFSJJVtiyNK&spc=l3OVKajNrL0smFjMjQEw6zTdNkHOXGBhvp4KTUyVwkhbHq9QS_3vaTWLEGxkWAygpdo&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=nncTvuXEtWhdQC3UOHCynQ4Q&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=CuJjqS3DY333FA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgIjXUHepK3UofRO5RKwwtuaTytG34sshmA2tVsTfd-6ECIDW5KKAp-7Koq-FLimjRJixHewBLPfbpsk4BDj3lnRCE&sig=AJfQdSswRQIgGl_GqN0dCoavqqj7j3fOgmlnsWitoO02Ev0YYC-Sav0CIQDt2jhGjOkZ4NCvcksabMFRp30FpdBSo0pvyESY-stoug%3D%3D&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319835&ei=u7BiaJbxC9elzPsPjoGw0As&ip=46.11.14.15&id=o-AHO28dBf5GkB8I3kfYGfsGvLzw_ELebT9yQQYj7PJspK&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298235%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLMG3uiGye8VHa5oSoZ9tdpCI5_oOpY3Y05sUczpmk-lFUfeTrGe7BzpKJcQdXdEQHFSJJVtiyNK&spc=l3OVKajNrL0smFjMjQEw6zTdNkHOXGBhvp4KTUyVwkhbHq9QS_3vaTWLEGxkWAygpdo&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=nncTvuXEtWhdQC3UOHCynQ4Q&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=CuJjqS3DY333FA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgIjXUHepK3UofRO5RKwwtuaTytG34sshmA2tVsTfd-6ECIDW5KKAp-7Koq-FLimjRJixHewBLPfbpsk4BDj3lnRCE&sig=AJfQdSswRQIgGl_GqN0dCoavqqj7j3fOgmlnsWitoO02Ev0YYC-Sav0CIQDt2jhGjOkZ4NCvcksabMFRp30FpdBSo0pvyESY-stoug%3D%3D&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:44:14 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:44:14 volumio-4 volumio[1366]: info: ------------------------------ 25ms Jun 30 17:44:14 volumio-4 volumio[1366]: info: ------------------------------ 23ms Jun 30 17:44:14 volumio-4 volumio[1366]: info: ------------------------------ 23ms Jun 30 17:44:16 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioToggle Jun 30 17:44:20 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 30 17:44:20 volumio-4 volumio[1366]: info: Listing playlists Jun 30 17:44:20 volumio-4 volumio[1366]: info: Listing playlists Jun 30 17:44:30 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 30 17:44:30 volumio-4 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 30 17:44:40 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 30 17:44:40 volumio-4 volumio[1366]: info: Listing playlists Jun 30 17:44:40 volumio-4 volumio[1366]: info: Listing playlists Jun 30 17:44:50 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 30 17:44:53 volumio-4 volumio[1366]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not access weather service: missing geographic coordinates. Jun 30 17:44:53 volumio-4 volumio[1366]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:95:19) Jun 30 17:44:53 volumio-4 volumio[1366]: at Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:116:37) Jun 30 17:44:53 volumio-4 volumio[1366]: at /data/plugins/user_interface/now_playing/dist/app/Router.js:51:13 Jun 30 17:44:53 volumio-4 volumio[1366]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jun 30 17:44:53 volumio-4 volumio[1366]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:149:13) Jun 30 17:44:53 volumio-4 volumio[1366]: at Route.dispatch (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:119:3) Jun 30 17:44:53 volumio-4 volumio[1366]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jun 30 17:44:53 volumio-4 volumio[1366]: at /data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:284:15 Jun 30 17:44:53 volumio-4 volumio[1366]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:365:14) Jun 30 17:44:53 volumio-4 volumio[1366]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Jun 30 17:44:53 volumio-4 volumio[1366]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Jun 30 17:44:53 volumio-4 volumio[1366]: at Function.process_params (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:421:3) Jun 30 17:44:53 volumio-4 volumio[1366]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:280:10) Jun 30 17:44:53 volumio-4 volumio[1366]: at Function.handle (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:175:3) Jun 30 17:44:53 volumio-4 volumio[1366]: at router (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:47:12) Jun 30 17:44:53 volumio-4 volumio[1366]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jun 30 17:44:53 volumio-4 volumio[1366]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 30 17:44:53 volumio-4 volumio[1366]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jun 30 17:44:53 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jun 30 17:44:53 volumio-4 volumio[1366]: info: Listing playlists Jun 30 17:44:53 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetQueue Jun 30 17:44:53 volumio-4 volumio[1366]: info: CoreStateMachine::getQueue Jun 30 17:44:53 volumio-4 volumio[1366]: info: CorePlayQueue::getQueue Jun 30 17:44:53 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetVisibleSources Jun 30 17:44:53 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 17:44:53 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 30 17:45:00 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 30 17:45:00 volumio-4 volumio[1366]: info: Listing playlists Jun 30 17:45:00 volumio-4 volumio[1366]: info: Listing playlists Jun 30 17:45:00 volumio-4 volumio[1366]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 30 17:45:01 volumio-4 volumio[1366]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 30 17:45:01 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 30 17:45:01 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 30 17:45:01 volumio-4 volumio[1366]: info: Listing playlists Jun 30 17:45:01 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 30 17:45:01 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 30 17:45:01 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 30 17:45:01 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 30 17:45:02 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 30 17:45:02 volumio-4 volumio[1366]: info: Received Get System Info Jun 30 17:45:02 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 17:45:02 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 17:45:02 volumio-4 volumio[1366]: info: Discovery: Getting this device information Jun 30 17:45:02 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 30 17:45:02 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 17:45:03 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 30 17:45:03 volumio-4 volumio[1366]: info: Received Get System Info Jun 30 17:45:03 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 17:45:03 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 17:45:03 volumio-4 volumio[1366]: info: Discovery: Getting this device information Jun 30 17:45:03 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 30 17:45:03 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 17:45:10 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 30 17:45:20 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 30 17:45:20 volumio-4 volumio[1366]: info: Listing playlists Jun 30 17:45:20 volumio-4 volumio[1366]: info: Listing playlists Jun 30 17:45:23 volumio-4 systemd[1]: Starting apt-daily.service - Daily apt download activities... Jun 30 17:45:23 volumio-4 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 30 17:45:23 volumio-4 dbus-daemon[939]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.41' (uid=0 pid=2967 comm="timedatectl show --property=NTPSynchronized --valu") Jun 30 17:45:23 volumio-4 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 30 17:45:23 volumio-4 dbus-daemon[939]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 30 17:45:23 volumio-4 setdatetime-helper.sh[2966]: Time is not synchronized. Attempting to sync... Jun 30 17:45:23 volumio-4 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 30 17:45:26 volumio-4 sudo[2991]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Mon, 30 Jun 2025 15:45:26 GMT#015' Jun 30 17:45:26 volumio-4 sudo[2991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 30 17:45:26 volumio-4 setdatetime-helper.sh[2992]: Mon Jun 30 17:45:26 CEST 2025 Jun 30 17:45:26 volumio-4 setdatetime-helper.sh[2966]: Time synchronized successfully. Jun 30 17:45:26 volumio-4 sudo[2991]: pam_unix(sudo:session): session closed for user root Jun 30 17:45:26 volumio-4 systemd-journald[424]: Time jumped backwards, rotating. Jun 30 17:45:26 volumio-4 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 30 17:45:26 volumio-4 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 30 17:45:26 volumio-4 systemd[1]: apt-daily.service: Deactivated successfully. Jun 30 17:45:26 volumio-4 systemd[1]: Finished apt-daily.service - Daily apt download activities. Jun 30 17:45:26 volumio-4 systemd[1]: apt-daily.service: Consumed 2.629s CPU time. Jun 30 17:45:27 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPlay Jun 30 17:45:27 volumio-4 volumio[1366]: info: CoreStateMachine::play index 0 Jun 30 17:45:27 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:27 volumio-4 volumio[1366]: info: CoreStateMachine::stop Jun 30 17:45:27 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:27 volumio-4 volumio[1366]: info: CoreStateMachine::play index undefined Jun 30 17:45:27 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:27 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:27 volumio-4 volumio[1366]: info: CoreStateMachine::startPlaybackTimer Jun 30 17:45:27 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:27 volumio-4 volumio[1366]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22The%20Best%20of%20Mozart%22%2C%22artist%22%3A%22ALIBI%20Music%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FRb0UmrCXxVA%2Fhqdefault.jpg%3Fsqp%3D-oaymwEWCMACELQBIAQqCghQEJADGFogjgJIWg%26rs%3DAMzJL3kiTeiRgxasqznYegA9R9ghoRO21A%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%7D%7D Jun 30 17:45:27 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPlay Jun 30 17:45:27 volumio-4 volumio[1366]: info: CoreStateMachine::play index 0 Jun 30 17:45:27 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:27 volumio-4 volumio[1366]: info: CoreStateMachine::stop Jun 30 17:45:27 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:28 volumio-4 volumio[1366]: info: CoreStateMachine::play index undefined Jun 30 17:45:28 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:28 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:28 volumio-4 volumio[1366]: info: CoreStateMachine::startPlaybackTimer Jun 30 17:45:28 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:28 volumio-4 volumio[1366]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22The%20Best%20of%20Mozart%22%2C%22artist%22%3A%22ALIBI%20Music%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FRb0UmrCXxVA%2Fhqdefault.jpg%3Fsqp%3D-oaymwEWCMACELQBIAQqCghQEJADGFogjgJIWg%26rs%3DAMzJL3kiTeiRgxasqznYegA9R9ghoRO21A%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%7D%7D Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPlay Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreStateMachine::play index 0 Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreStateMachine::stop Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreStateMachine::play index undefined Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:29 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreStateMachine::startPlaybackTimer Jun 30 17:45:29 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:29 volumio-4 volumio[1366]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22The%20Best%20of%20Mozart%22%2C%22artist%22%3A%22ALIBI%20Music%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FRb0UmrCXxVA%2Fhqdefault.jpg%3Fsqp%3D-oaymwEWCMACELQBIAQqCghQEJADGFogjgJIWg%26rs%3DAMzJL3kiTeiRgxasqznYegA9R9ghoRO21A%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%7D%7D Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPlay Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreStateMachine::play index 0 Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreStateMachine::stop Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreStateMachine::play index undefined Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:29 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreStateMachine::startPlaybackTimer Jun 30 17:45:29 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:29 volumio-4 volumio[1366]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22The%20Best%20of%20Mozart%22%2C%22artist%22%3A%22ALIBI%20Music%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FRb0UmrCXxVA%2Fhqdefault.jpg%3Fsqp%3D-oaymwEWCMACELQBIAQqCghQEJADGFogjgJIWg%26rs%3DAMzJL3kiTeiRgxasqznYegA9R9ghoRO21A%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%7D%7D Jun 30 17:45:29 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 30 17:45:29 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPlay Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::play index 0 Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::stop Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::play index undefined Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:30 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::startPlaybackTimer Jun 30 17:45:30 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:30 volumio-4 volumio[1366]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22The%20Best%20of%20Mozart%22%2C%22artist%22%3A%22ALIBI%20Music%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FRb0UmrCXxVA%2Fhqdefault.jpg%3Fsqp%3D-oaymwEWCMACELQBIAQqCghQEJADGFogjgJIWg%26rs%3DAMzJL3kiTeiRgxasqznYegA9R9ghoRO21A%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%7D%7D Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPlay Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::play index 0 Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::stop Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::play index undefined Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:30 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::startPlaybackTimer Jun 30 17:45:30 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:30 volumio-4 volumio[1366]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22The%20Best%20of%20Mozart%22%2C%22artist%22%3A%22ALIBI%20Music%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FRb0UmrCXxVA%2Fhqdefault.jpg%3Fsqp%3D-oaymwEWCMACELQBIAQqCghQEJADGFogjgJIWg%26rs%3DAMzJL3kiTeiRgxasqznYegA9R9ghoRO21A%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%7D%7D Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPlay Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::play index 0 Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::stop Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::play index undefined Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 17:45:30 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:30 volumio-4 volumio[1366]: info: CoreStateMachine::startPlaybackTimer Jun 30 17:45:30 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:30 volumio-4 volumio[1366]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22The%20Best%20of%20Mozart%22%2C%22artist%22%3A%22ALIBI%20Music%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FRb0UmrCXxVA%2Fhqdefault.jpg%3Fsqp%3D-oaymwEWCMACELQBIAQqCghQEJADGFogjgJIWg%26rs%3DAMzJL3kiTeiRgxasqznYegA9R9ghoRO21A%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%7D%7D Jun 30 17:45:31 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:31 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand stop Jun 30 17:45:31 volumio-4 volumio[1366]: info: Jun 30 17:45:31 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:31 volumio-4 volumio[1366]: info: sendMpdCommand stop took 5 milliseconds Jun 30 17:45:31 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:31 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:31 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand clear Jun 30 17:45:31 volumio-4 volumio[1366]: info: Jun 30 17:45:31 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:31 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:31 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:31 volumio-4 volumio[1366]: info: Jun 30 17:45:31 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:31 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:31 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:31 volumio-4 volumio[1366]: info: Jun 30 17:45:31 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:31 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:31 volumio-4 volumio[1366]: info: sendMpdCommand status took 2 milliseconds Jun 30 17:45:31 volumio-4 volumio[1366]: info: sendMpdCommand clear took 2 milliseconds Jun 30 17:45:31 volumio-4 volumio[1366]: info: sendMpdCommand status took 1 milliseconds Jun 30 17:45:31 volumio-4 volumio[1366]: info: sendMpdCommand status took 0 milliseconds Jun 30 17:45:31 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:31 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:31 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319932&ei=HLFiaL-MB5KPsvQP1KXrmA8&ip=46.11.14.15&id=o-AIeIZmD-03klt3ATxXKgSSj_MygeK7Zcn9J0rgcJpc-h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298332%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLNOJNeY-gGHNKZckRw2OuYTKfiyvz8-qorw8JNVRb71xjunB-XUONT49heTyfSE16IeisFW8dXj&spc=l3OVKQpYSlYK78R9swIl9t9ljkvnacXy5jVCK97DlY5t-RqEeKd1jspZrzyV0lQIAe0&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=LvoYYp20LzQh37if_RanGkUQ&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=402iuLQ4vnUA3w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwQwIfU84Q50U2oi1IhqVbGvJoGJJfsimDOIuXK95eLIdvNQIgczO3kzbDtQ9iUObdUkjVj9QCmM_J0Mb0of2CR59ZRFw%3D&sig=AJfQdSswRQIgCflroSCpdZTJfGSrWQ-paSldUF7zkLAYQjY7zS26Ge0CIQDkdZridgV0D-T8GLo6lowOLPLUdiV5szVSDp_tkRbYow%3D%3D&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube" Jun 30 17:45:31 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:31 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:31 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:31 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:31 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:31 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:31 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 17:45:31 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:31 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:31 volumio-4 volumio[1366]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Jun 30 17:45:31 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:31 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:31 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:31 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:31 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:31 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:31 volumio-4 volumio[1366]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Jun 30 17:45:31 volumio-4 volumio[1366]: info: ------------------------------ 12ms Jun 30 17:45:31 volumio-4 volumio[1366]: info: ------------------------------ 12ms Jun 30 17:45:31 volumio-4 volumio[1366]: info: Jun 30 17:45:31 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:31 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:31 volumio-4 volumio[1366]: info: Jun 30 17:45:31 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:31 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:31 volumio-4 volumio[1366]: error: updateQueue error: null Jun 30 17:45:31 volumio-4 volumio[1366]: info: Jun 30 17:45:31 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:31 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:31 volumio-4 volumio[1366]: info: ------------------------------ 12ms Jun 30 17:45:31 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 30 17:45:31 volumio-4 volumio[1366]: info: sendMpdCommand addid "https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319932&ei=HLFiaL-MB5KPsvQP1KXrmA8&ip=46.11.14.15&id=o-AIeIZmD-03klt3ATxXKgSSj_MygeK7Zcn9J0rgcJpc-h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298332%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLNOJNeY-gGHNKZckRw2OuYTKfiyvz8-qorw8JNVRb71xjunB-XUONT49heTyfSE16IeisFW8dXj&spc=l3OVKQpYSlYK78R9swIl9t9ljkvnacXy5jVCK97DlY5t-RqEeKd1jspZrzyV0lQIAe0&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=LvoYYp20LzQh37if_RanGkUQ&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=402iuLQ4vnUA3w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwQwIfU84Q50U2oi1IhqVbGvJoGJJfsimDOIuXK95eLIdvNQIgczO3kzbDtQ9iUObdUkjVj9QCmM_J0Mb0of2CR59ZRFw%3D&sig=AJfQdSswRQIgCflroSCpdZTJfGSrWQ-paSldUF7zkLAYQjY7zS26Ge0CIQDkdZridgV0D-T8GLo6lowOLPLUdiV5szVSDp_tkRbYow%3D%3D&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube" took 11 milliseconds Jun 30 17:45:31 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:31 volumio-4 volumio[1366]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 30 17:45:31 volumio-4 volumio[1366]: verbose: MPD COMMAND [object Object] Jun 30 17:45:31 volumio-4 volumio[1366]: verbose: MPD COMMAND [object Object] Jun 30 17:45:31 volumio-4 volumio[1366]: info: ------------------------------ 15ms Jun 30 17:45:31 volumio-4 volumio[1366]: info: Jun 30 17:45:31 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:31 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:31 volumio-4 volumio[1366]: info: ------------------------------ 6ms Jun 30 17:45:31 volumio-4 volumio[1366]: info: ------------------------------ 5ms Jun 30 17:45:31 volumio-4 volumio[1366]: info: ------------------------------ 5ms Jun 30 17:45:31 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 30 17:45:31 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand play Jun 30 17:45:31 volumio-4 volumio[1366]: info: Jun 30 17:45:31 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:31 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:31 volumio-4 volumio[1366]: info: Jun 30 17:45:31 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:31 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:31 volumio-4 volumio[1366]: info: ------------------------------ 3ms Jun 30 17:45:31 volumio-4 volumio[1366]: info: sendMpdCommand play took 3 milliseconds Jun 30 17:45:31 volumio-4 volumio[1366]: info: ------------------------------ 3ms Jun 30 17:45:31 volumio-4 volumio[1366]: info: ------------------------------ 2ms Jun 30 17:45:31 volumio-4 volumio[1366]: info: Jun 30 17:45:31 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:31 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:31 volumio-4 volumio[1366]: info: Jun 30 17:45:31 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:31 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:31 volumio-4 volumio[1366]: info: ------------------------------ 1ms Jun 30 17:45:31 volumio-4 volumio[1366]: info: ------------------------------ 1ms Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand status took 3 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand status took 7 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand status took 7 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand clearerror took 5 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand status took 5 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand status took 4 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319932&ei=HLFiaL-MB5KPsvQP1KXrmA8&ip=46.11.14.15&id=o-AIeIZmD-03klt3ATxXKgSSj_MygeK7Zcn9J0rgcJpc-h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298332%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLNOJNeY-gGHNKZckRw2OuYTKfiyvz8-qorw8JNVRb71xjunB-XUONT49heTyfSE16IeisFW8dXj&spc=l3OVKQpYSlYK78R9swIl9t9ljkvnacXy5jVCK97DlY5t-RqEeKd1jspZrzyV0lQIAe0&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=LvoYYp20LzQh37if_RanGkUQ&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=402iuLQ4vnUA3w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwQwIfU84Q50U2oi1IhqVbGvJoGJJfsimDOIuXK95eLIdvNQIgczO3kzbDtQ9iUObdUkjVj9QCmM_J0Mb0of2CR59ZRFw%3D&sig=AJfQdSswRQIgCflroSCpdZTJfGSrWQ-paSldUF7zkLAYQjY7zS26Ge0CIQDkdZridgV0D-T8GLo6lowOLPLUdiV5szVSDp_tkRbYow%3D%3D&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 21ms Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand status took 20 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand clearerror took 18 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand clearerror took 17 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 16 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319932&ei=HLFiaL-MB5KPsvQP1KXrmA8&ip=46.11.14.15&id=o-AIeIZmD-03klt3ATxXKgSSj_MygeK7Zcn9J0rgcJpc-h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298332%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLNOJNeY-gGHNKZckRw2OuYTKfiyvz8-qorw8JNVRb71xjunB-XUONT49heTyfSE16IeisFW8dXj&spc=l3OVKQpYSlYK78R9swIl9t9ljkvnacXy5jVCK97DlY5t-RqEeKd1jspZrzyV0lQIAe0&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=LvoYYp20LzQh37if_RanGkUQ&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=402iuLQ4vnUA3w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwQwIfU84Q50U2oi1IhqVbGvJoGJJfsimDOIuXK95eLIdvNQIgczO3kzbDtQ9iUObdUkjVj9QCmM_J0Mb0of2CR59ZRFw%3D&sig=AJfQdSswRQIgCflroSCpdZTJfGSrWQ-paSldUF7zkLAYQjY7zS26Ge0CIQDkdZridgV0D-T8GLo6lowOLPLUdiV5szVSDp_tkRbYow%3D%3D&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319932&ei=HLFiaL-MB5KPsvQP1KXrmA8&ip=46.11.14.15&id=o-AIeIZmD-03klt3ATxXKgSSj_MygeK7Zcn9J0rgcJpc-h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298332%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLNOJNeY-gGHNKZckRw2OuYTKfiyvz8-qorw8JNVRb71xjunB-XUONT49heTyfSE16IeisFW8dXj&spc=l3OVKQpYSlYK78R9swIl9t9ljkvnacXy5jVCK97DlY5t-RqEeKd1jspZrzyV0lQIAe0&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=LvoYYp20LzQh37if_RanGkUQ&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=402iuLQ4vnUA3w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwQwIfU84Q50U2oi1IhqVbGvJoGJJfsimDOIuXK95eLIdvNQIgczO3kzbDtQ9iUObdUkjVj9QCmM_J0Mb0of2CR59ZRFw%3D&sig=AJfQdSswRQIgCflroSCpdZTJfGSrWQ-paSldUF7zkLAYQjY7zS26Ge0CIQDkdZridgV0D-T8GLo6lowOLPLUdiV5szVSDp_tkRbYow%3D%3D&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319932&ei=HLFiaL-MB5KPsvQP1KXrmA8&ip=46.11.14.15&id=o-AIeIZmD-03klt3ATxXKgSSj_MygeK7Zcn9J0rgcJpc-h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298332%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLNOJNeY-gGHNKZckRw2OuYTKfiyvz8-qorw8JNVRb71xjunB-XUONT49heTyfSE16IeisFW8dXj&spc=l3OVKQpYSlYK78R9swIl9t9ljkvnacXy5jVCK97DlY5t-RqEeKd1jspZrzyV0lQIAe0&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=LvoYYp20LzQh37if_RanGkUQ&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=402iuLQ4vnUA3w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwQwIfU84Q50U2oi1IhqVbGvJoGJJfsimDOIuXK95eLIdvNQIgczO3kzbDtQ9iUObdUkjVj9QCmM_J0Mb0of2CR59ZRFw%3D&sig=AJfQdSswRQIgCflroSCpdZTJfGSrWQ-paSldUF7zkLAYQjY7zS26Ge0CIQDkdZridgV0D-T8GLo6lowOLPLUdiV5szVSDp_tkRbYow%3D%3D&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319932&ei=HLFiaL-MB5KPsvQP1KXrmA8&ip=46.11.14.15&id=o-AIeIZmD-03klt3ATxXKgSSj_MygeK7Zcn9J0rgcJpc-h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298332%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLNOJNeY-gGHNKZckRw2OuYTKfiyvz8-qorw8JNVRb71xjunB-XUONT49heTyfSE16IeisFW8dXj&spc=l3OVKQpYSlYK78R9swIl9t9ljkvnacXy5jVCK97DlY5t-RqEeKd1jspZrzyV0lQIAe0&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=LvoYYp20LzQh37if_RanGkUQ&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=402iuLQ4vnUA3w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwQwIfU84Q50U2oi1IhqVbGvJoGJJfsimDOIuXK95eLIdvNQIgczO3kzbDtQ9iUObdUkjVj9QCmM_J0Mb0of2CR59ZRFw%3D&sig=AJfQdSswRQIgCflroSCpdZTJfGSrWQ-paSldUF7zkLAYQjY7zS26Ge0CIQDkdZridgV0D-T8GLo6lowOLPLUdiV5szVSDp_tkRbYow%3D%3D&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 46ms Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 45ms Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 45ms Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 43ms Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 26 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319932&ei=HLFiaL-MB5KPsvQP1KXrmA8&ip=46.11.14.15&id=o-AIeIZmD-03klt3ATxXKgSSj_MygeK7Zcn9J0rgcJpc-h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298332%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLNOJNeY-gGHNKZckRw2OuYTKfiyvz8-qorw8JNVRb71xjunB-XUONT49heTyfSE16IeisFW8dXj&spc=l3OVKQpYSlYK78R9swIl9t9ljkvnacXy5jVCK97DlY5t-RqEeKd1jspZrzyV0lQIAe0&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=LvoYYp20LzQh37if_RanGkUQ&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=402iuLQ4vnUA3w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwQwIfU84Q50U2oi1IhqVbGvJoGJJfsimDOIuXK95eLIdvNQIgczO3kzbDtQ9iUObdUkjVj9QCmM_J0Mb0of2CR59ZRFw%3D&sig=AJfQdSswRQIgCflroSCpdZTJfGSrWQ-paSldUF7zkLAYQjY7zS26Ge0CIQDkdZridgV0D-T8GLo6lowOLPLUdiV5szVSDp_tkRbYow%3D%3D&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 59ms Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand stop Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand stop took 7 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand clear Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:34 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand status took 2 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand clear took 2 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand status took 1 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand status took 1 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319935&ei=H7FiaLSXDKzJ9fgPrfqSoAg&ip=46.11.14.15&id=o-AKMDov034iH22o5-BTdcbkd6HOyIlDABrf_QD3Mrd2y3&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298335%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5e6nzs&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3296250&bui=AY1jyLP2qNH3y1hmUPmt5SxPR81oBGrDJOFPy1wltd54kbVcbXRvoJMqMTPWYoVXdummLd8BzV4S9_JA&spc=l3OVKYmNwl9OglPHU4dMegGq55j_skLPETLYFiNt7Re6DlQF-njy5rML1UsWxedud24&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fprOhp8roaDbreUgEj8gmNsQ&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751298086&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=zOg8rUULhwWQzg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAOXb0mZL5cDAwFyG5XT6N1KLjbW7RM6W8N8qenoMht4uAiEA-ajGJx7e5Ny5xsbZbQ6BCho53bs6TaKtA-nbq63XvqE%3D&sig=AJfQdSswRAIgdP5mTprguUuOJ6_bTqTK0k7dNgYHuLaV-B26n68nGkACIBvGWwWcTDx9yPh-wiOp60CxSoa0Q4d5VI5Wfghxt9Hp&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube" Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService stop Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: No code Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService stop Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: No code Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 37ms Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 37ms Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:34 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:34 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:34 volumio-4 volumio[1366]: error: updateQueue error: null Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:34 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 42ms Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 41 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand addid "https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319935&ei=H7FiaLSXDKzJ9fgPrfqSoAg&ip=46.11.14.15&id=o-AKMDov034iH22o5-BTdcbkd6HOyIlDABrf_QD3Mrd2y3&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298335%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5e6nzs&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3296250&bui=AY1jyLP2qNH3y1hmUPmt5SxPR81oBGrDJOFPy1wltd54kbVcbXRvoJMqMTPWYoVXdummLd8BzV4S9_JA&spc=l3OVKYmNwl9OglPHU4dMegGq55j_skLPETLYFiNt7Re6DlQF-njy5rML1UsWxedud24&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fprOhp8roaDbreUgEj8gmNsQ&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751298086&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=zOg8rUULhwWQzg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAOXb0mZL5cDAwFyG5XT6N1KLjbW7RM6W8N8qenoMht4uAiEA-ajGJx7e5Ny5xsbZbQ6BCho53bs6TaKtA-nbq63XvqE%3D&sig=AJfQdSswRAIgdP5mTprguUuOJ6_bTqTK0k7dNgYHuLaV-B26n68nGkACIBvGWwWcTDx9yPh-wiOp60CxSoa0Q4d5VI5Wfghxt9Hp&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube" took 41 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 6ms Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 2ms Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:34 volumio-4 volumio[1366]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: MPD COMMAND [object Object] Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: MPD COMMAND [object Object] Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 46ms Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:34 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:34 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:34 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 13ms Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 11ms Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 10ms Jun 30 17:45:34 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 30 17:45:34 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand play Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:34 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:34 volumio-4 volumio[1366]: info: Jun 30 17:45:34 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:34 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 3ms Jun 30 17:45:34 volumio-4 volumio[1366]: info: sendMpdCommand play took 2 milliseconds Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 1ms Jun 30 17:45:34 volumio-4 volumio[1366]: info: ------------------------------ 0ms Jun 30 17:45:35 volumio-4 volumio[1366]: info: Jun 30 17:45:35 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:35 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:35 volumio-4 volumio[1366]: info: Jun 30 17:45:35 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:35 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:35 volumio-4 volumio[1366]: info: Jun 30 17:45:35 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:35 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:35 volumio-4 volumio[1366]: info: Jun 30 17:45:35 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:35 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 17:45:35 volumio-4 volumio[1366]: info: sendMpdCommand status took 3 milliseconds Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:35 volumio-4 volumio[1366]: info: Jun 30 17:45:35 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:35 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 17:45:35 volumio-4 volumio[1366]: info: sendMpdCommand status took 11 milliseconds Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 17:45:35 volumio-4 volumio[1366]: info: sendMpdCommand status took 11 milliseconds Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 17:45:35 volumio-4 volumio[1366]: info: sendMpdCommand status took 11 milliseconds Jun 30 17:45:35 volumio-4 volumio[1366]: info: sendMpdCommand clearerror took 9 milliseconds Jun 30 17:45:35 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:35 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319935&ei=H7FiaLSXDKzJ9fgPrfqSoAg&ip=46.11.14.15&id=o-AKMDov034iH22o5-BTdcbkd6HOyIlDABrf_QD3Mrd2y3&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298335%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5e6nzs&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3296250&bui=AY1jyLP2qNH3y1hmUPmt5SxPR81oBGrDJOFPy1wltd54kbVcbXRvoJMqMTPWYoVXdummLd8BzV4S9_JA&spc=l3OVKYmNwl9OglPHU4dMegGq55j_skLPETLYFiNt7Re6DlQF-njy5rML1UsWxedud24&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fprOhp8roaDbreUgEj8gmNsQ&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751298086&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=zOg8rUULhwWQzg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAOXb0mZL5cDAwFyG5XT6N1KLjbW7RM6W8N8qenoMht4uAiEA-ajGJx7e5Ny5xsbZbQ6BCho53bs6TaKtA-nbq63XvqE%3D&sig=AJfQdSswRAIgdP5mTprguUuOJ6_bTqTK0k7dNgYHuLaV-B26n68nGkACIBvGWwWcTDx9yPh-wiOp60CxSoa0Q4d5VI5Wfghxt9Hp&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: ------------------------------ 24ms Jun 30 17:45:35 volumio-4 volumio[1366]: info: Jun 30 17:45:35 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:35 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:35 volumio-4 volumio[1366]: info: sendMpdCommand status took 25 milliseconds Jun 30 17:45:35 volumio-4 volumio[1366]: info: sendMpdCommand clearerror took 19 milliseconds Jun 30 17:45:35 volumio-4 volumio[1366]: info: sendMpdCommand clearerror took 19 milliseconds Jun 30 17:45:35 volumio-4 volumio[1366]: info: sendMpdCommand clearerror took 19 milliseconds Jun 30 17:45:35 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 30 17:45:35 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 30 17:45:35 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:35 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319935&ei=H7FiaLSXDKzJ9fgPrfqSoAg&ip=46.11.14.15&id=o-AKMDov034iH22o5-BTdcbkd6HOyIlDABrf_QD3Mrd2y3&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298335%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5e6nzs&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3296250&bui=AY1jyLP2qNH3y1hmUPmt5SxPR81oBGrDJOFPy1wltd54kbVcbXRvoJMqMTPWYoVXdummLd8BzV4S9_JA&spc=l3OVKYmNwl9OglPHU4dMegGq55j_skLPETLYFiNt7Re6DlQF-njy5rML1UsWxedud24&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fprOhp8roaDbreUgEj8gmNsQ&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751298086&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=zOg8rUULhwWQzg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAOXb0mZL5cDAwFyG5XT6N1KLjbW7RM6W8N8qenoMht4uAiEA-ajGJx7e5Ny5xsbZbQ6BCho53bs6TaKtA-nbq63XvqE%3D&sig=AJfQdSswRAIgdP5mTprguUuOJ6_bTqTK0k7dNgYHuLaV-B26n68nGkACIBvGWwWcTDx9yPh-wiOp60CxSoa0Q4d5VI5Wfghxt9Hp&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319935&ei=H7FiaLSXDKzJ9fgPrfqSoAg&ip=46.11.14.15&id=o-AKMDov034iH22o5-BTdcbkd6HOyIlDABrf_QD3Mrd2y3&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298335%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5e6nzs&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3296250&bui=AY1jyLP2qNH3y1hmUPmt5SxPR81oBGrDJOFPy1wltd54kbVcbXRvoJMqMTPWYoVXdummLd8BzV4S9_JA&spc=l3OVKYmNwl9OglPHU4dMegGq55j_skLPETLYFiNt7Re6DlQF-njy5rML1UsWxedud24&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fprOhp8roaDbreUgEj8gmNsQ&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751298086&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=zOg8rUULhwWQzg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAOXb0mZL5cDAwFyG5XT6N1KLjbW7RM6W8N8qenoMht4uAiEA-ajGJx7e5Ny5xsbZbQ6BCho53bs6TaKtA-nbq63XvqE%3D&sig=AJfQdSswRAIgdP5mTprguUuOJ6_bTqTK0k7dNgYHuLaV-B26n68nGkACIBvGWwWcTDx9yPh-wiOp60CxSoa0Q4d5VI5Wfghxt9Hp&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319935&ei=H7FiaLSXDKzJ9fgPrfqSoAg&ip=46.11.14.15&id=o-AKMDov034iH22o5-BTdcbkd6HOyIlDABrf_QD3Mrd2y3&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298335%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5e6nzs&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3296250&bui=AY1jyLP2qNH3y1hmUPmt5SxPR81oBGrDJOFPy1wltd54kbVcbXRvoJMqMTPWYoVXdummLd8BzV4S9_JA&spc=l3OVKYmNwl9OglPHU4dMegGq55j_skLPETLYFiNt7Re6DlQF-njy5rML1UsWxedud24&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fprOhp8roaDbreUgEj8gmNsQ&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751298086&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=zOg8rUULhwWQzg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAOXb0mZL5cDAwFyG5XT6N1KLjbW7RM6W8N8qenoMht4uAiEA-ajGJx7e5Ny5xsbZbQ6BCho53bs6TaKtA-nbq63XvqE%3D&sig=AJfQdSswRAIgdP5mTprguUuOJ6_bTqTK0k7dNgYHuLaV-B26n68nGkACIBvGWwWcTDx9yPh-wiOp60CxSoa0Q4d5VI5Wfghxt9Hp&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: ------------------------------ 47ms Jun 30 17:45:35 volumio-4 volumio[1366]: info: ------------------------------ 47ms Jun 30 17:45:35 volumio-4 volumio[1366]: info: ------------------------------ 46ms Jun 30 17:45:35 volumio-4 volumio[1366]: info: sendMpdCommand status took 19 milliseconds Jun 30 17:45:35 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 18 milliseconds Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:35 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319935&ei=H7FiaLSXDKzJ9fgPrfqSoAg&ip=46.11.14.15&id=o-AKMDov034iH22o5-BTdcbkd6HOyIlDABrf_QD3Mrd2y3&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298335%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5e6nzs&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3296250&bui=AY1jyLP2qNH3y1hmUPmt5SxPR81oBGrDJOFPy1wltd54kbVcbXRvoJMqMTPWYoVXdummLd8BzV4S9_JA&spc=l3OVKYmNwl9OglPHU4dMegGq55j_skLPETLYFiNt7Re6DlQF-njy5rML1UsWxedud24&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fprOhp8roaDbreUgEj8gmNsQ&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751298086&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=zOg8rUULhwWQzg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAOXb0mZL5cDAwFyG5XT6N1KLjbW7RM6W8N8qenoMht4uAiEA-ajGJx7e5Ny5xsbZbQ6BCho53bs6TaKtA-nbq63XvqE%3D&sig=AJfQdSswRAIgdP5mTprguUuOJ6_bTqTK0k7dNgYHuLaV-B26n68nGkACIBvGWwWcTDx9yPh-wiOp60CxSoa0Q4d5VI5Wfghxt9Hp&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: ------------------------------ 50ms Jun 30 17:45:35 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:35 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319935&ei=H7FiaLSXDKzJ9fgPrfqSoAg&ip=46.11.14.15&id=o-AKMDov034iH22o5-BTdcbkd6HOyIlDABrf_QD3Mrd2y3&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298335%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5e6nzs&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3296250&bui=AY1jyLP2qNH3y1hmUPmt5SxPR81oBGrDJOFPy1wltd54kbVcbXRvoJMqMTPWYoVXdummLd8BzV4S9_JA&spc=l3OVKYmNwl9OglPHU4dMegGq55j_skLPETLYFiNt7Re6DlQF-njy5rML1UsWxedud24&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fprOhp8roaDbreUgEj8gmNsQ&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751298086&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=zOg8rUULhwWQzg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAOXb0mZL5cDAwFyG5XT6N1KLjbW7RM6W8N8qenoMht4uAiEA-ajGJx7e5Ny5xsbZbQ6BCho53bs6TaKtA-nbq63XvqE%3D&sig=AJfQdSswRAIgdP5mTprguUuOJ6_bTqTK0k7dNgYHuLaV-B26n68nGkACIBvGWwWcTDx9yPh-wiOp60CxSoa0Q4d5VI5Wfghxt9Hp&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:35 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 17:45:35 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:35 volumio-4 volumio[1366]: info: ------------------------------ 42ms Jun 30 17:45:37 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand stop Jun 30 17:45:37 volumio-4 volumio[1366]: info: Jun 30 17:45:37 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:37 volumio-4 volumio[1366]: info: sendMpdCommand stop took 36 milliseconds Jun 30 17:45:37 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:37 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:37 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand clear Jun 30 17:45:37 volumio-4 volumio[1366]: info: Jun 30 17:45:37 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:37 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:37 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:37 volumio-4 volumio[1366]: info: Jun 30 17:45:37 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:37 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:37 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:38 volumio-4 volumio[1366]: info: Jun 30 17:45:38 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:38 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand status took 514 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand clear took 514 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand status took 513 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand status took 514 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319938&ei=IrFiaKDiEPCJzPsP-bKR0AY&ip=46.11.14.15&id=o-AK5BgND4aXqGHHHG7_POjkI_nxmpNSAe11ltNiMMerUY&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298338%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLPS_SD6ptlMNAtNpreEsivA2I7K6r11tL4bwq3enTDxZrKusxgNLPEe8_gcHRS8If6zABbRXWee&spc=l3OVKQsQzwidrjJWZYfiEmk4znksVQQbFuQ05AjXiI_g7JuX7ykt4CNA25GPwlTziQw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=km13o6Yu5qEUwvhKae35d28Q&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=JTqG6rG0W7QWvQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAL4gHCh-xZ4wHgUd8Z0StOaCsWo8qDFvddkYUJIJMGvMAiEAxQefxwhkCvSHuNTU81bxZzdQVgaS65-SM3PXIgR0mwk%3D&sig=AJfQdSswRAIgAsaJfzub8_uuAnT0db1N2sZCzNUWtmC_D2zal9afTiICIAzbD4CIb7O3Bap5fINAeLa1aUynG2r9iED8zf2lDie8&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube" Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:38 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService stop Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: No code Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService stop Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: No code Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 536ms Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 537ms Jun 30 17:45:38 volumio-4 volumio[1366]: info: Jun 30 17:45:38 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:38 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:38 volumio-4 volumio[1366]: info: Jun 30 17:45:38 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:38 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:38 volumio-4 volumio[1366]: error: updateQueue error: null Jun 30 17:45:38 volumio-4 volumio[1366]: info: Jun 30 17:45:38 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:38 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 25ms Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 24 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand addid "https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319938&ei=IrFiaKDiEPCJzPsP-bKR0AY&ip=46.11.14.15&id=o-AK5BgND4aXqGHHHG7_POjkI_nxmpNSAe11ltNiMMerUY&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298338%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLPS_SD6ptlMNAtNpreEsivA2I7K6r11tL4bwq3enTDxZrKusxgNLPEe8_gcHRS8If6zABbRXWee&spc=l3OVKQsQzwidrjJWZYfiEmk4znksVQQbFuQ05AjXiI_g7JuX7ykt4CNA25GPwlTziQw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=km13o6Yu5qEUwvhKae35d28Q&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=JTqG6rG0W7QWvQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAL4gHCh-xZ4wHgUd8Z0StOaCsWo8qDFvddkYUJIJMGvMAiEAxQefxwhkCvSHuNTU81bxZzdQVgaS65-SM3PXIgR0mwk%3D&sig=AJfQdSswRAIgAsaJfzub8_uuAnT0db1N2sZCzNUWtmC_D2zal9afTiICIAzbD4CIb7O3Bap5fINAeLa1aUynG2r9iED8zf2lDie8&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube" took 24 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 1ms Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 1ms Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:38 volumio-4 volumio[1366]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: MPD COMMAND [object Object] Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: MPD COMMAND [object Object] Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 541ms Jun 30 17:45:38 volumio-4 volumio[1366]: info: Jun 30 17:45:38 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:38 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:38 volumio-4 volumio[1366]: info: Jun 30 17:45:38 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:38 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 11ms Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 10ms Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand play Jun 30 17:45:38 volumio-4 volumio[1366]: info: Jun 30 17:45:38 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:38 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:38 volumio-4 volumio[1366]: info: Jun 30 17:45:38 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:38 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 4ms Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand play took 3 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 2ms Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 2ms Jun 30 17:45:38 volumio-4 volumio[1366]: info: Jun 30 17:45:38 volumio-4 volumio[1366]: ---------------------------- MPD announces system playlist update Jun 30 17:45:38 volumio-4 volumio[1366]: info: Ignoring MPD Status Update Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 0ms Jun 30 17:45:38 volumio-4 volumio[1366]: info: Jun 30 17:45:38 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:38 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:38 volumio-4 volumio[1366]: info: Jun 30 17:45:38 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:38 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:38 volumio-4 volumio[1366]: info: Jun 30 17:45:38 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:38 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:38 volumio-4 volumio[1366]: info: Jun 30 17:45:38 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:38 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:38 volumio-4 volumio[1366]: info: Jun 30 17:45:38 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand status took 11 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand status took 11 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand status took 11 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand status took 11 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:38 volumio-4 volumio[1366]: info: Jun 30 17:45:38 volumio-4 volumio[1366]: ---------------------------- MPD announces state update: player Jun 30 17:45:38 volumio-4 volumio[1366]: info: ControllerMpd::getState Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand status Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand clearerror took 14 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand status took 11 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand clearerror took 11 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand clearerror took 12 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand clearerror took 12 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand status took 3 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::parseState Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 17:45:38 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319938&ei=IrFiaKDiEPCJzPsP-bKR0AY&ip=46.11.14.15&id=o-AK5BgND4aXqGHHHG7_POjkI_nxmpNSAe11ltNiMMerUY&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298338%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLPS_SD6ptlMNAtNpreEsivA2I7K6r11tL4bwq3enTDxZrKusxgNLPEe8_gcHRS8If6zABbRXWee&spc=l3OVKQsQzwidrjJWZYfiEmk4znksVQQbFuQ05AjXiI_g7JuX7ykt4CNA25GPwlTziQw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=km13o6Yu5qEUwvhKae35d28Q&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=JTqG6rG0W7QWvQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAL4gHCh-xZ4wHgUd8Z0StOaCsWo8qDFvddkYUJIJMGvMAiEAxQefxwhkCvSHuNTU81bxZzdQVgaS65-SM3PXIgR0mwk%3D&sig=AJfQdSswRAIgAsaJfzub8_uuAnT0db1N2sZCzNUWtmC_D2zal9afTiICIAzbD4CIb7O3Bap5fINAeLa1aUynG2r9iED8zf2lDie8&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319938&ei=IrFiaKDiEPCJzPsP-bKR0AY&ip=46.11.14.15&id=o-AK5BgND4aXqGHHHG7_POjkI_nxmpNSAe11ltNiMMerUY&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298338%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLPS_SD6ptlMNAtNpreEsivA2I7K6r11tL4bwq3enTDxZrKusxgNLPEe8_gcHRS8If6zABbRXWee&spc=l3OVKQsQzwidrjJWZYfiEmk4znksVQQbFuQ05AjXiI_g7JuX7ykt4CNA25GPwlTziQw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=km13o6Yu5qEUwvhKae35d28Q&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=JTqG6rG0W7QWvQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAL4gHCh-xZ4wHgUd8Z0StOaCsWo8qDFvddkYUJIJMGvMAiEAxQefxwhkCvSHuNTU81bxZzdQVgaS65-SM3PXIgR0mwk%3D&sig=AJfQdSswRAIgAsaJfzub8_uuAnT0db1N2sZCzNUWtmC_D2zal9afTiICIAzbD4CIb7O3Bap5fINAeLa1aUynG2r9iED8zf2lDie8&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319938&ei=IrFiaKDiEPCJzPsP-bKR0AY&ip=46.11.14.15&id=o-AK5BgND4aXqGHHHG7_POjkI_nxmpNSAe11ltNiMMerUY&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298338%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLPS_SD6ptlMNAtNpreEsivA2I7K6r11tL4bwq3enTDxZrKusxgNLPEe8_gcHRS8If6zABbRXWee&spc=l3OVKQsQzwidrjJWZYfiEmk4znksVQQbFuQ05AjXiI_g7JuX7ykt4CNA25GPwlTziQw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=km13o6Yu5qEUwvhKae35d28Q&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=JTqG6rG0W7QWvQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAL4gHCh-xZ4wHgUd8Z0StOaCsWo8qDFvddkYUJIJMGvMAiEAxQefxwhkCvSHuNTU81bxZzdQVgaS65-SM3PXIgR0mwk%3D&sig=AJfQdSswRAIgAsaJfzub8_uuAnT0db1N2sZCzNUWtmC_D2zal9afTiICIAzbD4CIb7O3Bap5fINAeLa1aUynG2r9iED8zf2lDie8&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319938&ei=IrFiaKDiEPCJzPsP-bKR0AY&ip=46.11.14.15&id=o-AK5BgND4aXqGHHHG7_POjkI_nxmpNSAe11ltNiMMerUY&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298338%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLPS_SD6ptlMNAtNpreEsivA2I7K6r11tL4bwq3enTDxZrKusxgNLPEe8_gcHRS8If6zABbRXWee&spc=l3OVKQsQzwidrjJWZYfiEmk4znksVQQbFuQ05AjXiI_g7JuX7ykt4CNA25GPwlTziQw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=km13o6Yu5qEUwvhKae35d28Q&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=JTqG6rG0W7QWvQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAL4gHCh-xZ4wHgUd8Z0StOaCsWo8qDFvddkYUJIJMGvMAiEAxQefxwhkCvSHuNTU81bxZzdQVgaS65-SM3PXIgR0mwk%3D&sig=AJfQdSswRAIgAsaJfzub8_uuAnT0db1N2sZCzNUWtmC_D2zal9afTiICIAzbD4CIb7O3Bap5fINAeLa1aUynG2r9iED8zf2lDie8&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 59ms Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 57ms Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 57ms Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 56ms Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 42 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: info: sendMpdCommand playlistinfo took 41 milliseconds Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: ControllerMpd::parseTrackInfo Jun 30 17:45:38 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319938&ei=IrFiaKDiEPCJzPsP-bKR0AY&ip=46.11.14.15&id=o-AK5BgND4aXqGHHHG7_POjkI_nxmpNSAe11ltNiMMerUY&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298338%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLPS_SD6ptlMNAtNpreEsivA2I7K6r11tL4bwq3enTDxZrKusxgNLPEe8_gcHRS8If6zABbRXWee&spc=l3OVKQsQzwidrjJWZYfiEmk4znksVQQbFuQ05AjXiI_g7JuX7ykt4CNA25GPwlTziQw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=km13o6Yu5qEUwvhKae35d28Q&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=JTqG6rG0W7QWvQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAL4gHCh-xZ4wHgUd8Z0StOaCsWo8qDFvddkYUJIJMGvMAiEAxQefxwhkCvSHuNTU81bxZzdQVgaS65-SM3PXIgR0mwk%3D&sig=AJfQdSswRAIgAsaJfzub8_uuAnT0db1N2sZCzNUWtmC_D2zal9afTiICIAzbD4CIb7O3Bap5fINAeLa1aUynG2r9iED8zf2lDie8&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: ControllerMpd::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6961,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Best of Mozart","artist":"ALIBI Music","album":null,"uri":"https://rr1---sn-q5u5nugx5h-ha5s.googlevideo.com/videoplayback?expire=1751319938&ei=IrFiaKDiEPCJzPsP-bKR0AY&ip=46.11.14.15&id=o-AK5BgND4aXqGHHHG7_POjkI_nxmpNSAe11ltNiMMerUY&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751298338%2C&mh=nU&mm=31%2C29&mn=sn-q5u5nugx5h-ha5s%2Csn-4g5edndz&ms=au%2Crdu&mv=m&mvi=1&pl=24&rms=au%2Cau&initcwndbps=3078750&bui=AY1jyLPS_SD6ptlMNAtNpreEsivA2I7K6r11tL4bwq3enTDxZrKusxgNLPEe8_gcHRS8If6zABbRXWee&spc=l3OVKQsQzwidrjJWZYfiEmk4znksVQQbFuQ05AjXiI_g7JuX7ykt4CNA25GPwlTziQw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=km13o6Yu5qEUwvhKae35d28Q&rqh=1&gir=yes&clen=121078870&dur=6961.041&lmt=1725579280377094&mt=1751297856&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=JTqG6rG0W7QWvQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAL4gHCh-xZ4wHgUd8Z0StOaCsWo8qDFvddkYUJIJMGvMAiEAxQefxwhkCvSHuNTU81bxZzdQVgaS65-SM3PXIgR0mwk%3D&sig=AJfQdSswRAIgAsaJfzub8_uuAnT0db1N2sZCzNUWtmC_D2zal9afTiICIAzbD4CIb7O3Bap5fINAeLa1aUynG2r9iED8zf2lDie8&pot=MpgBC8oh2qtv5mcL9op7gW_24TI613U9fsxUUBG9_fpqmiXN-0rmLxo5f7sbUA4peF7a1C9Mqfq320WRtPwLRSEZQSzG50T4MuDNq4IsxjbAcfLWv-1v3mKc9sUt9waVEA9cJXsRGnXCgBMwR8VwSP9lM9L4o5Erk0xV0YjGKy1vJx3o-16AYzxNq3iZ5RBMLorTI10YVItGOzA%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 30 17:45:38 volumio-4 volumio[1366]: verbose: CURRENT POSITION 0 Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::syncState stateService pause Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreStateMachine::pushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 76ms Jun 30 17:45:38 volumio-4 volumio[1366]: info: ------------------------------ 62ms Jun 30 17:45:39 volumio-4 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 30 17:45:39 volumio-4 volumio[1366]: info: Listing playlists Jun 30 17:45:39 volumio-4 volumio[1366]: info: Listing playlists Jun 30 17:45:41 volumio-4 volumio[1366]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 17:45:41 volumio-4 volumio[1366]: TypeError: fetch failed Jun 30 17:45:41 volumio-4 volumio[1366]: at HTTPClient.fetch (/data/plugins/music_service/ytmusic/node_modules/undici/index.js:112:15) Jun 30 17:45:41 volumio-4 volumio[1366]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 30 17:45:41 volumio-4 volumio[1366]: at async HTTPClient.fetch (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:8350:22) Jun 30 17:45:41 volumio-4 volumio[1366]: at async Actions.stats (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:26251:12) Jun 30 17:45:41 volumio-4 volumio[1366]: at async TrackInfo.addToWatchHistory (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:23293:12) { Jun 30 17:45:41 volumio-4 volumio[1366]: cause: ConnectTimeoutError: Connect Timeout Error Jun 30 17:45:41 volumio-4 volumio[1366]: at onConnectTimeout (/data/plugins/music_service/ytmusic/node_modules/undici/lib/core/connect.js:186:24) Jun 30 17:45:41 volumio-4 volumio[1366]: at /data/plugins/music_service/ytmusic/node_modules/undici/lib/core/connect.js:133:46 Jun 30 17:45:41 volumio-4 volumio[1366]: at Immediate._onImmediate (/data/plugins/music_service/ytmusic/node_modules/undici/lib/core/connect.js:174:9) Jun 30 17:45:41 volumio-4 volumio[1366]: at process.processImmediate (node:internal/timers:478:21) { Jun 30 17:45:41 volumio-4 volumio[1366]: code: 'UND_ERR_CONNECT_TIMEOUT' Jun 30 17:45:41 volumio-4 volumio[1366]: } Jun 30 17:45:41 volumio-4 volumio[1366]: } Jun 30 17:45:41 volumio-4 volumio[1366]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 17:45:41 volumio-4 sudo[3098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-30 17:44' Jun 30 17:45:41 volumio-4 sudo[3098]: 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="f9454895cc8e2f1971c705a815a7226e021e37d7" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1fc282af1865fae9b40239cedf2cc17c112eef0d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 28 16:48:46 UTC 2025" VOLUMIO_VERSION="4.014" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1b557e4869cd2eea14f4113140ecea1c"