-- Logs begin at Sat 2024-05-04 13:57:37 KST, end at Sat 2024-05-04 14:18:25 KST. -- May 04 14:17:01 volumio volumio[1301]: info: Initializing connection to go-librespot Websocket May 04 14:17:01 volumio volumio[1301]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 04 14:17:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 04 14:17:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. May 04 14:17:01 volumio systemd[1]: Stopped go-librespot Daemon. May 04 14:17:01 volumio systemd[1]: Started go-librespot Daemon. May 04 14:17:01 volumio go-librespot[18067]: Librespot-go daemon starting... May 04 14:17:01 volumio go-librespot[18067]: time="2024-05-04T14:17:01+09:00" level=info msg="generated new device id: 0c169f1396cb30789300d112a45be84cc949d16a" May 04 14:17:02 volumio go-librespot[18067]: time="2024-05-04T14:17:02+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" May 04 14:17:02 volumio go-librespot[18067]: time="2024-05-04T14:17:02+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 04 14:17:02 volumio go-librespot[18067]: time="2024-05-04T14:17:02+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 04 14:17:02 volumio go-librespot[18067]: time="2024-05-04T14:17:02+09:00" level=debug msg="zeroconf server listening on port 34441" May 04 14:17:04 volumio volumio[1301]: info: Initializing connection to go-librespot Websocket May 04 14:17:04 volumio go-librespot[18067]: time="2024-05-04T14:17:04+09:00" level=debug msg="new websocket client" May 04 14:17:04 volumio volumio[1301]: info: Connection to go-librespot Websocket established May 04 14:17:07 volumio volumio[1301]: info: Getting Spotify volume May 04 14:17:07 volumio volumio[1301]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 17 May 04 14:17:07 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState May 04 14:17:07 volumio volumio[1301]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 May 04 14:17:20 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState May 04 14:17:22 volumio volumio[1301]: info: Executing endpoint metavolumio May 04 14:17:22 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 04 14:17:22 volumio volumio[1301]: info: Executing endpoint metavolumio May 04 14:17:22 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 04 14:17:22 volumio volumio[1301]: info: Executing endpoint metavolumio May 04 14:17:22 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 04 14:17:24 volumio volumio[1301]: info: CoreCommandRouter::volumioPause May 04 14:17:24 volumio volumio[1301]: info: CoreStateMachine::pause May 04 14:17:24 volumio volumio[1301]: info: CoreStateMachine::stPlaybackTimer May 04 14:17:24 volumio volumio[1301]: info: CoreStateMachine::servicePause May 04 14:17:24 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 May 04 14:17:24 volumio volumio[1301]: info: CoreCommandRouter::servicePause May 04 14:17:24 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:17:24 volumio volumio[1301]: info: ControllerMpd::pause May 04 14:17:24 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand pause May 04 14:17:24 volumio volumio[1301]: info: May 04 14:17:24 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 04 14:17:24 volumio volumio[1301]: info: sendMpdCommand pause took 1 milliseconds May 04 14:17:24 volumio volumio[1301]: info: ControllerMpd::getState May 04 14:17:24 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 04 14:17:24 volumio volumio[1301]: info: sendMpdCommand status took 0 milliseconds May 04 14:17:24 volumio volumio[1301]: verbose: ControllerMpd::parseState May 04 14:17:24 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:17:24 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 1 milliseconds May 04 14:17:24 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 04 14:17:24 volumio volumio[1301]: info: ControllerMpd::pushState May 04 14:17:24 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 04 14:17:24 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 May 04 14:17:24 volumio volumio[1301]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":8999,"duration":281,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"조현아 (어반자카파) - 늦은 후회 / 가사","artist":"Music is my life","album":"YouTube","uri":"https://rr1---sn-3u-u5x6.googlevideo.com/videoplayback?expire=1714821396&ei=tMQ1Zp6NCa6g0-kP7KWYsAY&ip=221.157.68.169&id=o-ALAuWrGZePRrXLu1dkaaHipkjRC8SiArbgefe1Nh08pP&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=MJ&mm=31%2C29&mn=sn-3u-u5x6%2Csn-3u-bh2zz&ms=au%2Crdu&mv=m&mvi=1&pl=16&initcwndbps=1372500&bui=AWRWj2SH9w4s-8lcIZGXH9VQ5ANo_c2nXRhrohet120lq0YNSbe6xSY0XkftYkSQs_ivkHtjl15iHKvq&spc=UWF9f5_3eOvV7bHQBCJAgyLlAo8iznKPz419Af9eozYhqpdBXw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pbqgwfeNZsWs0mkEAvVm_zgQ&rqh=1&gir=yes&clen=4797558&dur=280.941&lmt=1647601859585669&mt=1714799355&fvip=3&keepalive=yes&c=WEB&sefc=1&txp=5432434&n=Ot1Gdq_XpZJuNQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIhAPkTpfG7elzvIGfGsrSvhZcnoFH0QbK2c87SNindZ7RSAiB6swEdOn0AlNpEbvjti75YSucXqh50PjBEsrCZb0RpiA%3D%3D&sig=AJfQdSswRgIhAOKst4avyW-vHfac6tIHfWuesIQX4J_6vvEta9OiN7fFAiEAg_rwLJAg7zZyc3jfY-y0vl9II4jIL1XPKSf0_TmOM3M%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} May 04 14:17:24 volumio volumio[1301]: verbose: CURRENT POSITION 1 May 04 14:17:24 volumio volumio[1301]: info: CoreStateMachine::syncState stateService pause May 04 14:17:24 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus pause May 04 14:17:24 volumio volumio[1301]: info: CoreStateMachine::pushState May 04 14:17:24 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:17:24 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 04 14:17:24 volumio volumio[1301]: info: MRS: Pushing multiroomSync output update for this device May 04 14:17:24 volumio volumio[1301]: info: MRS: Pushing multiroomSync output May 04 14:17:24 volumio volumio[1301]: info: CoreStateMachine::stPlaybackTimer May 04 14:17:24 volumio volumio[1301]: info: ------------------------------ 12ms May 04 14:17:24 volumio volumio[1301]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 May 04 14:17:24 volumio volumio[1301]: info: touch_display: Setting screensaver timeout to 120 seconds. May 04 14:17:26 volumio volumio[1301]: info: CoreCommandRouter::volumioPlay May 04 14:17:26 volumio volumio[1301]: info: CoreStateMachine::play index undefined May 04 14:17:26 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:17:26 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 May 04 14:17:26 volumio volumio[1301]: info: CoreStateMachine::startPlaybackTimer May 04 14:17:26 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 May 04 14:17:26 volumio volumio[1301]: info: CoreCommandRouter::volumioGetVisibleSources May 04 14:17:26 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 14:17:26 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 04 14:17:26 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:17:26 volumio volumio[1301]: info: ControllerMpd::resume May 04 14:17:26 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand play May 04 14:17:26 volumio volumio[1301]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 May 04 14:17:26 volumio volumio[1301]: info: FusionDsp - ---- read samplerate from file: 96000 May 04 14:17:26 volumio volumio[1301]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 04 14:17:26 volumio volumio[1301]: info: May 04 14:17:26 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 04 14:17:26 volumio volumio[1301]: info: sendMpdCommand play took 9 milliseconds May 04 14:17:26 volumio volumio[1301]: info: ControllerMpd::getState May 04 14:17:26 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 04 14:17:26 volumio volumio[1301]: info: sendMpdCommand status took 1 milliseconds May 04 14:17:26 volumio volumio[1301]: verbose: ControllerMpd::parseState May 04 14:17:26 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:17:26 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 14:17:26 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 04 14:17:26 volumio volumio[1301]: info: ControllerMpd::pushState May 04 14:17:26 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 04 14:17:26 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 May 04 14:17:26 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9398,"duration":281,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"조현아 (어반자카파) - 늦은 후회 / 가사","artist":"Music is my life","album":"YouTube","uri":"https://rr1---sn-3u-u5x6.googlevideo.com/videoplayback?expire=1714821396&ei=tMQ1Zp6NCa6g0-kP7KWYsAY&ip=221.157.68.169&id=o-ALAuWrGZePRrXLu1dkaaHipkjRC8SiArbgefe1Nh08pP&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=MJ&mm=31%2C29&mn=sn-3u-u5x6%2Csn-3u-bh2zz&ms=au%2Crdu&mv=m&mvi=1&pl=16&initcwndbps=1372500&bui=AWRWj2SH9w4s-8lcIZGXH9VQ5ANo_c2nXRhrohet120lq0YNSbe6xSY0XkftYkSQs_ivkHtjl15iHKvq&spc=UWF9f5_3eOvV7bHQBCJAgyLlAo8iznKPz419Af9eozYhqpdBXw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pbqgwfeNZsWs0mkEAvVm_zgQ&rqh=1&gir=yes&clen=4797558&dur=280.941&lmt=1647601859585669&mt=1714799355&fvip=3&keepalive=yes&c=WEB&sefc=1&txp=5432434&n=Ot1Gdq_XpZJuNQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIhAPkTpfG7elzvIGfGsrSvhZcnoFH0QbK2c87SNindZ7RSAiB6swEdOn0AlNpEbvjti75YSucXqh50PjBEsrCZb0RpiA%3D%3D&sig=AJfQdSswRgIhAOKst4avyW-vHfac6tIHfWuesIQX4J_6vvEta9OiN7fFAiEAg_rwLJAg7zZyc3jfY-y0vl9II4jIL1XPKSf0_TmOM3M%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} May 04 14:17:26 volumio volumio[1301]: verbose: CURRENT POSITION 1 May 04 14:17:26 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play May 04 14:17:26 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus pause May 04 14:17:26 volumio volumio[1301]: info: CoreStateMachine::pushState May 04 14:17:26 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:17:26 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 04 14:17:26 volumio volumio[1301]: info: MRS: Pushing multiroomSync output update for this device May 04 14:17:26 volumio volumio[1301]: info: MRS: Pushing multiroomSync output May 04 14:17:26 volumio volumio[1301]: info: ------------------------------ 20ms May 04 14:17:26 volumio volumio[1301]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 04 14:17:26 volumio volumio[1301]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 May 04 14:17:26 volumio volumio[1301]: info: touch_display: Setting screensaver timeout to 0 seconds. May 04 14:17:39 volumio volumio[1301]: info: CoreCommandRouter::volumioPause May 04 14:17:39 volumio volumio[1301]: info: CoreStateMachine::pause May 04 14:17:39 volumio volumio[1301]: info: CoreStateMachine::stPlaybackTimer May 04 14:17:39 volumio volumio[1301]: info: CoreStateMachine::servicePause May 04 14:17:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 May 04 14:17:39 volumio volumio[1301]: info: CoreCommandRouter::servicePause May 04 14:17:39 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:17:39 volumio volumio[1301]: info: ControllerMpd::pause May 04 14:17:39 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand pause May 04 14:17:39 volumio volumio[1301]: info: May 04 14:17:39 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 04 14:17:39 volumio volumio[1301]: info: sendMpdCommand pause took 1 milliseconds May 04 14:17:39 volumio volumio[1301]: info: ControllerMpd::getState May 04 14:17:39 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 04 14:17:39 volumio volumio[1301]: info: sendMpdCommand status took 0 milliseconds May 04 14:17:39 volumio volumio[1301]: verbose: ControllerMpd::parseState May 04 14:17:39 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:17:39 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 14:17:39 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 04 14:17:39 volumio volumio[1301]: info: ControllerMpd::pushState May 04 14:17:39 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 04 14:17:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 May 04 14:17:39 volumio volumio[1301]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12390,"duration":281,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"조현아 (어반자카파) - 늦은 후회 / 가사","artist":"Music is my life","album":"YouTube","uri":"https://rr1---sn-3u-u5x6.googlevideo.com/videoplayback?expire=1714821396&ei=tMQ1Zp6NCa6g0-kP7KWYsAY&ip=221.157.68.169&id=o-ALAuWrGZePRrXLu1dkaaHipkjRC8SiArbgefe1Nh08pP&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=MJ&mm=31%2C29&mn=sn-3u-u5x6%2Csn-3u-bh2zz&ms=au%2Crdu&mv=m&mvi=1&pl=16&initcwndbps=1372500&bui=AWRWj2SH9w4s-8lcIZGXH9VQ5ANo_c2nXRhrohet120lq0YNSbe6xSY0XkftYkSQs_ivkHtjl15iHKvq&spc=UWF9f5_3eOvV7bHQBCJAgyLlAo8iznKPz419Af9eozYhqpdBXw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pbqgwfeNZsWs0mkEAvVm_zgQ&rqh=1&gir=yes&clen=4797558&dur=280.941&lmt=1647601859585669&mt=1714799355&fvip=3&keepalive=yes&c=WEB&sefc=1&txp=5432434&n=Ot1Gdq_XpZJuNQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIhAPkTpfG7elzvIGfGsrSvhZcnoFH0QbK2c87SNindZ7RSAiB6swEdOn0AlNpEbvjti75YSucXqh50PjBEsrCZb0RpiA%3D%3D&sig=AJfQdSswRgIhAOKst4avyW-vHfac6tIHfWuesIQX4J_6vvEta9OiN7fFAiEAg_rwLJAg7zZyc3jfY-y0vl9II4jIL1XPKSf0_TmOM3M%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} May 04 14:17:39 volumio volumio[1301]: verbose: CURRENT POSITION 1 May 04 14:17:39 volumio volumio[1301]: info: CoreStateMachine::syncState stateService pause May 04 14:17:39 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus pause May 04 14:17:39 volumio volumio[1301]: info: CoreStateMachine::pushState May 04 14:17:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:17:39 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 04 14:17:39 volumio volumio[1301]: info: MRS: Pushing multiroomSync output update for this device May 04 14:17:39 volumio volumio[1301]: info: MRS: Pushing multiroomSync output May 04 14:17:39 volumio volumio[1301]: info: CoreStateMachine::stPlaybackTimer May 04 14:17:39 volumio volumio[1301]: info: ------------------------------ 26ms May 04 14:17:39 volumio volumio[1301]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 May 04 14:17:39 volumio volumio[1301]: info: touch_display: Setting screensaver timeout to 120 seconds. May 04 14:18:04 volumio volumio[1301]: info: CoreCommandRouter::volumioPlay May 04 14:18:04 volumio volumio[1301]: info: CoreStateMachine::play index undefined May 04 14:18:04 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:18:04 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 May 04 14:18:04 volumio volumio[1301]: info: CoreStateMachine::startPlaybackTimer May 04 14:18:04 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 May 04 14:18:04 volumio volumio[1301]: info: CoreCommandRouter::volumioGetVisibleSources May 04 14:18:04 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 14:18:04 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 04 14:18:04 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:18:04 volumio volumio[1301]: info: ControllerMpd::resume May 04 14:18:04 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand play May 04 14:18:04 volumio volumio[1301]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 May 04 14:18:04 volumio volumio[1301]: info: FusionDsp - ---- read samplerate from file: 96000 May 04 14:18:04 volumio volumio[1301]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 04 14:18:04 volumio volumio[1301]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 04 14:18:04 volumio volumio[1301]: info: sendMpdCommand play took 10 milliseconds May 04 14:18:04 volumio volumio[1301]: info: May 04 14:18:04 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 04 14:18:04 volumio volumio[1301]: info: ControllerMpd::getState May 04 14:18:04 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 04 14:18:04 volumio volumio[1301]: info: sendMpdCommand status took 0 milliseconds May 04 14:18:04 volumio volumio[1301]: verbose: ControllerMpd::parseState May 04 14:18:04 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:18:04 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 14:18:04 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 04 14:18:04 volumio volumio[1301]: info: ControllerMpd::pushState May 04 14:18:04 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 04 14:18:04 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 May 04 14:18:04 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13042,"duration":281,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"조현아 (어반자카파) - 늦은 후회 / 가사","artist":"Music is my life","album":"YouTube","uri":"https://rr1---sn-3u-u5x6.googlevideo.com/videoplayback?expire=1714821396&ei=tMQ1Zp6NCa6g0-kP7KWYsAY&ip=221.157.68.169&id=o-ALAuWrGZePRrXLu1dkaaHipkjRC8SiArbgefe1Nh08pP&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=MJ&mm=31%2C29&mn=sn-3u-u5x6%2Csn-3u-bh2zz&ms=au%2Crdu&mv=m&mvi=1&pl=16&initcwndbps=1372500&bui=AWRWj2SH9w4s-8lcIZGXH9VQ5ANo_c2nXRhrohet120lq0YNSbe6xSY0XkftYkSQs_ivkHtjl15iHKvq&spc=UWF9f5_3eOvV7bHQBCJAgyLlAo8iznKPz419Af9eozYhqpdBXw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pbqgwfeNZsWs0mkEAvVm_zgQ&rqh=1&gir=yes&clen=4797558&dur=280.941&lmt=1647601859585669&mt=1714799355&fvip=3&keepalive=yes&c=WEB&sefc=1&txp=5432434&n=Ot1Gdq_XpZJuNQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIhAPkTpfG7elzvIGfGsrSvhZcnoFH0QbK2c87SNindZ7RSAiB6swEdOn0AlNpEbvjti75YSucXqh50PjBEsrCZb0RpiA%3D%3D&sig=AJfQdSswRgIhAOKst4avyW-vHfac6tIHfWuesIQX4J_6vvEta9OiN7fFAiEAg_rwLJAg7zZyc3jfY-y0vl9II4jIL1XPKSf0_TmOM3M%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} May 04 14:18:04 volumio volumio[1301]: verbose: CURRENT POSITION 1 May 04 14:18:04 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play May 04 14:18:04 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus pause May 04 14:18:04 volumio volumio[1301]: info: CoreStateMachine::pushState May 04 14:18:04 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:18:04 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 04 14:18:04 volumio volumio[1301]: info: MRS: Pushing multiroomSync output update for this device May 04 14:18:04 volumio volumio[1301]: info: MRS: Pushing multiroomSync output May 04 14:18:04 volumio volumio[1301]: info: ------------------------------ 12ms May 04 14:18:04 volumio volumio[1301]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 May 04 14:18:04 volumio volumio[1301]: info: touch_display: Setting screensaver timeout to 0 seconds. May 04 14:18:12 volumio volumio[1301]: info: CoreCommandRouter::volumioPause May 04 14:18:12 volumio volumio[1301]: info: CoreStateMachine::pause May 04 14:18:12 volumio volumio[1301]: info: CoreStateMachine::stPlaybackTimer May 04 14:18:12 volumio volumio[1301]: info: CoreStateMachine::servicePause May 04 14:18:12 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 May 04 14:18:12 volumio volumio[1301]: info: CoreCommandRouter::servicePause May 04 14:18:12 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:18:12 volumio volumio[1301]: info: ControllerMpd::pause May 04 14:18:12 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand pause May 04 14:18:12 volumio volumio[1301]: info: May 04 14:18:12 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 04 14:18:12 volumio volumio[1301]: info: sendMpdCommand pause took 1 milliseconds May 04 14:18:12 volumio volumio[1301]: info: ControllerMpd::getState May 04 14:18:12 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 04 14:18:12 volumio volumio[1301]: info: sendMpdCommand status took 1 milliseconds May 04 14:18:12 volumio volumio[1301]: verbose: ControllerMpd::parseState May 04 14:18:12 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:18:12 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 14:18:12 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 04 14:18:12 volumio volumio[1301]: info: ControllerMpd::pushState May 04 14:18:12 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 04 14:18:12 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 May 04 14:18:12 volumio volumio[1301]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15152,"duration":281,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"조현아 (어반자카파) - 늦은 후회 / 가사","artist":"Music is my life","album":"YouTube","uri":"https://rr1---sn-3u-u5x6.googlevideo.com/videoplayback?expire=1714821396&ei=tMQ1Zp6NCa6g0-kP7KWYsAY&ip=221.157.68.169&id=o-ALAuWrGZePRrXLu1dkaaHipkjRC8SiArbgefe1Nh08pP&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=MJ&mm=31%2C29&mn=sn-3u-u5x6%2Csn-3u-bh2zz&ms=au%2Crdu&mv=m&mvi=1&pl=16&initcwndbps=1372500&bui=AWRWj2SH9w4s-8lcIZGXH9VQ5ANo_c2nXRhrohet120lq0YNSbe6xSY0XkftYkSQs_ivkHtjl15iHKvq&spc=UWF9f5_3eOvV7bHQBCJAgyLlAo8iznKPz419Af9eozYhqpdBXw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pbqgwfeNZsWs0mkEAvVm_zgQ&rqh=1&gir=yes&clen=4797558&dur=280.941&lmt=1647601859585669&mt=1714799355&fvip=3&keepalive=yes&c=WEB&sefc=1&txp=5432434&n=Ot1Gdq_XpZJuNQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIhAPkTpfG7elzvIGfGsrSvhZcnoFH0QbK2c87SNindZ7RSAiB6swEdOn0AlNpEbvjti75YSucXqh50PjBEsrCZb0RpiA%3D%3D&sig=AJfQdSswRgIhAOKst4avyW-vHfac6tIHfWuesIQX4J_6vvEta9OiN7fFAiEAg_rwLJAg7zZyc3jfY-y0vl9II4jIL1XPKSf0_TmOM3M%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} May 04 14:18:12 volumio volumio[1301]: verbose: CURRENT POSITION 1 May 04 14:18:12 volumio volumio[1301]: info: CoreStateMachine::syncState stateService pause May 04 14:18:12 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus pause May 04 14:18:12 volumio volumio[1301]: info: CoreStateMachine::pushState May 04 14:18:12 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:18:12 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 04 14:18:12 volumio volumio[1301]: info: MRS: Pushing multiroomSync output update for this device May 04 14:18:12 volumio volumio[1301]: info: MRS: Pushing multiroomSync output May 04 14:18:12 volumio volumio[1301]: info: CoreStateMachine::stPlaybackTimer May 04 14:18:12 volumio volumio[1301]: info: ------------------------------ 20ms May 04 14:18:12 volumio volumio[1301]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 May 04 14:18:12 volumio volumio[1301]: info: touch_display: Setting screensaver timeout to 120 seconds. May 04 14:18:25 volumio volumio[1301]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ May 04 14:18:25 volumio volumio[1301]: info: PLUGIN onReboot : networkfs May 04 14:18:25 volumio volumio[1301]: info: PLUGIN onReboot : audiophonicsonoff May 04 14:18:25 volumio volumio[1301]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 14:18:25 volumio volumio[1301]: TypeError: Cannot read property 'writeSync' of undefined May 04 14:18:25 volumio volumio[1301]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) May 04 14:18:25 volumio volumio[1301]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) May 04 14:18:25 volumio volumio[1301]: at HashMap. (/volumio/app/pluginmanager.js:668:31) May 04 14:18:25 volumio volumio[1301]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 04 14:18:25 volumio volumio[1301]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 04 14:18:25 volumio volumio[1301]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) May 04 14:18:25 volumio volumio[1301]: at CoreCommandRouter.reboot (/volumio/app/index.js:1325:22) May 04 14:18:25 volumio volumio[1301]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:836:33) May 04 14:18:25 volumio volumio[1301]: at Socket.emit (events.js:315:20) May 04 14:18:25 volumio volumio[1301]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 04 14:18:25 volumio volumio[1301]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 04 14:18:25 volumio volumio[1301]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 14:18:25 volumio sudo[18153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-04 14:17 May 04 14:18:25 volumio sudo[18153]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"