-- Logs begin at Thu 2025-05-22 01:34:44 +07, end at Thu 2025-05-22 14:44:46 +07. -- May 22 14:43:05 phongkhach ntpd[965]: Soliciting pool server 115.165.161.155 May 22 14:43:06 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:43:06 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:43:06 phongkhach volumio[1044]: info: Listing playlists May 22 14:43:06 phongkhach volumio[1044]: info: Listing playlists May 22 14:43:06 phongkhach go-librespot[1671]: time="2025-05-22T14:43:06+07:00" level=trace msg="received accesspoint ping" May 22 14:43:06 phongkhach go-librespot[1671]: time="2025-05-22T14:43:06+07:00" level=trace msg="received accesspoint pong ack" May 22 14:43:16 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:43:16 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:43:18 phongkhach ntpd[965]: Soliciting pool server 2401:5b60:0:1::52 May 22 14:43:21 phongkhach go-librespot[1671]: time="2025-05-22T14:43:21+07:00" level=trace msg="sent dealer ping" May 22 14:43:21 phongkhach go-librespot[1671]: time="2025-05-22T14:43:21+07:00" level=trace msg="received dealer pong" May 22 14:43:26 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:43:26 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:43:26 phongkhach volumio[1044]: info: Listing playlists May 22 14:43:26 phongkhach volumio[1044]: info: Listing playlists May 22 14:43:36 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:43:36 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:43:40 phongkhach ntpd[965]: Soliciting pool server 103.184.124.254 May 22 14:43:46 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:43:46 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:43:46 phongkhach volumio[1044]: info: Listing playlists May 22 14:43:46 phongkhach volumio[1044]: info: Listing playlists May 22 14:43:50 phongkhach ntpd[965]: Soliciting pool server 115.165.161.155 May 22 14:43:51 phongkhach go-librespot[1671]: time="2025-05-22T14:43:51+07:00" level=trace msg="sent dealer ping" May 22 14:43:51 phongkhach go-librespot[1671]: time="2025-05-22T14:43:51+07:00" level=trace msg="received dealer pong" May 22 14:43:56 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:43:56 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:43:57 phongkhach nmbd[911]: [2025/05/22 14:43:57.325009, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 22 14:43:57 phongkhach nmbd[911]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.100.10 for name WORKGROUP<1d>. May 22 14:43:57 phongkhach nmbd[911]: This response was from IP 192.168.100.100, reporting an IP address of 192.168.100.100. May 22 14:44:06 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:44:06 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:06 phongkhach volumio[1044]: info: Listing playlists May 22 14:44:06 phongkhach volumio[1044]: info: Listing playlists May 22 14:44:16 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:44:16 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:21 phongkhach go-librespot[1671]: time="2025-05-22T14:44:21+07:00" level=trace msg="sent dealer ping" May 22 14:44:21 phongkhach go-librespot[1671]: time="2025-05-22T14:44:21+07:00" level=trace msg="received dealer pong" May 22 14:44:24 phongkhach ntpd[965]: Soliciting pool server 2404:fbc0:0:2046::c May 22 14:44:26 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:44:26 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:26 phongkhach volumio[1044]: info: Listing playlists May 22 14:44:26 phongkhach volumio[1044]: info: Listing playlists May 22 14:44:31 phongkhach sudo[24161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 14:44:31 phongkhach sudo[24161]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 14:44:31 phongkhach sudo[24161]: pam_unix(sudo:session): session closed for user root May 22 14:44:31 phongkhach sudo[24164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 14:44:31 phongkhach sudo[24164]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 14:44:31 phongkhach sudo[24164]: pam_unix(sudo:session): session closed for user root May 22 14:44:31 phongkhach volumio[1044]: verbose: New Socket.io Connection to 192.168.100.10 from 192.168.100.26 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_1_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/136.0.7103.91 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 6 May 22 14:44:31 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 14:44:32 phongkhach sudo[24167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 14:44:32 phongkhach sudo[24167]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 14:44:32 phongkhach sudo[24167]: pam_unix(sudo:session): session closed for user root May 22 14:44:32 phongkhach sudo[24169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 14:44:32 phongkhach sudo[24169]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 14:44:32 phongkhach sudo[24169]: pam_unix(sudo:session): session closed for user root May 22 14:44:32 phongkhach volumio[1044]: verbose: New Socket.io Connection to 192.168.100.10 from 192.168.100.26 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_1_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/136.0.7103.91 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 6 May 22 14:44:32 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 22 14:44:32 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 14:44:32 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetVisibleSources May 22 14:44:32 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 14:44:32 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:44:32 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:32 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 22 14:44:32 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 14:44:32 phongkhach volumio[1044]: info: Received Get System Info May 22 14:44:32 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 14:44:32 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 14:44:32 phongkhach volumio[1044]: info: Discovery: Getting this device information May 22 14:44:32 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:44:32 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:32 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 14:44:32 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:44:32 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:32 phongkhach volumio[1044]: info: Listing playlists May 22 14:44:32 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 22 14:44:32 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 14:44:32 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 22 14:44:33 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 22 14:44:34 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 14:44:34 phongkhach volumio[1044]: info: Received Get System Info May 22 14:44:34 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 14:44:34 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 14:44:34 phongkhach volumio[1044]: info: Discovery: Getting this device information May 22 14:44:34 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:44:34 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:34 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 14:44:34 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 14:44:36 phongkhach volumio[1044]: info: Received Get System Info May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 14:44:36 phongkhach volumio[1044]: info: Discovery: Getting this device information May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:44:36 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 14:44:36 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:44:36 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:37 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 14:44:37 phongkhach volumio[1044]: info: Received Get System Info May 22 14:44:37 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 14:44:37 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 14:44:37 phongkhach volumio[1044]: info: Discovery: Getting this device information May 22 14:44:37 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:44:37 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:37 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 14:44:37 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 14:44:37 phongkhach volumio[1044]: info: Received Get System Info May 22 14:44:37 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 14:44:37 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 14:44:37 phongkhach volumio[1044]: info: Discovery: Getting this device information May 22 14:44:37 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:44:37 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:37 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 14:44:39 phongkhach volumio[1044]: info: May 22 14:44:39 phongkhach volumio[1044]: ---------------------------- Client requests Volumio play May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::volumioPlay May 22 14:44:39 phongkhach volumio[1044]: info: CoreStateMachine::play index undefined May 22 14:44:39 phongkhach volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 14:44:39 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:39 phongkhach volumio[1044]: info: CoreStateMachine::startPlaybackTimer May 22 14:44:39 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:39 phongkhach volumio[1044]: info: [1747899879754] ControllerSpotify::clearAddPlayTrack May 22 14:44:39 phongkhach volumio[1044]: info: Sending Spotify command with payload to local API: /player/play May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:44:39 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 14:44:39 phongkhach volumio[1044]: info: Retrieving Cloud Streaming UI May 22 14:44:39 phongkhach volumio[1044]: info: Getting Tidal Cloud Configuration May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 14:44:39 phongkhach volumio[1044]: info: Getting Qobuz Cloud Configuration May 22 14:44:39 phongkhach volumio[1044]: info: Asking plugin for UI Config May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 14:44:39 phongkhach volumio[1044]: info: Getting Spotify Cloud Configuration May 22 14:44:39 phongkhach volumio[1044]: info: Asking plugin for UI Config May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 14:44:39 phongkhach volumio[1044]: info: Saving Spotify Acccount May 22 14:44:39 phongkhach volumio[1044]: info: Got Tidal Cloud Configuration May 22 14:44:39 phongkhach volumio[1044]: info: Got it May 22 14:44:39 phongkhach volumio[1044]: info: Got it May 22 14:44:39 phongkhach volumio[1044]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 14:44:39 phongkhach volumio[1044]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetBrowseSources May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetBrowseSources May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetBrowseSources May 22 14:44:39 phongkhach go-librespot[1671]: time="2025-05-22T14:44:39+07:00" level=debug msg="resolved context of track" uri="spotify:track:53zusvCJkyGYjEZ6bnLIjh" May 22 14:44:39 phongkhach go-librespot[1671]: time="2025-05-22T14:44:39+07:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:53zusvCJkyGYjEZ6bnLIjh" May 22 14:44:39 phongkhach go-librespot[1671]: time="2025-05-22T14:44:39+07:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:53zusvCJkyGYjEZ6bnLIjh" May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 22 14:44:39 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 22 14:44:39 phongkhach go-librespot[1671]: time="2025-05-22T14:44:39+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 22 14:44:39 phongkhach go-librespot[1671]: time="2025-05-22T14:44:39+07:00" level=trace msg="emitting websocket event: will_play" May 22 14:44:39 phongkhach volumio[1044]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:53zusvCJkyGYjEZ6bnLIjh","play_origin":"go-librespot"}} May 22 14:44:39 phongkhach volumio[1044]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:53zusvCJkyGYjEZ6bnLIjh","play_origin":"go-librespot"}} May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=debug msg="selected format OGG_VORBIS_320 (d1a18303e221aa2890c014181c95dd5b47a0714f)" uri="spotify:track:53zusvCJkyGYjEZ6bnLIjh" May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=debug msg="requested aes key for file d1a18303e221aa2890c014181c95dd5b47a0714f, gid: 53zusvCJkyGYjEZ6bnLIjh" May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1363" May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1127" May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=debug msg="fetched first chunk of 24, total size is 12155824 bytes" uri="spotify:track:53zusvCJkyGYjEZ6bnLIjh" May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=info msg="loaded track \"Tháng Tư Là Lời Nói Dối Của Em\" (paused: false, position: 0ms, duration: 310066ms, prefetched: false)" uri="spotify:track:53zusvCJkyGYjEZ6bnLIjh" May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=debug msg="fetched chunk 1/23, size: 524288" uri="spotify:track:53zusvCJkyGYjEZ6bnLIjh" May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=debug msg="fetched chunk 2/23, size: 524288" uri="spotify:track:53zusvCJkyGYjEZ6bnLIjh" May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=trace msg="scheduling prefetch in 280s" May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=trace msg="emitting websocket event: metadata" May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:53zusvCJkyGYjEZ6bnLIjh","name":"Tháng Tư Là Lời Nói Dối Của Em","artist_names":["Hà Anh Tuấn"],"album_name":"Fragile","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02bf7d3058bc206639f626da8b","position":0,"duration":310066,"release_date":"year:2021 month:6 day:1","track_number":4,"disc_number":1}} May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:53zusvCJkyGYjEZ6bnLIjh","name":"Tháng Tư Là Lời Nói Dối Của Em","artist_names":["Hà Anh Tuấn"],"album_name":"Fragile","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02bf7d3058bc206639f626da8b","position":0,"duration":310066,"release_date":"year:2021 month:6 day:1","track_number":4,"disc_number":1}} May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=debug msg="fetched chunk 3/23, size: 524288" uri="spotify:track:53zusvCJkyGYjEZ6bnLIjh" May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 22 14:44:40 phongkhach go-librespot[1671]: time="2025-05-22T14:44:40+07:00" level=trace msg="emitting websocket event: playing" May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:53zusvCJkyGYjEZ6bnLIjh","play_origin":"go-librespot"}} May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: PUSH STATE SPOTIFY May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: {"status":"play","service":"spop","title":"Tháng Tư Là Lời Nói Dối Của Em","artist":"Hà Anh Tuấn","album":"Fragile","albumart":"https://i.scdn.co/image/ab67616d00001e02bf7d3058bc206639f626da8b","uri":"spotify:track:53zusvCJkyGYjEZ6bnLIjh","trackType":"spotify","seek":0,"duration":310,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 22 14:44:40 phongkhach volumio[1044]: info: CoreCommandRouter::servicePushState May 22 14:44:40 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:40 phongkhach volumio[1044]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Tháng Tư Là Lời Nói Dối Của Em","artist":"Hà Anh Tuấn","album":"Fragile","albumart":"https://i.scdn.co/image/ab67616d00001e02bf7d3058bc206639f626da8b","uri":"spotify:track:53zusvCJkyGYjEZ6bnLIjh","trackType":"spotify","seek":0,"duration":310,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 22 14:44:40 phongkhach volumio[1044]: verbose: CURRENT POSITION 0 May 22 14:44:40 phongkhach volumio[1044]: info: CoreStateMachine::syncState stateService play May 22 14:44:40 phongkhach volumio[1044]: info: CoreStateMachine::syncState currentStatus stop May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:53zusvCJkyGYjEZ6bnLIjh","play_origin":"go-librespot"}} May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: PUSH STATE SPOTIFY May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: {"status":"play","service":"spop","title":"Tháng Tư Là Lời Nói Dối Của Em","artist":"Hà Anh Tuấn","album":"Fragile","albumart":"https://i.scdn.co/image/ab67616d00001e02bf7d3058bc206639f626da8b","uri":"spotify:track:53zusvCJkyGYjEZ6bnLIjh","trackType":"spotify","seek":0,"duration":310,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 22 14:44:40 phongkhach volumio[1044]: info: CoreCommandRouter::servicePushState May 22 14:44:40 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:40 phongkhach volumio[1044]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Tháng Tư Là Lời Nói Dối Của Em","artist":"Hà Anh Tuấn","album":"Fragile","albumart":"https://i.scdn.co/image/ab67616d00001e02bf7d3058bc206639f626da8b","uri":"spotify:track:53zusvCJkyGYjEZ6bnLIjh","trackType":"spotify","seek":0,"duration":310,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 22 14:44:40 phongkhach volumio[1044]: verbose: CURRENT POSITION 0 May 22 14:44:40 phongkhach volumio[1044]: info: CoreStateMachine::syncState stateService play May 22 14:44:40 phongkhach volumio[1044]: info: CoreStateMachine::syncState currentStatus play May 22 14:44:40 phongkhach volumio[1044]: info: Received an update from plugin. extracting info from payload May 22 14:44:40 phongkhach volumio[1044]: info: CoreStateMachine::pushState May 22 14:44:40 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:40 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 14:44:40 phongkhach volumio[1044]: info: CoreCommandRouter::volumioPushState May 22 14:44:40 phongkhach volumio[1044]: info: CoreStateMachine::pushState May 22 14:44:40 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:40 phongkhach volumio[1044]: info: CoreCommandRouter::volumioPushState May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: PUSH STATE SPOTIFY May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: {"status":"play","service":"spop","title":"Tháng Tư Là Lời Nói Dối Của Em","artist":"Hà Anh Tuấn","album":"Fragile","albumart":"https://i.scdn.co/image/ab67616d00001e02bf7d3058bc206639f626da8b","uri":"spotify:track:53zusvCJkyGYjEZ6bnLIjh","trackType":"spotify","seek":0,"duration":310,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 22 14:44:40 phongkhach volumio[1044]: info: CoreCommandRouter::servicePushState May 22 14:44:40 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:40 phongkhach volumio[1044]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Tháng Tư Là Lời Nói Dối Của Em","artist":"Hà Anh Tuấn","album":"Fragile","albumart":"https://i.scdn.co/image/ab67616d00001e02bf7d3058bc206639f626da8b","uri":"spotify:track:53zusvCJkyGYjEZ6bnLIjh","trackType":"spotify","seek":0,"duration":310,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 22 14:44:40 phongkhach volumio[1044]: verbose: CURRENT POSITION 0 May 22 14:44:40 phongkhach volumio[1044]: info: CoreStateMachine::syncState stateService play May 22 14:44:40 phongkhach volumio[1044]: info: CoreStateMachine::syncState currentStatus play May 22 14:44:40 phongkhach volumio[1044]: info: Received an update from plugin. extracting info from payload May 22 14:44:40 phongkhach volumio[1044]: info: CoreStateMachine::pushState May 22 14:44:40 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:40 phongkhach volumio[1044]: info: CoreCommandRouter::volumioPushState May 22 14:44:40 phongkhach volumio[1044]: info: CoreStateMachine::pushState May 22 14:44:40 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:40 phongkhach volumio[1044]: info: CoreCommandRouter::volumioPushState May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: PUSH STATE SPOTIFY May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: {"status":"play","service":"spop","title":"Tháng Tư Là Lời Nói Dối Của Em","artist":"Hà Anh Tuấn","album":"Fragile","albumart":"https://i.scdn.co/image/ab67616d00001e02bf7d3058bc206639f626da8b","uri":"spotify:track:53zusvCJkyGYjEZ6bnLIjh","trackType":"spotify","seek":0,"duration":310,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 22 14:44:40 phongkhach volumio[1044]: info: CoreCommandRouter::servicePushState May 22 14:44:40 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:40 phongkhach volumio[1044]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Tháng Tư Là Lời Nói Dối Của Em","artist":"Hà Anh Tuấn","album":"Fragile","albumart":"https://i.scdn.co/image/ab67616d00001e02bf7d3058bc206639f626da8b","uri":"spotify:track:53zusvCJkyGYjEZ6bnLIjh","trackType":"spotify","seek":0,"duration":310,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 22 14:44:40 phongkhach volumio[1044]: verbose: CURRENT POSITION 0 May 22 14:44:40 phongkhach volumio[1044]: info: CoreStateMachine::syncState stateService play May 22 14:44:40 phongkhach volumio[1044]: info: CoreStateMachine::syncState currentStatus play May 22 14:44:40 phongkhach volumio[1044]: info: Received an update from plugin. extracting info from payload May 22 14:44:40 phongkhach volumio[1044]: info: CoreStateMachine::pushState May 22 14:44:40 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:40 phongkhach volumio[1044]: info: CoreCommandRouter::volumioPushState May 22 14:44:40 phongkhach volumio[1044]: info: CoreStateMachine::pushState May 22 14:44:40 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:40 phongkhach volumio[1044]: info: CoreCommandRouter::volumioPushState May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 May 22 14:44:40 phongkhach volumio[1044]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 May 22 14:44:41 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 22 14:44:41 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 22 14:44:42 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 22 14:44:42 phongkhach volumio[1044]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L music.local. May 22 14:44:42 phongkhach volumio[1044]: info: May 22 14:44:42 phongkhach volumio[1044]: ---------------------------- Client requests Volume 26 May 22 14:44:42 phongkhach volumio[1044]: info: VolumeController::SetAlsaVolume26 May 22 14:44:42 phongkhach volumio[1044]: info: CoreStateMachine::pushState May 22 14:44:42 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:42 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 14:44:42 phongkhach volumio[1044]: info: CoreCommandRouter::volumioPushState May 22 14:44:42 phongkhach volumio[1044]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 May 22 14:44:42 phongkhach volumio[1044]: SPOTIFY: SPOTIFY VOLUME 15 May 22 14:44:42 phongkhach volumio[1044]: SPOTIFY: VOLUMIO VOLUME 26 May 22 14:44:42 phongkhach volumio[1044]: SPOTIFY: DELTA VOLUME ENOUGH: true May 22 14:44:42 phongkhach volumio[1044]: info: Setting Spotify Volume from Volumio: 26 May 22 14:44:42 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:44:42 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:42 phongkhach volumio[1044]: info: Listing playlists May 22 14:44:42 phongkhach volumio[1044]: info: Listing playlists May 22 14:44:43 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 22 14:44:44 phongkhach volumio[1044]: info: May 22 14:44:44 phongkhach volumio[1044]: ---------------------------- Client requests Volume 22 May 22 14:44:44 phongkhach volumio[1044]: info: VolumeController::SetAlsaVolume22 May 22 14:44:44 phongkhach volumio[1044]: info: CoreStateMachine::pushState May 22 14:44:44 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:44 phongkhach volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 14:44:44 phongkhach volumio[1044]: info: CoreCommandRouter::volumioPushState May 22 14:44:44 phongkhach volumio[1044]: SPOTIFY: RECEIVED VOLUMIO VOLUME 22 May 22 14:44:44 phongkhach volumio[1044]: SPOTIFY: SPOTIFY VOLUME 26 May 22 14:44:44 phongkhach volumio[1044]: SPOTIFY: VOLUMIO VOLUME 22 May 22 14:44:44 phongkhach volumio[1044]: SPOTIFY: DELTA VOLUME ENOUGH: true May 22 14:44:44 phongkhach volumio[1044]: info: Setting Spotify Volume from Volumio: 22 May 22 14:44:44 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:44:44 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:44 phongkhach ntpd[965]: Soliciting pool server 103.184.124.254 May 22 14:44:45 phongkhach volumio[1044]: SPOTIFY: SETTING SPOTIFY VOLUME 22 May 22 14:44:45 phongkhach volumio[1044]: info: Sending Spotify command with payload to local API: /player/volume May 22 14:44:45 phongkhach go-librespot[1671]: time="2025-05-22T14:44:45+07:00" level=debug msg="update volume to 14417/65535" May 22 14:44:46 phongkhach volumio[1044]: info: CoreCommandRouter::volumioGetState May 22 14:44:46 phongkhach volumio[1044]: info: CorePlayQueue::getTrack 0 May 22 14:44:46 phongkhach volumio[1044]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 14:44:46 phongkhach volumio[1044]: Error: dns service error: unknown May 22 14:44:46 phongkhach volumio[1044]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) May 22 14:44:46 phongkhach volumio[1044]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { May 22 14:44:46 phongkhach volumio[1044]: errorCode: -65537 May 22 14:44:46 phongkhach volumio[1044]: } May 22 14:44:46 phongkhach volumio[1044]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 14:44:46 phongkhach go-librespot[1671]: time="2025-05-22T14:44:46+07:00" level=debug msg="put connect state because VOLUME_CHANGED" May 22 14:44:46 phongkhach go-librespot[1671]: time="2025-05-22T14:44:46+07:00" level=trace msg="emitting websocket event: volume" May 22 14:44:46 phongkhach sudo[24213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 14:43 May 22 14:44:46 phongkhach sudo[24213]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"