-- Logs begin at Sat 2025-04-26 09:57:31 CEST, end at Sat 2025-04-26 18:31:24 CEST. -- Apr 26 18:30:02 musicnonstop volumio[1339]: verbose: New Socket.io Connection to 192.168.1.134:3000 from 192.168.1.59 UA: Dart/3.7 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Apr 26 18:30:05 musicnonstop go-librespot[1843]: time="2025-04-26T18:30:05+02:00" level=debug msg="fetched chunk 7/8, size: 524288" uri="spotify:track:2BjRhZ2IJmsO1GRquNldsP" Apr 26 18:30:20 musicnonstop go-librespot[1843]: time="2025-04-26T18:30:20+02:00" level=debug msg="fetched chunk 8/8, size: 499120" uri="spotify:track:2BjRhZ2IJmsO1GRquNldsP" Apr 26 18:30:29 musicnonstop volumio[1339]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 18:30:29 musicnonstop volumio[1339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 18:30:29 musicnonstop volumio[1339]: info: Discovery: Getting this device information Apr 26 18:30:29 musicnonstop volumio[1339]: info: CoreCommandRouter::volumioGetState Apr 26 18:30:29 musicnonstop volumio[1339]: info: CorePlayQueue::getTrack 0 Apr 26 18:30:29 musicnonstop volumio[1339]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 18:30:58 musicnonstop volumio[1339]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 18:30:58 musicnonstop volumio[1339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 18:30:58 musicnonstop volumio[1339]: info: Discovery: Getting this device information Apr 26 18:30:58 musicnonstop volumio[1339]: info: CoreCommandRouter::volumioGetState Apr 26 18:30:58 musicnonstop volumio[1339]: info: CorePlayQueue::getTrack 0 Apr 26 18:30:58 musicnonstop volumio[1339]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 18:31:12 musicnonstop volumio[1339]: info: CorePlayQueue::getTrack 0 Apr 26 18:31:12 musicnonstop volumio[1339]: info: CorePlayQueue::getTrack 1 Apr 26 18:31:12 musicnonstop volumio[1339]: info: Prefetching next song Apr 26 18:31:12 musicnonstop volumio[1339]: info: [1745685072816] ControllerSpotify::prefetch Apr 26 18:31:12 musicnonstop volumio[1339]: info: Sending Spotify command with payload to local API: /player/add_to_queue Apr 26 18:31:12 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:12+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 26 18:31:12 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:12+02:00" level=debug msg="prefetching next track" uri="spotify:track:4LyO99BhEMAyVGe4J5genE" Apr 26 18:31:13 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:13+02:00" level=debug msg="selected format OGG_VORBIS_320 (693aee8a4e3e35b96a0d123fbb2d21d398318bdc)" uri="spotify:track:4LyO99BhEMAyVGe4J5genE" Apr 26 18:31:13 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:13+02:00" level=debug msg="requested aes key for file 693aee8a4e3e35b96a0d123fbb2d21d398318bdc, gid: 4LyO99BhEMAyVGe4J5genE" Apr 26 18:31:13 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:13+02:00" level=debug msg="fetched first chunk of 10, total size is 5226860 bytes" uri="spotify:track:4LyO99BhEMAyVGe4J5genE" Apr 26 18:31:13 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:13+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:4LyO99BhEMAyVGe4J5genE" Apr 26 18:31:13 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:13+02:00" level=info msg="prefetched track \"The Black Hawk War, Or, How to Demolish an Entire Civilization and Still Feel Good About Yourself In the Morning, Or, We Apologize for the Inconvenience But You're Going to Have to Leave Now, Or...\" (duration: 134786ms)" uri="spotify:track:4LyO99BhEMAyVGe4J5genE" Apr 26 18:31:13 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:13+02:00" level=debug msg="fetched chunk 2/9, size: 524288" uri="spotify:track:4LyO99BhEMAyVGe4J5genE" Apr 26 18:31:13 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:13+02:00" level=debug msg="fetched chunk 1/9, size: 524288" uri="spotify:track:4LyO99BhEMAyVGe4J5genE" Apr 26 18:31:13 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:13+02:00" level=debug msg="fetched chunk 3/9, size: 524288" uri="spotify:track:4LyO99BhEMAyVGe4J5genE" Apr 26 18:31:16 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:16+02:00" level=trace msg="emitting websocket event: not_playing" Apr 26 18:31:16 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:16+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:4LyO99BhEMAyVGe4J5genE" Apr 26 18:31:16 musicnonstop volumio[1339]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:2BjRhZ2IJmsO1GRquNldsP","play_origin":"go-librespot"}} Apr 26 18:31:16 musicnonstop volumio[1339]: error: Failed to decode event: not_playing Apr 26 18:31:16 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:16+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 26 18:31:16 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:16+02:00" level=trace msg="emitting websocket event: will_play" Apr 26 18:31:16 musicnonstop volumio[1339]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4LyO99BhEMAyVGe4J5genE","play_origin":"go-librespot"}} Apr 26 18:31:16 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:16+02:00" level=info msg="loaded track \"The Black Hawk War, Or, How to Demolish an Entire Civilization and Still Feel Good About Yourself In the Morning, Or, We Apologize for the Inconvenience But You're Going to Have to Leave Now, Or...\" (paused: false, position: 0ms, duration: 134786ms, prefetched: true)" uri="spotify:track:4LyO99BhEMAyVGe4J5genE" Apr 26 18:31:16 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:16+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 26 18:31:16 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:16+02:00" level=trace msg="scheduling prefetch in 104s" Apr 26 18:31:16 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:16+02:00" level=trace msg="emitting websocket event: metadata" Apr 26 18:31:16 musicnonstop volumio[1339]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4LyO99BhEMAyVGe4J5genE","name":"The Black Hawk War, Or, How to Demolish an Entire Civilization and Still Feel Good About Yourself In the Morning, Or, We Apologize for the Inconvenience But You're Going to Have to Leave Now, Or...","artist_names":["Sufjan Stevens"],"album_name":"Illinois","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02712c76b4152918fb8d37c53d","position":0,"duration":134786,"release_date":"year:2005 month:7 day:5","track_number":2,"disc_number":1}} Apr 26 18:31:17 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 26 18:31:17 musicnonstop go-librespot[1843]: time="2025-04-26T18:31:17+02:00" level=trace msg="emitting websocket event: playing" Apr 26 18:31:17 musicnonstop volumio[1339]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4LyO99BhEMAyVGe4J5genE","play_origin":"go-librespot"}} Apr 26 18:31:17 musicnonstop volumio[1339]: SPOTIFY: PUSH STATE SPOTIFY Apr 26 18:31:17 musicnonstop volumio[1339]: SPOTIFY: {"status":"play","service":"spop","title":"The Black Hawk War, Or, How to Demolish an Entire Civilization and Still Feel Good About Yourself In the Morning, Or, We Apologize for the Inconvenience But You're Going to Have to Leave Now, Or...","artist":"Sufjan Stevens","album":"Illinois","albumart":"https://i.scdn.co/image/ab67616d00001e02712c76b4152918fb8d37c53d","uri":"spotify:track:4LyO99BhEMAyVGe4J5genE","trackType":"spotify","seek":0,"duration":134,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreCommandRouter::servicePushState Apr 26 18:31:17 musicnonstop volumio[1339]: info: CorePlayQueue::getTrack 0 Apr 26 18:31:17 musicnonstop volumio[1339]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"The Black Hawk War, Or, How to Demolish an Entire Civilization and Still Feel Good About Yourself In the Morning, Or, We Apologize for the Inconvenience But You're Going to Have to Leave Now, Or...","artist":"Sufjan Stevens","album":"Illinois","albumart":"https://i.scdn.co/image/ab67616d00001e02712c76b4152918fb8d37c53d","uri":"spotify:track:4LyO99BhEMAyVGe4J5genE","trackType":"spotify","seek":0,"duration":134,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 26 18:31:17 musicnonstop volumio[1339]: verbose: CURRENT POSITION 0 Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreStateMachine::syncState stateService play Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreStateMachine::syncState currentStatus play Apr 26 18:31:17 musicnonstop volumio[1339]: info: Received an update from plugin. extracting info from payload Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreStateMachine::pushState Apr 26 18:31:17 musicnonstop volumio[1339]: info: CorePlayQueue::getTrack 0 Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreCommandRouter::volumioPushState Apr 26 18:31:17 musicnonstop volumio[1339]: info: MRS: Pushing multiroomSync output update for this device Apr 26 18:31:17 musicnonstop volumio[1339]: info: MRS: Pushing multiroomSync output Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreStateMachine::pushState Apr 26 18:31:17 musicnonstop volumio[1339]: info: CorePlayQueue::getTrack 0 Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreCommandRouter::volumioPushState Apr 26 18:31:17 musicnonstop volumio[1339]: info: MRS: Pushing multiroomSync output update for this device Apr 26 18:31:17 musicnonstop volumio[1339]: info: MRS: Pushing multiroomSync output Apr 26 18:31:17 musicnonstop volumio[1339]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 26 18:31:17 musicnonstop volumio[1339]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreStateMachine::startPlaybackTimer Apr 26 18:31:17 musicnonstop volumio[1339]: info: CorePlayQueue::getTrack 1 Apr 26 18:31:17 musicnonstop volumio[1339]: SPOTIFY: PUSH STATE SPOTIFY Apr 26 18:31:17 musicnonstop volumio[1339]: SPOTIFY: {"status":"play","service":"spop","title":"The Black Hawk War, Or, How to Demolish an Entire Civilization and Still Feel Good About Yourself In the Morning, Or, We Apologize for the Inconvenience But You're Going to Have to Leave Now, Or...","artist":"Sufjan Stevens","album":"Illinois","albumart":"https://i.scdn.co/image/ab67616d00001e02712c76b4152918fb8d37c53d","uri":"spotify:track:4LyO99BhEMAyVGe4J5genE","trackType":"spotify","seek":1000,"duration":134,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreCommandRouter::servicePushState Apr 26 18:31:17 musicnonstop volumio[1339]: info: CorePlayQueue::getTrack 1 Apr 26 18:31:17 musicnonstop volumio[1339]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"The Black Hawk War, Or, How to Demolish an Entire Civilization and Still Feel Good About Yourself In the Morning, Or, We Apologize for the Inconvenience But You're Going to Have to Leave Now, Or...","artist":"Sufjan Stevens","album":"Illinois","albumart":"https://i.scdn.co/image/ab67616d00001e02712c76b4152918fb8d37c53d","uri":"spotify:track:4LyO99BhEMAyVGe4J5genE","trackType":"spotify","seek":1000,"duration":134,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 26 18:31:17 musicnonstop volumio[1339]: verbose: CURRENT POSITION 1 Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreStateMachine::syncState stateService play Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreStateMachine::syncState currentStatus play Apr 26 18:31:17 musicnonstop volumio[1339]: info: Received an update from plugin. extracting info from payload Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreStateMachine::pushState Apr 26 18:31:17 musicnonstop volumio[1339]: info: CorePlayQueue::getTrack 1 Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreCommandRouter::volumioPushState Apr 26 18:31:17 musicnonstop volumio[1339]: info: MRS: Pushing multiroomSync output update for this device Apr 26 18:31:17 musicnonstop volumio[1339]: info: MRS: Pushing multiroomSync output Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreStateMachine::pushState Apr 26 18:31:17 musicnonstop volumio[1339]: info: CorePlayQueue::getTrack 1 Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreCommandRouter::volumioPushState Apr 26 18:31:17 musicnonstop volumio[1339]: info: MRS: Pushing multiroomSync output update for this device Apr 26 18:31:17 musicnonstop volumio[1339]: info: MRS: Pushing multiroomSync output Apr 26 18:31:17 musicnonstop volumio[1339]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 26 18:31:17 musicnonstop volumio[1339]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreStateMachine::pushState Apr 26 18:31:17 musicnonstop volumio[1339]: info: CorePlayQueue::getTrack 1 Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 18:31:17 musicnonstop volumio[1339]: info: CoreCommandRouter::volumioPushState Apr 26 18:31:17 musicnonstop volumio[1339]: info: MRS: Pushing multiroomSync output update for this device Apr 26 18:31:17 musicnonstop volumio[1339]: info: MRS: Pushing multiroomSync output Apr 26 18:31:17 musicnonstop volumio[1339]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 26 18:31:24 musicnonstop volumio[1339]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 18:31:24 musicnonstop volumio[1339]: Error: connect ETIMEDOUT 151.101.134.79:443 Apr 26 18:31:24 musicnonstop volumio[1339]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 26 18:31:24 musicnonstop volumio[1339]: errno: -110, Apr 26 18:31:24 musicnonstop volumio[1339]: code: 'ETIMEDOUT', Apr 26 18:31:24 musicnonstop volumio[1339]: syscall: 'connect', Apr 26 18:31:24 musicnonstop volumio[1339]: address: '151.101.134.79', Apr 26 18:31:24 musicnonstop volumio[1339]: port: 443 Apr 26 18:31:24 musicnonstop volumio[1339]: } Apr 26 18:31:24 musicnonstop volumio[1339]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 18:31:24 musicnonstop sudo[11556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 18:30 Apr 26 18:31:24 musicnonstop sudo[11556]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"