Jun 01 12:53:00 volumio volumio[1224]: info: Jun 01 12:53:00 volumio volumio[1224]: ---------------------------- MPD announces state update: player Jun 01 12:53:00 volumio volumio[1224]: info: ControllerMpd::getState Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:53:00 volumio volumio[1224]: info: Jun 01 12:53:00 volumio volumio[1224]: ---------------------------- MPD announces state update: player Jun 01 12:53:00 volumio volumio[1224]: info: ControllerMpd::getState Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:53:00 volumio volumio[1224]: info: Jun 01 12:53:00 volumio volumio[1224]: ---------------------------- MPD announces state update: player Jun 01 12:53:00 volumio volumio[1224]: info: ControllerMpd::getState Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:53:00 volumio volumio[1224]: info: Jun 01 12:53:00 volumio volumio[1224]: ---------------------------- MPD announces state update: player Jun 01 12:53:00 volumio volumio[1224]: info: sendMpdCommand status took 20 milliseconds Jun 01 12:53:00 volumio volumio[1224]: info: ControllerMpd::getState Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:53:00 volumio volumio[1224]: info: sendMpdCommand status took 15 milliseconds Jun 01 12:53:00 volumio volumio[1224]: info: sendMpdCommand status took 15 milliseconds Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::parseState Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::parseState Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::parseState Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:53:00 volumio volumio[1224]: info: Jun 01 12:53:00 volumio volumio[1224]: ---------------------------- MPD announces state update: player Jun 01 12:53:00 volumio volumio[1224]: info: ControllerMpd::getState Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:53:00 volumio volumio[1224]: info: Jun 01 12:53:00 volumio volumio[1224]: ---------------------------- MPD announces state update: player Jun 01 12:53:00 volumio volumio[1224]: info: ControllerMpd::getState Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:53:00 volumio volumio[1224]: info: sendMpdCommand status took 12 milliseconds Jun 01 12:53:00 volumio volumio[1224]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 01 12:53:00 volumio volumio[1224]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 01 12:53:00 volumio volumio[1224]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 01 12:53:00 volumio volumio[1224]: info: sendMpdCommand status took 5 milliseconds Jun 01 12:53:00 volumio volumio[1224]: info: sendMpdCommand status took 4 milliseconds Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::parseState Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::parseState Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::parseState Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:53:00 volumio volumio[1224]: info: ControllerMpd::pushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::servicePushState Jun 01 12:53:00 volumio volumio[1224]: info: CorePlayQueue::getTrack 0 Jun 01 12:53:00 volumio volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5743,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Best Audiophile Voices - Hi-Res Music 24 Bit 🎵 Best Voices","artist":"Top Music","album":"YouTube","uri":"https://rr4---sn-3cgv-un5l.googlevideo.com/videoplayback?expire=1748775179&ei=q9w7aI2dHqjas8IP1ZvqmQo&ip=180.177.30.88&id=o-AOSkejGHAPzAgb1_TJiX5aYF_IOywayzxJeuXSDS_Lkj&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748753579%2C&mh=eB&mm=31%2C29&mn=sn-3cgv-un5l%2Csn-u4o-u2xe7&ms=au%2Crdu&mv=m&mvi=4&pl=22&rms=au%2Cau&pcm2=yes&initcwndbps=3028750&bui=AY1jyLP9wlgDJwpryx4EcK_365hU9keLSewoKN2FI6h6KdwYrUVi8jegS36QgyhleUGYVL_K_KZv_F2n&spc=l3OVKU8VfXC-y3u9bpPJ_GMDolDBHZyXEWo3Wd_tbY-IR51DjaRzL8B7dkPbbI6RCAs&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=FtL7iW87UzFRlxWkKubiiN8Q&rqh=1&gir=yes&clen=99478083&dur=5743.301&lmt=1735297073745108&mt=1748753334&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5432434&n=DZZkPFM5iNccEg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cpcm2%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgRlgII0ZodwuzHYmwZ0wQ4nPn0Y2buLqAmPkTvdzc5m8CICHYWKNGS-oXt2gmyYiBwZCkttKQyzh8y8KpuS7sKx8K&sig=AJfQdSswRgIhAM9sAGqovuE5sRJt3g6--ngzzajHFimmLn3gNfo9h3zxAiEA86rpS4VEjp3FU6_XNepjqjBzT-_FbwjwXueMfYQAcrI%3D&pot=MngB2sqhUdwPwwHmSahPG1k4WKKOd4mpU0XDe6_eXeo6JCVTHTl2lwp976mBOCf1LE3-6ue60oO5QBzwGmhyhSBnFvVBvbcpAUtlK2Fpry8kYGqJfLxvLEcMtCqi0JGQKNd7wtpKdTFlBE0VAJfK-f2RNAEMfBophFg%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} Jun 01 12:53:00 volumio volumio[1224]: verbose: CURRENT POSITION 0 Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::syncState stateService play Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:53:00 volumio volumio[1224]: info: ControllerMpd::pushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::servicePushState Jun 01 12:53:00 volumio volumio[1224]: info: CorePlayQueue::getTrack 0 Jun 01 12:53:00 volumio volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5743,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Best Audiophile Voices - Hi-Res Music 24 Bit 🎵 Best Voices","artist":"Top Music","album":"YouTube","uri":"https://rr4---sn-3cgv-un5l.googlevideo.com/videoplayback?expire=1748775179&ei=q9w7aI2dHqjas8IP1ZvqmQo&ip=180.177.30.88&id=o-AOSkejGHAPzAgb1_TJiX5aYF_IOywayzxJeuXSDS_Lkj&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748753579%2C&mh=eB&mm=31%2C29&mn=sn-3cgv-un5l%2Csn-u4o-u2xe7&ms=au%2Crdu&mv=m&mvi=4&pl=22&rms=au%2Cau&pcm2=yes&initcwndbps=3028750&bui=AY1jyLP9wlgDJwpryx4EcK_365hU9keLSewoKN2FI6h6KdwYrUVi8jegS36QgyhleUGYVL_K_KZv_F2n&spc=l3OVKU8VfXC-y3u9bpPJ_GMDolDBHZyXEWo3Wd_tbY-IR51DjaRzL8B7dkPbbI6RCAs&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=FtL7iW87UzFRlxWkKubiiN8Q&rqh=1&gir=yes&clen=99478083&dur=5743.301&lmt=1735297073745108&mt=1748753334&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5432434&n=DZZkPFM5iNccEg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cpcm2%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgRlgII0ZodwuzHYmwZ0wQ4nPn0Y2buLqAmPkTvdzc5m8CICHYWKNGS-oXt2gmyYiBwZCkttKQyzh8y8KpuS7sKx8K&sig=AJfQdSswRgIhAM9sAGqovuE5sRJt3g6--ngzzajHFimmLn3gNfo9h3zxAiEA86rpS4VEjp3FU6_XNepjqjBzT-_FbwjwXueMfYQAcrI%3D&pot=MngB2sqhUdwPwwHmSahPG1k4WKKOd4mpU0XDe6_eXeo6JCVTHTl2lwp976mBOCf1LE3-6ue60oO5QBzwGmhyhSBnFvVBvbcpAUtlK2Fpry8kYGqJfLxvLEcMtCqi0JGQKNd7wtpKdTFlBE0VAJfK-f2RNAEMfBophFg%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} Jun 01 12:53:00 volumio volumio[1224]: verbose: CURRENT POSITION 0 Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::syncState stateService play Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:53:00 volumio volumio[1224]: info: Received an update from plugin. extracting info from payload Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::pushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::pushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Jun 01 12:53:00 volumio volumio[1224]: info: ControllerMpd::pushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::servicePushState Jun 01 12:53:00 volumio volumio[1224]: info: CorePlayQueue::getTrack 0 Jun 01 12:53:00 volumio volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5743,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Best Audiophile Voices - Hi-Res Music 24 Bit 🎵 Best Voices","artist":"Top Music","album":"YouTube","uri":"https://rr4---sn-3cgv-un5l.googlevideo.com/videoplayback?expire=1748775179&ei=q9w7aI2dHqjas8IP1ZvqmQo&ip=180.177.30.88&id=o-AOSkejGHAPzAgb1_TJiX5aYF_IOywayzxJeuXSDS_Lkj&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748753579%2C&mh=eB&mm=31%2C29&mn=sn-3cgv-un5l%2Csn-u4o-u2xe7&ms=au%2Crdu&mv=m&mvi=4&pl=22&rms=au%2Cau&pcm2=yes&initcwndbps=3028750&bui=AY1jyLP9wlgDJwpryx4EcK_365hU9keLSewoKN2FI6h6KdwYrUVi8jegS36QgyhleUGYVL_K_KZv_F2n&spc=l3OVKU8VfXC-y3u9bpPJ_GMDolDBHZyXEWo3Wd_tbY-IR51DjaRzL8B7dkPbbI6RCAs&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=FtL7iW87UzFRlxWkKubiiN8Q&rqh=1&gir=yes&clen=99478083&dur=5743.301&lmt=1735297073745108&mt=1748753334&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5432434&n=DZZkPFM5iNccEg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cpcm2%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgRlgII0ZodwuzHYmwZ0wQ4nPn0Y2buLqAmPkTvdzc5m8CICHYWKNGS-oXt2gmyYiBwZCkttKQyzh8y8KpuS7sKx8K&sig=AJfQdSswRgIhAM9sAGqovuE5sRJt3g6--ngzzajHFimmLn3gNfo9h3zxAiEA86rpS4VEjp3FU6_XNepjqjBzT-_FbwjwXueMfYQAcrI%3D&pot=MngB2sqhUdwPwwHmSahPG1k4WKKOd4mpU0XDe6_eXeo6JCVTHTl2lwp976mBOCf1LE3-6ue60oO5QBzwGmhyhSBnFvVBvbcpAUtlK2Fpry8kYGqJfLxvLEcMtCqi0JGQKNd7wtpKdTFlBE0VAJfK-f2RNAEMfBophFg%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} Jun 01 12:53:00 volumio volumio[1224]: verbose: CURRENT POSITION 0 Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::syncState stateService play Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:53:00 volumio volumio[1224]: info: Received an update from plugin. extracting info from payload Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::pushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::pushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Jun 01 12:53:00 volumio volumio[1224]: info: ------------------------------ 79ms Jun 01 12:53:00 volumio volumio[1224]: info: ------------------------------ 116ms Jun 01 12:53:00 volumio volumio[1224]: info: ------------------------------ 113ms Jun 01 12:53:00 volumio volumio[1224]: info: sendMpdCommand playlistinfo took 84 milliseconds Jun 01 12:53:00 volumio volumio[1224]: info: sendMpdCommand playlistinfo took 81 milliseconds Jun 01 12:53:00 volumio volumio[1224]: info: sendMpdCommand playlistinfo took 83 milliseconds Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:53:00 volumio volumio[1224]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:53:00 volumio volumio[1224]: info: ControllerMpd::pushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::servicePushState Jun 01 12:53:00 volumio volumio[1224]: info: CorePlayQueue::getTrack 0 Jun 01 12:53:00 volumio volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":81,"duration":5743,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Best Audiophile Voices - Hi-Res Music 24 Bit 🎵 Best Voices","artist":"Top Music","album":"YouTube","uri":"https://rr4---sn-3cgv-un5l.googlevideo.com/videoplayback?expire=1748775179&ei=q9w7aI2dHqjas8IP1ZvqmQo&ip=180.177.30.88&id=o-AOSkejGHAPzAgb1_TJiX5aYF_IOywayzxJeuXSDS_Lkj&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748753579%2C&mh=eB&mm=31%2C29&mn=sn-3cgv-un5l%2Csn-u4o-u2xe7&ms=au%2Crdu&mv=m&mvi=4&pl=22&rms=au%2Cau&pcm2=yes&initcwndbps=3028750&bui=AY1jyLP9wlgDJwpryx4EcK_365hU9keLSewoKN2FI6h6KdwYrUVi8jegS36QgyhleUGYVL_K_KZv_F2n&spc=l3OVKU8VfXC-y3u9bpPJ_GMDolDBHZyXEWo3Wd_tbY-IR51DjaRzL8B7dkPbbI6RCAs&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=FtL7iW87UzFRlxWkKubiiN8Q&rqh=1&gir=yes&clen=99478083&dur=5743.301&lmt=1735297073745108&mt=1748753334&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5432434&n=DZZkPFM5iNccEg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cpcm2%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgRlgII0ZodwuzHYmwZ0wQ4nPn0Y2buLqAmPkTvdzc5m8CICHYWKNGS-oXt2gmyYiBwZCkttKQyzh8y8KpuS7sKx8K&sig=AJfQdSswRgIhAM9sAGqovuE5sRJt3g6--ngzzajHFimmLn3gNfo9h3zxAiEA86rpS4VEjp3FU6_XNepjqjBzT-_FbwjwXueMfYQAcrI%3D&pot=MngB2sqhUdwPwwHmSahPG1k4WKKOd4mpU0XDe6_eXeo6JCVTHTl2lwp976mBOCf1LE3-6ue60oO5QBzwGmhyhSBnFvVBvbcpAUtlK2Fpry8kYGqJfLxvLEcMtCqi0JGQKNd7wtpKdTFlBE0VAJfK-f2RNAEMfBophFg%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} Jun 01 12:53:00 volumio volumio[1224]: verbose: CURRENT POSITION 0 Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::syncState stateService play Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:53:00 volumio volumio[1224]: info: Received an update from plugin. extracting info from payload Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::pushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::pushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Jun 01 12:53:00 volumio volumio[1224]: info: ControllerMpd::pushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::servicePushState Jun 01 12:53:00 volumio volumio[1224]: info: CorePlayQueue::getTrack 0 Jun 01 12:53:00 volumio volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":143,"duration":5743,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Best Audiophile Voices - Hi-Res Music 24 Bit 🎵 Best Voices","artist":"Top Music","album":"YouTube","uri":"https://rr4---sn-3cgv-un5l.googlevideo.com/videoplayback?expire=1748775179&ei=q9w7aI2dHqjas8IP1ZvqmQo&ip=180.177.30.88&id=o-AOSkejGHAPzAgb1_TJiX5aYF_IOywayzxJeuXSDS_Lkj&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748753579%2C&mh=eB&mm=31%2C29&mn=sn-3cgv-un5l%2Csn-u4o-u2xe7&ms=au%2Crdu&mv=m&mvi=4&pl=22&rms=au%2Cau&pcm2=yes&initcwndbps=3028750&bui=AY1jyLP9wlgDJwpryx4EcK_365hU9keLSewoKN2FI6h6KdwYrUVi8jegS36QgyhleUGYVL_K_KZv_F2n&spc=l3OVKU8VfXC-y3u9bpPJ_GMDolDBHZyXEWo3Wd_tbY-IR51DjaRzL8B7dkPbbI6RCAs&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=FtL7iW87UzFRlxWkKubiiN8Q&rqh=1&gir=yes&clen=99478083&dur=5743.301&lmt=1735297073745108&mt=1748753334&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5432434&n=DZZkPFM5iNccEg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cpcm2%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgRlgII0ZodwuzHYmwZ0wQ4nPn0Y2buLqAmPkTvdzc5m8CICHYWKNGS-oXt2gmyYiBwZCkttKQyzh8y8KpuS7sKx8K&sig=AJfQdSswRgIhAM9sAGqovuE5sRJt3g6--ngzzajHFimmLn3gNfo9h3zxAiEA86rpS4VEjp3FU6_XNepjqjBzT-_FbwjwXueMfYQAcrI%3D&pot=MngB2sqhUdwPwwHmSahPG1k4WKKOd4mpU0XDe6_eXeo6JCVTHTl2lwp976mBOCf1LE3-6ue60oO5QBzwGmhyhSBnFvVBvbcpAUtlK2Fpry8kYGqJfLxvLEcMtCqi0JGQKNd7wtpKdTFlBE0VAJfK-f2RNAEMfBophFg%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} Jun 01 12:53:00 volumio volumio[1224]: verbose: CURRENT POSITION 0 Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::syncState stateService play Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:53:00 volumio volumio[1224]: info: Received an update from plugin. extracting info from payload Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::pushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::pushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Jun 01 12:53:00 volumio volumio[1224]: info: ControllerMpd::pushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::servicePushState Jun 01 12:53:00 volumio volumio[1224]: info: CorePlayQueue::getTrack 0 Jun 01 12:53:00 volumio volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":175,"duration":5743,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Best Audiophile Voices - Hi-Res Music 24 Bit 🎵 Best Voices","artist":"Top Music","album":"YouTube","uri":"https://rr4---sn-3cgv-un5l.googlevideo.com/videoplayback?expire=1748775179&ei=q9w7aI2dHqjas8IP1ZvqmQo&ip=180.177.30.88&id=o-AOSkejGHAPzAgb1_TJiX5aYF_IOywayzxJeuXSDS_Lkj&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748753579%2C&mh=eB&mm=31%2C29&mn=sn-3cgv-un5l%2Csn-u4o-u2xe7&ms=au%2Crdu&mv=m&mvi=4&pl=22&rms=au%2Cau&pcm2=yes&initcwndbps=3028750&bui=AY1jyLP9wlgDJwpryx4EcK_365hU9keLSewoKN2FI6h6KdwYrUVi8jegS36QgyhleUGYVL_K_KZv_F2n&spc=l3OVKU8VfXC-y3u9bpPJ_GMDolDBHZyXEWo3Wd_tbY-IR51DjaRzL8B7dkPbbI6RCAs&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=FtL7iW87UzFRlxWkKubiiN8Q&rqh=1&gir=yes&clen=99478083&dur=5743.301&lmt=1735297073745108&mt=1748753334&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5432434&n=DZZkPFM5iNccEg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cpcm2%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgRlgII0ZodwuzHYmwZ0wQ4nPn0Y2buLqAmPkTvdzc5m8CICHYWKNGS-oXt2gmyYiBwZCkttKQyzh8y8KpuS7sKx8K&sig=AJfQdSswRgIhAM9sAGqovuE5sRJt3g6--ngzzajHFimmLn3gNfo9h3zxAiEA86rpS4VEjp3FU6_XNepjqjBzT-_FbwjwXueMfYQAcrI%3D&pot=MngB2sqhUdwPwwHmSahPG1k4WKKOd4mpU0XDe6_eXeo6JCVTHTl2lwp976mBOCf1LE3-6ue60oO5QBzwGmhyhSBnFvVBvbcpAUtlK2Fpry8kYGqJfLxvLEcMtCqi0JGQKNd7wtpKdTFlBE0VAJfK-f2RNAEMfBophFg%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} Jun 01 12:53:00 volumio volumio[1224]: verbose: CURRENT POSITION 0 Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::syncState stateService play Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:53:00 volumio volumio[1224]: info: Received an update from plugin. extracting info from payload Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::pushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreStateMachine::pushState Jun 01 12:53:00 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Jun 01 12:53:00 volumio volumio[1224]: info: ------------------------------ 228ms Jun 01 12:53:00 volumio volumio[1224]: info: ------------------------------ 220ms Jun 01 12:53:00 volumio volumio[1224]: info: ------------------------------ 218ms Jun 01 12:53:03 volumio volumio[1224]: info: CoreCommandRouter::volumioGetState Jun 01 12:53:07 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 01 12:53:52 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 01 12:53:52 volumio dbus-daemon[617]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.52' (uid=0 pid=3951 comm="timedatectl show --property=NTPSynchronized --valu") Jun 01 12:53:52 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 01 12:53:53 volumio dbus-daemon[617]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 01 12:53:53 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 01 12:53:53 volumio setdatetime-helper.sh[3950]: Time is not synchronized. Attempting to sync... Jun 01 12:53:53 volumio sudo[3959]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 01 Jun 2025 04:53:53 GMT#015' Jun 01 12:53:53 volumio sudo[3959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 12:53:53 volumio setdatetime-helper.sh[3960]: Sun Jun 1 12:53:53 CST 2025 Jun 01 12:53:53 volumio systemd-journald[355]: Time jumped backwards, rotating. Jun 01 12:53:53 volumio sudo[3959]: pam_unix(sudo:session): session closed for user root Jun 01 12:53:53 volumio setdatetime-helper.sh[3950]: Time synchronized successfully. Jun 01 12:53:53 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 01 12:53:53 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 01 12:54:00 volumio volumio[1224]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 12:54:00 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 01 12:54:01 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 01 12:54:01 volumio sudo[3999]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 01 12:54:01 volumio sudo[3999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:01 volumio sudo[3999]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:01 volumio sudo[4004]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 01 12:54:01 volumio sudo[4004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:01 volumio sudo[4004]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:01 volumio sudo[4010]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 01 12:54:01 volumio sudo[4010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:01 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 01 12:54:01 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 01 12:54:01 volumio sudo[4010]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:01 volumio sudo[4015]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 01 12:54:01 volumio sudo[4015]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:01 volumio sudo[4015]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:01 volumio sudo[4026]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 12:54:01 volumio sudo[4024]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 12:54:01 volumio sudo[4024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:01 volumio sudo[4026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:01 volumio sudo[4026]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:01 volumio sudo[4024]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:01 volumio sudo[4029]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 01 12:54:01 volumio sudo[4029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:05 volumio sudo[4029]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:22 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 01 12:54:39 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jun 01 12:54:39 volumio volumio[1224]: info: Saving new wireless network Jun 01 12:54:39 volumio sudo[4169]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Jun 01 12:54:39 volumio sudo[4169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:39 volumio sudo[4169]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:39 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 01 12:54:39 volumio volumio[1224]: info: Discovery: Restarting Advertising due to device name change Jun 01 12:54:39 volumio sudo[4172]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 01 12:54:39 volumio sudo[4172]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:39 volumio systemd[1]: Starting wireless.service - Wireless Services... Jun 01 12:54:40 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Jun 01 12:54:40 volumio dnsmasq[3149]: exiting on receipt of SIGTERM Jun 01 12:54:40 volumio systemd[1]: dnsmasq.service: Deactivated successfully. Jun 01 12:54:40 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Jun 01 12:54:40 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Jun 01 12:54:40 volumio volumio[1224]: info: Discovery: A device disappeared from network Jun 01 12:54:40 volumio volumio[1224]: info: Discovery: Device volumio disappeared from network Jun 01 12:54:40 volumio volumio[1224]: info: Discovery: A device disappeared from network Jun 01 12:54:40 volumio volumio[1224]: info: Discovery: A device disappeared from network Jun 01 12:54:40 volumio dhcpcd[756]: wlan0: carrier lost Jun 01 12:54:40 volumio systemd[1]: hostapd.service: Deactivated successfully. Jun 01 12:54:40 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Jun 01 12:54:40 volumio avahi-daemon[606]: Withdrawing address record for 169.254.38.36 on wlan0. Jun 01 12:54:40 volumio sudo[4205]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 01 12:54:40 volumio sudo[4205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 12:54:40 volumio dhcpcd[756]: wlan0: deleting route to 169.254.0.0/16 Jun 01 12:54:40 volumio avahi-daemon[606]: Withdrawing address record for 192.168.211.1 on wlan0. Jun 01 12:54:40 volumio avahi-daemon[606]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 01 12:54:40 volumio avahi-daemon[606]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 01 12:54:40 volumio sudo[4205]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:40 volumio sudo[4216]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 01 12:54:40 volumio sudo[4216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 12:54:40 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 01 12:54:40 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 01 12:54:40 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 01 12:54:40 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 01 12:54:40 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 01 12:54:40 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 01 12:54:41 volumio welcome[4227]: Resolved ip:[1] 192.168.0.43 Jun 01 12:54:41 volumio sudo[4216]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:41 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 01 12:54:41 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 01 12:54:41 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 01 12:54:41 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 01 12:54:41 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 01 12:54:41 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 01 12:54:41 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 01 12:54:41 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 01 12:54:41 volumio welcome[4257]: Resolved ip:[1] 192.168.0.43 Jun 01 12:54:41 volumio sudo[4263]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 01 12:54:41 volumio sudo[4263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:41 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 01 12:54:41 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 01 12:54:41 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 01 12:54:41 volumio sudo[4263]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:41 volumio sudo[4268]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 01 12:54:41 volumio sudo[4268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:41 volumio sudo[4268]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:41 volumio sudo[4276]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 01 12:54:41 volumio sudo[4276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:41 volumio sudo[4276]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:41 volumio sudo[4278]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 01 12:54:41 volumio sudo[4278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:42 volumio ntpd[933]: IO: Deleting interface #7 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=297 secs Jun 01 12:54:42 volumio ntpd[933]: IO: Deleting interface #8 wlan0, 169.254.38.36#123, interface stats: received=0, sent=0, dropped=0, active_time=286 secs Jun 01 12:54:44 volumio volumio[1224]: info: Discovery: Started advertising with name: Volumio Jun 01 12:54:44 volumio sudo[4278]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:44 volumio sudo[4305]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 01 12:54:44 volumio sudo[4305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:44 volumio sudo[4305]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:44 volumio sudo[4307]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set TW Jun 01 12:54:44 volumio sudo[4307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:44 volumio sudo[4307]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:44 volumio sudo[4317]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 01 12:54:44 volumio sudo[4317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 12:54:44 volumio sudo[4317]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:44 volumio sudo[4319]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 01 12:54:44 volumio sudo[4319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 12:54:45 volumio volumio[1224]: info: Discovery: adding e9073db9-7097-4202-9c8d-8764a3dad916 Jun 01 12:54:45 volumio volumio[1224]: info: Discovery: Found device Volumio Jun 01 12:54:45 volumio volumio[1224]: info: CoreCommandRouter::volumioGetState Jun 01 12:54:45 volumio volumio[1224]: info: Discovery: this is already registered, e9073db9-7097-4202-9c8d-8764a3dad916 Jun 01 12:54:45 volumio volumio[1224]: info: Discovery: Found device Volumio Jun 01 12:54:45 volumio volumio[1224]: info: CoreCommandRouter::volumioGetState Jun 01 12:54:45 volumio sudo[4319]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:45 volumio wpa_supplicant[4328]: Successfully initialized wpa_supplicant Jun 01 12:54:45 volumio sudo[4329]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 01 12:54:45 volumio sudo[4329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 12:54:45 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 01 12:54:45 volumio dhcpcd[756]: ps_ctl_dispatch: cannot handle another client Jun 01 12:54:45 volumio dhcpcd[756]: control_free: No such file or directory Jun 01 12:54:45 volumio sudo[4329]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:45 volumio wpa_supplicant[4328]: nl80211: kernel reports: Registration to specific type not supported Jun 01 12:54:46 volumio sudo[4338]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 12:54:46 volumio sudo[4338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:46 volumio sudo[4338]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:47 volumio sudo[4344]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 12:54:47 volumio sudo[4344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:47 volumio sudo[4344]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:48 volumio sudo[4350]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 12:54:48 volumio wpa_supplicant[4333]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Jun 01 12:54:48 volumio wpa_supplicant[4333]: wlan0: Trying to associate with 20:6a:94:81:ea:9f (SSID='32-1n7f-5G' freq=5180 MHz) Jun 01 12:54:48 volumio sudo[4350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:48 volumio sudo[4350]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:48 volumio wpa_supplicant[4333]: wlan0: Associated with 20:6a:94:81:ea:9f Jun 01 12:54:48 volumio wpa_supplicant[4333]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Jun 01 12:54:48 volumio wpa_supplicant[4333]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 01 12:54:48 volumio wpa_supplicant[4333]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=TW Jun 01 12:54:48 volumio volumio[1224]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not access weather service: missing geographic coordinates. Jun 01 12:54:48 volumio volumio[1224]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:95:19) Jun 01 12:54:48 volumio volumio[1224]: at Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:116:37) Jun 01 12:54:48 volumio volumio[1224]: at /data/plugins/user_interface/now_playing/dist/app/Router.js:51:13 Jun 01 12:54:48 volumio volumio[1224]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jun 01 12:54:48 volumio volumio[1224]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:149:13) Jun 01 12:54:48 volumio volumio[1224]: at Route.dispatch (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:119:3) Jun 01 12:54:48 volumio volumio[1224]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jun 01 12:54:48 volumio volumio[1224]: at /data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:284:15 Jun 01 12:54:48 volumio volumio[1224]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:365:14) Jun 01 12:54:48 volumio volumio[1224]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Jun 01 12:54:48 volumio volumio[1224]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Jun 01 12:54:48 volumio volumio[1224]: at Function.process_params (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:421:3) Jun 01 12:54:48 volumio volumio[1224]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:280:10) Jun 01 12:54:48 volumio volumio[1224]: at Function.handle (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:175:3) Jun 01 12:54:48 volumio volumio[1224]: at router (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:47:12) Jun 01 12:54:48 volumio volumio[1224]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jun 01 12:54:49 volumio sudo[4356]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 12:54:49 volumio sudo[4356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:49 volumio sudo[4356]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:49 volumio wpa_supplicant[4333]: wlan0: WPA: Key negotiation completed with 20:6a:94:81:ea:9f [PTK=CCMP GTK=CCMP] Jun 01 12:54:49 volumio wpa_supplicant[4333]: wlan0: CTRL-EVENT-CONNECTED - Connection to 20:6a:94:81:ea:9f completed [id=0 id_str=] Jun 01 12:54:49 volumio dhcpcd[756]: wlan0: carrier acquired Jun 01 12:54:49 volumio dhcpcd[756]: wlan0: connected to Access Point: 32-1n7f-5G Jun 01 12:54:49 volumio dhcpcd[756]: wlan0: IAID dd:5d:89:a1 Jun 01 12:54:49 volumio dhcpcd[756]: wlan0: rebinding lease of 192.168.0.44 Jun 01 12:54:49 volumio dhcpcd[756]: wlan0: probing address 192.168.0.44/24 Jun 01 12:54:50 volumio sudo[4365]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 12:54:50 volumio sudo[4365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:50 volumio sudo[4365]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:50 volumio dhcpcd[756]: wlan0: soliciting an IPv6 router Jun 01 12:54:51 volumio sudo[4371]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 12:54:51 volumio sudo[4371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:51 volumio sudo[4371]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:52 volumio sudo[4377]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 12:54:52 volumio sudo[4377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:52 volumio sudo[4377]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:53 volumio sudo[4384]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 12:54:53 volumio sudo[4384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:53 volumio sudo[4384]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:54 volumio sudo[4404]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 12:54:54 volumio sudo[4404]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:54 volumio sudo[4404]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:55 volumio sudo[4410]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 12:54:55 volumio sudo[4410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:55 volumio sudo[4410]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:55 volumio dhcpcd[756]: wlan0: leased 192.168.0.44 for 604800 seconds Jun 01 12:54:55 volumio avahi-daemon[606]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.44. Jun 01 12:54:55 volumio avahi-daemon[606]: New relevant interface wlan0.IPv4 for mDNS. Jun 01 12:54:55 volumio dhcpcd[756]: wlan0: adding route to 192.168.0.0/24 Jun 01 12:54:55 volumio avahi-daemon[606]: Registering new address record for 192.168.0.44 on wlan0.IPv4. Jun 01 12:54:55 volumio dhcpcd[756]: wlan0: adding default route via 192.168.0.1 Jun 01 12:54:55 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 01 12:54:55 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 01 12:54:55 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 01 12:54:55 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 01 12:54:55 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 01 12:54:55 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 01 12:54:55 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 01 12:54:55 volumio dbus-daemon[617]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.57' (uid=0 pid=4434 comm="timedatectl show --property=NTPSynchronized --valu") Jun 01 12:54:55 volumio welcome[4436]: Resolved ip:[2] 192.168.0.43 192.168.0.44 Jun 01 12:54:55 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 01 12:54:55 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 01 12:54:55 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 01 12:54:56 volumio volumio[1224]: compat.c: read() failed: Resource temporarily unavailable Jun 01 12:54:56 volumio volumio[1224]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 12:54:56 volumio volumio[1224]: Error: dns service error: unknown Jun 01 12:54:56 volumio volumio[1224]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jun 01 12:54:56 volumio volumio[1224]: errorCode: -65537 Jun 01 12:54:56 volumio volumio[1224]: } Jun 01 12:54:56 volumio volumio[1224]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 12:54:56 volumio dbus-daemon[617]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 01 12:54:56 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 01 12:54:56 volumio setdatetime-helper.sh[4424]: Time is not synchronized. Attempting to sync... Jun 01 12:54:56 volumio sudo[4460]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 01 Jun 2025 04:54:57 GMT#015' Jun 01 12:54:56 volumio sudo[4460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 12:54:57 volumio setdatetime-helper.sh[4461]: Sun Jun 1 12:54:57 CST 2025 Jun 01 12:54:57 volumio sudo[4460]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:57 volumio setdatetime-helper.sh[4424]: Time synchronized successfully. Jun 01 12:54:57 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 01 12:54:57 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 01 12:54:57 volumio sudo[4464]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 12:54:57 volumio sudo[4464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:57 volumio sudo[4464]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:57 volumio sudo[4467]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 12:54:57 volumio sudo[4467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 12:54:57 volumio sudo[4467]: pam_unix(sudo:session): session closed for user root Jun 01 12:54:57 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 01 12:54:57 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 01 12:54:57 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 12:54:57 volumio systemd[1]: shairport-sync.service: Consumed 4.417s CPU time. Jun 01 12:54:57 volumio avahi-daemon[606]: Got SIGTERM, quitting. Jun 01 12:54:57 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 01 12:54:57 volumio avahi-daemon[606]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.44. Jun 01 12:54:57 volumio avahi-daemon[606]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.43. Jun 01 12:54:57 volumio avahi-daemon[606]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 01 12:54:57 volumio sudo[4474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-01 12:53' Jun 01 12:54:57 volumio sudo[4474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 12:54:57 volumio dbus-daemon[617]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.60' (uid=0 pid=1266 comm="/usr/sbin/smbd --foreground --no-process-group") Jun 01 12:54:57 volumio avahi-daemon[606]: avahi-daemon 0.8 exiting. Jun 01 12:54:57 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. Jun 01 12:54:57 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. 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="1da895bfdbb6eb90d965df32a25a3568df7c84ed" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu May 29 13:12:59 UTC 2025" VOLUMIO_VERSION="4.004" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2df63d74196e499fa1e34fb765df1cca"