-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2024-10-18 04:55:18 CEST. -- Oct 18 04:54:03 volumio go-librespot[1188]: time="2024-10-18T04:54:03+02:00" level=error msg="did not receive last pong from dealer, 121800s passed" Oct 18 04:54:32 volumio go-librespot[1188]: time="2024-10-18T04:54:32+02:00" level=error msg="did not receive last pong ack from accesspoint, 42741s passed" Oct 18 04:54:33 volumio go-librespot[1188]: time="2024-10-18T04:54:33+02:00" level=error msg="did not receive last pong from dealer, 121830s passed" Oct 18 04:55:00 volumio volumio[999]: info: [1729220100957] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Oct 18 04:55:01 volumio volumio[999]: info: [1729220101606] [RadioParadise] received new metadata: {"time":234,"artist":"Rod Stewart","title":"Reason to Believe","album":"Every Picture Tells a Story","year":"1971","cover":"https://img.radioparadise.com/covers/l/B00000612P.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00000612P.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00000612P.jpg"} Oct 18 04:55:01 volumio volumio[999]: info: CorePlayQueue::getTrack 0 Oct 18 04:55:01 volumio volumio[999]: info: CoreCommandRouter::servicePushState Oct 18 04:55:01 volumio volumio[999]: info: CorePlayQueue::getTrack 0 Oct 18 04:55:01 volumio volumio[999]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00000612P.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Reason to Believe","title":"Reason to Believe","artist":"Rod Stewart","album":"Every Picture Tells a Story","streaming":true,"disableUiControls":true,"duration":239,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 18 04:55:01 volumio volumio[999]: verbose: CURRENT POSITION 0 Oct 18 04:55:01 volumio volumio[999]: info: CoreStateMachine::syncState stateService play Oct 18 04:55:01 volumio volumio[999]: info: CoreStateMachine::syncState currentStatus play Oct 18 04:55:01 volumio volumio[999]: info: Received an update from plugin. extracting info from payload Oct 18 04:55:01 volumio volumio[999]: info: CoreStateMachine::pushState Oct 18 04:55:01 volumio volumio[999]: info: CorePlayQueue::getTrack 0 Oct 18 04:55:01 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 04:55:01 volumio volumio[999]: info: CoreCommandRouter::volumioPushState Oct 18 04:55:01 volumio volumio[999]: info: MRS: Pushing multiroomSync output update for this device Oct 18 04:55:01 volumio volumio[999]: info: MRS: Pushing multiroomSync output Oct 18 04:55:01 volumio volumio[999]: info: CoreStateMachine::pushState Oct 18 04:55:01 volumio volumio[999]: info: CorePlayQueue::getTrack 0 Oct 18 04:55:01 volumio volumio[999]: info: CoreCommandRouter::volumioPushState Oct 18 04:55:01 volumio volumio[999]: info: MRS: Pushing multiroomSync output update for this device Oct 18 04:55:01 volumio volumio[999]: info: MRS: Pushing multiroomSync output Oct 18 04:55:01 volumio volumio[999]: info: [1729220101614] [RadioParadise] setting new timer with duration of 239000 seconds. Oct 18 04:55:01 volumio volumio[999]: info: FusionDsp - Checking filters freq for detected sample rate ======== 44100 Oct 18 04:55:01 volumio volumio[999]: info: FusionDsp - Checking filters freq for detected sample rate ======== 44100 Oct 18 04:55:01 volumio volumio[999]: info: FusionDsp - Effects disabled, Nulleq applied Oct 18 04:55:01 volumio volumio[999]: info: FusionDsp - Effects disabled, Nulleq applied Oct 18 04:55:01 volumio volumio[999]: info: FusionDsp - Effects disabled, Nulleq applied Oct 18 04:55:01 volumio volumio[999]: info: FusionDsp - Effects disabled, Nulleq applied Oct 18 04:55:01 volumio volumio[999]: info: CorePlayQueue::getTrack 0 Oct 18 04:55:01 volumio volumio[999]: info: CorePlayQueue::getTrack 1 Oct 18 04:55:02 volumio volumio[999]: info: FusionDsp - Oct 18 04:55:02 volumio volumio[999]: info: FusionDsp - Oct 18 04:55:03 volumio go-librespot[1188]: time="2024-10-18T04:55:03+02:00" level=error msg="did not receive last pong from dealer, 121860s passed" Oct 18 04:55:17 volumio volumio[999]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 04:55:17 volumio volumio[999]: Error: WebSocket is not open: readyState 0 (CONNECTING) Oct 18 04:55:17 volumio volumio[999]: at WebSocket.send (/data/plugins/audio_interface/fusiondsp/node_modules/ws/lib/websocket.js:394:13) Oct 18 04:55:17 volumio volumio[999]: at Timeout._onTimeout (/data/plugins/audio_interface/fusiondsp/index.js:2144:16) Oct 18 04:55:17 volumio volumio[999]: at listOnTimeout (internal/timers.js:554:17) Oct 18 04:55:17 volumio volumio[999]: at processTimers (internal/timers.js:497:7) Oct 18 04:55:17 volumio volumio[999]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 04:55:18 volumio sudo[4632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-18 04:54 Oct 18 04:55:18 volumio sudo[4632]: 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"