-- Logs begin at Sat 2024-07-20 20:22:39 BST, end at Tue 2024-07-23 11:34:46 BST. -- Jul 23 11:34:20 volumio go-librespot[25455]: time="2024-07-23T11:34:20+01:00" level=debug msg="obtained new client token: AACaxHTXhbNqnaM94zewgHsmteRFb0AgmtAv0cKUA/QMLCSVXTfrPMuKcMf94UyH5+twe2g8c9Zl+E6RMGNbnhvhVkpggMXmqJd9gY2PMza6EEEWgBvW11e/Wo2b03bLtLw8Dqu1MB1UBcCZCLuwdyu8MDQxIM5Dohvnf8lQaRdFGzCQBFwlLKQEyv8xlg+FuvkgH8wd5aL+03k1v8wFi4Ufn1xhctjrc/FrlHOtuktcHUQ+x9u+N/jlq7Q85O89" Jul 23 11:34:20 volumio go-librespot[25455]: time="2024-07-23T11:34:20+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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 23 11:34:20 volumio go-librespot[25455]: time="2024-07-23T11:34:20+01:00" level=debug msg="completed keyexchange" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="completed challenge" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="authenticated as 1149492164" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="authenticated as 1149492164" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+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]" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="dealer connection opened" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=info msg="accepted zeroconf user 1149492164 from iPhone" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 517" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="received connection id: Nzc4YzQwM2YtMGRkYS00YTVlLWI3ZmQtNGMyN2IyMjVkNDMwK2RlYWxlcit0Y3A6Ly9nZXcxLWRlYWxlci1hLXdmcXYuZ2V3MS5zcG90aWZ5Lm5ldDo1NzAwKzQzMDE1MERGRjcyRjI4Q0ExODFCMzNBNzNEOUYxQTdFMTAyRTVFMzc5ODYzMkYyOEY3NDNFNUYwQUE3RUU1RUI=" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="put connect state because NEW_DEVICE" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="handling transfer player command from 8581d52884e3764d262d95d9e7a07e7f772d9804" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=trace msg="fetched new page 0 with 303 items (list: 303)" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="shuffled context with seed 6745288142822273424 (len: 303, keep: 254)" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="loading track spotify:track:2uhEKg8kIzpdvz4gyy6x8W (paused: false, position: 2912ms)" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=trace msg="emitting websocket event: will_play" Jul 23 11:34:21 volumio volumio[959]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2uhEKg8kIzpdvz4gyy6x8W","play_origin":"playlist/ondemand"}} Jul 23 11:34:21 volumio go-librespot[25455]: time="2024-07-23T11:34:21+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:2uhEKg8kIzpdvz4gyy6x8W" Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=debug msg="requested aes key for file 4496e9e9d65eecde30c048cd149246f6e8afc0b1, gid: 2uhEKg8kIzpdvz4gyy6x8W" Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1946" Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=debug msg="fetched first chunk of 19, total size is 9495432 bytes" Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=trace msg="seek to 2912ms (diff: 126ms, samples: 128419, bytes: 97642)" Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=info msg="loaded track \"Only Love\" (uri: spotify:track:2uhEKg8kIzpdvz4gyy6x8W, paused: false, position: 2912ms, duration: 249173ms)" Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=debug msg="fetched chunk 1/18, size: 524288" Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=trace msg="emitting websocket event: metadata" Jul 23 11:34:22 volumio volumio[959]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2uhEKg8kIzpdvz4gyy6x8W","name":"Only Love","artist_names":["Ben Howard"],"album_name":"Every Kingdom","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0225d10f813fd1114b2bb9e39a","position":2912,"duration":249173,"release_date":"year:2011 month:1 day:1","track_number":5,"disc_number":1}} Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=trace msg="emitting websocket event: active" Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=debug msg="sending successful reply for delaer request" Jul 23 11:34:22 volumio volumio[959]: SPOTIFY: received: {"type":"active","data":null} Jul 23 11:34:22 volumio volumio[959]: info: Aligning Spotify Volume to Volumio Volume Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 23 11:34:22 volumio volumio[959]: info: CoreCommandRouter::volumioGetState Jul 23 11:34:22 volumio volumio[959]: info: CorePlayQueue::getTrack 0 Jul 23 11:34:22 volumio volumio[959]: info: Setting Spotify Volume from Volumio: 75 Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=debug msg="fetched chunk 3/18, size: 524288" Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=trace msg="emitting websocket event: playing" Jul 23 11:34:22 volumio volumio[959]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2uhEKg8kIzpdvz4gyy6x8W","play_origin":"playlist/ondemand"}} Jul 23 11:34:22 volumio volumio[959]: info: Spotify is playing in volatile mode Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1556" Jul 23 11:34:22 volumio volumio[959]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 11:34:22 volumio volumio[959]: SPOTIFY: UNSET VOLATILE Jul 23 11:34:22 volumio volumio[959]: SPOTIFY: {"status":"play","position":0,"title":"Culture Beat - Inside Out","artist":"90s90s Hits","album":"","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music/v4/5b/87/6f/5b876fd4-a561-0c9b-c349-ab5cf149b089/source/600x600bb.jpg","uri":"http://streams.90s90s.de/pop/mp3-192/volumio","trackType":"80s80s Radio","seek":0,"duration":"231","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":75,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"80s80s Radio","volatile":false,"service":"80s80s"} Jul 23 11:34:22 volumio volumio[959]: info: Setting Spotify stop after unset volatile call Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=debug msg="update volume to 20315/65535" Jul 23 11:34:22 volumio volumio[959]: SPOTIFY: PUSH STATE SPOTIFY Jul 23 11:34:22 volumio volumio[959]: SPOTIFY: {"status":"play","service":"spop","title":"Only Love","artist":"Ben Howard","album":"Every Kingdom","albumart":"https://i.scdn.co/image/ab67616d00001e0225d10f813fd1114b2bb9e39a","uri":"spotify:track:2uhEKg8kIzpdvz4gyy6x8W","trackType":"spotify","seek":2912,"duration":249,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 23 11:34:22 volumio volumio[959]: info: CoreCommandRouter::servicePushState Jul 23 11:34:22 volumio volumio[959]: info: CoreStateMachine::pushState Jul 23 11:34:22 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 11:34:22 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 23 11:34:22 volumio volumio[959]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=debug msg="fetched chunk 2/18, size: 524288" Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 23 11:34:22 volumio go-librespot[25455]: time="2024-07-23T11:34:22+01:00" level=trace msg="emitting websocket event: volume" Jul 23 11:34:22 volumio volumio[959]: SPOTIFY: received: {"type":"volume","data":{"value":31,"max":100}} Jul 23 11:34:22 volumio volumio[959]: SPOTIFY: RECEIVED SPOTIFY VOLUME 31 Jul 23 11:34:22 volumio volumio[959]: info: Setting Volumio Volume from Spotify: 31 Jul 23 11:34:22 volumio volumio[959]: info: VolumeController::SetAlsaVolume31 Jul 23 11:34:22 volumio volumio[959]: info: CoreStateMachine::pushState Jul 23 11:34:22 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 11:34:22 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 23 11:34:22 volumio volumio[959]: SPOTIFY: RECEIVED VOLUMIO VOLUME 31 Jul 23 11:34:22 volumio volumio[959]: SPOTIFY: PUSH STATE SPOTIFY Jul 23 11:34:22 volumio volumio[959]: SPOTIFY: {"status":"play","service":"spop","title":"Only Love","artist":"Ben Howard","album":"Every Kingdom","albumart":"https://i.scdn.co/image/ab67616d00001e0225d10f813fd1114b2bb9e39a","uri":"spotify:track:2uhEKg8kIzpdvz4gyy6x8W","trackType":"spotify","seek":2912,"duration":249,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jul 23 11:34:22 volumio volumio[959]: info: CoreCommandRouter::servicePushState Jul 23 11:34:22 volumio volumio[959]: info: CoreStateMachine::pushState Jul 23 11:34:22 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 23 11:34:22 volumio volumio[959]: SPOTIFY: RECEIVED VOLUMIO VOLUME 31 Jul 23 11:34:22 volumio volumio[959]: info: Spotify Stop Jul 23 11:34:22 volumio volumio[959]: SPOTIFY: SPOTIFY STOP Jul 23 11:34:22 volumio volumio[959]: SPOTIFY: {"status":"play","title":"Only Love","artist":"Ben Howard","album":"Every Kingdom","albumart":"https://i.scdn.co/image/ab67616d00001e0225d10f813fd1114b2bb9e39a","uri":"spotify:track:2uhEKg8kIzpdvz4gyy6x8W","trackType":"spotify","codec":"ogg","seek":2912,"duration":249,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":31,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Jul 23 11:34:23 volumio volumio[959]: SPOTIFY: SETTING SPOTIFY VOLUME 75 Jul 23 11:34:23 volumio volumio[959]: info: Sending Spotify command with payload to local API: /player/volume Jul 23 11:34:23 volumio go-librespot[25455]: time="2024-07-23T11:34:23+01:00" level=debug msg="update volume to 49151/65535" Jul 23 11:34:23 volumio go-librespot[25455]: time="2024-07-23T11:34:23+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 23 11:34:23 volumio go-librespot[25455]: time="2024-07-23T11:34:23+01:00" level=trace msg="emitting websocket event: volume" Jul 23 11:34:23 volumio volumio[959]: SPOTIFY: received: {"type":"volume","data":{"value":75,"max":100}} Jul 23 11:34:23 volumio volumio[959]: SPOTIFY: RECEIVED SPOTIFY VOLUME 75 Jul 23 11:34:23 volumio volumio[959]: info: Setting Volumio Volume from Spotify: 75 Jul 23 11:34:23 volumio volumio[959]: info: VolumeController::SetAlsaVolume75 Jul 23 11:34:23 volumio volumio[959]: info: CoreStateMachine::pushState Jul 23 11:34:23 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 11:34:23 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 23 11:34:23 volumio volumio[959]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Jul 23 11:34:24 volumio go-librespot[25455]: time="2024-07-23T11:34:24+01:00" level=debug msg="update volume to 53083/65535" Jul 23 11:34:24 volumio go-librespot[25455]: time="2024-07-23T11:34:24+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 23 11:34:24 volumio go-librespot[25455]: time="2024-07-23T11:34:24+01:00" level=trace msg="emitting websocket event: volume" Jul 23 11:34:24 volumio volumio[959]: SPOTIFY: received: {"type":"volume","data":{"value":81,"max":100}} Jul 23 11:34:24 volumio volumio[959]: SPOTIFY: RECEIVED SPOTIFY VOLUME 81 Jul 23 11:34:24 volumio volumio[959]: info: Setting Volumio Volume from Spotify: 81 Jul 23 11:34:24 volumio volumio[959]: info: VolumeController::SetAlsaVolume81 Jul 23 11:34:24 volumio volumio[959]: info: CoreStateMachine::pushState Jul 23 11:34:24 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 11:34:24 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 23 11:34:24 volumio volumio[959]: SPOTIFY: RECEIVED VOLUMIO VOLUME 81 Jul 23 11:34:24 volumio go-librespot[25455]: time="2024-07-23T11:34:24+01:00" level=debug msg="update volume to 57015/65535" Jul 23 11:34:24 volumio go-librespot[25455]: time="2024-07-23T11:34:24+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 23 11:34:24 volumio go-librespot[25455]: time="2024-07-23T11:34:24+01:00" level=trace msg="emitting websocket event: volume" Jul 23 11:34:24 volumio volumio[959]: SPOTIFY: received: {"type":"volume","data":{"value":87,"max":100}} Jul 23 11:34:24 volumio volumio[959]: SPOTIFY: RECEIVED SPOTIFY VOLUME 87 Jul 23 11:34:24 volumio volumio[959]: info: Setting Volumio Volume from Spotify: 87 Jul 23 11:34:24 volumio volumio[959]: info: VolumeController::SetAlsaVolume87 Jul 23 11:34:24 volumio volumio[959]: info: CoreStateMachine::pushState Jul 23 11:34:24 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 11:34:24 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 23 11:34:24 volumio volumio[959]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Jul 23 11:34:26 volumio go-librespot[25455]: time="2024-07-23T11:34:26+01:00" level=debug msg="update volume to 61602/65535" Jul 23 11:34:26 volumio go-librespot[25455]: time="2024-07-23T11:34:26+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 23 11:34:26 volumio go-librespot[25455]: time="2024-07-23T11:34:26+01:00" level=trace msg="emitting websocket event: volume" Jul 23 11:34:26 volumio volumio[959]: SPOTIFY: received: {"type":"volume","data":{"value":94,"max":100}} Jul 23 11:34:26 volumio volumio[959]: SPOTIFY: RECEIVED SPOTIFY VOLUME 94 Jul 23 11:34:26 volumio volumio[959]: info: Setting Volumio Volume from Spotify: 94 Jul 23 11:34:26 volumio volumio[959]: info: VolumeController::SetAlsaVolume94 Jul 23 11:34:26 volumio volumio[959]: info: CoreStateMachine::pushState Jul 23 11:34:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 11:34:26 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 23 11:34:26 volumio volumio[959]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Jul 23 11:34:27 volumio go-librespot[25455]: time="2024-07-23T11:34:27+01:00" level=debug msg="update volume to 65535/65535" Jul 23 11:34:27 volumio go-librespot[25455]: time="2024-07-23T11:34:27+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 23 11:34:27 volumio go-librespot[25455]: time="2024-07-23T11:34:27+01:00" level=trace msg="emitting websocket event: volume" Jul 23 11:34:27 volumio volumio[959]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Jul 23 11:34:27 volumio volumio[959]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Jul 23 11:34:27 volumio volumio[959]: info: Setting Volumio Volume from Spotify: 100 Jul 23 11:34:27 volumio volumio[959]: info: VolumeController::SetAlsaVolume100 Jul 23 11:34:27 volumio volumio[959]: info: CoreStateMachine::pushState Jul 23 11:34:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 11:34:27 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 23 11:34:27 volumio volumio[959]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 23 11:34:32 volumio go-librespot[25455]: time="2024-07-23T11:34:32+01:00" level=debug msg="fetched chunk 4/18, size: 524288" Jul 23 11:34:45 volumio go-librespot[25455]: time="2024-07-23T11:34:45+01:00" level=debug msg="fetched chunk 5/18, size: 524288" Jul 23 11:34:46 volumio volumio[959]: info: [1721730886234] [80s80s] Pushing the next song state Culture Beat - Inside Out and getting next track. Jul 23 11:34:46 volumio volumio[959]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 23 11:34:46 volumio volumio[959]: TypeError: Cannot set property 'name' of undefined Jul 23 11:34:46 volumio volumio[959]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Jul 23 11:34:46 volumio volumio[959]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Jul 23 11:34:46 volumio volumio[959]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Jul 23 11:34:46 volumio volumio[959]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Jul 23 11:34:46 volumio volumio[959]: at processImmediate (internal/timers.js:461:21) Jul 23 11:34:46 volumio volumio[959]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 23 11:34:46 volumio sudo[7416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-23 11:33 Jul 23 11:34:46 volumio sudo[7416]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"