-- Logs begin at Sat 2024-11-02 22:12:40 CET, end at Sat 2024-11-02 23:11:09 CET. -- Nov 02 23:10:08 volumio volumio[7415]: info: CoreCommandRouter::volumioGetState Nov 02 23:10:08 volumio volumio[7415]: info: CorePlayQueue::getTrack 548 Nov 02 23:10:54 volumio volumio[7415]: error: [yt-cast-receiver] RPC connection error: Nov 02 23:10:54 volumio volumio[7415]: (FetchError) request to https://www.youtube.com/api/lounge/bc/bind?AID=31&CI=0&RID=rpc&SID=33023C3DF64197B1&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=dj2eflAjt94TF10bpKXWCN6ESb1OHpeP&id=947a5208-c6b6-46ba-9f86-695303223b58&loungeIdToken=AGdO5p9pU0TiTuesalGcUkz7e47ksC5wliKSChHbeyN5BSng2mLukRL8k_5tpoARCruusEW_XOV38GxlmkN7oQvdOsi8kIGnC5UAfITPvp_2YS1cm5-a8Ao&mdxVersion=2&name=YouTube%20on%20Volumio&obfuscatedGaiaId=&t=1&theme=cl&v=2&zx=21e545972808 failed, reason: connect ETIMEDOUT 216.58.209.46:443 Nov 02 23:10:54 volumio volumio[7415]: FetchError: request to https://www.youtube.com/api/lounge/bc/bind?AID=31&CI=0&RID=rpc&SID=33023C3DF64197B1&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=dj2eflAjt94TF10bpKXWCN6ESb1OHpeP&id=947a5208-c6b6-46ba-9f86-695303223b58&loungeIdToken=AGdO5p9pU0TiTuesalGcUkz7e47ksC5wliKSChHbeyN5BSng2mLukRL8k_5tpoARCruusEW_XOV38GxlmkN7oQvdOsi8kIGnC5UAfITPvp_2YS1cm5-a8Ao&mdxVersion=2&name=YouTube%20on%20Volumio&obfuscatedGaiaId=&t=1&theme=cl&v=2&zx=21e545972808 failed, reason: connect ETIMEDOUT 216.58.209.46:443 Nov 02 23:10:54 volumio volumio[7415]: at ClientRequest. (/data/plugins/music_service/ytcr/node_modules/node-fetch/lib/index.js:1505:11) Nov 02 23:10:54 volumio volumio[7415]: at ClientRequest.emit (events.js:315:20) Nov 02 23:10:54 volumio volumio[7415]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Nov 02 23:10:54 volumio volumio[7415]: at TLSSocket.emit (events.js:315:20) Nov 02 23:10:54 volumio volumio[7415]: at emitErrorNT (internal/streams/destroy.js:106:8) Nov 02 23:10:54 volumio volumio[7415]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Nov 02 23:10:54 volumio volumio[7415]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 02 23:10:54 volumio volumio[7415]: error: [yt-cast-receiver] Retrying 1 / 3 Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::volumioClearQueue Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::ClearQueue Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::stop Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::stPlaybackTimer Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::updateTrackBlock Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::getTrackBlock Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::pushState Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::getTrack 548 Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::volumioPushState Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::serviceStop Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::getTrack 548 Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::serviceStop Nov 02 23:11:00 volumio volumio[7415]: info: ControllerMpd::stop Nov 02 23:11:00 volumio volumio[7415]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::clearPlayQueue Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::saveQueue Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::volumioPushState Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::volumioPushQueue Nov 02 23:11:00 volumio volumio[7415]: info: Nov 02 23:11:00 volumio volumio[7415]: ---------------------------- MPD announces state update: player Nov 02 23:11:00 volumio volumio[7415]: info: sendMpdCommand stop took 30 milliseconds Nov 02 23:11:00 volumio volumio[7415]: info: ControllerMpd::getState Nov 02 23:11:00 volumio volumio[7415]: verbose: ControllerMpd::sendMpdCommand status Nov 02 23:11:00 volumio volumio[7415]: info: sendMpdCommand status took 2 milliseconds Nov 02 23:11:00 volumio volumio[7415]: verbose: ControllerMpd::parseState Nov 02 23:11:00 volumio volumio[7415]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 23:11:00 volumio volumio[7415]: info: Nov 02 23:11:00 volumio volumio[7415]: ---------------------------- MPD announces state update: player Nov 02 23:11:00 volumio volumio[7415]: info: ControllerMpd::getState Nov 02 23:11:00 volumio volumio[7415]: verbose: ControllerMpd::sendMpdCommand status Nov 02 23:11:00 volumio volumio[7415]: info: Nov 02 23:11:00 volumio volumio[7415]: ---------------------------- MPD announces state update: player Nov 02 23:11:00 volumio volumio[7415]: info: ControllerMpd::getState Nov 02 23:11:00 volumio volumio[7415]: verbose: ControllerMpd::sendMpdCommand status Nov 02 23:11:00 volumio volumio[7415]: info: sendMpdCommand playlistinfo took 40 milliseconds Nov 02 23:11:00 volumio volumio[7415]: info: sendMpdCommand status took 8 milliseconds Nov 02 23:11:00 volumio volumio[7415]: info: sendMpdCommand status took 6 milliseconds Nov 02 23:11:00 volumio volumio[7415]: verbose: ControllerMpd::parseTrackInfo Nov 02 23:11:00 volumio volumio[7415]: verbose: ControllerMpd::parseState Nov 02 23:11:00 volumio volumio[7415]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 23:11:00 volumio volumio[7415]: verbose: ControllerMpd::parseState Nov 02 23:11:00 volumio volumio[7415]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 23:11:00 volumio volumio[7415]: info: ControllerMpd::pushState Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::servicePushState Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::pushState Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::getTrack 548 Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::volumioPushState Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::getTrack 548 Nov 02 23:11:00 volumio volumio[7415]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I'm Not in Love","artist":"6. 10CC","album":null,"uri":"USB/FE3E-A0D2/I'm Not in Love.mp3","trackType":"mp3"} Nov 02 23:11:00 volumio volumio[7415]: verbose: CURRENT POSITION 548 Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::syncState stateService stop Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::syncState currentStatus stop Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::pushState Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::getTrack 548 Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::volumioPushState Nov 02 23:11:00 volumio volumio[7415]: info: No code Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::pushState Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::getTrack 548 Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::volumioPushState Nov 02 23:11:00 volumio volumio[7415]: info: ------------------------------ 102ms Nov 02 23:11:00 volumio volumio[7415]: info: touch_display: Setting screensaver timeout to 47 seconds. Nov 02 23:11:00 volumio volumio[7415]: info: sendMpdCommand playlistinfo took 56 milliseconds Nov 02 23:11:00 volumio volumio[7415]: info: sendMpdCommand playlistinfo took 55 milliseconds Nov 02 23:11:00 volumio volumio[7415]: verbose: ControllerMpd::parseTrackInfo Nov 02 23:11:00 volumio volumio[7415]: verbose: ControllerMpd::parseTrackInfo Nov 02 23:11:00 volumio volumio[7415]: info: ControllerMpd::pushState Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::servicePushState Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::pushState Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::getTrack 548 Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::volumioPushState Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::getTrack 548 Nov 02 23:11:00 volumio volumio[7415]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I'm Not in Love","artist":"6. 10CC","album":null,"uri":"USB/FE3E-A0D2/I'm Not in Love.mp3","trackType":"mp3"} Nov 02 23:11:00 volumio volumio[7415]: verbose: CURRENT POSITION 548 Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::syncState stateService stop Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::syncState currentStatus stop Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::pushState Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::getTrack 548 Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::volumioPushState Nov 02 23:11:00 volumio volumio[7415]: info: No code Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::pushState Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::getTrack 548 Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::volumioPushState Nov 02 23:11:00 volumio volumio[7415]: info: ControllerMpd::pushState Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::servicePushState Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::pushState Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::getTrack 548 Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::volumioPushState Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::getTrack 548 Nov 02 23:11:00 volumio volumio[7415]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I'm Not in Love","artist":"6. 10CC","album":null,"uri":"USB/FE3E-A0D2/I'm Not in Love.mp3","trackType":"mp3"} Nov 02 23:11:00 volumio volumio[7415]: verbose: CURRENT POSITION 548 Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::syncState stateService stop Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::syncState currentStatus stop Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::pushState Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::getTrack 548 Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::volumioPushState Nov 02 23:11:00 volumio volumio[7415]: info: No code Nov 02 23:11:00 volumio volumio[7415]: info: CoreStateMachine::pushState Nov 02 23:11:00 volumio volumio[7415]: info: CorePlayQueue::getTrack 548 Nov 02 23:11:00 volumio volumio[7415]: info: CoreCommandRouter::volumioPushState Nov 02 23:11:00 volumio volumio[7415]: info: ------------------------------ 144ms Nov 02 23:11:00 volumio volumio[7415]: info: ------------------------------ 143ms Nov 02 23:11:06 volumio volumio[7415]: info: CoreCommandRouter::volumioGetState Nov 02 23:11:06 volumio volumio[7415]: info: CorePlayQueue::getTrack 548 Nov 02 23:11:09 volumio volumio[7415]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 23:11:09 volumio volumio[7415]: Error: connect ETIMEDOUT 151.101.242.79:80 Nov 02 23:11:09 volumio volumio[7415]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Nov 02 23:11:09 volumio volumio[7415]: errno: -110, Nov 02 23:11:09 volumio volumio[7415]: code: 'ETIMEDOUT', Nov 02 23:11:09 volumio volumio[7415]: syscall: 'connect', Nov 02 23:11:09 volumio volumio[7415]: address: '151.101.242.79', Nov 02 23:11:09 volumio volumio[7415]: port: 80 Nov 02 23:11:09 volumio volumio[7415]: } Nov 02 23:11:09 volumio volumio[7415]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 23:11:09 volumio sudo[8804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 23:10 Nov 02 23:11:09 volumio sudo[8804]: 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"