-- Logs begin at Tue 2025-03-25 15:59:20 UTC, end at Sun 2025-08-31 07:27:50 UTC. -- Aug 31 07:27:03 volumio volumio[4476]: info: Aug 31 07:27:03 volumio volumio[4476]: ---------------------------- MPD announces state update: player Aug 31 07:27:03 volumio volumio[4476]: info: ControllerMpd::getState Aug 31 07:27:03 volumio volumio[4476]: info: ControllerMpd::sendMpdCommand status Aug 31 07:27:03 volumio volumio[4476]: info: sending command... Aug 31 07:27:03 volumio volumio[4476]: info: parsing response... Aug 31 07:27:03 volumio volumio[4476]: info: ControllerMpd::parseState Aug 31 07:27:03 volumio volumio[4476]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 31 07:27:03 volumio volumio[4476]: info: sending command... Aug 31 07:27:03 volumio volumio[4476]: info: parsing response... Aug 31 07:27:03 volumio volumio[4476]: info: ControllerMpd::parseTrackInfo Aug 31 07:27:03 volumio volumio[4476]: info: ControllerMpd::pushState Aug 31 07:27:03 volumio volumio[4476]: info: CoreCommandRouter::servicePushState Aug 31 07:27:03 volumio volumio[4476]: info: CoreStateMachine::syncState Aug 31 07:27:03 volumio volumio[4476]: In UPNP mode Aug 31 07:27:03 volumio volumio[4476]: info: STATE SERVICE {"status":"pause","position":0,"seek":82883,"duration":263,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"弱水三千","artist":"石头/张晓棠","album":"念","uri":"http://m801.music.126.net/20250831155039/35ed6e1074d197b5a49e35b36a7bed7f/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1496745057/3b91/a173/999d/bde8a651af97b4cda67755a453ceccaf.mp3?vuutv=yzD9y9ynFs5sfU19M8FQvCrwzQMjY41VC5H812HVUfi583tdUnaCJQnIHYUfIXclGbmbQpPPvpL9ZAzHtk/gAfwrYjUKE4U57mWhPPg9PVY=","trackType":""} Aug 31 07:27:03 volumio volumio[4476]: info: CURRENT POSITION 0 Aug 31 07:27:03 volumio volumio[4476]: info: CoreStateMachine::syncState stateService pause Aug 31 07:27:03 volumio volumio[4476]: info: CoreStateMachine::syncState currentStatus play Aug 31 07:27:03 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:03 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:03 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:03 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:03 volumio volumio[4476]: info: CoreStateMachine::stPlaybackTimer Aug 31 07:27:03 volumio volumio[4476]: info: ------------------------------ 24ms Aug 31 07:27:03 volumio volumio[4476]: info: CoreCommandRouter::volumioGetQueue Aug 31 07:27:03 volumio volumio[4476]: info: CoreStateMachine::getQueue Aug 31 07:27:03 volumio volumio[4476]: info: CorePlayQueue::getQueue Aug 31 07:27:07 volumio volumio[4476]: info: Clearing queue after UPNP request Aug 31 07:27:07 volumio volumio[4476]: info: CoreStateMachine::ClearQueue Aug 31 07:27:07 volumio volumio[4476]: info: CoreStateMachine::stop Aug 31 07:27:07 volumio volumio[4476]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 07:27:07 volumio volumio[4476]: UNSET VOLATILE Aug 31 07:27:07 volumio volumio[4476]: info: CoreStateMachine::stPlaybackTimer Aug 31 07:27:07 volumio volumio[4476]: info: CoreStateMachine::updateTrackBlock Aug 31 07:27:07 volumio volumio[4476]: info: CorePlayQueue::getTrackBlock Aug 31 07:27:07 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:07 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:07 volumio volumio[4476]: info: CorePlayQueue::getTrack 0 Aug 31 07:27:07 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:07 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:07 volumio volumio[4476]: info: CoreStateMachine::serviceStop Aug 31 07:27:07 volumio volumio[4476]: info: CorePlayQueue::getTrack 0 Aug 31 07:27:07 volumio volumio[4476]: info: ControllerMpd::stop Aug 31 07:27:07 volumio volumio[4476]: info: ControllerMpd::sendMpdCommand stop Aug 31 07:27:07 volumio volumio[4476]: info: CorePlayQueue::clearPlayQueue Aug 31 07:27:07 volumio volumio[4476]: info: CorePlayQueue::saveQueue Aug 31 07:27:07 volumio volumio[4476]: info: CoreStateMachine::pushEmptyState Aug 31 07:27:07 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:07 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:07 volumio volumio[4476]: info: CoreCommandRouter::volumioPushQueue Aug 31 07:27:07 volumio volumio[4476]: info: sending command... Aug 31 07:27:07 volumio volumio[4476]: info: Aug 31 07:27:07 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:07 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:07 volumio volumio[4476]: info: Aug 31 07:27:07 volumio volumio[4476]: ---------------------------- MPD announces state update: player Aug 31 07:27:07 volumio volumio[4476]: info: ControllerMpd::getState Aug 31 07:27:07 volumio volumio[4476]: info: ControllerMpd::sendMpdCommand status Aug 31 07:27:07 volumio volumio[4476]: info: parsing response... Aug 31 07:27:07 volumio volumio[4476]: info: sending command... Aug 31 07:27:07 volumio volumio[4476]: info: null Aug 31 07:27:07 volumio volumio[4476]: info: ------------------------------ 8ms Aug 31 07:27:07 volumio volumio[4476]: info: parsing response... Aug 31 07:27:07 volumio volumio[4476]: info: ControllerMpd::parseState Aug 31 07:27:08 volumio volumio[4476]: info: ControllerMpd::pushState Aug 31 07:27:08 volumio volumio[4476]: info: CoreCommandRouter::servicePushState Aug 31 07:27:08 volumio volumio[4476]: info: CoreStateMachine::syncState Aug 31 07:27:08 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:08 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:08 volumio volumio[4476]: info: CorePlayQueue::getTrack 0 Aug 31 07:27:08 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:08 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:08 volumio volumio[4476]: info: CorePlayQueue::getTrack 0 Aug 31 07:27:08 volumio volumio[4476]: info: 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} Aug 31 07:27:08 volumio volumio[4476]: info: CURRENT POSITION 0 Aug 31 07:27:08 volumio volumio[4476]: info: CoreStateMachine::syncState stateService stop Aug 31 07:27:08 volumio volumio[4476]: info: CoreStateMachine::syncState currentStatus stop Aug 31 07:27:08 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:08 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:08 volumio volumio[4476]: info: CorePlayQueue::getTrack 0 Aug 31 07:27:08 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:08 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:08 volumio volumio[4476]: info: No code Aug 31 07:27:08 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:08 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:08 volumio volumio[4476]: info: CorePlayQueue::getTrack 0 Aug 31 07:27:08 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:08 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:08 volumio volumio[4476]: info: ------------------------------ 41ms Aug 31 07:27:08 volumio volumio[4476]: info: Starting UPNP Playback Aug 31 07:27:08 volumio volumio[4476]: info: Preparing playback through UPNP Aug 31 07:27:08 volumio volumio[4476]: info: CoreCommandRouter::volumioGetState Aug 31 07:27:08 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:08 volumio volumio[4476]: info: CorePlayQueue::getTrack 0 Aug 31 07:27:08 volumio volumio[4476]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 07:27:08 volumio volumio[4476]: info: Aug 31 07:27:08 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:08 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:08 volumio volumio[4476]: info: ------------------------------ 2ms Aug 31 07:27:08 volumio volumio[4476]: info: Aug 31 07:27:08 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:08 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:08 volumio volumio[4476]: info: Aug 31 07:27:08 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:08 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:08 volumio volumio[4476]: info: ------------------------------ 4ms Aug 31 07:27:08 volumio volumio[4476]: info: ------------------------------ 3ms Aug 31 07:27:08 volumio volumio[4476]: info: Aug 31 07:27:08 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:08 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:08 volumio volumio[4476]: info: ------------------------------ 3ms Aug 31 07:27:08 volumio volumio[4476]: info: Aug 31 07:27:08 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:08 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:08 volumio volumio[4476]: info: Aug 31 07:27:08 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:08 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:08 volumio volumio[4476]: info: ------------------------------ 4ms Aug 31 07:27:08 volumio volumio[4476]: info: ------------------------------ 3ms Aug 31 07:27:08 volumio wpa_supplicant[4292]: TDLS: Creating peer entry for 62:3f:06:f2:4d:3b Aug 31 07:27:08 volumio kernel: CFG80211-ERROR) wl_cfg80211_tdls_mgmt : wl_cfg80211_tdls_mgmt WLAN_TDLS_SET_MODE_WFD_DISABLED Aug 31 07:27:08 volumio kernel: Disable tdls_auto_op failed. -1 Aug 31 07:27:09 volumio volumio[4476]: info: Aug 31 07:27:09 volumio volumio[4476]: ---------------------------- MPD announces state update: player Aug 31 07:27:09 volumio volumio[4476]: info: ControllerMpd::getState Aug 31 07:27:09 volumio volumio[4476]: info: ControllerMpd::sendMpdCommand status Aug 31 07:27:09 volumio volumio[4476]: info: sending command... Aug 31 07:27:09 volumio volumio[4476]: info: Aug 31 07:27:09 volumio volumio[4476]: ---------------------------- MPD announces state update: player Aug 31 07:27:09 volumio volumio[4476]: info: parsing response... Aug 31 07:27:09 volumio volumio[4476]: info: ControllerMpd::getState Aug 31 07:27:09 volumio volumio[4476]: info: ControllerMpd::sendMpdCommand status Aug 31 07:27:09 volumio volumio[4476]: info: ControllerMpd::parseState Aug 31 07:27:09 volumio volumio[4476]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 31 07:27:09 volumio volumio[4476]: info: sending command... Aug 31 07:27:09 volumio volumio[4476]: info: sending command... Aug 31 07:27:09 volumio volumio[4476]: info: Aug 31 07:27:09 volumio volumio[4476]: ---------------------------- MPD announces state update: player Aug 31 07:27:09 volumio volumio[4476]: info: ControllerMpd::getState Aug 31 07:27:09 volumio volumio[4476]: info: ControllerMpd::sendMpdCommand status Aug 31 07:27:09 volumio volumio[4476]: info: parsing response... Aug 31 07:27:09 volumio volumio[4476]: info: parsing response... Aug 31 07:27:09 volumio volumio[4476]: info: sending command... Aug 31 07:27:09 volumio volumio[4476]: info: ControllerMpd::parseState Aug 31 07:27:09 volumio volumio[4476]: info: ControllerMpd::parseTrackInfo Aug 31 07:27:09 volumio volumio[4476]: info: ControllerMpd::pushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::servicePushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::syncState Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:09 volumio volumio[4476]: info: CorePlayQueue::getTrack 0 Aug 31 07:27:09 volumio volumio[4476]: info: 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} Aug 31 07:27:09 volumio volumio[4476]: info: CURRENT POSITION 0 Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::syncState stateService stop Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::syncState currentStatus stop Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:09 volumio volumio[4476]: info: No code Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:09 volumio volumio[4476]: info: ControllerMpd::pushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::servicePushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::syncState Aug 31 07:27:09 volumio volumio[4476]: In UPNP mode Aug 31 07:27:09 volumio volumio[4476]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":25,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2471 Kbps","isStreaming":false,"title":"弱水三千","artist":"石头/张晓棠","album":"念","uri":"http://m7.music.126.net/20250831155207/c558fdde5921ee3365df69456ba19266/yyaac/obj/wonDkMOGw6XDiTHCmMOi/3036041867/3257/09ce/d8ac/ba1d0376202b3b74034d404852eed14b.m4a","trackType":"m4a"} Aug 31 07:27:09 volumio volumio[4476]: info: CURRENT POSITION 0 Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::syncState stateService play Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::syncState currentStatus stop Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:09 volumio volumio[4476]: info: ------------------------------ 78ms Aug 31 07:27:09 volumio volumio[4476]: info: ------------------------------ 115ms Aug 31 07:27:09 volumio volumio[4476]: info: parsing response... Aug 31 07:27:09 volumio volumio[4476]: info: ControllerMpd::parseState Aug 31 07:27:09 volumio volumio[4476]: info: ControllerMpd::pushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::servicePushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::syncState Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:09 volumio volumio[4476]: info: CorePlayQueue::getTrack 0 Aug 31 07:27:09 volumio volumio[4476]: info: 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} Aug 31 07:27:09 volumio volumio[4476]: info: CURRENT POSITION 0 Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::syncState stateService stop Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::syncState currentStatus play Aug 31 07:27:09 volumio volumio[4476]: info: CURRENT POSITION 0 Aug 31 07:27:09 volumio volumio[4476]: info: CURRENT POSITION 0 Aug 31 07:27:09 volumio volumio[4476]: info: END OF QUEUE Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::pushEmptyState Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::stPlaybackTimer Aug 31 07:27:09 volumio volumio[4476]: info: ------------------------------ 82ms Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::volumioGetQueue Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::getQueue Aug 31 07:27:09 volumio volumio[4476]: info: CorePlayQueue::getQueue Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::volumioGetQueue Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::getQueue Aug 31 07:27:09 volumio volumio[4476]: info: CorePlayQueue::getQueue Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::volumioGetQueue Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::getQueue Aug 31 07:27:09 volumio volumio[4476]: info: CorePlayQueue::getQueue Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::volumioGetQueue Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::getQueue Aug 31 07:27:09 volumio volumio[4476]: info: CorePlayQueue::getQueue Aug 31 07:27:09 volumio volumio[4476]: info: CoreCommandRouter::volumioGetQueue Aug 31 07:27:09 volumio volumio[4476]: info: CoreStateMachine::getQueue Aug 31 07:27:09 volumio volumio[4476]: info: CorePlayQueue::getQueue Aug 31 07:27:13 volumio volumio[4476]: info: Clearing queue after UPNP request Aug 31 07:27:13 volumio volumio[4476]: info: CoreStateMachine::ClearQueue Aug 31 07:27:13 volumio volumio[4476]: info: CoreStateMachine::stop Aug 31 07:27:13 volumio volumio[4476]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 07:27:13 volumio volumio[4476]: UNSET VOLATILE Aug 31 07:27:13 volumio volumio[4476]: info: CorePlayQueue::clearPlayQueue Aug 31 07:27:13 volumio volumio[4476]: info: CorePlayQueue::saveQueue Aug 31 07:27:13 volumio volumio[4476]: info: CoreStateMachine::pushEmptyState Aug 31 07:27:13 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:13 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:13 volumio volumio[4476]: info: CoreCommandRouter::volumioPushQueue Aug 31 07:27:13 volumio volumio[4476]: info: Aug 31 07:27:13 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:13 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:13 volumio volumio[4476]: info: null Aug 31 07:27:13 volumio volumio[4476]: info: ------------------------------ 4ms Aug 31 07:27:13 volumio volumio[4476]: info: Starting UPNP Playback Aug 31 07:27:13 volumio volumio[4476]: info: Preparing playback through UPNP Aug 31 07:27:13 volumio volumio[4476]: info: CoreCommandRouter::volumioGetState Aug 31 07:27:13 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:13 volumio volumio[4476]: info: CorePlayQueue::getTrack 0 Aug 31 07:27:13 volumio volumio[4476]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 07:27:14 volumio volumio[4476]: info: Aug 31 07:27:14 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:14 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:14 volumio volumio[4476]: info: Aug 31 07:27:14 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:14 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:14 volumio volumio[4476]: info: ------------------------------ 4ms Aug 31 07:27:14 volumio volumio[4476]: info: ------------------------------ 3ms Aug 31 07:27:14 volumio volumio[4476]: info: Aug 31 07:27:14 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:14 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:14 volumio volumio[4476]: info: ------------------------------ 3ms Aug 31 07:27:14 volumio volumio[4476]: info: Aug 31 07:27:14 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:14 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:14 volumio volumio[4476]: info: ------------------------------ 3ms Aug 31 07:27:14 volumio volumio[4476]: info: Aug 31 07:27:14 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:14 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:14 volumio volumio[4476]: info: ------------------------------ 2ms Aug 31 07:27:14 volumio volumio[4476]: info: Aug 31 07:27:14 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:14 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:14 volumio volumio[4476]: info: ------------------------------ 3ms Aug 31 07:27:14 volumio volumio[4476]: info: Aug 31 07:27:14 volumio volumio[4476]: ---------------------------- MPD announces state update: player Aug 31 07:27:14 volumio volumio[4476]: info: ControllerMpd::getState Aug 31 07:27:14 volumio volumio[4476]: info: ControllerMpd::sendMpdCommand status Aug 31 07:27:14 volumio volumio[4476]: info: sending command... Aug 31 07:27:14 volumio volumio[4476]: info: parsing response... Aug 31 07:27:14 volumio volumio[4476]: info: ControllerMpd::sendMpdCommand clearerror Aug 31 07:27:14 volumio volumio[4476]: info: sending command... Aug 31 07:27:14 volumio volumio[4476]: info: ControllerMpd::parseState Aug 31 07:27:14 volumio volumio[4476]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 31 07:27:14 volumio volumio[4476]: info: sending command... Aug 31 07:27:14 volumio volumio[4476]: info: parsing response... Aug 31 07:27:14 volumio volumio[4476]: info: parsing response... Aug 31 07:27:14 volumio volumio[4476]: info: ControllerMpd::parseTrackInfo Aug 31 07:27:14 volumio volumio[4476]: info: ControllerMpd::pushState Aug 31 07:27:14 volumio volumio[4476]: info: CoreCommandRouter::servicePushState Aug 31 07:27:14 volumio volumio[4476]: info: CoreStateMachine::syncState Aug 31 07:27:14 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:14 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:14 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:14 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:14 volumio volumio[4476]: info: CorePlayQueue::getTrack 0 Aug 31 07:27:14 volumio volumio[4476]: info: 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://m8.music.126.net/20250831155212/759fa3e8c64e4830dab814379f610df6/yyaac/obj/wonDkMOGw6XDiTHCmMOi/3036041867/3257/09ce/d8ac/ba1d0376202b3b74034d404852eed14b.m4a","trackType":"m4a"} Aug 31 07:27:14 volumio volumio[4476]: info: CURRENT POSITION 0 Aug 31 07:27:14 volumio volumio[4476]: info: CoreStateMachine::syncState stateService stop Aug 31 07:27:14 volumio volumio[4476]: info: CoreStateMachine::syncState currentStatus stop Aug 31 07:27:14 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:14 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:14 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:14 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:14 volumio volumio[4476]: info: No code Aug 31 07:27:14 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:14 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:14 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:14 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:14 volumio volumio[4476]: info: ------------------------------ 44ms Aug 31 07:27:18 volumio volumio[4476]: info: Clearing queue after UPNP request Aug 31 07:27:18 volumio volumio[4476]: info: CoreStateMachine::ClearQueue Aug 31 07:27:18 volumio volumio[4476]: info: CoreStateMachine::stop Aug 31 07:27:18 volumio volumio[4476]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 07:27:18 volumio volumio[4476]: UNSET VOLATILE Aug 31 07:27:18 volumio volumio[4476]: info: CorePlayQueue::clearPlayQueue Aug 31 07:27:18 volumio volumio[4476]: info: CorePlayQueue::saveQueue Aug 31 07:27:18 volumio volumio[4476]: info: CoreStateMachine::pushEmptyState Aug 31 07:27:18 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:18 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:18 volumio volumio[4476]: info: CoreCommandRouter::volumioPushQueue Aug 31 07:27:18 volumio volumio[4476]: info: Aug 31 07:27:18 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:18 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:18 volumio volumio[4476]: info: null Aug 31 07:27:18 volumio volumio[4476]: info: ------------------------------ 3ms Aug 31 07:27:18 volumio volumio[4476]: info: Starting UPNP Playback Aug 31 07:27:18 volumio volumio[4476]: info: Preparing playback through UPNP Aug 31 07:27:18 volumio volumio[4476]: info: CoreCommandRouter::volumioGetState Aug 31 07:27:18 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:18 volumio volumio[4476]: info: CorePlayQueue::getTrack 0 Aug 31 07:27:18 volumio volumio[4476]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 07:27:18 volumio volumio[4476]: info: Aug 31 07:27:18 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:18 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:18 volumio volumio[4476]: info: ------------------------------ 3ms Aug 31 07:27:18 volumio volumio[4476]: info: Aug 31 07:27:18 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:18 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:18 volumio volumio[4476]: info: ------------------------------ 3ms Aug 31 07:27:18 volumio volumio[4476]: info: Aug 31 07:27:18 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:18 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:18 volumio volumio[4476]: info: ------------------------------ 3ms Aug 31 07:27:18 volumio volumio[4476]: info: Aug 31 07:27:18 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:18 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:18 volumio volumio[4476]: info: ------------------------------ 3ms Aug 31 07:27:18 volumio volumio[4476]: info: Aug 31 07:27:18 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:18 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:18 volumio volumio[4476]: info: ------------------------------ 4ms Aug 31 07:27:18 volumio volumio[4476]: info: Aug 31 07:27:18 volumio volumio[4476]: ---------------------------- MPD announces system playlist update Aug 31 07:27:18 volumio volumio[4476]: info: Ignoring MPD Status Update Aug 31 07:27:18 volumio volumio[4476]: info: ------------------------------ 3ms Aug 31 07:27:19 volumio volumio[4476]: info: Aug 31 07:27:19 volumio volumio[4476]: ---------------------------- MPD announces state update: player Aug 31 07:27:19 volumio volumio[4476]: info: ControllerMpd::getState Aug 31 07:27:19 volumio volumio[4476]: info: ControllerMpd::sendMpdCommand status Aug 31 07:27:19 volumio volumio[4476]: info: sending command... Aug 31 07:27:19 volumio volumio[4476]: info: parsing response... Aug 31 07:27:19 volumio volumio[4476]: info: ControllerMpd::parseState Aug 31 07:27:19 volumio volumio[4476]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 31 07:27:19 volumio volumio[4476]: info: sending command... Aug 31 07:27:19 volumio volumio[4476]: info: Aug 31 07:27:19 volumio volumio[4476]: ---------------------------- MPD announces state update: player Aug 31 07:27:19 volumio volumio[4476]: info: ControllerMpd::getState Aug 31 07:27:19 volumio volumio[4476]: info: ControllerMpd::sendMpdCommand status Aug 31 07:27:19 volumio volumio[4476]: info: sending command... Aug 31 07:27:19 volumio volumio[4476]: info: Aug 31 07:27:19 volumio volumio[4476]: ---------------------------- MPD announces state update: player Aug 31 07:27:19 volumio volumio[4476]: info: parsing response... Aug 31 07:27:19 volumio volumio[4476]: info: ControllerMpd::getState Aug 31 07:27:19 volumio volumio[4476]: info: ControllerMpd::sendMpdCommand status Aug 31 07:27:19 volumio volumio[4476]: info: parsing response... Aug 31 07:27:19 volumio volumio[4476]: info: ControllerMpd::parseTrackInfo Aug 31 07:27:19 volumio volumio[4476]: info: sending command... Aug 31 07:27:19 volumio volumio[4476]: info: ControllerMpd::parseState Aug 31 07:27:19 volumio volumio[4476]: info: ControllerMpd::pushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreCommandRouter::servicePushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::syncState Aug 31 07:27:19 volumio volumio[4476]: In UPNP mode Aug 31 07:27:19 volumio volumio[4476]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":26,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2540 Kbps","isStreaming":false,"title":"弱水三千","artist":"石头/张晓棠","album":"念","uri":"http://m7.music.126.net/20250831155217/815dc0e3de866ffb90fc51e2afe7df35/yyaac/obj/wonDkMOGw6XDiTHCmMOi/3036041867/3257/09ce/d8ac/ba1d0376202b3b74034d404852eed14b.m4a","trackType":"m4a"} Aug 31 07:27:19 volumio volumio[4476]: info: CURRENT POSITION 0 Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::syncState stateService play Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::syncState currentStatus stop Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:19 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:19 volumio volumio[4476]: info: ControllerMpd::pushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreCommandRouter::servicePushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::syncState Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:19 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:19 volumio volumio[4476]: info: CorePlayQueue::getTrack 0 Aug 31 07:27:19 volumio volumio[4476]: info: 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} Aug 31 07:27:19 volumio volumio[4476]: info: CURRENT POSITION 0 Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::syncState stateService stop Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::syncState currentStatus play Aug 31 07:27:19 volumio volumio[4476]: info: CURRENT POSITION 0 Aug 31 07:27:19 volumio volumio[4476]: info: CURRENT POSITION 0 Aug 31 07:27:19 volumio volumio[4476]: info: END OF QUEUE Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::pushEmptyState Aug 31 07:27:19 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::stPlaybackTimer Aug 31 07:27:19 volumio volumio[4476]: info: ------------------------------ 118ms Aug 31 07:27:19 volumio volumio[4476]: info: ------------------------------ 66ms Aug 31 07:27:19 volumio volumio[4476]: info: parsing response... Aug 31 07:27:19 volumio volumio[4476]: info: ControllerMpd::parseState Aug 31 07:27:19 volumio volumio[4476]: info: ControllerMpd::pushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreCommandRouter::servicePushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::syncState Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:19 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:19 volumio volumio[4476]: info: CorePlayQueue::getTrack 0 Aug 31 07:27:19 volumio volumio[4476]: info: 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} Aug 31 07:27:19 volumio volumio[4476]: info: CURRENT POSITION 0 Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::syncState stateService stop Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::syncState currentStatus stop Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:19 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:19 volumio volumio[4476]: info: No code Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::pushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreStateMachine::getState Aug 31 07:27:19 volumio volumio[4476]: info: CoreCommandRouter::volumioPushState Aug 31 07:27:19 volumio volumio[4476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:27:19 volumio volumio[4476]: info: ------------------------------ 93ms Aug 31 07:27:49 volumio volumio[4476]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 07:27:49 volumio volumio[4476]: { Error: connect ETIMEDOUT 128.242.240.180:443 Aug 31 07:27:49 volumio volumio[4476]: at Object._errnoException (util.js:1022:11) Aug 31 07:27:49 volumio volumio[4476]: at _exceptionWithHostPort (util.js:1044:20) Aug 31 07:27:49 volumio volumio[4476]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 31 07:27:49 volumio volumio[4476]: code: 'ETIMEDOUT', Aug 31 07:27:49 volumio volumio[4476]: errno: 'ETIMEDOUT', Aug 31 07:27:49 volumio volumio[4476]: syscall: 'connect', Aug 31 07:27:49 volumio volumio[4476]: address: '128.242.240.180', Aug 31 07:27:49 volumio volumio[4476]: port: 443 } Aug 31 07:27:49 volumio volumio[4476]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 07:27:50 volumio sudo[8827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-31 07:26 Aug 31 07:27:50 volumio sudo[8827]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="ac2a0afddd424ea15d982b8c75eeec3a8648a313" VOLUMIO_FE_VERSION="9604ef2f02ec15ad7e9a30d48b0c663e23cfc947" VOLUMIO_BE_VERSION="b2e85b2e8facd2d741f2fc40be426a34804b56fc" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 6 23:48:23 CEST 2020" VOLUMIO_VERSION="2.774" VOLUMIO_HARDWARE="kvim3" VOLUMIO_HASH="b427efb88d6696856039c964623d0d3f"