-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Wed 2026-03-04 15:13:45 UTC. -- Mar 04 15:12:00 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:12:00 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:12:05 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:12:05 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:12:10 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:12:10 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:12:16 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:12:16 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:12:17 volumio go-librespot[1326]: time="2026-03-04T15:12:17Z" level=trace msg="sent dealer ping" Mar 04 15:12:17 volumio go-librespot[1326]: time="2026-03-04T15:12:17Z" level=trace msg="received dealer pong" Mar 04 15:12:21 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:12:21 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:12:26 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:12:26 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:12:31 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:12:31 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:12:36 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:12:36 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:12:40 volumio go-librespot[1326]: time="2026-03-04T15:12:40Z" level=debug msg="handling transfer player command from 546a1cf3e4b7b842a276ccef7f96ea3db9f8f655" Mar 04 15:12:41 volumio go-librespot[1326]: time="2026-03-04T15:12:41Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Mar 04 15:12:41 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:12:41 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:12:41 volumio go-librespot[1326]: time="2026-03-04T15:12:41Z" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1E4wYWiDJuwz1b" Mar 04 15:12:41 volumio go-librespot[1326]: time="2026-03-04T15:12:41Z" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1E4wYWiDJuwz1b" Mar 04 15:12:41 volumio go-librespot[1326]: time="2026-03-04T15:12:41Z" level=debug msg="shuffled context with seed 11030784173951229599 (len: 50, keep: 13)" uri="spotify:playlist:37i9dQZF1E4wYWiDJuwz1b" Mar 04 15:12:41 volumio go-librespot[1326]: time="2026-03-04T15:12:41Z" level=debug msg="loading track (paused: true, position: 125428ms)" uri="spotify:track:0lvhEsN1zkMzfp2M1o17yy" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=trace msg="emitting websocket event: will_play" Mar 04 15:12:42 volumio volumio[1015]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1E4wYWiDJuwz1b","uri":"spotify:track:0lvhEsN1zkMzfp2M1o17yy","play_origin":"com.spotify.service.carplayv2"}} Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="selected format OGG_VORBIS_320 (16bb1a088dd27e60f4f5cc6ecf02b0985ad2fa55)" uri="spotify:track:0lvhEsN1zkMzfp2M1o17yy" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="requested aes key for file 16bb1a088dd27e60f4f5cc6ecf02b0985ad2fa55, gid: 0lvhEsN1zkMzfp2M1o17yy" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=trace msg="found 2 cdn urls" uri="spotify:track:0lvhEsN1zkMzfp2M1o17yy" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="fetched first chunk of 18, total size is 9351778 bytes" uri="spotify:track:0lvhEsN1zkMzfp2M1o17yy" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="fetched chunk 1/17, size: 524288" uri="spotify:track:0lvhEsN1zkMzfp2M1o17yy" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="fetched chunk 2/17, size: 524288" uri="spotify:track:0lvhEsN1zkMzfp2M1o17yy" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="fetched chunk 9/17, size: 524288" uri="spotify:track:0lvhEsN1zkMzfp2M1o17yy" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=trace msg="seek to 125428ms (diff: 25ms, samples: 5531374, bytes: 5065440)" uri="spotify:track:0lvhEsN1zkMzfp2M1o17yy" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="created new output device" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=info msg="loaded track \"Holiday\" (paused: true, position: 125428ms, duration: 232826ms, prefetched: false)" uri="spotify:track:0lvhEsN1zkMzfp2M1o17yy" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="fetched chunk 11/17, size: 524288" uri="spotify:track:0lvhEsN1zkMzfp2M1o17yy" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="fetched chunk 3/17, size: 524288" uri="spotify:track:0lvhEsN1zkMzfp2M1o17yy" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="fetched chunk 10/17, size: 524288" uri="spotify:track:0lvhEsN1zkMzfp2M1o17yy" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="fetched chunk 12/17, size: 524288" uri="spotify:track:0lvhEsN1zkMzfp2M1o17yy" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=trace msg="emitting websocket event: metadata" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=trace msg="emitting websocket event: active" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="sending successful reply for dealer request" Mar 04 15:12:42 volumio volumio[1015]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0lvhEsN1zkMzfp2M1o17yy","name":"Holiday","artist_names":["Green Day"],"album_name":"American Idiot (20th Anniversary Deluxe Edition)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0291809a44f16f6434927c1e3f","position":125428,"duration":232826,"release_date":"year:2024 month:10 day:25","track_number":3,"disc_number":1}} Mar 04 15:12:42 volumio volumio[1015]: SPOTIFY: received: {"type":"active","data":null} Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E4wYWiDJuwz1b" Mar 04 15:12:42 volumio go-librespot[1326]: time="2026-03-04T15:12:42Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E4wYWiDJuwz1b" Mar 04 15:12:42 volumio volumio[1015]: info: Aligning Spotify Volume to Volumio Volume Mar 04 15:12:42 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Mar 04 15:12:42 volumio volumio[1015]: info: CorePlayQueue::getTrack 2557 Mar 04 15:12:42 volumio volumio[1015]: info: Setting Spotify Volume from Volumio: 100 Mar 04 15:12:43 volumio go-librespot[1326]: time="2026-03-04T15:12:43Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 04 15:12:43 volumio go-librespot[1326]: time="2026-03-04T15:12:43Z" level=trace msg="emitting websocket event: paused" Mar 04 15:12:43 volumio go-librespot[1326]: time="2026-03-04T15:12:43Z" level=debug msg="handling update_context player command from 546a1cf3e4b7b842a276ccef7f96ea3db9f8f655" Mar 04 15:12:43 volumio volumio[1015]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1E4wYWiDJuwz1b","uri":"spotify:track:0lvhEsN1zkMzfp2M1o17yy","play_origin":"com.spotify.service.carplayv2"}} Mar 04 15:12:43 volumio volumio[1015]: info: Spotify is playing in volatile mode Mar 04 15:12:43 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 15:12:43 volumio volumio[1015]: SPOTIFY: UNSET VOLATILE Mar 04 15:12:43 volumio volumio[1015]: SPOTIFY: {"status":"play","position":2557,"title":"Pearl Jam - Elderly Woman Behind the Counter In a Small Town","artist":"90s90s Grunge","album":"","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music124/v4/a9/ee/ae/a9eeaef2-a46e-dd11-f36c-e52d0ed717da/source/600x600bb.jpg","uri":"http://streams.90s90s.de/grunge/mp3-192/volumio","trackType":"80s80s Radio","seek":0,"duration":"187","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":true,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"80s80s Radio","volatile":false,"service":"80s80s"} Mar 04 15:12:43 volumio volumio[1015]: info: Setting Spotify stop after unset volatile call Mar 04 15:12:43 volumio volumio[1015]: SPOTIFY: PUSH STATE SPOTIFY Mar 04 15:12:43 volumio volumio[1015]: SPOTIFY: {"status":"pause","service":"spop","title":"Holiday","artist":"Green Day","album":"American Idiot (20th Anniversary Deluxe Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e0291809a44f16f6434927c1e3f","uri":"spotify:track:0lvhEsN1zkMzfp2M1o17yy","trackType":"spotify","seek":125428,"duration":232,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 04 15:12:43 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Mar 04 15:12:43 volumio volumio[1015]: info: CoreStateMachine::pushState Mar 04 15:12:43 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 15:12:43 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Mar 04 15:12:43 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 15:12:43 volumio go-librespot[1326]: time="2026-03-04T15:12:43Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 04 15:12:43 volumio go-librespot[1326]: time="2026-03-04T15:12:43Z" level=debug msg="sending successful reply for dealer request" Mar 04 15:12:43 volumio volumio[1015]: info: Spotify Stop Mar 04 15:12:43 volumio volumio[1015]: SPOTIFY: SPOTIFY STOP Mar 04 15:12:43 volumio volumio[1015]: SPOTIFY: {"status":"pause","title":"Holiday","artist":"Green Day","album":"American Idiot (20th Anniversary Deluxe Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e0291809a44f16f6434927c1e3f","uri":"spotify:track:0lvhEsN1zkMzfp2M1o17yy","trackType":"spotify","codec":"ogg","seek":125428,"duration":232,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Mar 04 15:12:44 volumio volumio[1015]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Mar 04 15:12:44 volumio volumio[1015]: info: Sending Spotify command with payload to local API: /player/volume Mar 04 15:12:44 volumio go-librespot[1326]: time="2026-03-04T15:12:44Z" level=debug msg="update volume requested to 65535/65535" Mar 04 15:12:45 volumio go-librespot[1326]: time="2026-03-04T15:12:45Z" level=debug msg="put connect state because VOLUME_CHANGED" Mar 04 15:12:45 volumio go-librespot[1326]: time="2026-03-04T15:12:45Z" level=trace msg="emitting websocket event: volume" Mar 04 15:12:45 volumio volumio[1015]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Mar 04 15:12:45 volumio volumio[1015]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Mar 04 15:12:46 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:12:46 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:12:47 volumio go-librespot[1326]: time="2026-03-04T15:12:47Z" level=trace msg="sent dealer ping" Mar 04 15:12:47 volumio go-librespot[1326]: time="2026-03-04T15:12:47Z" level=trace msg="received dealer pong" Mar 04 15:12:51 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:12:51 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:12:56 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:12:56 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:13:01 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:13:01 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:13:06 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:13:06 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:13:12 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:13:12 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:13:17 volumio go-librespot[1326]: time="2026-03-04T15:13:17Z" level=trace msg="sent dealer ping" Mar 04 15:13:17 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:13:17 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:13:17 volumio go-librespot[1326]: time="2026-03-04T15:13:17Z" level=trace msg="received dealer pong" Mar 04 15:13:22 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:13:22 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:13:27 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:13:27 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:13:32 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:13:32 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:13:37 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:13:37 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:13:42 volumio volumio[1015]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 15:13:42 volumio volumio[1015]: info: Discovery: Restarting Browsing Mar 04 15:13:44 volumio volumio[1015]: info: [1772637224773] [80s80s] Pushing the next song state Pearl Jam - Elderly Woman Behind the Counter In a Small Town and getting next track. Mar 04 15:13:44 volumio volumio[1015]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 15:13:44 volumio volumio[1015]: TypeError: Cannot set property 'name' of undefined Mar 04 15:13:44 volumio volumio[1015]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Mar 04 15:13:44 volumio volumio[1015]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Mar 04 15:13:44 volumio volumio[1015]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Mar 04 15:13:44 volumio volumio[1015]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Mar 04 15:13:44 volumio volumio[1015]: at processImmediate (internal/timers.js:461:21) Mar 04 15:13:44 volumio volumio[1015]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 15:13:45 volumio sudo[5414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-04 15:12 Mar 04 15:13:45 volumio sudo[5414]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"