-- Logs begin at Wed 2025-05-21 14:20:19 UTC, end at Wed 2025-05-21 14:33:55 UTC. -- May 21 14:32:03 volumio volumio[1192]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 14:32:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 21 14:32:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:32:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 21 14:32:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 14:32:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 14:32:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 14:32:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 14:32:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 14:32:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 14:32:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 14:32:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 21 14:32:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 21 14:32:06 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 14:32:06 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 14:32:06 volumio volumio[1192]: info: Discovery: Getting this device information May 21 14:32:06 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState May 21 14:32:06 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:06 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 14:32:07 volumio volumio[1192]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 21 14:32:07 volumio volumio[1192]: info: Retrying Login Due to Network Error May 21 14:32:11 volumio volumio[1192]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] May 21 14:32:11 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions May 21 14:32:11 volumio sudo[4495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 14:32:11 volumio sudo[4495]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:32:11 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:11 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:11 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:11 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:11 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:11 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:11 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:11 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:11 volumio sudo[4495]: pam_unix(sudo:session): session closed for user root May 21 14:32:11 volumio sudo[4497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 14:32:11 volumio sudo[4497]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:32:11 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:32:11 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 21 14:32:11 volumio systemd[1]: Stopping Music Player Daemon... May 21 14:32:11 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:11 volumio systemd[1]: mpd.service: Succeeded. May 21 14:32:11 volumio volumio[1192]: info: MPD Permissions set May 21 14:32:11 volumio systemd[1]: Stopped Music Player Daemon. May 21 14:32:11 volumio systemd[1]: Starting Music Player Daemon... May 21 14:32:11 volumio sudo[4506]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 14:32:11 volumio sudo[4506]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:32:11 volumio sudo[4506]: pam_unix(sudo:session): session closed for user root May 21 14:32:12 volumio mpd[4508]: May 21 14:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 21 14:32:12 volumio systemd[1]: Started Music Player Daemon. May 21 14:32:12 volumio sudo[4497]: pam_unix(sudo:session): session closed for user root May 21 14:32:12 volumio volumio[1192]: error: updateQueue error: null May 21 14:32:12 volumio volumio[1192]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] May 21 14:32:12 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions May 21 14:32:12 volumio sudo[4514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 14:32:12 volumio sudo[4514]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:32:12 volumio sudo[4514]: pam_unix(sudo:session): session closed for user root May 21 14:32:12 volumio volumio[1192]: info: MPD Permissions set May 21 14:32:12 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:12 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:12 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:12 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:12 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:12 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:12 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:12 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:12 volumio sudo[4517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 14:32:12 volumio sudo[4517]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:32:12 volumio systemd[1]: Stopping Music Player Daemon... May 21 14:32:12 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:32:12 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 21 14:32:12 volumio systemd[1]: mpd.service: Succeeded. May 21 14:32:12 volumio systemd[1]: Stopped Music Player Daemon. May 21 14:32:12 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:32:12 volumio systemd[1]: Starting Music Player Daemon... May 21 14:32:12 volumio sudo[4524]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 14:32:12 volumio sudo[4524]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:32:12 volumio sudo[4524]: pam_unix(sudo:session): session closed for user root May 21 14:32:13 volumio mpd[4528]: May 21 14:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 21 14:32:13 volumio systemd[1]: Started Music Player Daemon. May 21 14:32:13 volumio sudo[4517]: pam_unix(sudo:session): session closed for user root May 21 14:32:13 volumio volumio[1192]: error: updateQueue error: null May 21 14:32:17 volumio volumio[1192]: info: MyVolumio login type: Token May 21 14:32:20 volumio volumio[1192]: info: Clearing queue after UPNP request May 21 14:32:20 volumio volumio[1192]: info: CoreStateMachine::ClearQueue May 21 14:32:20 volumio volumio[1192]: info: CoreStateMachine::stop May 21 14:32:20 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 14:32:20 volumio volumio[1192]: info: CorePlayQueue::clearPlayQueue May 21 14:32:20 volumio volumio[1192]: info: CorePlayQueue::saveQueue May 21 14:32:20 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:20 volumio volumio[1192]: info: CoreCommandRouter::volumioPushQueue May 21 14:32:20 volumio volumio[1192]: error: Upnp client error: Error: This socket has been ended by the other party May 21 14:32:22 volumio volumio[1192]: info: Starting UPNP Playback May 21 14:32:22 volumio volumio[1192]: info: Preparing playback through UPNP May 21 14:32:22 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState May 21 14:32:22 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:22 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 14:32:22 volumio volumio[1192]: info: May 21 14:32:22 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:22 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:22 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:22 volumio volumio[1192]: info: May 21 14:32:22 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:22 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:22 volumio volumio[1192]: info: ------------------------------ 5ms May 21 14:32:22 volumio volumio[1192]: info: May 21 14:32:22 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:22 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:22 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:22 volumio volumio[1192]: info: May 21 14:32:22 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:22 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:22 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:22 volumio volumio[1192]: info: May 21 14:32:22 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:22 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:22 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:22 volumio volumio[1192]: info: May 21 14:32:22 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:22 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:22 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:23 volumio volumio[1192]: info: Clearing queue after UPNP request May 21 14:32:23 volumio volumio[1192]: info: May 21 14:32:23 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:32:23 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:32:23 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:32:23 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand clearerror May 21 14:32:23 volumio volumio[1192]: info: sendMpdCommand status took 5 milliseconds May 21 14:32:23 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:32:23 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:32:23 volumio volumio[1192]: info: sendMpdCommand clearerror took 2 milliseconds May 21 14:32:23 volumio volumio[1192]: info: sendMpdCommand playlistinfo took 3 milliseconds May 21 14:32:23 volumio volumio[1192]: verbose: ControllerMpd::parseTrackInfo May 21 14:32:23 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:32:23 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:32:23 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:23 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:32:23 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:23 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:23 volumio volumio[1192]: 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":"https://music.163.com/package/aHR0cDovL25wLnN5Y2RuLmt1d28uY24vMDcyMjlkYTM1ODE5ZTBlMDNhMzU1ODIyYTc5ZjEyNDIvNjgyZGUzZjQvcmVzb3VyY2UvbjIvMi84MS8xODI1ODIwNzgubXAzP2JpdHJhdGU=/159581.mp3","trackType":"mp3"} May 21 14:32:23 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:32:23 volumio volumio[1192]: info: CoreStateMachine::syncState stateService stop May 21 14:32:23 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus stop May 21 14:32:23 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:23 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:23 volumio volumio[1192]: info: No code May 21 14:32:23 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:23 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:23 volumio volumio[1192]: info: ------------------------------ 45ms May 21 14:32:24 volumio volumio[1192]: info: CoreStateMachine::ClearQueue May 21 14:32:24 volumio volumio[1192]: info: CoreStateMachine::stop May 21 14:32:24 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 14:32:24 volumio volumio[1192]: info: CorePlayQueue::clearPlayQueue May 21 14:32:24 volumio volumio[1192]: info: CorePlayQueue::saveQueue May 21 14:32:24 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:24 volumio volumio[1192]: info: CoreCommandRouter::volumioPushQueue May 21 14:32:24 volumio volumio[1192]: info: May 21 14:32:24 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:24 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:24 volumio volumio[1192]: error: updateQueue error: null May 21 14:32:24 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:24 volumio volumio[1192]: info: Starting UPNP Playback May 21 14:32:24 volumio volumio[1192]: info: Preparing playback through UPNP May 21 14:32:24 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState May 21 14:32:24 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:24 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 14:32:24 volumio volumio[1192]: info: May 21 14:32:24 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:24 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:24 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:24 volumio volumio[1192]: info: May 21 14:32:24 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:24 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:24 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:24 volumio volumio[1192]: info: May 21 14:32:24 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:24 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:24 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:24 volumio volumio[1192]: info: May 21 14:32:24 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:24 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:24 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:24 volumio volumio[1192]: info: May 21 14:32:24 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:24 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:24 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:24 volumio volumio[1192]: info: May 21 14:32:24 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:24 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:24 volumio volumio[1192]: info: ------------------------------ 2ms May 21 14:32:26 volumio volumio[1192]: info: May 21 14:32:26 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:32:26 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:32:26 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:32:26 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand clearerror May 21 14:32:26 volumio volumio[1192]: info: sendMpdCommand status took 5 milliseconds May 21 14:32:26 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:32:26 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:32:26 volumio volumio[1192]: info: sendMpdCommand clearerror took 2 milliseconds May 21 14:32:26 volumio volumio[1192]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 14:32:26 volumio volumio[1192]: verbose: ControllerMpd::parseTrackInfo May 21 14:32:26 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:32:26 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:32:26 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:26 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:26 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:26 volumio volumio[1192]: 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":"http://m801.music.126.net/20250521225723/02d6da038e5710e9105459f3c540dd5b/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/28482242887/20e6/1703/69fa/a04a747d4ef40c26f6a173ae9b641872.m4a?vuutv=3RaoeSAvRDYb0E85ASR022zMIN/fF3bd7Rsphx+DZfB51bY/ZbK2uEVrRbQcP2NVUtd7BS4wwkPX4w09aWqRi4Gq52/MjoJUChLGg/MR+mY=","trackType":""} May 21 14:32:26 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:32:26 volumio volumio[1192]: info: CoreStateMachine::syncState stateService stop May 21 14:32:26 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus stop May 21 14:32:26 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:26 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:26 volumio volumio[1192]: info: No code May 21 14:32:26 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:26 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:26 volumio volumio[1192]: info: ------------------------------ 37ms May 21 14:32:29 volumio volumio[1192]: info: May 21 14:32:29 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:32:29 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:32:29 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:32:29 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand clearerror May 21 14:32:29 volumio volumio[1192]: info: sendMpdCommand status took 5 milliseconds May 21 14:32:29 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:32:29 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:32:29 volumio volumio[1192]: info: sendMpdCommand clearerror took 2 milliseconds May 21 14:32:29 volumio volumio[1192]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 14:32:29 volumio volumio[1192]: verbose: ControllerMpd::parseTrackInfo May 21 14:32:29 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:32:29 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:32:29 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:29 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:29 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:29 volumio volumio[1192]: 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":"http://m801.music.126.net/20250521225723/02d6da038e5710e9105459f3c540dd5b/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/28482242887/20e6/1703/69fa/a04a747d4ef40c26f6a173ae9b641872.m4a?vuutv=3RaoeSAvRDYb0E85ASR022zMIN/fF3bd7Rsphx+DZfB51bY/ZbK2uEVrRbQcP2NVUtd7BS4wwkPX4w09aWqRi4Gq52/MjoJUChLGg/MR+mY=","trackType":""} May 21 14:32:29 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:32:29 volumio volumio[1192]: info: CoreStateMachine::syncState stateService stop May 21 14:32:29 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus stop May 21 14:32:29 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:29 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:29 volumio volumio[1192]: info: No code May 21 14:32:29 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:29 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:29 volumio volumio[1192]: info: ------------------------------ 34ms May 21 14:32:33 volumio volumio[1192]: info: Clearing queue after UPNP request May 21 14:32:34 volumio volumio[1192]: info: CoreStateMachine::ClearQueue May 21 14:32:34 volumio volumio[1192]: info: CoreStateMachine::stop May 21 14:32:34 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 14:32:34 volumio volumio[1192]: info: CorePlayQueue::clearPlayQueue May 21 14:32:34 volumio volumio[1192]: info: CorePlayQueue::saveQueue May 21 14:32:34 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:34 volumio volumio[1192]: info: CoreCommandRouter::volumioPushQueue May 21 14:32:34 volumio volumio[1192]: info: May 21 14:32:34 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:34 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:34 volumio volumio[1192]: error: updateQueue error: null May 21 14:32:34 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:34 volumio volumio[1192]: info: Starting UPNP Playback May 21 14:32:34 volumio volumio[1192]: info: Preparing playback through UPNP May 21 14:32:34 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState May 21 14:32:34 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:34 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 14:32:34 volumio volumio[1192]: info: May 21 14:32:34 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:34 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:34 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:34 volumio volumio[1192]: info: May 21 14:32:34 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:34 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:34 volumio volumio[1192]: info: ------------------------------ 2ms May 21 14:32:34 volumio volumio[1192]: info: May 21 14:32:34 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:34 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:34 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:34 volumio volumio[1192]: info: May 21 14:32:34 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:34 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:34 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:34 volumio volumio[1192]: info: May 21 14:32:34 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:34 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:34 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:34 volumio volumio[1192]: info: May 21 14:32:34 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:34 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:34 volumio volumio[1192]: info: ------------------------------ 2ms May 21 14:32:35 volumio volumio[1192]: info: May 21 14:32:35 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:32:35 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:32:35 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:32:35 volumio volumio[1192]: info: May 21 14:32:35 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:32:35 volumio volumio[1192]: info: sendMpdCommand status took 6 milliseconds May 21 14:32:35 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:32:35 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:32:35 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:32:35 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:32:35 volumio volumio[1192]: info: sendMpdCommand status took 1 milliseconds May 21 14:32:35 volumio volumio[1192]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 14:32:35 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:32:35 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:32:35 volumio volumio[1192]: verbose: ControllerMpd::parseTrackInfo May 21 14:32:35 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:32:35 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:32:35 volumio volumio[1192]: verbose: In UPNP mode May 21 14:32:35 volumio volumio[1192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"秋水悠悠","artist":"巫娜","album":"秋水悠悠","uri":"http://m701.music.126.net/20250521225733/f5e15afd08e13887695cfb1a5bf8070a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/28482217516/1eb6/0b04/6026/ee57629359f2b5d070a5672eeecf7be3.m4a?vuutv=6dDYPbCHbcaq/a9OI87B9DsgAaWJ9CmvDtpG1xAc9EVydkP7ntjVtSg7J4VlR72zXzc72KAWbgi0GTqxEAcEgOmOYtjoClTM+uoez+vIYko=","trackType":""} May 21 14:32:35 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:32:35 volumio volumio[1192]: info: CoreStateMachine::syncState stateService play May 21 14:32:35 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus stop May 21 14:32:35 volumio volumio[1192]: info: ------------------------------ 10ms May 21 14:32:35 volumio volumio[1192]: info: sendMpdCommand playlistinfo took 5 milliseconds May 21 14:32:35 volumio volumio[1192]: verbose: ControllerMpd::parseTrackInfo May 21 14:32:35 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:32:35 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:32:35 volumio volumio[1192]: verbose: In UPNP mode May 21 14:32:35 volumio volumio[1192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"秋水悠悠","artist":"巫娜","album":"秋水悠悠","uri":"http://m701.music.126.net/20250521225733/f5e15afd08e13887695cfb1a5bf8070a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/28482217516/1eb6/0b04/6026/ee57629359f2b5d070a5672eeecf7be3.m4a?vuutv=6dDYPbCHbcaq/a9OI87B9DsgAaWJ9CmvDtpG1xAc9EVydkP7ntjVtSg7J4VlR72zXzc72KAWbgi0GTqxEAcEgOmOYtjoClTM+uoez+vIYko=","trackType":""} May 21 14:32:35 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:32:35 volumio volumio[1192]: info: CoreStateMachine::syncState stateService play May 21 14:32:35 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus play May 21 14:32:35 volumio volumio[1192]: info: Received an update from plugin. extracting info from payload May 21 14:32:35 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 14:32:35 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:35 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:32:35 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:35 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:35 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:35 volumio volumio[1192]: info: ------------------------------ 23ms May 21 14:32:35 volumio volumio[1192]: info: CoreCommandRouter::volumioGetQueue May 21 14:32:35 volumio volumio[1192]: info: CoreStateMachine::getQueue May 21 14:32:35 volumio volumio[1192]: info: CorePlayQueue::getQueue May 21 14:32:35 volumio volumio[1192]: info: CoreCommandRouter::volumioGetQueue May 21 14:32:35 volumio volumio[1192]: info: CoreStateMachine::getQueue May 21 14:32:35 volumio volumio[1192]: info: CorePlayQueue::getQueue May 21 14:32:39 volumio volumio[1192]: info: Clearing queue after UPNP request May 21 14:32:40 volumio volumio[1192]: info: CoreStateMachine::ClearQueue May 21 14:32:40 volumio volumio[1192]: info: CoreStateMachine::stop May 21 14:32:40 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 14:32:40 volumio volumio[1192]: info: CoreStateMachine::stPlaybackTimer May 21 14:32:40 volumio volumio[1192]: info: CoreStateMachine::updateTrackBlock May 21 14:32:40 volumio volumio[1192]: info: CorePlayQueue::getTrackBlock May 21 14:32:40 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:40 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:40 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:32:40 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:40 volumio volumio[1192]: info: CoreStateMachine::serviceStop May 21 14:32:40 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:40 volumio volumio[1192]: info: ControllerMpd::stop May 21 14:32:40 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand stop May 21 14:32:40 volumio volumio[1192]: info: CorePlayQueue::clearPlayQueue May 21 14:32:40 volumio volumio[1192]: info: CorePlayQueue::saveQueue May 21 14:32:40 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:40 volumio volumio[1192]: info: CoreCommandRouter::volumioPushQueue May 21 14:32:40 volumio volumio[1192]: info: May 21 14:32:40 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:32:40 volumio volumio[1192]: info: sendMpdCommand stop took 22 milliseconds May 21 14:32:40 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:32:40 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:32:40 volumio volumio[1192]: info: May 21 14:32:40 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:40 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:40 volumio volumio[1192]: error: updateQueue error: null May 21 14:32:40 volumio volumio[1192]: info: sendMpdCommand status took 1 milliseconds May 21 14:32:40 volumio volumio[1192]: info: ------------------------------ 2ms May 21 14:32:40 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:32:40 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:32:40 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:32:40 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:40 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:40 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:40 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:40 volumio volumio[1192]: 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 21 14:32:40 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:32:40 volumio volumio[1192]: info: CoreStateMachine::syncState stateService stop May 21 14:32:40 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus stop May 21 14:32:40 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:40 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:40 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:40 volumio volumio[1192]: info: No code May 21 14:32:40 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:40 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:40 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:40 volumio volumio[1192]: info: ------------------------------ 22ms May 21 14:32:40 volumio volumio[1192]: info: Starting UPNP Playback May 21 14:32:40 volumio volumio[1192]: info: Preparing playback through UPNP May 21 14:32:40 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState May 21 14:32:40 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:40 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 14:32:40 volumio volumio[1192]: info: May 21 14:32:40 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:40 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:40 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:40 volumio volumio[1192]: info: May 21 14:32:40 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:40 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:40 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:40 volumio volumio[1192]: info: May 21 14:32:40 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:40 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:40 volumio volumio[1192]: info: ------------------------------ 2ms May 21 14:32:40 volumio volumio[1192]: info: May 21 14:32:40 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:40 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:40 volumio volumio[1192]: info: ------------------------------ 2ms May 21 14:32:40 volumio volumio[1192]: info: May 21 14:32:40 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:40 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:40 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:40 volumio volumio[1192]: info: May 21 14:32:40 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:40 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:40 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:41 volumio volumio[1192]: info: May 21 14:32:41 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:32:41 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:32:41 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:32:41 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand clearerror May 21 14:32:41 volumio volumio[1192]: info: sendMpdCommand status took 4 milliseconds May 21 14:32:41 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:32:41 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:32:41 volumio volumio[1192]: info: sendMpdCommand clearerror took 1 milliseconds May 21 14:32:41 volumio volumio[1192]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 14:32:41 volumio volumio[1192]: verbose: ControllerMpd::parseTrackInfo May 21 14:32:41 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:32:41 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:32:41 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:41 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:32:41 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:41 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:41 volumio volumio[1192]: 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":"http://m701.music.126.net/20250521225739/a048cfce0dc927851d1288c244f538db/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/28482242887/20e6/1703/69fa/a04a747d4ef40c26f6a173ae9b641872.m4a?vuutv=ScvGz3m17qdt20Lv8gb6rDs2IYVtnzngevC5EMktLLYYy1+lP46xLDHAHlgmMB7FMc04075vj6GG2eqg7e5GnZWENj6bbVkNsYspVpbWpQk=","trackType":""} May 21 14:32:41 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:32:41 volumio volumio[1192]: info: CoreStateMachine::syncState stateService stop May 21 14:32:41 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus stop May 21 14:32:41 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:41 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:41 volumio volumio[1192]: info: No code May 21 14:32:41 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:41 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:41 volumio volumio[1192]: info: ------------------------------ 35ms May 21 14:32:41 volumio volumio[1192]: info: CoreCommandRouter::volumioGetQueue May 21 14:32:41 volumio volumio[1192]: info: CoreStateMachine::getQueue May 21 14:32:41 volumio volumio[1192]: info: CorePlayQueue::getQueue May 21 14:32:41 volumio volumio[1192]: info: CoreCommandRouter::volumioGetQueue May 21 14:32:41 volumio volumio[1192]: info: CoreStateMachine::getQueue May 21 14:32:41 volumio volumio[1192]: info: CorePlayQueue::getQueue May 21 14:32:41 volumio volumio[1192]: info: CoreCommandRouter::volumioGetQueue May 21 14:32:41 volumio volumio[1192]: info: CoreStateMachine::getQueue May 21 14:32:41 volumio volumio[1192]: info: CorePlayQueue::getQueue May 21 14:32:41 volumio volumio[1192]: info: May 21 14:32:41 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:32:41 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:32:41 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:32:41 volumio volumio[1192]: info: May 21 14:32:41 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:32:41 volumio volumio[1192]: info: sendMpdCommand status took 5 milliseconds May 21 14:32:41 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:32:41 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:32:41 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:32:41 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:32:41 volumio volumio[1192]: info: sendMpdCommand status took 2 milliseconds May 21 14:32:41 volumio volumio[1192]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 14:32:41 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:32:41 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:32:41 volumio volumio[1192]: verbose: ControllerMpd::parseTrackInfo May 21 14:32:41 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:32:41 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:32:41 volumio volumio[1192]: verbose: In UPNP mode May 21 14:32:41 volumio volumio[1192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":547,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"心游太玄","artist":"巫娜","album":"禅踪","uri":"http://m701.music.126.net/20250521225739/a048cfce0dc927851d1288c244f538db/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/28482242887/20e6/1703/69fa/a04a747d4ef40c26f6a173ae9b641872.m4a?vuutv=ScvGz3m17qdt20Lv8gb6rDs2IYVtnzngevC5EMktLLYYy1+lP46xLDHAHlgmMB7FMc04075vj6GG2eqg7e5GnZWENj6bbVkNsYspVpbWpQk=","trackType":""} May 21 14:32:41 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:32:41 volumio volumio[1192]: info: CoreStateMachine::syncState stateService play May 21 14:32:41 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus stop May 21 14:32:41 volumio volumio[1192]: info: ------------------------------ 9ms May 21 14:32:41 volumio volumio[1192]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 14:32:41 volumio volumio[1192]: verbose: ControllerMpd::parseTrackInfo May 21 14:32:41 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:32:41 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:32:41 volumio volumio[1192]: verbose: In UPNP mode May 21 14:32:41 volumio volumio[1192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":547,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"心游太玄","artist":"巫娜","album":"禅踪","uri":"http://m701.music.126.net/20250521225739/a048cfce0dc927851d1288c244f538db/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/28482242887/20e6/1703/69fa/a04a747d4ef40c26f6a173ae9b641872.m4a?vuutv=ScvGz3m17qdt20Lv8gb6rDs2IYVtnzngevC5EMktLLYYy1+lP46xLDHAHlgmMB7FMc04075vj6GG2eqg7e5GnZWENj6bbVkNsYspVpbWpQk=","trackType":""} May 21 14:32:41 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:32:41 volumio volumio[1192]: info: CoreStateMachine::syncState stateService play May 21 14:32:41 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus play May 21 14:32:41 volumio volumio[1192]: info: Received an update from plugin. extracting info from payload May 21 14:32:41 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 14:32:41 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:41 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:32:41 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:41 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:41 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:41 volumio volumio[1192]: info: ------------------------------ 20ms May 21 14:32:41 volumio volumio[1192]: info: CoreCommandRouter::volumioGetQueue May 21 14:32:41 volumio volumio[1192]: info: CoreStateMachine::getQueue May 21 14:32:41 volumio volumio[1192]: info: CorePlayQueue::getQueue May 21 14:32:41 volumio volumio[1192]: info: CoreCommandRouter::volumioGetQueue May 21 14:32:41 volumio volumio[1192]: info: CoreStateMachine::getQueue May 21 14:32:41 volumio volumio[1192]: info: CorePlayQueue::getQueue May 21 14:32:44 volumio volumio[1192]: info: May 21 14:32:44 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:32:44 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:32:44 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:32:44 volumio volumio[1192]: info: sendMpdCommand status took 1 milliseconds May 21 14:32:44 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:32:44 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:32:44 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:32:44 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:44 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:44 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:44 volumio volumio[1192]: 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 21 14:32:44 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:32:44 volumio volumio[1192]: info: CoreStateMachine::syncState stateService stop May 21 14:32:44 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus play May 21 14:32:44 volumio volumio[1192]: info: CoreStateMachine::play index undefined May 21 14:32:44 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 14:32:44 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:44 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:44 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:32:44 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:44 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:44 volumio volumio[1192]: info: ------------------------------ 13ms May 21 14:32:44 volumio volumio[1192]: info: CoreCommandRouter::volumioGetQueue May 21 14:32:44 volumio volumio[1192]: info: CoreStateMachine::getQueue May 21 14:32:44 volumio volumio[1192]: info: CorePlayQueue::getQueue May 21 14:32:47 volumio volumio[1192]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 21 14:32:47 volumio volumio[1192]: info: Retrying Login Due to Network Error May 21 14:32:49 volumio volumio[1192]: info: Clearing queue after UPNP request May 21 14:32:50 volumio volumio[1192]: info: CoreStateMachine::ClearQueue May 21 14:32:50 volumio volumio[1192]: info: CoreStateMachine::stop May 21 14:32:50 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 14:32:50 volumio volumio[1192]: info: CorePlayQueue::clearPlayQueue May 21 14:32:50 volumio volumio[1192]: info: CorePlayQueue::saveQueue May 21 14:32:50 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:50 volumio volumio[1192]: info: CoreCommandRouter::volumioPushQueue May 21 14:32:50 volumio volumio[1192]: info: May 21 14:32:50 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:50 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:50 volumio volumio[1192]: error: updateQueue error: null May 21 14:32:50 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:50 volumio volumio[1192]: info: Starting UPNP Playback May 21 14:32:50 volumio volumio[1192]: info: Preparing playback through UPNP May 21 14:32:50 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState May 21 14:32:50 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:32:50 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 14:32:50 volumio volumio[1192]: info: May 21 14:32:50 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:50 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:50 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:50 volumio volumio[1192]: info: May 21 14:32:50 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:50 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:50 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:50 volumio volumio[1192]: info: May 21 14:32:50 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:50 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:50 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:50 volumio volumio[1192]: info: May 21 14:32:50 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:50 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:50 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:50 volumio volumio[1192]: info: May 21 14:32:50 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:50 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:50 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:50 volumio volumio[1192]: info: May 21 14:32:50 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:32:50 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:32:50 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:32:50 volumio volumio[1192]: info: May 21 14:32:50 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:32:50 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:32:50 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:32:50 volumio volumio[1192]: info: May 21 14:32:50 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:32:50 volumio volumio[1192]: info: sendMpdCommand status took 2 milliseconds May 21 14:32:50 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:32:50 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:32:50 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:32:50 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:32:50 volumio volumio[1192]: info: sendMpdCommand status took 9 milliseconds May 21 14:32:50 volumio volumio[1192]: info: sendMpdCommand playlistinfo took 9 milliseconds May 21 14:32:50 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:32:50 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:32:50 volumio volumio[1192]: verbose: ControllerMpd::parseTrackInfo May 21 14:32:50 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:32:50 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:32:50 volumio volumio[1192]: verbose: In UPNP mode May 21 14:32:50 volumio volumio[1192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"秋水悠悠","artist":"巫娜","album":"秋水悠悠","uri":"http://m701.music.126.net/20250521225749/d91f126cac45d6a0f01a199c60fb09d4/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/28482217516/1eb6/0b04/6026/ee57629359f2b5d070a5672eeecf7be3.m4a?vuutv=tv8ATBY2MgXGgkQnG3N2aHv+4fyvjDIeRL3mUXK/IeCMa6vvlenW79Oxd27niE2ZVVpYU/41jadABnia5Hb/dA/2E+YYEvrHbwb0kOlJLHg=","trackType":""} May 21 14:32:50 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:32:50 volumio volumio[1192]: info: CoreStateMachine::syncState stateService play May 21 14:32:50 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus stop May 21 14:32:50 volumio volumio[1192]: info: ------------------------------ 14ms May 21 14:32:50 volumio volumio[1192]: info: sendMpdCommand playlistinfo took 3 milliseconds May 21 14:32:50 volumio volumio[1192]: verbose: ControllerMpd::parseTrackInfo May 21 14:32:50 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:32:50 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:32:50 volumio volumio[1192]: verbose: In UPNP mode May 21 14:32:50 volumio volumio[1192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57,"duration":219,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"秋水悠悠","artist":"巫娜","album":"秋水悠悠","uri":"http://m701.music.126.net/20250521225749/d91f126cac45d6a0f01a199c60fb09d4/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/28482217516/1eb6/0b04/6026/ee57629359f2b5d070a5672eeecf7be3.m4a?vuutv=tv8ATBY2MgXGgkQnG3N2aHv+4fyvjDIeRL3mUXK/IeCMa6vvlenW79Oxd27niE2ZVVpYU/41jadABnia5Hb/dA/2E+YYEvrHbwb0kOlJLHg=","trackType":""} May 21 14:32:50 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:32:50 volumio volumio[1192]: info: CoreStateMachine::syncState stateService play May 21 14:32:50 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus play May 21 14:32:50 volumio volumio[1192]: info: Received an update from plugin. extracting info from payload May 21 14:32:50 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 14:32:50 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:50 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:32:50 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:50 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:32:50 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:32:50 volumio volumio[1192]: info: ------------------------------ 45ms May 21 14:32:50 volumio volumio[1192]: info: CoreCommandRouter::volumioGetQueue May 21 14:32:50 volumio volumio[1192]: info: CoreStateMachine::getQueue May 21 14:32:50 volumio volumio[1192]: info: CorePlayQueue::getQueue May 21 14:32:50 volumio volumio[1192]: info: CoreCommandRouter::volumioGetQueue May 21 14:32:50 volumio volumio[1192]: info: CoreStateMachine::getQueue May 21 14:32:50 volumio volumio[1192]: info: CorePlayQueue::getQueue May 21 14:32:53 volumio volumio[1192]: verbose: New Socket.io Connection to 192.168.1.188 from 192.168.1.81 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 5 May 21 14:32:53 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState May 21 14:32:53 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 21 14:32:53 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 21 14:32:53 volumio volumio[1192]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B7%AB%E5%A8%9C/%E7%A7%8B%E6%B0%B4%E6%82%A0%E6%82%A0/bbfa522d-a19c-4a84-b5ee-4331ec29b3c7.jpg' May 21 14:32:53 volumio volumio[1192]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B7%AB%E5%A8%9C/%E7%A7%8B%E6%B0%B4%E6%82%A0%E6%82%A0/bbfa522d-a19c-4a84-b5ee-4331ec29b3c7.jpg' May 21 14:32:54 volumio volumio[1192]: verbose: New Socket.io Connection to 192.168.1.188 from 192.168.1.81 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 May 21 14:32:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 14:32:54 volumio volumio[1192]: info: CoreCommandRouter::volumioGetVisibleSources May 21 14:32:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 14:32:54 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState May 21 14:32:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 21 14:32:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 21 14:32:54 volumio volumio[1192]: info: Received Get System Info May 21 14:32:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 14:32:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 14:32:54 volumio volumio[1192]: info: Discovery: Getting this device information May 21 14:32:54 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState May 21 14:32:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 14:32:54 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState May 21 14:32:54 volumio volumio[1192]: info: Listing playlists May 21 14:32:54 volumio volumio[1192]: info: CoreCommandRouter::volumioGetQueue May 21 14:32:54 volumio volumio[1192]: info: CoreStateMachine::getQueue May 21 14:32:54 volumio volumio[1192]: info: CorePlayQueue::getQueue May 21 14:32:54 volumio volumio[1192]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B7%AB%E5%A8%9C/%E7%A7%8B%E6%B0%B4%E6%82%A0%E6%82%A0/bbfa522d-a19c-4a84-b5ee-4331ec29b3c7.jpg' May 21 14:32:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 21 14:32:57 volumio volumio[1192]: info: MyVolumio login type: Token May 21 14:33:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 14:33:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 14:33:00 volumio volumio[1192]: info: Discovery: Getting this device information May 21 14:33:00 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState May 21 14:33:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 14:33:03 volumio volumio[1192]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] May 21 14:33:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions May 21 14:33:03 volumio sudo[4773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 14:33:03 volumio sudo[4773]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:33:03 volumio sudo[4773]: pam_unix(sudo:session): session closed for user root May 21 14:33:03 volumio volumio[1192]: info: MPD Permissions set May 21 14:33:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:03 volumio sudo[4776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 14:33:03 volumio sudo[4776]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:33:03 volumio systemd[1]: Stopping Music Player Daemon... May 21 14:33:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:33:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 21 14:33:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:03 volumio systemd[1]: mpd.service: Succeeded. May 21 14:33:03 volumio systemd[1]: Stopped Music Player Daemon. May 21 14:33:03 volumio systemd[1]: Starting Music Player Daemon... May 21 14:33:03 volumio sudo[4784]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 14:33:03 volumio sudo[4784]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:33:03 volumio sudo[4784]: pam_unix(sudo:session): session closed for user root May 21 14:33:04 volumio mpd[4786]: May 21 14:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 21 14:33:04 volumio systemd[1]: Started Music Player Daemon. May 21 14:33:04 volumio sudo[4776]: pam_unix(sudo:session): session closed for user root May 21 14:33:04 volumio volumio[1192]: error: updateQueue error: null May 21 14:33:19 volumio volumio[1192]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] May 21 14:33:19 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts May 21 14:33:19 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions May 21 14:33:19 volumio sudo[4813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 14:33:19 volumio sudo[4813]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:33:19 volumio sudo[4813]: pam_unix(sudo:session): session closed for user root May 21 14:33:19 volumio volumio[1192]: info: MPD Permissions set May 21 14:33:19 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:19 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:19 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:19 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:19 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:19 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:19 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:19 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:19 volumio sudo[4815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 14:33:19 volumio sudo[4815]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:33:19 volumio systemd[1]: Stopping Music Player Daemon... May 21 14:33:19 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:33:19 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 21 14:33:19 volumio systemd[1]: mpd.service: Succeeded. May 21 14:33:19 volumio systemd[1]: Stopped Music Player Daemon. May 21 14:33:19 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:33:19 volumio systemd[1]: Starting Music Player Daemon... May 21 14:33:19 volumio sudo[4823]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 14:33:19 volumio sudo[4823]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:33:19 volumio sudo[4823]: pam_unix(sudo:session): session closed for user root May 21 14:33:20 volumio mpd[4826]: May 21 14:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 21 14:33:20 volumio systemd[1]: Started Music Player Daemon. May 21 14:33:20 volumio sudo[4815]: pam_unix(sudo:session): session closed for user root May 21 14:33:20 volumio volumio[1192]: error: updateQueue error: null May 21 14:33:24 volumio volumio[1192]: info: Clearing queue after UPNP request May 21 14:33:25 volumio volumio[1192]: info: CoreStateMachine::ClearQueue May 21 14:33:25 volumio volumio[1192]: info: CoreStateMachine::stop May 21 14:33:25 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 14:33:25 volumio volumio[1192]: info: CoreStateMachine::stPlaybackTimer May 21 14:33:25 volumio volumio[1192]: info: CoreStateMachine::updateTrackBlock May 21 14:33:25 volumio volumio[1192]: info: CorePlayQueue::getTrackBlock May 21 14:33:25 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:33:25 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:33:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:33:25 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:33:25 volumio volumio[1192]: info: CoreStateMachine::serviceStop May 21 14:33:25 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:33:25 volumio volumio[1192]: info: ControllerMpd::stop May 21 14:33:25 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand stop May 21 14:33:25 volumio volumio[1192]: info: CorePlayQueue::clearPlayQueue May 21 14:33:25 volumio volumio[1192]: info: CorePlayQueue::saveQueue May 21 14:33:25 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:33:25 volumio volumio[1192]: info: CoreCommandRouter::volumioPushQueue May 21 14:33:25 volumio volumio[1192]: error: Upnp client error: Error: This socket has been ended by the other party May 21 14:33:25 volumio volumio[1192]: info: sendMpdCommand stop took 16 milliseconds May 21 14:33:26 volumio volumio[1192]: info: Starting UPNP Playback May 21 14:33:26 volumio volumio[1192]: info: Preparing playback through UPNP May 21 14:33:26 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState May 21 14:33:26 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:33:26 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 14:33:27 volumio volumio[1192]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 21 14:33:27 volumio volumio[1192]: info: Retrying Login Due to Network Error May 21 14:33:27 volumio volumio[1192]: info: May 21 14:33:27 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:33:27 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:33:27 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:33:27 volumio volumio[1192]: info: May 21 14:33:27 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:33:27 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:33:27 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:33:27 volumio volumio[1192]: info: May 21 14:33:27 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:33:27 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:33:27 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:33:27 volumio volumio[1192]: info: May 21 14:33:27 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:33:27 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:33:27 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:33:27 volumio volumio[1192]: info: May 21 14:33:27 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:33:27 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:33:27 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:33:27 volumio volumio[1192]: info: May 21 14:33:27 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:33:27 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:33:27 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:33:27 volumio volumio[1192]: info: May 21 14:33:27 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:33:27 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:33:27 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:33:27 volumio volumio[1192]: info: May 21 14:33:27 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:33:27 volumio volumio[1192]: info: sendMpdCommand status took 7 milliseconds May 21 14:33:27 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:33:27 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:33:27 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:33:27 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:33:27 volumio volumio[1192]: info: sendMpdCommand status took 1 milliseconds May 21 14:33:27 volumio volumio[1192]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 14:33:27 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:33:27 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:33:27 volumio volumio[1192]: verbose: ControllerMpd::parseTrackInfo May 21 14:33:27 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:33:27 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:33:27 volumio volumio[1192]: verbose: In UPNP mode May 21 14:33:27 volumio volumio[1192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":811,"samplerate":"11.025 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"心游太玄","artist":"巫娜","album":"禅踪","uri":"http://m701.music.126.net/20250521225824/bcb918dd4a0b8bd9c37445b03aaf793e/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/28482242887/20e6/1703/69fa/a04a747d4ef40c26f6a173ae9b641872.m4a?vuutv=RcyXonh46rYMc6Awlg7RPpKDgwVELylBd8g/O3KFdeMd+tmEjevRF4BkLaAngrzG+2H3fbHbKhwPBkxeB4AkEGVbjLURoNZvBG/NclyafqY=","trackType":""} May 21 14:33:27 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:33:27 volumio volumio[1192]: info: CoreStateMachine::syncState stateService play May 21 14:33:27 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus stop May 21 14:33:27 volumio volumio[1192]: info: ------------------------------ 13ms May 21 14:33:27 volumio volumio[1192]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 14:33:27 volumio volumio[1192]: verbose: ControllerMpd::parseTrackInfo May 21 14:33:27 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:33:27 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:33:27 volumio volumio[1192]: verbose: In UPNP mode May 21 14:33:27 volumio volumio[1192]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":811,"samplerate":"11.025 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"心游太玄","artist":"巫娜","album":"禅踪","uri":"http://m701.music.126.net/20250521225824/bcb918dd4a0b8bd9c37445b03aaf793e/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/28482242887/20e6/1703/69fa/a04a747d4ef40c26f6a173ae9b641872.m4a?vuutv=RcyXonh46rYMc6Awlg7RPpKDgwVELylBd8g/O3KFdeMd+tmEjevRF4BkLaAngrzG+2H3fbHbKhwPBkxeB4AkEGVbjLURoNZvBG/NclyafqY=","trackType":""} May 21 14:33:27 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:33:27 volumio volumio[1192]: info: CoreStateMachine::syncState stateService play May 21 14:33:27 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus play May 21 14:33:27 volumio volumio[1192]: info: Received an update from plugin. extracting info from payload May 21 14:33:27 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 14:33:27 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:33:27 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:33:27 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:33:27 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:33:27 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:33:27 volumio volumio[1192]: info: ------------------------------ 25ms May 21 14:33:27 volumio volumio[1192]: info: CoreCommandRouter::volumioGetQueue May 21 14:33:27 volumio volumio[1192]: info: CoreStateMachine::getQueue May 21 14:33:27 volumio volumio[1192]: info: CorePlayQueue::getQueue May 21 14:33:27 volumio volumio[1192]: info: CoreCommandRouter::volumioGetQueue May 21 14:33:27 volumio volumio[1192]: info: CoreStateMachine::getQueue May 21 14:33:27 volumio volumio[1192]: info: CorePlayQueue::getQueue May 21 14:33:30 volumio volumio[1192]: info: May 21 14:33:30 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:33:30 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:33:30 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:33:30 volumio volumio[1192]: info: sendMpdCommand status took 1 milliseconds May 21 14:33:30 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:33:30 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:33:30 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:33:30 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:33:30 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:33:30 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:33:30 volumio volumio[1192]: 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 21 14:33:30 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:33:30 volumio volumio[1192]: info: CoreStateMachine::syncState stateService stop May 21 14:33:30 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus play May 21 14:33:30 volumio volumio[1192]: info: CoreStateMachine::play index undefined May 21 14:33:30 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 14:33:30 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:33:30 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:33:30 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:33:30 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:33:30 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:33:30 volumio volumio[1192]: info: ------------------------------ 28ms May 21 14:33:30 volumio volumio[1192]: info: CoreCommandRouter::volumioGetQueue May 21 14:33:30 volumio volumio[1192]: info: CoreStateMachine::getQueue May 21 14:33:30 volumio volumio[1192]: info: CorePlayQueue::getQueue May 21 14:33:34 volumio volumio[1192]: info: Clearing queue after UPNP request May 21 14:33:34 volumio volumio[1192]: info: CoreStateMachine::ClearQueue May 21 14:33:34 volumio volumio[1192]: info: CoreStateMachine::stop May 21 14:33:34 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 14:33:34 volumio volumio[1192]: info: CorePlayQueue::clearPlayQueue May 21 14:33:34 volumio volumio[1192]: info: CorePlayQueue::saveQueue May 21 14:33:34 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:33:34 volumio volumio[1192]: info: CoreCommandRouter::volumioPushQueue May 21 14:33:34 volumio volumio[1192]: info: May 21 14:33:34 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:33:34 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:33:34 volumio volumio[1192]: error: updateQueue error: null May 21 14:33:34 volumio volumio[1192]: info: ------------------------------ 5ms May 21 14:33:34 volumio volumio[1192]: info: Starting UPNP Playback May 21 14:33:34 volumio volumio[1192]: info: Preparing playback through UPNP May 21 14:33:34 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState May 21 14:33:34 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:33:34 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 14:33:34 volumio volumio[1192]: info: May 21 14:33:34 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:33:34 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:33:34 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:33:34 volumio volumio[1192]: info: May 21 14:33:34 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:33:34 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:33:34 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:33:34 volumio volumio[1192]: info: May 21 14:33:34 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:33:34 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:33:34 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:33:34 volumio volumio[1192]: info: May 21 14:33:34 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:33:34 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:33:34 volumio volumio[1192]: info: ------------------------------ 0ms May 21 14:33:34 volumio volumio[1192]: info: May 21 14:33:34 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:33:34 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:33:34 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:33:34 volumio volumio[1192]: info: May 21 14:33:34 volumio volumio[1192]: ---------------------------- MPD announces system playlist update May 21 14:33:34 volumio volumio[1192]: info: Ignoring MPD Status Update May 21 14:33:34 volumio volumio[1192]: info: ------------------------------ 1ms May 21 14:33:35 volumio volumio[1192]: info: May 21 14:33:35 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:33:35 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:33:35 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:33:35 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand clearerror May 21 14:33:35 volumio volumio[1192]: info: sendMpdCommand status took 7 milliseconds May 21 14:33:35 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:33:35 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:33:35 volumio volumio[1192]: info: sendMpdCommand clearerror took 2 milliseconds May 21 14:33:35 volumio volumio[1192]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 14:33:35 volumio volumio[1192]: verbose: ControllerMpd::parseTrackInfo May 21 14:33:35 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:33:35 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:33:35 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:33:35 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:33:35 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:33:35 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:33:35 volumio volumio[1192]: 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":"https://music.163.com/package/aHR0cDovL25wLnN5Y2RuLmt1d28uY24vZDJhOWU4M2I5OWNiYmY4NmVhZmZkYzUwMzIzZjhjZGUvNjgyZGU0M2QvcmVzb3VyY2UvbjIvMi84MS8xODI1ODIwNzgubXAzP2JpdHJhdGU=/159581.mp3","trackType":"mp3"} May 21 14:33:35 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:33:35 volumio volumio[1192]: info: CoreStateMachine::syncState stateService stop May 21 14:33:35 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus stop May 21 14:33:35 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:33:35 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:33:35 volumio volumio[1192]: info: No code May 21 14:33:35 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:33:35 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:33:35 volumio volumio[1192]: info: ------------------------------ 42ms May 21 14:33:37 volumio volumio[1192]: info: MyVolumio login type: Token May 21 14:33:39 volumio volumio[1192]: info: May 21 14:33:39 volumio volumio[1192]: ---------------------------- MPD announces state update: player May 21 14:33:39 volumio volumio[1192]: info: ControllerMpd::getState May 21 14:33:39 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand status May 21 14:33:39 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand clearerror May 21 14:33:39 volumio volumio[1192]: info: sendMpdCommand status took 5 milliseconds May 21 14:33:39 volumio volumio[1192]: verbose: ControllerMpd::parseState May 21 14:33:39 volumio volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:33:39 volumio volumio[1192]: info: sendMpdCommand clearerror took 1 milliseconds May 21 14:33:39 volumio volumio[1192]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 14:33:39 volumio volumio[1192]: verbose: ControllerMpd::parseTrackInfo May 21 14:33:39 volumio volumio[1192]: info: ControllerMpd::pushState May 21 14:33:39 volumio volumio[1192]: info: CoreCommandRouter::servicePushState May 21 14:33:39 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:33:39 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:33:39 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 May 21 14:33:39 volumio volumio[1192]: 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":"https://music.163.com/package/aHR0cDovL25wLnN5Y2RuLmt1d28uY24vZDJhOWU4M2I5OWNiYmY4NmVhZmZkYzUwMzIzZjhjZGUvNjgyZGU0M2QvcmVzb3VyY2UvbjIvMi84MS8xODI1ODIwNzgubXAzP2JpdHJhdGU=/159581.mp3","trackType":"mp3"} May 21 14:33:39 volumio volumio[1192]: verbose: CURRENT POSITION 0 May 21 14:33:39 volumio volumio[1192]: info: CoreStateMachine::syncState stateService stop May 21 14:33:39 volumio volumio[1192]: info: CoreStateMachine::syncState currentStatus stop May 21 14:33:39 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:33:39 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:33:39 volumio volumio[1192]: info: No code May 21 14:33:39 volumio volumio[1192]: info: CoreStateMachine::pushState May 21 14:33:39 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState May 21 14:33:39 volumio volumio[1192]: info: ------------------------------ 27ms May 21 14:33:52 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 14:33:52 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 14:33:52 volumio volumio[1192]: info: Discovery: Getting this device information May 21 14:33:52 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState May 21 14:33:52 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 14:33:54 volumio volumio[1192]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 14:33:54 volumio volumio[1192]: Error: connect ETIMEDOUT 108.160.169.37:443 May 21 14:33:54 volumio volumio[1192]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 21 14:33:54 volumio volumio[1192]: errno: -110, May 21 14:33:54 volumio volumio[1192]: code: 'ETIMEDOUT', May 21 14:33:54 volumio volumio[1192]: syscall: 'connect', May 21 14:33:54 volumio volumio[1192]: address: '108.160.169.37', May 21 14:33:54 volumio volumio[1192]: port: 443 May 21 14:33:54 volumio volumio[1192]: } May 21 14:33:54 volumio volumio[1192]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 14:33:55 volumio sudo[5004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 14:32 May 21 14:33:55 volumio sudo[5004]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"