-- 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"