Jun 24 16:36:00 volumio1 sudo[1849]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 24 16:36:00 volumio1 sudo[1849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 24 16:36:00 volumio1 sudo[1849]: pam_unix(sudo:session): session closed for user root Jun 24 16:36:00 volumio1 sudo[1851]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 24 16:36:00 volumio1 sudo[1851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 24 16:36:00 volumio1 sudo[1851]: pam_unix(sudo:session): session closed for user root Jun 24 16:36:00 volumio1 volumio[1134]: verbose: New Socket.io Connection to 192.168.1.111 from 192.168.1.106 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/16.6.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 5 Jun 24 16:36:01 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 24 16:36:01 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 24 16:36:01 volumio1 volumio[1134]: info: CoreCommandRouter::volumioGetVisibleSources Jun 24 16:36:01 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:36:01 volumio1 volumio[1134]: info: CoreCommandRouter::volumioGetState Jun 24 16:36:01 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 24 16:36:01 volumio1 volumio[1134]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 24 16:36:01 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 24 16:36:01 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 24 16:36:01 volumio1 volumio[1134]: info: Received Get System Info Jun 24 16:36:01 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 24 16:36:01 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 24 16:36:01 volumio1 volumio[1134]: info: Discovery: Getting this device information Jun 24 16:36:01 volumio1 volumio[1134]: info: CoreCommandRouter::volumioGetState Jun 24 16:36:01 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 24 16:36:01 volumio1 volumio[1134]: info: CoreCommandRouter::volumioGetState Jun 24 16:36:01 volumio1 volumio[1134]: info: Listing playlists Jun 24 16:36:01 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 24 16:36:01 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 24 16:36:02 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 24 16:36:02 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 24 16:36:02 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jun 24 16:36:02 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 24 16:36:02 volumio1 volumio[1134]: info: Received Get System Info Jun 24 16:36:02 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 24 16:36:02 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 24 16:36:02 volumio1 volumio[1134]: info: Discovery: Getting this device information Jun 24 16:36:02 volumio1 volumio[1134]: info: CoreCommandRouter::volumioGetState Jun 24 16:36:02 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 24 16:36:02 volumio1 volumio[1134]: info: Received Get System Version Jun 24 16:36:02 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:36:03 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 24 16:36:03 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 24 16:36:03 volumio1 volumio[1134]: info: Received Get System Info Jun 24 16:36:03 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 24 16:36:03 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 24 16:36:03 volumio1 volumio[1134]: info: Discovery: Getting this device information Jun 24 16:36:03 volumio1 volumio[1134]: info: CoreCommandRouter::volumioGetState Jun 24 16:36:03 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPause Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreStateMachine::pause Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreStateMachine::stPlaybackTimer Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreStateMachine::servicePause Jun 24 16:36:10 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreCommandRouter::servicePause Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 24 16:36:10 volumio1 volumio[1134]: info: ControllerMpd::pause Jun 24 16:36:10 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand pause Jun 24 16:36:10 volumio1 volumio[1134]: info: Jun 24 16:36:10 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:10 volumio1 volumio[1134]: info: sendMpdCommand pause took 11 milliseconds Jun 24 16:36:10 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:10 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:10 volumio1 volumio[1134]: info: Jun 24 16:36:10 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:10 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:10 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:10 volumio1 volumio[1134]: info: Jun 24 16:36:10 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:10 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:10 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:10 volumio1 volumio[1134]: info: sendMpdCommand status took 19 milliseconds Jun 24 16:36:10 volumio1 volumio[1134]: info: sendMpdCommand status took 18 milliseconds Jun 24 16:36:10 volumio1 volumio[1134]: info: sendMpdCommand status took 7 milliseconds Jun 24 16:36:10 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:10 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:10 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:10 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:10 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:10 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:10 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 24 16:36:10 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 24 16:36:10 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 24 16:36:10 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:10 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:10 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:10 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:10 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:10 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":49992,"duration":425,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Relaxing a bit (Live)","artist":"The Jazzists","album":"A nice try","uri":"https://rr8---sn-3qqp-ioqsl.googlevideo.com/videoplayback?expire=1750772118&ei=NlVaaOvlOpSW1d8Pm7uniAw&ip=111.99.215.134&id=o-AEXeWWm1LWqy3vJZjfilTvnXFr2qfzJN4uNY4vmtjSHF&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1750750518%2C&mh=gQ&mm=31%2C26&mn=sn-3qqp-ioqsl%2Csn-3pm7dn7z&ms=au%2Conr&mv=m&mvi=8&pl=25&rms=au%2Cau&ctier=A&pfa=5&gcr=jp&initcwndbps=3898750&hightc=yes&siu=1&bui=AY1jyLMqR91JbMToD2KAfQ7B99NZY2phgNHLL3G42hRcjp8WEWR7ecARwBWuXor3vNNfCD2ukQ&spc=l3OVKU_486BibeDyBMW0j_Y-HkjwY-OHwRmhbUhTxBLYjSlhnHZkjGM5Hq3f16Q7y_3QumaC1Gklxj8&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=3IxWkbZqILq4YlQQGm_w5V0Q&rqh=1&gir=yes&clen=14295386&dur=425.021&lmt=1714743294280140&mt=1750750155&fvip=4&keepalive=yes&fexp=51466643%2C51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=kY5UtfNFCZdybA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgc4VfATc646NLGCVsssNR5o-lQOL0T9HHfwsQGkjCOkQCIQC70CxucUIKENLbNI1fsE9Ts1EPRjJjF9saQDm65QTIeA%3D%3D&sig=AJfQdSswRAIgfjEdWzlqM_r_UKLa9obVQO-CM7VUXvcPnzy7y6fptCMCIA4qr4dhwQ_rmyhqcJzkrfu5bjhOpvLa_m6To4ww52LP&pot=Ml89bZY78taE5D1RrNE50WNKnqNHQoT7mFKSzBndzOtcddIhgGJEvvsSrfBKEmcVHiJmtf5-_hUjokWBmBSXC4K5ZXJiAC_k-aKXUnnglyB8yBj3ip59Svhu_lGozO786A%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 24 16:36:10 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService pause Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus pause Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreStateMachine::stPlaybackTimer Jun 24 16:36:10 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:10 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:10 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":49992,"duration":425,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Relaxing a bit (Live)","artist":"The Jazzists","album":"A nice try","uri":"https://rr8---sn-3qqp-ioqsl.googlevideo.com/videoplayback?expire=1750772118&ei=NlVaaOvlOpSW1d8Pm7uniAw&ip=111.99.215.134&id=o-AEXeWWm1LWqy3vJZjfilTvnXFr2qfzJN4uNY4vmtjSHF&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1750750518%2C&mh=gQ&mm=31%2C26&mn=sn-3qqp-ioqsl%2Csn-3pm7dn7z&ms=au%2Conr&mv=m&mvi=8&pl=25&rms=au%2Cau&ctier=A&pfa=5&gcr=jp&initcwndbps=3898750&hightc=yes&siu=1&bui=AY1jyLMqR91JbMToD2KAfQ7B99NZY2phgNHLL3G42hRcjp8WEWR7ecARwBWuXor3vNNfCD2ukQ&spc=l3OVKU_486BibeDyBMW0j_Y-HkjwY-OHwRmhbUhTxBLYjSlhnHZkjGM5Hq3f16Q7y_3QumaC1Gklxj8&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=3IxWkbZqILq4YlQQGm_w5V0Q&rqh=1&gir=yes&clen=14295386&dur=425.021&lmt=1714743294280140&mt=1750750155&fvip=4&keepalive=yes&fexp=51466643%2C51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=kY5UtfNFCZdybA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgc4VfATc646NLGCVsssNR5o-lQOL0T9HHfwsQGkjCOkQCIQC70CxucUIKENLbNI1fsE9Ts1EPRjJjF9saQDm65QTIeA%3D%3D&sig=AJfQdSswRAIgfjEdWzlqM_r_UKLa9obVQO-CM7VUXvcPnzy7y6fptCMCIA4qr4dhwQ_rmyhqcJzkrfu5bjhOpvLa_m6To4ww52LP&pot=Ml89bZY78taE5D1RrNE50WNKnqNHQoT7mFKSzBndzOtcddIhgGJEvvsSrfBKEmcVHiJmtf5-_hUjokWBmBSXC4K5ZXJiAC_k-aKXUnnglyB8yBj3ip59Svhu_lGozO786A%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 24 16:36:10 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService pause Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus pause Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreStateMachine::stPlaybackTimer Jun 24 16:36:10 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:10 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:10 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":49992,"duration":425,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Relaxing a bit (Live)","artist":"The Jazzists","album":"A nice try","uri":"https://rr8---sn-3qqp-ioqsl.googlevideo.com/videoplayback?expire=1750772118&ei=NlVaaOvlOpSW1d8Pm7uniAw&ip=111.99.215.134&id=o-AEXeWWm1LWqy3vJZjfilTvnXFr2qfzJN4uNY4vmtjSHF&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1750750518%2C&mh=gQ&mm=31%2C26&mn=sn-3qqp-ioqsl%2Csn-3pm7dn7z&ms=au%2Conr&mv=m&mvi=8&pl=25&rms=au%2Cau&ctier=A&pfa=5&gcr=jp&initcwndbps=3898750&hightc=yes&siu=1&bui=AY1jyLMqR91JbMToD2KAfQ7B99NZY2phgNHLL3G42hRcjp8WEWR7ecARwBWuXor3vNNfCD2ukQ&spc=l3OVKU_486BibeDyBMW0j_Y-HkjwY-OHwRmhbUhTxBLYjSlhnHZkjGM5Hq3f16Q7y_3QumaC1Gklxj8&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=3IxWkbZqILq4YlQQGm_w5V0Q&rqh=1&gir=yes&clen=14295386&dur=425.021&lmt=1714743294280140&mt=1750750155&fvip=4&keepalive=yes&fexp=51466643%2C51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=kY5UtfNFCZdybA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgc4VfATc646NLGCVsssNR5o-lQOL0T9HHfwsQGkjCOkQCIQC70CxucUIKENLbNI1fsE9Ts1EPRjJjF9saQDm65QTIeA%3D%3D&sig=AJfQdSswRAIgfjEdWzlqM_r_UKLa9obVQO-CM7VUXvcPnzy7y6fptCMCIA4qr4dhwQ_rmyhqcJzkrfu5bjhOpvLa_m6To4ww52LP&pot=Ml89bZY78taE5D1RrNE50WNKnqNHQoT7mFKSzBndzOtcddIhgGJEvvsSrfBKEmcVHiJmtf5-_hUjokWBmBSXC4K5ZXJiAC_k-aKXUnnglyB8yBj3ip59Svhu_lGozO786A%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 24 16:36:10 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService pause Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus pause Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreStateMachine::stPlaybackTimer Jun 24 16:36:10 volumio1 volumio[1134]: info: ------------------------------ 120ms Jun 24 16:36:10 volumio1 volumio[1134]: info: ------------------------------ 114ms Jun 24 16:36:10 volumio1 volumio[1134]: info: ------------------------------ 112ms Jun 24 16:36:10 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 24 16:36:15 volumio1 volumio[1134]: info: CoreCommandRouter::volumioGetQueue Jun 24 16:36:15 volumio1 volumio[1134]: info: CoreStateMachine::getQueue Jun 24 16:36:15 volumio1 volumio[1134]: info: CorePlayQueue::getQueue Jun 24 16:36:19 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 24 16:36:19 volumio1 volumio[1134]: info: Preload queue cleared Jun 24 16:36:19 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 24 16:36:19 volumio1 volumio[1134]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 24 16:36:19 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 24 16:36:19 volumio1 volumio[1134]: info: Received Get System Version Jun 24 16:36:19 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:36:19 volumio1 volumio[1134]: info: Received Get System Info Jun 24 16:36:19 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 24 16:36:19 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 24 16:36:19 volumio1 volumio[1134]: info: Discovery: Getting this device information Jun 24 16:36:19 volumio1 volumio[1134]: info: CoreCommandRouter::volumioGetState Jun 24 16:36:19 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 24 16:36:20 volumio1 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 24 16:36:28 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 24 16:36:28 volumio1 volumio[1134]: info: TuneIn handleBrowseUri: tunein/best Jun 24 16:36:28 volumio1 volumio[1134]: info: Preload queue cleared Jun 24 16:36:33 volumio1 volumio[1134]: info: Preload queue cleared Jun 24 16:36:33 volumio1 volumio[1134]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 24 16:36:33 volumio1 volumio[1134]: info: CoreStateMachine::ClearQueue Jun 24 16:36:33 volumio1 volumio[1134]: info: CoreStateMachine::stop Jun 24 16:36:33 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:36:33 volumio1 volumio[1134]: info: CoreStateMachine::updateTrackBlock Jun 24 16:36:33 volumio1 volumio[1134]: info: CorePlayQueue::getTrackBlock Jun 24 16:36:33 volumio1 volumio[1134]: info: CoreStateMachine::stPlaybackTimer Jun 24 16:36:33 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:33 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:33 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:36:33 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:33 volumio1 volumio[1134]: info: CoreStateMachine::serviceStop Jun 24 16:36:33 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:33 volumio1 volumio[1134]: info: CoreCommandRouter::serviceStop Jun 24 16:36:33 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 24 16:36:33 volumio1 volumio[1134]: info: ControllerMpd::stop Jun 24 16:36:33 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand stop Jun 24 16:36:33 volumio1 volumio[1134]: info: CorePlayQueue::clearPlayQueue Jun 24 16:36:33 volumio1 volumio[1134]: info: CorePlayQueue::saveQueue Jun 24 16:36:33 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushQueue Jun 24 16:36:33 volumio1 volumio[1134]: info: CoreStateMachine::addQueueItems Jun 24 16:36:33 volumio1 volumio[1134]: info: CorePlayQueue::addQueueItems Jun 24 16:36:33 volumio1 volumio[1134]: info: Preload queue cleared Jun 24 16:36:33 volumio1 volumio[1134]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s8138 Jun 24 16:36:33 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 24 16:36:33 volumio1 volumio[1134]: info: Jun 24 16:36:33 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:33 volumio1 volumio[1134]: info: sendMpdCommand stop took 233 milliseconds Jun 24 16:36:33 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:33 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:33 volumio1 volumio[1134]: info: Jun 24 16:36:33 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:33 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:33 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:33 volumio1 volumio[1134]: info: Jun 24 16:36:33 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:33 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:33 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:33 volumio1 volumio[1134]: info: sendMpdCommand status took 44 milliseconds Jun 24 16:36:33 volumio1 volumio[1134]: info: sendMpdCommand status took 63 milliseconds Jun 24 16:36:33 volumio1 volumio[1134]: info: sendMpdCommand status took 62 milliseconds Jun 24 16:36:33 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:33 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:33 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:33 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:33 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:33 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:34 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 27 milliseconds Jun 24 16:36:34 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 26 milliseconds Jun 24 16:36:34 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 27 milliseconds Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:34 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:34 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Relaxing a bit (Live)","artist":"The Jazzists","album":"A nice try","uri":"https://rr8---sn-3qqp-ioqsl.googlevideo.com/videoplayback?expire=1750772118&ei=NlVaaOvlOpSW1d8Pm7uniAw&ip=111.99.215.134&id=o-AEXeWWm1LWqy3vJZjfilTvnXFr2qfzJN4uNY4vmtjSHF&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1750750518%2C&mh=gQ&mm=31%2C26&mn=sn-3qqp-ioqsl%2Csn-3pm7dn7z&ms=au%2Conr&mv=m&mvi=8&pl=25&rms=au%2Cau&ctier=A&pfa=5&gcr=jp&initcwndbps=3898750&hightc=yes&siu=1&bui=AY1jyLMqR91JbMToD2KAfQ7B99NZY2phgNHLL3G42hRcjp8WEWR7ecARwBWuXor3vNNfCD2ukQ&spc=l3OVKU_486BibeDyBMW0j_Y-HkjwY-OHwRmhbUhTxBLYjSlhnHZkjGM5Hq3f16Q7y_3QumaC1Gklxj8&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=3IxWkbZqILq4YlQQGm_w5V0Q&rqh=1&gir=yes&clen=14295386&dur=425.021&lmt=1714743294280140&mt=1750750155&fvip=4&keepalive=yes&fexp=51466643%2C51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=kY5UtfNFCZdybA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgc4VfATc646NLGCVsssNR5o-lQOL0T9HHfwsQGkjCOkQCIQC70CxucUIKENLbNI1fsE9Ts1EPRjJjF9saQDm65QTIeA%3D%3D&sig=AJfQdSswRAIgfjEdWzlqM_r_UKLa9obVQO-CM7VUXvcPnzy7y6fptCMCIA4qr4dhwQ_rmyhqcJzkrfu5bjhOpvLa_m6To4ww52LP&pot=Ml89bZY78taE5D1RrNE50WNKnqNHQoT7mFKSzBndzOtcddIhgGJEvvsSrfBKEmcVHiJmtf5-_hUjokWBmBSXC4K5ZXJiAC_k-aKXUnnglyB8yBj3ip59Svhu_lGozO786A%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 24 16:36:34 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService stop Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:34 volumio1 volumio[1134]: info: No code Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:34 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:34 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Relaxing a bit (Live)","artist":"The Jazzists","album":"A nice try","uri":"https://rr8---sn-3qqp-ioqsl.googlevideo.com/videoplayback?expire=1750772118&ei=NlVaaOvlOpSW1d8Pm7uniAw&ip=111.99.215.134&id=o-AEXeWWm1LWqy3vJZjfilTvnXFr2qfzJN4uNY4vmtjSHF&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1750750518%2C&mh=gQ&mm=31%2C26&mn=sn-3qqp-ioqsl%2Csn-3pm7dn7z&ms=au%2Conr&mv=m&mvi=8&pl=25&rms=au%2Cau&ctier=A&pfa=5&gcr=jp&initcwndbps=3898750&hightc=yes&siu=1&bui=AY1jyLMqR91JbMToD2KAfQ7B99NZY2phgNHLL3G42hRcjp8WEWR7ecARwBWuXor3vNNfCD2ukQ&spc=l3OVKU_486BibeDyBMW0j_Y-HkjwY-OHwRmhbUhTxBLYjSlhnHZkjGM5Hq3f16Q7y_3QumaC1Gklxj8&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=3IxWkbZqILq4YlQQGm_w5V0Q&rqh=1&gir=yes&clen=14295386&dur=425.021&lmt=1714743294280140&mt=1750750155&fvip=4&keepalive=yes&fexp=51466643%2C51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=kY5UtfNFCZdybA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgc4VfATc646NLGCVsssNR5o-lQOL0T9HHfwsQGkjCOkQCIQC70CxucUIKENLbNI1fsE9Ts1EPRjJjF9saQDm65QTIeA%3D%3D&sig=AJfQdSswRAIgfjEdWzlqM_r_UKLa9obVQO-CM7VUXvcPnzy7y6fptCMCIA4qr4dhwQ_rmyhqcJzkrfu5bjhOpvLa_m6To4ww52LP&pot=Ml89bZY78taE5D1RrNE50WNKnqNHQoT7mFKSzBndzOtcddIhgGJEvvsSrfBKEmcVHiJmtf5-_hUjokWBmBSXC4K5ZXJiAC_k-aKXUnnglyB8yBj3ip59Svhu_lGozO786A%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 24 16:36:34 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService stop Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:34 volumio1 volumio[1134]: info: No code Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:34 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:34 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Relaxing a bit (Live)","artist":"The Jazzists","album":"A nice try","uri":"https://rr8---sn-3qqp-ioqsl.googlevideo.com/videoplayback?expire=1750772118&ei=NlVaaOvlOpSW1d8Pm7uniAw&ip=111.99.215.134&id=o-AEXeWWm1LWqy3vJZjfilTvnXFr2qfzJN4uNY4vmtjSHF&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1750750518%2C&mh=gQ&mm=31%2C26&mn=sn-3qqp-ioqsl%2Csn-3pm7dn7z&ms=au%2Conr&mv=m&mvi=8&pl=25&rms=au%2Cau&ctier=A&pfa=5&gcr=jp&initcwndbps=3898750&hightc=yes&siu=1&bui=AY1jyLMqR91JbMToD2KAfQ7B99NZY2phgNHLL3G42hRcjp8WEWR7ecARwBWuXor3vNNfCD2ukQ&spc=l3OVKU_486BibeDyBMW0j_Y-HkjwY-OHwRmhbUhTxBLYjSlhnHZkjGM5Hq3f16Q7y_3QumaC1Gklxj8&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=3IxWkbZqILq4YlQQGm_w5V0Q&rqh=1&gir=yes&clen=14295386&dur=425.021&lmt=1714743294280140&mt=1750750155&fvip=4&keepalive=yes&fexp=51466643%2C51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=kY5UtfNFCZdybA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgc4VfATc646NLGCVsssNR5o-lQOL0T9HHfwsQGkjCOkQCIQC70CxucUIKENLbNI1fsE9Ts1EPRjJjF9saQDm65QTIeA%3D%3D&sig=AJfQdSswRAIgfjEdWzlqM_r_UKLa9obVQO-CM7VUXvcPnzy7y6fptCMCIA4qr4dhwQ_rmyhqcJzkrfu5bjhOpvLa_m6To4ww52LP&pot=Ml89bZY78taE5D1RrNE50WNKnqNHQoT7mFKSzBndzOtcddIhgGJEvvsSrfBKEmcVHiJmtf5-_hUjokWBmBSXC4K5ZXJiAC_k-aKXUnnglyB8yBj3ip59Svhu_lGozO786A%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 24 16:36:34 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService stop Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:34 volumio1 volumio[1134]: info: No code Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:34 volumio1 volumio[1134]: info: ------------------------------ 440ms Jun 24 16:36:34 volumio1 volumio[1134]: info: ------------------------------ 439ms Jun 24 16:36:34 volumio1 volumio[1134]: info: ------------------------------ 438ms Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushQueue Jun 24 16:36:34 volumio1 volumio[1134]: info: CorePlayQueue::saveQueue Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::updateTrackBlock Jun 24 16:36:34 volumio1 volumio[1134]: info: CorePlayQueue::getTrackBlock Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPlay Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::play index 0 Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::stop Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::play index undefined Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:36:34 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::startPlaybackTimer Jun 24 16:36:34 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:34 volumio1 volumio[1134]: info: [1750750594563] ControllerWebradio::clearAddPlayTrack Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand stop Jun 24 16:36:34 volumio1 volumio[1134]: info: sendMpdCommand stop took 3 milliseconds Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand clear Jun 24 16:36:34 volumio1 volumio[1134]: info: Jun 24 16:36:34 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:34 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:34 volumio1 volumio[1134]: info: sendMpdCommand clear took 2 milliseconds Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand load "http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3" Jun 24 16:36:34 volumio1 volumio[1134]: info: Jun 24 16:36:34 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:34 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:34 volumio1 volumio[1134]: info: Jun 24 16:36:34 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:34 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:34 volumio1 volumio[1134]: error: updateQueue error: null Jun 24 16:36:34 volumio1 volumio[1134]: info: ------------------------------ 7ms Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand add "http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3" Jun 24 16:36:34 volumio1 volumio[1134]: error: updateQueue error: null Jun 24 16:36:34 volumio1 volumio[1134]: error: updateQueue error: null Jun 24 16:36:34 volumio1 volumio[1134]: info: Jun 24 16:36:34 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:34 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:34 volumio1 volumio[1134]: info: ------------------------------ 52ms Jun 24 16:36:34 volumio1 volumio[1134]: info: ------------------------------ 50ms Jun 24 16:36:34 volumio1 volumio[1134]: info: sendMpdCommand add "http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3" took 6 milliseconds Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand play Jun 24 16:36:34 volumio1 volumio[1134]: info: Jun 24 16:36:34 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:34 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:34 volumio1 volumio[1134]: info: Jun 24 16:36:34 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:34 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:34 volumio1 volumio[1134]: info: ------------------------------ 16ms Jun 24 16:36:34 volumio1 volumio[1134]: info: sendMpdCommand play took 12 milliseconds Jun 24 16:36:34 volumio1 volumio[1134]: info: ------------------------------ 11ms Jun 24 16:36:34 volumio1 volumio[1134]: info: ------------------------------ 10ms Jun 24 16:36:34 volumio1 volumio[1134]: info: Jun 24 16:36:34 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:34 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:34 volumio1 volumio[1134]: info: Jun 24 16:36:34 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:34 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:34 volumio1 volumio[1134]: info: Jun 24 16:36:34 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:34 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:34 volumio1 volumio[1134]: info: Jun 24 16:36:34 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:34 volumio1 volumio[1134]: info: sendMpdCommand status took 39 milliseconds Jun 24 16:36:34 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:34 volumio1 volumio[1134]: info: sendMpdCommand status took 46 milliseconds Jun 24 16:36:34 volumio1 volumio[1134]: info: sendMpdCommand status took 46 milliseconds Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:34 volumio1 volumio[1134]: info: Jun 24 16:36:34 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:34 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:34 volumio1 volumio[1134]: info: Jun 24 16:36:34 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:34 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:34 volumio1 volumio[1134]: info: sendMpdCommand status took 18 milliseconds Jun 24 16:36:34 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 60 milliseconds Jun 24 16:36:34 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 60 milliseconds Jun 24 16:36:34 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 59 milliseconds Jun 24 16:36:34 volumio1 volumio[1134]: info: sendMpdCommand status took 56 milliseconds Jun 24 16:36:34 volumio1 volumio[1134]: info: sendMpdCommand status took 55 milliseconds Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:34 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:34 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:34 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":12,"samplerate":"16 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"24 Kbps","isStreaming":false,"title":"notcompatible.enUS.mp3","artist":null,"album":null,"uri":"http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3","trackType":"mp3"} Jun 24 16:36:34 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Jun 24 16:36:34 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:34 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":12,"samplerate":"16 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"24 Kbps","isStreaming":false,"title":"notcompatible.enUS.mp3","artist":null,"album":null,"uri":"http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3","trackType":"mp3"} Jun 24 16:36:34 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:34 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:34 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:34 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":379,"duration":12,"samplerate":"16 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"24 Kbps","isStreaming":false,"title":"notcompatible.enUS.mp3","artist":null,"album":null,"uri":"http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3","trackType":"mp3"} Jun 24 16:36:34 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:34 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:34 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:34 volumio1 volumio[1134]: info: ------------------------------ 255ms Jun 24 16:36:35 volumio1 volumio[1134]: info: ------------------------------ 355ms Jun 24 16:36:35 volumio1 volumio[1134]: info: ------------------------------ 365ms Jun 24 16:36:35 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 250 milliseconds Jun 24 16:36:35 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 253 milliseconds Jun 24 16:36:35 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 253 milliseconds Jun 24 16:36:35 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:35 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:35 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:35 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:35 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:35 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":758,"duration":12,"samplerate":"16 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"24 Kbps","isStreaming":false,"title":"notcompatible.enUS.mp3","artist":null,"album":null,"uri":"http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3","trackType":"mp3"} Jun 24 16:36:35 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:35 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:35 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:35 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:35 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":758,"duration":12,"samplerate":"16 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"24 Kbps","isStreaming":false,"title":"notcompatible.enUS.mp3","artist":null,"album":null,"uri":"http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3","trackType":"mp3"} Jun 24 16:36:35 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:35 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:35 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:35 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:35 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":758,"duration":12,"samplerate":"16 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"24 Kbps","isStreaming":false,"title":"notcompatible.enUS.mp3","artist":null,"album":null,"uri":"http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3","trackType":"mp3"} Jun 24 16:36:35 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:35 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:35 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:35 volumio1 volumio[1134]: info: ------------------------------ 617ms Jun 24 16:36:35 volumio1 volumio[1134]: info: ------------------------------ 594ms Jun 24 16:36:35 volumio1 volumio[1134]: info: ------------------------------ 597ms Jun 24 16:36:46 volumio1 volumio[1134]: info: Jun 24 16:36:46 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:46 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:46 volumio1 volumio[1134]: info: Jun 24 16:36:46 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:46 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:46 volumio1 volumio[1134]: info: Jun 24 16:36:46 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:46 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:46 volumio1 volumio[1134]: info: sendMpdCommand status took 3 milliseconds Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:46 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:46 volumio1 volumio[1134]: 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 24 16:36:46 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService stop Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::play index undefined Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::startPlaybackTimer Jun 24 16:36:46 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:46 volumio1 volumio[1134]: info: [1750750606429] ControllerWebradio::clearAddPlayTrack Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand stop Jun 24 16:36:46 volumio1 volumio[1134]: info: ------------------------------ 85ms Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:46 volumio1 volumio[1134]: info: sendMpdCommand status took 136 milliseconds Jun 24 16:36:46 volumio1 volumio[1134]: info: sendMpdCommand status took 179 milliseconds Jun 24 16:36:46 volumio1 volumio[1134]: info: sendMpdCommand stop took 99 milliseconds Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand clear Jun 24 16:36:46 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:46 volumio1 volumio[1134]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 24 16:36:46 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:46 volumio1 volumio[1134]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 24 16:36:46 volumio1 volumio[1134]: info: ------------------------------ 285ms Jun 24 16:36:46 volumio1 volumio[1134]: info: ------------------------------ 285ms Jun 24 16:36:46 volumio1 volumio[1134]: info: Jun 24 16:36:46 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:46 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:46 volumio1 volumio[1134]: info: sendMpdCommand clear took 211 milliseconds Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand load "http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3" Jun 24 16:36:46 volumio1 volumio[1134]: info: Jun 24 16:36:46 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:46 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:46 volumio1 volumio[1134]: info: Jun 24 16:36:46 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:46 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:46 volumio1 volumio[1134]: error: updateQueue error: null Jun 24 16:36:46 volumio1 volumio[1134]: info: ------------------------------ 39ms Jun 24 16:36:46 volumio1 volumio[1134]: error: updateQueue error: null Jun 24 16:36:46 volumio1 volumio[1134]: error: updateQueue error: null Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand add "http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3" Jun 24 16:36:46 volumio1 volumio[1134]: info: ------------------------------ 51ms Jun 24 16:36:46 volumio1 volumio[1134]: info: ------------------------------ 47ms Jun 24 16:36:46 volumio1 volumio[1134]: info: Jun 24 16:36:46 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:46 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:46 volumio1 volumio[1134]: info: sendMpdCommand add "http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3" took 15 milliseconds Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand play Jun 24 16:36:46 volumio1 volumio[1134]: info: Jun 24 16:36:46 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:46 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:46 volumio1 volumio[1134]: info: Jun 24 16:36:46 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:46 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:46 volumio1 volumio[1134]: info: ------------------------------ 17ms Jun 24 16:36:46 volumio1 volumio[1134]: info: sendMpdCommand play took 11 milliseconds Jun 24 16:36:46 volumio1 volumio[1134]: info: ------------------------------ 19ms Jun 24 16:36:46 volumio1 volumio[1134]: info: ------------------------------ 16ms Jun 24 16:36:46 volumio1 volumio[1134]: info: Jun 24 16:36:46 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:46 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:46 volumio1 volumio[1134]: info: Jun 24 16:36:46 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:46 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:46 volumio1 volumio[1134]: info: Jun 24 16:36:46 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:46 volumio1 volumio[1134]: info: Jun 24 16:36:46 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:46 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:46 volumio1 volumio[1134]: info: sendMpdCommand status took 17 milliseconds Jun 24 16:36:46 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:46 volumio1 volumio[1134]: info: sendMpdCommand status took 8 milliseconds Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:46 volumio1 volumio[1134]: info: Jun 24 16:36:46 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:46 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:46 volumio1 volumio[1134]: info: Jun 24 16:36:46 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:46 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:46 volumio1 volumio[1134]: info: sendMpdCommand status took 38 milliseconds Jun 24 16:36:46 volumio1 volumio[1134]: info: sendMpdCommand status took 37 milliseconds Jun 24 16:36:46 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 30 milliseconds Jun 24 16:36:46 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 30 milliseconds Jun 24 16:36:46 volumio1 volumio[1134]: info: sendMpdCommand status took 20 milliseconds Jun 24 16:36:46 volumio1 volumio[1134]: info: sendMpdCommand status took 11 milliseconds Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:46 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:46 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:46 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":758,"duration":12,"samplerate":"16 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"24 Kbps","isStreaming":false,"title":"notcompatible.enUS.mp3","artist":null,"album":null,"uri":"http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3","trackType":"mp3"} Jun 24 16:36:46 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Jun 24 16:36:46 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:46 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":758,"duration":12,"samplerate":"16 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"24 Kbps","isStreaming":false,"title":"notcompatible.enUS.mp3","artist":null,"album":null,"uri":"http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3","trackType":"mp3"} Jun 24 16:36:46 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:46 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:46 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:47 volumio1 volumio[1134]: info: ------------------------------ 189ms Jun 24 16:36:47 volumio1 volumio[1134]: info: ------------------------------ 247ms Jun 24 16:36:47 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 195 milliseconds Jun 24 16:36:47 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 195 milliseconds Jun 24 16:36:47 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 194 milliseconds Jun 24 16:36:47 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 195 milliseconds Jun 24 16:36:47 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:47 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:47 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:47 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:47 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:47 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":758,"duration":12,"samplerate":"16 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"24 Kbps","isStreaming":false,"title":"notcompatible.enUS.mp3","artist":null,"album":null,"uri":"http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3","trackType":"mp3"} Jun 24 16:36:47 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:47 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:47 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:47 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":758,"duration":12,"samplerate":"16 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"24 Kbps","isStreaming":false,"title":"notcompatible.enUS.mp3","artist":null,"album":null,"uri":"http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3","trackType":"mp3"} Jun 24 16:36:47 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:47 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:47 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:47 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":758,"duration":12,"samplerate":"16 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"24 Kbps","isStreaming":false,"title":"notcompatible.enUS.mp3","artist":null,"album":null,"uri":"http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3","trackType":"mp3"} Jun 24 16:36:47 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:47 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:47 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:47 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":758,"duration":12,"samplerate":"16 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"24 Kbps","isStreaming":false,"title":"notcompatible.enUS.mp3","artist":null,"album":null,"uri":"http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3","trackType":"mp3"} Jun 24 16:36:47 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:47 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:47 volumio1 volumio[1134]: info: ------------------------------ 577ms Jun 24 16:36:47 volumio1 volumio[1134]: info: ------------------------------ 578ms Jun 24 16:36:47 volumio1 volumio[1134]: info: ------------------------------ 568ms Jun 24 16:36:47 volumio1 volumio[1134]: info: ------------------------------ 556ms Jun 24 16:36:47 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 24 16:36:47 volumio1 volumio[1134]: info: Getting webradio selection Jun 24 16:36:47 volumio1 volumio[1134]: info: Preload queue cleared Jun 24 16:36:57 volumio1 volumio[1134]: info: Preload queue cleared Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::ClearQueue Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::stop Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::stPlaybackTimer Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::updateTrackBlock Jun 24 16:36:57 volumio1 volumio[1134]: info: CorePlayQueue::getTrackBlock Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:57 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::serviceStop Jun 24 16:36:57 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreCommandRouter::serviceStop Jun 24 16:36:57 volumio1 volumio[1134]: info: [1750750617078] ControllerWebradio::stop Jun 24 16:36:57 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand stop Jun 24 16:36:57 volumio1 volumio[1134]: info: CorePlayQueue::clearPlayQueue Jun 24 16:36:57 volumio1 volumio[1134]: info: CorePlayQueue::saveQueue Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushQueue Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::addQueueItems Jun 24 16:36:57 volumio1 volumio[1134]: info: CorePlayQueue::addQueueItems Jun 24 16:36:57 volumio1 volumio[1134]: info: Preload queue cleared Jun 24 16:36:57 volumio1 volumio[1134]: info: Adding Item to queue: http://listen.jazz88.org/ksds.mp3 Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushQueue Jun 24 16:36:57 volumio1 volumio[1134]: info: CorePlayQueue::saveQueue Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::updateTrackBlock Jun 24 16:36:57 volumio1 volumio[1134]: info: CorePlayQueue::getTrackBlock Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPlay Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::play index 0 Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::stop Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::play index undefined Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:36:57 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::startPlaybackTimer Jun 24 16:36:57 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:57 volumio1 volumio[1134]: info: [1750750617150] ControllerWebradio::clearAddPlayTrack Jun 24 16:36:57 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand stop Jun 24 16:36:57 volumio1 volumio[1134]: info: Jun 24 16:36:57 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:57 volumio1 volumio[1134]: info: sendMpdCommand stop took 102 milliseconds Jun 24 16:36:57 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:57 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:57 volumio1 volumio[1134]: info: sendMpdCommand stop took 32 milliseconds Jun 24 16:36:57 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand clear Jun 24 16:36:57 volumio1 volumio[1134]: info: Jun 24 16:36:57 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:57 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:57 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:57 volumio1 volumio[1134]: info: Jun 24 16:36:57 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:57 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:57 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:57 volumio1 volumio[1134]: info: Jun 24 16:36:57 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:57 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:57 volumio1 volumio[1134]: info: sendMpdCommand status took 44 milliseconds Jun 24 16:36:57 volumio1 volumio[1134]: info: sendMpdCommand clear took 43 milliseconds Jun 24 16:36:57 volumio1 volumio[1134]: info: sendMpdCommand status took 15 milliseconds Jun 24 16:36:57 volumio1 volumio[1134]: info: sendMpdCommand status took 14 milliseconds Jun 24 16:36:57 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:57 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:57 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand load "http://listen.jazz88.org/ksds.mp3" Jun 24 16:36:57 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:57 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:57 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:57 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:57 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:57 volumio1 volumio[1134]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 24 16:36:57 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:57 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:57 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:57 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:57 volumio1 volumio[1134]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 24 16:36:57 volumio1 volumio[1134]: info: ------------------------------ 181ms Jun 24 16:36:57 volumio1 volumio[1134]: info: ------------------------------ 188ms Jun 24 16:36:57 volumio1 volumio[1134]: info: Jun 24 16:36:57 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:57 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:57 volumio1 volumio[1134]: info: Jun 24 16:36:57 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:57 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:57 volumio1 volumio[1134]: error: updateQueue error: null Jun 24 16:36:57 volumio1 volumio[1134]: info: ------------------------------ 201ms Jun 24 16:36:57 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 199 milliseconds Jun 24 16:36:57 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:57 volumio1 volumio[1134]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 24 16:36:57 volumio1 volumio[1134]: info: ------------------------------ 259ms Jun 24 16:36:58 volumio1 volumio[1134]: error: updateQueue error: null Jun 24 16:36:58 volumio1 volumio[1134]: error: updateQueue error: null Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" Jun 24 16:36:58 volumio1 volumio[1134]: info: ------------------------------ 662ms Jun 24 16:36:58 volumio1 volumio[1134]: info: ------------------------------ 661ms Jun 24 16:36:58 volumio1 volumio[1134]: info: Jun 24 16:36:58 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:58 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:58 volumio1 volumio[1134]: info: sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" took 18 milliseconds Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand play Jun 24 16:36:58 volumio1 volumio[1134]: info: Jun 24 16:36:58 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:58 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:58 volumio1 volumio[1134]: info: Jun 24 16:36:58 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:58 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:58 volumio1 volumio[1134]: info: ------------------------------ 38ms Jun 24 16:36:58 volumio1 volumio[1134]: info: sendMpdCommand play took 31 milliseconds Jun 24 16:36:58 volumio1 volumio[1134]: info: ------------------------------ 26ms Jun 24 16:36:58 volumio1 volumio[1134]: info: ------------------------------ 24ms Jun 24 16:36:58 volumio1 volumio[1134]: info: Jun 24 16:36:58 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:58 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:58 volumio1 volumio[1134]: info: Jun 24 16:36:58 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:58 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:58 volumio1 volumio[1134]: info: Jun 24 16:36:58 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:58 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:58 volumio1 volumio[1134]: info: Jun 24 16:36:58 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:58 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:58 volumio1 volumio[1134]: info: Jun 24 16:36:58 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:58 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:58 volumio1 volumio[1134]: info: Jun 24 16:36:58 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:58 volumio1 volumio[1134]: info: sendMpdCommand status took 41 milliseconds Jun 24 16:36:58 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:58 volumio1 volumio[1134]: info: sendMpdCommand status took 43 milliseconds Jun 24 16:36:58 volumio1 volumio[1134]: info: sendMpdCommand status took 27 milliseconds Jun 24 16:36:58 volumio1 volumio[1134]: info: sendMpdCommand status took 20 milliseconds Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:58 volumio1 volumio[1134]: info: sendMpdCommand status took 26 milliseconds Jun 24 16:36:58 volumio1 volumio[1134]: info: sendMpdCommand status took 15 milliseconds Jun 24 16:36:58 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 24 16:36:58 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 24 16:36:58 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 24 16:36:58 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:58 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:58 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Jun 24 16:36:58 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Jun 24 16:36:58 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:58 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Jun 24 16:36:58 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:58 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:58 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:58 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Jun 24 16:36:58 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:58 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:58 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:58 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Jun 24 16:36:58 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:58 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:58 volumio1 volumio[1134]: info: ------------------------------ 169ms Jun 24 16:36:58 volumio1 volumio[1134]: info: ------------------------------ 309ms Jun 24 16:36:58 volumio1 volumio[1134]: info: ------------------------------ 363ms Jun 24 16:36:58 volumio1 volumio[1134]: info: ------------------------------ 349ms Jun 24 16:36:58 volumio1 volumio[1134]: info: Jun 24 16:36:58 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:58 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:58 volumio1 volumio[1134]: info: Jun 24 16:36:58 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:58 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 337 milliseconds Jun 24 16:36:58 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 325 milliseconds Jun 24 16:36:58 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:58 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:58 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:58 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Jun 24 16:36:58 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:58 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:58 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:58 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Jun 24 16:36:58 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:58 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:58 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:59 volumio1 volumio[1134]: info: ------------------------------ 579ms Jun 24 16:36:59 volumio1 volumio[1134]: info: ------------------------------ 575ms Jun 24 16:36:59 volumio1 volumio[1134]: info: Jun 24 16:36:59 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:59 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:59 volumio1 volumio[1134]: info: Jun 24 16:36:59 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:59 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:59 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:59 volumio1 volumio[1134]: info: Jun 24 16:36:59 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:36:59 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:36:59 volumio1 volumio[1134]: info: Jun 24 16:36:59 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:36:59 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:36:59 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:36:59 volumio1 volumio[1134]: info: ------------------------------ 387ms Jun 24 16:36:59 volumio1 volumio[1134]: info: sendMpdCommand status took 385 milliseconds Jun 24 16:36:59 volumio1 volumio[1134]: info: ------------------------------ 42ms Jun 24 16:36:59 volumio1 volumio[1134]: info: sendMpdCommand status took 34 milliseconds Jun 24 16:36:59 volumio1 volumio[1134]: info: ------------------------------ 28ms Jun 24 16:36:59 volumio1 volumio[1134]: info: sendMpdCommand status took 24 milliseconds Jun 24 16:36:59 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:59 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:59 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:59 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:59 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:36:59 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:36:59 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 21 milliseconds Jun 24 16:36:59 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 21 milliseconds Jun 24 16:36:59 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 21 milliseconds Jun 24 16:36:59 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:59 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:59 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:36:59 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:59 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:59 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1204,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" - ","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Jun 24 16:36:59 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:59 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:59 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:59 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:59 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1456,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" - ","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Jun 24 16:36:59 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:59 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:59 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:36:59 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:36:59 volumio1 volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1594,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" - ","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Jun 24 16:36:59 volumio1 volumio[1134]: verbose: CURRENT POSITION 0 Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreStateMachine::syncState stateService play Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:36:59 volumio1 volumio[1134]: info: Received an update from plugin. extracting info from payload Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:36:59 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:36:59 volumio1 volumio[1134]: info: ------------------------------ 683ms Jun 24 16:36:59 volumio1 volumio[1134]: info: ------------------------------ 342ms Jun 24 16:36:59 volumio1 volumio[1134]: info: ------------------------------ 334ms Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreCommandRouter::volumioStop Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreStateMachine::stop Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreStateMachine::stPlaybackTimer Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreStateMachine::updateTrackBlock Jun 24 16:37:02 volumio1 volumio[1134]: info: CorePlayQueue::getTrackBlock Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:37:02 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreStateMachine::serviceStop Jun 24 16:37:02 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreCommandRouter::serviceStop Jun 24 16:37:02 volumio1 volumio[1134]: info: [1750750622370] ControllerWebradio::stop Jun 24 16:37:02 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand stop Jun 24 16:37:02 volumio1 volumio[1134]: info: Jun 24 16:37:02 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:37:02 volumio1 volumio[1134]: info: sendMpdCommand stop took 46 milliseconds Jun 24 16:37:02 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:37:02 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:37:02 volumio1 volumio[1134]: info: Jun 24 16:37:02 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:37:02 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:37:02 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:37:02 volumio1 volumio[1134]: info: Jun 24 16:37:02 volumio1 volumio[1134]: ---------------------------- MPD announces state update: player Jun 24 16:37:02 volumio1 volumio[1134]: info: ControllerMpd::getState Jun 24 16:37:02 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:37:02 volumio1 volumio[1134]: info: sendMpdCommand status took 20 milliseconds Jun 24 16:37:02 volumio1 volumio[1134]: info: sendMpdCommand status took 17 milliseconds Jun 24 16:37:02 volumio1 volumio[1134]: info: sendMpdCommand status took 19 milliseconds Jun 24 16:37:02 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:37:02 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:37:02 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:37:02 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:37:02 volumio1 volumio[1134]: verbose: ControllerMpd::parseState Jun 24 16:37:02 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:37:02 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 24 16:37:02 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 24 16:37:02 volumio1 volumio[1134]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 24 16:37:02 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:37:02 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:37:02 volumio1 volumio[1134]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:37:02 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:37:02 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:37:02 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:37:02 volumio1 volumio[1134]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 24 16:37:02 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:37:02 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:37:02 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:37:02 volumio1 volumio[1134]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 24 16:37:02 volumio1 volumio[1134]: info: ControllerMpd::pushState Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreCommandRouter::servicePushState Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreStateMachine::pushState Jun 24 16:37:02 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:37:02 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushState Jun 24 16:37:02 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:37:02 volumio1 volumio[1134]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 24 16:37:02 volumio1 volumio[1134]: info: ------------------------------ 174ms Jun 24 16:37:02 volumio1 volumio[1134]: info: ------------------------------ 163ms Jun 24 16:37:02 volumio1 volumio[1134]: info: ------------------------------ 164ms Jun 24 16:37:05 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 24 16:37:05 volumio1 volumio[1134]: info: Preload queue cleared Jun 24 16:37:11 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 24 16:37:11 volumio1 volumio[1134]: info: Preload queue cleared Jun 24 16:37:19 volumio1 volumio[1134]: info: Preload queue cleared Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreStateMachine::ClearQueue Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreStateMachine::stop Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:37:19 volumio1 volumio[1134]: info: CorePlayQueue::clearPlayQueue Jun 24 16:37:19 volumio1 volumio[1134]: info: CorePlayQueue::saveQueue Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushQueue Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreStateMachine::addQueueItems Jun 24 16:37:19 volumio1 volumio[1134]: info: CorePlayQueue::addQueueItems Jun 24 16:37:19 volumio1 volumio[1134]: info: Preload queue cleared Jun 24 16:37:19 volumio1 volumio[1134]: info: Adding Item to queue: https://listen.classicalradio.com/premium_high/baroqueperiod.pls?listen_key=6e35cf52af1ca839ef561e77 Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushQueue Jun 24 16:37:19 volumio1 volumio[1134]: info: CorePlayQueue::saveQueue Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreStateMachine::updateTrackBlock Jun 24 16:37:19 volumio1 volumio[1134]: info: CorePlayQueue::getTrackBlock Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPlay Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreStateMachine::play index 0 Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreStateMachine::stop Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreStateMachine::play index undefined Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:37:19 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:37:19 volumio1 volumio[1134]: info: CoreStateMachine::startPlaybackTimer Jun 24 16:37:19 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:37:19 volumio1 volumio[1134]: info: [1750750639858] ControllerWebradio::clearAddPlayTrack Jun 24 16:37:19 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand stop Jun 24 16:37:19 volumio1 volumio[1134]: info: sendMpdCommand stop took 46 milliseconds Jun 24 16:37:19 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand clear Jun 24 16:37:19 volumio1 volumio[1134]: info: Jun 24 16:37:19 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:37:19 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:37:19 volumio1 volumio[1134]: info: sendMpdCommand clear took 13 milliseconds Jun 24 16:37:19 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand load "https://listen.classicalradio.com/premium_high/baroqueperiod.pls?listen_key=6e35cf52af1ca839ef561e77" Jun 24 16:37:19 volumio1 volumio[1134]: info: Jun 24 16:37:19 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:37:19 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:37:19 volumio1 volumio[1134]: info: Jun 24 16:37:19 volumio1 volumio[1134]: ---------------------------- MPD announces system playlist update Jun 24 16:37:19 volumio1 volumio[1134]: info: Ignoring MPD Status Update Jun 24 16:37:19 volumio1 volumio[1134]: error: updateQueue error: null Jun 24 16:37:19 volumio1 volumio[1134]: info: ------------------------------ 24ms Jun 24 16:37:25 volumio1 volumio[1134]: info: Preload queue cleared Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreStateMachine::ClearQueue Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreStateMachine::stop Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:37:25 volumio1 volumio[1134]: info: CorePlayQueue::clearPlayQueue Jun 24 16:37:25 volumio1 volumio[1134]: info: CorePlayQueue::saveQueue Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushQueue Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreStateMachine::addQueueItems Jun 24 16:37:25 volumio1 volumio[1134]: info: CorePlayQueue::addQueueItems Jun 24 16:37:25 volumio1 volumio[1134]: info: Preload queue cleared Jun 24 16:37:25 volumio1 volumio[1134]: info: Adding Item to queue: https://listen.classicalradio.com/premium_high/baroqueperiod.pls?listen_key=6e35cf52af1ca839ef561e77 Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushQueue Jun 24 16:37:25 volumio1 volumio[1134]: info: CorePlayQueue::saveQueue Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreStateMachine::updateTrackBlock Jun 24 16:37:25 volumio1 volumio[1134]: info: CorePlayQueue::getTrackBlock Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPlay Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreStateMachine::play index 0 Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreStateMachine::stop Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreStateMachine::play index undefined Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:37:25 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:37:25 volumio1 volumio[1134]: info: CoreStateMachine::startPlaybackTimer Jun 24 16:37:25 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:37:25 volumio1 volumio[1134]: info: [1750750645717] ControllerWebradio::clearAddPlayTrack Jun 24 16:37:25 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand stop Jun 24 16:37:26 volumio1 volumio[1134]: info: Preload queue cleared Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreStateMachine::ClearQueue Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreStateMachine::stop Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:37:26 volumio1 volumio[1134]: info: CorePlayQueue::clearPlayQueue Jun 24 16:37:26 volumio1 volumio[1134]: info: CorePlayQueue::saveQueue Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushQueue Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreStateMachine::addQueueItems Jun 24 16:37:26 volumio1 volumio[1134]: info: CorePlayQueue::addQueueItems Jun 24 16:37:26 volumio1 volumio[1134]: info: Preload queue cleared Jun 24 16:37:26 volumio1 volumio[1134]: info: Adding Item to queue: https://listen.classicalradio.com/premium_high/baroqueperiod.pls?listen_key=6e35cf52af1ca839ef561e77 Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPushQueue Jun 24 16:37:26 volumio1 volumio[1134]: info: CorePlayQueue::saveQueue Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreStateMachine::updateTrackBlock Jun 24 16:37:26 volumio1 volumio[1134]: info: CorePlayQueue::getTrackBlock Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreCommandRouter::volumioPlay Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreStateMachine::play index 0 Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreStateMachine::stop Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreStateMachine::play index undefined Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:37:26 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:37:26 volumio1 volumio[1134]: info: CoreStateMachine::startPlaybackTimer Jun 24 16:37:26 volumio1 volumio[1134]: info: CorePlayQueue::getTrack 0 Jun 24 16:37:26 volumio1 volumio[1134]: info: [1750750646824] ControllerWebradio::clearAddPlayTrack Jun 24 16:37:26 volumio1 volumio[1134]: verbose: ControllerMpd::sendMpdCommand stop Jun 24 16:37:40 volumio1 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 24 16:37:42 volumio1 volumio[1134]: Use XMLElement.setAttribute instead Jun 24 16:37:42 volumio1 volumio[1134]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 16:37:42 volumio1 volumio[1134]: TypeError: children[i].attr(...).value is not a function Jun 24 16:37:42 volumio1 volumio[1134]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 Jun 24 16:37:42 volumio1 volumio[1134]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 24 16:37:42 volumio1 volumio[1134]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 16:37:45 volumio1 sudo[2131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-24 16:36' Jun 24 16:37:45 volumio1 sudo[2131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 24 16:37:45 volumio1 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 24 16:37:45 volumio1 dbus-daemon[582]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.26' (uid=0 pid=2134 comm="timedatectl show --property=NTPSynchronized --valu") Jun 24 16:37:45 volumio1 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 24 16:37:45 volumio1 dbus-daemon[582]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 24 16:37:45 volumio1 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 24 16:37:45 volumio1 setdatetime-helper.sh[2132]: Time is not synchronized. Attempting to sync... Jun 24 16:37:45 volumio1 sudo[2142]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Tue, 24 Jun 2025 07:37:46 GMT#015' Jun 24 16:37:45 volumio1 sudo[2142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 24 16:37:46 volumio1 setdatetime-helper.sh[2143]: Tue Jun 24 16:37:46 JST 2025 Jun 24 16:37:46 volumio1 sudo[2142]: pam_unix(sudo:session): session closed for user root Jun 24 16:37:46 volumio1 setdatetime-helper.sh[2132]: Time synchronized successfully. Jun 24 16:37:46 volumio1 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 24 16:37:46 volumio1 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. 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="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="b0310d60a07948a2a8a2e8db322034758c86aad6" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 21 15:01:23 UTC 2025" VOLUMIO_VERSION="4.013" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6df2d5f0b86e6f2c0cd4dc68c66de48c"