-- Logs begin at Mon 2024-10-21 16:11:31 KST, end at Mon 2024-10-21 16:16:00 KST. -- Oct 21 16:15:01 volumio volumio[1095]: info: VolumeController::SetAlsaVolumeNaN Oct 21 16:15:01 volumio volumio[1095]: info: CoreStateMachine::pushState Oct 21 16:15:01 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 21 16:15:01 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 16:15:01 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Oct 21 16:15:01 volumio volumio[1095]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 21 16:15:04 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 21 16:15:04 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 21 16:15:10 volumio volumio[1095]: info: CoreCommandRouter::volumioPlay Oct 21 16:15:10 volumio volumio[1095]: info: CoreStateMachine::play index undefined Oct 21 16:15:10 volumio volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 21 16:15:10 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 21 16:15:10 volumio volumio[1095]: info: CoreStateMachine::startPlaybackTimer Oct 21 16:15:10 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 21 16:15:10 volumio volumio[1095]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22AievARSbCEo%22%2C%22playlistId%22%3A%22PL5dY_A13NMFtBlwRnOQuPZKv3Rau7yK9j%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Makeba%20-%20Jain%20(%20Jimmy%20sax%20Reworks%20)%22%2C%22artist%22%3A%22Jimmy%20Sax%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FAievARSbCEo%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2CNACELwBSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gmAAtAFigIMCAAQARhlIFwoMjAP%26rs%3DAOn4CLBaADYWdW9xQhW1AcGv6MpziUcpcA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22AievARSbCEo%22%2C%22playlistId%22%3A%22PL5dY_A13NMFtBlwRnOQuPZKv3Rau7yK9j%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D%7D Oct 21 16:15:10 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 21 16:15:10 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand stop Oct 21 16:15:10 volumio volumio[1095]: info: sendMpdCommand stop took 1 milliseconds Oct 21 16:15:10 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand clear Oct 21 16:15:10 volumio volumio[1095]: info: Oct 21 16:15:10 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Oct 21 16:15:10 volumio volumio[1095]: info: Ignoring MPD Status Update Oct 21 16:15:10 volumio volumio[1095]: info: sendMpdCommand clear took 2 milliseconds Oct 21 16:15:10 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand addid "https://rr7---sn-3u-nf06.googlevideo.com/videoplayback?expire=1729516510&ei=fv8VZ6GiHPuD1d8PvIbzmQM&ip=59.18.142.148&id=o-ANX6rEIIsfQDeuudhF9dTldYX7M9wHZOzJiw_2iJa_nO&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1729494910%2C&mh=XS&mm=31%2C29&mn=sn-3u-nf06%2Csn-3u-bh2s7&ms=au%2Crdu&mv=m&mvi=7&pcm2cms=yes&pl=21&rms=au%2Cau&ctier=A&pfa=5&gcr=kr&initcwndbps=1055000&hightc=yes&siu=1&bui=AXLXGFTiou4Vpe79NHKmLT_72gTJpED3QiEJE1l1mt3Jkfo-q9Q3SY8LsYL-ej-_FwNXQFZ6wA&spc=54MbxSisWotU6rbZj0IDN7ksXBlsj5ebr1g5oygCZLwjeu2AiFnbyzwL8YdpaiU&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=aLW0dy1R62Dz9u4xW1l_nKYQ&rqh=1&gir=yes&clen=5298332&dur=310.501&lmt=1713816895113126&mt=1729494552&fvip=6&keepalive=yes&fexp=51312688&c=WEB&sefc=1&txp=5432434&n=9aU4lCFzHvCHvQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRAIgH-0wQaoFpLJkQC1EozwUrT0DmhB1jLZLya3lKAFdEI0CIFu1HZC6MNuW1R2U6FmTnDNkdl4xk3ICGgufCEpAVkVz&sig=AJfQdSswRQIhALzfYJETfBrRY9Us57KlgOd7o8OFv_xlbECjUA4TkCs2AiBkCFiuHg4-w-WqDXJT926xTFy1J9ihmxrTn3r3OLk5nQ%3D%3D&cver=2.20230104.01.00&t.YouTube" Oct 21 16:15:10 volumio volumio[1095]: error: updateQueue error: null Oct 21 16:15:10 volumio volumio[1095]: info: Oct 21 16:15:10 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Oct 21 16:15:10 volumio volumio[1095]: info: Ignoring MPD Status Update Oct 21 16:15:10 volumio volumio[1095]: info: ------------------------------ 3ms Oct 21 16:15:10 volumio volumio[1095]: info: sendMpdCommand addid "https://rr7---sn-3u-nf06.googlevideo.com/videoplayback?expire=1729516510&ei=fv8VZ6GiHPuD1d8PvIbzmQM&ip=59.18.142.148&id=o-ANX6rEIIsfQDeuudhF9dTldYX7M9wHZOzJiw_2iJa_nO&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1729494910%2C&mh=XS&mm=31%2C29&mn=sn-3u-nf06%2Csn-3u-bh2s7&ms=au%2Crdu&mv=m&mvi=7&pcm2cms=yes&pl=21&rms=au%2Cau&ctier=A&pfa=5&gcr=kr&initcwndbps=1055000&hightc=yes&siu=1&bui=AXLXGFTiou4Vpe79NHKmLT_72gTJpED3QiEJE1l1mt3Jkfo-q9Q3SY8LsYL-ej-_FwNXQFZ6wA&spc=54MbxSisWotU6rbZj0IDN7ksXBlsj5ebr1g5oygCZLwjeu2AiFnbyzwL8YdpaiU&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=aLW0dy1R62Dz9u4xW1l_nKYQ&rqh=1&gir=yes&clen=5298332&dur=310.501&lmt=1713816895113126&mt=1729494552&fvip=6&keepalive=yes&fexp=51312688&c=WEB&sefc=1&txp=5432434&n=9aU4lCFzHvCHvQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRAIgH-0wQaoFpLJkQC1EozwUrT0DmhB1jLZLya3lKAFdEI0CIFu1HZC6MNuW1R2U6FmTnDNkdl4xk3ICGgufCEpAVkVz&sig=AJfQdSswRQIhALzfYJETfBrRY9Us57KlgOd7o8OFv_xlbECjUA4TkCs2AiBkCFiuHg4-w-WqDXJT926xTFy1J9ihmxrTn3r3OLk5nQ%3D%3D&cver=2.20230104.01.00&t.YouTube" took 2 milliseconds Oct 21 16:15:10 volumio volumio[1095]: verbose: MPD COMMAND [object Object] Oct 21 16:15:10 volumio volumio[1095]: verbose: MPD COMMAND [object Object] Oct 21 16:15:10 volumio volumio[1095]: verbose: MPD COMMAND [object Object] Oct 21 16:15:10 volumio volumio[1095]: info: Oct 21 16:15:10 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Oct 21 16:15:10 volumio volumio[1095]: info: Ignoring MPD Status Update Oct 21 16:15:10 volumio volumio[1095]: info: ------------------------------ 4ms Oct 21 16:15:10 volumio volumio[1095]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 21 16:15:10 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand play Oct 21 16:15:10 volumio volumio[1095]: info: ------------------------------ 2ms Oct 21 16:15:10 volumio volumio[1095]: info: sendMpdCommand play took 2 milliseconds Oct 21 16:15:11 volumio volumio[1095]: info: Oct 21 16:15:11 volumio volumio[1095]: ---------------------------- MPD announces state update: player Oct 21 16:15:11 volumio volumio[1095]: info: ControllerMpd::getState Oct 21 16:15:11 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Oct 21 16:15:11 volumio volumio[1095]: info: Oct 21 16:15:11 volumio volumio[1095]: ---------------------------- MPD announces state update: player Oct 21 16:15:11 volumio volumio[1095]: info: ControllerMpd::getState Oct 21 16:15:11 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Oct 21 16:15:11 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 21 16:15:11 volumio volumio[1095]: info: sendMpdCommand status took 5 milliseconds Oct 21 16:15:11 volumio volumio[1095]: verbose: ControllerMpd::parseState Oct 21 16:15:11 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 16:15:11 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 21 16:15:11 volumio volumio[1095]: info: sendMpdCommand status took 11 milliseconds Oct 21 16:15:11 volumio volumio[1095]: info: sendMpdCommand clearerror took 9 milliseconds Oct 21 16:15:11 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 21 16:15:11 volumio volumio[1095]: verbose: ControllerMpd::parseState Oct 21 16:15:11 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 16:15:11 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Oct 21 16:15:11 volumio volumio[1095]: info: ControllerMpd::pushState Oct 21 16:15:11 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Oct 21 16:15:11 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 21 16:15:11 volumio volumio[1095]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":311,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Makeba - Jain ( Jimmy sax Reworks )","artist":"Jimmy Sax","album":"YouTube","uri":"https://rr7---sn-3u-nf06.googlevideo.com/videoplayback?expire=1729516510&ei=fv8VZ6GiHPuD1d8PvIbzmQM&ip=59.18.142.148&id=o-ANX6rEIIsfQDeuudhF9dTldYX7M9wHZOzJiw_2iJa_nO&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1729494910%2C&mh=XS&mm=31%2C29&mn=sn-3u-nf06%2Csn-3u-bh2s7&ms=au%2Crdu&mv=m&mvi=7&pcm2cms=yes&pl=21&rms=au%2Cau&ctier=A&pfa=5&gcr=kr&initcwndbps=1055000&hightc=yes&siu=1&bui=AXLXGFTiou4Vpe79NHKmLT_72gTJpED3QiEJE1l1mt3Jkfo-q9Q3SY8LsYL-ej-_FwNXQFZ6wA&spc=54MbxSisWotU6rbZj0IDN7ksXBlsj5ebr1g5oygCZLwjeu2AiFnbyzwL8YdpaiU&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=aLW0dy1R62Dz9u4xW1l_nKYQ&rqh=1&gir=yes&clen=5298332&dur=310.501&lmt=1713816895113126&mt=1729494552&fvip=6&keepalive=yes&fexp=51312688&c=WEB&sefc=1&txp=5432434&n=9aU4lCFzHvCHvQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRAIgH-0wQaoFpLJkQC1EozwUrT0DmhB1jLZLya3lKAFdEI0CIFu1HZC6MNuW1R2U6FmTnDNkdl4xk3ICGgufCEpAVkVz&sig=AJfQdSswRQIhALzfYJETfBrRY9Us57KlgOd7o8OFv_xlbECjUA4TkCs2AiBkCFiuHg4-w-WqDXJT926xTFy1J9ihmxrTn3r3OLk5nQ%3D%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Oct 21 16:15:11 volumio volumio[1095]: verbose: CURRENT POSITION 0 Oct 21 16:15:11 volumio volumio[1095]: info: CoreStateMachine::syncState stateService pause Oct 21 16:15:11 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus stop Oct 21 16:15:11 volumio volumio[1095]: info: CoreStateMachine::pushState Oct 21 16:15:11 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 16:15:11 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Oct 21 16:15:11 volumio volumio[1095]: info: ------------------------------ 30ms Oct 21 16:15:11 volumio volumio[1095]: info: sendMpdCommand clearerror took 11 milliseconds Oct 21 16:15:11 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 21 16:15:11 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Oct 21 16:15:11 volumio volumio[1095]: info: ControllerMpd::pushState Oct 21 16:15:11 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Oct 21 16:15:11 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 21 16:15:11 volumio volumio[1095]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":311,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Makeba - Jain ( Jimmy sax Reworks )","artist":"Jimmy Sax","album":"YouTube","uri":"https://rr7---sn-3u-nf06.googlevideo.com/videoplayback?expire=1729516510&ei=fv8VZ6GiHPuD1d8PvIbzmQM&ip=59.18.142.148&id=o-ANX6rEIIsfQDeuudhF9dTldYX7M9wHZOzJiw_2iJa_nO&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1729494910%2C&mh=XS&mm=31%2C29&mn=sn-3u-nf06%2Csn-3u-bh2s7&ms=au%2Crdu&mv=m&mvi=7&pcm2cms=yes&pl=21&rms=au%2Cau&ctier=A&pfa=5&gcr=kr&initcwndbps=1055000&hightc=yes&siu=1&bui=AXLXGFTiou4Vpe79NHKmLT_72gTJpED3QiEJE1l1mt3Jkfo-q9Q3SY8LsYL-ej-_FwNXQFZ6wA&spc=54MbxSisWotU6rbZj0IDN7ksXBlsj5ebr1g5oygCZLwjeu2AiFnbyzwL8YdpaiU&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=aLW0dy1R62Dz9u4xW1l_nKYQ&rqh=1&gir=yes&clen=5298332&dur=310.501&lmt=1713816895113126&mt=1729494552&fvip=6&keepalive=yes&fexp=51312688&c=WEB&sefc=1&txp=5432434&n=9aU4lCFzHvCHvQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRAIgH-0wQaoFpLJkQC1EozwUrT0DmhB1jLZLya3lKAFdEI0CIFu1HZC6MNuW1R2U6FmTnDNkdl4xk3ICGgufCEpAVkVz&sig=AJfQdSswRQIhALzfYJETfBrRY9Us57KlgOd7o8OFv_xlbECjUA4TkCs2AiBkCFiuHg4-w-WqDXJT926xTFy1J9ihmxrTn3r3OLk5nQ%3D%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Oct 21 16:15:11 volumio volumio[1095]: verbose: CURRENT POSITION 0 Oct 21 16:15:11 volumio volumio[1095]: info: CoreStateMachine::syncState stateService pause Oct 21 16:15:11 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus stop Oct 21 16:15:11 volumio volumio[1095]: info: CoreStateMachine::pushState Oct 21 16:15:11 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Oct 21 16:15:11 volumio volumio[1095]: info: ------------------------------ 30ms Oct 21 16:15:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 21 16:15:22 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 21 16:15:25 volumio volumio[1095]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Oct 21 16:15:27 volumio volumio[1095]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Oct 21 16:15:27 volumio volumio[1095]: info: Folder /tmp/plugins removed Oct 21 16:15:28 volumio volumio[1095]: info: Check plugin dependencies Oct 21 16:15:28 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 21 16:15:28 volumio volumio[1095]: info: Checking if plugin already exists Oct 21 16:15:28 volumio volumio[1095]: info: Rename folder Oct 21 16:15:28 volumio volumio[1095]: info: Folder /tmp/downloaded_plugin.zip removed Oct 21 16:15:28 volumio volumio[1095]: info: Move to category Oct 21 16:15:28 volumio volumio[1095]: info: Checking if install.sh is present Oct 21 16:15:28 volumio volumio[1095]: info: Executing install.sh Oct 21 16:15:28 volumio sudo[2467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Oct 21 16:15:28 volumio sudo[2467]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 16:15:28 volumio volumio[1095]: info: creating filters folder and copying demo filters Oct 21 16:15:28 volumio volumio[1095]: info: copying demo flters Oct 21 16:15:28 volumio volumio[1095]: info: Installing/fusiondsp dependencies Oct 21 16:15:28 volumio sudo[2488]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update Oct 21 16:15:28 volumio sudo[2488]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 16:15:29 volumio volumio[1095]: info: Get:1 https://deb.nodesource.com/node_14.x buster InRelease [4584 B] Oct 21 16:15:29 volumio volumio[1095]: info: Get:2 https://deb.nodesource.com/node_14.x buster/main armhf Packages [786 B] Oct 21 16:15:29 volumio volumio[1095]: info: Get:3 http://raspbian.raspberrypi.org/raspbian buster InRelease [15.0 kB] Oct 21 16:15:29 volumio volumio[1095]: info: Get:4 http://archive.raspberrypi.org/debian buster InRelease [54.2 kB] Oct 21 16:15:30 volumio volumio[1095]: info: Get:5 http://raspbian.raspberrypi.org/raspbian buster/main armhf Packages [13.0 MB] Oct 21 16:15:30 volumio volumio[1095]: info: Get:6 http://archive.raspberrypi.org/debian buster/main armhf Packages [400 kB] Oct 21 16:15:33 volumio volumio[1095]: info: Get:7 http://raspbian.raspberrypi.org/raspbian buster/contrib armhf Packages [58.8 kB] Oct 21 16:15:34 volumio volumio[1095]: info: Get:8 http://raspbian.raspberrypi.org/raspbian buster/non-free armhf Packages [110 kB] Oct 21 16:15:35 volumio volumio[1095]: info: Get:9 http://raspbian.raspberrypi.org/raspbian buster/rpi armhf Packages [1360 B] Oct 21 16:15:38 volumio volumio[1095]: info: Fetched 13.7 MB in 9s (1471 kB/s) Oct 21 16:15:40 volumio volumio[1095]: info: Reading package lists... Oct 21 16:15:40 volumio volumio[1095]: info: Building dependency tree... Oct 21 16:15:40 volumio volumio[1095]: info: Reading state information... Oct 21 16:15:40 volumio volumio[1095]: info: All packages are up to date. Oct 21 16:15:40 volumio sudo[2488]: pam_unix(sudo:session): session closed for user root Oct 21 16:15:40 volumio sudo[2733]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Oct 21 16:15:41 volumio sudo[2733]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 16:15:41 volumio volumio[1095]: info: Reading package lists... Oct 21 16:15:41 volumio volumio[1095]: info: Building dependency tree... Oct 21 16:15:41 volumio volumio[1095]: info: Reading state information... Oct 21 16:15:42 volumio volumio[1095]: info: The following additional packages will be installed: Oct 21 16:15:42 volumio volumio[1095]: info: build-essential bzip2 cpp cpp-8 dh-python dpkg-dev fakeroot g++ g++-8 gcc Oct 21 16:15:42 volumio volumio[1095]: info: gcc-8 libalgorithm-diff-perl libalgorithm-diff-xs-perl Oct 21 16:15:42 volumio volumio[1095]: info: libalgorithm-merge-perl libasan5 libcc1-0 libexpat1-dev libfakeroot Oct 21 16:15:42 volumio volumio[1095]: info: libgcc-8-dev libisl19 libmpc3 libmpfr6 libpython3-dev libpython3.7 Oct 21 16:15:42 volumio volumio[1095]: info: libpython3.7-dev libstdc++-8-dev libubsan1 make patch python-pip-whl Oct 21 16:15:42 volumio volumio[1095]: info: python3-asn1crypto python3-async-timeout python3-attr python3-cffi-backend Oct 21 16:15:42 volumio volumio[1095]: info: python3-crypto python3-cryptography python3-dbus python3-dev Oct 21 16:15:42 volumio volumio[1095]: info: python3-entrypoints python3-gi python3-keyring python3-keyrings.alt Oct 21 16:15:42 volumio volumio[1095]: info: python3-multidict python3-secretstorage python3-setuptools python3-wheel Oct 21 16:15:42 volumio volumio[1095]: info: python3-xdg python3-yarl python3.7-dev Oct 21 16:15:42 volumio volumio[1095]: info: Suggested packages: Oct 21 16:15:42 volumio volumio[1095]: info: bzip2-doc cpp-doc gcc-8-locales debian-keyring gcc-8-doc libstdc++6-8-dbg Oct 21 16:15:42 volumio volumio[1095]: info: gcc-multilib autoconf automake libtool flex bison gdb gcc-doc libgcc1-dbg Oct 21 16:15:42 volumio volumio[1095]: info: libgomp1-dbg libitm1-dbg libatomic1-dbg libasan5-dbg liblsan0-dbg Oct 21 16:15:42 volumio volumio[1095]: info: libtsan0-dbg libubsan1-dbg libmpx2-dbg libquadmath0-dbg libstdc++-8-doc Oct 21 16:15:42 volumio volumio[1095]: info: make-doc ed diffutils-doc python-attr-doc python-crypto-doc Oct 21 16:15:42 volumio volumio[1095]: info: python-cryptography-doc python3-cryptography-vectors python-dbus-doc Oct 21 16:15:42 volumio volumio[1095]: info: python3-dbus-dbg gnome-keyring libkf5wallet-bin gir1.2-gnomekeyring-1.0 Oct 21 16:15:42 volumio volumio[1095]: info: python-secretstorage-doc python-setuptools-doc Oct 21 16:15:42 volumio volumio[1095]: info: The following NEW packages will be installed: Oct 21 16:15:42 volumio volumio[1095]: info: build-essential bzip2 cpp cpp-8 dh-python dpkg-dev fakeroot g++ g++-8 gcc Oct 21 16:15:42 volumio volumio[1095]: info: gcc-8 libalgorithm-diff-perl libalgorithm-diff-xs-perl Oct 21 16:15:42 volumio volumio[1095]: info: libalgorithm-merge-perl libasan5 libcc1-0 libexpat1-dev libfakeroot Oct 21 16:15:42 volumio volumio[1095]: info: libgcc-8-dev libisl19 libmpc3 libmpfr6 libpython3-dev libpython3.7 Oct 21 16:15:42 volumio volumio[1095]: info: libpython3.7-dev libstdc++-8-dev libubsan1 make patch python-pip-whl Oct 21 16:15:42 volumio volumio[1095]: info: python3-aiohttp python3-asn1crypto python3-async-timeout python3-attr Oct 21 16:15:42 volumio volumio[1095]: info: python3-cffi-backend python3-crypto python3-cryptography python3-dbus Oct 21 16:15:42 volumio volumio[1095]: info: python3-dev python3-entrypoints python3-gi python3-keyring Oct 21 16:15:42 volumio volumio[1095]: info: python3-keyrings.alt python3-multidict python3-pip python3-secretstorage Oct 21 16:15:42 volumio volumio[1095]: info: python3-setuptools python3-wheel python3-xdg python3-yarl python3.7-dev Oct 21 16:15:42 volumio volumio[1095]: info: 0 upgraded, 51 newly installed, 0 to remove and 2 not upgraded. Oct 21 16:15:42 volumio volumio[1095]: info: Need to get 80.4 MB of archives. Oct 21 16:15:42 volumio volumio[1095]: info: After this operation, 182 MB of additional disk space will be used. Oct 21 16:15:42 volumio volumio[1095]: info: Get:1 http://archive.raspberrypi.org/debian buster/main armhf python-pip-whl all 18.1-5+rpt1 [1591 kB] Oct 21 16:15:43 volumio volumio[1095]: info: Get:2 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf bzip2 armhf 1.0.6-9.2~deb10u2 [46.3 kB] Oct 21 16:15:43 volumio volumio[1095]: info: Get:3 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libisl19 armhf 0.20-2 [439 kB] Oct 21 16:15:44 volumio volumio[1095]: info: Get:4 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libmpfr6 armhf 4.0.2-1 [723 kB] Oct 21 16:15:44 volumio volumio[1095]: info: Get:5 http://archive.raspberrypi.org/debian buster/main armhf python3-pip all 18.1-5+rpt1 [171 kB] Oct 21 16:15:44 volumio volumio[1095]: info: Get:6 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libmpc3 armhf 1.1.0-1 [32.1 kB] Oct 21 16:15:45 volumio volumio[1095]: info: Get:7 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf cpp-8 armhf 8.3.0-6+rpi1 [6787 kB] Oct 21 16:15:46 volumio volumio[1095]: info: Get:8 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf cpp armhf 4:8.3.0-1+rpi2 [19.6 kB] Oct 21 16:15:46 volumio volumio[1095]: info: Get:9 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libcc1-0 armhf 8.3.0-6+rpi1 [37.9 kB] Oct 21 16:15:47 volumio volumio[1095]: info: Get:10 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libasan5 armhf 8.3.0-6+rpi1 [317 kB] Oct 21 16:15:47 volumio volumio[1095]: info: Get:11 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libubsan1 armhf 8.3.0-6+rpi1 [103 kB] Oct 21 16:15:48 volumio volumio[1095]: info: Get:12 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libgcc-8-dev armhf 8.3.0-6+rpi1 [595 kB] Oct 21 16:15:48 volumio volumio[1095]: info: Get:13 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf gcc-8 armhf 8.3.0-6+rpi1 [7223 kB] Oct 21 16:15:49 volumio volumio[1095]: info: Get:14 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf gcc armhf 4:8.3.0-1+rpi2 [5200 B] Oct 21 16:15:50 volumio volumio[1095]: info: Get:15 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libstdc++-8-dev armhf 8.3.0-6+rpi1 [1545 kB] Oct 21 16:15:50 volumio volumio[1095]: info: Get:16 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf g++-8 armhf 8.3.0-6+rpi1 [7464 kB] Oct 21 16:15:51 volumio volumio[1095]: info: Get:17 http://raspbian.raspberrypi.org/raspbian buster/main armhf g++ armhf 4:8.3.0-1+rpi2 [1632 B] Oct 21 16:15:51 volumio volumio[1095]: info: Get:18 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf make armhf 4.2.1-1.2 [321 kB] Oct 21 16:15:52 volumio volumio[1095]: info: Get:19 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf patch armhf 2.7.6-3+deb10u1 [115 kB] Oct 21 16:15:52 volumio volumio[1095]: info: Get:20 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf dpkg-dev all 1.19.8 [1776 kB] Oct 21 16:15:53 volumio wpa_supplicant[2013]: wlan0: CTRL-EVENT-DISCONNECTED bssid=58:86:94:4b:1c:55 reason=8 Oct 21 16:15:53 volumio dhcpcd[2055]: wlan0: carrier lost Oct 21 16:15:53 volumio wpa_supplicant[2013]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Oct 21 16:15:53 volumio wpa_supplicant[2013]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Oct 21 16:15:53 volumio volumio[1095]: info: Get:21 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf build-essential armhf 12.6 [7568 B] Oct 21 16:15:53 volumio avahi-daemon[833]: Withdrawing address record for 192.168.0.78 on wlan0. Oct 21 16:15:53 volumio avahi-daemon[833]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.78. Oct 21 16:15:53 volumio avahi-daemon[833]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 21 16:15:53 volumio dhcpcd[2055]: wlan0: deleting route to 192.168.0.0/24 Oct 21 16:15:53 volumio dhcpcd[2055]: wlan0: deleting default route via 192.168.0.1 Oct 21 16:15:53 volumio volumio[1095]: info: Discovery: A device disappeared from network Oct 21 16:15:53 volumio volumio[1095]: info: Discovery: Device volumio disappeared from network Oct 21 16:15:53 volumio dnsmasq[1086]: reading /etc/resolv.conf Oct 21 16:15:53 volumio dnsmasq[1086]: using nameserver 168.126.63.1#53 Oct 21 16:15:53 volumio dnsmasq[1086]: using nameserver 168.126.63.2#53 Oct 21 16:15:53 volumio dnsmasq[1086]: using nameserver 208.67.222.222#53 Oct 21 16:15:53 volumio dnsmasq[1086]: using nameserver 208.67.220.220#53 Oct 21 16:15:53 volumio wpa_supplicant[2013]: wlan0: Trying to associate with SSID 'SangjaBox' Oct 21 16:15:53 volumio volumio[1095]: info: Get:22 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf dh-python all 3.20190308 [99.3 kB] Oct 21 16:15:54 volumio volumio[1095]: info: Get:23 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libfakeroot armhf 1.23-1 [43.3 kB] Oct 21 16:15:54 volumio dhcpcd[2055]: wlan0: carrier acquired Oct 21 16:15:54 volumio wpa_supplicant[2013]: wlan0: Associated with 58:86:94:4b:1c:55 Oct 21 16:15:54 volumio wpa_supplicant[2013]: wlan0: CTRL-EVENT-CONNECTED - Connection to 58:86:94:4b:1c:55 completed [id=0 id_str=] Oct 21 16:15:54 volumio wpa_supplicant[2013]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 21 16:15:54 volumio dhcpcd[2055]: wlan0: IAID 67:03:db:89 Oct 21 16:15:54 volumio dhcpcd[2055]: wlan0: carrier lost Oct 21 16:15:54 volumio dhcpcd[2055]: wlan0: carrier acquired Oct 21 16:15:54 volumio dhcpcd[2055]: wlan0: IAID 67:03:db:89 Oct 21 16:15:54 volumio dhcpcd[2055]: wlan0: rebinding lease of 192.168.0.78 Oct 21 16:15:54 volumio dhcpcd[2055]: wlan0: probing address 192.168.0.78/24 Oct 21 16:15:54 volumio dhcpcd[2055]: wlan0: soliciting an IPv6 router Oct 21 16:15:54 volumio volumio[1095]: info: Get:24 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf fakeroot armhf 1.23-1 [85.2 kB] Oct 21 16:15:55 volumio volumio[1095]: info: Get:25 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libalgorithm-diff-perl all 1.19.03-2 [47.9 kB] Oct 21 16:15:56 volumio ntpd[1012]: Deleting interface #5 wlan0, 192.168.0.78#123, interface stats: received=0, sent=0, dropped=0, active_time=175 secs Oct 21 16:15:56 volumio volumio[1095]: info: Get:26 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libalgorithm-diff-xs-perl armhf 0.04-5+b1 [11.4 kB] Oct 21 16:15:56 volumio volumio[1095]: info: Get:27 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libalgorithm-merge-perl all 0.08-3 [12.7 kB] Oct 21 16:15:57 volumio volumio[1095]: info: Get:28 http://mirror.rise.ph/raspbian/raspbian buster/main armhf libexpat1-dev armhf 2.2.6-2+deb10u7 [130 kB] Oct 21 16:15:58 volumio volumio[1095]: info: Get:29 http://mirror.rise.ph/raspbian/raspbian buster/main armhf libpython3.7 armhf 3.7.3-2+deb10u7 [1252 kB] Oct 21 16:15:58 volumio volumio[1095]: info: Get:31 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libpython3-dev armhf 3.7.3-1 [20.1 kB] Oct 21 16:15:59 volumio volumio[1095]: info: Get:30 http://mirror.rise.ph/raspbian/raspbian buster/main armhf libpython3.7-dev armhf 3.7.3-2+deb10u7 [47.2 MB] Oct 21 16:15:59 volumio volumio[1095]: info: Get:32 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf python3-multidict armhf 4.5.2-1 [52.9 kB] Oct 21 16:15:59 volumio dhcpcd[2055]: wlan0: leased 192.168.0.78 for 7200 seconds Oct 21 16:15:59 volumio avahi-daemon[833]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.78. Oct 21 16:15:59 volumio avahi-daemon[833]: New relevant interface wlan0.IPv4 for mDNS. Oct 21 16:15:59 volumio dhcpcd[2055]: wlan0: adding route to 192.168.0.0/24 Oct 21 16:15:59 volumio avahi-daemon[833]: Registering new address record for 192.168.0.78 on wlan0.IPv4. Oct 21 16:15:59 volumio dhcpcd[2055]: wlan0: adding default route via 192.168.0.1 Oct 21 16:15:59 volumio dnsmasq[1086]: reading /etc/resolv.conf Oct 21 16:15:59 volumio dnsmasq[1086]: using nameserver 168.126.63.1#53 Oct 21 16:15:59 volumio dnsmasq[1086]: using nameserver 168.126.63.2#53 Oct 21 16:15:59 volumio dnsmasq[1086]: using nameserver 208.67.222.222#53 Oct 21 16:15:59 volumio dnsmasq[1086]: using nameserver 208.67.220.220#53 Oct 21 16:16:00 volumio volumio[1095]: info: Get:33 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf python3-yarl armhf 1.3.0-1 [44.0 kB] Oct 21 16:16:00 volumio volumio[1095]: info: Discovery: adding 73c6473a-f0f2-4b93-91be-8b64ad834d71 Oct 21 16:16:00 volumio volumio[1095]: info: Discovery: Found device Volumio Oct 21 16:16:00 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 21 16:16:00 volumio volumio[1095]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 21 16:16:00 volumio volumio[1095]: Error: The value NaN is not a number Oct 21 16:16:00 volumio volumio[1095]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Oct 21 16:16:00 volumio volumio[1095]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Oct 21 16:16:00 volumio volumio[1095]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Oct 21 16:16:00 volumio volumio[1095]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Oct 21 16:16:00 volumio volumio[1095]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Oct 21 16:16:00 volumio volumio[1095]: at Browser.emit (events.js:315:20) Oct 21 16:16:00 volumio volumio[1095]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Oct 21 16:16:00 volumio volumio[1095]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Oct 21 16:16:00 volumio volumio[1095]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Oct 21 16:16:00 volumio volumio[1095]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Oct 21 16:16:00 volumio volumio[1095]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Oct 21 16:16:00 volumio volumio[1095]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Oct 21 16:16:00 volumio volumio[1095]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Oct 21 16:16:00 volumio volumio[1095]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 21 16:16:00 volumio sudo[2830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-21 16:15 Oct 21 16:16:00 volumio sudo[2830]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"