-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-09-16 20:47:02 CEST. -- Sep 16 20:46:00 volumio volumio[968]: info: Reading package lists... Sep 16 20:46:00 volumio volumio[968]: info: Building dependency tree... Sep 16 20:46:00 volumio volumio[968]: info: Reading state information... Sep 16 20:46:02 volumio volumio[968]: info: drc is already the newest version (3.2.2~dfsg0-2). Sep 16 20:46:02 volumio sudo[4704]: pam_unix(sudo:session): session closed for user root Sep 16 20:46:02 volumio sudo[4321]: pam_unix(sudo:session): session closed for user root Sep 16 20:46:02 volumio volumio[968]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. Sep 16 20:46:02 volumio volumio[968]: info: Install script completed Sep 16 20:46:02 volumio volumio[968]: info: Done installing plugin. Sep 16 20:46:02 volumio volumio[968]: info: Enabling plugin fusiondsp Sep 16 20:46:02 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 16 20:46:02 volumio volumio[968]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.61","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":true,"enabled":true,"active":false},{"prettyName":"YouTube Cast Receiver","name":"ytcr","category":"music_service","version":"1.2.0","icon":"fa-youtube","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.3.1","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true}] Sep 16 20:46:02 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 16 20:46:02 volumio volumio[968]: info: Folder /tmp/plugins removed Sep 16 20:46:02 volumio volumio[968]: info: Folder /tmp/downloaded_plugin.zip removed Sep 16 20:46:02 volumio volumio[968]: Plugin install end detected on script Sep 16 20:46:02 volumio volumio[968]: info: Folder /data/temp removed Sep 16 20:46:02 volumio systemd[1]: Starting Cleanup of Temporary Directories... Sep 16 20:46:02 volumio volumio[968]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 20:46:02 volumio volumio[968]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 20:46:02 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Sep 16 20:46:02 volumio systemd[1]: Started Cleanup of Temporary Directories. Sep 16 20:46:21 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 16 20:46:21 volumio volumio[968]: info: Preload queue cleared Sep 16 20:46:32 volumio volumio[968]: info: CoreCommandRouter::volumioSeek Sep 16 20:46:32 volumio volumio[968]: info: CoreStateMachine::seek Sep 16 20:46:32 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:32 volumio volumio[968]: info: TRACKBLOCK {"service":"ytmusic","uri":"ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Second%20Sight%22%2C%22artist%22%3A%22Paste%20Magazine%22%2C%22album%22%3A%22The%20Gotobeds%20-%20Full%20Session%20%7C%20Paste%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F_unaWZ3DXeEwZkzOitg0a3UcJSArfau3dq5sEGkfDhI9PkHZeYzMehq_gb8S7KmE41WBSFbQwl0IH_4s%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22qhcBOhDPICI%22%2C%22playlistId%22%3A%22OLAK5uy_kYlBOgVrDznBTb20e9fqocxWBafORqz7Q%22%2C%22index%22%3A2%2C%22playlistSetVideoId%22%3A%22D0A0EF93DCE5742B%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kYlBOgVrDznBTb20e9fqocxWBafORqz7Q%22%2C%22videoId%22%3A%22qhcBOhDPICI%22%7D%7D%7D%7D","albumart":"https://lh3.googleusercontent.com/_unaWZ3DXeEwZkzOitg0a3UcJSArfau3dq5sEGkfDhI9PkHZeYzMehq_gb8S7KmE41WBSFbQwl0IH_4s=w701-h701-l90-rj","artist":"Paste Magazine","album":"The Gotobeds - Full Session | Paste","name":"Second Sight","title":"Second Sight","duration":226,"samplerate":"128 kbps"} Sep 16 20:46:32 volumio volumio[968]: info: CoreStateMachine::startPlaybackTimer Sep 16 20:46:32 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:32 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 16 20:46:32 volumio volumio[968]: info: ControllerMpd::seek Sep 16 20:46:32 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:32 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 20:46:32 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:32 volumio volumio[968]: error: null Sep 16 20:46:32 volumio volumio[968]: info: CoreCommandRouter::volumioSeek Sep 16 20:46:32 volumio volumio[968]: info: CoreStateMachine::seek Sep 16 20:46:32 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:32 volumio volumio[968]: info: TRACKBLOCK {"service":"ytmusic","uri":"ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Second%20Sight%22%2C%22artist%22%3A%22Paste%20Magazine%22%2C%22album%22%3A%22The%20Gotobeds%20-%20Full%20Session%20%7C%20Paste%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F_unaWZ3DXeEwZkzOitg0a3UcJSArfau3dq5sEGkfDhI9PkHZeYzMehq_gb8S7KmE41WBSFbQwl0IH_4s%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22qhcBOhDPICI%22%2C%22playlistId%22%3A%22OLAK5uy_kYlBOgVrDznBTb20e9fqocxWBafORqz7Q%22%2C%22index%22%3A2%2C%22playlistSetVideoId%22%3A%22D0A0EF93DCE5742B%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kYlBOgVrDznBTb20e9fqocxWBafORqz7Q%22%2C%22videoId%22%3A%22qhcBOhDPICI%22%7D%7D%7D%7D","albumart":"https://lh3.googleusercontent.com/_unaWZ3DXeEwZkzOitg0a3UcJSArfau3dq5sEGkfDhI9PkHZeYzMehq_gb8S7KmE41WBSFbQwl0IH_4s=w701-h701-l90-rj","artist":"Paste Magazine","album":"The Gotobeds - Full Session | Paste","name":"Second Sight","title":"Second Sight","duration":226,"samplerate":"128 kbps"} Sep 16 20:46:32 volumio volumio[968]: info: CoreStateMachine::startPlaybackTimer Sep 16 20:46:32 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:32 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 16 20:46:32 volumio volumio[968]: info: ControllerMpd::seek Sep 16 20:46:32 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:32 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:33 volumio volumio[968]: error: [5@0] {seek} Failed to decode https://rr3---sn-hgn7yn7e.googlevideo.com/videoplayback?expire=1758069404&ei=PK7JaL65FK-dzPsPub2J2AQ&ip=78.208.23.183&id=o-AGsLElZcaoBtEmIGumaHvoCfM532vGO6MJ1HvqTubE7t&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758047804%2C&mh=eV&mm=31%2C29&mn=sn-hgn7yn7e%2Csn-hpa7znzy&ms=au%2Crdu&mv=m&mvi=3&pl=17&rms=au%2Cau&gcr=it&initcwndbps=525000&siu=1&bui=AY1jyLMsJ2blQJoBjD7-ddK2frlJjH8mKT_s_7IJ6Ej5s5swDtc0UCT2_sbEhaBmAyxsIWCHyg&spc=l3OVKf9I6RwItw98gQlMX7Nt2961O9Hv2wHJQz8iWsnx2cVFicnZVHzIAOtPlyFAoA&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=lAFrsk_D8bOnXKjQcUUbSQcQ&rqh=1&gir=yes&clen=3656519&dur=225.767&lmt=1757010147247564&mt=1758047411&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=sV7cWLUO9xg-Z4BhuH-_w8_w1CmDPv_OyBuBWMijO&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbp; got HTTP status 403 Sep 16 20:46:33 volumio volumio[968]: info: Sep 16 20:46:33 volumio volumio[968]: ---------------------------- MPD announces state update: player Sep 16 20:46:33 volumio volumio[968]: info: ControllerMpd::getState Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Sep 16 20:46:33 volumio volumio[968]: info: Sep 16 20:46:33 volumio volumio[968]: ---------------------------- MPD announces state update: player Sep 16 20:46:33 volumio volumio[968]: info: ControllerMpd::getState Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Sep 16 20:46:33 volumio volumio[968]: info: Sep 16 20:46:33 volumio volumio[968]: ---------------------------- MPD announces state update: player Sep 16 20:46:33 volumio volumio[968]: info: ControllerMpd::getState Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 16 20:46:33 volumio volumio[968]: info: sendMpdCommand status took 14 milliseconds Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 16 20:46:33 volumio volumio[968]: info: sendMpdCommand status took 10 milliseconds Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 16 20:46:33 volumio volumio[968]: info: sendMpdCommand status took 9 milliseconds Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::parseState Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::parseState Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::parseState Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 20:46:33 volumio volumio[968]: info: Sep 16 20:46:33 volumio volumio[968]: ---------------------------- MPD announces state update: player Sep 16 20:46:33 volumio volumio[968]: info: ControllerMpd::getState Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Sep 16 20:46:33 volumio volumio[968]: info: Sep 16 20:46:33 volumio volumio[968]: ---------------------------- MPD announces state update: player Sep 16 20:46:33 volumio volumio[968]: info: ControllerMpd::getState Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Sep 16 20:46:33 volumio volumio[968]: error: Upnp client error: Error: This socket has been ended by the other party Sep 16 20:46:33 volumio volumio[968]: info: sendMpdCommand clearerror took 19 milliseconds Sep 16 20:46:33 volumio volumio[968]: info: sendMpdCommand clearerror took 19 milliseconds Sep 16 20:46:33 volumio volumio[968]: info: sendMpdCommand clearerror took 18 milliseconds Sep 16 20:46:33 volumio volumio[968]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 16 20:46:33 volumio volumio[968]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 16 20:46:33 volumio volumio[968]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 16 20:46:33 volumio volumio[968]: info: sendMpdCommand status took 6 milliseconds Sep 16 20:46:33 volumio volumio[968]: info: sendMpdCommand status took 8 milliseconds Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::parseState Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::parseState Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 20:46:33 volumio volumio[968]: info: ControllerMpd::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::servicePushState Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:33 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:33 volumio volumio[968]: 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":"Second Sight","artist":"Paste Magazine","album":"The Gotobeds - Full Session | Paste","uri":"https://rr3---sn-hgn7yn7e.googlevideo.com/videoplayback?expire=1758069404&ei=PK7JaL65FK-dzPsPub2J2AQ&ip=78.208.23.183&id=o-AGsLElZcaoBtEmIGumaHvoCfM532vGO6MJ1HvqTubE7t&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758047804%2C&mh=eV&mm=31%2C29&mn=sn-hgn7yn7e%2Csn-hpa7znzy&ms=au%2Crdu&mv=m&mvi=3&pl=17&rms=au%2Cau&gcr=it&initcwndbps=525000&siu=1&bui=AY1jyLMsJ2blQJoBjD7-ddK2frlJjH8mKT_s_7IJ6Ej5s5swDtc0UCT2_sbEhaBmAyxsIWCHyg&spc=l3OVKf9I6RwItw98gQlMX7Nt2961O9Hv2wHJQz8iWsnx2cVFicnZVHzIAOtPlyFAoA&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=lAFrsk_D8bOnXKjQcUUbSQcQ&rqh=1&gir=yes&clen=3656519&dur=225.767&lmt=1757010147247564&mt=1758047411&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=sV7cWLUO9xg-Z4BhuH-_w8_w1CmDPv_OyBuBWMijO&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAPi8Kr4PTKKnpGqXEnFu98E2xSnbsQocpHssNsPvTBL3AiEA83jHW2W90_cvdDnW8DvBNcI51zX_vlRJx56L-vCce34%3D&pot=MpoBbZeNeXXniIttqRNU9gDLs2ciFV4S8XUc6S2l_AAvOcBqpqKqTyWmr2CHUk0haAj5Gbqf6aigVDVj2sJhN5QhLAwVWcGTcUCoskHtBnJ-dUuczw62bxebbMd2ZLXudq2G40RG2n_7SFLwd_pZzsOm6KW5qr1rc6NtQPrr9w0iKxVH0_2ucZIPtih45jDPmuaeYlgCt-8ckuQWOw%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 16 20:46:33 volumio volumio[968]: verbose: CURRENT POSITION 2 Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::syncState stateService stop Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:33 volumio volumio[968]: info: No code Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:33 volumio volumio[968]: info: ControllerMpd::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::servicePushState Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:33 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:33 volumio volumio[968]: 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":"Second Sight","artist":"Paste Magazine","album":"The Gotobeds - Full Session | Paste","uri":"https://rr3---sn-hgn7yn7e.googlevideo.com/videoplayback?expire=1758069404&ei=PK7JaL65FK-dzPsPub2J2AQ&ip=78.208.23.183&id=o-AGsLElZcaoBtEmIGumaHvoCfM532vGO6MJ1HvqTubE7t&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758047804%2C&mh=eV&mm=31%2C29&mn=sn-hgn7yn7e%2Csn-hpa7znzy&ms=au%2Crdu&mv=m&mvi=3&pl=17&rms=au%2Cau&gcr=it&initcwndbps=525000&siu=1&bui=AY1jyLMsJ2blQJoBjD7-ddK2frlJjH8mKT_s_7IJ6Ej5s5swDtc0UCT2_sbEhaBmAyxsIWCHyg&spc=l3OVKf9I6RwItw98gQlMX7Nt2961O9Hv2wHJQz8iWsnx2cVFicnZVHzIAOtPlyFAoA&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=lAFrsk_D8bOnXKjQcUUbSQcQ&rqh=1&gir=yes&clen=3656519&dur=225.767&lmt=1757010147247564&mt=1758047411&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=sV7cWLUO9xg-Z4BhuH-_w8_w1CmDPv_OyBuBWMijO&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAPi8Kr4PTKKnpGqXEnFu98E2xSnbsQocpHssNsPvTBL3AiEA83jHW2W90_cvdDnW8DvBNcI51zX_vlRJx56L-vCce34%3D&pot=MpoBbZeNeXXniIttqRNU9gDLs2ciFV4S8XUc6S2l_AAvOcBqpqKqTyWmr2CHUk0haAj5Gbqf6aigVDVj2sJhN5QhLAwVWcGTcUCoskHtBnJ-dUuczw62bxebbMd2ZLXudq2G40RG2n_7SFLwd_pZzsOm6KW5qr1rc6NtQPrr9w0iKxVH0_2ucZIPtih45jDPmuaeYlgCt-8ckuQWOw%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 16 20:46:33 volumio volumio[968]: verbose: CURRENT POSITION 2 Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::syncState stateService stop Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:33 volumio volumio[968]: info: No code Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:33 volumio volumio[968]: info: ControllerMpd::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::servicePushState Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:33 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:33 volumio volumio[968]: 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":"Second Sight","artist":"Paste Magazine","album":"The Gotobeds - Full Session | Paste","uri":"https://rr3---sn-hgn7yn7e.googlevideo.com/videoplayback?expire=1758069404&ei=PK7JaL65FK-dzPsPub2J2AQ&ip=78.208.23.183&id=o-AGsLElZcaoBtEmIGumaHvoCfM532vGO6MJ1HvqTubE7t&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758047804%2C&mh=eV&mm=31%2C29&mn=sn-hgn7yn7e%2Csn-hpa7znzy&ms=au%2Crdu&mv=m&mvi=3&pl=17&rms=au%2Cau&gcr=it&initcwndbps=525000&siu=1&bui=AY1jyLMsJ2blQJoBjD7-ddK2frlJjH8mKT_s_7IJ6Ej5s5swDtc0UCT2_sbEhaBmAyxsIWCHyg&spc=l3OVKf9I6RwItw98gQlMX7Nt2961O9Hv2wHJQz8iWsnx2cVFicnZVHzIAOtPlyFAoA&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=lAFrsk_D8bOnXKjQcUUbSQcQ&rqh=1&gir=yes&clen=3656519&dur=225.767&lmt=1757010147247564&mt=1758047411&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=sV7cWLUO9xg-Z4BhuH-_w8_w1CmDPv_OyBuBWMijO&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAPi8Kr4PTKKnpGqXEnFu98E2xSnbsQocpHssNsPvTBL3AiEA83jHW2W90_cvdDnW8DvBNcI51zX_vlRJx56L-vCce34%3D&pot=MpoBbZeNeXXniIttqRNU9gDLs2ciFV4S8XUc6S2l_AAvOcBqpqKqTyWmr2CHUk0haAj5Gbqf6aigVDVj2sJhN5QhLAwVWcGTcUCoskHtBnJ-dUuczw62bxebbMd2ZLXudq2G40RG2n_7SFLwd_pZzsOm6KW5qr1rc6NtQPrr9w0iKxVH0_2ucZIPtih45jDPmuaeYlgCt-8ckuQWOw%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 16 20:46:33 volumio volumio[968]: verbose: CURRENT POSITION 2 Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::syncState stateService stop Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:33 volumio volumio[968]: info: No code Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:33 volumio volumio[968]: info: ------------------------------ 115ms Sep 16 20:46:33 volumio volumio[968]: info: ------------------------------ 110ms Sep 16 20:46:33 volumio volumio[968]: info: ------------------------------ 110ms Sep 16 20:46:33 volumio volumio[968]: info: sendMpdCommand playlistinfo took 87 milliseconds Sep 16 20:46:33 volumio volumio[968]: info: sendMpdCommand playlistinfo took 88 milliseconds Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Sep 16 20:46:33 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Sep 16 20:46:33 volumio volumio[968]: info: ControllerMpd::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::servicePushState Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:33 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:33 volumio volumio[968]: 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":"Second Sight","artist":"Paste Magazine","album":"The Gotobeds - Full Session | Paste","uri":"https://rr3---sn-hgn7yn7e.googlevideo.com/videoplayback?expire=1758069404&ei=PK7JaL65FK-dzPsPub2J2AQ&ip=78.208.23.183&id=o-AGsLElZcaoBtEmIGumaHvoCfM532vGO6MJ1HvqTubE7t&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758047804%2C&mh=eV&mm=31%2C29&mn=sn-hgn7yn7e%2Csn-hpa7znzy&ms=au%2Crdu&mv=m&mvi=3&pl=17&rms=au%2Cau&gcr=it&initcwndbps=525000&siu=1&bui=AY1jyLMsJ2blQJoBjD7-ddK2frlJjH8mKT_s_7IJ6Ej5s5swDtc0UCT2_sbEhaBmAyxsIWCHyg&spc=l3OVKf9I6RwItw98gQlMX7Nt2961O9Hv2wHJQz8iWsnx2cVFicnZVHzIAOtPlyFAoA&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=lAFrsk_D8bOnXKjQcUUbSQcQ&rqh=1&gir=yes&clen=3656519&dur=225.767&lmt=1757010147247564&mt=1758047411&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=sV7cWLUO9xg-Z4BhuH-_w8_w1CmDPv_OyBuBWMijO&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAPi8Kr4PTKKnpGqXEnFu98E2xSnbsQocpHssNsPvTBL3AiEA83jHW2W90_cvdDnW8DvBNcI51zX_vlRJx56L-vCce34%3D&pot=MpoBbZeNeXXniIttqRNU9gDLs2ciFV4S8XUc6S2l_AAvOcBqpqKqTyWmr2CHUk0haAj5Gbqf6aigVDVj2sJhN5QhLAwVWcGTcUCoskHtBnJ-dUuczw62bxebbMd2ZLXudq2G40RG2n_7SFLwd_pZzsOm6KW5qr1rc6NtQPrr9w0iKxVH0_2ucZIPtih45jDPmuaeYlgCt-8ckuQWOw%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 16 20:46:33 volumio volumio[968]: verbose: CURRENT POSITION 2 Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::syncState stateService stop Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:33 volumio volumio[968]: info: No code Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:33 volumio volumio[968]: info: ControllerMpd::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::servicePushState Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:33 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:33 volumio volumio[968]: 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":"Second Sight","artist":"Paste Magazine","album":"The Gotobeds - Full Session | Paste","uri":"https://rr3---sn-hgn7yn7e.googlevideo.com/videoplayback?expire=1758069404&ei=PK7JaL65FK-dzPsPub2J2AQ&ip=78.208.23.183&id=o-AGsLElZcaoBtEmIGumaHvoCfM532vGO6MJ1HvqTubE7t&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758047804%2C&mh=eV&mm=31%2C29&mn=sn-hgn7yn7e%2Csn-hpa7znzy&ms=au%2Crdu&mv=m&mvi=3&pl=17&rms=au%2Cau&gcr=it&initcwndbps=525000&siu=1&bui=AY1jyLMsJ2blQJoBjD7-ddK2frlJjH8mKT_s_7IJ6Ej5s5swDtc0UCT2_sbEhaBmAyxsIWCHyg&spc=l3OVKf9I6RwItw98gQlMX7Nt2961O9Hv2wHJQz8iWsnx2cVFicnZVHzIAOtPlyFAoA&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=lAFrsk_D8bOnXKjQcUUbSQcQ&rqh=1&gir=yes&clen=3656519&dur=225.767&lmt=1757010147247564&mt=1758047411&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=sV7cWLUO9xg-Z4BhuH-_w8_w1CmDPv_OyBuBWMijO&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAPi8Kr4PTKKnpGqXEnFu98E2xSnbsQocpHssNsPvTBL3AiEA83jHW2W90_cvdDnW8DvBNcI51zX_vlRJx56L-vCce34%3D&pot=MpoBbZeNeXXniIttqRNU9gDLs2ciFV4S8XUc6S2l_AAvOcBqpqKqTyWmr2CHUk0haAj5Gbqf6aigVDVj2sJhN5QhLAwVWcGTcUCoskHtBnJ-dUuczw62bxebbMd2ZLXudq2G40RG2n_7SFLwd_pZzsOm6KW5qr1rc6NtQPrr9w0iKxVH0_2ucZIPtih45jDPmuaeYlgCt-8ckuQWOw%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 16 20:46:33 volumio volumio[968]: verbose: CURRENT POSITION 2 Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::syncState stateService stop Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:33 volumio volumio[968]: info: No code Sep 16 20:46:33 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:33 volumio volumio[968]: info: ------------------------------ 149ms Sep 16 20:46:33 volumio volumio[968]: info: ------------------------------ 149ms Sep 16 20:46:33 volumio volumio[968]: info: CoreCommandRouter::volumioGetState Sep 16 20:46:36 volumio volumio[968]: info: CoreCommandRouter::volumioSeek Sep 16 20:46:36 volumio volumio[968]: info: CoreStateMachine::seek Sep 16 20:46:36 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:36 volumio volumio[968]: info: TRACKBLOCK {"service":"ytmusic","uri":"ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Second%20Sight%22%2C%22artist%22%3A%22Paste%20Magazine%22%2C%22album%22%3A%22The%20Gotobeds%20-%20Full%20Session%20%7C%20Paste%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F_unaWZ3DXeEwZkzOitg0a3UcJSArfau3dq5sEGkfDhI9PkHZeYzMehq_gb8S7KmE41WBSFbQwl0IH_4s%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22qhcBOhDPICI%22%2C%22playlistId%22%3A%22OLAK5uy_kYlBOgVrDznBTb20e9fqocxWBafORqz7Q%22%2C%22index%22%3A2%2C%22playlistSetVideoId%22%3A%22D0A0EF93DCE5742B%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kYlBOgVrDznBTb20e9fqocxWBafORqz7Q%22%2C%22videoId%22%3A%22qhcBOhDPICI%22%7D%7D%7D%7D","albumart":"https://lh3.googleusercontent.com/_unaWZ3DXeEwZkzOitg0a3UcJSArfau3dq5sEGkfDhI9PkHZeYzMehq_gb8S7KmE41WBSFbQwl0IH_4s=w701-h701-l90-rj","artist":"Paste Magazine","album":"The Gotobeds - Full Session | Paste","name":"Second Sight","title":"Second Sight","duration":226,"samplerate":"128 kbps"} Sep 16 20:46:36 volumio volumio[968]: info: CoreStateMachine::startPlaybackTimer Sep 16 20:46:36 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:36 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 16 20:46:36 volumio volumio[968]: info: ControllerMpd::seek Sep 16 20:46:36 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:36 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 20:46:36 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:36 volumio volumio[968]: error: null Sep 16 20:46:36 volumio volumio[968]: info: CoreCommandRouter::volumioSeek Sep 16 20:46:36 volumio volumio[968]: info: CoreStateMachine::seek Sep 16 20:46:36 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:36 volumio volumio[968]: info: TRACKBLOCK {"service":"ytmusic","uri":"ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Second%20Sight%22%2C%22artist%22%3A%22Paste%20Magazine%22%2C%22album%22%3A%22The%20Gotobeds%20-%20Full%20Session%20%7C%20Paste%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F_unaWZ3DXeEwZkzOitg0a3UcJSArfau3dq5sEGkfDhI9PkHZeYzMehq_gb8S7KmE41WBSFbQwl0IH_4s%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22qhcBOhDPICI%22%2C%22playlistId%22%3A%22OLAK5uy_kYlBOgVrDznBTb20e9fqocxWBafORqz7Q%22%2C%22index%22%3A2%2C%22playlistSetVideoId%22%3A%22D0A0EF93DCE5742B%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kYlBOgVrDznBTb20e9fqocxWBafORqz7Q%22%2C%22videoId%22%3A%22qhcBOhDPICI%22%7D%7D%7D%7D","albumart":"https://lh3.googleusercontent.com/_unaWZ3DXeEwZkzOitg0a3UcJSArfau3dq5sEGkfDhI9PkHZeYzMehq_gb8S7KmE41WBSFbQwl0IH_4s=w701-h701-l90-rj","artist":"Paste Magazine","album":"The Gotobeds - Full Session | Paste","name":"Second Sight","title":"Second Sight","duration":226,"samplerate":"128 kbps"} Sep 16 20:46:36 volumio volumio[968]: info: CoreStateMachine::startPlaybackTimer Sep 16 20:46:36 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:36 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 16 20:46:36 volumio volumio[968]: info: ControllerMpd::seek Sep 16 20:46:36 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:36 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:37 volumio volumio[968]: error: [5@0] {seek} Failed to decode https://rr3---sn-hgn7yn7e.googlevideo.com/videoplayback?expire=1758069404&ei=PK7JaL65FK-dzPsPub2J2AQ&ip=78.208.23.183&id=o-AGsLElZcaoBtEmIGumaHvoCfM532vGO6MJ1HvqTubE7t&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758047804%2C&mh=eV&mm=31%2C29&mn=sn-hgn7yn7e%2Csn-hpa7znzy&ms=au%2Crdu&mv=m&mvi=3&pl=17&rms=au%2Cau&gcr=it&initcwndbps=525000&siu=1&bui=AY1jyLMsJ2blQJoBjD7-ddK2frlJjH8mKT_s_7IJ6Ej5s5swDtc0UCT2_sbEhaBmAyxsIWCHyg&spc=l3OVKf9I6RwItw98gQlMX7Nt2961O9Hv2wHJQz8iWsnx2cVFicnZVHzIAOtPlyFAoA&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=lAFrsk_D8bOnXKjQcUUbSQcQ&rqh=1&gir=yes&clen=3656519&dur=225.767&lmt=1757010147247564&mt=1758047411&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=sV7cWLUO9xg-Z4BhuH-_w8_w1CmDPv_OyBuBWMijO&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbp; got HTTP status 403 Sep 16 20:46:37 volumio volumio[968]: info: Sep 16 20:46:37 volumio volumio[968]: ---------------------------- MPD announces state update: player Sep 16 20:46:37 volumio volumio[968]: info: ControllerMpd::getState Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Sep 16 20:46:37 volumio volumio[968]: info: Sep 16 20:46:37 volumio volumio[968]: ---------------------------- MPD announces state update: player Sep 16 20:46:37 volumio volumio[968]: info: ControllerMpd::getState Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Sep 16 20:46:37 volumio volumio[968]: info: Sep 16 20:46:37 volumio volumio[968]: ---------------------------- MPD announces state update: player Sep 16 20:46:37 volumio volumio[968]: info: ControllerMpd::getState Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 16 20:46:37 volumio volumio[968]: info: sendMpdCommand status took 12 milliseconds Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 16 20:46:37 volumio volumio[968]: info: sendMpdCommand status took 11 milliseconds Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::parseState Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::parseState Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 20:46:37 volumio volumio[968]: info: Sep 16 20:46:37 volumio volumio[968]: ---------------------------- MPD announces state update: player Sep 16 20:46:37 volumio volumio[968]: info: ControllerMpd::getState Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Sep 16 20:46:37 volumio volumio[968]: info: Sep 16 20:46:37 volumio volumio[968]: ---------------------------- MPD announces state update: player Sep 16 20:46:37 volumio volumio[968]: info: ControllerMpd::getState Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 16 20:46:37 volumio volumio[968]: info: sendMpdCommand status took 24 milliseconds Sep 16 20:46:37 volumio volumio[968]: info: sendMpdCommand clearerror took 17 milliseconds Sep 16 20:46:37 volumio volumio[968]: info: sendMpdCommand clearerror took 15 milliseconds Sep 16 20:46:37 volumio volumio[968]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 16 20:46:37 volumio volumio[968]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 16 20:46:37 volumio volumio[968]: info: sendMpdCommand status took 9 milliseconds Sep 16 20:46:37 volumio volumio[968]: info: sendMpdCommand status took 7 milliseconds Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::parseState Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::parseState Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::parseState Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 20:46:37 volumio volumio[968]: info: ControllerMpd::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::servicePushState Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:37 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:37 volumio volumio[968]: 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":"Second Sight","artist":"Paste Magazine","album":"The Gotobeds - Full Session | Paste","uri":"https://rr3---sn-hgn7yn7e.googlevideo.com/videoplayback?expire=1758069404&ei=PK7JaL65FK-dzPsPub2J2AQ&ip=78.208.23.183&id=o-AGsLElZcaoBtEmIGumaHvoCfM532vGO6MJ1HvqTubE7t&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758047804%2C&mh=eV&mm=31%2C29&mn=sn-hgn7yn7e%2Csn-hpa7znzy&ms=au%2Crdu&mv=m&mvi=3&pl=17&rms=au%2Cau&gcr=it&initcwndbps=525000&siu=1&bui=AY1jyLMsJ2blQJoBjD7-ddK2frlJjH8mKT_s_7IJ6Ej5s5swDtc0UCT2_sbEhaBmAyxsIWCHyg&spc=l3OVKf9I6RwItw98gQlMX7Nt2961O9Hv2wHJQz8iWsnx2cVFicnZVHzIAOtPlyFAoA&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=lAFrsk_D8bOnXKjQcUUbSQcQ&rqh=1&gir=yes&clen=3656519&dur=225.767&lmt=1757010147247564&mt=1758047411&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=sV7cWLUO9xg-Z4BhuH-_w8_w1CmDPv_OyBuBWMijO&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAPi8Kr4PTKKnpGqXEnFu98E2xSnbsQocpHssNsPvTBL3AiEA83jHW2W90_cvdDnW8DvBNcI51zX_vlRJx56L-vCce34%3D&pot=MpoBbZeNeXXniIttqRNU9gDLs2ciFV4S8XUc6S2l_AAvOcBqpqKqTyWmr2CHUk0haAj5Gbqf6aigVDVj2sJhN5QhLAwVWcGTcUCoskHtBnJ-dUuczw62bxebbMd2ZLXudq2G40RG2n_7SFLwd_pZzsOm6KW5qr1rc6NtQPrr9w0iKxVH0_2ucZIPtih45jDPmuaeYlgCt-8ckuQWOw%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 16 20:46:37 volumio volumio[968]: verbose: CURRENT POSITION 2 Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::syncState stateService stop Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:37 volumio volumio[968]: info: No code Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:37 volumio volumio[968]: info: ControllerMpd::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::servicePushState Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:37 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:37 volumio volumio[968]: 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":"Second Sight","artist":"Paste Magazine","album":"The Gotobeds - Full Session | Paste","uri":"https://rr3---sn-hgn7yn7e.googlevideo.com/videoplayback?expire=1758069404&ei=PK7JaL65FK-dzPsPub2J2AQ&ip=78.208.23.183&id=o-AGsLElZcaoBtEmIGumaHvoCfM532vGO6MJ1HvqTubE7t&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758047804%2C&mh=eV&mm=31%2C29&mn=sn-hgn7yn7e%2Csn-hpa7znzy&ms=au%2Crdu&mv=m&mvi=3&pl=17&rms=au%2Cau&gcr=it&initcwndbps=525000&siu=1&bui=AY1jyLMsJ2blQJoBjD7-ddK2frlJjH8mKT_s_7IJ6Ej5s5swDtc0UCT2_sbEhaBmAyxsIWCHyg&spc=l3OVKf9I6RwItw98gQlMX7Nt2961O9Hv2wHJQz8iWsnx2cVFicnZVHzIAOtPlyFAoA&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=lAFrsk_D8bOnXKjQcUUbSQcQ&rqh=1&gir=yes&clen=3656519&dur=225.767&lmt=1757010147247564&mt=1758047411&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=sV7cWLUO9xg-Z4BhuH-_w8_w1CmDPv_OyBuBWMijO&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAPi8Kr4PTKKnpGqXEnFu98E2xSnbsQocpHssNsPvTBL3AiEA83jHW2W90_cvdDnW8DvBNcI51zX_vlRJx56L-vCce34%3D&pot=MpoBbZeNeXXniIttqRNU9gDLs2ciFV4S8XUc6S2l_AAvOcBqpqKqTyWmr2CHUk0haAj5Gbqf6aigVDVj2sJhN5QhLAwVWcGTcUCoskHtBnJ-dUuczw62bxebbMd2ZLXudq2G40RG2n_7SFLwd_pZzsOm6KW5qr1rc6NtQPrr9w0iKxVH0_2ucZIPtih45jDPmuaeYlgCt-8ckuQWOw%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 16 20:46:37 volumio volumio[968]: verbose: CURRENT POSITION 2 Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::syncState stateService stop Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:37 volumio volumio[968]: info: No code Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:37 volumio volumio[968]: info: ------------------------------ 90ms Sep 16 20:46:37 volumio volumio[968]: info: ------------------------------ 89ms Sep 16 20:46:37 volumio volumio[968]: info: sendMpdCommand clearerror took 69 milliseconds Sep 16 20:46:37 volumio volumio[968]: info: sendMpdCommand playlistinfo took 67 milliseconds Sep 16 20:46:37 volumio volumio[968]: info: sendMpdCommand playlistinfo took 66 milliseconds Sep 16 20:46:37 volumio volumio[968]: info: sendMpdCommand playlistinfo took 66 milliseconds Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Sep 16 20:46:37 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Sep 16 20:46:37 volumio volumio[968]: info: ControllerMpd::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::servicePushState Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:37 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:37 volumio volumio[968]: 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":"Second Sight","artist":"Paste Magazine","album":"The Gotobeds - Full Session | Paste","uri":"https://rr3---sn-hgn7yn7e.googlevideo.com/videoplayback?expire=1758069404&ei=PK7JaL65FK-dzPsPub2J2AQ&ip=78.208.23.183&id=o-AGsLElZcaoBtEmIGumaHvoCfM532vGO6MJ1HvqTubE7t&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758047804%2C&mh=eV&mm=31%2C29&mn=sn-hgn7yn7e%2Csn-hpa7znzy&ms=au%2Crdu&mv=m&mvi=3&pl=17&rms=au%2Cau&gcr=it&initcwndbps=525000&siu=1&bui=AY1jyLMsJ2blQJoBjD7-ddK2frlJjH8mKT_s_7IJ6Ej5s5swDtc0UCT2_sbEhaBmAyxsIWCHyg&spc=l3OVKf9I6RwItw98gQlMX7Nt2961O9Hv2wHJQz8iWsnx2cVFicnZVHzIAOtPlyFAoA&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=lAFrsk_D8bOnXKjQcUUbSQcQ&rqh=1&gir=yes&clen=3656519&dur=225.767&lmt=1757010147247564&mt=1758047411&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=sV7cWLUO9xg-Z4BhuH-_w8_w1CmDPv_OyBuBWMijO&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAPi8Kr4PTKKnpGqXEnFu98E2xSnbsQocpHssNsPvTBL3AiEA83jHW2W90_cvdDnW8DvBNcI51zX_vlRJx56L-vCce34%3D&pot=MpoBbZeNeXXniIttqRNU9gDLs2ciFV4S8XUc6S2l_AAvOcBqpqKqTyWmr2CHUk0haAj5Gbqf6aigVDVj2sJhN5QhLAwVWcGTcUCoskHtBnJ-dUuczw62bxebbMd2ZLXudq2G40RG2n_7SFLwd_pZzsOm6KW5qr1rc6NtQPrr9w0iKxVH0_2ucZIPtih45jDPmuaeYlgCt-8ckuQWOw%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 16 20:46:37 volumio volumio[968]: verbose: CURRENT POSITION 2 Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::syncState stateService stop Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:37 volumio volumio[968]: info: No code Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:37 volumio volumio[968]: info: ControllerMpd::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::servicePushState Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:37 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:37 volumio volumio[968]: 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":"Second Sight","artist":"Paste Magazine","album":"The Gotobeds - Full Session | Paste","uri":"https://rr3---sn-hgn7yn7e.googlevideo.com/videoplayback?expire=1758069404&ei=PK7JaL65FK-dzPsPub2J2AQ&ip=78.208.23.183&id=o-AGsLElZcaoBtEmIGumaHvoCfM532vGO6MJ1HvqTubE7t&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758047804%2C&mh=eV&mm=31%2C29&mn=sn-hgn7yn7e%2Csn-hpa7znzy&ms=au%2Crdu&mv=m&mvi=3&pl=17&rms=au%2Cau&gcr=it&initcwndbps=525000&siu=1&bui=AY1jyLMsJ2blQJoBjD7-ddK2frlJjH8mKT_s_7IJ6Ej5s5swDtc0UCT2_sbEhaBmAyxsIWCHyg&spc=l3OVKf9I6RwItw98gQlMX7Nt2961O9Hv2wHJQz8iWsnx2cVFicnZVHzIAOtPlyFAoA&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=lAFrsk_D8bOnXKjQcUUbSQcQ&rqh=1&gir=yes&clen=3656519&dur=225.767&lmt=1757010147247564&mt=1758047411&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=sV7cWLUO9xg-Z4BhuH-_w8_w1CmDPv_OyBuBWMijO&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAPi8Kr4PTKKnpGqXEnFu98E2xSnbsQocpHssNsPvTBL3AiEA83jHW2W90_cvdDnW8DvBNcI51zX_vlRJx56L-vCce34%3D&pot=MpoBbZeNeXXniIttqRNU9gDLs2ciFV4S8XUc6S2l_AAvOcBqpqKqTyWmr2CHUk0haAj5Gbqf6aigVDVj2sJhN5QhLAwVWcGTcUCoskHtBnJ-dUuczw62bxebbMd2ZLXudq2G40RG2n_7SFLwd_pZzsOm6KW5qr1rc6NtQPrr9w0iKxVH0_2ucZIPtih45jDPmuaeYlgCt-8ckuQWOw%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 16 20:46:37 volumio volumio[968]: verbose: CURRENT POSITION 2 Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::syncState stateService stop Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:37 volumio volumio[968]: info: No code Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:37 volumio volumio[968]: info: ControllerMpd::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::servicePushState Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:37 volumio volumio[968]: info: CorePlayQueue::getTrack 2 Sep 16 20:46:37 volumio volumio[968]: 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":"Second Sight","artist":"Paste Magazine","album":"The Gotobeds - Full Session | Paste","uri":"https://rr3---sn-hgn7yn7e.googlevideo.com/videoplayback?expire=1758069404&ei=PK7JaL65FK-dzPsPub2J2AQ&ip=78.208.23.183&id=o-AGsLElZcaoBtEmIGumaHvoCfM532vGO6MJ1HvqTubE7t&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758047804%2C&mh=eV&mm=31%2C29&mn=sn-hgn7yn7e%2Csn-hpa7znzy&ms=au%2Crdu&mv=m&mvi=3&pl=17&rms=au%2Cau&gcr=it&initcwndbps=525000&siu=1&bui=AY1jyLMsJ2blQJoBjD7-ddK2frlJjH8mKT_s_7IJ6Ej5s5swDtc0UCT2_sbEhaBmAyxsIWCHyg&spc=l3OVKf9I6RwItw98gQlMX7Nt2961O9Hv2wHJQz8iWsnx2cVFicnZVHzIAOtPlyFAoA&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=lAFrsk_D8bOnXKjQcUUbSQcQ&rqh=1&gir=yes&clen=3656519&dur=225.767&lmt=1757010147247564&mt=1758047411&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=sV7cWLUO9xg-Z4BhuH-_w8_w1CmDPv_OyBuBWMijO&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAPi8Kr4PTKKnpGqXEnFu98E2xSnbsQocpHssNsPvTBL3AiEA83jHW2W90_cvdDnW8DvBNcI51zX_vlRJx56L-vCce34%3D&pot=MpoBbZeNeXXniIttqRNU9gDLs2ciFV4S8XUc6S2l_AAvOcBqpqKqTyWmr2CHUk0haAj5Gbqf6aigVDVj2sJhN5QhLAwVWcGTcUCoskHtBnJ-dUuczw62bxebbMd2ZLXudq2G40RG2n_7SFLwd_pZzsOm6KW5qr1rc6NtQPrr9w0iKxVH0_2ucZIPtih45jDPmuaeYlgCt-8ckuQWOw%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 16 20:46:37 volumio volumio[968]: verbose: CURRENT POSITION 2 Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::syncState stateService stop Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:37 volumio volumio[968]: info: No code Sep 16 20:46:37 volumio volumio[968]: info: CoreStateMachine::pushState Sep 16 20:46:37 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Sep 16 20:46:37 volumio volumio[968]: info: ------------------------------ 158ms Sep 16 20:46:37 volumio volumio[968]: info: ------------------------------ 143ms Sep 16 20:46:37 volumio volumio[968]: info: ------------------------------ 142ms Sep 16 20:46:44 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 16 20:46:44 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 16 20:46:45 volumio volumio[968]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 20:46:50 volumio volumio[968]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 20:46:55 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 16 20:46:55 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 16 20:46:55 volumio volumio[968]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 20:47:00 volumio volumio[968]: info: Enabling plugin fusiondsp Sep 16 20:47:00 volumio volumio[968]: info: Loading plugin "fusiondsp"... Sep 16 20:47:01 volumio volumio[968]: info: Preparing to generate the ALSA configuration file Sep 16 20:47:01 volumio volumio[968]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 16 20:47:01 volumio volumio[968]: info: Reading ALSA contributions from plugins. Sep 16 20:47:01 volumio volumio[968]: info: Asound.conf file written Sep 16 20:47:01 volumio sudo[4876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 16 20:47:01 volumio sudo[4876]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 20:47:01 volumio sudo[4876]: pam_unix(sudo:session): session closed for user root Sep 16 20:47:01 volumio volumio[968]: info: Output device has changed, restarting MPD Sep 16 20:47:01 volumio sudo[4881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 20:47:01 volumio sudo[4881]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 20:47:01 volumio sudo[4881]: pam_unix(sudo:session): session closed for user root Sep 16 20:47:01 volumio volumio[968]: info: Output device has changed, restarting Shairport Sync Sep 16 20:47:01 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 20:47:01 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 20:47:01 volumio sudo[4884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 20:47:01 volumio sudo[4884]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 20:47:01 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 20:47:01 volumio volumio[968]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 20:47:01 volumio volumio[968]: info: PLUGIN START: fusiondsp Sep 16 20:47:01 volumio volumio[968]: info: Loading i18n strings for locale it Sep 16 20:47:01 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 16 20:47:01 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 20:47:01 volumio volumio[968]: info: FusionDsp - mixtype--------------------- Hardware Sep 16 20:47:01 volumio volumio[968]: info: Preparing to generate the ALSA configuration file Sep 16 20:47:01 volumio volumio[968]: info: Done. Sep 16 20:47:01 volumio volumio[968]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 16 20:47:01 volumio volumio[968]: info: Reading ALSA contributions from plugins. Sep 16 20:47:01 volumio volumio[968]: info: MPD Permissions set Sep 16 20:47:01 volumio volumio[968]: info: CoreCommandRouter::volumioGetState Sep 16 20:47:01 volumio volumio[968]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 20:47:01 volumio volumio[968]: info: FusionDsp - Sep 16 20:47:01 volumio systemd[1]: mpd.service: Succeeded. Sep 16 20:47:01 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 20:47:01 volumio systemd[1]: Starting Music Player Daemon... Sep 16 20:47:01 volumio sudo[4889]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 16 20:47:01 volumio sudo[4889]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 20:47:01 volumio volumio[968]: info: FusionDsp - undefined Sep 16 20:47:01 volumio sudo[4889]: pam_unix(sudo:session): session closed for user root Sep 16 20:47:01 volumio volumio[968]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 20:47:01 volumio volumio[968]: TypeError: Cannot read property 'off' of undefined Sep 16 20:47:01 volumio volumio[968]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Sep 16 20:47:01 volumio volumio[968]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Sep 16 20:47:01 volumio volumio[968]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Sep 16 20:47:01 volumio volumio[968]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Sep 16 20:47:01 volumio volumio[968]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Sep 16 20:47:01 volumio volumio[968]: at Socket.emit (events.js:315:20) Sep 16 20:47:01 volumio volumio[968]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 16 20:47:01 volumio volumio[968]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 16 20:47:01 volumio volumio[968]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 20:47:02 volumio sudo[4901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 20:46 Sep 16 20:47:02 volumio sudo[4901]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"