-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Wed 2025-04-23 09:21:18 CEST. -- Apr 23 09:21:07 volumio3-2 ntpd[788]: receive: Unexpected origin timestamp 0xeab8b4f1.b88bf6a9 does not match aorg 0000000000.00000000 from server@194.58.202.20 xmt 0xebb31363.328391c4 Apr 23 09:21:07 volumio3-2 ntpd[788]: receive: Unexpected origin timestamp 0xeab8b4f1.b88a5d02 does not match aorg 0000000000.00000000 from server@85.24.237.71 xmt 0xebb31363.328a7390 Apr 23 09:21:07 volumio3-2 ntpd[788]: receive: Unexpected origin timestamp 0xeab8b4f1.b88d620c does not match aorg 0000000000.00000000 from server@85.24.237.72 xmt 0xebb31363.328a2afc Apr 23 09:21:07 volumio3-2 ntpd[788]: receive: Unexpected origin timestamp 0xeab8b4f1.b87d6d00 does not match aorg 0000000000.00000000 from server@98.128.230.186 xmt 0xebb31363.325f108c Apr 23 09:21:07 volumio3-2 ntpd[788]: receive: Unexpected origin timestamp 0xeab8b4f1.b888c31e does not match aorg 0000000000.00000000 from server@194.58.203.148 xmt 0xebb31363.335dee66 Apr 23 09:21:07 volumio3-2 ntpd[788]: receive: Unexpected origin timestamp 0xeab8b4f1.b886730f does not match aorg 0000000000.00000000 from server@98.128.175.45 xmt 0xebb31363.34c22d2f Apr 23 09:21:07 volumio3-2 systemd[1]: Starting Daily apt download activities... Apr 23 09:21:07 volumio3-2 volumio[1089]: info: Initializing connection to go-librespot Websocket Apr 23 09:21:07 volumio3-2 volumio[1089]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 23 09:21:07 volumio3-2 volumio[1089]: info: Discovery: A device disappeared from network Apr 23 09:21:07 volumio3-2 volumio[1089]: info: Discovery: Device volumio3-2 disappeared from network Apr 23 09:21:07 volumio3-2 volumio[1089]: info: Discovery: adding 770a0810-dc64-4a98-a6ff-ba297a9f91d3 Apr 23 09:21:07 volumio3-2 volumio[1089]: info: Discovery: Found device Volumio3-2 Apr 23 09:21:07 volumio3-2 volumio[1089]: info: CoreCommandRouter::volumioGetState Apr 23 09:21:07 volumio3-2 volumio[1089]: info: CorePlayQueue::getTrack 0 Apr 23 09:21:10 volumio3-2 volumio[1089]: info: Initializing connection to go-librespot Websocket Apr 23 09:21:10 volumio3-2 volumio[1089]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 23 09:21:10 volumio3-2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 23 09:21:10 volumio3-2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Apr 23 09:21:10 volumio3-2 systemd[1]: Stopped go-librespot Daemon. Apr 23 09:21:10 volumio3-2 systemd[1]: Started go-librespot Daemon. Apr 23 09:21:10 volumio3-2 go-librespot[1506]: Librespot-go daemon starting... Apr 23 09:21:10 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:10+02:00" level=info msg="generated new device id: 0bcd8ca0d6792763c85eaa8c064a2bc2822e22dc" Apr 23 09:21:10 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:10+02:00" level=debug msg="stored credentials found for bencarlp" Apr 23 09:21:10 volumio3-2 systemd[1]: apt-daily.service: Succeeded. Apr 23 09:21:10 volumio3-2 systemd[1]: Started Daily apt download activities. Apr 23 09:21:10 volumio3-2 systemd[1]: Starting Daily apt upgrade and clean activities... Apr 23 09:21:10 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:10+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 23 09:21:10 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:10+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 23 09:21:10 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:10+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 23 09:21:10 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:10+02:00" level=debug msg="zeroconf server listening on port 35835" Apr 23 09:21:10 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:10+02:00" level=debug msg="obtained new client token: AADZng7EolMcVnH9wJ5tFcuuA0fpsLVDYwNibJUkvfqheR9apP42P4GFpqTbYJ044LtkK0Efo6dSw/5Vq+rKbIoB++Oi5Cn5ZKqIrnXjk4is8grPGUz2oppyHf9HoPnyoPV9SgXa6yQ+io0psfaiHindwajqkxjdUvNTxClyjwnHfwXBMo2QcyQNEdYFtrlgsNTL1gDmTlOOS4P5hz7054gwXngyP1oLo6SHDd2zliuoUaUDNQCmtwRq5SXFKCI=" Apr 23 09:21:11 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:11+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Apr 23 09:21:11 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:11+02:00" level=debug msg="completed keyexchange" Apr 23 09:21:11 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:11+02:00" level=debug msg="completed challenge" Apr 23 09:21:11 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:11+02:00" level=debug msg="authenticated as bencarlp" Apr 23 09:21:11 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:11+02:00" level=debug msg="authenticated as bencarlp" Apr 23 09:21:12 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:12+02:00" level=debug msg="dealer connection opened" Apr 23 09:21:12 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:12+02:00" level=debug msg="initializing zeroconf session, username: bencarlp" Apr 23 09:21:12 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:12+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 23 09:21:12 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:12+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 23 09:21:12 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:12+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 23 09:21:12 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:12+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 23 09:21:12 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:12+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 23 09:21:12 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:12+02:00" level=debug msg="autoplay enabled: false" Apr 23 09:21:12 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:12+02:00" level=debug msg="received connection id: ZjY1Nzk4NTUtYTU4ZC00YjhkLTllODYtZWI4OGFlYzc5NzMzK2RlYWxlcit0Y3A6Ly8wYWNhNTg0OS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNjM4NEI2MkRGNzdFNjQ3QzNDNjAzMDVBODY1MzY5NEFCQTE0Q0E4NDZFMzZENjZBQThCRDg4QjBBQjZEMTg0Ng==" Apr 23 09:21:12 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:12+02:00" level=debug msg="put connect state because NEW_DEVICE" Apr 23 09:21:12 volumio3-2 systemd[1]: apt-daily-upgrade.service: Succeeded. Apr 23 09:21:12 volumio3-2 systemd[1]: Started Daily apt upgrade and clean activities. Apr 23 09:21:13 volumio3-2 volumio[1089]: info: Initializing connection to go-librespot Websocket Apr 23 09:21:13 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:13+02:00" level=debug msg="new websocket client" Apr 23 09:21:13 volumio3-2 volumio[1089]: info: Connection to go-librespot Websocket established Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 23 09:21:14 volumio3-2 volumio[1089]: info: Adding plugin bluetooth to MyMusic Plugins Apr 23 09:21:14 volumio3-2 volumio[1089]: info: Adding plugin multiroom to MyMusic Plugins Apr 23 09:21:14 volumio3-2 volumio[1089]: info: Adding plugin metavolumio to MyMusic Plugins Apr 23 09:21:14 volumio3-2 volumio[1089]: info: Adding plugin cd_controller to MyMusic Plugins Apr 23 09:21:14 volumio3-2 volumio[1089]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 23 09:21:14 volumio3-2 volumio[1089]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 23 09:21:14 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="handling transfer player command from c0b799b2633a7067c8b98969b42d5ebc51c6392c" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="resolved context of track" uri="spotify:playlist:7FQkeDgyvFXIF1COWQFkbw" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=trace msg="fetched new page 0 with 13 items (list: 13)" uri="spotify:playlist:7FQkeDgyvFXIF1COWQFkbw" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="loading track (paused: false, position: 8198ms)" uri="spotify:track:1MYTg8tznXrUL9DeYqdJrw" Apr 23 09:21:16 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 23 09:21:16 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 23 09:21:16 volumio3-2 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 09:21:16 volumio3-2 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 09:21:16 volumio3-2 volumio[1089]: info: Starting MyVolumio Remote Streaming Endpoints Apr 23 09:21:16 volumio3-2 volumio[1089]: info: MyVolumio login type: Token Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=trace msg="emitting websocket event: will_play" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Apr 23 09:21:16 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 23 09:21:16 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="selected format OGG_VORBIS_320 (ea9d359d8e0c95c3da1e601fbf83d63cbea63f60)" uri="spotify:track:1MYTg8tznXrUL9DeYqdJrw" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="requested aes key for file ea9d359d8e0c95c3da1e601fbf83d63cbea63f60, gid: 1MYTg8tznXrUL9DeYqdJrw" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1335" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1113" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="fetched first chunk of 17, total size is 8890270 bytes" uri="spotify:track:1MYTg8tznXrUL9DeYqdJrw" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=trace msg="seek to 8198ms (diff: 83ms, samples: 361531, bytes: 299832)" uri="spotify:track:1MYTg8tznXrUL9DeYqdJrw" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5102 us, period size = 225 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 98 frames" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="created new output device" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=info msg="loaded track \"Show Me\" (paused: false, position: 8198ms, duration: 239400ms, prefetched: false)" uri="spotify:track:1MYTg8tznXrUL9DeYqdJrw" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="fetched chunk 1/16, size: 524288" uri="spotify:track:1MYTg8tznXrUL9DeYqdJrw" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=trace msg="scheduling prefetch in 201s" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=trace msg="emitting websocket event: metadata" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=trace msg="emitting websocket event: active" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="sending successful reply for dealer request" Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 23 09:21:16 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:16+02:00" level=debug msg="fetched chunk 3/16, size: 524288" uri="spotify:track:1MYTg8tznXrUL9DeYqdJrw" Apr 23 09:21:17 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 23 09:21:17 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:17+02:00" level=trace msg="emitting websocket event: playing" Apr 23 09:21:17 volumio3-2 go-librespot[1506]: time="2025-04-23T09:21:17+02:00" level=debug msg="fetched chunk 2/16, size: 524288" uri="spotify:track:1MYTg8tznXrUL9DeYqdJrw" Apr 23 09:21:18 volumio3-2 volumio[1089]: info: Starting Streaming Service Transparent Proxy Apr 23 09:21:18 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 23 09:21:18 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 23 09:21:18 volumio3-2 volumio[1089]: info: Streaming services startup Apr 23 09:21:18 volumio3-2 volumio[1089]: info: Starting Streaming Daemon Apr 23 09:21:18 volumio3-2 sudo[1583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 23 09:21:18 volumio3-2 sudo[1583]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 09:21:18 volumio3-2 volumio[1089]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 23 09:21:18 volumio3-2 sudo[1583]: pam_unix(sudo:session): session closed for user root Apr 23 09:21:18 volumio3-2 volumio[1089]: info: Getting Spotify volume Apr 23 09:21:18 volumio3-2 volumio[1089]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1MYTg8tznXrUL9DeYqdJrw","play_origin":"playlist"}} Apr 23 09:21:18 volumio3-2 volumio[1089]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1MYTg8tznXrUL9DeYqdJrw","name":"Show Me","artist_names":["Deep Purple"],"album_name":"=1","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02f82ad2b691201e84d88912f7","position":8198,"duration":239400,"release_date":"year:2024 month:7 day:19","track_number":1,"disc_number":1}} Apr 23 09:21:18 volumio3-2 volumio[1089]: SPOTIFY: received: {"type":"active","data":null} Apr 23 09:21:18 volumio3-2 volumio[1089]: info: Aligning Spotify Volume to Volumio Volume Apr 23 09:21:18 volumio3-2 volumio[1089]: info: CoreCommandRouter::volumioGetState Apr 23 09:21:18 volumio3-2 volumio[1089]: info: CorePlayQueue::getTrack 0 Apr 23 09:21:18 volumio3-2 volumio[1089]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1MYTg8tznXrUL9DeYqdJrw","play_origin":"playlist"}} Apr 23 09:21:18 volumio3-2 volumio[1089]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 09:21:18 volumio3-2 volumio[1089]: TypeError: Cannot read property 'service' of undefined Apr 23 09:21:18 volumio3-2 volumio[1089]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Apr 23 09:21:18 volumio3-2 volumio[1089]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:263:18) Apr 23 09:21:18 volumio3-2 volumio[1089]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Apr 23 09:21:18 volumio3-2 volumio[1089]: at WebSocket.emit (events.js:315:20) Apr 23 09:21:18 volumio3-2 volumio[1089]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Apr 23 09:21:18 volumio3-2 volumio[1089]: at Receiver.emit (events.js:315:20) Apr 23 09:21:18 volumio3-2 volumio[1089]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Apr 23 09:21:18 volumio3-2 volumio[1089]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Apr 23 09:21:18 volumio3-2 volumio[1089]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Apr 23 09:21:18 volumio3-2 volumio[1089]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Apr 23 09:21:18 volumio3-2 volumio[1089]: at writeOrBuffer (internal/streams/writable.js:358:12) Apr 23 09:21:18 volumio3-2 volumio[1089]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Apr 23 09:21:18 volumio3-2 volumio[1089]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Apr 23 09:21:18 volumio3-2 volumio[1089]: at Socket.emit (events.js:315:20) Apr 23 09:21:18 volumio3-2 volumio[1089]: at addChunk (internal/streams/readable.js:309:12) Apr 23 09:21:18 volumio3-2 volumio[1089]: at readableAddChunk (internal/streams/readable.js:284:9) Apr 23 09:21:18 volumio3-2 volumio[1089]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 09:21:18 volumio3-2 sudo[1599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-23 09:20 Apr 23 09:21:18 volumio3-2 sudo[1599]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"