-- Logs begin at Sun 2025-05-04 17:20:18 CST, end at Sun 2025-05-04 17:35:42 CST. -- May 04 17:34:00 volumiozb ntpd[5636]: Soliciting pool server 81.168.83.93 May 04 17:34:00 volumiozb ntpd[5636]: Soliciting pool server 162.159.200.1 May 04 17:34:01 volumiozb ntpd[5636]: Soliciting pool server 193.182.111.141 May 04 17:34:02 volumiozb ntpd[5636]: Soliciting pool server 202.118.1.130 May 04 17:34:03 volumiozb volumio[1159]: info: Volumio Network Manager: Network status updated: 2 May 04 17:34:03 volumiozb ntpd[5636]: Soliciting pool server 45.76.221.157 May 04 17:34:03 volumiozb sudo[5669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 04 17:34:03 volumiozb sudo[5669]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 17:34:03 volumiozb sudo[5669]: pam_unix(sudo:session): session closed for user root May 04 17:34:03 volumiozb sudo[5673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 17:34:03 volumiozb sudo[5673]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 17:34:03 volumiozb sudo[5673]: pam_unix(sudo:session): session closed for user root May 04 17:34:03 volumiozb volumio[1159]: verbose: New Socket.io Connection to 192.168.0.111 from 192.168.0.105 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 May 04 17:34:03 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 04 17:34:04 volumiozb sudo[5678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 04 17:34:04 volumiozb sudo[5678]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 17:34:04 volumiozb sudo[5678]: pam_unix(sudo:session): session closed for user root May 04 17:34:04 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 04 17:34:04 volumiozb sudo[5682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 17:34:04 volumiozb sudo[5682]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 17:34:04 volumiozb sudo[5682]: pam_unix(sudo:session): session closed for user root May 04 17:34:04 volumiozb volumio[1159]: verbose: New Socket.io Connection to 192.168.0.111 from 192.168.0.105 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 May 04 17:34:04 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 04 17:34:04 volumiozb volumio[1159]: info: CoreCommandRouter::volumioGetVisibleSources May 04 17:34:04 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 17:34:04 volumiozb volumio[1159]: info: CoreCommandRouter::volumioGetState May 04 17:34:04 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:04 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 04 17:34:04 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 04 17:34:04 volumiozb volumio[1159]: info: Received Get System Info May 04 17:34:04 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 17:34:04 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 17:34:04 volumiozb volumio[1159]: info: Discovery: Getting this device information May 04 17:34:04 volumiozb volumio[1159]: info: CoreCommandRouter::volumioGetState May 04 17:34:04 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:04 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 17:34:04 volumiozb volumio[1159]: info: CoreCommandRouter::volumioGetState May 04 17:34:04 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:04 volumiozb volumio[1159]: info: Listing playlists May 04 17:34:04 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 04 17:34:04 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 04 17:34:04 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 04 17:34:05 volumiozb volumio[1159]: info: CoreCommandRouter::volumioGetState May 04 17:34:05 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:05 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 04 17:34:06 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 04 17:34:06 volumiozb volumio[1159]: info: Received Get System Info May 04 17:34:06 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 17:34:06 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 17:34:06 volumiozb volumio[1159]: info: Discovery: Getting this device information May 04 17:34:06 volumiozb volumio[1159]: info: CoreCommandRouter::volumioGetState May 04 17:34:06 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:06 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 17:34:07 volumiozb volumio[1159]: info: Retrieving Cloud Streaming UI May 04 17:34:07 volumiozb volumio[1159]: info: Getting Tidal Cloud Configuration May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 17:34:07 volumiozb volumio[1159]: info: Getting Qobuz Cloud Configuration May 04 17:34:07 volumiozb volumio[1159]: info: Asking plugin for UI Config May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 17:34:07 volumiozb volumio[1159]: info: Getting Spotify Cloud Configuration May 04 17:34:07 volumiozb volumio[1159]: info: Asking plugin for UI Config May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 17:34:07 volumiozb volumio[1159]: info: Saving Spotify Acccount May 04 17:34:07 volumiozb volumio[1159]: info: Got Tidal Cloud Configuration May 04 17:34:07 volumiozb volumio[1159]: info: Got it May 04 17:34:07 volumiozb volumio[1159]: info: Got it May 04 17:34:07 volumiozb volumio[1159]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 17:34:07 volumiozb volumio[1159]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::volumioGetBrowseSources May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::volumioGetBrowseSources May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::volumioGetBrowseSources May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 17:34:07 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 04 17:34:09 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 04 17:34:09 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand update May 04 17:34:09 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 04 17:34:09 volumiozb volumio[1159]: info: May 04 17:34:09 volumiozb volumio[1159]: ---------------------------- MPD announces state update: update May 04 17:34:09 volumiozb volumio[1159]: info: sendMpdCommand update took 7 milliseconds May 04 17:34:09 volumiozb volumio[1159]: info: ControllerMpd::getState May 04 17:34:09 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 04 17:34:09 volumiozb volumio[1159]: info: sendMpdCommand status took 7 milliseconds May 04 17:34:09 volumiozb volumio[1159]: info: sendMpdCommand status took 2 milliseconds May 04 17:34:09 volumiozb volumio[1159]: verbose: ControllerMpd::parseState May 04 17:34:09 volumiozb volumio[1159]: info: Command Router : Notfying DB Updatetrue May 04 17:34:09 volumiozb volumio[1159]: verbose: ControllerMpd::parseState May 04 17:34:09 volumiozb volumio[1159]: info: ControllerMpd::pushState May 04 17:34:09 volumiozb volumio[1159]: info: CoreCommandRouter::servicePushState May 04 17:34:09 volumiozb volumio[1159]: info: CoreStateMachine::pushState May 04 17:34:09 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:09 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPushState May 04 17:34:09 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:09 volumiozb volumio[1159]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 04 17:34:09 volumiozb volumio[1159]: verbose: CURRENT POSITION 0 May 04 17:34:09 volumiozb volumio[1159]: info: CoreStateMachine::syncState stateService stop May 04 17:34:09 volumiozb volumio[1159]: info: CoreStateMachine::syncState currentStatus stop May 04 17:34:09 volumiozb volumio[1159]: info: CoreStateMachine::pushState May 04 17:34:09 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:09 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 17:34:09 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPushState May 04 17:34:09 volumiozb volumio[1159]: info: No code May 04 17:34:09 volumiozb volumio[1159]: info: CoreStateMachine::pushState May 04 17:34:09 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:09 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPushState May 04 17:34:09 volumiozb volumio[1159]: info: ------------------------------ 305ms May 04 17:34:09 volumiozb volumio[1159]: info: MPD Database updated - AlbumList cache refreshed May 04 17:34:09 volumiozb volumio[1159]: info: May 04 17:34:09 volumiozb volumio[1159]: ---------------------------- MPD announces state update: database May 04 17:34:09 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 04 17:34:09 volumiozb volumio[1159]: info: May 04 17:34:09 volumiozb volumio[1159]: ---------------------------- MPD announces state update: update May 04 17:34:09 volumiozb volumio[1159]: info: ControllerMpd::getState May 04 17:34:09 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 04 17:34:09 volumiozb volumio[1159]: info: ControllerMpd::getState May 04 17:34:09 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 04 17:34:09 volumiozb volumio[1159]: info: sendMpdCommand status took 25 milliseconds May 04 17:34:09 volumiozb volumio[1159]: info: sendMpdCommand status took 24 milliseconds May 04 17:34:09 volumiozb volumio[1159]: info: sendMpdCommand status took 23 milliseconds May 04 17:34:09 volumiozb volumio[1159]: verbose: ControllerMpd::parseState May 04 17:34:09 volumiozb volumio[1159]: info: Command Router : Notfying DB Updatefalse May 04 17:34:09 volumiozb volumio[1159]: info: CoreCommandRouter::Close All Modals sent May 04 17:34:09 volumiozb volumio[1159]: verbose: ControllerMpd::parseState May 04 17:34:09 volumiozb volumio[1159]: verbose: ControllerMpd::parseState May 04 17:34:09 volumiozb volumio[1159]: info: ControllerMpd::pushState May 04 17:34:09 volumiozb volumio[1159]: info: CoreCommandRouter::servicePushState May 04 17:34:09 volumiozb volumio[1159]: info: CoreStateMachine::pushState May 04 17:34:09 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:09 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPushState May 04 17:34:09 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:09 volumiozb volumio[1159]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 04 17:34:09 volumiozb volumio[1159]: verbose: CURRENT POSITION 0 May 04 17:34:09 volumiozb volumio[1159]: info: CoreStateMachine::syncState stateService stop May 04 17:34:09 volumiozb volumio[1159]: info: CoreStateMachine::syncState currentStatus stop May 04 17:34:09 volumiozb volumio[1159]: info: CoreStateMachine::pushState May 04 17:34:09 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:09 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 17:34:09 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPushState May 04 17:34:09 volumiozb volumio[1159]: info: No code May 04 17:34:09 volumiozb volumio[1159]: info: CoreStateMachine::pushState May 04 17:34:09 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:09 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPushState May 04 17:34:09 volumiozb volumio[1159]: info: ControllerMpd::pushState May 04 17:34:09 volumiozb volumio[1159]: info: CoreCommandRouter::servicePushState May 04 17:34:09 volumiozb volumio[1159]: info: CoreStateMachine::pushState May 04 17:34:09 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:09 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPushState May 04 17:34:09 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:09 volumiozb volumio[1159]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 04 17:34:09 volumiozb volumio[1159]: verbose: CURRENT POSITION 0 May 04 17:34:09 volumiozb volumio[1159]: info: CoreStateMachine::syncState stateService stop May 04 17:34:09 volumiozb volumio[1159]: info: CoreStateMachine::syncState currentStatus stop May 04 17:34:09 volumiozb volumio[1159]: info: CoreStateMachine::pushState May 04 17:34:09 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:09 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPushState May 04 17:34:09 volumiozb volumio[1159]: info: No code May 04 17:34:09 volumiozb volumio[1159]: info: CoreStateMachine::pushState May 04 17:34:09 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:09 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPushState May 04 17:34:09 volumiozb volumio[1159]: info: ------------------------------ 248ms May 04 17:34:09 volumiozb volumio[1159]: info: ------------------------------ 249ms May 04 17:34:11 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 17:34:14 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 04 17:34:16 volumiozb volumio[1159]: info: CoreCommandRouter::volumioGetState May 04 17:34:16 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:16 volumiozb volumio[1159]: info: CoreCommandRouter::volumioGetQueue May 04 17:34:16 volumiozb volumio[1159]: info: CoreStateMachine::getQueue May 04 17:34:16 volumiozb volumio[1159]: info: CorePlayQueue::getQueue May 04 17:34:18 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 17:34:18 volumiozb volumio[1159]: info: CURURI: music-library May 04 17:34:18 volumiozb volumio[1159]: info: Preload queue cleared May 04 17:34:19 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 17:34:19 volumiozb volumio[1159]: info: CURURI: music-library/INTERNAL May 04 17:34:19 volumiozb volumio[1159]: info: Preload queue cleared May 04 17:34:25 volumiozb volumio[1159]: info: CoreCommandRouter::volumioAddQueueItems May 04 17:34:25 volumiozb volumio[1159]: info: CoreStateMachine::addQueueItems May 04 17:34:25 volumiozb volumio[1159]: info: CorePlayQueue::addQueueItems May 04 17:34:25 volumiozb volumio[1159]: info: Preload queue cleared May 04 17:34:25 volumiozb volumio[1159]: info: Adding Item to queue: music-library/INTERNAL/单曲集锦01 (Singles) May 04 17:34:25 volumiozb volumio[1159]: info: Exploding uri music-library/INTERNAL/单曲集锦01 (Singles) in service mpd May 04 17:34:25 volumiozb volumio[1159]: info: ALBUMART /albumart?cacheid=977&web=%E4%BB%BB%E7%B4%A0%E6%B1%90/%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A6%20(Singles)/extralarge&path=%2Fmnt%2FINTERNAL%2F%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A601%20(Singles)%2F%E4%BB%BB%E7%B4%A0%E6%B1%90%20-%20%E6%9E%95%E7%9D%80%E5%85%89%E7%9A%84%E5%A5%B9%2F%E4%BB%BB%E7%B4%A0%E6%B1%90%20-%20%E6%9E%95%E7%9D%80%E5%85%89%E7%9A%84%E5%A5%B9.flac&metadata=false May 04 17:34:25 volumiozb volumio[1159]: info: URI /mnt/INTERNAL/单曲集锦01 (Singles)/任素汐 - 枕着光的她/任素汐 - 枕着光的她.flac May 04 17:34:25 volumiozb volumio[1159]: info: ALBUMART /albumart?cacheid=977&web=%E5%80%AA%E9%9B%85%E4%B8%B0/%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A6%20(Singles)/extralarge&path=%2Fmnt%2FINTERNAL%2F%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A601%20(Singles)%2F%E5%80%AA%E9%9B%85%E4%B8%B0%20-%20%E5%A5%B3%E5%AD%A9%E4%B8%8E%E5%9B%9B%E9%87%8D%E5%A5%8F%2F%E5%80%AA%E9%9B%85%E4%B8%B0%20-%20%E5%A5%B3%E5%AD%A9%E4%B8%8E%E5%9B%9B%E9%87%8D%E5%A5%8F.flac&metadata=false May 04 17:34:25 volumiozb volumio[1159]: info: URI /mnt/INTERNAL/单曲集锦01 (Singles)/倪雅丰 - 女孩与四重奏/倪雅丰 - 女孩与四重奏.flac May 04 17:34:25 volumiozb volumio[1159]: info: ALBUMART /albumart?cacheid=977&web=%E5%8D%95%E4%BE%9D%E7%BA%AF/%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A6%20(Singles)/extralarge&path=%2Fmnt%2FINTERNAL%2F%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A601%20(Singles)%2F%E5%8D%95%E4%BE%9D%E7%BA%AF%20-%204ever%20in%20U%2F%E5%8D%95%E4%BE%9D%E7%BA%AF%20-%204ever%20in%20U.wav&metadata=false May 04 17:34:25 volumiozb volumio[1159]: info: URI /mnt/INTERNAL/单曲集锦01 (Singles)/单依纯 - 4ever in U/单依纯 - 4ever in U.wav May 04 17:34:25 volumiozb volumio[1159]: info: ALBUMART /albumart?cacheid=977&web=%E5%8D%97%E6%8B%B3%E5%AA%BD%E5%AA%BD%E5%BD%88%E9%A0%AD/%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A6%20(Singles)/extralarge&path=%2Fmnt%2FINTERNAL%2F%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A601%20(Singles)%2F%E5%8D%97%E6%8B%B3%E5%AA%BD%E5%AA%BD%E5%BD%88%E9%A0%AD%20-%20%E8%91%97%E6%B6%BC%20%5BQobuz%5D%2F%E5%8D%97%E6%8B%B3%E5%AA%BD%E5%AA%BD%E5%BD%88%E9%A0%AD%20-%20%E8%91%97%E6%B6%BC%20%5BQobuz%5D.flac&metadata=false May 04 17:34:25 volumiozb volumio[1159]: info: URI /mnt/INTERNAL/单曲集锦01 (Singles)/南拳媽媽彈頭 - 著涼 [Qobuz]/南拳媽媽彈頭 - 著涼 [Qobuz].flac May 04 17:34:25 volumiozb volumio[1159]: info: ALBUMART /albumart?cacheid=977&web=%E5%BC%A0%E4%BF%A1%E5%93%B2/%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A6%20(Singles)/extralarge&path=%2Fmnt%2FINTERNAL%2F%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A601%20(Singles)%2F%E5%BC%A0%E4%BF%A1%E5%93%B2%20-%20%E6%B2%A1%E8%B5%84%E6%A0%BC%E9%9A%BE%E8%BF%87%20%5B24-48%5D%2F%E5%BC%A0%E4%BF%A1%E5%93%B2%20-%20%E6%B2%A1%E8%B5%84%E6%A0%BC%E9%9A%BE%E8%BF%87.wav&metadata=false May 04 17:34:25 volumiozb volumio[1159]: info: URI /mnt/INTERNAL/单曲集锦01 (Singles)/张信哲 - 没资格难过 [24-48]/张信哲 - 没资格难过.wav May 04 17:34:25 volumiozb volumio[1159]: info: ALBUMART /albumart?cacheid=977&web=%E6%B5%B7%E6%9D%A5%E9%98%BF%E6%9C%A8%20%26%20%E5%8D%95%E4%BE%9D%E7%BA%AF/%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A6%20(Singles)/extralarge&path=%2Fmnt%2FINTERNAL%2F%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A601%20(Singles)%2F%E6%B5%B7%E6%9D%A5%E9%98%BF%E6%9C%A8%20%26%20%E5%8D%95%E4%BE%9D%E7%BA%AF%20-%20%E4%B8%8D%E5%A6%82%E8%A7%81%E4%B8%80%E9%9D%A2%2F%E6%B5%B7%E6%9D%A5%E9%98%BF%E6%9C%A8%20%26%20%E5%8D%95%E4%BE%9D%E7%BA%AF%20-%20%E4%B8%8D%E5%A6%82%E8%A7%81%E4%B8%80%E9%9D%A2.flac&metadata=false May 04 17:34:25 volumiozb volumio[1159]: info: URI /mnt/INTERNAL/单曲集锦01 (Singles)/海来阿木 & 单依纯 - 不如见一面/海来阿木 & 单依纯 - 不如见一面.flac May 04 17:34:25 volumiozb volumio[1159]: info: ALBUMART /albumart?cacheid=977&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A601%20(Singles)%2F%E7%B1%B3%E6%B4%A5%E7%8E%84%E5%B8%AB%20%E2%80%93%20Lemon%20(DFF)%2F%E7%B1%B3%E6%B4%A5%E7%8E%84%E5%B8%AB%20-%20Lemon.dff&metadata=false May 04 17:34:25 volumiozb volumio[1159]: info: URI /mnt/INTERNAL/单曲集锦01 (Singles)/米津玄師 – Lemon (DFF)/米津玄師 - Lemon.dff May 04 17:34:25 volumiozb volumio[1159]: info: ALBUMART /albumart?cacheid=977&web=Karen%20Mok/%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A6%20(Singles)/extralarge&path=%2Fmnt%2FINTERNAL%2F%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A601%20(Singles)%2F%E8%8E%AB%E6%96%87%E8%94%9A%20-%20%E8%BF%99%E4%B8%96%E7%95%8C%E9%82%A3%E4%B9%88%E5%A4%9A%E4%BA%BA%20(24-48)%2F%E8%8E%AB%E6%96%87%E8%94%9A%20-%20%E8%BF%99%E4%B8%96%E7%95%8C%E9%82%A3%E4%B9%88%E5%A4%9A%E4%BA%BA%20(24-48).flac&metadata=false May 04 17:34:25 volumiozb volumio[1159]: info: URI /mnt/INTERNAL/单曲集锦01 (Singles)/莫文蔚 - 这世界那么多人 (24-48)/莫文蔚 - 这世界那么多人 (24-48).flac May 04 17:34:25 volumiozb volumio[1159]: info: ALBUMART /albumart?cacheid=977&web=%E8%B5%B5%E8%81%AA/%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A6%20(Singles)/extralarge&path=%2Fmnt%2FINTERNAL%2F%E5%8D%95%E6%9B%B2%E9%9B%86%E9%94%A601%20(Singles)%2F%E8%B5%B5%E8%81%AA%20-%20%E6%98%A5%E6%B1%9F%E8%8A%B1%E6%9C%88%E5%A4%9C%20(DSF)%2F%E8%B5%B5%E8%81%AA%20-%20%E6%98%A5%E6%B1%9F%E8%8A%B1%E6%9C%88%E5%A4%9C.dsf&metadata=false May 04 17:34:25 volumiozb volumio[1159]: info: URI /mnt/INTERNAL/单曲集锦01 (Singles)/赵聪 - 春江花月夜 (DSF)/赵聪 - 春江花月夜.dsf May 04 17:34:25 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPushQueue May 04 17:34:25 volumiozb volumio[1159]: info: CorePlayQueue::saveQueue May 04 17:34:25 volumiozb volumio[1159]: info: CoreStateMachine::updateTrackBlock May 04 17:34:25 volumiozb volumio[1159]: info: CorePlayQueue::getTrackBlock May 04 17:34:32 volumiozb volumio[1159]: info: CoreCommandRouter::volumioGetState May 04 17:34:32 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:33 volumiozb wpa_supplicant[3185]: wlan0: CTRL-EVENT-DISCONNECTED bssid=0c:72:2c:fc:8a:17 reason=0 locally_generated=1 May 04 17:34:33 volumiozb dhcpcd[1044]: wlan0: carrier lost May 04 17:34:33 volumiozb wpa_supplicant[3185]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 04 17:34:33 volumiozb wpa_supplicant[3185]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN May 04 17:34:33 volumiozb avahi-daemon[916]: Withdrawing address record for 192.168.0.111 on wlan0. May 04 17:34:33 volumiozb avahi-daemon[916]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.111. May 04 17:34:33 volumiozb avahi-daemon[916]: Interface wlan0.IPv4 no longer relevant for mDNS. May 04 17:34:33 volumiozb volumio[1159]: info: Discovery: A device disappeared from network May 04 17:34:33 volumiozb dhcpcd[1044]: wlan0: deleting route to 192.168.0.0/24 May 04 17:34:33 volumiozb dhcpcd[1044]: wlan0: deleting default route via 192.168.0.1 May 04 17:34:35 volumiozb wpa_supplicant[3185]: wlan0: Trying to associate with 0c:72:2c:fc:8a:17 (SSID='TP-LINK24' freq=2462 MHz) May 04 17:34:35 volumiozb wpa_supplicant[3185]: wlan0: Associated with 0c:72:2c:fc:8a:17 May 04 17:34:35 volumiozb wpa_supplicant[3185]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 04 17:34:35 volumiozb wpa_supplicant[3185]: wlan0: WPA: Key negotiation completed with 0c:72:2c:fc:8a:17 [PTK=CCMP GTK=CCMP] May 04 17:34:35 volumiozb wpa_supplicant[3185]: wlan0: CTRL-EVENT-CONNECTED - Connection to 0c:72:2c:fc:8a:17 completed [id=0 id_str=] May 04 17:34:35 volumiozb dhcpcd[1044]: wlan0: carrier acquired May 04 17:34:35 volumiozb dhcpcd[1044]: wlan0: IAID 9c:f2:de:7c May 04 17:34:35 volumiozb dhcpcd[1044]: wlan0: soliciting an IPv6 router May 04 17:34:35 volumiozb dhcpcd[1044]: wlan0: rebinding lease of 192.168.0.111 May 04 17:34:35 volumiozb dhcpcd[1044]: wlan0: probing address 192.168.0.111/24 May 04 17:34:36 volumiozb ntpd[5636]: Deleting interface #3 wlan0, 192.168.0.111#123, interface stats: received=31, sent=38, dropped=0, active_time=39 secs May 04 17:34:36 volumiozb ntpd[5636]: 45.76.221.157 local addr 192.168.0.111 -> May 04 17:34:36 volumiozb ntpd[5636]: 202.118.1.130 local addr 192.168.0.111 -> May 04 17:34:36 volumiozb ntpd[5636]: 193.182.111.141 local addr 192.168.0.111 -> May 04 17:34:36 volumiozb ntpd[5636]: 78.46.102.180 local addr 192.168.0.111 -> May 04 17:34:36 volumiozb ntpd[5636]: 162.159.200.123 local addr 192.168.0.111 -> May 04 17:34:41 volumiozb dhcpcd[1044]: wlan0: leased 192.168.0.111 for 7200 seconds May 04 17:34:41 volumiozb avahi-daemon[916]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.111. May 04 17:34:41 volumiozb dhcpcd[1044]: wlan0: adding route to 192.168.0.0/24 May 04 17:34:41 volumiozb avahi-daemon[916]: New relevant interface wlan0.IPv4 for mDNS. May 04 17:34:41 volumiozb dhcpcd[1044]: wlan0: adding default route via 192.168.0.1 May 04 17:34:41 volumiozb avahi-daemon[916]: Registering new address record for 192.168.0.111 on wlan0.IPv4. May 04 17:34:41 volumiozb systemd[1]: Starting Cleanup of Temporary Directories... May 04 17:34:41 volumiozb systemd[1]: systemd-tmpfiles-clean.service: Succeeded. May 04 17:34:41 volumiozb systemd[1]: Started Cleanup of Temporary Directories. May 04 17:34:41 volumiozb ntpd[5636]: ntpd exiting on signal 15 (Terminated) May 04 17:34:41 volumiozb systemd[1]: Stopping Network Time Service... May 04 17:34:41 volumiozb systemd[1]: ntp.service: Succeeded. May 04 17:34:41 volumiozb systemd[1]: Stopped Network Time Service. May 04 17:34:41 volumiozb systemd[1]: Starting Network Time Service... May 04 17:34:41 volumiozb ntpd[5845]: ntpd 4.2.8p12@1.3728-o (1): Starting May 04 17:34:41 volumiozb ntpd[5845]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 04 17:34:41 volumiozb systemd[1]: Started Network Time Service. May 04 17:34:41 volumiozb ntpd[5854]: proto: precision = 0.237 usec (-22) May 04 17:34:41 volumiozb ntpd[5854]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 04 17:34:41 volumiozb ntpd[5854]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 04 17:34:41 volumiozb ntpd[5854]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 859 days ago May 04 17:34:41 volumiozb ntpd[5854]: Listen and drop on 0 v6wildcard [::]:123 May 04 17:34:41 volumiozb ntpd[5854]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 04 17:34:41 volumiozb ntpd[5854]: Listen normally on 2 lo 127.0.0.1:123 May 04 17:34:41 volumiozb ntpd[5854]: Listen normally on 3 wlan0 192.168.0.111:123 May 04 17:34:41 volumiozb ntpd[5854]: Listening on routing socket on fd #20 for interface updates May 04 17:34:41 volumiozb ntpd[5854]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 04 17:34:41 volumiozb ntpd[5854]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 04 17:34:42 volumiozb volumio[1159]: info: Discovery: adding 79a4dd61-a63b-490f-a4b6-99b06d50e877 May 04 17:34:42 volumiozb volumio[1159]: info: Discovery: Found device VolumioZB May 04 17:34:42 volumiozb volumio[1159]: info: CoreCommandRouter::volumioGetState May 04 17:34:42 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:42 volumiozb ntpd[5854]: Soliciting pool server 81.168.83.93 May 04 17:34:42 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPlay May 04 17:34:42 volumiozb volumio[1159]: info: CoreStateMachine::play index 0 May 04 17:34:42 volumiozb volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 17:34:42 volumiozb volumio[1159]: info: CoreStateMachine::stop May 04 17:34:42 volumiozb volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 17:34:42 volumiozb volumio[1159]: info: CoreStateMachine::play index undefined May 04 17:34:42 volumiozb volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 17:34:42 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:42 volumiozb volumio[1159]: info: CoreStateMachine::startPlaybackTimer May 04 17:34:42 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:42 volumiozb volumio[1159]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/单曲集锦01 (Singles)/任素汐 - 枕着光的她/任素汐 - 枕着光的她.flac May 04 17:34:42 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand stop May 04 17:34:42 volumiozb volumio[1159]: info: sendMpdCommand stop took 1 milliseconds May 04 17:34:42 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand clear May 04 17:34:42 volumiozb volumio[1159]: info: May 04 17:34:42 volumiozb volumio[1159]: ---------------------------- MPD announces system playlist update May 04 17:34:42 volumiozb volumio[1159]: info: Ignoring MPD Status Update May 04 17:34:42 volumiozb volumio[1159]: info: sendMpdCommand clear took 3 milliseconds May 04 17:34:42 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/单曲集锦01 (Singles)/任素汐 - 枕着光的她/任素汐 - 枕着光的她.flac" May 04 17:34:42 volumiozb volumio[1159]: error: updateQueue error: null May 04 17:34:42 volumiozb volumio[1159]: info: ------------------------------ 4ms May 04 17:34:42 volumiozb volumio[1159]: info: sendMpdCommand add "INTERNAL/单曲集锦01 (Singles)/任素汐 - 枕着光的她/任素汐 - 枕着光的她.flac" took 2 milliseconds May 04 17:34:42 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand play May 04 17:34:42 volumiozb volumio[1159]: info: May 04 17:34:42 volumiozb volumio[1159]: ---------------------------- MPD announces system playlist update May 04 17:34:42 volumiozb volumio[1159]: info: Ignoring MPD Status Update May 04 17:34:42 volumiozb volumio[1159]: info: sendMpdCommand play took 3 milliseconds May 04 17:34:42 volumiozb volumio[1159]: info: ------------------------------ 3ms May 04 17:34:42 volumiozb volumio[1159]: info: May 04 17:34:42 volumiozb volumio[1159]: ---------------------------- MPD announces state update: player May 04 17:34:42 volumiozb volumio[1159]: info: ControllerMpd::getState May 04 17:34:42 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 04 17:34:42 volumiozb kernel: intel_sst_acpi 808622A8:00: FW Version 01.0b.02.02 May 04 17:34:42 volumiozb kernel: Audio Port: ASoC: no backend DAIs enabled for Audio Port, possibly missing ALSA mixer-based routing or UCM profile May 04 17:34:42 volumiozb volumio[1159]: info: May 04 17:34:42 volumiozb volumio[1159]: ---------------------------- MPD announces state update: player May 04 17:34:42 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand clearerror May 04 17:34:42 volumiozb volumio[1159]: info: sendMpdCommand status took 67 milliseconds May 04 17:34:42 volumiozb volumio[1159]: info: ControllerMpd::getState May 04 17:34:42 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 04 17:34:42 volumiozb volumio[1159]: verbose: ControllerMpd::parseState May 04 17:34:42 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 17:34:42 volumiozb volumio[1159]: info: sendMpdCommand clearerror took 5 milliseconds May 04 17:34:42 volumiozb volumio[1159]: info: sendMpdCommand status took 6 milliseconds May 04 17:34:42 volumiozb volumio[1159]: info: sendMpdCommand playlistinfo took 5 milliseconds May 04 17:34:42 volumiozb volumio[1159]: verbose: ControllerMpd::parseState May 04 17:34:42 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 17:34:42 volumiozb volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 04 17:34:42 volumiozb volumio[1159]: info: ControllerMpd::pushState May 04 17:34:42 volumiozb volumio[1159]: info: CoreCommandRouter::servicePushState May 04 17:34:42 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:42 volumiozb volumio[1159]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":190,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"枕着光的她","artist":"任素汐","album":"单曲集锦 (Singles)","uri":"INTERNAL/单曲集锦01 (Singles)/任素汐 - 枕着光的她/任素汐 - 枕着光的她.flac","trackType":"flac"} May 04 17:34:42 volumiozb volumio[1159]: verbose: CURRENT POSITION 0 May 04 17:34:42 volumiozb volumio[1159]: info: CoreStateMachine::syncState stateService pause May 04 17:34:42 volumiozb volumio[1159]: info: CoreStateMachine::syncState currentStatus stop May 04 17:34:42 volumiozb volumio[1159]: info: CoreStateMachine::pushState May 04 17:34:42 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:42 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 17:34:42 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPushState May 04 17:34:42 volumiozb volumio[1159]: info: ------------------------------ 98ms May 04 17:34:42 volumiozb volumio[1159]: info: sendMpdCommand playlistinfo took 21 milliseconds May 04 17:34:42 volumiozb volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 04 17:34:42 volumiozb volumio[1159]: info: ControllerMpd::pushState May 04 17:34:42 volumiozb volumio[1159]: info: CoreCommandRouter::servicePushState May 04 17:34:42 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:42 volumiozb volumio[1159]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":190,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"枕着光的她","artist":"任素汐","album":"单曲集锦 (Singles)","uri":"INTERNAL/单曲集锦01 (Singles)/任素汐 - 枕着光的她/任素汐 - 枕着光的她.flac","trackType":"flac"} May 04 17:34:42 volumiozb volumio[1159]: verbose: CURRENT POSITION 0 May 04 17:34:42 volumiozb volumio[1159]: info: CoreStateMachine::syncState stateService pause May 04 17:34:42 volumiozb volumio[1159]: info: CoreStateMachine::syncState currentStatus stop May 04 17:34:42 volumiozb volumio[1159]: info: CoreStateMachine::pushState May 04 17:34:42 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:42 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPushState May 04 17:34:42 volumiozb volumio[1159]: info: ------------------------------ 54ms May 04 17:34:43 volumiozb ntpd[5854]: Soliciting pool server 193.182.111.143 May 04 17:34:44 volumiozb ntpd[5854]: Soliciting pool server 162.159.200.123 May 04 17:34:45 volumiozb ntpd[5854]: Soliciting pool server 193.182.111.143 May 04 17:34:45 volumiozb ntpd[5854]: Soliciting pool server 193.182.111.141 May 04 17:34:46 volumiozb ntpd[5854]: Soliciting pool server 45.76.221.157 May 04 17:34:56 volumiozb volumio[1159]: info: CoreCommandRouter::volumioGetState May 04 17:34:56 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:34:59 volumiozb volumio[1159]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 17:34:59 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 04 17:34:59 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 17:34:59 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 04 17:34:59 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 17:34:59 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 17:34:59 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 17:34:59 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 17:34:59 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 04 17:34:59 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 04 17:35:13 volumiozb volumio[1159]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::volumioGetState May 04 17:35:13 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:35:13 volumiozb volumio[1159]: info: Restoring Previous Volume level: false false May 04 17:35:13 volumiozb volumio[1159]: info: Volume configurations have been set May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 04 17:35:13 volumiozb volumio[1159]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphone Jack Mixer: Speaker Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 04 17:35:13 volumiozb volumio[1159]: info: Disabling external Volume Control May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 04 17:35:13 volumiozb volumio[1159]: Unable to parse: May 04 17:35:13 volumiozb volumio[1159]: Simple mixer control 'Speaker',0 May 04 17:35:13 volumiozb volumio[1159]: Capabilities: pswitch pswitch-joined May 04 17:35:13 volumiozb volumio[1159]: Playback channels: Mono May 04 17:35:13 volumiozb volumio[1159]: Mono: Playback [on] May 04 17:35:13 volumiozb volumio[1159]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 04 17:35:13 volumiozb volumio[1159]: Unable to parse: May 04 17:35:13 volumiozb volumio[1159]: Simple mixer control 'Speaker',0 May 04 17:35:13 volumiozb volumio[1159]: Capabilities: pswitch pswitch-joined May 04 17:35:13 volumiozb volumio[1159]: Playback channels: Mono May 04 17:35:13 volumiozb volumio[1159]: Mono: Playback [on] May 04 17:35:13 volumiozb volumio[1159]: info: VolumeController:: Volume=undefined Mute =false May 04 17:35:13 volumiozb volumio[1159]: info: CoreStateMachine::pushState May 04 17:35:13 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 17:35:13 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPushState May 04 17:35:17 volumiozb volumio[1159]: info: VolumeController::SetAlsaVolumeNaN May 04 17:35:17 volumiozb volumio[1159]: info: CoreStateMachine::pushState May 04 17:35:17 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:35:17 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 17:35:17 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPushState May 04 17:35:21 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPlay May 04 17:35:21 volumiozb volumio[1159]: info: CoreStateMachine::play index undefined May 04 17:35:21 volumiozb volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 17:35:21 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:35:21 volumiozb volumio[1159]: info: CoreStateMachine::startPlaybackTimer May 04 17:35:21 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/单曲集锦01 (Singles)/任素汐 - 枕着光的她/任素汐 - 枕着光的她.flac May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand stop May 04 17:35:21 volumiozb volumio[1159]: info: May 04 17:35:21 volumiozb volumio[1159]: ---------------------------- MPD announces state update: player May 04 17:35:21 volumiozb volumio[1159]: info: sendMpdCommand stop took 8 milliseconds May 04 17:35:21 volumiozb volumio[1159]: info: ControllerMpd::getState May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand clear May 04 17:35:21 volumiozb volumio[1159]: info: sendMpdCommand status took 4 milliseconds May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::parseState May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 17:35:21 volumiozb volumio[1159]: info: May 04 17:35:21 volumiozb volumio[1159]: ---------------------------- MPD announces system playlist update May 04 17:35:21 volumiozb volumio[1159]: info: Ignoring MPD Status Update May 04 17:35:21 volumiozb volumio[1159]: info: sendMpdCommand clear took 15 milliseconds May 04 17:35:21 volumiozb volumio[1159]: info: sendMpdCommand playlistinfo took 10 milliseconds May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/单曲集锦01 (Singles)/任素汐 - 枕着光的她/任素汐 - 枕着光的她.flac" May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 04 17:35:21 volumiozb volumio[1159]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 04 17:35:21 volumiozb volumio[1159]: info: ------------------------------ 28ms May 04 17:35:21 volumiozb volumio[1159]: error: updateQueue error: null May 04 17:35:21 volumiozb volumio[1159]: info: May 04 17:35:21 volumiozb volumio[1159]: ---------------------------- MPD announces system playlist update May 04 17:35:21 volumiozb volumio[1159]: info: Ignoring MPD Status Update May 04 17:35:21 volumiozb volumio[1159]: info: ------------------------------ 14ms May 04 17:35:21 volumiozb volumio[1159]: info: sendMpdCommand add "INTERNAL/单曲集锦01 (Singles)/任素汐 - 枕着光的她/任素汐 - 枕着光的她.flac" took 11 milliseconds May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand play May 04 17:35:21 volumiozb volumio[1159]: info: ------------------------------ 8ms May 04 17:35:21 volumiozb volumio[1159]: info: May 04 17:35:21 volumiozb volumio[1159]: ---------------------------- MPD announces state update: player May 04 17:35:21 volumiozb volumio[1159]: info: sendMpdCommand play took 5 milliseconds May 04 17:35:21 volumiozb volumio[1159]: info: ControllerMpd::getState May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 04 17:35:21 volumiozb volumio[1159]: info: May 04 17:35:21 volumiozb volumio[1159]: ---------------------------- MPD announces state update: player May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand clearerror May 04 17:35:21 volumiozb volumio[1159]: info: sendMpdCommand status took 97 milliseconds May 04 17:35:21 volumiozb volumio[1159]: info: ControllerMpd::getState May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::parseState May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 17:35:21 volumiozb volumio[1159]: info: sendMpdCommand clearerror took 8 milliseconds May 04 17:35:21 volumiozb volumio[1159]: info: sendMpdCommand status took 8 milliseconds May 04 17:35:21 volumiozb volumio[1159]: info: sendMpdCommand playlistinfo took 7 milliseconds May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::parseState May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 04 17:35:21 volumiozb volumio[1159]: info: ControllerMpd::pushState May 04 17:35:21 volumiozb volumio[1159]: info: CoreCommandRouter::servicePushState May 04 17:35:21 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:35:21 volumiozb volumio[1159]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":190,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"枕着光的她","artist":"任素汐","album":"单曲集锦 (Singles)","uri":"INTERNAL/单曲集锦01 (Singles)/任素汐 - 枕着光的她/任素汐 - 枕着光的她.flac","trackType":"flac"} May 04 17:35:21 volumiozb volumio[1159]: verbose: CURRENT POSITION 0 May 04 17:35:21 volumiozb volumio[1159]: info: CoreStateMachine::syncState stateService pause May 04 17:35:21 volumiozb volumio[1159]: info: CoreStateMachine::syncState currentStatus stop May 04 17:35:21 volumiozb volumio[1159]: info: CoreStateMachine::pushState May 04 17:35:21 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:35:21 volumiozb volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 17:35:21 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPushState May 04 17:35:21 volumiozb volumio[1159]: info: ------------------------------ 125ms May 04 17:35:21 volumiozb volumio[1159]: info: sendMpdCommand playlistinfo took 17 milliseconds May 04 17:35:21 volumiozb volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 04 17:35:21 volumiozb volumio[1159]: info: ControllerMpd::pushState May 04 17:35:21 volumiozb volumio[1159]: info: CoreCommandRouter::servicePushState May 04 17:35:21 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:35:21 volumiozb volumio[1159]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":190,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"枕着光的她","artist":"任素汐","album":"单曲集锦 (Singles)","uri":"INTERNAL/单曲集锦01 (Singles)/任素汐 - 枕着光的她/任素汐 - 枕着光的她.flac","trackType":"flac"} May 04 17:35:21 volumiozb volumio[1159]: verbose: CURRENT POSITION 0 May 04 17:35:21 volumiozb volumio[1159]: info: CoreStateMachine::syncState stateService pause May 04 17:35:21 volumiozb volumio[1159]: info: CoreStateMachine::syncState currentStatus stop May 04 17:35:21 volumiozb volumio[1159]: info: CoreStateMachine::pushState May 04 17:35:21 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:35:21 volumiozb volumio[1159]: info: CoreCommandRouter::volumioPushState May 04 17:35:21 volumiozb volumio[1159]: info: ------------------------------ 48ms May 04 17:35:34 volumiozb wpa_supplicant[3185]: wlan0: CTRL-EVENT-DISCONNECTED bssid=0c:72:2c:fc:8a:17 reason=0 locally_generated=1 May 04 17:35:34 volumiozb dhcpcd[1044]: wlan0: carrier lost May 04 17:35:34 volumiozb wpa_supplicant[3185]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 04 17:35:34 volumiozb wpa_supplicant[3185]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN May 04 17:35:34 volumiozb avahi-daemon[916]: Withdrawing address record for 192.168.0.111 on wlan0. May 04 17:35:34 volumiozb avahi-daemon[916]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.111. May 04 17:35:34 volumiozb avahi-daemon[916]: Interface wlan0.IPv4 no longer relevant for mDNS. May 04 17:35:34 volumiozb volumio[1159]: info: Discovery: A device disappeared from network May 04 17:35:34 volumiozb dhcpcd[1044]: wlan0: deleting route to 192.168.0.0/24 May 04 17:35:34 volumiozb dhcpcd[1044]: wlan0: deleting default route via 192.168.0.1 May 04 17:35:36 volumiozb wpa_supplicant[3185]: wlan0: Trying to associate with 0c:72:2c:fc:8a:17 (SSID='TP-LINK24' freq=2462 MHz) May 04 17:35:36 volumiozb wpa_supplicant[3185]: wlan0: Associated with 0c:72:2c:fc:8a:17 May 04 17:35:36 volumiozb wpa_supplicant[3185]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 04 17:35:36 volumiozb wpa_supplicant[3185]: wlan0: WPA: Key negotiation completed with 0c:72:2c:fc:8a:17 [PTK=CCMP GTK=CCMP] May 04 17:35:36 volumiozb wpa_supplicant[3185]: wlan0: CTRL-EVENT-CONNECTED - Connection to 0c:72:2c:fc:8a:17 completed [id=0 id_str=] May 04 17:35:36 volumiozb dhcpcd[1044]: wlan0: carrier acquired May 04 17:35:36 volumiozb dhcpcd[1044]: wlan0: IAID 9c:f2:de:7c May 04 17:35:37 volumiozb dhcpcd[1044]: wlan0: rebinding lease of 192.168.0.111 May 04 17:35:37 volumiozb dhcpcd[1044]: wlan0: probing address 192.168.0.111/24 May 04 17:35:37 volumiozb dhcpcd[1044]: wlan0: soliciting an IPv6 router May 04 17:35:38 volumiozb ntpd[5854]: Deleting interface #3 wlan0, 192.168.0.111#123, interface stats: received=13, sent=18, dropped=0, active_time=57 secs May 04 17:35:38 volumiozb ntpd[5854]: 193.182.111.141 local addr 192.168.0.111 -> May 04 17:35:38 volumiozb ntpd[5854]: 162.159.200.123 local addr 192.168.0.111 -> May 04 17:35:41 volumiozb dhcpcd[1044]: wlan0: leased 192.168.0.111 for 7200 seconds May 04 17:35:41 volumiozb dhcpcd[1044]: wlan0: adding route to 192.168.0.0/24 May 04 17:35:41 volumiozb dhcpcd[1044]: wlan0: adding default route via 192.168.0.1 May 04 17:35:41 volumiozb avahi-daemon[916]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.111. May 04 17:35:41 volumiozb avahi-daemon[916]: New relevant interface wlan0.IPv4 for mDNS. May 04 17:35:41 volumiozb avahi-daemon[916]: Registering new address record for 192.168.0.111 on wlan0.IPv4. May 04 17:35:41 volumiozb ntpd[5854]: ntpd exiting on signal 15 (Terminated) May 04 17:35:41 volumiozb systemd[1]: Stopping Network Time Service... May 04 17:35:41 volumiozb systemd[1]: ntp.service: Succeeded. May 04 17:35:41 volumiozb systemd[1]: Stopped Network Time Service. May 04 17:35:41 volumiozb systemd[1]: Starting Network Time Service... May 04 17:35:41 volumiozb ntpd[6090]: ntpd 4.2.8p12@1.3728-o (1): Starting May 04 17:35:41 volumiozb ntpd[6090]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 04 17:35:41 volumiozb systemd[1]: Started Network Time Service. May 04 17:35:41 volumiozb ntpd[6096]: proto: precision = 0.250 usec (-22) May 04 17:35:41 volumiozb ntpd[6096]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 04 17:35:41 volumiozb ntpd[6096]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 04 17:35:41 volumiozb ntpd[6096]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 859 days ago May 04 17:35:41 volumiozb ntpd[6096]: Listen and drop on 0 v6wildcard [::]:123 May 04 17:35:41 volumiozb ntpd[6096]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 04 17:35:41 volumiozb ntpd[6096]: Listen normally on 2 lo 127.0.0.1:123 May 04 17:35:41 volumiozb ntpd[6096]: Listen normally on 3 wlan0 192.168.0.111:123 May 04 17:35:41 volumiozb ntpd[6096]: Listening on routing socket on fd #20 for interface updates May 04 17:35:41 volumiozb ntpd[6096]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 04 17:35:41 volumiozb ntpd[6096]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 04 17:35:42 volumiozb volumio[1159]: info: Discovery: adding 79a4dd61-a63b-490f-a4b6-99b06d50e877 May 04 17:35:42 volumiozb volumio[1159]: info: Discovery: Found device VolumioZB May 04 17:35:42 volumiozb volumio[1159]: info: CoreCommandRouter::volumioGetState May 04 17:35:42 volumiozb volumio[1159]: info: CorePlayQueue::getTrack 0 May 04 17:35:42 volumiozb volumio[1159]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 17:35:42 volumiozb volumio[1159]: Error: The value NaN is not a number May 04 17:35:42 volumiozb volumio[1159]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) May 04 17:35:42 volumiozb volumio[1159]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) May 04 17:35:42 volumiozb volumio[1159]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) May 04 17:35:42 volumiozb volumio[1159]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) May 04 17:35:42 volumiozb volumio[1159]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) May 04 17:35:42 volumiozb volumio[1159]: at Browser.emit (events.js:400:28) May 04 17:35:42 volumiozb volumio[1159]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) May 04 17:35:42 volumiozb volumio[1159]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) May 04 17:35:42 volumiozb volumio[1159]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) May 04 17:35:42 volumiozb volumio[1159]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) May 04 17:35:42 volumiozb volumio[1159]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 May 04 17:35:42 volumiozb volumio[1159]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) May 04 17:35:42 volumiozb volumio[1159]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) May 04 17:35:42 volumiozb volumio[1159]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 17:35:42 volumiozb sudo[6110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-04 17:34 May 04 17:35:42 volumiozb sudo[6110]: 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"