-- Logs begin at Sat 2025-04-26 20:03:01 CST, end at Thu 2025-05-15 01:15:12 CST. -- May 15 01:14:00 lp systemd[1]: mpd.service: Succeeded. May 15 01:14:00 lp systemd[1]: Stopped Music Player Daemon. May 15 01:14:00 lp systemd[1]: Starting Music Player Daemon... May 15 01:14:00 lp volumio[1163]: error: Upnp client error: Error: This socket has been ended by the other party May 15 01:14:00 lp sudo[8029]: root : unable to resolve host lp May 15 01:14:00 lp sudo[8029]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 15 01:14:00 lp sudo[8029]: sudo: unable to resolve host lp: System error May 15 01:14:00 lp sudo[8029]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 15 01:14:00 lp sudo[8029]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 01:14:00 lp sudo[8029]: pam_unix(sudo:session): session closed for user root May 15 01:14:02 lp mpd[8031]: May 15 01:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 01:14:02 lp systemd[1]: Started Music Player Daemon. May 15 01:14:02 lp sudo[8022]: pam_unix(sudo:session): session closed for user root May 15 01:14:02 lp volumio[1163]: error: updateQueue error: null May 15 01:14:04 lp volumio[1163]: info: Clearing queue after UPNP request May 15 01:14:05 lp volumio[1163]: info: CoreStateMachine::ClearQueue May 15 01:14:05 lp volumio[1163]: info: CoreStateMachine::stop May 15 01:14:05 lp volumio[1163]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 01:14:05 lp volumio[1163]: info: CoreStateMachine::stPlaybackTimer May 15 01:14:05 lp volumio[1163]: info: CoreStateMachine::updateTrackBlock May 15 01:14:05 lp volumio[1163]: info: CorePlayQueue::getTrackBlock May 15 01:14:05 lp volumio[1163]: info: CoreStateMachine::pushState May 15 01:14:05 lp volumio[1163]: info: CorePlayQueue::getTrack 0 May 15 01:14:05 lp volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 01:14:05 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:05 lp volumio[1163]: info: CoreStateMachine::serviceStop May 15 01:14:05 lp volumio[1163]: info: CorePlayQueue::getTrack 0 May 15 01:14:05 lp volumio[1163]: info: ControllerMpd::stop May 15 01:14:05 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand stop May 15 01:14:05 lp volumio[1163]: info: CorePlayQueue::clearPlayQueue May 15 01:14:05 lp volumio[1163]: info: CorePlayQueue::saveQueue May 15 01:14:05 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:05 lp volumio[1163]: info: CoreCommandRouter::volumioPushQueue May 15 01:14:05 lp volumio[1163]: info: May 15 01:14:05 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:05 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:05 lp volumio[1163]: info: sendMpdCommand stop took 23 milliseconds May 15 01:14:05 lp volumio[1163]: error: updateQueue error: null May 15 01:14:05 lp volumio[1163]: info: ------------------------------ 4ms May 15 01:14:05 lp volumio[1163]: info: Starting UPNP Playback May 15 01:14:05 lp volumio[1163]: info: Preparing playback through UPNP May 15 01:14:05 lp volumio[1163]: info: CoreCommandRouter::volumioGetState May 15 01:14:05 lp volumio[1163]: info: CorePlayQueue::getTrack 0 May 15 01:14:05 lp volumio[1163]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 01:14:05 lp volumio[1163]: info: May 15 01:14:05 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:05 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:05 lp volumio[1163]: info: May 15 01:14:05 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:05 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:05 lp volumio[1163]: info: ------------------------------ 3ms May 15 01:14:05 lp volumio[1163]: info: ------------------------------ 4ms May 15 01:14:05 lp volumio[1163]: info: May 15 01:14:05 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:05 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:05 lp volumio[1163]: info: ------------------------------ 3ms May 15 01:14:05 lp volumio[1163]: info: May 15 01:14:05 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:05 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:05 lp volumio[1163]: info: ------------------------------ 3ms May 15 01:14:05 lp volumio[1163]: info: May 15 01:14:05 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:05 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:05 lp volumio[1163]: info: ------------------------------ 3ms May 15 01:14:05 lp volumio[1163]: info: May 15 01:14:05 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:05 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:05 lp volumio[1163]: info: ------------------------------ 3ms May 15 01:14:05 lp volumio[1163]: info: May 15 01:14:05 lp volumio[1163]: ---------------------------- MPD announces state update: player May 15 01:14:05 lp volumio[1163]: info: ControllerMpd::getState May 15 01:14:05 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 15 01:14:05 lp volumio[1163]: info: sendMpdCommand status took 2 milliseconds May 15 01:14:05 lp volumio[1163]: verbose: ControllerMpd::parseState May 15 01:14:05 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 01:14:05 lp volumio[1163]: info: sendMpdCommand playlistinfo took 1 milliseconds May 15 01:14:05 lp volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 15 01:14:05 lp volumio[1163]: info: ControllerMpd::pushState May 15 01:14:05 lp volumio[1163]: info: CoreCommandRouter::servicePushState May 15 01:14:05 lp volumio[1163]: info: CoreStateMachine::pushState May 15 01:14:05 lp volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 01:14:05 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:05 lp volumio[1163]: info: CorePlayQueue::getTrack 0 May 15 01:14:05 lp volumio[1163]: 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://aqqmusic.tc.qq.com/F0000028JWQm05B9Yg.flac?guid=ffffffff91843f3400000194a033c7b7&vkey=742006EFC73124C92596FF5252C3D5C4BC2F4F87C448C0FB5A564864D72E9DBD5081F1712DD55F261A24324F1B614A5C92BC4AD2C28D2AD0__v21e2a1874&uin=1152921504891883214&src=F000001yM4tH246ciJ.flac&redirect=1&fromtag=111114","trackType":""} May 15 01:14:05 lp volumio[1163]: verbose: CURRENT POSITION 0 May 15 01:14:05 lp volumio[1163]: info: CoreStateMachine::syncState stateService stop May 15 01:14:05 lp volumio[1163]: info: CoreStateMachine::syncState currentStatus stop May 15 01:14:05 lp volumio[1163]: info: CoreStateMachine::pushState May 15 01:14:05 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:05 lp volumio[1163]: info: No code May 15 01:14:05 lp volumio[1163]: info: CoreStateMachine::pushState May 15 01:14:05 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:05 lp volumio[1163]: info: ------------------------------ 29ms May 15 01:14:05 lp volumio[1163]: info: CoreCommandRouter::volumioGetQueue May 15 01:14:05 lp volumio[1163]: info: CoreStateMachine::getQueue May 15 01:14:05 lp volumio[1163]: info: CorePlayQueue::getQueue May 15 01:14:05 lp volumio[1163]: info: CoreCommandRouter::volumioGetQueue May 15 01:14:05 lp volumio[1163]: info: CoreStateMachine::getQueue May 15 01:14:05 lp volumio[1163]: info: CorePlayQueue::getQueue May 15 01:14:06 lp volumio[1163]: info: CoreCommandRouter::volumioGetQueue May 15 01:14:06 lp volumio[1163]: info: CoreStateMachine::getQueue May 15 01:14:06 lp volumio[1163]: info: CorePlayQueue::getQueue May 15 01:14:06 lp volumio[1163]: info: Clearing queue after UPNP request May 15 01:14:06 lp volumio[1163]: info: CoreStateMachine::ClearQueue May 15 01:14:06 lp volumio[1163]: info: CoreStateMachine::stop May 15 01:14:06 lp volumio[1163]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 01:14:06 lp volumio[1163]: info: CorePlayQueue::clearPlayQueue May 15 01:14:06 lp volumio[1163]: info: CorePlayQueue::saveQueue May 15 01:14:06 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:06 lp volumio[1163]: info: CoreCommandRouter::volumioPushQueue May 15 01:14:06 lp volumio[1163]: info: May 15 01:14:06 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:06 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:06 lp volumio[1163]: error: updateQueue error: null May 15 01:14:06 lp volumio[1163]: info: ------------------------------ 3ms May 15 01:14:06 lp volumio[1163]: info: Starting UPNP Playback May 15 01:14:06 lp volumio[1163]: info: Preparing playback through UPNP May 15 01:14:06 lp volumio[1163]: info: CoreCommandRouter::volumioGetState May 15 01:14:06 lp volumio[1163]: info: CorePlayQueue::getTrack 0 May 15 01:14:06 lp volumio[1163]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 01:14:06 lp volumio[1163]: info: May 15 01:14:06 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:06 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:06 lp volumio[1163]: info: May 15 01:14:06 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:06 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:06 lp volumio[1163]: info: ------------------------------ 4ms May 15 01:14:06 lp volumio[1163]: info: ------------------------------ 3ms May 15 01:14:06 lp volumio[1163]: info: May 15 01:14:06 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:06 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:06 lp volumio[1163]: info: ------------------------------ 3ms May 15 01:14:06 lp volumio[1163]: info: May 15 01:14:06 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:06 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:06 lp volumio[1163]: info: ------------------------------ 3ms May 15 01:14:06 lp volumio[1163]: info: May 15 01:14:06 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:06 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:06 lp volumio[1163]: info: ------------------------------ 3ms May 15 01:14:06 lp volumio[1163]: info: May 15 01:14:06 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:06 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:06 lp volumio[1163]: info: ------------------------------ 3ms May 15 01:14:07 lp volumio[1163]: info: May 15 01:14:07 lp volumio[1163]: ---------------------------- MPD announces state update: player May 15 01:14:07 lp volumio[1163]: info: ControllerMpd::getState May 15 01:14:07 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 15 01:14:07 lp volumio[1163]: info: May 15 01:14:07 lp volumio[1163]: ---------------------------- MPD announces state update: player May 15 01:14:07 lp volumio[1163]: info: sendMpdCommand status took 19 milliseconds May 15 01:14:07 lp volumio[1163]: info: ControllerMpd::getState May 15 01:14:07 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 15 01:14:07 lp volumio[1163]: verbose: ControllerMpd::parseState May 15 01:14:07 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 01:14:07 lp volumio[1163]: info: sendMpdCommand status took 4 milliseconds May 15 01:14:07 lp volumio[1163]: info: sendMpdCommand playlistinfo took 5 milliseconds May 15 01:14:07 lp volumio[1163]: verbose: ControllerMpd::parseState May 15 01:14:07 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 01:14:07 lp volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 15 01:14:07 lp volumio[1163]: info: ControllerMpd::pushState May 15 01:14:07 lp volumio[1163]: info: CoreCommandRouter::servicePushState May 15 01:14:07 lp volumio[1163]: verbose: In UPNP mode May 15 01:14:07 lp volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"星语心愿","artist":"凰逸欣","album":"天使恋人","uri":"http://aqqmusic.tc.qq.com/M500004Tx4Pt13HdTm.mp3?guid=ffffffff91843f3400000194a033c7b7&vkey=D11CE6B8ADDBCB99FB85A5FA67BD1A0230A79BF23CB1C1D69AF166F7514FE36D173998D283CD7F325FC0277DB6AE27C474755713B4DC7CD3__v21e2a1874&uin=1152921504891883214&redirect=1&fromtag=111042","trackType":""} May 15 01:14:07 lp volumio[1163]: verbose: CURRENT POSITION 0 May 15 01:14:07 lp volumio[1163]: info: CoreStateMachine::syncState stateService play May 15 01:14:07 lp volumio[1163]: info: CoreStateMachine::syncState currentStatus stop May 15 01:14:07 lp volumio[1163]: info: ------------------------------ 36ms May 15 01:14:07 lp volumio[1163]: info: sendMpdCommand playlistinfo took 7 milliseconds May 15 01:14:07 lp volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 15 01:14:07 lp volumio[1163]: info: ControllerMpd::pushState May 15 01:14:07 lp volumio[1163]: info: CoreCommandRouter::servicePushState May 15 01:14:07 lp volumio[1163]: verbose: In UPNP mode May 15 01:14:07 lp volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"星语心愿","artist":"凰逸欣","album":"天使恋人","uri":"http://aqqmusic.tc.qq.com/M500004Tx4Pt13HdTm.mp3?guid=ffffffff91843f3400000194a033c7b7&vkey=D11CE6B8ADDBCB99FB85A5FA67BD1A0230A79BF23CB1C1D69AF166F7514FE36D173998D283CD7F325FC0277DB6AE27C474755713B4DC7CD3__v21e2a1874&uin=1152921504891883214&redirect=1&fromtag=111042","trackType":""} May 15 01:14:07 lp volumio[1163]: verbose: CURRENT POSITION 0 May 15 01:14:07 lp volumio[1163]: info: CoreStateMachine::syncState stateService play May 15 01:14:07 lp volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 15 01:14:07 lp volumio[1163]: info: Received an update from plugin. extracting info from payload May 15 01:14:07 lp volumio[1163]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 15 01:14:07 lp volumio[1163]: info: CoreStateMachine::pushState May 15 01:14:07 lp volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 01:14:07 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:07 lp volumio[1163]: info: CoreStateMachine::pushState May 15 01:14:07 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:07 lp volumio[1163]: info: ------------------------------ 39ms May 15 01:14:07 lp volumio[1163]: info: May 15 01:14:07 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:07 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:07 lp volumio[1163]: info: May 15 01:14:07 lp volumio[1163]: ---------------------------- MPD announces state update: player May 15 01:14:07 lp volumio[1163]: info: ControllerMpd::getState May 15 01:14:07 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 15 01:14:07 lp volumio[1163]: info: CoreCommandRouter::volumioGetQueue May 15 01:14:07 lp volumio[1163]: info: CoreStateMachine::getQueue May 15 01:14:07 lp volumio[1163]: info: CorePlayQueue::getQueue May 15 01:14:07 lp volumio[1163]: info: CoreCommandRouter::volumioGetQueue May 15 01:14:07 lp volumio[1163]: info: CoreStateMachine::getQueue May 15 01:14:07 lp volumio[1163]: info: CorePlayQueue::getQueue May 15 01:14:07 lp volumio[1163]: info: ------------------------------ 15ms May 15 01:14:07 lp volumio[1163]: info: sendMpdCommand status took 14 milliseconds May 15 01:14:07 lp volumio[1163]: verbose: ControllerMpd::parseState May 15 01:14:07 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 01:14:07 lp volumio[1163]: info: sendMpdCommand playlistinfo took 4 milliseconds May 15 01:14:07 lp volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 15 01:14:07 lp volumio[1163]: info: ControllerMpd::pushState May 15 01:14:07 lp volumio[1163]: info: CoreCommandRouter::servicePushState May 15 01:14:07 lp volumio[1163]: verbose: In UPNP mode May 15 01:14:07 lp volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":182,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"星语心愿","artist":"黄莉媛","album":"天使恋人","uri":"http://aqqmusic.tc.qq.com/M500004Tx4Pt13HdTm.mp3?guid=ffffffff91843f3400000194a033c7b7&vkey=D11CE6B8ADDBCB99FB85A5FA67BD1A0230A79BF23CB1C1D69AF166F7514FE36D173998D283CD7F325FC0277DB6AE27C474755713B4DC7CD3__v21e2a1874&uin=1152921504891883214&redirect=1&fromtag=111042","trackType":""} May 15 01:14:07 lp volumio[1163]: verbose: CURRENT POSITION 0 May 15 01:14:07 lp volumio[1163]: info: CoreStateMachine::syncState stateService play May 15 01:14:07 lp volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 15 01:14:07 lp volumio[1163]: info: Received an update from plugin. extracting info from payload May 15 01:14:07 lp volumio[1163]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 15 01:14:07 lp volumio[1163]: info: CoreStateMachine::pushState May 15 01:14:07 lp volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 01:14:07 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:07 lp volumio[1163]: info: CoreStateMachine::pushState May 15 01:14:07 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:07 lp volumio[1163]: info: ------------------------------ 50ms May 15 01:14:07 lp volumio[1163]: info: CoreCommandRouter::volumioGetQueue May 15 01:14:07 lp volumio[1163]: info: CoreStateMachine::getQueue May 15 01:14:07 lp volumio[1163]: info: CorePlayQueue::getQueue May 15 01:14:07 lp volumio[1163]: info: CoreCommandRouter::volumioGetQueue May 15 01:14:07 lp volumio[1163]: info: CoreStateMachine::getQueue May 15 01:14:07 lp volumio[1163]: info: CorePlayQueue::getQueue May 15 01:14:08 lp volumio[1163]: info: May 15 01:14:08 lp volumio[1163]: ---------------------------- MPD announces state update: player May 15 01:14:08 lp volumio[1163]: info: ControllerMpd::getState May 15 01:14:08 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 15 01:14:08 lp volumio[1163]: info: sendMpdCommand status took 2 milliseconds May 15 01:14:08 lp volumio[1163]: verbose: ControllerMpd::parseState May 15 01:14:08 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 01:14:08 lp volumio[1163]: info: sendMpdCommand playlistinfo took 1 milliseconds May 15 01:14:08 lp volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 15 01:14:08 lp volumio[1163]: info: ControllerMpd::pushState May 15 01:14:08 lp volumio[1163]: info: CoreCommandRouter::servicePushState May 15 01:14:08 lp volumio[1163]: info: CoreStateMachine::pushState May 15 01:14:08 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:08 lp volumio[1163]: info: CorePlayQueue::getTrack 0 May 15 01:14:08 lp volumio[1163]: 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://aqqmusic.tc.qq.com/M500004Tx4Pt13HdTm.mp3?guid=ffffffff91843f3400000194a033c7b7&vkey=D11CE6B8ADDBCB99FB85A5FA67BD1A0230A79BF23CB1C1D69AF166F7514FE36D173998D283CD7F325FC0277DB6AE27C474755713B4DC7CD3__v21e2a1874&uin=1152921504891883214&redirect=1&fromtag=111042","trackType":""} May 15 01:14:08 lp volumio[1163]: verbose: CURRENT POSITION 0 May 15 01:14:08 lp volumio[1163]: info: CoreStateMachine::syncState stateService stop May 15 01:14:08 lp volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 15 01:14:08 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:08 lp volumio[1163]: info: CoreStateMachine::stPlaybackTimer May 15 01:14:08 lp volumio[1163]: info: ------------------------------ 26ms May 15 01:14:08 lp volumio[1163]: info: CoreCommandRouter::volumioGetQueue May 15 01:14:08 lp volumio[1163]: info: CoreStateMachine::getQueue May 15 01:14:08 lp volumio[1163]: info: CorePlayQueue::getQueue May 15 01:14:08 lp volumio[1163]: info: Clearing queue after UPNP request May 15 01:14:08 lp volumio[1163]: info: CoreStateMachine::ClearQueue May 15 01:14:08 lp volumio[1163]: info: CoreStateMachine::stop May 15 01:14:08 lp volumio[1163]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 01:14:08 lp volumio[1163]: info: CorePlayQueue::clearPlayQueue May 15 01:14:08 lp volumio[1163]: info: CorePlayQueue::saveQueue May 15 01:14:08 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:08 lp volumio[1163]: info: CoreCommandRouter::volumioPushQueue May 15 01:14:08 lp volumio[1163]: info: May 15 01:14:08 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:08 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:08 lp volumio[1163]: error: updateQueue error: null May 15 01:14:08 lp volumio[1163]: info: ------------------------------ 2ms May 15 01:14:08 lp volumio[1163]: info: Starting UPNP Playback May 15 01:14:08 lp volumio[1163]: info: Preparing playback through UPNP May 15 01:14:08 lp volumio[1163]: info: CoreCommandRouter::volumioGetState May 15 01:14:08 lp volumio[1163]: info: CorePlayQueue::getTrack 0 May 15 01:14:08 lp volumio[1163]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 01:14:09 lp volumio[1163]: info: May 15 01:14:09 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:09 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:09 lp volumio[1163]: info: May 15 01:14:09 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:09 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:09 lp volumio[1163]: info: ------------------------------ 3ms May 15 01:14:09 lp volumio[1163]: info: ------------------------------ 2ms May 15 01:14:09 lp volumio[1163]: info: May 15 01:14:09 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:09 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:09 lp volumio[1163]: info: ------------------------------ 2ms May 15 01:14:09 lp volumio[1163]: info: May 15 01:14:09 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:09 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:09 lp volumio[1163]: info: ------------------------------ 2ms May 15 01:14:09 lp volumio[1163]: info: May 15 01:14:09 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:09 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:09 lp volumio[1163]: info: ------------------------------ 2ms May 15 01:14:09 lp volumio[1163]: info: May 15 01:14:09 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:09 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:09 lp volumio[1163]: info: ------------------------------ 2ms May 15 01:14:09 lp volumio[1163]: info: May 15 01:14:09 lp volumio[1163]: ---------------------------- MPD announces state update: player May 15 01:14:09 lp volumio[1163]: info: ControllerMpd::getState May 15 01:14:09 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 15 01:14:09 lp volumio[1163]: info: May 15 01:14:09 lp volumio[1163]: ---------------------------- MPD announces state update: player May 15 01:14:09 lp volumio[1163]: info: sendMpdCommand status took 17 milliseconds May 15 01:14:09 lp volumio[1163]: info: ControllerMpd::getState May 15 01:14:09 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 15 01:14:09 lp volumio[1163]: verbose: ControllerMpd::parseState May 15 01:14:09 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 01:14:09 lp volumio[1163]: info: sendMpdCommand status took 3 milliseconds May 15 01:14:09 lp volumio[1163]: info: sendMpdCommand playlistinfo took 2 milliseconds May 15 01:14:09 lp volumio[1163]: verbose: ControllerMpd::parseState May 15 01:14:09 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 01:14:09 lp volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 15 01:14:09 lp volumio[1163]: info: ControllerMpd::pushState May 15 01:14:09 lp volumio[1163]: info: CoreCommandRouter::servicePushState May 15 01:14:09 lp volumio[1163]: verbose: In UPNP mode May 15 01:14:09 lp volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"落花流水","artist":"蔡琴","album":"痴痴的等","uri":"http://aqqmusic.tc.qq.com/F000003x2w2r15HWXM.flac?guid=ffffffff91843f3400000194a033c7b7&vkey=71E06E65D003E36A3C5C54F1AB956156B250A53090293371BC4599FC659DA21B08233B6AA0518328366D77326B3B4A5751A8FCEF7C01EBC1__v21e2a171d&uin=1152921504891883214&redirect=1&fromtag=111114","trackType":""} May 15 01:14:09 lp volumio[1163]: verbose: CURRENT POSITION 0 May 15 01:14:09 lp volumio[1163]: info: CoreStateMachine::syncState stateService play May 15 01:14:09 lp volumio[1163]: info: CoreStateMachine::syncState currentStatus stop May 15 01:14:09 lp volumio[1163]: info: ------------------------------ 26ms May 15 01:14:09 lp volumio[1163]: info: sendMpdCommand playlistinfo took 4 milliseconds May 15 01:14:09 lp volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 15 01:14:09 lp volumio[1163]: info: ControllerMpd::pushState May 15 01:14:09 lp volumio[1163]: info: CoreCommandRouter::servicePushState May 15 01:14:09 lp volumio[1163]: verbose: In UPNP mode May 15 01:14:09 lp volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"落花流水","artist":"蔡琴","album":"痴痴的等","uri":"http://aqqmusic.tc.qq.com/F000003x2w2r15HWXM.flac?guid=ffffffff91843f3400000194a033c7b7&vkey=71E06E65D003E36A3C5C54F1AB956156B250A53090293371BC4599FC659DA21B08233B6AA0518328366D77326B3B4A5751A8FCEF7C01EBC1__v21e2a171d&uin=1152921504891883214&redirect=1&fromtag=111114","trackType":""} May 15 01:14:09 lp volumio[1163]: verbose: CURRENT POSITION 0 May 15 01:14:09 lp volumio[1163]: info: CoreStateMachine::syncState stateService play May 15 01:14:09 lp volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 15 01:14:09 lp volumio[1163]: info: Received an update from plugin. extracting info from payload May 15 01:14:09 lp volumio[1163]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 15 01:14:09 lp volumio[1163]: info: CoreStateMachine::pushState May 15 01:14:09 lp volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 01:14:09 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:09 lp volumio[1163]: info: CoreStateMachine::pushState May 15 01:14:09 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:09 lp volumio[1163]: info: ------------------------------ 79ms May 15 01:14:09 lp volumio[1163]: info: May 15 01:14:09 lp volumio[1163]: ---------------------------- MPD announces system playlist update May 15 01:14:09 lp volumio[1163]: info: Ignoring MPD Status Update May 15 01:14:09 lp volumio[1163]: info: May 15 01:14:09 lp volumio[1163]: ---------------------------- MPD announces state update: player May 15 01:14:09 lp volumio[1163]: info: ControllerMpd::getState May 15 01:14:09 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 15 01:14:09 lp volumio[1163]: info: CoreCommandRouter::volumioGetQueue May 15 01:14:09 lp volumio[1163]: info: CoreStateMachine::getQueue May 15 01:14:09 lp volumio[1163]: info: CorePlayQueue::getQueue May 15 01:14:09 lp volumio[1163]: info: CoreCommandRouter::volumioGetQueue May 15 01:14:09 lp volumio[1163]: info: CoreStateMachine::getQueue May 15 01:14:09 lp volumio[1163]: info: CorePlayQueue::getQueue May 15 01:14:09 lp volumio[1163]: info: ------------------------------ 58ms May 15 01:14:09 lp volumio[1163]: info: sendMpdCommand status took 58 milliseconds May 15 01:14:09 lp volumio[1163]: verbose: ControllerMpd::parseState May 15 01:14:09 lp volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 01:14:09 lp volumio[1163]: info: sendMpdCommand playlistinfo took 11 milliseconds May 15 01:14:09 lp volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 15 01:14:09 lp volumio[1163]: info: ControllerMpd::pushState May 15 01:14:09 lp volumio[1163]: info: CoreCommandRouter::servicePushState May 15 01:14:09 lp volumio[1163]: verbose: In UPNP mode May 15 01:14:09 lp volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":640,"duration":166,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"756 Kbps","isStreaming":false,"title":"落花流水","artist":"蔡琴","album":"老歌","uri":"http://aqqmusic.tc.qq.com/F000003x2w2r15HWXM.flac?guid=ffffffff91843f3400000194a033c7b7&vkey=71E06E65D003E36A3C5C54F1AB956156B250A53090293371BC4599FC659DA21B08233B6AA0518328366D77326B3B4A5751A8FCEF7C01EBC1__v21e2a171d&uin=1152921504891883214&redirect=1&fromtag=111114","trackType":""} May 15 01:14:09 lp volumio[1163]: verbose: CURRENT POSITION 0 May 15 01:14:09 lp volumio[1163]: info: CoreStateMachine::syncState stateService play May 15 01:14:09 lp volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 15 01:14:09 lp volumio[1163]: info: Received an update from plugin. extracting info from payload May 15 01:14:09 lp volumio[1163]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 15 01:14:09 lp volumio[1163]: info: CoreStateMachine::pushState May 15 01:14:09 lp volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 01:14:09 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:09 lp volumio[1163]: info: CoreStateMachine::pushState May 15 01:14:09 lp volumio[1163]: info: CoreCommandRouter::volumioPushState May 15 01:14:09 lp volumio[1163]: info: ------------------------------ 210ms May 15 01:14:09 lp volumio[1163]: info: CoreCommandRouter::volumioGetQueue May 15 01:14:09 lp volumio[1163]: info: CoreStateMachine::getQueue May 15 01:14:09 lp volumio[1163]: info: CorePlayQueue::getQueue May 15 01:14:09 lp volumio[1163]: info: CoreCommandRouter::volumioGetQueue May 15 01:14:09 lp volumio[1163]: info: CoreStateMachine::getQueue May 15 01:14:09 lp volumio[1163]: info: CorePlayQueue::getQueue May 15 01:15:10 lp volumio[1163]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 01:15:10 lp volumio[1163]: Error: connect ETIMEDOUT 157.240.10.36:443 May 15 01:15:10 lp volumio[1163]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 15 01:15:10 lp volumio[1163]: errno: -110, May 15 01:15:10 lp volumio[1163]: code: 'ETIMEDOUT', May 15 01:15:10 lp volumio[1163]: syscall: 'connect', May 15 01:15:10 lp volumio[1163]: address: '157.240.10.36', May 15 01:15:10 lp volumio[1163]: port: 443 May 15 01:15:10 lp volumio[1163]: } May 15 01:15:10 lp volumio[1163]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 01:15:12 lp sudo[8420]: volumio : unable to resolve host lp May 15 01:15:12 lp sudo[8420]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 15 01:15:12 lp sudo[8420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-15 01:14 May 15 01:15:12 lp sudo[8420]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"