Jun 27 11:44:00 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 468. Jun 27 11:44:00 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:00 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:00 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:02 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 469. Jun 27 11:44:02 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:02 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:02 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:02 volumio ntpd[818]: CLOCK: time stepped by -0.169903 Jun 27 11:44:02 volumio ntpd[818]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jun 27 11:44:04 volumio volumio[1114]: info: Adding ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Stretch%20-%20Why%20Did%20You%20Do%20It%20%E2%80%A2%20TopPop%22%2C%22artist%22%3A%22TopPop%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fsmg2S8CssQU%2Fhq720.jpg%3Fsqp%3D-oaymwEXCKAGEMIDIAQqCwjVARCqCBh4INgESFo%26rs%3DAMzJL3mPWCTOziyKRoZEL3-Gq5bu4uc4Og%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22smg2S8CssQU%22%2C%22playlistId%22%3A%22RDAMVMsmg2S8CssQU%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22smg2S8CssQU%22%2C%22playlistId%22%3A%22RDAMVMsmg2S8CssQU%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D to favourites using generic method Jun 27 11:44:04 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 470. Jun 27 11:44:04 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:04 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: ytmusic , explodeUri Jun 27 11:44:04 volumio volumio[1114]: info: [ytmusic-browse] explodeUri: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Stretch%20-%20Why%20Did%20You%20Do%20It%20%E2%80%A2%20TopPop%22%2C%22artist%22%3A%22TopPop%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fsmg2S8CssQU%2Fhq720.jpg%3Fsqp%3D-oaymwEXCKAGEMIDIAQqCwjVARCqCBh4INgESFo%26rs%3DAMzJL3mPWCTOziyKRoZEL3-Gq5bu4uc4Og%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22smg2S8CssQU%22%2C%22playlistId%22%3A%22RDAMVMsmg2S8CssQU%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22smg2S8CssQU%22%2C%22playlistId%22%3A%22RDAMVMsmg2S8CssQU%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Jun 27 11:44:04 volumio volumio[1114]: info: Saving local item favourites Jun 27 11:44:04 volumio volumio[1114]: info: Pushing Favourites {"uri":"ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Stretch%20-%20Why%20Did%20You%20Do%20It%20%E2%80%A2%20TopPop%22%2C%22artist%22%3A%22TopPop%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fsmg2S8CssQU%2Fhq720.jpg%3Fsqp%3D-oaymwEXCKAGEMIDIAQqCwjVARCqCBh4INgESFo%26rs%3DAMzJL3mPWCTOziyKRoZEL3-Gq5bu4uc4Og%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22smg2S8CssQU%22%2C%22playlistId%22%3A%22RDAMVMsmg2S8CssQU%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22smg2S8CssQU%22%2C%22playlistId%22%3A%22RDAMVMsmg2S8CssQU%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D","favourite":true} Jun 27 11:44:04 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:04 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:06 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 471. Jun 27 11:44:06 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:06 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:06 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:09 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 472. Jun 27 11:44:09 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:09 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:09 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:11 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 473. Jun 27 11:44:11 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:11 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:11 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:13 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 474. Jun 27 11:44:13 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:13 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:13 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:14 volumio volumio[1114]: info: CoreCommandRouter::volumioSeek Jun 27 11:44:14 volumio volumio[1114]: info: CoreStateMachine::seek Jun 27 11:44:14 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Jun 27 11:44:14 volumio volumio[1114]: info: TRACKBLOCK {"service":"ytmusic","uri":"ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Stretch%20-%20Why%20Did%20You%20Do%20It%20%E2%80%A2%20TopPop%22%2C%22artist%22%3A%22TopPop%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fsmg2S8CssQU%2Fhq720.jpg%3Fsqp%3D-oaymwEXCKAGEMIDIAQqCwjVARCqCBh4INgESFo%26rs%3DAMzJL3mPWCTOziyKRoZEL3-Gq5bu4uc4Og%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22smg2S8CssQU%22%2C%22playlistId%22%3A%22RDAMVMsmg2S8CssQU%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22smg2S8CssQU%22%2C%22playlistId%22%3A%22RDAMVMsmg2S8CssQU%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D","albumart":"https://i.ytimg.com/vi/smg2S8CssQU/hq720.jpg?sqp=-oaymwEXCNUGEOADIAQqCwjVARCqCBh4INgESFo&rs=AOn4CLBNJHuV_lV5ZOW8fyj9d6-kMD-WFg","artist":"TopPop","album":"","name":"Stretch - Why Did You Do It • TopPop","title":"Stretch - Why Did You Do It • TopPop","duration":207,"samplerate":"128 kbps"} Jun 27 11:44:14 volumio volumio[1114]: info: CoreStateMachine::startPlaybackTimer Jun 27 11:44:14 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Jun 27 11:44:14 volumio volumio[1114]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 27 11:44:14 volumio volumio[1114]: info: ControllerMpd::seek Jun 27 11:44:14 volumio volumio[1114]: info: CoreStateMachine::pushState Jun 27 11:44:14 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 11:44:14 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Jun 27 11:44:14 volumio volumio[1114]: error: null Jun 27 11:44:14 volumio volumio[1114]: info: Jun 27 11:44:14 volumio volumio[1114]: ---------------------------- MPD announces state update: player Jun 27 11:44:14 volumio volumio[1114]: info: ControllerMpd::getState Jun 27 11:44:14 volumio volumio[1114]: verbose: ControllerMpd::sendMpdCommand status Jun 27 11:44:14 volumio volumio[1114]: info: Jun 27 11:44:14 volumio volumio[1114]: ---------------------------- MPD announces state update: player Jun 27 11:44:14 volumio volumio[1114]: info: ControllerMpd::getState Jun 27 11:44:14 volumio volumio[1114]: verbose: ControllerMpd::sendMpdCommand status Jun 27 11:44:14 volumio volumio[1114]: info: sendMpdCommand status took 11 milliseconds Jun 27 11:44:14 volumio volumio[1114]: info: sendMpdCommand status took 9 milliseconds Jun 27 11:44:14 volumio volumio[1114]: verbose: ControllerMpd::parseState Jun 27 11:44:14 volumio volumio[1114]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 11:44:14 volumio volumio[1114]: verbose: ControllerMpd::parseState Jun 27 11:44:14 volumio volumio[1114]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 11:44:14 volumio volumio[1114]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 27 11:44:14 volumio volumio[1114]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 27 11:44:14 volumio volumio[1114]: verbose: ControllerMpd::parseTrackInfo Jun 27 11:44:14 volumio volumio[1114]: verbose: ControllerMpd::parseTrackInfo Jun 27 11:44:14 volumio volumio[1114]: info: ControllerMpd::pushState Jun 27 11:44:14 volumio volumio[1114]: info: CoreCommandRouter::servicePushState Jun 27 11:44:14 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Jun 27 11:44:14 volumio volumio[1114]: verbose: STATE SERVICE {"status":"play","position":0,"seek":115984,"duration":207,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6 Kbps","isStreaming":false,"title":"Stretch - Why Did You Do It • TopPop","artist":"TopPop","album":null,"uri":"https://rr1---sn-uhvcpaxoa-5hne.googlevideo.com/videoplayback?expire=1751038810&ei=-mZeaJvWLurp6dsPu8X0yAw&ip=87.209.40.189&id=o-AIzZtLo48_znqYygMDIQWOsaBdYplQixmRwYv4nu-MNd&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751017210%2C&mh=6k&mm=31%2C29&mn=sn-uhvcpaxoa-5hne%2Csn-5hne6nzd&ms=au%2Crdu&mv=m&mvi=1&pcm2cms=yes&pl=25&rms=au%2Cau&initcwndbps=4961250&siu=1&bui=AY1jyLPLlPnUH9dD7M9T3EY9-8IhaCL4h4iNFpMcsPQEP0sESB6K6KCyLTKsbPFS_6aP9xv2ww&spc=l3OVKXglznCqAaFTrBcgVzIzzGHKuGMzsUkqBpGWT7wZBFB4UigqBUqagF0nZrdbewwGYLRw8I34QcDBxU0&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=HUx4YUdVhzEEkYMlTAlE1aYQ&rqh=1&gir=yes&clen=3352071&dur=207.075&lmt=1750458692505526&mt=1751016774&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5432534&n=TlR8H4JkRrFLug&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhALGddOwttCrE0w-oKIG5HJMzfxIgJZ5KpexFB7_JeOyvAiA_GAsyA-uLfLcgOz52H9l7uelV20fNAvamWmzAuJ87jA%3D%3D&sig=AJfQdSswRQIhAJVp_rDBXpA2fywJ_qAY66OybEyKxCGEDvIYoCG3seB1AiBXmyYv3iTcPN5wmvdLhiZZZFUdmpKjuOm26juaKAlOnQ%3D%3D&pot=Ml8dWUwMGBIhkR1lHE2PIbX8QRV7LRzFQGVevVKjIhnOVZhngK0ofC6FcopMLF1j0sjxujs4udAIBdBWwpwWqRarBYZ_VRbv12YTBLoi5SWrZioD2AUxXJRlbZpe_1t0Lw%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 27 11:44:14 volumio volumio[1114]: verbose: CURRENT POSITION 0 Jun 27 11:44:14 volumio volumio[1114]: info: CoreStateMachine::syncState stateService play Jun 27 11:44:14 volumio volumio[1114]: info: CoreStateMachine::syncState currentStatus play Jun 27 11:44:14 volumio volumio[1114]: info: Received an update from plugin. extracting info from payload Jun 27 11:44:14 volumio volumio[1114]: info: CoreStateMachine::pushState Jun 27 11:44:14 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 11:44:14 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Jun 27 11:44:14 volumio volumio[1114]: info: CoreStateMachine::pushState Jun 27 11:44:14 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Jun 27 11:44:14 volumio volumio[1114]: info: ControllerMpd::pushState Jun 27 11:44:14 volumio volumio[1114]: info: CoreCommandRouter::servicePushState Jun 27 11:44:14 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Jun 27 11:44:14 volumio volumio[1114]: verbose: STATE SERVICE {"status":"play","position":0,"seek":115984,"duration":207,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6 Kbps","isStreaming":false,"title":"Stretch - Why Did You Do It • TopPop","artist":"TopPop","album":null,"uri":"https://rr1---sn-uhvcpaxoa-5hne.googlevideo.com/videoplayback?expire=1751038810&ei=-mZeaJvWLurp6dsPu8X0yAw&ip=87.209.40.189&id=o-AIzZtLo48_znqYygMDIQWOsaBdYplQixmRwYv4nu-MNd&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1751017210%2C&mh=6k&mm=31%2C29&mn=sn-uhvcpaxoa-5hne%2Csn-5hne6nzd&ms=au%2Crdu&mv=m&mvi=1&pcm2cms=yes&pl=25&rms=au%2Cau&initcwndbps=4961250&siu=1&bui=AY1jyLPLlPnUH9dD7M9T3EY9-8IhaCL4h4iNFpMcsPQEP0sESB6K6KCyLTKsbPFS_6aP9xv2ww&spc=l3OVKXglznCqAaFTrBcgVzIzzGHKuGMzsUkqBpGWT7wZBFB4UigqBUqagF0nZrdbewwGYLRw8I34QcDBxU0&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=HUx4YUdVhzEEkYMlTAlE1aYQ&rqh=1&gir=yes&clen=3352071&dur=207.075&lmt=1750458692505526&mt=1751016774&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5432534&n=TlR8H4JkRrFLug&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhALGddOwttCrE0w-oKIG5HJMzfxIgJZ5KpexFB7_JeOyvAiA_GAsyA-uLfLcgOz52H9l7uelV20fNAvamWmzAuJ87jA%3D%3D&sig=AJfQdSswRQIhAJVp_rDBXpA2fywJ_qAY66OybEyKxCGEDvIYoCG3seB1AiBXmyYv3iTcPN5wmvdLhiZZZFUdmpKjuOm26juaKAlOnQ%3D%3D&pot=Ml8dWUwMGBIhkR1lHE2PIbX8QRV7LRzFQGVevVKjIhnOVZhngK0ofC6FcopMLF1j0sjxujs4udAIBdBWwpwWqRarBYZ_VRbv12YTBLoi5SWrZioD2AUxXJRlbZpe_1t0Lw%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 27 11:44:14 volumio volumio[1114]: verbose: CURRENT POSITION 0 Jun 27 11:44:14 volumio volumio[1114]: info: CoreStateMachine::syncState stateService play Jun 27 11:44:14 volumio volumio[1114]: info: CoreStateMachine::syncState currentStatus play Jun 27 11:44:14 volumio volumio[1114]: info: Received an update from plugin. extracting info from payload Jun 27 11:44:14 volumio volumio[1114]: info: CoreStateMachine::pushState Jun 27 11:44:14 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Jun 27 11:44:14 volumio volumio[1114]: info: CoreStateMachine::pushState Jun 27 11:44:14 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Jun 27 11:44:14 volumio volumio[1114]: info: ------------------------------ 51ms Jun 27 11:44:14 volumio volumio[1114]: info: ------------------------------ 49ms Jun 27 11:44:14 volumio volumio[1114]: info: FusionDsp - Volumio is playing Jun 27 11:44:14 volumio volumio[1114]: info: FusionDsp - Volumio is playing Jun 27 11:44:14 volumio volumio[1114]: info: FusionDsp - Volumio is playing Jun 27 11:44:14 volumio volumio[1114]: info: FusionDsp - Volumio is playing Jun 27 11:44:14 volumio volumio[1114]: info: FusionDsp - Volumio is playing Jun 27 11:44:15 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 475. Jun 27 11:44:15 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:15 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:15 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:18 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 476. Jun 27 11:44:18 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:18 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:18 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:20 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 477. Jun 27 11:44:20 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:20 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:20 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:22 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 27 11:44:22 volumio dbus-daemon[606]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.88' (uid=0 pid=7468 comm="timedatectl show --property=NTPSynchronized --valu") Jun 27 11:44:22 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 27 11:44:22 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 478. Jun 27 11:44:22 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:22 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:22 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:22 volumio dbus-daemon[606]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 27 11:44:22 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 27 11:44:22 volumio setdatetime-helper.sh[7467]: Time is already synchronized. Jun 27 11:44:22 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 27 11:44:22 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 27 11:44:24 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 479. Jun 27 11:44:24 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:24 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:24 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:27 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 480. Jun 27 11:44:27 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:27 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:27 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:29 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 481. Jun 27 11:44:29 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:29 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:29 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:31 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 482. Jun 27 11:44:31 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:31 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:31 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:33 volumio volumio[1114]: info: VolumeController::SetAlsaVolume31 Jun 27 11:44:33 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Jun 27 11:44:33 volumio volumio[1114]: info: CoreStateMachine::pushState Jun 27 11:44:33 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 11:44:33 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Jun 27 11:44:33 volumio volumio[1114]: info: FusionDsp - Volumio is playing Jun 27 11:44:33 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 483. Jun 27 11:44:33 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:33 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:33 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:36 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 484. Jun 27 11:44:36 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:36 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:36 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:38 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 485. Jun 27 11:44:38 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:38 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:38 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:40 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 486. Jun 27 11:44:40 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:40 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:40 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:42 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 487. Jun 27 11:44:42 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:42 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:42 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:44 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 27 11:44:44 volumio volumio[1114]: info: Preload queue cleared Jun 27 11:44:45 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 488. Jun 27 11:44:45 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:45 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:45 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:47 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 489. Jun 27 11:44:47 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:47 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:47 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:49 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 490. Jun 27 11:44:49 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:49 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:49 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:51 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 491. Jun 27 11:44:51 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:51 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:51 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:52 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 27 11:44:53 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 27 11:44:53 volumio volumio[1114]: info: TuneIn handleBrowseUri: tunein/popular Jun 27 11:44:53 volumio volumio[1114]: info: Preload queue cleared Jun 27 11:44:54 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 492. Jun 27 11:44:54 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:54 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:54 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:56 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 493. Jun 27 11:44:56 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:56 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:56 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:44:58 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 494. Jun 27 11:44:58 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:58 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:44:58 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:00 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 495. Jun 27 11:45:00 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:00 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:00 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:03 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 496. Jun 27 11:45:03 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:03 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:03 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:05 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 497. Jun 27 11:45:05 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:05 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:05 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:07 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 498. Jun 27 11:45:07 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:07 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:07 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 27 11:45:07 volumio volumio[1114]: info: Preload queue cleared Jun 27 11:45:09 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 499. Jun 27 11:45:09 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:09 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:09 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:12 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 500. Jun 27 11:45:12 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:12 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:12 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:14 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 501. Jun 27 11:45:14 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:14 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:14 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:14 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 27 11:45:14 volumio volumio[1114]: info: TuneIn handleBrowseUri: tunein/popular Jun 27 11:45:14 volumio volumio[1114]: info: Preload queue cleared Jun 27 11:45:16 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 502. Jun 27 11:45:16 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:16 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:16 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:18 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 503. Jun 27 11:45:18 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:18 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:18 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:21 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 504. Jun 27 11:45:21 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:21 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:21 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:23 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 505. Jun 27 11:45:23 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:23 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:23 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 27 11:45:23 volumio dbus-daemon[606]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.90' (uid=0 pid=7618 comm="timedatectl show --property=NTPSynchronized --valu") Jun 27 11:45:23 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 27 11:45:23 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:23 volumio dbus-daemon[606]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 27 11:45:23 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 27 11:45:23 volumio setdatetime-helper.sh[7616]: Time is already synchronized. Jun 27 11:45:23 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 27 11:45:23 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 27 11:45:24 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 27 11:45:24 volumio volumio[1114]: info: TuneIn handleBrowseUri: tunein/popular Jun 27 11:45:24 volumio volumio[1114]: info: Preload queue cleared Jun 27 11:45:25 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 506. Jun 27 11:45:25 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:25 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:25 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:27 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 507. Jun 27 11:45:27 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:27 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:27 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:29 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 27 11:45:29 volumio volumio[1114]: info: TuneIn handleBrowseUri: tunein/location Jun 27 11:45:30 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 508. Jun 27 11:45:30 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:30 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:30 volumio volumio[1114]: info: Preload queue cleared Jun 27 11:45:30 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:32 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 509. Jun 27 11:45:32 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:32 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:32 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:33 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 27 11:45:33 volumio volumio[1114]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101217 Jun 27 11:45:33 volumio volumio[1114]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101217 Jun 27 11:45:33 volumio volumio[1114]: info: Preload queue cleared Jun 27 11:45:34 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 510. Jun 27 11:45:34 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:34 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:34 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:36 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 511. Jun 27 11:45:36 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:36 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:36 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:39 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 512. Jun 27 11:45:39 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:39 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:39 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:41 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 27 11:45:41 volumio volumio[1114]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100385 Jun 27 11:45:41 volumio volumio[1114]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100385 Jun 27 11:45:41 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 513. Jun 27 11:45:41 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:41 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:41 volumio volumio[1114]: info: Preload queue cleared Jun 27 11:45:41 volumio volumio[1114]: info: Preload queue cleared Jun 27 11:45:41 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:41 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Jun 27 11:45:41 volumio volumio[1114]: info: CorePlayQueue::getTrack 1 Jun 27 11:45:43 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 514. Jun 27 11:45:43 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:43 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:43 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:45 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 515. Jun 27 11:45:45 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:45 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:45 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Jun 27 11:45:47 volumio volumio[1114]: info: Jun 27 11:45:47 volumio volumio[1114]: ---------------------------- MPD announces state update: player Jun 27 11:45:47 volumio volumio[1114]: info: ControllerMpd::getState Jun 27 11:45:47 volumio volumio[1114]: verbose: ControllerMpd::sendMpdCommand status Jun 27 11:45:47 volumio volumio[1114]: info: Jun 27 11:45:47 volumio volumio[1114]: ---------------------------- MPD announces state update: player Jun 27 11:45:47 volumio volumio[1114]: info: ControllerMpd::getState Jun 27 11:45:47 volumio volumio[1114]: verbose: ControllerMpd::sendMpdCommand status Jun 27 11:45:47 volumio volumio[1114]: info: sendMpdCommand status took 2 milliseconds Jun 27 11:45:47 volumio volumio[1114]: info: sendMpdCommand status took 2 milliseconds Jun 27 11:45:47 volumio volumio[1114]: verbose: ControllerMpd::parseState Jun 27 11:45:47 volumio volumio[1114]: verbose: ControllerMpd::parseState Jun 27 11:45:47 volumio volumio[1114]: info: ControllerMpd::pushState Jun 27 11:45:47 volumio volumio[1114]: info: CoreCommandRouter::servicePushState Jun 27 11:45:47 volumio volumio[1114]: info: CoreStateMachine::pushState Jun 27 11:45:47 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 11:45:47 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Jun 27 11:45:47 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Jun 27 11:45:47 volumio volumio[1114]: 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 27 11:45:47 volumio volumio[1114]: verbose: CURRENT POSITION 0 Jun 27 11:45:47 volumio volumio[1114]: info: CoreStateMachine::syncState stateService stop Jun 27 11:45:47 volumio volumio[1114]: info: CoreStateMachine::syncState currentStatus play Jun 27 11:45:47 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Jun 27 11:45:47 volumio volumio[1114]: info: CoreStateMachine::stPlaybackTimer Jun 27 11:45:47 volumio volumio[1114]: info: ControllerMpd::pushState Jun 27 11:45:47 volumio volumio[1114]: info: CoreCommandRouter::servicePushState Jun 27 11:45:47 volumio volumio[1114]: info: CoreStateMachine::pushState Jun 27 11:45:47 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 11:45:47 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Jun 27 11:45:47 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Jun 27 11:45:47 volumio volumio[1114]: 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 27 11:45:47 volumio volumio[1114]: verbose: CURRENT POSITION 0 Jun 27 11:45:47 volumio volumio[1114]: info: CoreStateMachine::syncState stateService stop Jun 27 11:45:47 volumio volumio[1114]: info: CoreStateMachine::syncState currentStatus stop Jun 27 11:45:47 volumio volumio[1114]: info: CoreStateMachine::pushState Jun 27 11:45:47 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Jun 27 11:45:47 volumio volumio[1114]: info: No code Jun 27 11:45:47 volumio volumio[1114]: info: CoreStateMachine::pushState Jun 27 11:45:47 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Jun 27 11:45:47 volumio volumio[1114]: info: ------------------------------ 31ms Jun 27 11:45:47 volumio volumio[1114]: info: ------------------------------ 31ms Jun 27 11:45:47 volumio volumio[1114]: info: FusionDsp - Volumio is playing Jun 27 11:45:47 volumio volumio[1114]: info: FusionDsp - Volumio is not playing Jun 27 11:45:47 volumio volumio[1114]: info: FusionDsp - Clipped samples monitor stopped Jun 27 11:45:47 volumio volumio[1114]: info: FusionDsp - Volumio is not playing Jun 27 11:45:47 volumio volumio[1114]: info: FusionDsp - Clipped samples monitor stopped Jun 27 11:45:47 volumio volumio[1114]: info: FusionDsp - Volumio is not playing Jun 27 11:45:47 volumio volumio[1114]: info: FusionDsp - Clipped samples monitor stopped Jun 27 11:45:47 volumio volumio[1114]: info: FusionDsp - Volumio is not playing Jun 27 11:45:47 volumio volumio[1114]: info: FusionDsp - Clipped samples monitor stopped Jun 27 11:45:47 volumio volumio[1114]: Searching plugin music_service/webradio Jun 27 11:45:47 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 27 11:45:47 volumio volumio[1114]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 11:45:47 volumio volumio[1114]: TypeError: Cannot read properties of undefined (reading 'length') Jun 27 11:45:47 volumio volumio[1114]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:186:126 Jun 27 11:45:47 volumio volumio[1114]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Jun 27 11:45:47 volumio volumio[1114]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 Jun 27 11:45:47 volumio volumio[1114]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 27 11:45:47 volumio volumio[1114]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 11:45:48 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 516. Jun 27 11:45:48 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:48 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 27 11:45:48 volumio sudo[7700]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-27 11:44' Jun 27 11:45:48 volumio sudo[7700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 11:45:48 volumio systemd[1]: fusiondsp.service: Deactivated successfully. 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"