-- Logs begin at Fri 2025-12-05 13:36:56 UTC, end at Fri 2025-12-05 14:20:58 UTC. -- Dec 05 14:19:34 volumio sudo[4282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 05 14:19:34 volumio sudo[4282]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 14:19:34 volumio sudo[4282]: pam_unix(sudo:session): session closed for user root Dec 05 14:19:34 volumio sudo[4284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 14:19:34 volumio sudo[4284]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 14:19:34 volumio sudo[4284]: pam_unix(sudo:session): session closed for user root Dec 05 14:19:34 volumio volumio[776]: verbose: New Socket.io Connection to 192.168.0.106 from 192.168.0.101 UA: Mozilla/5.0 (Linux; Android 15; VER-AN00 Build/HONORVER-AN00;) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/97.0.4692.98 Mobile Safari/537.36 T7/13.38 SP-engine/2.76.0 languageType/0 bdh_dvt/6 bdh_de/1 bdh_ds/1 bdapp/1.0 (bdhonorbrowser; bdhonorbrowser) bdhonorbrowser/9.6.0.4 (P1 15) NABar/1.0 Total Clients: 6 Dec 05 14:19:34 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 05 14:19:34 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 05 14:19:34 volumio volumio[776]: info: CoreCommandRouter::volumioGetVisibleSources Dec 05 14:19:34 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 05 14:19:34 volumio volumio[776]: info: CoreCommandRouter::volumioGetState Dec 05 14:19:34 volumio volumio[776]: info: CorePlayQueue::getTrack 0 Dec 05 14:19:34 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 05 14:19:34 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 05 14:19:34 volumio volumio[776]: info: Received Get System Info Dec 05 14:19:34 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 14:19:34 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 14:19:34 volumio volumio[776]: info: Discovery: Getting this device information Dec 05 14:19:34 volumio volumio[776]: info: CoreCommandRouter::volumioGetState Dec 05 14:19:34 volumio volumio[776]: info: CorePlayQueue::getTrack 0 Dec 05 14:19:34 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 14:19:34 volumio volumio[776]: info: CoreCommandRouter::volumioGetState Dec 05 14:19:34 volumio volumio[776]: info: CorePlayQueue::getTrack 0 Dec 05 14:19:34 volumio volumio[776]: info: Listing playlists Dec 05 14:19:34 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 05 14:19:34 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 05 14:19:34 volumio volumio[776]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B8%B8%E9%9D%99/%E5%8D%81%E6%8C%87%E5%BC%B9%E4%B8%80/aa4dd3bb-dca2-4d4e-9ac5-6664a2ee416d.jpg' Dec 05 14:19:34 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 05 14:19:36 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 05 14:19:36 volumio volumio[776]: info: Received Get System Info Dec 05 14:19:36 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 14:19:36 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 14:19:36 volumio volumio[776]: info: Discovery: Getting this device information Dec 05 14:19:36 volumio volumio[776]: info: CoreCommandRouter::volumioGetState Dec 05 14:19:36 volumio volumio[776]: info: CorePlayQueue::getTrack 0 Dec 05 14:19:36 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 14:19:39 volumio volumio[776]: info: CoreCommandRouter::volumioGetQueue Dec 05 14:19:39 volumio volumio[776]: info: CoreStateMachine::getQueue Dec 05 14:19:39 volumio volumio[776]: info: CorePlayQueue::getQueue Dec 05 14:19:57 volumio volumio[776]: info: CoreCommandRouter::volumioPlay Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::play index 3 Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::stop Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::stPlaybackTimer Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::updateTrackBlock Dec 05 14:19:57 volumio volumio[776]: info: CorePlayQueue::getTrackBlock Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::pushState Dec 05 14:19:57 volumio volumio[776]: info: CorePlayQueue::getTrack 0 Dec 05 14:19:57 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 14:19:57 volumio volumio[776]: info: CoreCommandRouter::volumioPushState Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::serviceStop Dec 05 14:19:57 volumio volumio[776]: info: CorePlayQueue::getTrack 0 Dec 05 14:19:57 volumio volumio[776]: info: CoreCommandRouter::serviceStop Dec 05 14:19:57 volumio volumio[776]: info: ControllerMpd::stop Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 14:19:57 volumio volumio[776]: info: sendMpdCommand stop took 93 milliseconds Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::play index undefined Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 14:19:57 volumio volumio[776]: info: CorePlayQueue::getTrack 3 Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::startPlaybackTimer Dec 05 14:19:57 volumio volumio[776]: info: CorePlayQueue::getTrack 3 Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::clearAddPlayTracks USB/BOOT/李克勤 _ 王赫野 - 秒针.flac Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 14:19:57 volumio volumio[776]: info: Dec 05 14:19:57 volumio volumio[776]: ---------------------------- MPD announces state update: player Dec 05 14:19:57 volumio volumio[776]: info: ControllerMpd::getState Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:19:57 volumio volumio[776]: info: sendMpdCommand stop took 2 milliseconds Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand clear Dec 05 14:19:57 volumio volumio[776]: info: Dec 05 14:19:57 volumio volumio[776]: ---------------------------- MPD announces system playlist update Dec 05 14:19:57 volumio volumio[776]: info: Ignoring MPD Status Update Dec 05 14:19:57 volumio volumio[776]: info: sendMpdCommand status took 3 milliseconds Dec 05 14:19:57 volumio volumio[776]: info: sendMpdCommand clear took 2 milliseconds Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::parseState Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand add "USB/BOOT/李克勤 _ 王赫野 - 秒针.flac" Dec 05 14:19:57 volumio volumio[776]: error: updateQueue error: null Dec 05 14:19:57 volumio volumio[776]: info: Dec 05 14:19:57 volumio volumio[776]: ---------------------------- MPD announces system playlist update Dec 05 14:19:57 volumio volumio[776]: info: Ignoring MPD Status Update Dec 05 14:19:57 volumio volumio[776]: info: ------------------------------ 5ms Dec 05 14:19:57 volumio volumio[776]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 05 14:19:57 volumio volumio[776]: info: sendMpdCommand add "USB/BOOT/李克勤 _ 王赫野 - 秒针.flac" took 3 milliseconds Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand play Dec 05 14:19:57 volumio volumio[776]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 05 14:19:57 volumio volumio[776]: info: ------------------------------ 11ms Dec 05 14:19:57 volumio volumio[776]: info: Dec 05 14:19:57 volumio volumio[776]: ---------------------------- MPD announces state update: player Dec 05 14:19:57 volumio volumio[776]: info: ------------------------------ 6ms Dec 05 14:19:57 volumio volumio[776]: info: sendMpdCommand play took 3 milliseconds Dec 05 14:19:57 volumio volumio[776]: info: ControllerMpd::getState Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:19:57 volumio volumio[776]: info: Dec 05 14:19:57 volumio volumio[776]: ---------------------------- MPD announces state update: player Dec 05 14:19:57 volumio volumio[776]: info: ControllerMpd::getState Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:19:57 volumio volumio[776]: info: sendMpdCommand status took 24 milliseconds Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::parseState Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:19:57 volumio volumio[776]: info: sendMpdCommand status took 4 milliseconds Dec 05 14:19:57 volumio volumio[776]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::parseState Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:19:57 volumio volumio[776]: info: ControllerMpd::pushState Dec 05 14:19:57 volumio volumio[776]: info: CoreCommandRouter::servicePushState Dec 05 14:19:57 volumio volumio[776]: info: CorePlayQueue::getTrack 3 Dec 05 14:19:57 volumio volumio[776]: verbose: STATE SERVICE {"status":"play","position":0,"seek":146,"duration":173,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"837 Kbps","isStreaming":false,"title":"秒针","artist":"李克勤/王赫野","album":"秒针","uri":"USB/BOOT/李克勤 _ 王赫野 - 秒针.flac","trackType":"flac"} Dec 05 14:19:57 volumio volumio[776]: verbose: CURRENT POSITION 3 Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::syncState stateService play Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus stop Dec 05 14:19:57 volumio volumio[776]: info: ------------------------------ 39ms Dec 05 14:19:57 volumio volumio[776]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 05 14:19:57 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:19:57 volumio volumio[776]: info: ControllerMpd::pushState Dec 05 14:19:57 volumio volumio[776]: info: CoreCommandRouter::servicePushState Dec 05 14:19:57 volumio volumio[776]: info: CorePlayQueue::getTrack 3 Dec 05 14:19:57 volumio volumio[776]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":173,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"787 Kbps","isStreaming":false,"title":"秒针","artist":"李克勤/王赫野","album":"秒针","uri":"USB/BOOT/李克勤 _ 王赫野 - 秒针.flac","trackType":"flac"} Dec 05 14:19:57 volumio volumio[776]: verbose: CURRENT POSITION 3 Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::syncState stateService play Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus play Dec 05 14:19:57 volumio volumio[776]: info: Received an update from plugin. extracting info from payload Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::pushState Dec 05 14:19:57 volumio volumio[776]: info: CorePlayQueue::getTrack 3 Dec 05 14:19:57 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 14:19:57 volumio volumio[776]: info: CoreCommandRouter::volumioPushState Dec 05 14:19:57 volumio volumio[776]: info: CoreStateMachine::pushState Dec 05 14:19:57 volumio volumio[776]: info: CorePlayQueue::getTrack 3 Dec 05 14:19:57 volumio volumio[776]: info: CoreCommandRouter::volumioPushState Dec 05 14:19:57 volumio volumio[776]: info: ------------------------------ 37ms Dec 05 14:20:38 volumio volumio[776]: info: Dec 05 14:20:38 volumio volumio[776]: ---------------------------- MPD announces state update: player Dec 05 14:20:38 volumio volumio[776]: info: ControllerMpd::getState Dec 05 14:20:38 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:20:38 volumio volumio[776]: info: sendMpdCommand status took 1 milliseconds Dec 05 14:20:38 volumio volumio[776]: verbose: ControllerMpd::parseState Dec 05 14:20:38 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:20:38 volumio volumio[776]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 05 14:20:38 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:20:38 volumio volumio[776]: info: ControllerMpd::pushState Dec 05 14:20:38 volumio volumio[776]: info: CoreCommandRouter::servicePushState Dec 05 14:20:38 volumio volumio[776]: info: CoreStateMachine::pushState Dec 05 14:20:38 volumio volumio[776]: info: CorePlayQueue::getTrack 3 Dec 05 14:20:38 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 14:20:38 volumio volumio[776]: info: CoreCommandRouter::volumioPushState Dec 05 14:20:38 volumio volumio[776]: info: CorePlayQueue::getTrack 3 Dec 05 14:20:38 volumio volumio[776]: 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":"秒针","artist":"李克勤/王赫野","album":"秒针","uri":"USB/BOOT/李克勤 _ 王赫野 - 秒针.flac","trackType":"flac"} Dec 05 14:20:38 volumio volumio[776]: verbose: CURRENT POSITION 3 Dec 05 14:20:38 volumio volumio[776]: info: CoreStateMachine::syncState stateService stop Dec 05 14:20:38 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus play Dec 05 14:20:38 volumio volumio[776]: info: CoreCommandRouter::volumioPushState Dec 05 14:20:38 volumio volumio[776]: info: CoreStateMachine::stPlaybackTimer Dec 05 14:20:38 volumio volumio[776]: info: ------------------------------ 32ms Dec 05 14:20:38 volumio volumio[776]: info: Clearing queue after UPNP request Dec 05 14:20:38 volumio volumio[776]: info: CoreStateMachine::ClearQueue Dec 05 14:20:38 volumio volumio[776]: info: CoreStateMachine::stop Dec 05 14:20:38 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 14:20:38 volumio volumio[776]: info: CorePlayQueue::clearPlayQueue Dec 05 14:20:38 volumio volumio[776]: info: CorePlayQueue::saveQueue Dec 05 14:20:38 volumio volumio[776]: info: CoreCommandRouter::volumioPushState Dec 05 14:20:39 volumio volumio[776]: info: CoreCommandRouter::volumioPushQueue Dec 05 14:20:39 volumio volumio[776]: info: Dec 05 14:20:39 volumio volumio[776]: ---------------------------- MPD announces system playlist update Dec 05 14:20:39 volumio volumio[776]: info: Ignoring MPD Status Update Dec 05 14:20:39 volumio volumio[776]: error: updateQueue error: null Dec 05 14:20:39 volumio volumio[776]: info: ------------------------------ 1ms Dec 05 14:20:39 volumio volumio[776]: info: Starting UPNP Playback Dec 05 14:20:39 volumio volumio[776]: info: Preparing playback through UPNP Dec 05 14:20:39 volumio volumio[776]: info: CoreCommandRouter::volumioGetState Dec 05 14:20:39 volumio volumio[776]: info: CorePlayQueue::getTrack 0 Dec 05 14:20:39 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 05 14:20:39 volumio volumio[776]: info: Dec 05 14:20:39 volumio volumio[776]: ---------------------------- MPD announces system playlist update Dec 05 14:20:39 volumio volumio[776]: info: Ignoring MPD Status Update Dec 05 14:20:39 volumio volumio[776]: info: Dec 05 14:20:39 volumio volumio[776]: ---------------------------- MPD announces system playlist update Dec 05 14:20:39 volumio volumio[776]: info: Ignoring MPD Status Update Dec 05 14:20:39 volumio volumio[776]: info: ------------------------------ 5ms Dec 05 14:20:39 volumio volumio[776]: info: ------------------------------ 2ms Dec 05 14:20:39 volumio volumio[776]: info: Dec 05 14:20:39 volumio volumio[776]: ---------------------------- MPD announces system playlist update Dec 05 14:20:39 volumio volumio[776]: info: Ignoring MPD Status Update Dec 05 14:20:39 volumio volumio[776]: info: ------------------------------ 1ms Dec 05 14:20:39 volumio volumio[776]: info: Dec 05 14:20:39 volumio volumio[776]: ---------------------------- MPD announces system playlist update Dec 05 14:20:39 volumio volumio[776]: info: Ignoring MPD Status Update Dec 05 14:20:39 volumio volumio[776]: info: ------------------------------ 1ms Dec 05 14:20:39 volumio volumio[776]: info: Dec 05 14:20:39 volumio volumio[776]: ---------------------------- MPD announces system playlist update Dec 05 14:20:39 volumio volumio[776]: info: Ignoring MPD Status Update Dec 05 14:20:39 volumio volumio[776]: info: ------------------------------ 1ms Dec 05 14:20:39 volumio volumio[776]: info: Dec 05 14:20:39 volumio volumio[776]: ---------------------------- MPD announces system playlist update Dec 05 14:20:39 volumio volumio[776]: info: Ignoring MPD Status Update Dec 05 14:20:39 volumio volumio[776]: info: ------------------------------ 1ms Dec 05 14:20:39 volumio volumio[776]: info: Dec 05 14:20:39 volumio volumio[776]: ---------------------------- MPD announces state update: player Dec 05 14:20:39 volumio volumio[776]: info: ControllerMpd::getState Dec 05 14:20:39 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:20:39 volumio volumio[776]: info: sendMpdCommand status took 19 milliseconds Dec 05 14:20:39 volumio volumio[776]: verbose: ControllerMpd::parseState Dec 05 14:20:39 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:20:39 volumio volumio[776]: info: Dec 05 14:20:39 volumio volumio[776]: ---------------------------- MPD announces state update: player Dec 05 14:20:39 volumio volumio[776]: info: ControllerMpd::getState Dec 05 14:20:39 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:20:39 volumio volumio[776]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 05 14:20:39 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:20:39 volumio volumio[776]: info: ControllerMpd::pushState Dec 05 14:20:39 volumio volumio[776]: info: CoreCommandRouter::servicePushState Dec 05 14:20:39 volumio volumio[776]: verbose: In UPNP mode Dec 05 14:20:39 volumio volumio[776]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"痴心绝对","artist":"李圣杰","album":null,"uri":"http://192.168.0.101:49152/upnp/service/local/47522154/0/0.wav","trackType":"wav"} Dec 05 14:20:39 volumio volumio[776]: verbose: CURRENT POSITION 0 Dec 05 14:20:39 volumio volumio[776]: info: CoreStateMachine::syncState stateService play Dec 05 14:20:39 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus stop Dec 05 14:20:39 volumio volumio[776]: info: ------------------------------ 26ms Dec 05 14:20:39 volumio volumio[776]: info: sendMpdCommand status took 5 milliseconds Dec 05 14:20:39 volumio volumio[776]: verbose: ControllerMpd::parseState Dec 05 14:20:39 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:20:39 volumio volumio[776]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 05 14:20:39 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:20:39 volumio volumio[776]: info: ControllerMpd::pushState Dec 05 14:20:39 volumio volumio[776]: info: CoreCommandRouter::servicePushState Dec 05 14:20:39 volumio volumio[776]: verbose: In UPNP mode Dec 05 14:20:39 volumio volumio[776]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"痴心绝对","artist":"李圣杰","album":null,"uri":"http://192.168.0.101:49152/upnp/service/local/47522154/0/0.wav","trackType":"wav"} Dec 05 14:20:39 volumio volumio[776]: verbose: CURRENT POSITION 0 Dec 05 14:20:39 volumio volumio[776]: info: CoreStateMachine::syncState stateService play Dec 05 14:20:39 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus play Dec 05 14:20:39 volumio volumio[776]: info: Received an update from plugin. extracting info from payload Dec 05 14:20:39 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 05 14:20:39 volumio volumio[776]: info: CoreStateMachine::pushState Dec 05 14:20:39 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 14:20:39 volumio volumio[776]: info: CoreCommandRouter::volumioPushState Dec 05 14:20:39 volumio volumio[776]: info: CoreStateMachine::pushState Dec 05 14:20:39 volumio volumio[776]: info: CoreCommandRouter::volumioPushState Dec 05 14:20:39 volumio volumio[776]: info: ------------------------------ 44ms Dec 05 14:20:39 volumio volumio[776]: info: Dec 05 14:20:39 volumio volumio[776]: ---------------------------- MPD announces state update: player Dec 05 14:20:39 volumio volumio[776]: info: ControllerMpd::getState Dec 05 14:20:39 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:20:39 volumio volumio[776]: info: sendMpdCommand status took 1 milliseconds Dec 05 14:20:39 volumio volumio[776]: verbose: ControllerMpd::parseState Dec 05 14:20:39 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:20:39 volumio volumio[776]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 05 14:20:39 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:20:39 volumio volumio[776]: info: ControllerMpd::pushState Dec 05 14:20:39 volumio volumio[776]: info: CoreCommandRouter::servicePushState Dec 05 14:20:39 volumio volumio[776]: verbose: In UPNP mode Dec 05 14:20:39 volumio volumio[776]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30000,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"痴心绝对","artist":"李圣杰","album":null,"uri":"http://192.168.0.101:49152/upnp/service/local/47522154/0/0.wav","trackType":"wav"} Dec 05 14:20:39 volumio volumio[776]: verbose: CURRENT POSITION 0 Dec 05 14:20:39 volumio volumio[776]: info: CoreStateMachine::syncState stateService play Dec 05 14:20:39 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus play Dec 05 14:20:39 volumio volumio[776]: info: Received an update from plugin. extracting info from payload Dec 05 14:20:39 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 05 14:20:39 volumio volumio[776]: info: CoreStateMachine::pushState Dec 05 14:20:39 volumio volumio[776]: info: CoreCommandRouter::volumioPushState Dec 05 14:20:39 volumio volumio[776]: info: CoreStateMachine::pushState Dec 05 14:20:39 volumio volumio[776]: info: CoreCommandRouter::volumioPushState Dec 05 14:20:39 volumio volumio[776]: info: ------------------------------ 35ms Dec 05 14:20:54 volumio volumio[776]: info: Dec 05 14:20:54 volumio volumio[776]: ---------------------------- MPD announces state update: player Dec 05 14:20:54 volumio volumio[776]: info: ControllerMpd::getState Dec 05 14:20:54 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:20:54 volumio volumio[776]: info: sendMpdCommand status took 2 milliseconds Dec 05 14:20:54 volumio volumio[776]: verbose: ControllerMpd::parseState Dec 05 14:20:54 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:20:54 volumio volumio[776]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 05 14:20:54 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:20:54 volumio volumio[776]: info: ControllerMpd::pushState Dec 05 14:20:54 volumio volumio[776]: info: CoreCommandRouter::servicePushState Dec 05 14:20:54 volumio volumio[776]: info: CoreStateMachine::pushState Dec 05 14:20:54 volumio volumio[776]: info: CoreCommandRouter::volumioPushState Dec 05 14:20:54 volumio volumio[776]: info: CorePlayQueue::getTrack 0 Dec 05 14:20:54 volumio volumio[776]: 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":"痴心绝对","artist":"李圣杰","album":null,"uri":"http://192.168.0.101:49152/upnp/service/local/47522154/0/0.wav","trackType":"wav"} Dec 05 14:20:54 volumio volumio[776]: verbose: CURRENT POSITION 0 Dec 05 14:20:54 volumio volumio[776]: info: CoreStateMachine::syncState stateService stop Dec 05 14:20:54 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus play Dec 05 14:20:54 volumio volumio[776]: info: CoreCommandRouter::volumioPushState Dec 05 14:20:54 volumio volumio[776]: info: CoreStateMachine::stPlaybackTimer Dec 05 14:20:54 volumio volumio[776]: info: ------------------------------ 28ms Dec 05 14:20:54 volumio volumio[776]: info: Clearing queue after UPNP request Dec 05 14:20:55 volumio volumio[776]: info: CoreStateMachine::ClearQueue Dec 05 14:20:55 volumio volumio[776]: info: CoreStateMachine::stop Dec 05 14:20:55 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 14:20:55 volumio volumio[776]: info: CorePlayQueue::clearPlayQueue Dec 05 14:20:55 volumio volumio[776]: info: CorePlayQueue::saveQueue Dec 05 14:20:55 volumio volumio[776]: info: CoreCommandRouter::volumioPushState Dec 05 14:20:55 volumio volumio[776]: info: CoreCommandRouter::volumioPushQueue Dec 05 14:20:55 volumio volumio[776]: info: Dec 05 14:20:55 volumio volumio[776]: ---------------------------- MPD announces system playlist update Dec 05 14:20:55 volumio volumio[776]: info: Ignoring MPD Status Update Dec 05 14:20:55 volumio volumio[776]: error: updateQueue error: null Dec 05 14:20:55 volumio volumio[776]: info: ------------------------------ 2ms Dec 05 14:20:55 volumio volumio[776]: info: Starting UPNP Playback Dec 05 14:20:55 volumio volumio[776]: info: Preparing playback through UPNP Dec 05 14:20:55 volumio volumio[776]: info: CoreCommandRouter::volumioGetState Dec 05 14:20:55 volumio volumio[776]: info: CorePlayQueue::getTrack 0 Dec 05 14:20:55 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 05 14:20:55 volumio volumio[776]: info: Dec 05 14:20:55 volumio volumio[776]: ---------------------------- MPD announces system playlist update Dec 05 14:20:55 volumio volumio[776]: info: Ignoring MPD Status Update Dec 05 14:20:55 volumio volumio[776]: info: ------------------------------ 2ms Dec 05 14:20:55 volumio volumio[776]: info: Dec 05 14:20:55 volumio volumio[776]: ---------------------------- MPD announces system playlist update Dec 05 14:20:55 volumio volumio[776]: info: Ignoring MPD Status Update Dec 05 14:20:55 volumio volumio[776]: info: Dec 05 14:20:55 volumio volumio[776]: ---------------------------- MPD announces system playlist update Dec 05 14:20:55 volumio volumio[776]: info: Ignoring MPD Status Update Dec 05 14:20:55 volumio volumio[776]: info: ------------------------------ 4ms Dec 05 14:20:55 volumio volumio[776]: info: ------------------------------ 2ms Dec 05 14:20:55 volumio volumio[776]: info: Dec 05 14:20:55 volumio volumio[776]: ---------------------------- MPD announces system playlist update Dec 05 14:20:55 volumio volumio[776]: info: Ignoring MPD Status Update Dec 05 14:20:55 volumio volumio[776]: info: ------------------------------ 2ms Dec 05 14:20:55 volumio volumio[776]: info: Dec 05 14:20:55 volumio volumio[776]: ---------------------------- MPD announces system playlist update Dec 05 14:20:55 volumio volumio[776]: info: Ignoring MPD Status Update Dec 05 14:20:55 volumio volumio[776]: info: ------------------------------ 2ms Dec 05 14:20:55 volumio volumio[776]: info: Dec 05 14:20:55 volumio volumio[776]: ---------------------------- MPD announces system playlist update Dec 05 14:20:55 volumio volumio[776]: info: Ignoring MPD Status Update Dec 05 14:20:55 volumio volumio[776]: info: ------------------------------ 2ms Dec 05 14:20:55 volumio volumio[776]: info: Dec 05 14:20:55 volumio volumio[776]: ---------------------------- MPD announces state update: player Dec 05 14:20:55 volumio volumio[776]: info: ControllerMpd::getState Dec 05 14:20:55 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:20:55 volumio volumio[776]: info: Dec 05 14:20:55 volumio volumio[776]: ---------------------------- MPD announces state update: player Dec 05 14:20:55 volumio volumio[776]: info: sendMpdCommand status took 46 milliseconds Dec 05 14:20:55 volumio volumio[776]: info: ControllerMpd::getState Dec 05 14:20:55 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:20:55 volumio volumio[776]: verbose: ControllerMpd::parseState Dec 05 14:20:55 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:20:55 volumio volumio[776]: info: sendMpdCommand status took 7 milliseconds Dec 05 14:20:55 volumio volumio[776]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 05 14:20:55 volumio volumio[776]: verbose: ControllerMpd::parseState Dec 05 14:20:55 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:20:55 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:20:55 volumio volumio[776]: info: ControllerMpd::pushState Dec 05 14:20:55 volumio volumio[776]: info: CoreCommandRouter::servicePushState Dec 05 14:20:55 volumio volumio[776]: verbose: In UPNP mode Dec 05 14:20:55 volumio volumio[776]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Unknown (streaming?)","artist":"Unknown","album":"Unknown","uri":"http://192.168.0.101:49152/upnp/service/local/47522154/0/1.wav","trackType":"wav"} Dec 05 14:20:55 volumio volumio[776]: verbose: CURRENT POSITION 0 Dec 05 14:20:55 volumio volumio[776]: info: CoreStateMachine::syncState stateService play Dec 05 14:20:55 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus stop Dec 05 14:20:55 volumio volumio[776]: info: ------------------------------ 71ms Dec 05 14:20:55 volumio volumio[776]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 05 14:20:55 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:20:55 volumio volumio[776]: info: ControllerMpd::pushState Dec 05 14:20:55 volumio volumio[776]: info: CoreCommandRouter::servicePushState Dec 05 14:20:55 volumio volumio[776]: verbose: In UPNP mode Dec 05 14:20:55 volumio volumio[776]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Unknown (streaming?)","artist":"Unknown","album":"Unknown","uri":"http://192.168.0.101:49152/upnp/service/local/47522154/0/1.wav","trackType":"wav"} Dec 05 14:20:55 volumio volumio[776]: verbose: CURRENT POSITION 0 Dec 05 14:20:55 volumio volumio[776]: info: CoreStateMachine::syncState stateService play Dec 05 14:20:55 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus play Dec 05 14:20:55 volumio volumio[776]: info: Received an update from plugin. extracting info from payload Dec 05 14:20:55 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 05 14:20:55 volumio volumio[776]: info: CoreStateMachine::pushState Dec 05 14:20:55 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 14:20:55 volumio volumio[776]: info: CoreCommandRouter::volumioPushState Dec 05 14:20:55 volumio volumio[776]: info: CoreStateMachine::pushState Dec 05 14:20:55 volumio volumio[776]: info: CoreCommandRouter::volumioPushState Dec 05 14:20:55 volumio volumio[776]: info: ------------------------------ 80ms Dec 05 14:20:55 volumio volumio[776]: info: CoreCommandRouter::volumioGetQueue Dec 05 14:20:55 volumio volumio[776]: info: CoreStateMachine::getQueue Dec 05 14:20:55 volumio volumio[776]: info: CorePlayQueue::getQueue Dec 05 14:20:55 volumio volumio[776]: info: CoreCommandRouter::volumioGetQueue Dec 05 14:20:55 volumio volumio[776]: info: CoreStateMachine::getQueue Dec 05 14:20:55 volumio volumio[776]: info: CorePlayQueue::getQueue Dec 05 14:20:58 volumio volumio[776]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 05 14:20:58 volumio volumio[776]: Error: connect ETIMEDOUT 128.242.245.43:443 Dec 05 14:20:58 volumio volumio[776]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 05 14:20:58 volumio volumio[776]: errno: -110, Dec 05 14:20:58 volumio volumio[776]: code: 'ETIMEDOUT', Dec 05 14:20:58 volumio volumio[776]: syscall: 'connect', Dec 05 14:20:58 volumio volumio[776]: address: '128.242.245.43', Dec 05 14:20:58 volumio volumio[776]: port: 443 Dec 05 14:20:58 volumio volumio[776]: } Dec 05 14:20:58 volumio volumio[776]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 05 14:20:58 volumio sudo[4562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-05 14:19 Dec 05 14:20:58 volumio sudo[4562]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 04:09:51 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="2fe933ffb50e198f3245fc325fd10b17"