-- Logs begin at Sat 2024-04-13 08:12:11 CEST, end at Sun 2024-04-14 21:44:42 CEST. -- Apr 14 21:43:03 woutervolumio wpa_supplicant[761]: wlan0: Failed to initiate sched scan Apr 14 21:43:10 woutervolumio wpa_supplicant[761]: wlan0: Failed to initiate sched scan Apr 14 21:43:18 woutervolumio wpa_supplicant[761]: wlan0: Failed to initiate sched scan Apr 14 21:43:25 woutervolumio wpa_supplicant[761]: wlan0: Failed to initiate sched scan Apr 14 21:43:32 woutervolumio wpa_supplicant[761]: wlan0: Failed to initiate sched scan Apr 14 21:43:40 woutervolumio wpa_supplicant[761]: wlan0: Failed to initiate sched scan Apr 14 21:43:47 woutervolumio wpa_supplicant[761]: wlan0: Failed to initiate sched scan Apr 14 21:43:48 woutervolumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 21:43:48 woutervolumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 21:43:48 woutervolumio volumio[918]: info: Discovery: Getting this device information Apr 14 21:43:48 woutervolumio volumio[918]: info: CoreCommandRouter::volumioGetState Apr 14 21:43:48 woutervolumio volumio[918]: info: CorePlayQueue::getTrack 0 Apr 14 21:43:48 woutervolumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 21:43:48 woutervolumio volumio[918]: verbose: New Socket.io Connection to 192.168.2.70 from 192.168.2.69 UA: okhttp/4.9.2 Total Clients: 10 Apr 14 21:43:48 woutervolumio volumio[918]: info: CoreCommandRouter::volumioGetState Apr 14 21:43:48 woutervolumio volumio[918]: info: CorePlayQueue::getTrack 0 Apr 14 21:43:48 woutervolumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 21:43:48 woutervolumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 21:43:55 woutervolumio wpa_supplicant[761]: wlan0: Failed to initiate sched scan Apr 14 21:44:02 woutervolumio wpa_supplicant[761]: wlan0: Failed to initiate sched scan Apr 14 21:44:09 woutervolumio wpa_supplicant[761]: wlan0: Failed to initiate sched scan Apr 14 21:44:17 woutervolumio wpa_supplicant[761]: wlan0: Failed to initiate sched scan Apr 14 21:44:24 woutervolumio wpa_supplicant[761]: wlan0: Failed to initiate sched scan Apr 14 21:44:31 woutervolumio wpa_supplicant[761]: wlan0: Failed to initiate sched scan Apr 14 21:44:39 woutervolumio wpa_supplicant[761]: wlan0: Failed to initiate sched scan Apr 14 21:44:40 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:40+02:00" level=debug msg="handling transfer player command from c4086cfabb333b14328ae397265caa9fb42a387d" Apr 14 21:44:40 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:40+02:00" level=warning msg="returning empty context page (hm://artistplaycontext/v1/page/spotify/album/7lQjTf8RRjpHVbG2KGwOOE/km_artist) for spotify:artist:0pK9ubH7AYU4noAktZv1VI" Apr 14 21:44:40 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:40+02:00" level=debug msg="loading track spotify:track:3UEyliT6eaqFxgR4UUjH9e (paused: true, position: 7738ms)" Apr 14 21:44:40 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:40+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 21:44:40 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:40+02:00" level=trace msg="emitting websocket event: will_play" Apr 14 21:44:41 woutervolumio volumio[918]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3UEyliT6eaqFxgR4UUjH9e","play_origin":"media-session"}} Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:3UEyliT6eaqFxgR4UUjH9e" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="requested aes key for file 54d64010d120d0aac5d1e2935282fb60aada4387, track: spotify:track:3UEyliT6eaqFxgR4UUjH9e" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2813" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="fetched first chunk of 15, total size is 7698232 bytes" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=trace msg="seek to 7738ms (diff: 66ms, samples: 341245, bytes: 284860)" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=info msg="loaded track \"100 Words - single version\" (uri: spotify:track:3UEyliT6eaqFxgR4UUjH9e, paused: true, position: 7738ms, duration: 186518ms)" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=trace msg="emitting websocket event: metadata" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=trace msg="emitting websocket event: active" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="sending successful reply for delaer request" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 14 21:44:41 woutervolumio volumio[918]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3UEyliT6eaqFxgR4UUjH9e","name":"100 Words - single version","artist_names":["Solution"],"album_name":"100 Words","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0295e559196fae7c2b1ceeb89b","position":7738,"duration":186518}} Apr 14 21:44:41 woutervolumio volumio[918]: SPOTIFY: received: {"type":"active","data":null} Apr 14 21:44:41 woutervolumio volumio[918]: info: Aligning Spotify Volume to Volumio Volume Apr 14 21:44:41 woutervolumio volumio[918]: info: CoreCommandRouter::volumioGetState Apr 14 21:44:41 woutervolumio volumio[918]: info: CorePlayQueue::getTrack 0 Apr 14 21:44:41 woutervolumio volumio[918]: info: Setting Spotify Volume from Volumio: 70 Apr 14 21:44:41 woutervolumio volumio[918]: SPOTIFY: SETTING SPOTIFY VOLUME 70 Apr 14 21:44:41 woutervolumio volumio[918]: info: Sending Spotify command with payload to local API: /player/volume Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="fetched chunk 1/14, size: 524288" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=trace msg="emitting websocket event: paused" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="update volume to 45874/65535" Apr 14 21:44:41 woutervolumio volumio[918]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:3UEyliT6eaqFxgR4UUjH9e","play_origin":"media-session"}} Apr 14 21:44:41 woutervolumio volumio[918]: info: Spotify is playing in volatile mode Apr 14 21:44:41 woutervolumio volumio[918]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 21:44:41 woutervolumio volumio[918]: SPOTIFY: UNSET VOLATILE Apr 14 21:44:41 woutervolumio volumio[918]: SPOTIFY: {"status":"play","position":0,"title":"Katrina & The Waves - Walking On Sunshine","artist":"80s80s Real 80s Radio","album":"","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music115/v4/29/b2/91/29b291af-ba1d-9fd4-eeda-7a9674bc17bc/source/600x600bb.jpg","uri":"http://streams.80s80s.de/web/mp3-192/volumio","trackType":"80s80s Radio","seek":0,"duration":"215","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":70,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"80s80s Radio","volatile":false,"service":"80s80s"} Apr 14 21:44:41 woutervolumio volumio[918]: info: Setting Spotify stop after unset volatile call Apr 14 21:44:41 woutervolumio volumio[918]: SPOTIFY: PUSH STATE SPOTIFY Apr 14 21:44:41 woutervolumio volumio[918]: SPOTIFY: {"status":"pause","service":"spop","title":"100 Words - single version","artist":"Solution","album":"100 Words","albumart":"https://i.scdn.co/image/ab67616d00001e0295e559196fae7c2b1ceeb89b","uri":"spotify:track:3UEyliT6eaqFxgR4UUjH9e","trackType":"spotify","seek":7738,"duration":186,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"stream":false,"repeatMode":"all"} Apr 14 21:44:41 woutervolumio volumio[918]: info: CoreCommandRouter::servicePushState Apr 14 21:44:41 woutervolumio volumio[918]: info: CoreStateMachine::pushState Apr 14 21:44:41 woutervolumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 21:44:41 woutervolumio volumio[918]: info: CoreCommandRouter::volumioPushState Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1959" Apr 14 21:44:41 woutervolumio volumio[918]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="fetched chunk 2/14, size: 524288" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=trace msg="emitting websocket event: volume" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2817" Apr 14 21:44:41 woutervolumio volumio[918]: SPOTIFY: received: {"type":"volume","data":{"value":69,"max":100}} Apr 14 21:44:41 woutervolumio volumio[918]: SPOTIFY: RECEIVED SPOTIFY VOLUME 70 Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="fetched chunk 3/14, size: 524288" Apr 14 21:44:41 woutervolumio volumio[918]: info: peppy_screensaver: Start PeppyMeter Apr 14 21:44:41 woutervolumio volumio[918]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2810" Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 14 21:44:41 woutervolumio go-librespot[1189]: time="2024-04-14T21:44:41+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 14 21:44:41 woutervolumio volumio[918]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 14 21:44:41 woutervolumio volumio[918]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 14 21:44:41 woutervolumio volumio[918]: info: Spotify Stop Apr 14 21:44:41 woutervolumio volumio[918]: SPOTIFY: SPOTIFY STOP Apr 14 21:44:41 woutervolumio volumio[918]: SPOTIFY: {"status":"pause","title":"100 Words - single version","artist":"Solution","album":"100 Words","albumart":"https://i.scdn.co/image/ab67616d00001e0295e559196fae7c2b1ceeb89b","uri":"spotify:track:3UEyliT6eaqFxgR4UUjH9e","trackType":"spotify","codec":"ogg","seek":7738,"duration":186,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"consume":false,"volume":70,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Apr 14 21:44:42 woutervolumio volumio[918]: info: [1713123882181] [80s80s] Pushing the next song state Billy Idol - Sweet Sixteen and getting next track. Apr 14 21:44:42 woutervolumio volumio[918]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 21:44:42 woutervolumio volumio[918]: TypeError: Cannot set property 'name' of undefined Apr 14 21:44:42 woutervolumio volumio[918]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Apr 14 21:44:42 woutervolumio volumio[918]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Apr 14 21:44:42 woutervolumio volumio[918]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Apr 14 21:44:42 woutervolumio volumio[918]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Apr 14 21:44:42 woutervolumio volumio[918]: at processImmediate (internal/timers.js:461:21) Apr 14 21:44:42 woutervolumio volumio[918]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 21:44:42 woutervolumio sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-14 21:43 Apr 14 21:44:42 woutervolumio sudo[1261]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"