-- Logs begin at Thu 2024-02-22 03:42:47 +07, end at Mon 2025-12-15 15:32:12 +07. --
Dec 15 15:32:04 mpi-mr-dung ntpd[672]: receive: Unexpected origin timestamp 0xe980df1a.1257256f does not match aorg 0000000000.00000000 from server@103.221.223.185 xmt 0xecea4604.04245dc0
Dec 15 15:32:04 mpi-mr-dung systemd[1]: Starting Daily apt download activities...
Dec 15 15:32:05 mpi-mr-dung volumio[772]: Cannot compose Albumart path
Dec 15 15:32:06 mpi-mr-dung systemd[1]: apt-daily.service: Succeeded.
Dec 15 15:32:06 mpi-mr-dung systemd[1]: Started Daily apt download activities.
Dec 15 15:32:06 mpi-mr-dung systemd[1]: Starting Daily apt upgrade and clean activities...
Dec 15 15:32:06 mpi-mr-dung systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Dec 15 15:32:06 mpi-mr-dung systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10.
Dec 15 15:32:06 mpi-mr-dung systemd[1]: Stopped go-librespot Daemon.
Dec 15 15:32:06 mpi-mr-dung systemd[1]: Started go-librespot Daemon.
Dec 15 15:32:06 mpi-mr-dung go-librespot[1337]: go-librespot daemon starting...
Dec 15 15:32:06 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:06+07:00" level=info msg="running go-librespot 0.4.0"
Dec 15 15:32:06 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:06+07:00" level=debug msg="app state loaded"
Dec 15 15:32:06 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:06+07:00" level=debug msg="stored credentials not found"
Dec 15 15:32:06 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:06+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 15:32:07 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:07+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 15 15:32:07 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:07+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 15 15:32:07 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:07+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 15 15:32:07 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:07+07:00" level=info msg="zeroconf server listening on port 33627"
Dec 15 15:32:07 mpi-mr-dung systemd[1]: apt-daily-upgrade.service: Succeeded.
Dec 15 15:32:07 mpi-mr-dung systemd[1]: Started Daily apt upgrade and clean activities.
Dec 15 15:32:07 mpi-mr-dung volumio[772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 15:32:07 mpi-mr-dung volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 15 15:32:07 mpi-mr-dung volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 15:32:07 mpi-mr-dung volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 15 15:32:07 mpi-mr-dung volumio[772]: info: Discovery: A device disappeared from network
Dec 15 15:32:07 mpi-mr-dung volumio[772]: info: Discovery: A device disappeared from network
Dec 15 15:32:07 mpi-mr-dung volumio[772]: info: Discovery: adding fcc17800-4f9d-4cf4-9982-4d6ff1a90a53
Dec 15 15:32:07 mpi-mr-dung volumio[772]: info: Discovery: Found device Mpi Mr Dung
Dec 15 15:32:07 mpi-mr-dung volumio[772]: info: CoreCommandRouter::volumioGetState
Dec 15 15:32:07 mpi-mr-dung volumio[772]: info: CorePlayQueue::getTrack 0
Dec 15 15:32:07 mpi-mr-dung volumio[772]: info: Discovery: this is already registered, fcc17800-4f9d-4cf4-9982-4d6ff1a90a53
Dec 15 15:32:07 mpi-mr-dung volumio[772]: info: Discovery: Found device Mpi Mr Dung
Dec 15 15:32:07 mpi-mr-dung volumio[772]: info: CoreCommandRouter::volumioGetState
Dec 15 15:32:07 mpi-mr-dung volumio[772]: info: CorePlayQueue::getTrack 0
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: Adding plugin bluetooth to MyMusic Plugins
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: Adding plugin multiroom to MyMusic Plugins
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: Adding plugin metavolumio to MyMusic Plugins
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: Adding plugin cd_controller to MyMusic Plugins
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: Adding plugin smart_inputs to MyMusic Plugins
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: Adding plugin tidalconnect to MyMusic Plugins
Dec 15 15:32:08 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Dec 15 15:32:09 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Dec 15 15:32:09 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Dec 15 15:32:09 mpi-mr-dung volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 15:32:09 mpi-mr-dung volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 15:32:09 mpi-mr-dung volumio[772]: info: Starting MyVolumio Remote Streaming Endpoints
Dec 15 15:32:09 mpi-mr-dung volumio[772]: info: MyVolumio login type: Token
Dec 15 15:32:09 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Dec 15 15:32:09 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Dec 15 15:32:09 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:09+07:00" level=debug msg="obtained new client token: AABDD9onGoczqM3DetZMOVmVMgvOKlBm3/5W8M1rUKH5KuYY7K3eu77OJRd4bE8jfCsoliTdy0HYJXs7Jk4OyZ2e0JSmRpzA8x1wIN2Bj5WGD53RlmRPzzKwOXw89ydSWYoL8BkSBZ1n/rHQqjvq+GO+2RFS19+my/61o0vHB7uuONEK+Hu1mXyeQgytva49Gr+Dwxu11r8WgpnHdkPBJrx8VjScDbLGQrcMpKKEjJ2qBhc0vSuBdBuDKg=="
Dec 15 15:32:09 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:09+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused"
Dec 15 15:32:09 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:09+07:00" level=debug msg="connected to ap-gae2.spotify.com:443"
Dec 15 15:32:09 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:09+07:00" level=debug msg="completed keyexchange"
Dec 15 15:32:09 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:09+07:00" level=debug msg="completed challenge"
Dec 15 15:32:09 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:09+07:00" level=info msg="authenticated AP" username="kz*********************ef"
Dec 15 15:32:09 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:09+07:00" level=info msg="authenticated Login5" username="kz*********************ef"
Dec 15 15:32:09 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:09+07:00" level=info msg="accepted zeroconf from iPhone" username="kz*********************ef"
Dec 15 15:32:10 mpi-mr-dung volumio[772]: info: Starting Streaming Service Transparent Proxy
Dec 15 15:32:10 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 15 15:32:10 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 15 15:32:10 mpi-mr-dung volumio[772]: info: Streaming services startup
Dec 15 15:32:10 mpi-mr-dung volumio[772]: info: Starting Streaming Daemon
Dec 15 15:32:10 mpi-mr-dung sudo[1393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 15 15:32:10 mpi-mr-dung sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="dealer connection opened"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=trace msg="starting accesspoint recv loop"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=trace msg="received accesspoint ping"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=trace msg="starting dealer recv loop"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="received connection id: NzhmMzg5M2YtODQ2...MEUyNjBDMUFCNg=="
Dec 15 15:32:10 mpi-mr-dung sudo[1393]: pam_unix(sudo:session): session closed for user root
Dec 15 15:32:10 mpi-mr-dung volumio[772]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=trace msg="received accesspoint pong ack"
Dec 15 15:32:10 mpi-mr-dung volumio[772]: error: Cannot start Volumio Streaming Daemon
Dec 15 15:32:10 mpi-mr-dung volumio[772]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 15 15:32:10 mpi-mr-dung volumio[772]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="put connect state because NEW_DEVICE"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="handling transfer player command from 19982451bdcc627a3887922868c4cf7c54ccbce9"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="resolved context of track" uri="spotify:album:6F8qL9zxUVzcH2rlw4QQ4a"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=trace msg="fetched new page 0 with 2 items (list: 2)" uri="spotify:album:6F8qL9zxUVzcH2rlw4QQ4a"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="loading track (paused: true, position: 199758ms)" uri="spotify:track:3Gu94d4ZfGrOZMgstGc1fl"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=trace msg="emitting websocket event: will_play"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411"
Dec 15 15:32:10 mpi-mr-dung volumio[772]: error: MyVolumio Custom Token format not valid, refreshing it
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="selected format OGG_VORBIS_320 (fe02bb9c1ea88aaa3b0e3681add4ef63d0056deb)" uri="spotify:track:3Gu94d4ZfGrOZMgstGc1fl"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="requested aes key for file fe02bb9c1ea88aaa3b0e3681add4ef63d0056deb, gid: 3Gu94d4ZfGrOZMgstGc1fl"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1360"
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=trace msg="found 2 cdn urls" uri="spotify:track:3Gu94d4ZfGrOZMgstGc1fl"
Dec 15 15:32:10 mpi-mr-dung volumio[772]: STREAMING PROXY: Starting server on port 3245
Dec 15 15:32:10 mpi-mr-dung volumio[772]: Node JS runtime: 14
Dec 15 15:32:10 mpi-mr-dung volumio[772]: info: Initializing connection to go-librespot Websocket
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="new websocket client"
Dec 15 15:32:10 mpi-mr-dung volumio[772]: info: Connection to go-librespot Websocket established
Dec 15 15:32:10 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:10+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1126"
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=debug msg="fetched first chunk of 23, total size is 11798231 bytes" uri="spotify:track:3Gu94d4ZfGrOZMgstGc1fl"
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=debug msg="fetched chunk 2/22, size: 524288" uri="spotify:track:3Gu94d4ZfGrOZMgstGc1fl"
Dec 15 15:32:11 mpi-mr-dung volumio[772]: info: MyVolumio login type: Token
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=debug msg="fetched chunk 3/22, size: 524288" uri="spotify:track:3Gu94d4ZfGrOZMgstGc1fl"
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=debug msg="fetched chunk 1/22, size: 524288" uri="spotify:track:3Gu94d4ZfGrOZMgstGc1fl"
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=debug msg="fetched chunk 16/22, size: 524288" uri="spotify:track:3Gu94d4ZfGrOZMgstGc1fl"
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=trace msg="seek to 199758ms (diff: 264ms, samples: 8809327, bytes: 8429202)" uri="spotify:track:3Gu94d4ZfGrOZMgstGc1fl"
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=debug msg="created new output device"
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=info msg="loaded track \"Chẳng Hợp Nhau Đâu - Huy Pt Remix Instrument\" (paused: true, position: 199758ms, duration: 282102ms, prefetched: false)" uri="spotify:track:3Gu94d4ZfGrOZMgstGc1fl"
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=debug msg="fetched chunk 17/22, size: 524288" uri="spotify:track:3Gu94d4ZfGrOZMgstGc1fl"
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=trace msg="emitting websocket event: metadata"
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=debug msg="fetched chunk 18/22, size: 524288" uri="spotify:track:3Gu94d4ZfGrOZMgstGc1fl"
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=trace msg="emitting websocket event: active"
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=debug msg="sending successful reply for dealer request"
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update
Dec 15 15:32:11 mpi-mr-dung volumio[772]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3Gu94d4ZfGrOZMgstGc1fl","name":"Chẳng Hợp Nhau Đâu - Huy Pt Remix Instrument","artist_names":["Lê Bảo Bình","Huy PT"],"album_name":"Chẳng Hợp Nhau Đâu","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02e6302a486ed88352c7f5ba49","position":199758,"duration":282102,"release_date":"year:2025 month:11 day:15","track_number":2,"disc_number":1}}
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update
Dec 15 15:32:11 mpi-mr-dung volumio[772]: SPOTIFY: received: {"type":"active","data":null}
Dec 15 15:32:11 mpi-mr-dung volumio[772]: info: Aligning Spotify Volume to Volumio Volume
Dec 15 15:32:11 mpi-mr-dung volumio[772]: info: CoreCommandRouter::volumioGetState
Dec 15 15:32:11 mpi-mr-dung volumio[772]: info: CorePlayQueue::getTrack 0
Dec 15 15:32:11 mpi-mr-dung volumio[772]: info: Setting Spotify Volume from Volumio: 100
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=trace msg="emitting websocket event: paused"
Dec 15 15:32:11 mpi-mr-dung volumio[772]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:album:6F8qL9zxUVzcH2rlw4QQ4a","uri":"spotify:track:3Gu94d4ZfGrOZMgstGc1fl","play_origin":"album"}}
Dec 15 15:32:11 mpi-mr-dung volumio[772]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 15 15:32:11 mpi-mr-dung volumio[772]: TypeError: Cannot read property 'service' of undefined
Dec 15 15:32:11 mpi-mr-dung volumio[772]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50)
Dec 15 15:32:11 mpi-mr-dung volumio[772]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:280:18)
Dec 15 15:32:11 mpi-mr-dung volumio[772]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14)
Dec 15 15:32:11 mpi-mr-dung volumio[772]: at WebSocket.emit (events.js:315:20)
Dec 15 15:32:11 mpi-mr-dung volumio[772]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20)
Dec 15 15:32:11 mpi-mr-dung volumio[772]: at Receiver.emit (events.js:315:20)
Dec 15 15:32:11 mpi-mr-dung volumio[772]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14)
Dec 15 15:32:11 mpi-mr-dung volumio[772]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10)
Dec 15 15:32:11 mpi-mr-dung volumio[772]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16)
Dec 15 15:32:11 mpi-mr-dung volumio[772]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10)
Dec 15 15:32:11 mpi-mr-dung volumio[772]: at writeOrBuffer (internal/streams/writable.js:358:12)
Dec 15 15:32:11 mpi-mr-dung volumio[772]: at Receiver.Writable.write (internal/streams/writable.js:303:10)
Dec 15 15:32:11 mpi-mr-dung volumio[772]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35)
Dec 15 15:32:11 mpi-mr-dung volumio[772]: at Socket.emit (events.js:315:20)
Dec 15 15:32:11 mpi-mr-dung volumio[772]: at addChunk (internal/streams/readable.js:309:12)
Dec 15 15:32:11 mpi-mr-dung volumio[772]: at readableAddChunk (internal/streams/readable.js:284:9)
Dec 15 15:32:11 mpi-mr-dung volumio[772]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 15 15:32:11 mpi-mr-dung go-librespot[1337]: time="2025-12-15T15:32:11+07:00" level=debug msg="fetched chunk 19/22, size: 524288" uri="spotify:track:3Gu94d4ZfGrOZMgstGc1fl"
Dec 15 15:32:12 mpi-mr-dung sudo[1410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-15 15:31
Dec 15 15:32:12 mpi-mr-dung sudo[1410]: 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"