-- 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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ） 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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/CDImage1.ape Jul 20 07:19:31 volumio volumio[1119]: info: Preloading song: music-library/NAS/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/CDImage2.ape Jul 20 07:19:31 volumio volumio[1119]: info: Exploding uri music-library/NAS/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/CDImage1.ape in service mpd Jul 20 07:19:31 volumio volumio[1119]: info: Exploding uri music-library/NAS/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/CDImage1.ape Jul 20 07:19:34 volumio volumio[1119]: info: Using cached record of: music-library/NAS/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/CDImage1.ape Jul 20 07:19:34 volumio volumio[1119]: info: Adding Item to queue: music-library/NAS/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/CDImage2.ape Jul 20 07:19:34 volumio volumio[1119]: info: Using cached record of: music-library/NAS/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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/音乐/无损/（原抓）情歌王子－胡里奥·伊格莱西亚斯---浪漫一生：精选辑（２ＣＤ）/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"