-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Thu 2024-10-17 19:15:59 CEST. -- Oct 17 19:14:17 jolumio volumio[980]: info: CoreCommandRouter::volumioPlay Oct 17 19:14:17 jolumio volumio[980]: info: CoreStateMachine::play index undefined Oct 17 19:14:17 jolumio volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:14:17 jolumio volumio[980]: info: CorePlayQueue::getTrack 44 Oct 17 19:14:17 jolumio volumio[980]: info: CoreStateMachine::startPlaybackTimer Oct 17 19:14:17 jolumio volumio[980]: info: CorePlayQueue::getTrack 44 Oct 17 19:14:17 jolumio volumio[980]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 17 19:14:17 jolumio volumio[980]: info: ControllerMpd::resume Oct 17 19:14:17 jolumio volumio[980]: verbose: ControllerMpd::sendMpdCommand play Oct 17 19:14:17 jolumio volumio[980]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Oct 17 19:14:17 jolumio volumio[980]: info: FusionDsp - ---- read samplerate from file: 48000 Oct 17 19:14:17 jolumio volumio[980]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 17 19:14:17 jolumio volumio[980]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Oct 17 19:14:17 jolumio volumio[980]: info: FusionDsp - ---- read samplerate from file: 48000 Oct 17 19:14:17 jolumio volumio[980]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 17 19:14:17 jolumio volumio[980]: info: ControllerMpd::getState Oct 17 19:14:17 jolumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:14:17 jolumio volumio[980]: info: Oct 17 19:14:17 jolumio volumio[980]: ---------------------------- MPD announces state update: player Oct 17 19:14:17 jolumio volumio[980]: info: sendMpdCommand play took 43 milliseconds Oct 17 19:14:17 jolumio volumio[980]: info: ControllerMpd::getState Oct 17 19:14:17 jolumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:14:17 jolumio volumio[980]: info: sendMpdCommand status took 5 milliseconds Oct 17 19:14:17 jolumio volumio[980]: verbose: ControllerMpd::parseState Oct 17 19:14:17 jolumio volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:14:17 jolumio volumio[980]: verbose: ControllerMpd::parseState Oct 17 19:14:17 jolumio volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:14:17 jolumio volumio[980]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 19:14:17 jolumio volumio[980]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 19:14:17 jolumio volumio[980]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:14:17 jolumio volumio[980]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:14:17 jolumio volumio[980]: info: ControllerMpd::pushState Oct 17 19:14:17 jolumio volumio[980]: info: CoreCommandRouter::servicePushState Oct 17 19:14:17 jolumio volumio[980]: info: CorePlayQueue::getTrack 44 Oct 17 19:14:17 jolumio volumio[980]: verbose: STATE SERVICE {"status":"play","position":0,"seek":76734,"duration":232,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Again","artist":"Lenny Kravitz","album":"Again","uri":"https://rr4---sn-45nufxc-4jvl.googlevideo.com/videoplayback?expire=1729206791&ei=p0URZ_CkGuafp-oP3P3JmAw&ip=93.34.92.21&id=o-ALpHdVpfZpUpWuUv6v_Jamv0WsaV6v2AI8u6cfWJJh9H&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1729185191%2C&mh=ae&mm=31%2C26&mn=sn-45nufxc-4jvl%2Csn-hgn7rnee&ms=au%2Conr&mv=m&mvi=4&pl=21&rms=au%2Cau&ctier=A&pfa=5&gcr=it&initcwndbps=1501250&hightc=yes&siu=1&bui=AXLXGFTkz2vntjSS3HkrZWguF0kEbt-_wOsLTyV9FabxERxZGnuGDI9vJ4hRV_Zd7pd4Ohj9Tw&spc=54MbxR2h_YP1M3BcJ__iAL_Qm6_Cq1Jkilcnae8OyppRoeYKhx7uyUf0HbUS4mo&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=ra_2QUd-4QPq3vh6_MLLbtkQ&rqh=1&gir=yes&clen=7321802&dur=231.681&lmt=1714537757628855&mt=1729184730&fvip=2&keepalive=yes&fexp=51300761%2C51312688&c=WEB_REMIX&sefc=1&txp=5432434&n=Ds3Mq7I-Spf9kQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRQIgEAnc14MSXHapHZdGC-HAVISRPc4MUA9NDLhK_GvqSf8CIQDyQjE7eoOthqGqzXeZjTTGQ4E4ViuwajJ6pYZ2dP9M-Q%3D%3D&sig=AJfQdSswRAIgIW8tox7X-O4Ra_cvJquahmF2FVO9EYfTpb74p0pXttgCIGn9ZqtCRTWSM62chiVQdKtXYWJdLQE9sB4HfUT3uv4W&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Oct 17 19:14:17 jolumio volumio[980]: verbose: CURRENT POSITION 44 Oct 17 19:14:17 jolumio volumio[980]: info: CoreStateMachine::syncState stateService play Oct 17 19:14:17 jolumio volumio[980]: info: CoreStateMachine::syncState currentStatus pause Oct 17 19:14:17 jolumio volumio[980]: info: CoreStateMachine::pushState Oct 17 19:14:17 jolumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:14:17 jolumio volumio[980]: info: CoreCommandRouter::volumioPushState Oct 17 19:14:17 jolumio volumio[980]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:14:17 jolumio volumio[980]: info: MRS: Pushing multiroomSync output Oct 17 19:14:17 jolumio volumio[980]: info: ------------------------------ 50ms Oct 17 19:14:17 jolumio volumio[980]: info: FusionDsp - {"Reload":{"result":"Ok"}} Oct 17 19:14:17 jolumio volumio[980]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Oct 17 19:14:17 jolumio volumio[980]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 17 19:14:17 jolumio volumio[980]: info: FusionDsp - {"Reload":{"result":"Ok"}} Oct 17 19:14:17 jolumio sudo[1522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 17 19:14:17 jolumio sudo[1522]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:14:17 jolumio volumio[980]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 17 19:14:17 jolumio systemd[1]: Started peppymeterbasic Daemon. Oct 17 19:14:17 jolumio sudo[1522]: pam_unix(sudo:session): session closed for user root Oct 17 19:14:17 jolumio volumio[980]: info: peppymeterbasic Daemon Started Oct 17 19:14:18 jolumio volumio[980]: info: CoreCommandRouter::volumioPause Oct 17 19:14:18 jolumio volumio[980]: info: CoreStateMachine::pause Oct 17 19:14:18 jolumio volumio[980]: info: CoreStateMachine::stPlaybackTimer Oct 17 19:14:18 jolumio volumio[980]: info: CoreStateMachine::servicePause Oct 17 19:14:18 jolumio volumio[980]: info: CorePlayQueue::getTrack 44 Oct 17 19:14:18 jolumio volumio[980]: info: CoreCommandRouter::servicePause Oct 17 19:14:18 jolumio volumio[980]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 17 19:14:18 jolumio volumio[980]: info: ControllerMpd::pause Oct 17 19:14:18 jolumio volumio[980]: verbose: ControllerMpd::sendMpdCommand pause Oct 17 19:14:18 jolumio volumio[980]: info: ControllerMpd::getState Oct 17 19:14:18 jolumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:14:18 jolumio volumio[980]: info: Oct 17 19:14:18 jolumio volumio[980]: ---------------------------- MPD announces state update: player Oct 17 19:14:18 jolumio volumio[980]: info: sendMpdCommand pause took 3 milliseconds Oct 17 19:14:18 jolumio volumio[980]: info: ControllerMpd::getState Oct 17 19:14:18 jolumio volumio[980]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:14:18 jolumio volumio[980]: info: sendMpdCommand status took 4 milliseconds Oct 17 19:14:18 jolumio volumio[980]: info: sendMpdCommand status took 2 milliseconds Oct 17 19:14:18 jolumio volumio[980]: verbose: ControllerMpd::parseState Oct 17 19:14:18 jolumio volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:14:18 jolumio volumio[980]: verbose: ControllerMpd::parseState Oct 17 19:14:18 jolumio volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:14:18 jolumio volumio[980]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 19:14:18 jolumio volumio[980]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 19:14:18 jolumio volumio[980]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:14:18 jolumio volumio[980]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:14:18 jolumio volumio[980]: info: ControllerMpd::pushState Oct 17 19:14:18 jolumio volumio[980]: info: CoreCommandRouter::servicePushState Oct 17 19:14:18 jolumio volumio[980]: info: CorePlayQueue::getTrack 44 Oct 17 19:14:18 jolumio volumio[980]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":78855,"duration":232,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Again","artist":"Lenny Kravitz","album":"Again","uri":"https://rr4---sn-45nufxc-4jvl.googlevideo.com/videoplayback?expire=1729206791&ei=p0URZ_CkGuafp-oP3P3JmAw&ip=93.34.92.21&id=o-ALpHdVpfZpUpWuUv6v_Jamv0WsaV6v2AI8u6cfWJJh9H&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1729185191%2C&mh=ae&mm=31%2C26&mn=sn-45nufxc-4jvl%2Csn-hgn7rnee&ms=au%2Conr&mv=m&mvi=4&pl=21&rms=au%2Cau&ctier=A&pfa=5&gcr=it&initcwndbps=1501250&hightc=yes&siu=1&bui=AXLXGFTkz2vntjSS3HkrZWguF0kEbt-_wOsLTyV9FabxERxZGnuGDI9vJ4hRV_Zd7pd4Ohj9Tw&spc=54MbxR2h_YP1M3BcJ__iAL_Qm6_Cq1Jkilcnae8OyppRoeYKhx7uyUf0HbUS4mo&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=ra_2QUd-4QPq3vh6_MLLbtkQ&rqh=1&gir=yes&clen=7321802&dur=231.681&lmt=1714537757628855&mt=1729184730&fvip=2&keepalive=yes&fexp=51300761%2C51312688&c=WEB_REMIX&sefc=1&txp=5432434&n=Ds3Mq7I-Spf9kQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRQIgEAnc14MSXHapHZdGC-HAVISRPc4MUA9NDLhK_GvqSf8CIQDyQjE7eoOthqGqzXeZjTTGQ4E4ViuwajJ6pYZ2dP9M-Q%3D%3D&sig=AJfQdSswRAIgIW8tox7X-O4Ra_cvJquahmF2FVO9EYfTpb74p0pXttgCIGn9ZqtCRTWSM62chiVQdKtXYWJdLQE9sB4HfUT3uv4W&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Oct 17 19:14:18 jolumio volumio[980]: verbose: CURRENT POSITION 44 Oct 17 19:14:18 jolumio volumio[980]: info: CoreStateMachine::syncState stateService pause Oct 17 19:14:18 jolumio volumio[980]: info: CoreStateMachine::syncState currentStatus pause Oct 17 19:14:18 jolumio volumio[980]: info: CoreStateMachine::pushState Oct 17 19:14:18 jolumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:14:18 jolumio volumio[980]: info: CoreCommandRouter::volumioPushState Oct 17 19:14:18 jolumio volumio[980]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:14:18 jolumio volumio[980]: info: MRS: Pushing multiroomSync output Oct 17 19:14:18 jolumio volumio[980]: info: CoreStateMachine::stPlaybackTimer Oct 17 19:14:18 jolumio volumio[980]: info: ------------------------------ 41ms Oct 17 19:14:18 jolumio volumio[980]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Oct 17 19:14:18 jolumio volumio[980]: info: PeppyMeterBasic ---peppymeterbasic status pause Oct 17 19:14:18 jolumio sudo[1532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Oct 17 19:14:18 jolumio sudo[1532]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:14:18 jolumio systemd[1]: Stopping peppymeterbasic Daemon... Oct 17 19:14:18 jolumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Oct 17 19:14:18 jolumio volumio[980]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:14:18 jolumio systemd[1]: peppymeterbasic.service: Succeeded. Oct 17 19:14:18 jolumio systemd[1]: Stopped peppymeterbasic Daemon. Oct 17 19:14:18 jolumio volumio[980]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 17 19:14:18 jolumio sudo[1532]: pam_unix(sudo:session): session closed for user root Oct 17 19:14:18 jolumio volumio[980]: info: peppymeterbasic Daemon Stop Oct 17 19:14:19 jolumio volumio[980]: info: camilladsp spawned new process with pid 1536, instance 1, run: true Oct 17 19:14:24 jolumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 19:14:24 jolumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 19:14:24 jolumio volumio[980]: info: Discovery: Getting this device information Oct 17 19:14:24 jolumio volumio[980]: info: CoreCommandRouter::volumioGetState Oct 17 19:14:24 jolumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 19:14:34 jolumio volumiossh-tunnel[1417]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Oct 17 19:15:44 jolumio volumio[980]: verbose: New Socket.io Connection to 192.168.1.183:3000 from 192.168.1.84 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Oct 17 19:15:44 jolumio volumio[980]: verbose: New Socket.io Connection to 192.168.1.183 from 192.168.1.84 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 13 Oct 17 19:15:46 jolumio volumio[980]: verbose: New Socket.io Connection to 192.168.1.183 from 192.168.1.84 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 14 Oct 17 19:15:47 jolumio volumio[980]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 19:15:47 jolumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 19:15:47 jolumio volumio[980]: info: CoreCommandRouter::volumioGetState Oct 17 19:15:47 jolumio volumio[980]: info: CoreCommandRouter::volumioGetQueue Oct 17 19:15:47 jolumio volumio[980]: info: CoreStateMachine::getQueue Oct 17 19:15:47 jolumio volumio[980]: info: CorePlayQueue::getQueue Oct 17 19:15:47 jolumio volumio[980]: info: Listing playlists Oct 17 19:15:47 jolumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 17 19:15:47 jolumio volumio[980]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 17 19:15:57 jolumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Oct 17 19:15:57 jolumio volumio[980]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Oct 17 19:15:57 jolumio volumio[980]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 19:15:57 jolumio volumio[980]: TypeError: Cannot read property 'then' of undefined Oct 17 19:15:57 jolumio volumio[980]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Oct 17 19:15:57 jolumio volumio[980]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2260:30) Oct 17 19:15:57 jolumio volumio[980]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1442:26) Oct 17 19:15:57 jolumio volumio[980]: at Socket.emit (events.js:315:20) Oct 17 19:15:57 jolumio volumio[980]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 17 19:15:57 jolumio volumio[980]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 17 19:15:57 jolumio volumio[980]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 19:15:59 jolumio sudo[1630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-17 19:14 Oct 17 19:15:59 jolumio sudo[1630]: 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"