-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Fri 2025-07-04 13:16:58 UTC. -- Jul 04 13:16:50 yeti ntpd[667]: receive: Unexpected origin timestamp 0xe95e9a0c.5af3a191 does not match aorg 0000000000.00000000 from server@23.147.168.175 xmt 0xec1252c2.cfb331c7 Jul 04 13:16:50 yeti systemd[1]: Starting Daily apt download activities... Jul 04 13:16:50 yeti ntpd[667]: receive: Unexpected origin timestamp 0xe95e9a0c.5aeff375 does not match aorg 0000000000.00000000 from server@81.193.254.213 xmt 0xec1252c2.ce7cf905 Jul 04 13:16:50 yeti volumio[850]: info: Discovery: A device disappeared from network Jul 04 13:16:50 yeti volumio[850]: info: Discovery: Device yeti disappeared from network Jul 04 13:16:51 yeti volumio[850]: info: Discovery: adding 57c3c149-cadc-4d8f-a1f9-3d651363d260 Jul 04 13:16:51 yeti volumio[850]: info: Discovery: Found device yeti Jul 04 13:16:51 yeti volumio[850]: info: CoreCommandRouter::volumioGetState Jul 04 13:16:51 yeti volumio[850]: info: CorePlayQueue::getTrack 0 Jul 04 13:16:51 yeti volumio[850]: info: Initializing connection to go-librespot Websocket Jul 04 13:16:51 yeti volumio[850]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 04 13:16:53 yeti systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 04 13:16:53 yeti systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Jul 04 13:16:53 yeti systemd[1]: Stopped go-librespot Daemon. Jul 04 13:16:53 yeti systemd[1]: Started go-librespot Daemon. Jul 04 13:16:53 yeti go-librespot[1220]: Librespot-go daemon starting... Jul 04 13:16:53 yeti go-librespot[1220]: time="2025-07-04T13:16:53Z" level=info msg="generated new device id: 08a22d97df8e13906df84f48086af220432e2d80" Jul 04 13:16:53 yeti go-librespot[1220]: time="2025-07-04T13:16:53Z" level=debug msg="stored credentials found for 31v5jfahumapveynnxu2hshgfmiq" Jul 04 13:16:53 yeti go-librespot[1220]: time="2025-07-04T13:16:53Z" level=debug msg="obtained new client token: AAD/QiMlcZ7Ki7K8MdMWYvw4v/bfR48KJ3sSsh89j5CKwwcbSqD+shFKYSbGqaWpsGseKsYKYIUzMJVPNUygOHy6YaZWDfwzgDspiLhzx3GlI1Z0FD2N9XRzCqwaOOF7ccK4iz2e8aKaiaSQfXwLwiVb7ikx5ZpYfOSqyN0c1bHM45KCZ3OKR+lDkShRqOtFBcuSLOL9LNnTBGTfKo0QhXXtR3UvdRSXlQjacPTyXRsVAqv8OYM9uc9kpA==" Jul 04 13:16:53 yeti go-librespot[1220]: time="2025-07-04T13:16:53Z" 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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 04 13:16:53 yeti go-librespot[1220]: time="2025-07-04T13:16:53Z" level=debug msg="completed keyexchange" Jul 04 13:16:53 yeti systemd[1]: apt-daily.service: Succeeded. Jul 04 13:16:53 yeti systemd[1]: Started Daily apt download activities. Jul 04 13:16:53 yeti systemd[1]: Starting Daily apt upgrade and clean activities... Jul 04 13:16:54 yeti go-librespot[1220]: time="2025-07-04T13:16:54Z" level=debug msg="completed challenge" Jul 04 13:16:54 yeti go-librespot[1220]: time="2025-07-04T13:16:54Z" level=debug msg="authenticated as 31v5jfahumapveynnxu2hshgfmiq" Jul 04 13:16:54 yeti go-librespot[1220]: time="2025-07-04T13:16:54Z" level=debug msg="authenticated as 31v5jfahumapveynnxu2hshgfmiq" Jul 04 13:16:54 yeti go-librespot[1220]: time="2025-07-04T13:16:54Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jul 04 13:16:54 yeti go-librespot[1220]: time="2025-07-04T13:16:54Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jul 04 13:16:54 yeti go-librespot[1220]: time="2025-07-04T13:16:54Z" level=debug msg="dealer connection opened" Jul 04 13:16:54 yeti go-librespot[1220]: time="2025-07-04T13:16:54Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 04 13:16:54 yeti go-librespot[1220]: time="2025-07-04T13:16:54Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 04 13:16:54 yeti go-librespot[1220]: time="2025-07-04T13:16:54Z" level=debug msg="received connection id: Nzk2ZDhkNzAtNTc5OS00ODVmLTgxOGMtM2RkODhhNDU3ZWRjK2RlYWxlcit0Y3A6Ly8wYWNhNDFmZC5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArNUUyODlCNTIxREVFQ0FCMTAzMDMxMDM3MjhFRDVBMDcyNjBGOERBMUY4Mzc0NkY0NjMxQUIwRTk0ODkyREQ4Rg==" Jul 04 13:16:54 yeti go-librespot[1220]: time="2025-07-04T13:16:54Z" level=debug msg="put connect state because NEW_DEVICE" Jul 04 13:16:54 yeti volumio[850]: info: Initializing connection to go-librespot Websocket Jul 04 13:16:54 yeti go-librespot[1220]: time="2025-07-04T13:16:54Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 04 13:16:54 yeti go-librespot[1220]: time="2025-07-04T13:16:54Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 04 13:16:54 yeti go-librespot[1220]: time="2025-07-04T13:16:54Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 04 13:16:54 yeti go-librespot[1220]: time="2025-07-04T13:16:54Z" level=debug msg="new websocket client" Jul 04 13:16:54 yeti volumio[850]: info: Connection to go-librespot Websocket established Jul 04 13:16:54 yeti systemd[1]: apt-daily-upgrade.service: Succeeded. Jul 04 13:16:54 yeti systemd[1]: Started Daily apt upgrade and clean activities. Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 04 13:16:54 yeti volumio[850]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 04 13:16:54 yeti volumio[850]: info: Adding plugin bluetooth to MyMusic Plugins Jul 04 13:16:55 yeti volumio[850]: info: Adding plugin multiroom to MyMusic Plugins Jul 04 13:16:55 yeti volumio[850]: info: Adding plugin metavolumio to MyMusic Plugins Jul 04 13:16:55 yeti volumio[850]: info: Adding plugin cd_controller to MyMusic Plugins Jul 04 13:16:55 yeti volumio[850]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 04 13:16:55 yeti volumio[850]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 04 13:16:55 yeti volumio[850]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 04 13:16:55 yeti volumio[850]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 04 13:16:55 yeti volumio[850]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 04 13:16:55 yeti volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 13:16:55 yeti volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 13:16:55 yeti volumio[850]: info: Starting MyVolumio Remote Streaming Endpoints Jul 04 13:16:55 yeti volumio[850]: info: MyVolumio login type: Token Jul 04 13:16:55 yeti volumio[850]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 04 13:16:55 yeti volumio[850]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 04 13:16:56 yeti go-librespot[1220]: time="2025-07-04T13:16:56Z" level=debug msg="handling transfer player command from 36b2fbe4d57b7cc4216e57b196ecccb82a6f6fd7" Jul 04 13:16:56 yeti go-librespot[1220]: time="2025-07-04T13:16:56Z" level=trace msg="fetched new page 0 with 107 items (list: 107)" Jul 04 13:16:56 yeti go-librespot[1220]: time="2025-07-04T13:16:56Z" level=debug msg="shuffled context with seed 12606332454184285157 (len: 107, keep: 12)" Jul 04 13:16:56 yeti go-librespot[1220]: time="2025-07-04T13:16:56Z" level=debug msg="loading track spotify:track:1MuXJDpSo7sl0FJ2axtU83 (paused: true, position: 114646ms)" Jul 04 13:16:56 yeti go-librespot[1220]: time="2025-07-04T13:16:56Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 04 13:16:56 yeti go-librespot[1220]: time="2025-07-04T13:16:56Z" level=trace msg="emitting websocket event: will_play" Jul 04 13:16:56 yeti go-librespot[1220]: time="2025-07-04T13:16:56Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Jul 04 13:16:56 yeti go-librespot[1220]: time="2025-07-04T13:16:56Z" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:1MuXJDpSo7sl0FJ2axtU83" Jul 04 13:16:56 yeti go-librespot[1220]: time="2025-07-04T13:16:56Z" level=debug msg="requested aes key for file 7d6445f634a2e4a03f6676fa91b18c86a1afde22, gid: 1MuXJDpSo7sl0FJ2axtU83" Jul 04 13:16:56 yeti go-librespot[1220]: time="2025-07-04T13:16:56Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1369" Jul 04 13:16:56 yeti volumio[850]: info: Starting Streaming Service Transparent Proxy Jul 04 13:16:56 yeti volumio[850]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 04 13:16:56 yeti volumio[850]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 04 13:16:56 yeti volumio[850]: info: Streaming services startup Jul 04 13:16:56 yeti volumio[850]: info: Starting Streaming Daemon Jul 04 13:16:56 yeti go-librespot[1220]: time="2025-07-04T13:16:56Z" level=debug msg="fetched first chunk of 15, total size is 7518764 bytes" Jul 04 13:16:56 yeti go-librespot[1220]: time="2025-07-04T13:16:56Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1127" Jul 04 13:16:56 yeti sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 04 13:16:56 yeti sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 13:16:56 yeti systemd[1]: Stopping Volumio Streaming Daemon... Jul 04 13:16:56 yeti systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Jul 04 13:16:56 yeti systemd[1]: volumio-streaming-daemon.service: Succeeded. Jul 04 13:16:56 yeti systemd[1]: Stopped Volumio Streaming Daemon. Jul 04 13:16:56 yeti systemd[1]: Started Volumio Streaming Daemon. Jul 04 13:16:56 yeti sudo[1316]: pam_unix(sudo:session): session closed for user root Jul 04 13:16:56 yeti volumio[850]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 04 13:16:56 yeti volumio-streaming-daemon[1322]: ############################ Jul 04 13:16:56 yeti volumio-streaming-daemon[1322]: # Volumio Streaming Daemon # Jul 04 13:16:56 yeti volumio-streaming-daemon[1322]: # Running on port 7777 # Jul 04 13:16:56 yeti volumio-streaming-daemon[1322]: ############################ Jul 04 13:16:56 yeti volumio-streaming-daemon[1322]: Environment: production Jul 04 13:16:57 yeti volumio[850]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1MuXJDpSo7sl0FJ2axtU83","play_origin":"collection/songs"}} Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="fetched chunk 1/14, size: 524288" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="fetched chunk 3/14, size: 524288" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 319" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1420" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="fetched chunk 8/14, size: 524288" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=trace msg="seek to 114646ms (diff: 98ms, samples: 5055888, bytes: 4372765)" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=info msg="loaded track \"Moog River\" (uri: spotify:track:1MuXJDpSo7sl0FJ2axtU83, paused: true, position: 114646ms, duration: 195773ms)" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="fetched chunk 10/14, size: 524288" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="fetched chunk 11/14, size: 524288" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="fetched chunk 2/14, size: 524288" Jul 04 13:16:57 yeti volumio[850]: error: MyVolumio Custom Token format not valid, refreshing it Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="fetched chunk 9/14, size: 524288" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=trace msg="emitting websocket event: metadata" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=trace msg="emitting websocket event: active" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="sending successful reply for delaer request" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 04 13:16:57 yeti volumio[850]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1MuXJDpSo7sl0FJ2axtU83","name":"Moog River","artist_names":["Funki Porcini"],"album_name":"On","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0298e6ee03127e31a9fcb68a11","position":114646,"duration":195773,"release_date":"year:2010 month:5 day:3","track_number":1,"disc_number":1}} Jul 04 13:16:57 yeti volumio[850]: SPOTIFY: received: {"type":"active","data":null} Jul 04 13:16:57 yeti volumio[850]: info: Aligning Spotify Volume to Volumio Volume Jul 04 13:16:57 yeti volumio[850]: info: CoreCommandRouter::volumioGetState Jul 04 13:16:57 yeti volumio[850]: info: CorePlayQueue::getTrack 0 Jul 04 13:16:57 yeti volumio[850]: info: Setting Spotify Volume from Volumio: 100 Jul 04 13:16:57 yeti volumio[850]: STREAMING PROXY: Starting server on port 3245 Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=trace msg="emitting websocket event: paused" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 04 13:16:57 yeti volumio[850]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1MuXJDpSo7sl0FJ2axtU83","play_origin":"collection/songs"}} Jul 04 13:16:57 yeti volumio[850]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 13:16:57 yeti volumio[850]: TypeError: Cannot read property 'service' of undefined Jul 04 13:16:57 yeti volumio[850]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50) Jul 04 13:16:57 yeti volumio[850]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:270:18) Jul 04 13:16:57 yeti volumio[850]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Jul 04 13:16:57 yeti volumio[850]: at WebSocket.emit (events.js:315:20) Jul 04 13:16:57 yeti volumio[850]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1203:20) Jul 04 13:16:57 yeti volumio[850]: at Receiver.emit (events.js:315:20) Jul 04 13:16:57 yeti volumio[850]: at /data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:608:16 Jul 04 13:16:57 yeti volumio[850]: at internal/process/task_queues.js:149:7 Jul 04 13:16:57 yeti volumio[850]: at AsyncResource.runInAsyncScope (async_hooks.js:197:9) Jul 04 13:16:57 yeti volumio[850]: at AsyncResource.runMicrotask (internal/process/task_queues.js:146:8) Jul 04 13:16:57 yeti volumio[850]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 04 13:16:57 yeti volumio[850]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="put connect state inactive" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=trace msg="emitting websocket event: inactive" Jul 04 13:16:57 yeti go-librespot[1220]: time="2025-07-04T13:16:57Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1369" Jul 04 13:16:58 yeti sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-04 13:15 Jul 04 13:16:58 yeti sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 13:16:58 yeti go-librespot[1220]: time="2025-07-04T13:16:58Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 04 13:16:58 yeti go-librespot[1220]: time="2025-07-04T13:16:58Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1127" 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="8e6823482d6af342172ec9fdc1c15b0dd02653d2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 04 Dec 2022 08:41:29 PM CET" VOLUMIO_VERSION="3.396" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d460c75d04818d0f55c67627fa362668"