-- Logs begin at Thu 2019-02-14 11:11:59 WAT, end at Wed 2025-04-30 04:11:06 WAT. -- Apr 30 04:10:00 volumio volumio[871]: info: CoreCommandRouter::volumioGetState Apr 30 04:10:00 volumio volumio[871]: info: CorePlayQueue::getTrack 0 Apr 30 04:10:02 volumio volumio[871]: error: error Apr 30 04:10:02 volumio volumio[871]: info: Initializing connection to go-librespot Websocket Apr 30 04:10:02 volumio go-librespot[5450]: time="2025-04-30T04:10:02+01:00" level=debug msg="new websocket client" Apr 30 04:10:02 volumio volumio[871]: info: Connection to go-librespot Websocket established Apr 30 04:10:05 volumio go-librespot[5450]: time="2025-04-30T04:10:05+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 30 04:10:05 volumio go-librespot[5450]: time="2025-04-30T04:10:05+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Apr 30 04:10:05 volumio go-librespot[5450]: time="2025-04-30T04:10:05+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Apr 30 04:10:05 volumio go-librespot[5450]: time="2025-04-30T04:10:05+01:00" level=debug msg="zeroconf server listening on port 42635" Apr 30 04:10:05 volumio volumio[871]: info: Getting Spotify volume Apr 30 04:10:05 volumio volumio[871]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 30 04:10:05 volumio volumio[871]: info: CoreCommandRouter::volumioGetState Apr 30 04:10:05 volumio volumio[871]: info: CorePlayQueue::getTrack 0 Apr 30 04:10:05 volumio volumio[871]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Apr 30 04:10:06 volumio go-librespot[5450]: time="2025-04-30T04:10:06+01:00" level=debug msg="obtained new client token: AADVlBIOF+UADiJIMnNDp8VQ0ZQxjoJXEIXEHhlxwAfcIvndYZ3CU9WbvpAegF9h6AwEekFYhl/gQnIyLeZceS4Cwmn5cFHftFzT+rFoFMdYsW5yUA8/PtpgkVC3J/o8bv8wFpdoiHPCqEJRlqQkd1JfqHirrmlGInai3Xzs+jdPCy8FRADeATHTDnEenuow6UnLk5DoWHnZ0xbHPU6fx+NibY7TrLAyL6YJzPA1xNClb8cYofeEqW4TESk=" Apr 30 04:10:06 volumio go-librespot[5450]: time="2025-04-30T04:10:06+01:00" level=debug msg="completed keyexchange" Apr 30 04:10:06 volumio go-librespot[5450]: time="2025-04-30T04:10:06+01:00" level=debug msg="completed challenge" Apr 30 04:10:06 volumio go-librespot[5450]: time="2025-04-30T04:10:06+01:00" level=debug msg="authenticated as collinskcs" Apr 30 04:10:10 volumio volumio[871]: info: CoreCommandRouter::volumioGetState Apr 30 04:10:10 volumio volumio[871]: info: CorePlayQueue::getTrack 0 Apr 30 04:10:12 volumio go-librespot[5450]: time="2025-04-30T04:10:12+01:00" level=debug msg="authenticated as collinskcs" Apr 30 04:10:12 volumio go-librespot[5450]: time="2025-04-30T04:10:12+01:00" level=debug msg="dealer connection opened" Apr 30 04:10:12 volumio go-librespot[5450]: time="2025-04-30T04:10:12+01:00" level=debug msg="initializing zeroconf session, username: collinskcs" Apr 30 04:10:12 volumio go-librespot[5450]: time="2025-04-30T04:10:12+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 30 04:10:12 volumio go-librespot[5450]: time="2025-04-30T04:10:12+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 30 04:10:12 volumio go-librespot[5450]: time="2025-04-30T04:10:12+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 30 04:10:12 volumio go-librespot[5450]: time="2025-04-30T04:10:12+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 30 04:10:12 volumio go-librespot[5450]: time="2025-04-30T04:10:12+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 30 04:10:12 volumio go-librespot[5450]: time="2025-04-30T04:10:12+01:00" level=debug msg="autoplay enabled: false" Apr 30 04:10:12 volumio go-librespot[5450]: time="2025-04-30T04:10:12+01:00" level=debug msg="received connection id: ZTYxOGQxODEtM2Q1MC00MWU1LWJiMjctZjMyY2NiNmU3OWNiK2RlYWxlcit0Y3A6Ly8wYWNhNDEwMi5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArQzI5MTg4RUI0MDgzQzI4OTlDNzA4NTNDNzc2M0YyODI1Q0YyMjI3NjIyMEY5REJFNTNEOTA0RjMwMDIxRjVEOQ==" Apr 30 04:10:12 volumio volumio[871]: info: Spotify volume: 100 Apr 30 04:10:17 volumio go-librespot[5450]: time="2025-04-30T04:10:17+01:00" level=debug msg="put connect state because NEW_DEVICE" Apr 30 04:10:20 volumio volumio[871]: info: CoreCommandRouter::volumioGetState Apr 30 04:10:20 volumio volumio[871]: info: CorePlayQueue::getTrack 0 Apr 30 04:10:30 volumio volumio[871]: info: CoreCommandRouter::volumioGetState Apr 30 04:10:30 volumio volumio[871]: info: CorePlayQueue::getTrack 0 Apr 30 04:10:40 volumio volumio[871]: info: CoreCommandRouter::volumioGetState Apr 30 04:10:40 volumio volumio[871]: info: CorePlayQueue::getTrack 0 Apr 30 04:10:50 volumio volumio[871]: info: CoreCommandRouter::volumioGetState Apr 30 04:10:50 volumio volumio[871]: info: CorePlayQueue::getTrack 0 Apr 30 04:10:52 volumio volumio[871]: error: error Apr 30 04:11:00 volumio volumio[871]: info: CoreCommandRouter::volumioGetState Apr 30 04:11:00 volumio volumio[871]: info: CorePlayQueue::getTrack 0 Apr 30 04:11:05 volumio volumio[871]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 04:11:05 volumio volumio[871]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Apr 30 04:11:05 volumio volumio[871]: code: 'auth/network-request-failed', Apr 30 04:11:05 volumio volumio[871]: a: null Apr 30 04:11:05 volumio volumio[871]: } Apr 30 04:11:05 volumio volumio[871]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 04:11:06 volumio sudo[5652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-30 04:10 Apr 30 04:11:06 volumio sudo[5652]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"