-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Sat 2025-07-05 12:32:47 CST. --
Jul 05 12:31:02 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 05 12:31:02 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 05 12:31:02 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 05 12:31:02 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jul 05 12:31:02 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jul 05 12:31:02 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jul 05 12:31:02 volumio volumio[4329]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 05 12:31:02 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 05 12:31:03 volumio kernel: hwmon hwmon1: Undervoltage detected!
Jul 05 12:31:04 volumio volumio[4329]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Jul 05 12:31:04 volumio volumio[4329]: info: Completed starting MyVolumio Plugin
Jul 05 12:31:04 volumio volumio[4329]: [Metrics] CommandRouter: 45s 485.62ms
Jul 05 12:31:04 volumio volumio[4329]: info: CoreCommandRouter::volumiosetStartupVolume
Jul 05 12:31:04 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 05 12:31:04 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 05 12:31:04 volumio volumio[4329]: info: CoreCommandRouter::Close All Modals sent
Jul 05 12:31:04 volumio volumio[4329]: info: CoreCommandRouter::Close All Modals sent
Jul 05 12:31:05 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jul 05 12:31:05 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 05 12:31:05 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jul 05 12:31:06 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 05 12:31:07 volumio kernel: hwmon hwmon1: Voltage normalised
Jul 05 12:31:09 volumio kernel: hwmon hwmon1: Undervoltage detected!
Jul 05 12:31:11 volumio volumio[4329]: info: BOOT COMPLETED
Jul 05 12:31:11 volumio volumio[4329]: info: CoreCommandRouter::volumioNext
Jul 05 12:31:11 volumio volumio[4329]: info: CoreStateMachine::next
Jul 05 12:31:11 volumio volumio[4329]: info: CoreStateMachine::stop
Jul 05 12:31:11 volumio volumio[4329]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 05 12:31:11 volumio volumio[4329]: info: CoreStateMachine::play index undefined
Jul 05 12:31:11 volumio volumio[4329]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 05 12:31:11 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::startPlaybackTimer
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::clearAddPlayTracks USB/469A-F83B/song/郁可唯-水中花(Live).mp3
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand stop
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::updateTrackBlock
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrackBlock
Jul 05 12:31:12 volumio volumio[4329]: info: sendMpdCommand stop took 8 milliseconds
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand clear
Jul 05 12:31:12 volumio volumio[4329]: info:
Jul 05 12:31:12 volumio volumio[4329]: ---------------------------- MPD announces system playlist update
Jul 05 12:31:12 volumio volumio[4329]: info: Ignoring MPD Status Update
Jul 05 12:31:12 volumio volumio[4329]: info: sendMpdCommand clear took 7 milliseconds
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand add "USB/469A-F83B/song/郁可唯-水中花(Live).mp3"
Jul 05 12:31:12 volumio volumio[4329]: info:
Jul 05 12:31:12 volumio volumio[4329]: ---------------------------- MPD announces system playlist update
Jul 05 12:31:12 volumio volumio[4329]: info: Ignoring MPD Status Update
Jul 05 12:31:12 volumio volumio[4329]: info:
Jul 05 12:31:12 volumio volumio[4329]: ---------------------------- MPD announces system playlist update
Jul 05 12:31:12 volumio volumio[4329]: info: Ignoring MPD Status Update
Jul 05 12:31:12 volumio volumio[4329]: error: updateQueue error: null
Jul 05 12:31:12 volumio volumio[4329]: info:
Jul 05 12:31:12 volumio volumio[4329]: ---------------------------- MPD announces system playlist update
Jul 05 12:31:12 volumio volumio[4329]: info: Ignoring MPD Status Update
Jul 05 12:31:12 volumio volumio[4329]: info: ------------------------------ 27ms
Jul 05 12:31:12 volumio volumio[4329]: info: sendMpdCommand add "USB/469A-F83B/song/郁可唯-水中花(Live).mp3" took 20 milliseconds
Jul 05 12:31:12 volumio volumio[4329]: info: ------------------------------ 19ms
Jul 05 12:31:12 volumio volumio[4329]: info: ------------------------------ 16ms
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand play
Jul 05 12:31:12 volumio volumio[4329]: info:
Jul 05 12:31:12 volumio volumio[4329]: ---------------------------- MPD announces system playlist update
Jul 05 12:31:12 volumio volumio[4329]: info: Ignoring MPD Status Update
Jul 05 12:31:12 volumio volumio[4329]: info:
Jul 05 12:31:12 volumio volumio[4329]: ---------------------------- MPD announces system playlist update
Jul 05 12:31:12 volumio volumio[4329]: info: Ignoring MPD Status Update
Jul 05 12:31:12 volumio volumio[4329]: info:
Jul 05 12:31:12 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:12 volumio volumio[4329]: info: ------------------------------ 28ms
Jul 05 12:31:12 volumio volumio[4329]: info: sendMpdCommand play took 18 milliseconds
Jul 05 12:31:12 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:12 volumio volumio[4329]: info: ------------------------------ 20ms
Jul 05 12:31:12 volumio volumio[4329]: info: ------------------------------ 17ms
Jul 05 12:31:12 volumio volumio[4329]: info:
Jul 05 12:31:12 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:12 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:12 volumio volumio[4329]: info:
Jul 05 12:31:12 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:12 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:12 volumio volumio[4329]: info:
Jul 05 12:31:12 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:12 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:12 volumio volumio[4329]: info: sendMpdCommand status took 19 milliseconds
Jul 05 12:31:12 volumio volumio[4329]: info: sendMpdCommand status took 15 milliseconds
Jul 05 12:31:12 volumio volumio[4329]: info: sendMpdCommand status took 11 milliseconds
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 05 12:31:12 volumio volumio[4329]: info:
Jul 05 12:31:12 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:12 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:12 volumio volumio[4329]: info:
Jul 05 12:31:12 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:12 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:12 volumio volumio[4329]: info: sendMpdCommand status took 32 milliseconds
Jul 05 12:31:12 volumio volumio[4329]: info: sendMpdCommand playlistinfo took 22 milliseconds
Jul 05 12:31:12 volumio volumio[4329]: info: sendMpdCommand playlistinfo took 22 milliseconds
Jul 05 12:31:12 volumio volumio[4329]: info: sendMpdCommand playlistinfo took 21 milliseconds
Jul 05 12:31:12 volumio volumio[4329]: info: sendMpdCommand status took 17 milliseconds
Jul 05 12:31:12 volumio volumio[4329]: info: sendMpdCommand status took 12 milliseconds
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::parseTrackInfo
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::parseTrackInfo
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::parseTrackInfo
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 05 12:31:12 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"水中花(Live)","artist":"郁可唯","album":"时光音乐会 第2期","uri":"USB/469A-F83B/song/郁可唯-水中花(Live).mp3","trackType":"mp3"}
Jul 05 12:31:12 volumio volumio[4329]: verbose: CURRENT POSITION 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::syncState stateService play
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus stop
Jul 05 12:31:12 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"水中花(Live)","artist":"郁可唯","album":"时光音乐会 第2期","uri":"USB/469A-F83B/song/郁可唯-水中花(Live).mp3","trackType":"mp3"}
Jul 05 12:31:12 volumio volumio[4329]: verbose: CURRENT POSITION 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::syncState stateService play
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus play
Jul 05 12:31:12 volumio volumio[4329]: info: Received an update from plugin. extracting info from payload
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:12 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"水中花(Live)","artist":"郁可唯","album":"时光音乐会 第2期","uri":"USB/469A-F83B/song/郁可唯-水中花(Live).mp3","trackType":"mp3"}
Jul 05 12:31:12 volumio volumio[4329]: verbose: CURRENT POSITION 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::syncState stateService play
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus play
Jul 05 12:31:12 volumio volumio[4329]: info: Received an update from plugin. extracting info from payload
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:12 volumio volumio[4329]: info: ------------------------------ 120ms
Jul 05 12:31:12 volumio volumio[4329]: info: ------------------------------ 137ms
Jul 05 12:31:12 volumio volumio[4329]: info: ------------------------------ 134ms
Jul 05 12:31:12 volumio volumio[4329]: info: sendMpdCommand playlistinfo took 86 milliseconds
Jul 05 12:31:12 volumio volumio[4329]: info: sendMpdCommand playlistinfo took 79 milliseconds
Jul 05 12:31:12 volumio volumio[4329]: info: sendMpdCommand playlistinfo took 77 milliseconds
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::parseTrackInfo
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::parseTrackInfo
Jul 05 12:31:12 volumio volumio[4329]: verbose: ControllerMpd::parseTrackInfo
Jul 05 12:31:12 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"水中花(Live)","artist":"郁可唯","album":"时光音乐会 第2期","uri":"USB/469A-F83B/song/郁可唯-水中花(Live).mp3","trackType":"mp3"}
Jul 05 12:31:12 volumio volumio[4329]: verbose: CURRENT POSITION 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::syncState stateService play
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus play
Jul 05 12:31:12 volumio volumio[4329]: info: Received an update from plugin. extracting info from payload
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:12 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"水中花(Live)","artist":"郁可唯","album":"时光音乐会 第2期","uri":"USB/469A-F83B/song/郁可唯-水中花(Live).mp3","trackType":"mp3"}
Jul 05 12:31:12 volumio volumio[4329]: verbose: CURRENT POSITION 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::syncState stateService play
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus play
Jul 05 12:31:12 volumio volumio[4329]: info: Received an update from plugin. extracting info from payload
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:12 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":605,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"水中花(Live)","artist":"郁可唯","album":"时光音乐会 第2期","uri":"USB/469A-F83B/song/郁可唯-水中花(Live).mp3","trackType":"mp3"}
Jul 05 12:31:12 volumio volumio[4329]: verbose: CURRENT POSITION 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::syncState stateService play
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus play
Jul 05 12:31:12 volumio volumio[4329]: info: Received an update from plugin. extracting info from payload
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:12 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:12 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:12 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:12 volumio volumio[4329]: info: ------------------------------ 251ms
Jul 05 12:31:12 volumio volumio[4329]: info: ------------------------------ 232ms
Jul 05 12:31:12 volumio volumio[4329]: info: ------------------------------ 229ms
Jul 05 12:31:13 volumio volumio[4329]: info: CoreCommandRouter::volumioPause
Jul 05 12:31:13 volumio volumio[4329]: info: CoreStateMachine::pause
Jul 05 12:31:13 volumio volumio[4329]: info: CoreStateMachine::stPlaybackTimer
Jul 05 12:31:13 volumio volumio[4329]: info: CoreStateMachine::servicePause
Jul 05 12:31:13 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:13 volumio volumio[4329]: info: CoreCommandRouter::servicePause
Jul 05 12:31:13 volumio volumio[4329]: info: ControllerMpd::pause
Jul 05 12:31:13 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand pause
Jul 05 12:31:13 volumio volumio[4329]: info: sendMpdCommand pause took 3 milliseconds
Jul 05 12:31:13 volumio volumio[4329]: info:
Jul 05 12:31:13 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:13 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:13 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:13 volumio volumio[4329]: info:
Jul 05 12:31:13 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:13 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:13 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:13 volumio volumio[4329]: info: sendMpdCommand status took 5 milliseconds
Jul 05 12:31:13 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:13 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 05 12:31:13 volumio volumio[4329]: info:
Jul 05 12:31:13 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:13 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:13 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:13 volumio volumio[4329]: info: sendMpdCommand status took 11 milliseconds
Jul 05 12:31:13 volumio volumio[4329]: info: sendMpdCommand playlistinfo took 9 milliseconds
Jul 05 12:31:13 volumio volumio[4329]: info: sendMpdCommand status took 5 milliseconds
Jul 05 12:31:13 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:13 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 05 12:31:13 volumio volumio[4329]: verbose: ControllerMpd::parseTrackInfo
Jul 05 12:31:13 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:13 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 05 12:31:13 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:13 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:13 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:13 volumio volumio[4329]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2354,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"水中花(Live)","artist":"郁可唯","album":"时光音乐会 第2期","uri":"USB/469A-F83B/song/郁可唯-水中花(Live).mp3","trackType":"mp3"}
Jul 05 12:31:13 volumio volumio[4329]: verbose: CURRENT POSITION 89
Jul 05 12:31:13 volumio volumio[4329]: info: CoreStateMachine::syncState stateService pause
Jul 05 12:31:13 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus pause
Jul 05 12:31:13 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:13 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:13 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 05 12:31:13 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:13 volumio volumio[4329]: info: CoreStateMachine::stPlaybackTimer
Jul 05 12:31:13 volumio volumio[4329]: info: ------------------------------ 52ms
Jul 05 12:31:13 volumio volumio[4329]: info: sendMpdCommand playlistinfo took 33 milliseconds
Jul 05 12:31:13 volumio volumio[4329]: info: sendMpdCommand playlistinfo took 32 milliseconds
Jul 05 12:31:13 volumio volumio[4329]: verbose: ControllerMpd::parseTrackInfo
Jul 05 12:31:13 volumio volumio[4329]: verbose: ControllerMpd::parseTrackInfo
Jul 05 12:31:13 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:13 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:13 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:13 volumio volumio[4329]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2354,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"水中花(Live)","artist":"郁可唯","album":"时光音乐会 第2期","uri":"USB/469A-F83B/song/郁可唯-水中花(Live).mp3","trackType":"mp3"}
Jul 05 12:31:13 volumio volumio[4329]: verbose: CURRENT POSITION 89
Jul 05 12:31:13 volumio volumio[4329]: info: CoreStateMachine::syncState stateService pause
Jul 05 12:31:13 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus pause
Jul 05 12:31:13 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:13 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:13 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:13 volumio volumio[4329]: info: CoreStateMachine::stPlaybackTimer
Jul 05 12:31:13 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:13 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:13 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:13 volumio volumio[4329]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2354,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"水中花(Live)","artist":"郁可唯","album":"时光音乐会 第2期","uri":"USB/469A-F83B/song/郁可唯-水中花(Live).mp3","trackType":"mp3"}
Jul 05 12:31:13 volumio volumio[4329]: verbose: CURRENT POSITION 89
Jul 05 12:31:13 volumio volumio[4329]: info: CoreStateMachine::syncState stateService pause
Jul 05 12:31:13 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus pause
Jul 05 12:31:13 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:13 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:13 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:13 volumio volumio[4329]: info: CoreStateMachine::stPlaybackTimer
Jul 05 12:31:13 volumio volumio[4329]: info: ------------------------------ 101ms
Jul 05 12:31:13 volumio volumio[4329]: info: ------------------------------ 96ms
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::volumioNext
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::next
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::stop
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::updateTrackBlock
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrackBlock
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::stPlaybackTimer
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::serviceStop
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrack 89
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::serviceStop
Jul 05 12:31:14 volumio volumio[4329]: info: ControllerMpd::stop
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand stop
Jul 05 12:31:14 volumio volumio[4329]: info:
Jul 05 12:31:14 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:14 volumio volumio[4329]: info: sendMpdCommand stop took 15 milliseconds
Jul 05 12:31:14 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::play index undefined
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::startPlaybackTimer
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::clearAddPlayTracks USB/469A-F83B/song/于冬然-其实都没有.mp3
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand stop
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::updateTrackBlock
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrackBlock
Jul 05 12:31:14 volumio volumio[4329]: info: sendMpdCommand status took 10 milliseconds
Jul 05 12:31:14 volumio volumio[4329]: info: sendMpdCommand stop took 5 milliseconds
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand clear
Jul 05 12:31:14 volumio volumio[4329]: info:
Jul 05 12:31:14 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:14 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:14 volumio volumio[4329]: info:
Jul 05 12:31:14 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:14 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:14 volumio volumio[4329]: info:
Jul 05 12:31:14 volumio volumio[4329]: ---------------------------- MPD announces system playlist update
Jul 05 12:31:14 volumio volumio[4329]: info: Ignoring MPD Status Update
Jul 05 12:31:14 volumio volumio[4329]: info: sendMpdCommand playlistinfo took 20 milliseconds
Jul 05 12:31:14 volumio volumio[4329]: info: sendMpdCommand clear took 20 milliseconds
Jul 05 12:31:14 volumio volumio[4329]: info: sendMpdCommand status took 17 milliseconds
Jul 05 12:31:14 volumio volumio[4329]: info: sendMpdCommand status took 14 milliseconds
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::parseTrackInfo
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand add "USB/469A-F83B/song/于冬然-其实都没有.mp3"
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:14 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:14 volumio volumio[4329]: 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":"水中花(Live)","artist":"郁可唯","album":"时光音乐会 第2期","uri":"USB/469A-F83B/song/郁可唯-水中花(Live).mp3","trackType":"mp3"}
Jul 05 12:31:14 volumio volumio[4329]: verbose: CURRENT POSITION 36
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::syncState stateService stop
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus stop
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:14 volumio volumio[4329]: info: No code
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:14 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:14 volumio volumio[4329]: 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}
Jul 05 12:31:14 volumio volumio[4329]: verbose: CURRENT POSITION 36
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::syncState stateService stop
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus stop
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:14 volumio volumio[4329]: info: No code
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:14 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:14 volumio volumio[4329]: 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}
Jul 05 12:31:14 volumio volumio[4329]: verbose: CURRENT POSITION 36
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::syncState stateService stop
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus stop
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:14 volumio volumio[4329]: info: No code
Jul 05 12:31:14 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:14 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:14 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:14 volumio volumio[4329]: info: ------------------------------ 209ms
Jul 05 12:31:14 volumio volumio[4329]: info: ------------------------------ 193ms
Jul 05 12:31:14 volumio volumio[4329]: info: ------------------------------ 193ms
Jul 05 12:31:14 volumio volumio[4329]: info:
Jul 05 12:31:14 volumio volumio[4329]: ---------------------------- MPD announces system playlist update
Jul 05 12:31:14 volumio volumio[4329]: info: Ignoring MPD Status Update
Jul 05 12:31:14 volumio volumio[4329]: info:
Jul 05 12:31:14 volumio volumio[4329]: ---------------------------- MPD announces system playlist update
Jul 05 12:31:14 volumio volumio[4329]: info: Ignoring MPD Status Update
Jul 05 12:31:14 volumio volumio[4329]: error: updateQueue error: null
Jul 05 12:31:14 volumio volumio[4329]: info:
Jul 05 12:31:14 volumio volumio[4329]: ---------------------------- MPD announces system playlist update
Jul 05 12:31:14 volumio volumio[4329]: info: Ignoring MPD Status Update
Jul 05 12:31:14 volumio volumio[4329]: info: ------------------------------ 201ms
Jul 05 12:31:14 volumio volumio[4329]: info: sendMpdCommand add "USB/469A-F83B/song/于冬然-其实都没有.mp3" took 195 milliseconds
Jul 05 12:31:14 volumio volumio[4329]: info: ------------------------------ 18ms
Jul 05 12:31:14 volumio volumio[4329]: info: ------------------------------ 16ms
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand play
Jul 05 12:31:14 volumio volumio[4329]: info:
Jul 05 12:31:14 volumio volumio[4329]: ---------------------------- MPD announces system playlist update
Jul 05 12:31:14 volumio volumio[4329]: info: Ignoring MPD Status Update
Jul 05 12:31:14 volumio volumio[4329]: info:
Jul 05 12:31:14 volumio volumio[4329]: ---------------------------- MPD announces system playlist update
Jul 05 12:31:14 volumio volumio[4329]: info: Ignoring MPD Status Update
Jul 05 12:31:14 volumio volumio[4329]: info:
Jul 05 12:31:14 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:14 volumio volumio[4329]: info:
Jul 05 12:31:14 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:14 volumio volumio[4329]: info: ------------------------------ 83ms
Jul 05 12:31:14 volumio volumio[4329]: info: sendMpdCommand play took 78 milliseconds
Jul 05 12:31:14 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:14 volumio volumio[4329]: info: ------------------------------ 79ms
Jul 05 12:31:14 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:14 volumio volumio[4329]: info: ------------------------------ 71ms
Jul 05 12:31:14 volumio volumio[4329]: info:
Jul 05 12:31:14 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:14 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:14 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:14 volumio volumio[4329]: info:
Jul 05 12:31:14 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:15 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:15 volumio volumio[4329]: info: sendMpdCommand status took 14 milliseconds
Jul 05 12:31:15 volumio volumio[4329]: info: sendMpdCommand status took 15 milliseconds
Jul 05 12:31:15 volumio volumio[4329]: info: sendMpdCommand status took 10 milliseconds
Jul 05 12:31:15 volumio volumio[4329]: info: sendMpdCommand status took 6 milliseconds
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 05 12:31:15 volumio volumio[4329]: info:
Jul 05 12:31:15 volumio volumio[4329]: ---------------------------- MPD announces state update: player
Jul 05 12:31:15 volumio volumio[4329]: info: ControllerMpd::getState
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 12:31:15 volumio volumio[4329]: info: sendMpdCommand playlistinfo took 13 milliseconds
Jul 05 12:31:15 volumio volumio[4329]: info: sendMpdCommand playlistinfo took 13 milliseconds
Jul 05 12:31:15 volumio volumio[4329]: info: sendMpdCommand playlistinfo took 13 milliseconds
Jul 05 12:31:15 volumio volumio[4329]: info: sendMpdCommand playlistinfo took 12 milliseconds
Jul 05 12:31:15 volumio volumio[4329]: info: sendMpdCommand status took 7 milliseconds
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::parseTrackInfo
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::parseTrackInfo
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::parseTrackInfo
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::parseTrackInfo
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::parseState
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 05 12:31:15 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:15 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:15 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:15 volumio volumio[4329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":222,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"其实都没有","artist":"于冬然","album":"其实都没有","uri":"USB/469A-F83B/song/于冬然-其实都没有.mp3","trackType":"mp3"}
Jul 05 12:31:15 volumio volumio[4329]: verbose: CURRENT POSITION 36
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::syncState stateService play
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus stop
Jul 05 12:31:15 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:15 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:15 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:15 volumio volumio[4329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":222,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"其实都没有","artist":"于冬然","album":"其实都没有","uri":"USB/469A-F83B/song/于冬然-其实都没有.mp3","trackType":"mp3"}
Jul 05 12:31:15 volumio volumio[4329]: verbose: CURRENT POSITION 36
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::syncState stateService play
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus play
Jul 05 12:31:15 volumio volumio[4329]: info: Received an update from plugin. extracting info from payload
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:15 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:15 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 05 12:31:15 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:15 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:15 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:15 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:15 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:15 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:15 volumio volumio[4329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":222,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"其实都没有","artist":"于冬然","album":"其实都没有","uri":"USB/469A-F83B/song/于冬然-其实都没有.mp3","trackType":"mp3"}
Jul 05 12:31:15 volumio volumio[4329]: verbose: CURRENT POSITION 36
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::syncState stateService play
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus play
Jul 05 12:31:15 volumio volumio[4329]: info: Received an update from plugin. extracting info from payload
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:15 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:15 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:15 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:15 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:15 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:15 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:15 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:15 volumio volumio[4329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":222,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"其实都没有","artist":"于冬然","album":"其实都没有","uri":"USB/469A-F83B/song/于冬然-其实都没有.mp3","trackType":"mp3"}
Jul 05 12:31:15 volumio volumio[4329]: verbose: CURRENT POSITION 36
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::syncState stateService play
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus play
Jul 05 12:31:15 volumio volumio[4329]: info: Received an update from plugin. extracting info from payload
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:15 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:15 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:15 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:15 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:15 volumio volumio[4329]: info: ------------------------------ 119ms
Jul 05 12:31:15 volumio volumio[4329]: info: ------------------------------ 186ms
Jul 05 12:31:15 volumio volumio[4329]: info: ------------------------------ 181ms
Jul 05 12:31:15 volumio volumio[4329]: info: ------------------------------ 179ms
Jul 05 12:31:15 volumio volumio[4329]: info: sendMpdCommand playlistinfo took 150 milliseconds
Jul 05 12:31:15 volumio volumio[4329]: verbose: ControllerMpd::parseTrackInfo
Jul 05 12:31:15 volumio volumio[4329]: info: ControllerMpd::pushState
Jul 05 12:31:15 volumio volumio[4329]: info: CoreCommandRouter::servicePushState
Jul 05 12:31:15 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:15 volumio volumio[4329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":222,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"其实都没有","artist":"于冬然","album":"其实都没有","uri":"USB/469A-F83B/song/于冬然-其实都没有.mp3","trackType":"mp3"}
Jul 05 12:31:15 volumio volumio[4329]: verbose: CURRENT POSITION 36
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::syncState stateService play
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::syncState currentStatus play
Jul 05 12:31:15 volumio volumio[4329]: info: Received an update from plugin. extracting info from payload
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:15 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:15 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:15 volumio volumio[4329]: info: CoreStateMachine::pushState
Jul 05 12:31:15 volumio volumio[4329]: info: CorePlayQueue::getTrack 36
Jul 05 12:31:15 volumio volumio[4329]: info: CoreCommandRouter::volumioPushState
Jul 05 12:31:15 volumio volumio[4329]: info: ------------------------------ 215ms
Jul 05 12:31:15 volumio volumio-remote-updater[1391]: No test mode
Jul 05 12:31:15 volumio volumio-remote-updater[1391]: No alpha test mode
Jul 05 12:31:15 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jul 05 12:31:16 volumio volumio[4329]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Disable UI controls during DLNA playback
\n- Fix for Cast functionality halting due to memory filling up
\n- Fix for XML parsing on web-radio search
\n
\nNEW ADDITIONS
\n\n- Add link for downloading CORRD in the side-menu
\n
\n","title":"Update v3.819","updateavailable":true}
Jul 05 12:31:16 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jul 05 12:31:17 volumio kernel: hwmon hwmon1: Voltage normalised
Jul 05 12:31:19 volumio kernel: hwmon hwmon1: Undervoltage detected!
Jul 05 12:31:26 volumio volumio[4329]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 05 12:31:26 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jul 05 12:31:27 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork
Jul 05 12:31:27 volumio sudo[4696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0
Jul 05 12:31:27 volumio sudo[4696]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 05 12:31:27 volumio sudo[4701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Jul 05 12:31:27 volumio sudo[4701]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 05 12:31:27 volumio sudo[4696]: pam_unix(sudo:session): session closed for user root
Jul 05 12:31:27 volumio sudo[4701]: pam_unix(sudo:session): session closed for user root
Jul 05 12:31:27 volumio sudo[4709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Jul 05 12:31:27 volumio sudo[4709]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 05 12:31:27 volumio sudo[4709]: pam_unix(sudo:session): session closed for user root
Jul 05 12:31:27 volumio sudo[4715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Jul 05 12:31:27 volumio sudo[4715]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 05 12:31:27 volumio sudo[4715]: pam_unix(sudo:session): session closed for user root
Jul 05 12:31:27 volumio sudo[4721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 05 12:31:27 volumio sudo[4721]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 05 12:31:27 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 05 12:31:27 volumio volumio[4329]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 05 12:31:27 volumio sudo[4721]: pam_unix(sudo:session): session closed for user root
Jul 05 12:31:27 volumio sudo[4724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 05 12:31:27 volumio sudo[4724]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 05 12:31:27 volumio sudo[4724]: pam_unix(sudo:session): session closed for user root
Jul 05 12:31:27 volumio sudo[4728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 05 12:31:27 volumio sudo[4728]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 05 12:31:28 volumio sudo[4728]: pam_unix(sudo:session): session closed for user root
Jul 05 12:31:38 volumio kernel: hwmon hwmon1: Voltage normalised
Jul 05 12:31:42 volumio kernel: hwmon hwmon1: Undervoltage detected!
Jul 05 12:31:42 volumio systemd[1]: Starting Cleanup of Temporary Directories...
Jul 05 12:31:42 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded.
Jul 05 12:31:42 volumio systemd[1]: Started Cleanup of Temporary Directories.
Jul 05 12:31:48 volumio kernel: hwmon hwmon1: Voltage normalised
Jul 05 12:31:50 volumio kernel: hwmon hwmon1: Undervoltage detected!
Jul 05 12:31:57 volumio kernel: hwmon hwmon1: Voltage normalised
Jul 05 12:32:03 volumio kernel: hwmon hwmon1: Undervoltage detected!
Jul 05 12:32:09 volumio kernel: hwmon hwmon1: Voltage normalised
Jul 05 12:32:13 volumio kernel: hwmon hwmon1: Undervoltage detected!
Jul 05 12:32:19 volumio kernel: hwmon hwmon1: Voltage normalised
Jul 05 12:32:24 volumio kernel: hwmon hwmon1: Undervoltage detected!
Jul 05 12:32:28 volumio kernel: hwmon hwmon1: Voltage normalised
Jul 05 12:32:34 volumio kernel: hwmon hwmon1: Undervoltage detected!
Jul 05 12:32:38 volumio kernel: hwmon hwmon1: Voltage normalised
Jul 05 12:32:42 volumio kernel: hwmon hwmon1: Undervoltage detected!
Jul 05 12:32:46 volumio volumio[4329]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 05 12:32:46 volumio volumio[4329]: Error: connect ETIMEDOUT 128.242.245.157:443
Jul 05 12:32:46 volumio volumio[4329]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) {
Jul 05 12:32:46 volumio volumio[4329]: errno: -110,
Jul 05 12:32:46 volumio volumio[4329]: code: 'ETIMEDOUT',
Jul 05 12:32:46 volumio volumio[4329]: syscall: 'connect',
Jul 05 12:32:46 volumio volumio[4329]: address: '128.242.245.157',
Jul 05 12:32:46 volumio volumio[4329]: port: 443
Jul 05 12:32:46 volumio volumio[4329]: }
Jul 05 12:32:46 volumio volumio[4329]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 05 12:32:47 volumio sudo[4921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 12:31
Jul 05 12:32:47 volumio sudo[4921]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST"
VOLUMIO_VERSION="3.816"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"