-- Logs begin at Sat 2024-07-20 07:14:33 CST, end at Sat 2024-07-20 07:20:08 CST. --
Jul 20 07:19:06 volumio volumio[1119]: info: VolumeController::SetAlsaVolume44
Jul 20 07:19:06 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:06 volumio volumio[1119]: info: CorePlayQueue::getTrack 0
Jul 20 07:19:06 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 20 07:19:06 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:06 volumio volumio[1119]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number.
Jul 20 07:19:09 volumio volumio[1119]: info: VolumeController::SetAlsaVolume55
Jul 20 07:19:09 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:09 volumio volumio[1119]: info: CorePlayQueue::getTrack 0
Jul 20 07:19:09 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 20 07:19:09 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:09 volumio volumio[1119]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number.
Jul 20 07:19:09 volumio volumio[1119]: info: VolumeController::SetAlsaVolume65
Jul 20 07:19:09 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:09 volumio volumio[1119]: info: CorePlayQueue::getTrack 0
Jul 20 07:19:09 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 20 07:19:09 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:09 volumio volumio[1119]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number.
Jul 20 07:19:20 volumio ntpd[1104]: Soliciting pool server 223.255.185.2
Jul 20 07:19:24 volumio volumio[1119]: info: CoreCommandRouter::volumioGetQueue
Jul 20 07:19:24 volumio volumio[1119]: info: CoreStateMachine::getQueue
Jul 20 07:19:24 volumio volumio[1119]: info: CorePlayQueue::getQueue
Jul 20 07:19:25 volumio ntpd[1104]: Soliciting pool server 2a01:4f8:120:9224::2
Jul 20 07:19:25 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jul 20 07:19:25 volumio volumio[1119]: info: CURURI: music-library/NAS/音乐
Jul 20 07:19:27 volumio volumio[1119]: info: Preload queue cleared
Jul 20 07:19:27 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jul 20 07:19:27 volumio volumio[1119]: info: CURURI: music-library/NAS/音乐/无损
Jul 20 07:19:27 volumio volumio[1119]: info: Preload queue cleared
Jul 20 07:19:27 volumio volumio[1119]: info: Preloading song: music-library/NAS/音乐/无损/云音乐AI,网易天音 - 若把你 越南电音.flac
Jul 20 07:19:27 volumio volumio[1119]: info: Preloading song: music-library/NAS/音乐/无损/Kirsty刘瑾睿 - 若把你.flac
Jul 20 07:19:28 volumio volumio[1119]: info: Exploding uri music-library/NAS/音乐/无损/云音乐AI,网易天音 - 若把你 越南电音.flac in service mpd
Jul 20 07:19:28 volumio volumio[1119]: info: ALBUMART /albumart?cacheid=759&web=/%E6%B5%81%E8%A1%8C%E7%83%AD%E6%AD%8CDJ%E6%94%B9%E7%BC%96/extralarge&path=%2Fmnt%2FNAS%2F%E9%9F%B3%E4%B9%90%2F%E6%97%A0%E6%8D%9F%2F%E4%BA%91%E9%9F%B3%E4%B9%90AI%2C%E7%BD%91%E6%98%93%E5%A4%A9%E9%9F%B3%20-%20%E8%8B%A5%E6%8A%8A%E4%BD%A0%20%E8%B6%8A%E5%8D%97%E7%94%B5%E9%9F%B3.flac&metadata=false
Jul 20 07:19:28 volumio volumio[1119]: info: URI /mnt/NAS/音乐/无损/云音乐AI,网易天音 - 若把你 越南电音.flac
Jul 20 07:19:28 volumio volumio[1119]: info: Exploding uri music-library/NAS/音乐/无损/Kirsty刘瑾睿 - 若把你.flac in service mpd
Jul 20 07:19:28 volumio volumio[1119]: info: ALBUMART /albumart?cacheid=759&web=/%E8%8B%A5%E6%8A%8A%E4%BD%A0/extralarge&path=%2Fmnt%2FNAS%2F%E9%9F%B3%E4%B9%90%2F%E6%97%A0%E6%8D%9F%2FKirsty%E5%88%98%E7%91%BE%E7%9D%BF%20-%20%E8%8B%A5%E6%8A%8A%E4%BD%A0.flac&metadata=false
Jul 20 07:19:28 volumio volumio[1119]: info: URI /mnt/NAS/音乐/无损/Kirsty刘瑾睿 - 若把你.flac
Jul 20 07:19:31 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jul 20 07:19:31 volumio volumio[1119]: info: CURURI: music-library/NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)
Jul 20 07:19:31 volumio volumio[1119]: info: Preload queue cleared
Jul 20 07:19:31 volumio volumio[1119]: info: Preloading song: music-library/NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage1.ape
Jul 20 07:19:31 volumio volumio[1119]: info: Preloading song: music-library/NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape
Jul 20 07:19:31 volumio volumio[1119]: info: Exploding uri music-library/NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage1.ape in service mpd
Jul 20 07:19:31 volumio volumio[1119]: info: Exploding uri music-library/NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape in service mpd
Jul 20 07:19:31 volumio volumio[1119]: info: ALBUMART /albumart?cacheid=759&web=//extralarge&path=%2Fmnt%2FNAS%2F%E9%9F%B3%E4%B9%90%2F%E6%97%A0%E6%8D%9F%2F%EF%BC%88%E5%8E%9F%E6%8A%93%EF%BC%89%E6%83%85%E6%AD%8C%E7%8E%8B%E5%AD%90%EF%BC%8D%E8%83%A1%E9%87%8C%E5%A5%A5%C2%B7%E4%BC%8A%E6%A0%BC%E8%8E%B1%E8%A5%BF%E4%BA%9A%E6%96%AF---%E6%B5%AA%E6%BC%AB%E4%B8%80%E7%94%9F%EF%BC%9A%E7%B2%BE%E9%80%89%E8%BE%91%EF%BC%88%EF%BC%92%EF%BC%A3%EF%BC%A4%EF%BC%89%2FCDImage1.ape&metadata=false
Jul 20 07:19:31 volumio volumio[1119]: info: URI /mnt/NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage1.ape
Jul 20 07:19:31 volumio volumio[1119]: info: ALBUMART /albumart?cacheid=759&web=//extralarge&path=%2Fmnt%2FNAS%2F%E9%9F%B3%E4%B9%90%2F%E6%97%A0%E6%8D%9F%2F%EF%BC%88%E5%8E%9F%E6%8A%93%EF%BC%89%E6%83%85%E6%AD%8C%E7%8E%8B%E5%AD%90%EF%BC%8D%E8%83%A1%E9%87%8C%E5%A5%A5%C2%B7%E4%BC%8A%E6%A0%BC%E8%8E%B1%E8%A5%BF%E4%BA%9A%E6%96%AF---%E6%B5%AA%E6%BC%AB%E4%B8%80%E7%94%9F%EF%BC%9A%E7%B2%BE%E9%80%89%E8%BE%91%EF%BC%88%EF%BC%92%EF%BC%A3%EF%BC%A4%EF%BC%89%2FCDImage2.ape&metadata=false
Jul 20 07:19:31 volumio volumio[1119]: info: URI /mnt/NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape
Jul 20 07:19:33 volumio ntpd[1104]: Soliciting pool server 193.182.111.143
Jul 20 07:19:33 volumio volumio[1119]: info: Preload queue cleared
Jul 20 07:19:33 volumio volumio[1119]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jul 20 07:19:33 volumio volumio[1119]: info: CoreStateMachine::ClearQueue
Jul 20 07:19:33 volumio volumio[1119]: info: CoreStateMachine::stop
Jul 20 07:19:33 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 20 07:19:33 volumio volumio[1119]: info: CoreStateMachine::stPlaybackTimer
Jul 20 07:19:33 volumio volumio[1119]: info: CoreStateMachine::updateTrackBlock
Jul 20 07:19:33 volumio volumio[1119]: info: CorePlayQueue::getTrackBlock
Jul 20 07:19:33 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:33 volumio volumio[1119]: info: CorePlayQueue::getTrack 0
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::serviceStop
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::getTrack 0
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::serviceStop
Jul 20 07:19:34 volumio volumio[1119]: info: ControllerMpd::stop
Jul 20 07:19:34 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand stop
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::clearPlayQueue
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::saveQueue
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::volumioPushQueue
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::addQueueItems
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::addQueueItems
Jul 20 07:19:34 volumio volumio[1119]: info: Preload queue cleared
Jul 20 07:19:34 volumio volumio[1119]: info: Adding Item to queue: music-library/NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage1.ape
Jul 20 07:19:34 volumio volumio[1119]: info: Using cached record of: music-library/NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage1.ape
Jul 20 07:19:34 volumio volumio[1119]: info: Adding Item to queue: music-library/NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape
Jul 20 07:19:34 volumio volumio[1119]: info: Using cached record of: music-library/NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::volumioPushQueue
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::saveQueue
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::updateTrackBlock
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::getTrackBlock
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::volumioPlay
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::play index 1
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::stop
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::play index undefined
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::startPlaybackTimer
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:34 volumio volumio[1119]: verbose: ControllerMpd::clearAddPlayTracks NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape
Jul 20 07:19:34 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand stop
Jul 20 07:19:34 volumio volumio[1119]: info:
Jul 20 07:19:34 volumio volumio[1119]: ---------------------------- MPD announces state update: player
Jul 20 07:19:34 volumio volumio[1119]: info: sendMpdCommand stop took 47 milliseconds
Jul 20 07:19:34 volumio volumio[1119]: info: ControllerMpd::getState
Jul 20 07:19:34 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status
Jul 20 07:19:34 volumio volumio[1119]: info: sendMpdCommand stop took 10 milliseconds
Jul 20 07:19:34 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand clear
Jul 20 07:19:34 volumio volumio[1119]: info:
Jul 20 07:19:34 volumio volumio[1119]: ---------------------------- MPD announces state update: player
Jul 20 07:19:34 volumio volumio[1119]: info: ControllerMpd::getState
Jul 20 07:19:34 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status
Jul 20 07:19:34 volumio volumio[1119]: info:
Jul 20 07:19:34 volumio volumio[1119]: ---------------------------- MPD announces state update: player
Jul 20 07:19:34 volumio volumio[1119]: info: ControllerMpd::getState
Jul 20 07:19:34 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status
Jul 20 07:19:34 volumio volumio[1119]: info:
Jul 20 07:19:34 volumio volumio[1119]: ---------------------------- MPD announces state update: player
Jul 20 07:19:34 volumio volumio[1119]: info: ControllerMpd::getState
Jul 20 07:19:34 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status
Jul 20 07:19:34 volumio volumio[1119]: info:
Jul 20 07:19:34 volumio volumio[1119]: ---------------------------- MPD announces system playlist update
Jul 20 07:19:34 volumio volumio[1119]: info: Ignoring MPD Status Update
Jul 20 07:19:34 volumio volumio[1119]: info: sendMpdCommand status took 16 milliseconds
Jul 20 07:19:34 volumio volumio[1119]: info: sendMpdCommand clear took 16 milliseconds
Jul 20 07:19:34 volumio volumio[1119]: info: sendMpdCommand status took 14 milliseconds
Jul 20 07:19:34 volumio volumio[1119]: info: sendMpdCommand status took 13 milliseconds
Jul 20 07:19:34 volumio volumio[1119]: info: sendMpdCommand status took 11 milliseconds
Jul 20 07:19:34 volumio volumio[1119]: verbose: ControllerMpd::parseState
Jul 20 07:19:34 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 20 07:19:34 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand add "NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape"
Jul 20 07:19:34 volumio volumio[1119]: verbose: ControllerMpd::parseState
Jul 20 07:19:34 volumio volumio[1119]: verbose: ControllerMpd::parseState
Jul 20 07:19:34 volumio volumio[1119]: verbose: ControllerMpd::parseState
Jul 20 07:19:34 volumio volumio[1119]: info: ControllerMpd::pushState
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::servicePushState
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:34 volumio volumio[1119]: 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 20 07:19:34 volumio volumio[1119]: verbose: CURRENT POSITION 1
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::syncState stateService stop
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus stop
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:34 volumio volumio[1119]: info: No code
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:34 volumio volumio[1119]: info: ControllerMpd::pushState
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::servicePushState
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:34 volumio volumio[1119]: 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 20 07:19:34 volumio volumio[1119]: verbose: CURRENT POSITION 1
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::syncState stateService stop
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus stop
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:34 volumio volumio[1119]: info: No code
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:34 volumio volumio[1119]: info: ControllerMpd::pushState
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::servicePushState
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:34 volumio volumio[1119]: 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 20 07:19:34 volumio volumio[1119]: verbose: CURRENT POSITION 1
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::syncState stateService stop
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus stop
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:34 volumio volumio[1119]: info: No code
Jul 20 07:19:34 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:34 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:34 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:34 volumio volumio[1119]: info: ------------------------------ 180ms
Jul 20 07:19:34 volumio volumio[1119]: info: ------------------------------ 179ms
Jul 20 07:19:34 volumio volumio[1119]: info: ------------------------------ 177ms
Jul 20 07:19:34 volumio volumio[1119]: info:
Jul 20 07:19:34 volumio volumio[1119]: ---------------------------- MPD announces system playlist update
Jul 20 07:19:34 volumio volumio[1119]: info: Ignoring MPD Status Update
Jul 20 07:19:34 volumio volumio[1119]: info:
Jul 20 07:19:34 volumio volumio[1119]: ---------------------------- MPD announces system playlist update
Jul 20 07:19:34 volumio volumio[1119]: info: Ignoring MPD Status Update
Jul 20 07:19:34 volumio volumio[1119]: info:
Jul 20 07:19:34 volumio volumio[1119]: ---------------------------- MPD announces system playlist update
Jul 20 07:19:34 volumio volumio[1119]: info: Ignoring MPD Status Update
Jul 20 07:19:34 volumio volumio[1119]: error: updateQueue error: null
Jul 20 07:19:34 volumio volumio[1119]: info:
Jul 20 07:19:34 volumio volumio[1119]: ---------------------------- MPD announces system playlist update
Jul 20 07:19:34 volumio volumio[1119]: info: Ignoring MPD Status Update
Jul 20 07:19:34 volumio volumio[1119]: info: ------------------------------ 181ms
Jul 20 07:19:34 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 177 milliseconds
Jul 20 07:19:34 volumio volumio[1119]: info: sendMpdCommand add "NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape" took 178 milliseconds
Jul 20 07:19:34 volumio volumio[1119]: info: ------------------------------ 12ms
Jul 20 07:19:34 volumio volumio[1119]: info: ------------------------------ 11ms
Jul 20 07:19:34 volumio volumio[1119]: info: ------------------------------ 10ms
Jul 20 07:19:34 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo
Jul 20 07:19:34 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand play
Jul 20 07:19:34 volumio volumio[1119]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Jul 20 07:19:34 volumio volumio[1119]: info: ------------------------------ 202ms
Jul 20 07:19:34 volumio volumio[1119]: info:
Jul 20 07:19:34 volumio volumio[1119]: ---------------------------- MPD announces system playlist update
Jul 20 07:19:34 volumio volumio[1119]: info: Ignoring MPD Status Update
Jul 20 07:19:34 volumio volumio[1119]: info:
Jul 20 07:19:34 volumio volumio[1119]: ---------------------------- MPD announces system playlist update
Jul 20 07:19:34 volumio volumio[1119]: info: Ignoring MPD Status Update
Jul 20 07:19:34 volumio volumio[1119]: info:
Jul 20 07:19:34 volumio volumio[1119]: ---------------------------- MPD announces system playlist update
Jul 20 07:19:34 volumio volumio[1119]: info: Ignoring MPD Status Update
Jul 20 07:19:34 volumio volumio[1119]: info: ------------------------------ 29ms
Jul 20 07:19:34 volumio volumio[1119]: info: sendMpdCommand play took 25 milliseconds
Jul 20 07:19:34 volumio volumio[1119]: info: ------------------------------ 24ms
Jul 20 07:19:34 volumio volumio[1119]: info: ------------------------------ 23ms
Jul 20 07:19:34 volumio volumio[1119]: info: ------------------------------ 21ms
Jul 20 07:19:35 volumio ntpd[1104]: Soliciting pool server 84.16.67.12
Jul 20 07:19:37 volumio volumio[1119]: info:
Jul 20 07:19:37 volumio volumio[1119]: ---------------------------- MPD announces state update: player
Jul 20 07:19:37 volumio volumio[1119]: info: ControllerMpd::getState
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status
Jul 20 07:19:37 volumio volumio[1119]: info:
Jul 20 07:19:37 volumio volumio[1119]: ---------------------------- MPD announces state update: player
Jul 20 07:19:37 volumio volumio[1119]: info: ControllerMpd::getState
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status
Jul 20 07:19:37 volumio volumio[1119]: info:
Jul 20 07:19:37 volumio volumio[1119]: ---------------------------- MPD announces state update: player
Jul 20 07:19:37 volumio volumio[1119]: info: ControllerMpd::getState
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status
Jul 20 07:19:37 volumio volumio[1119]: info:
Jul 20 07:19:37 volumio volumio[1119]: ---------------------------- MPD announces state update: player
Jul 20 07:19:37 volumio volumio[1119]: info: ControllerMpd::getState
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status
Jul 20 07:19:37 volumio volumio[1119]: info:
Jul 20 07:19:37 volumio volumio[1119]: ---------------------------- MPD announces state update: player
Jul 20 07:19:37 volumio volumio[1119]: info: ControllerMpd::getState
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status
Jul 20 07:19:37 volumio volumio[1119]: info: sendMpdCommand status took 365 milliseconds
Jul 20 07:19:37 volumio volumio[1119]: info: sendMpdCommand status took 363 milliseconds
Jul 20 07:19:37 volumio volumio[1119]: info: sendMpdCommand status took 362 milliseconds
Jul 20 07:19:37 volumio volumio[1119]: info: sendMpdCommand status took 362 milliseconds
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::parseState
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::parseState
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::parseState
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::parseState
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 20 07:19:37 volumio volumio[1119]: info:
Jul 20 07:19:37 volumio volumio[1119]: ---------------------------- MPD announces state update: player
Jul 20 07:19:37 volumio volumio[1119]: info: ControllerMpd::getState
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status
Jul 20 07:19:37 volumio volumio[1119]: info:
Jul 20 07:19:37 volumio volumio[1119]: ---------------------------- MPD announces state update: player
Jul 20 07:19:37 volumio volumio[1119]: info: ControllerMpd::getState
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status
Jul 20 07:19:37 volumio volumio[1119]: info:
Jul 20 07:19:37 volumio volumio[1119]: ---------------------------- MPD announces state update: player
Jul 20 07:19:37 volumio volumio[1119]: info: ControllerMpd::getState
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status
Jul 20 07:19:37 volumio volumio[1119]: Upnp client error: Error: This socket has been ended by the other party
Jul 20 07:19:37 volumio volumio[1119]: info: sendMpdCommand status took 50 milliseconds
Jul 20 07:19:37 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 45 milliseconds
Jul 20 07:19:37 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 43 milliseconds
Jul 20 07:19:37 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 43 milliseconds
Jul 20 07:19:37 volumio volumio[1119]: info: sendMpdCommand status took 40 milliseconds
Jul 20 07:19:37 volumio volumio[1119]: info: sendMpdCommand status took 30 milliseconds
Jul 20 07:19:37 volumio volumio[1119]: info: sendMpdCommand status took 27 milliseconds
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::parseState
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::parseState
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::parseState
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::parseState
Jul 20 07:19:37 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 20 07:19:37 volumio volumio[1119]: info: ControllerMpd::pushState
Jul 20 07:19:37 volumio volumio[1119]: info: CoreCommandRouter::servicePushState
Jul 20 07:19:37 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:37 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2481,"duration":4411,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage2.ape","artist":null,"album":null,"uri":"NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape","trackType":"ape"}
Jul 20 07:19:37 volumio volumio[1119]: verbose: CURRENT POSITION 1
Jul 20 07:19:37 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play
Jul 20 07:19:37 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus stop
Jul 20 07:19:37 volumio volumio[1119]: info: ControllerMpd::pushState
Jul 20 07:19:37 volumio volumio[1119]: info: CoreCommandRouter::servicePushState
Jul 20 07:19:37 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:37 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2481,"duration":4411,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage2.ape","artist":null,"album":null,"uri":"NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape","trackType":"ape"}
Jul 20 07:19:37 volumio volumio[1119]: verbose: CURRENT POSITION 1
Jul 20 07:19:37 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play
Jul 20 07:19:37 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play
Jul 20 07:19:37 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload
Jul 20 07:19:37 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:37 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:37 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 20 07:19:37 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:37 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:37 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:37 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:37 volumio volumio[1119]: info: ControllerMpd::pushState
Jul 20 07:19:37 volumio volumio[1119]: info: CoreCommandRouter::servicePushState
Jul 20 07:19:37 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:37 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2481,"duration":4411,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage2.ape","artist":null,"album":null,"uri":"NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape","trackType":"ape"}
Jul 20 07:19:37 volumio volumio[1119]: verbose: CURRENT POSITION 1
Jul 20 07:19:37 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play
Jul 20 07:19:37 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play
Jul 20 07:19:37 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload
Jul 20 07:19:37 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:37 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:37 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:37 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:37 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:37 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:37 volumio volumio[1119]: info: ControllerMpd::pushState
Jul 20 07:19:37 volumio volumio[1119]: info: CoreCommandRouter::servicePushState
Jul 20 07:19:37 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:37 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2481,"duration":4411,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage2.ape","artist":null,"album":null,"uri":"NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape","trackType":"ape"}
Jul 20 07:19:37 volumio volumio[1119]: verbose: CURRENT POSITION 1
Jul 20 07:19:37 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play
Jul 20 07:19:37 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play
Jul 20 07:19:37 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload
Jul 20 07:19:37 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:37 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:37 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:37 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:37 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:37 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:37 volumio volumio[1119]: info: ------------------------------ 470ms
Jul 20 07:19:37 volumio volumio[1119]: info: ------------------------------ 615ms
Jul 20 07:19:37 volumio volumio[1119]: info: ------------------------------ 614ms
Jul 20 07:19:37 volumio volumio[1119]: info: ------------------------------ 612ms
Jul 20 07:19:38 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 571 milliseconds
Jul 20 07:19:38 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 566 milliseconds
Jul 20 07:19:38 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 565 milliseconds
Jul 20 07:19:38 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 565 milliseconds
Jul 20 07:19:38 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo
Jul 20 07:19:38 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo
Jul 20 07:19:38 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo
Jul 20 07:19:38 volumio volumio[1119]: info: ControllerMpd::pushState
Jul 20 07:19:38 volumio volumio[1119]: info: CoreCommandRouter::servicePushState
Jul 20 07:19:38 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:38 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2845,"duration":4411,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage2.ape","artist":null,"album":null,"uri":"NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape","trackType":"ape"}
Jul 20 07:19:38 volumio volumio[1119]: verbose: CURRENT POSITION 1
Jul 20 07:19:38 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play
Jul 20 07:19:38 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play
Jul 20 07:19:38 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload
Jul 20 07:19:38 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:38 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:38 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 20 07:19:38 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:38 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:38 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:38 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:38 volumio volumio[1119]: info: ControllerMpd::pushState
Jul 20 07:19:38 volumio volumio[1119]: info: CoreCommandRouter::servicePushState
Jul 20 07:19:38 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:38 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2845,"duration":4411,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage2.ape","artist":null,"album":null,"uri":"NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape","trackType":"ape"}
Jul 20 07:19:38 volumio volumio[1119]: verbose: CURRENT POSITION 1
Jul 20 07:19:38 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play
Jul 20 07:19:38 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play
Jul 20 07:19:38 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload
Jul 20 07:19:38 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:38 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:38 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:38 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:38 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:38 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:38 volumio volumio[1119]: info: ControllerMpd::pushState
Jul 20 07:19:38 volumio volumio[1119]: info: CoreCommandRouter::servicePushState
Jul 20 07:19:38 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:38 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2845,"duration":4411,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage2.ape","artist":null,"album":null,"uri":"NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape","trackType":"ape"}
Jul 20 07:19:38 volumio volumio[1119]: verbose: CURRENT POSITION 1
Jul 20 07:19:38 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play
Jul 20 07:19:38 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play
Jul 20 07:19:38 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload
Jul 20 07:19:38 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:38 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:38 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:38 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:38 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:38 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:38 volumio volumio[1119]: info: ControllerMpd::pushState
Jul 20 07:19:38 volumio volumio[1119]: info: CoreCommandRouter::servicePushState
Jul 20 07:19:38 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:38 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2845,"duration":4411,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage2.ape","artist":null,"album":null,"uri":"NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape","trackType":"ape"}
Jul 20 07:19:38 volumio volumio[1119]: verbose: CURRENT POSITION 1
Jul 20 07:19:38 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play
Jul 20 07:19:38 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play
Jul 20 07:19:38 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload
Jul 20 07:19:38 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:38 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:38 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:38 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:19:38 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:38 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:19:38 volumio volumio[1119]: info: ------------------------------ 745ms
Jul 20 07:19:38 volumio volumio[1119]: info: ------------------------------ 730ms
Jul 20 07:19:38 volumio volumio[1119]: info: ------------------------------ 726ms
Jul 20 07:19:38 volumio volumio[1119]: info: ------------------------------ 716ms
Jul 20 07:19:44 volumio volumio[1119]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8
Jul 20 07:19:44 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState
Jul 20 07:19:44 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:19:53 volumio volumio[1119]: Upnp client error: Error: This socket has been ended by the other party
Jul 20 07:20:03 volumio nmbd[920]: [2024/07/20 07:20:03.989449, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Jul 20 07:20:03 volumio nmbd[920]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.31.176 for name WORKGROUP<1d>.
Jul 20 07:20:03 volumio nmbd[920]: This response was from IP 192.168.31.214, reporting an IP address of 192.168.31.214.
Jul 20 07:20:05 volumio kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd
Jul 20 07:20:05 volumio kernel: usb 1-1: New USB device found, idVendor=22d9, idProduct=0436, bcdDevice= 6.c5
Jul 20 07:20:05 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0
Jul 20 07:20:05 volumio kernel: usb 1-1: Product: USB AUDIO 2.0 Device
Jul 20 07:20:05 volumio kernel: usb 1-1: Manufacturer: Borizsonic-Audio
Jul 20 07:20:05 volumio kernel: usb 1-1: 1:2 : unsupported format bits 0x100000000
Jul 20 07:20:05 volumio kernel: usbcore: registered new interface driver snd-usb-audio
Jul 20 07:20:05 volumio volumio[1119]: info:
Jul 20 07:20:05 volumio volumio[1119]: ---------------------------- USB Audio Device Attached
Jul 20 07:20:05 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach
Jul 20 07:20:05 volumio volumio[1119]: info: CoreCommandRouter::Close All Modals sent
Jul 20 07:20:05 volumio volumio[1119]: info: Preparing to save Alsa Options, stopping services first
Jul 20 07:20:05 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState
Jul 20 07:20:05 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:20:05 volumio volumio[1119]: info: CoreCommandRouter::volumioPause
Jul 20 07:20:05 volumio volumio[1119]: info: CoreStateMachine::pause
Jul 20 07:20:05 volumio volumio[1119]: info: CoreStateMachine::stPlaybackTimer
Jul 20 07:20:05 volumio volumio[1119]: info: CoreStateMachine::servicePause
Jul 20 07:20:05 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:20:05 volumio volumio[1119]: info: CoreCommandRouter::servicePause
Jul 20 07:20:05 volumio volumio[1119]: info: ControllerMpd::pause
Jul 20 07:20:05 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand pause
Jul 20 07:20:05 volumio volumio[1119]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"USB AUDIO 2.0 Device","alsacard":"Device"},"i2s":false}
Jul 20 07:20:05 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 20 07:20:05 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 20 07:20:06 volumio volumio[1119]: info: Setting mixer USB AUDIO 2.0 Device for card USB AUDIO 2.0 Device
Jul 20 07:20:06 volumio volumio[1119]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Jul 20 07:20:06 volumio volumio[1119]: info: Updating Volume Controller Parameters: Device: 5 Name: USB AUDIO 2.0 Device Mixer: USB AUDIO 2.0 Device Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1
Jul 20 07:20:06 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
Jul 20 07:20:06 volumio volumio[1119]: info: Disabling external Volume Control
Jul 20 07:20:06 volumio volumio[1119]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 20 07:20:06 volumio volumio[1119]: info: Preparing to generate the ALSA configuration file
Jul 20 07:20:06 volumio volumio[1119]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf
Jul 20 07:20:06 volumio volumio[1119]: info: Reading ALSA contributions from plugins.
Jul 20 07:20:06 volumio volumio[1119]: info:
Jul 20 07:20:06 volumio volumio[1119]: ---------------------------- MPD announces state update: player
Jul 20 07:20:06 volumio volumio[1119]: info: sendMpdCommand pause took 343 milliseconds
Jul 20 07:20:06 volumio volumio[1119]: info: ControllerMpd::getState
Jul 20 07:20:06 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status
Jul 20 07:20:06 volumio volumio[1119]: info:
Jul 20 07:20:06 volumio volumio[1119]: ---------------------------- MPD announces state update: player
Jul 20 07:20:06 volumio volumio[1119]: info: ControllerMpd::getState
Jul 20 07:20:06 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status
Jul 20 07:20:06 volumio volumio[1119]: info:
Jul 20 07:20:06 volumio volumio[1119]: ---------------------------- MPD announces state update: player
Jul 20 07:20:06 volumio volumio[1119]: info: ControllerMpd::getState
Jul 20 07:20:06 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status
Jul 20 07:20:06 volumio volumio[1119]: info:
Jul 20 07:20:06 volumio volumio[1119]: ---------------------------- MPD announces state update: player
Jul 20 07:20:06 volumio volumio[1119]: info: ControllerMpd::getState
Jul 20 07:20:06 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand status
Jul 20 07:20:07 volumio volumio[1119]: info: sendMpdCommand status took 1013 milliseconds
Jul 20 07:20:07 volumio volumio[1119]: info: sendMpdCommand status took 1013 milliseconds
Jul 20 07:20:07 volumio volumio[1119]: info: sendMpdCommand status took 1012 milliseconds
Jul 20 07:20:07 volumio volumio[1119]: info: sendMpdCommand status took 1012 milliseconds
Jul 20 07:20:07 volumio volumio[1119]: verbose: ControllerMpd::parseState
Jul 20 07:20:07 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 20 07:20:07 volumio volumio[1119]: verbose: ControllerMpd::parseState
Jul 20 07:20:07 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 20 07:20:07 volumio volumio[1119]: verbose: ControllerMpd::parseState
Jul 20 07:20:07 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 20 07:20:07 volumio volumio[1119]: verbose: ControllerMpd::parseState
Jul 20 07:20:07 volumio volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat
Jul 20 07:20:07 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 46 milliseconds
Jul 20 07:20:07 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 45 milliseconds
Jul 20 07:20:07 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 43 milliseconds
Jul 20 07:20:07 volumio volumio[1119]: info: sendMpdCommand playlistinfo took 43 milliseconds
Jul 20 07:20:07 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo
Jul 20 07:20:07 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo
Jul 20 07:20:07 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo
Jul 20 07:20:07 volumio volumio[1119]: verbose: ControllerMpd::parseTrackInfo
Jul 20 07:20:07 volumio volumio[1119]: info: ControllerMpd::pushState
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::servicePushState
Jul 20 07:20:07 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:20:07 volumio volumio[1119]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":31553,"duration":4411,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage2.ape","artist":null,"album":null,"uri":"NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape","trackType":"ape"}
Jul 20 07:20:07 volumio volumio[1119]: verbose: CURRENT POSITION 1
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::syncState stateService pause
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus pause
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:20:07 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::stPlaybackTimer
Jul 20 07:20:07 volumio volumio[1119]: info: ControllerMpd::pushState
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::servicePushState
Jul 20 07:20:07 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:20:07 volumio volumio[1119]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":31553,"duration":4411,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage2.ape","artist":null,"album":null,"uri":"NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape","trackType":"ape"}
Jul 20 07:20:07 volumio volumio[1119]: verbose: CURRENT POSITION 1
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::syncState stateService pause
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus pause
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:20:07 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::stPlaybackTimer
Jul 20 07:20:07 volumio volumio[1119]: info: ControllerMpd::pushState
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::servicePushState
Jul 20 07:20:07 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:20:07 volumio volumio[1119]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":31553,"duration":4411,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage2.ape","artist":null,"album":null,"uri":"NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape","trackType":"ape"}
Jul 20 07:20:07 volumio volumio[1119]: verbose: CURRENT POSITION 1
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::syncState stateService pause
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus pause
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:20:07 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::stPlaybackTimer
Jul 20 07:20:07 volumio volumio[1119]: info: ControllerMpd::pushState
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::servicePushState
Jul 20 07:20:07 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:20:07 volumio volumio[1119]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":31553,"duration":4411,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage2.ape","artist":null,"album":null,"uri":"NAS/音乐/无损/(原抓)情歌王子-胡里奥·伊格莱西亚斯---浪漫一生:精选辑(2CD)/CDImage2.ape","trackType":"ape"}
Jul 20 07:20:07 volumio volumio[1119]: verbose: CURRENT POSITION 1
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::syncState stateService pause
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus pause
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:20:07 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::stPlaybackTimer
Jul 20 07:20:07 volumio volumio[1119]: info: ------------------------------ 1177ms
Jul 20 07:20:07 volumio volumio[1119]: info: ------------------------------ 1176ms
Jul 20 07:20:07 volumio volumio[1119]: info: ------------------------------ 1174ms
Jul 20 07:20:07 volumio volumio[1119]: info: ------------------------------ 1170ms
Jul 20 07:20:07 volumio volumio[1119]: info: VolumeController:: Volume=68 Mute =false
Jul 20 07:20:07 volumio volumio[1119]: info: CoreStateMachine::pushState
Jul 20 07:20:07 volumio volumio[1119]: info: CorePlayQueue::getTrack 1
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Jul 20 07:20:07 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jul 20 07:20:07 volumio volumio[1119]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 20 07:20:07 volumio volumio[1119]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning'
Jul 20 07:20:07 volumio volumio[1119]: at Object.readdirSync (fs.js:1048:3)
Jul 20 07:20:07 volumio volumio[1119]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11)
Jul 20 07:20:07 volumio volumio[1119]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7)
Jul 20 07:20:07 volumio volumio[1119]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62)
Jul 20 07:20:07 volumio volumio[1119]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17)
Jul 20 07:20:07 volumio volumio[1119]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48)
Jul 20 07:20:07 volumio volumio[1119]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
Jul 20 07:20:07 volumio volumio[1119]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10)
Jul 20 07:20:07 volumio volumio[1119]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12)
Jul 20 07:20:07 volumio volumio[1119]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15)
Jul 20 07:20:07 volumio volumio[1119]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
Jul 20 07:20:07 volumio volumio[1119]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8)
Jul 20 07:20:07 volumio volumio[1119]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15)
Jul 20 07:20:07 volumio volumio[1119]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
Jul 20 07:20:07 volumio volumio[1119]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12)
Jul 20 07:20:07 volumio volumio[1119]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) {
Jul 20 07:20:07 volumio volumio[1119]: errno: -20,
Jul 20 07:20:07 volumio volumio[1119]: syscall: 'scandir',
Jul 20 07:20:07 volumio volumio[1119]: code: 'ENOTDIR',
Jul 20 07:20:07 volumio volumio[1119]: path: '/tmp/peppyrunning'
Jul 20 07:20:07 volumio volumio[1119]: }
Jul 20 07:20:07 volumio volumio[1119]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 20 07:20:08 volumio sudo[2936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-20 07:19
Jul 20 07:20:08 volumio sudo[2936]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 10 (buster)"
NAME="Debian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST"
VOLUMIO_VERSION="3.703"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"