-- Logs begin at Mon 2025-05-12 19:15:16 +07, end at Tue 2025-05-13 11:19:55 +07. -- May 13 11:18:03 volumio ntpd[684]: Soliciting pool server 2400:6760:0:200::254 May 13 11:18:09 volumio ntpd[684]: Soliciting pool server 103.199.19.135 May 13 11:18:11 volumio volumio[864]: info: May 13 11:18:11 volumio volumio[864]: ---------------------------- MPD announces state update: player May 13 11:18:11 volumio volumio[864]: info: ControllerMpd::getState May 13 11:18:11 volumio volumio[864]: verbose: ControllerMpd::sendMpdCommand status May 13 11:18:11 volumio volumio[864]: info: sendMpdCommand status took 1 milliseconds May 13 11:18:11 volumio volumio[864]: verbose: ControllerMpd::parseState May 13 11:18:11 volumio volumio[864]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 11:18:11 volumio volumio[864]: info: sendMpdCommand playlistinfo took 0 milliseconds May 13 11:18:11 volumio volumio[864]: verbose: ControllerMpd::parseTrackInfo May 13 11:18:11 volumio volumio[864]: info: ControllerMpd::pushState May 13 11:18:11 volumio volumio[864]: info: CoreCommandRouter::servicePushState May 13 11:18:11 volumio volumio[864]: info: CoreStateMachine::pushState May 13 11:18:11 volumio volumio[864]: info: CoreCommandRouter::volumioPushState May 13 11:18:11 volumio volumio[864]: info: CorePlayQueue::getTrack 0 May 13 11:18:11 volumio volumio[864]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bước Qua Nhau","artist":"Vũ.","album":"Bước Qua Nhau","uri":"http://192.168.1.6:12186/tidal/204382876.flac","trackType":"tidal"} May 13 11:18:11 volumio volumio[864]: verbose: CURRENT POSITION 0 May 13 11:18:11 volumio volumio[864]: info: CoreStateMachine::syncState stateService stop May 13 11:18:11 volumio volumio[864]: info: CoreStateMachine::syncState currentStatus play May 13 11:18:11 volumio volumio[864]: info: CoreCommandRouter::volumioPushState May 13 11:18:11 volumio volumio[864]: info: CoreStateMachine::stPlaybackTimer May 13 11:18:11 volumio volumio[864]: info: ------------------------------ 5ms May 13 11:18:11 volumio volumio[864]: info: CoreCommandRouter::volumioGetQueue May 13 11:18:11 volumio volumio[864]: info: CoreStateMachine::getQueue May 13 11:18:11 volumio volumio[864]: info: CorePlayQueue::getQueue May 13 11:18:11 volumio volumio[864]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 13 11:18:31 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 11:18:31 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 11:18:31 volumio volumio[864]: info: Discovery: Getting this device information May 13 11:18:31 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:18:31 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 11:18:31 volumio volumio[864]: verbose: New Socket.io Connection to 192.168.1.8:3000 from 192.168.1.6 UA: Dart/3.7 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 May 13 11:18:31 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:18:31 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 13 11:18:31 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 13 11:18:31 volumio volumio[864]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/V%C5%A9./B%C6%B0%E1%BB%9Bc%20Qua%20Nhau/680a3622-b739-49bf-85ed-bcd6e21ffbab.jpg' May 13 11:18:31 volumio volumio[864]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/V%C5%A9./B%C6%B0%E1%BB%9Bc%20Qua%20Nhau/680a3622-b739-49bf-85ed-bcd6e21ffbab.jpg' May 13 11:18:32 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:18:32 volumio volumio[864]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/V%C5%A9./B%C6%B0%E1%BB%9Bc%20Qua%20Nhau/680a3622-b739-49bf-85ed-bcd6e21ffbab.jpg' May 13 11:18:33 volumio sudo[11941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 13 11:18:33 volumio sudo[11941]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 11:18:33 volumio sudo[11941]: pam_unix(sudo:session): session closed for user root May 13 11:18:33 volumio sudo[11944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 13 11:18:33 volumio sudo[11944]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 11:18:33 volumio sudo[11944]: pam_unix(sudo:session): session closed for user root May 13 11:18:33 volumio volumio[864]: verbose: New Socket.io Connection to 192.168.1.8 from 192.168.1.6 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 15_8_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 May 13 11:18:33 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 13 11:18:34 volumio volumio[864]: info: CoreCommandRouter::volumioGetVisibleSources May 13 11:18:34 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 11:18:34 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:18:34 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 13 11:18:34 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 13 11:18:34 volumio volumio[864]: info: Received Get System Info May 13 11:18:34 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 11:18:34 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 11:18:34 volumio volumio[864]: info: Discovery: Getting this device information May 13 11:18:34 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:18:34 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 11:18:34 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:18:34 volumio volumio[864]: info: Listing playlists May 13 11:18:34 volumio sudo[11950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 13 11:18:34 volumio sudo[11950]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 11:18:34 volumio sudo[11950]: pam_unix(sudo:session): session closed for user root May 13 11:18:34 volumio sudo[11953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 13 11:18:34 volumio sudo[11953]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 11:18:34 volumio sudo[11953]: pam_unix(sudo:session): session closed for user root May 13 11:18:34 volumio volumio[864]: verbose: New Socket.io Connection to 192.168.1.8 from 192.168.1.6 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 15_8_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 May 13 11:18:35 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 13 11:18:35 volumio volumio[864]: info: CoreCommandRouter::volumioGetVisibleSources May 13 11:18:35 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 11:18:35 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:18:35 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 13 11:18:35 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 13 11:18:35 volumio volumio[864]: info: Received Get System Info May 13 11:18:35 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 11:18:35 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 11:18:35 volumio volumio[864]: info: Discovery: Getting this device information May 13 11:18:35 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:18:35 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 11:18:35 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:18:35 volumio volumio[864]: info: Listing playlists May 13 11:18:35 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 13 11:18:35 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 13 11:18:35 volumio volumio[864]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/V%C5%A9./B%C6%B0%E1%BB%9Bc%20Qua%20Nhau/680a3622-b739-49bf-85ed-bcd6e21ffbab.jpg' May 13 11:18:36 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 13 11:18:36 volumio volumio[864]: info: Received Get System Info May 13 11:18:36 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 11:18:36 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 11:18:36 volumio volumio[864]: info: Discovery: Getting this device information May 13 11:18:36 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:18:36 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 11:18:36 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 13 11:18:37 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 13 11:18:37 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 13 11:18:37 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 13 11:18:39 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 13 11:18:39 volumio volumio[864]: info: Received Get System Info May 13 11:18:39 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 11:18:39 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 11:18:39 volumio volumio[864]: info: Discovery: Getting this device information May 13 11:18:39 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:18:39 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 11:18:42 volumio volumio[864]: info: CoreCommandRouter::volumioPlay May 13 11:18:42 volumio volumio[864]: info: CoreStateMachine::play index undefined May 13 11:18:42 volumio volumio[864]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 11:18:42 volumio volumio[864]: info: CorePlayQueue::getTrack 0 May 13 11:18:43 volumio ntpd[684]: Soliciting pool server 115.165.161.155 May 13 11:18:43 volumio ntpd[684]: Soliciting pool server 115.165.161.155 May 13 11:18:43 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 13 11:18:43 volumio volumio[864]: info: Received Get System Info May 13 11:18:43 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 11:18:43 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 11:18:43 volumio volumio[864]: info: Discovery: Getting this device information May 13 11:18:43 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:18:43 volumio volumio[864]: info: CorePlayQueue::getTrack 0 May 13 11:18:43 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 11:18:43 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 13 11:18:43 volumio volumio[864]: info: Received Get System Info May 13 11:18:43 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 11:18:43 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 11:18:43 volumio volumio[864]: info: Discovery: Getting this device information May 13 11:18:43 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:18:43 volumio volumio[864]: info: CorePlayQueue::getTrack 0 May 13 11:18:43 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 11:18:44 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 13 11:18:44 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 11:18:44 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 11:18:44 volumio volumio[864]: info: Discovery: Getting this device information May 13 11:18:44 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:18:44 volumio volumio[864]: info: CorePlayQueue::getTrack 0 May 13 11:18:44 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 11:19:00 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 13 11:19:00 volumio volumio[864]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 13 11:19:00 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 13 11:19:00 volumio volumio[864]: info: Received Get System Version May 13 11:19:00 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 13 11:19:00 volumio volumio[864]: info: Received Get System Info May 13 11:19:00 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 11:19:00 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 11:19:00 volumio volumio[864]: info: Discovery: Getting this device information May 13 11:19:00 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:19:00 volumio volumio[864]: info: CorePlayQueue::getTrack 0 May 13 11:19:00 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 11:19:09 volumio ntpd[684]: Soliciting pool server 2404:fbc0:0:2046::c May 13 11:19:16 volumio ntpd[684]: Soliciting pool server 27.71.27.209 May 13 11:19:26 volumio volumio[864]: error: Upnp client error: Error: This socket has been ended by the other party May 13 11:19:34 volumio volumio[864]: info: Clearing queue after UPNP request May 13 11:19:34 volumio volumio[864]: info: CoreStateMachine::ClearQueue May 13 11:19:34 volumio volumio[864]: info: CoreStateMachine::stop May 13 11:19:34 volumio volumio[864]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 11:19:34 volumio volumio[864]: info: CorePlayQueue::clearPlayQueue May 13 11:19:34 volumio volumio[864]: info: CorePlayQueue::saveQueue May 13 11:19:34 volumio volumio[864]: info: CoreCommandRouter::volumioPushState May 13 11:19:34 volumio volumio[864]: info: CoreCommandRouter::volumioPushQueue May 13 11:19:34 volumio volumio[864]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 13 11:19:34 volumio volumio[864]: info: May 13 11:19:34 volumio volumio[864]: ---------------------------- MPD announces system playlist update May 13 11:19:34 volumio volumio[864]: info: Ignoring MPD Status Update May 13 11:19:34 volumio volumio[864]: error: updateQueue error: null May 13 11:19:34 volumio volumio[864]: info: ------------------------------ 1ms May 13 11:19:34 volumio volumio[864]: info: Starting UPNP Playback May 13 11:19:34 volumio volumio[864]: info: Preparing playback through UPNP May 13 11:19:34 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:19:34 volumio volumio[864]: info: CorePlayQueue::getTrack 0 May 13 11:19:34 volumio volumio[864]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 11:19:35 volumio volumio[864]: info: May 13 11:19:35 volumio volumio[864]: ---------------------------- MPD announces system playlist update May 13 11:19:35 volumio volumio[864]: info: Ignoring MPD Status Update May 13 11:19:35 volumio volumio[864]: info: ------------------------------ 1ms May 13 11:19:35 volumio volumio[864]: info: May 13 11:19:35 volumio volumio[864]: ---------------------------- MPD announces system playlist update May 13 11:19:35 volumio volumio[864]: info: Ignoring MPD Status Update May 13 11:19:35 volumio volumio[864]: info: ------------------------------ 0ms May 13 11:19:35 volumio volumio[864]: info: May 13 11:19:35 volumio volumio[864]: ---------------------------- MPD announces system playlist update May 13 11:19:35 volumio volumio[864]: info: Ignoring MPD Status Update May 13 11:19:35 volumio volumio[864]: info: ------------------------------ 0ms May 13 11:19:35 volumio volumio[864]: info: May 13 11:19:35 volumio volumio[864]: ---------------------------- MPD announces system playlist update May 13 11:19:35 volumio volumio[864]: info: Ignoring MPD Status Update May 13 11:19:35 volumio volumio[864]: info: ------------------------------ 1ms May 13 11:19:35 volumio volumio[864]: info: May 13 11:19:35 volumio volumio[864]: ---------------------------- MPD announces system playlist update May 13 11:19:35 volumio volumio[864]: info: Ignoring MPD Status Update May 13 11:19:35 volumio volumio[864]: info: ------------------------------ 1ms May 13 11:19:35 volumio volumio[864]: info: May 13 11:19:35 volumio volumio[864]: ---------------------------- MPD announces system playlist update May 13 11:19:35 volumio volumio[864]: info: Ignoring MPD Status Update May 13 11:19:35 volumio volumio[864]: info: ------------------------------ 1ms May 13 11:19:35 volumio volumio[864]: info: May 13 11:19:35 volumio volumio[864]: ---------------------------- MPD announces state update: player May 13 11:19:35 volumio volumio[864]: info: ControllerMpd::getState May 13 11:19:35 volumio volumio[864]: verbose: ControllerMpd::sendMpdCommand status May 13 11:19:35 volumio volumio[864]: info: May 13 11:19:35 volumio volumio[864]: ---------------------------- MPD announces state update: player May 13 11:19:35 volumio volumio[864]: info: sendMpdCommand status took 9 milliseconds May 13 11:19:35 volumio volumio[864]: info: ControllerMpd::getState May 13 11:19:35 volumio volumio[864]: verbose: ControllerMpd::sendMpdCommand status May 13 11:19:35 volumio volumio[864]: verbose: ControllerMpd::parseState May 13 11:19:35 volumio volumio[864]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 11:19:35 volumio volumio[864]: info: sendMpdCommand status took 1 milliseconds May 13 11:19:35 volumio volumio[864]: info: sendMpdCommand playlistinfo took 0 milliseconds May 13 11:19:35 volumio volumio[864]: verbose: ControllerMpd::parseState May 13 11:19:35 volumio volumio[864]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 11:19:35 volumio volumio[864]: verbose: ControllerMpd::parseTrackInfo May 13 11:19:35 volumio volumio[864]: info: ControllerMpd::pushState May 13 11:19:35 volumio volumio[864]: info: CoreCommandRouter::servicePushState May 13 11:19:35 volumio volumio[864]: verbose: In UPNP mode May 13 11:19:35 volumio volumio[864]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thói Quen (25 Mét Vuông) [feat. Gducky]","artist":"Hoàng Dũng","album":"25 Mét Vuông","uri":"http://192.168.1.6:15798/tidal/209433211.flac","trackType":"tidal"} May 13 11:19:35 volumio volumio[864]: verbose: CURRENT POSITION 0 May 13 11:19:35 volumio volumio[864]: info: CoreStateMachine::syncState stateService play May 13 11:19:35 volumio volumio[864]: info: CoreStateMachine::syncState currentStatus stop May 13 11:19:35 volumio volumio[864]: info: ------------------------------ 11ms May 13 11:19:35 volumio volumio[864]: info: sendMpdCommand playlistinfo took 2 milliseconds May 13 11:19:35 volumio volumio[864]: verbose: ControllerMpd::parseTrackInfo May 13 11:19:35 volumio volumio[864]: info: ControllerMpd::pushState May 13 11:19:35 volumio volumio[864]: info: CoreCommandRouter::servicePushState May 13 11:19:35 volumio volumio[864]: verbose: In UPNP mode May 13 11:19:35 volumio volumio[864]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thói Quen (25 Mét Vuông) [feat. Gducky]","artist":"Hoàng Dũng","album":"25 Mét Vuông","uri":"http://192.168.1.6:15798/tidal/209433211.flac","trackType":"tidal"} May 13 11:19:35 volumio volumio[864]: verbose: CURRENT POSITION 0 May 13 11:19:35 volumio volumio[864]: info: CoreStateMachine::syncState stateService play May 13 11:19:35 volumio volumio[864]: info: CoreStateMachine::syncState currentStatus play May 13 11:19:35 volumio volumio[864]: info: Received an update from plugin. extracting info from payload May 13 11:19:35 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 11:19:35 volumio volumio[864]: info: CoreStateMachine::pushState May 13 11:19:35 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 11:19:35 volumio volumio[864]: info: CoreCommandRouter::volumioPushState May 13 11:19:35 volumio volumio[864]: info: CoreStateMachine::pushState May 13 11:19:35 volumio volumio[864]: info: CoreCommandRouter::volumioPushState May 13 11:19:35 volumio volumio[864]: info: ------------------------------ 10ms May 13 11:19:35 volumio volumio[864]: info: CoreCommandRouter::volumioGetQueue May 13 11:19:35 volumio volumio[864]: info: CoreStateMachine::getQueue May 13 11:19:35 volumio volumio[864]: info: CorePlayQueue::getQueue May 13 11:19:35 volumio volumio[864]: info: CoreCommandRouter::volumioGetQueue May 13 11:19:35 volumio volumio[864]: info: CoreStateMachine::getQueue May 13 11:19:35 volumio volumio[864]: info: CorePlayQueue::getQueue May 13 11:19:35 volumio volumio[864]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 13 11:19:35 volumio volumio[864]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 13 11:19:45 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 11:19:45 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 11:19:45 volumio volumio[864]: info: Discovery: Getting this device information May 13 11:19:45 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:19:45 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 11:19:45 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 11:19:45 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 11:19:45 volumio volumio[864]: info: Discovery: Getting this device information May 13 11:19:45 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:19:45 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 11:19:46 volumio volumio[864]: verbose: New Socket.io Connection to 192.168.1.8:3000 from 192.168.1.6 UA: Dart/3.7 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 May 13 11:19:48 volumio ntpd[684]: Soliciting pool server 103.70.115.65 May 13 11:19:49 volumio ntpd[684]: Soliciting pool server 103.70.115.65 May 13 11:19:51 volumio volumio[864]: verbose: New Socket.io Connection to 192.168.1.8 from 192.168.1.6 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 15_8_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 May 13 11:19:51 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 13 11:19:51 volumio volumio[864]: info: CoreCommandRouter::volumioGetVisibleSources May 13 11:19:51 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 11:19:51 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:19:51 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 13 11:19:51 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 13 11:19:51 volumio volumio[864]: info: Received Get System Info May 13 11:19:51 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 11:19:51 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 11:19:51 volumio volumio[864]: info: Discovery: Getting this device information May 13 11:19:51 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:19:51 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 11:19:51 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:19:51 volumio volumio[864]: info: Listing playlists May 13 11:19:51 volumio volumio[864]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ho%C3%A0ng%20D%C5%A9ng/25%20M%C3%A9t%20Vu%C3%B4ng/aac1a9e1-c0bb-45dd-b0f7-8bd4c2417243.jpg' May 13 11:19:51 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 13 11:19:52 volumio volumio[864]: info: May 13 11:19:52 volumio volumio[864]: ---------------------------- MPD announces state update: player May 13 11:19:52 volumio volumio[864]: info: ControllerMpd::getState May 13 11:19:52 volumio volumio[864]: verbose: ControllerMpd::sendMpdCommand status May 13 11:19:52 volumio volumio[864]: info: sendMpdCommand status took 0 milliseconds May 13 11:19:52 volumio volumio[864]: verbose: ControllerMpd::parseState May 13 11:19:52 volumio volumio[864]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 11:19:52 volumio volumio[864]: info: sendMpdCommand playlistinfo took 0 milliseconds May 13 11:19:52 volumio volumio[864]: verbose: ControllerMpd::parseTrackInfo May 13 11:19:52 volumio volumio[864]: info: ControllerMpd::pushState May 13 11:19:52 volumio volumio[864]: info: CoreCommandRouter::servicePushState May 13 11:19:52 volumio volumio[864]: info: CoreStateMachine::pushState May 13 11:19:52 volumio volumio[864]: info: CoreCommandRouter::volumioPushState May 13 11:19:52 volumio volumio[864]: info: CorePlayQueue::getTrack 0 May 13 11:19:52 volumio volumio[864]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thói Quen (25 Mét Vuông) [feat. Gducky]","artist":"Hoàng Dũng","album":"25 Mét Vuông","uri":"http://192.168.1.6:15798/tidal/209433211.flac","trackType":"tidal"} May 13 11:19:52 volumio volumio[864]: verbose: CURRENT POSITION 0 May 13 11:19:52 volumio volumio[864]: info: CoreStateMachine::syncState stateService stop May 13 11:19:52 volumio volumio[864]: info: CoreStateMachine::syncState currentStatus play May 13 11:19:52 volumio volumio[864]: info: CoreCommandRouter::volumioPushState May 13 11:19:52 volumio volumio[864]: info: CoreStateMachine::stPlaybackTimer May 13 11:19:52 volumio volumio[864]: info: ------------------------------ 7ms May 13 11:19:52 volumio volumio[864]: info: CoreCommandRouter::volumioGetQueue May 13 11:19:52 volumio volumio[864]: info: CoreStateMachine::getQueue May 13 11:19:52 volumio volumio[864]: info: CorePlayQueue::getQueue May 13 11:19:52 volumio volumio[864]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 13 11:19:52 volumio volumio[864]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ho%C3%A0ng%20D%C5%A9ng/25%20M%C3%A9t%20Vu%C3%B4ng/aac1a9e1-c0bb-45dd-b0f7-8bd4c2417243.jpg' May 13 11:19:54 volumio volumio[864]: info: Clearing queue after UPNP request May 13 11:19:54 volumio volumio[864]: info: CoreStateMachine::ClearQueue May 13 11:19:54 volumio volumio[864]: info: CoreStateMachine::stop May 13 11:19:54 volumio volumio[864]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 11:19:54 volumio volumio[864]: info: CorePlayQueue::clearPlayQueue May 13 11:19:54 volumio volumio[864]: info: CorePlayQueue::saveQueue May 13 11:19:54 volumio volumio[864]: info: CoreCommandRouter::volumioPushState May 13 11:19:54 volumio volumio[864]: info: CoreCommandRouter::volumioPushQueue May 13 11:19:54 volumio volumio[864]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 13 11:19:54 volumio volumio[864]: info: May 13 11:19:54 volumio volumio[864]: ---------------------------- MPD announces system playlist update May 13 11:19:54 volumio volumio[864]: info: Ignoring MPD Status Update May 13 11:19:54 volumio volumio[864]: error: updateQueue error: null May 13 11:19:54 volumio volumio[864]: info: ------------------------------ 1ms May 13 11:19:54 volumio volumio[864]: info: Starting UPNP Playback May 13 11:19:54 volumio volumio[864]: info: Preparing playback through UPNP May 13 11:19:54 volumio volumio[864]: info: CoreCommandRouter::volumioGetState May 13 11:19:54 volumio volumio[864]: info: CorePlayQueue::getTrack 0 May 13 11:19:54 volumio volumio[864]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 11:19:54 volumio volumio[864]: info: May 13 11:19:54 volumio volumio[864]: ---------------------------- MPD announces system playlist update May 13 11:19:54 volumio volumio[864]: info: Ignoring MPD Status Update May 13 11:19:54 volumio volumio[864]: info: ------------------------------ 1ms May 13 11:19:54 volumio volumio[864]: info: May 13 11:19:54 volumio volumio[864]: ---------------------------- MPD announces system playlist update May 13 11:19:54 volumio volumio[864]: info: Ignoring MPD Status Update May 13 11:19:54 volumio volumio[864]: info: ------------------------------ 1ms May 13 11:19:54 volumio volumio[864]: info: May 13 11:19:54 volumio volumio[864]: ---------------------------- MPD announces system playlist update May 13 11:19:54 volumio volumio[864]: info: Ignoring MPD Status Update May 13 11:19:54 volumio volumio[864]: info: ------------------------------ 1ms May 13 11:19:54 volumio volumio[864]: info: May 13 11:19:54 volumio volumio[864]: ---------------------------- MPD announces system playlist update May 13 11:19:54 volumio volumio[864]: info: Ignoring MPD Status Update May 13 11:19:55 volumio volumio[864]: info: May 13 11:19:55 volumio volumio[864]: ---------------------------- MPD announces system playlist update May 13 11:19:55 volumio volumio[864]: info: Ignoring MPD Status Update May 13 11:19:55 volumio volumio[864]: info: ------------------------------ 1ms May 13 11:19:55 volumio volumio[864]: info: ------------------------------ 0ms May 13 11:19:55 volumio volumio[864]: info: May 13 11:19:55 volumio volumio[864]: ---------------------------- MPD announces system playlist update May 13 11:19:55 volumio volumio[864]: info: Ignoring MPD Status Update May 13 11:19:55 volumio volumio[864]: info: ------------------------------ 0ms May 13 11:19:55 volumio volumio[864]: info: May 13 11:19:55 volumio volumio[864]: ---------------------------- MPD announces state update: player May 13 11:19:55 volumio volumio[864]: info: ControllerMpd::getState May 13 11:19:55 volumio volumio[864]: verbose: ControllerMpd::sendMpdCommand status May 13 11:19:55 volumio volumio[864]: info: sendMpdCommand status took 9 milliseconds May 13 11:19:55 volumio volumio[864]: verbose: ControllerMpd::parseState May 13 11:19:55 volumio volumio[864]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 11:19:55 volumio volumio[864]: info: May 13 11:19:55 volumio volumio[864]: ---------------------------- MPD announces state update: player May 13 11:19:55 volumio volumio[864]: info: ControllerMpd::getState May 13 11:19:55 volumio volumio[864]: verbose: ControllerMpd::sendMpdCommand status May 13 11:19:55 volumio volumio[864]: info: sendMpdCommand playlistinfo took 4 milliseconds May 13 11:19:55 volumio volumio[864]: info: sendMpdCommand status took 4 milliseconds May 13 11:19:55 volumio volumio[864]: verbose: ControllerMpd::parseTrackInfo May 13 11:19:55 volumio volumio[864]: verbose: ControllerMpd::parseState May 13 11:19:55 volumio volumio[864]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 11:19:55 volumio volumio[864]: info: ControllerMpd::pushState May 13 11:19:55 volumio volumio[864]: info: CoreCommandRouter::servicePushState May 13 11:19:55 volumio volumio[864]: verbose: In UPNP mode May 13 11:19:55 volumio volumio[864]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1218 Kbps","isStreaming":false,"title":"Và Thế Là Hết","artist":"Chillies","album":"Và Thế Là Hết","uri":"http://192.168.1.6:15798/tidal/135375311.flac","trackType":"tidal"} May 13 11:19:55 volumio volumio[864]: verbose: CURRENT POSITION 0 May 13 11:19:55 volumio volumio[864]: info: CoreStateMachine::syncState stateService play May 13 11:19:55 volumio volumio[864]: info: CoreStateMachine::syncState currentStatus stop May 13 11:19:55 volumio volumio[864]: info: ------------------------------ 18ms May 13 11:19:55 volumio volumio[864]: info: sendMpdCommand playlistinfo took 3 milliseconds May 13 11:19:55 volumio volumio[864]: verbose: ControllerMpd::parseTrackInfo May 13 11:19:55 volumio volumio[864]: info: ControllerMpd::pushState May 13 11:19:55 volumio volumio[864]: info: CoreCommandRouter::servicePushState May 13 11:19:55 volumio volumio[864]: verbose: In UPNP mode May 13 11:19:55 volumio volumio[864]: verbose: STATE SERVICE {"status":"play","position":0,"seek":376,"duration":292,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1217 Kbps","isStreaming":false,"title":"Và Thế Là Hết","artist":"Chillies","album":"Và Thế Là Hết","uri":"http://192.168.1.6:15798/tidal/135375311.flac","trackType":"tidal"} May 13 11:19:55 volumio volumio[864]: verbose: CURRENT POSITION 0 May 13 11:19:55 volumio volumio[864]: info: CoreStateMachine::syncState stateService play May 13 11:19:55 volumio volumio[864]: info: CoreStateMachine::syncState currentStatus play May 13 11:19:55 volumio volumio[864]: info: Received an update from plugin. extracting info from payload May 13 11:19:55 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 11:19:55 volumio volumio[864]: info: CoreStateMachine::pushState May 13 11:19:55 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 11:19:55 volumio volumio[864]: info: CoreCommandRouter::volumioPushState May 13 11:19:55 volumio volumio[864]: info: CoreStateMachine::pushState May 13 11:19:55 volumio volumio[864]: info: CoreCommandRouter::volumioPushState May 13 11:19:55 volumio volumio[864]: info: ------------------------------ 14ms May 13 11:19:55 volumio volumio[864]: info: CoreCommandRouter::volumioGetQueue May 13 11:19:55 volumio volumio[864]: info: CoreStateMachine::getQueue May 13 11:19:55 volumio volumio[864]: info: CorePlayQueue::getQueue May 13 11:19:55 volumio volumio[864]: info: CoreCommandRouter::volumioGetQueue May 13 11:19:55 volumio volumio[864]: info: CoreStateMachine::getQueue May 13 11:19:55 volumio volumio[864]: info: CorePlayQueue::getQueue May 13 11:19:55 volumio volumio[864]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 13 11:19:55 volumio volumio[864]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 13 11:19:55 volumio volumio[864]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 11:19:55 volumio volumio[864]: Error: connect ETIMEDOUT 151.101.78.79:443 May 13 11:19:55 volumio volumio[864]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 13 11:19:55 volumio volumio[864]: errno: -110, May 13 11:19:55 volumio volumio[864]: code: 'ETIMEDOUT', May 13 11:19:55 volumio volumio[864]: syscall: 'connect', May 13 11:19:55 volumio volumio[864]: address: '151.101.78.79', May 13 11:19:55 volumio volumio[864]: port: 443 May 13 11:19:55 volumio volumio[864]: } May 13 11:19:55 volumio volumio[864]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 11:19:55 volumio sudo[12255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-13 11:18 May 13 11:19:55 volumio sudo[12255]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 12:21:23 PM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a9588e47e4b05f1a05bf70b4acc7555c"