-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Wed 2024-05-08 17:42:18 CST. -- May 08 17:41:00 volumio-test-2 volumio[634]: info: Preload queue cleared May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioReplaceandPlayItems May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::ClearQueue May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::stop May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::stPlaybackTimer May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::updateTrackBlock May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrackBlock May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 1 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::serviceStop May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 1 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::serviceStop May 08 17:41:00 volumio-test-2 volumio[634]: info: ControllerMpd::stop May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand stop May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::clearPlayQueue May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::saveQueue May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushQueue May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::addQueueItems May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::addQueueItems May 08 17:41:00 volumio-test-2 volumio[634]: info: Preload queue cleared May 08 17:41:00 volumio-test-2 volumio[634]: info: Adding Item to queue: music-library/USB/MUSIC_128G/_试音碟/DENON之声/10高原兰.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Using cached record of: music-library/USB/MUSIC_128G/_试音碟/DENON之声/10高原兰.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Adding Item to queue: music-library/USB/MUSIC_128G/_试音碟/DENON之声/11得到你的人得不到你的心.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Using cached record of: music-library/USB/MUSIC_128G/_试音碟/DENON之声/11得到你的人得不到你的心.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Adding Item to queue: music-library/USB/MUSIC_128G/_试音碟/DENON之声/12独角戏.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Using cached record of: music-library/USB/MUSIC_128G/_试音碟/DENON之声/12独角戏.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Adding Item to queue: music-library/USB/MUSIC_128G/_试音碟/DENON之声/13南泥湾.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Using cached record of: music-library/USB/MUSIC_128G/_试音碟/DENON之声/13南泥湾.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Adding Item to queue: music-library/USB/MUSIC_128G/_试音碟/DENON之声/14秋意浓.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Using cached record of: music-library/USB/MUSIC_128G/_试音碟/DENON之声/14秋意浓.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Adding Item to queue: music-library/USB/MUSIC_128G/_试音碟/DENON之声/15阿姐鼓.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Using cached record of: music-library/USB/MUSIC_128G/_试音碟/DENON之声/15阿姐鼓.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Adding Item to queue: music-library/USB/MUSIC_128G/_试音碟/DENON之声/1葬心.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Using cached record of: music-library/USB/MUSIC_128G/_试音碟/DENON之声/1葬心.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Adding Item to queue: music-library/USB/MUSIC_128G/_试音碟/DENON之声/2渡口.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Using cached record of: music-library/USB/MUSIC_128G/_试音碟/DENON之声/2渡口.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Adding Item to queue: music-library/USB/MUSIC_128G/_试音碟/DENON之声/3Let's start from Here.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Using cached record of: music-library/USB/MUSIC_128G/_试音碟/DENON之声/3Let's start from Here.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Adding Item to queue: music-library/USB/MUSIC_128G/_试音碟/DENON之声/4再会无缘的情人.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Using cached record of: music-library/USB/MUSIC_128G/_试音碟/DENON之声/4再会无缘的情人.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Adding Item to queue: music-library/USB/MUSIC_128G/_试音碟/DENON之声/5隐形眼镜.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Using cached record of: music-library/USB/MUSIC_128G/_试音碟/DENON之声/5隐形眼镜.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Adding Item to queue: music-library/USB/MUSIC_128G/_试音碟/DENON之声/6南海姑娘.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Using cached record of: music-library/USB/MUSIC_128G/_试音碟/DENON之声/6南海姑娘.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Adding Item to queue: music-library/USB/MUSIC_128G/_试音碟/DENON之声/7冷冷的夏.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Using cached record of: music-library/USB/MUSIC_128G/_试音碟/DENON之声/7冷冷的夏.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Adding Item to queue: music-library/USB/MUSIC_128G/_试音碟/DENON之声/8Warm Your Heart.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Using cached record of: music-library/USB/MUSIC_128G/_试音碟/DENON之声/8Warm Your Heart.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Adding Item to queue: music-library/USB/MUSIC_128G/_试音碟/DENON之声/9Hijo de la luna.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: Using cached record of: music-library/USB/MUSIC_128G/_试音碟/DENON之声/9Hijo de la luna.wav May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushQueue May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::saveQueue May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::updateTrackBlock May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrackBlock May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPlay May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::play index 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::stop May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::play index undefined May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::startPlaybackTimer May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSIC_128G/_试音碟/DENON之声/1葬心.wav May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand stop May 08 17:41:00 volumio-test-2 volumio[634]: info: May 08 17:41:00 volumio-test-2 volumio[634]: ---------------------------- MPD announces state update: player May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand stop took 92 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: info: ControllerMpd::getState May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand status May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand stop took 21 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand clear May 08 17:41:00 volumio-test-2 volumio[634]: info: May 08 17:41:00 volumio-test-2 volumio[634]: ---------------------------- MPD announces state update: player May 08 17:41:00 volumio-test-2 volumio[634]: info: ControllerMpd::getState May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand status May 08 17:41:00 volumio-test-2 volumio[634]: info: May 08 17:41:00 volumio-test-2 volumio[634]: ---------------------------- MPD announces state update: player May 08 17:41:00 volumio-test-2 volumio[634]: info: ControllerMpd::getState May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand status May 08 17:41:00 volumio-test-2 volumio[634]: info: May 08 17:41:00 volumio-test-2 volumio[634]: ---------------------------- MPD announces system playlist update May 08 17:41:00 volumio-test-2 volumio[634]: info: Ignoring MPD Status Update May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand status took 24 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand clear took 22 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand status took 18 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand status took 15 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseState May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIC_128G/_试音碟/DENON之声/1葬心.wav" May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseState May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseState May 08 17:41:00 volumio-test-2 volumio[634]: info: ControllerMpd::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: 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} May 08 17:41:00 volumio-test-2 volumio[634]: verbose: CURRENT POSITION 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState stateService stop May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState currentStatus stop May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:00 volumio-test-2 volumio[634]: info: No code May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:00 volumio-test-2 volumio[634]: info: ControllerMpd::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: 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} May 08 17:41:00 volumio-test-2 volumio[634]: verbose: CURRENT POSITION 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState stateService stop May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState currentStatus stop May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:00 volumio-test-2 volumio[634]: info: No code May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:00 volumio-test-2 volumio[634]: info: ------------------------------ 107ms May 08 17:41:00 volumio-test-2 volumio[634]: info: ------------------------------ 105ms May 08 17:41:00 volumio-test-2 volumio[634]: info: May 08 17:41:00 volumio-test-2 volumio[634]: ---------------------------- MPD announces system playlist update May 08 17:41:00 volumio-test-2 volumio[634]: info: Ignoring MPD Status Update May 08 17:41:00 volumio-test-2 volumio[634]: info: May 08 17:41:00 volumio-test-2 volumio[634]: ---------------------------- MPD announces system playlist update May 08 17:41:00 volumio-test-2 volumio[634]: info: Ignoring MPD Status Update May 08 17:41:00 volumio-test-2 volumio[634]: error: updateQueue error: null May 08 17:41:00 volumio-test-2 volumio[634]: info: May 08 17:41:00 volumio-test-2 volumio[634]: ---------------------------- MPD announces system playlist update May 08 17:41:00 volumio-test-2 volumio[634]: info: Ignoring MPD Status Update May 08 17:41:00 volumio-test-2 volumio[634]: info: ------------------------------ 111ms May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand playlistinfo took 103 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand add "USB/MUSIC_128G/_试音碟/DENON之声/1葬心.wav" took 104 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: info: ------------------------------ 18ms May 08 17:41:00 volumio-test-2 volumio[634]: info: ------------------------------ 16ms May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseTrackInfo May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand play May 08 17:41:00 volumio-test-2 volumio[634]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 08 17:41:00 volumio-test-2 volumio[634]: info: ------------------------------ 149ms May 08 17:41:00 volumio-test-2 volumio[634]: info: May 08 17:41:00 volumio-test-2 volumio[634]: ---------------------------- MPD announces system playlist update May 08 17:41:00 volumio-test-2 volumio[634]: info: Ignoring MPD Status Update May 08 17:41:00 volumio-test-2 volumio[634]: info: May 08 17:41:00 volumio-test-2 volumio[634]: ---------------------------- MPD announces system playlist update May 08 17:41:00 volumio-test-2 volumio[634]: info: Ignoring MPD Status Update May 08 17:41:00 volumio-test-2 volumio[634]: info: May 08 17:41:00 volumio-test-2 volumio[634]: ---------------------------- MPD announces state update: player May 08 17:41:00 volumio-test-2 volumio[634]: info: May 08 17:41:00 volumio-test-2 volumio[634]: ---------------------------- MPD announces state update: player May 08 17:41:00 volumio-test-2 volumio[634]: info: ------------------------------ 41ms May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand play took 30 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: info: ControllerMpd::getState May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand status May 08 17:41:00 volumio-test-2 volumio[634]: info: ------------------------------ 25ms May 08 17:41:00 volumio-test-2 volumio[634]: info: ControllerMpd::getState May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand status May 08 17:41:00 volumio-test-2 volumio[634]: info: ------------------------------ 21ms May 08 17:41:00 volumio-test-2 volumio[634]: info: May 08 17:41:00 volumio-test-2 volumio[634]: ---------------------------- MPD announces state update: player May 08 17:41:00 volumio-test-2 volumio[634]: info: ControllerMpd::getState May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand status May 08 17:41:00 volumio-test-2 volumio[634]: info: May 08 17:41:00 volumio-test-2 volumio[634]: ---------------------------- MPD announces state update: player May 08 17:41:00 volumio-test-2 volumio[634]: info: ControllerMpd::getState May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand status May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand status took 18 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand status took 17 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand status took 12 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand status took 8 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseState May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseState May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseState May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseState May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 17:41:00 volumio-test-2 volumio[634]: info: May 08 17:41:00 volumio-test-2 volumio[634]: ---------------------------- MPD announces state update: player May 08 17:41:00 volumio-test-2 volumio[634]: info: ControllerMpd::getState May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand status May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand playlistinfo took 30 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand playlistinfo took 29 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand playlistinfo took 34 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand playlistinfo took 35 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand status took 29 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseTrackInfo May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseTrackInfo May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseTrackInfo May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseTrackInfo May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseState May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 17:41:00 volumio-test-2 volumio[634]: info: ControllerMpd::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1葬心.wav","artist":null,"album":null,"uri":"USB/MUSIC_128G/_试音碟/DENON之声/1葬心.wav","trackType":"wav"} May 08 17:41:00 volumio-test-2 volumio[634]: verbose: CURRENT POSITION 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState stateService play May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState currentStatus stop May 08 17:41:00 volumio-test-2 volumio[634]: info: ControllerMpd::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1葬心.wav","artist":null,"album":null,"uri":"USB/MUSIC_128G/_试音碟/DENON之声/1葬心.wav","trackType":"wav"} May 08 17:41:00 volumio-test-2 volumio[634]: verbose: CURRENT POSITION 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState stateService play May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState currentStatus play May 08 17:41:00 volumio-test-2 volumio[634]: info: Received an update from plugin. extracting info from payload May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:00 volumio-test-2 volumio[634]: info: ControllerMpd::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1葬心.wav","artist":null,"album":null,"uri":"USB/MUSIC_128G/_试音碟/DENON之声/1葬心.wav","trackType":"wav"} May 08 17:41:00 volumio-test-2 volumio[634]: verbose: CURRENT POSITION 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState stateService play May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState currentStatus play May 08 17:41:00 volumio-test-2 volumio[634]: info: Received an update from plugin. extracting info from payload May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:00 volumio-test-2 volumio[634]: info: ControllerMpd::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1葬心.wav","artist":null,"album":null,"uri":"USB/MUSIC_128G/_试音碟/DENON之声/1葬心.wav","trackType":"wav"} May 08 17:41:00 volumio-test-2 volumio[634]: verbose: CURRENT POSITION 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState stateService play May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState currentStatus play May 08 17:41:00 volumio-test-2 volumio[634]: info: Received an update from plugin. extracting info from payload May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:00 volumio-test-2 volumio[634]: info: ------------------------------ 125ms May 08 17:41:00 volumio-test-2 volumio[634]: info: ------------------------------ 156ms May 08 17:41:00 volumio-test-2 volumio[634]: info: ------------------------------ 148ms May 08 17:41:00 volumio-test-2 volumio[634]: info: ------------------------------ 146ms May 08 17:41:00 volumio-test-2 volumio[634]: info: sendMpdCommand playlistinfo took 88 milliseconds May 08 17:41:00 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseTrackInfo May 08 17:41:00 volumio-test-2 volumio[634]: info: ControllerMpd::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1葬心.wav","artist":null,"album":null,"uri":"USB/MUSIC_128G/_试音碟/DENON之声/1葬心.wav","trackType":"wav"} May 08 17:41:00 volumio-test-2 volumio[634]: verbose: CURRENT POSITION 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState stateService play May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState currentStatus play May 08 17:41:00 volumio-test-2 volumio[634]: info: Received an update from plugin. extracting info from payload May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:00 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:00 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:00 volumio-test-2 volumio[634]: info: ------------------------------ 161ms May 08 17:41:10 volumio-test-2 volumiologrotate[446]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 08 17:41:10 volumio-test-2 volumiologrotate[446]: ls: cannot access 'TEST': No such file or directory May 08 17:41:10 volumio-test-2 volumiologrotate[446]: ls: cannot access '2': No such file or directory May 08 17:41:16 volumio-test-2 volumio[634]: info: Airplay started streaming, receiving metadatas May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioStop May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::stop May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::stPlaybackTimer May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::updateTrackBlock May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrackBlock May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::serviceStop May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::serviceStop May 08 17:41:16 volumio-test-2 volumio[634]: info: ControllerMpd::stop May 08 17:41:16 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand stop May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 17:41:16 volumio-test-2 volumio[634]: info: Airplay started streaming, receiving metadatas May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioStop May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::stop May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 17:41:16 volumio-test-2 volumio[634]: info: Airplay started streaming, receiving metadatas May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioStop May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::stop May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 17:41:16 volumio-test-2 volumio[634]: info: May 08 17:41:16 volumio-test-2 volumio[634]: ---------------------------- MPD announces state update: player May 08 17:41:16 volumio-test-2 volumio[634]: info: sendMpdCommand stop took 38 milliseconds May 08 17:41:16 volumio-test-2 volumio[634]: info: ControllerMpd::getState May 08 17:41:16 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand status May 08 17:41:16 volumio-test-2 volumio[634]: info: sendMpdCommand status took 3 milliseconds May 08 17:41:16 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseState May 08 17:41:16 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 17:41:16 volumio-test-2 volumio[634]: info: May 08 17:41:16 volumio-test-2 volumio[634]: ---------------------------- MPD announces state update: player May 08 17:41:16 volumio-test-2 volumio[634]: info: ControllerMpd::getState May 08 17:41:16 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand status May 08 17:41:16 volumio-test-2 volumio[634]: info: May 08 17:41:16 volumio-test-2 volumio[634]: ---------------------------- MPD announces state update: player May 08 17:41:16 volumio-test-2 volumio[634]: info: ControllerMpd::getState May 08 17:41:16 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand status May 08 17:41:16 volumio-test-2 volumio[634]: info: sendMpdCommand playlistinfo took 14 milliseconds May 08 17:41:16 volumio-test-2 volumio[634]: info: sendMpdCommand status took 9 milliseconds May 08 17:41:16 volumio-test-2 volumio[634]: info: sendMpdCommand status took 6 milliseconds May 08 17:41:16 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseTrackInfo May 08 17:41:16 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseState May 08 17:41:16 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 17:41:16 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseState May 08 17:41:16 volumio-test-2 volumio[634]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 17:41:16 volumio-test-2 volumio[634]: info: ControllerMpd::pushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:16 volumio-test-2 shairport-sync[2252]: % Total % Received % Xferd Average Speed Time Time Time Current May 08 17:41:16 volumio-test-2 shairport-sync[2252]: Dload Upload Total Spent Left Speed May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1葬心.wav","artist":null,"album":null,"uri":"USB/MUSIC_128G/_试音碟/DENON之声/1葬心.wav","trackType":"wav"} May 08 17:41:16 volumio-test-2 volumio[634]: verbose: CURRENT POSITION 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState stateService stop May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState currentStatus stop May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:16 volumio-test-2 volumio[634]: info: No code May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:16 volumio-test-2 volumio[634]: info: ------------------------------ 73ms May 08 17:41:16 volumio-test-2 volumio[634]: info: sendMpdCommand playlistinfo took 47 milliseconds May 08 17:41:16 volumio-test-2 volumio[634]: info: sendMpdCommand playlistinfo took 46 milliseconds May 08 17:41:16 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseTrackInfo May 08 17:41:16 volumio-test-2 volumio[634]: verbose: ControllerMpd::parseTrackInfo May 08 17:41:16 volumio-test-2 volumio[634]: info: ControllerMpd::pushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1葬心.wav","artist":null,"album":null,"uri":"USB/MUSIC_128G/_试音碟/DENON之声/1葬心.wav","trackType":"wav"} May 08 17:41:16 volumio-test-2 volumio[634]: verbose: CURRENT POSITION 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState stateService stop May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState currentStatus stop May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:16 volumio-test-2 volumio[634]: info: No code May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:16 volumio-test-2 volumio[634]: info: ControllerMpd::pushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1葬心.wav","artist":null,"album":null,"uri":"USB/MUSIC_128G/_试音碟/DENON之声/1葬心.wav","trackType":"wav"} May 08 17:41:16 volumio-test-2 volumio[634]: verbose: CURRENT POSITION 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState stateService stop May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::syncState currentStatus stop May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:16 volumio-test-2 volumio[634]: info: No code May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:16 volumio-test-2 volumio[634]: info: ------------------------------ 143ms May 08 17:41:16 volumio-test-2 volumio[634]: info: ------------------------------ 140ms May 08 17:41:16 volumio-test-2 volumio[634]: info: May 08 17:41:16 volumio-test-2 volumio[634]: ---------------------------- Client requests Start Airplay PlaybackRoutine May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback May 08 17:41:16 volumio-test-2 volumio[634]: info: Airplay playback start May 08 17:41:16 volumio-test-2 volumio[634]: info: CorePlayQueue::getTrack 6 May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioStop May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::stop May 08 17:41:16 volumio-test-2 volumio[634]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 17:41:16 volumio-test-2 shairport-sync[2252]: [158B blob data] May 08 17:41:17 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:17 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:17 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 17:41:17 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:17 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:17 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:17 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:17 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:17 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:17 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:17 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:17 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:17 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 17:41:17 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:18 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:18 volumio-test-2 volumio[634]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E9%9F%B6%E6%B6%B5/%E7%A0%B4%E8%8C%A7(%E3%80%8A%E6%96%97%E7%BD%97%E5%A4%A7%E9%99%86%E3%80%8B%E5%8A%A8%E7%94%BB2020%E5%B9%B4%E6%96%B0%E4%B8%BB%E9%A2%98%E6%9B%B2)/7adc1d92-4a59-4709-8e9f-04410cba144b.jpg' May 08 17:41:18 volumio-test-2 volumio[634]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E9%9F%B6%E6%B6%B5/%E7%A0%B4%E8%8C%A7(%E3%80%8A%E6%96%97%E7%BD%97%E5%A4%A7%E9%99%86%E3%80%8B%E5%8A%A8%E7%94%BB2020%E5%B9%B4%E6%96%B0%E4%B8%BB%E9%A2%98%E6%9B%B2)/7adc1d92-4a59-4709-8e9f-04410cba144b.jpg' May 08 17:41:20 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:20 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:20 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 17:41:20 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:20 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:20 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:20 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:20 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:20 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:20 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:20 volumio-test-2 shairport-sync[2252]: {"time":1715161276930,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 08 17:41:20 volumio-test-2 shairport-sync[2252]: Dload Upload Total Spent Left Speed May 08 17:41:20 volumio-test-2 volumio[634]: info: May 08 17:41:20 volumio-test-2 volumio[634]: ---------------------------- Client requests Stop Airplay Playback May 08 17:41:20 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback May 08 17:41:20 volumio-test-2 volumio[634]: info: Airplay playback stop May 08 17:41:20 volumio-test-2 volumio[634]: info: Airplay Playback Stopped, clearing state May 08 17:41:20 volumio-test-2 volumio[634]: info: CoreStateMachine::resetVolumioState May 08 17:41:20 volumio-test-2 volumio[634]: info: CoreStateMachine::getcurrentVolume May 08 17:41:20 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioRetrievevolume May 08 17:41:20 volumio-test-2 shairport-sync[2252]: [158B blob data] May 08 17:41:20 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:20 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:46 volumio-test-2 volumio[634]: info: Airplay started streaming, receiving metadatas May 08 17:41:46 volumio-test-2 volumio[634]: info: Airplay started streaming, receiving metadatas May 08 17:41:46 volumio-test-2 shairport-sync[2252]: {"time":1715161280727,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 08 17:41:46 volumio-test-2 shairport-sync[2252]: Dload Upload Total Spent Left Speed May 08 17:41:46 volumio-test-2 volumio[634]: info: May 08 17:41:46 volumio-test-2 volumio[634]: ---------------------------- Client requests Start Airplay PlaybackRoutine May 08 17:41:46 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback May 08 17:41:46 volumio-test-2 volumio[634]: info: Airplay playback start May 08 17:41:46 volumio-test-2 shairport-sync[2252]: [158B blob data] May 08 17:41:47 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:47 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:47 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 17:41:47 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:47 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:47 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:47 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:47 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:47 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:47 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:47 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:47 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:47 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:48 volumio-test-2 volumio[634]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E9%9F%B6%E6%B6%B5/%E7%A0%B4%E8%8C%A7(%E3%80%8A%E6%96%97%E7%BD%97%E5%A4%A7%E9%99%86%E3%80%8B%E5%8A%A8%E7%94%BB2020%E5%B9%B4%E6%96%B0%E4%B8%BB%E9%A2%98%E6%9B%B2)/7adc1d92-4a59-4709-8e9f-04410cba144b.jpg' May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::servicePushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:48 volumio-test-2 shairport-sync[2252]: {"time":1715161306752,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 08 17:41:48 volumio-test-2 shairport-sync[2252]: Dload Upload Total Spent Left Speed May 08 17:41:48 volumio-test-2 volumio[634]: info: May 08 17:41:48 volumio-test-2 volumio[634]: ---------------------------- Client requests Stop Airplay Playback May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback May 08 17:41:48 volumio-test-2 volumio[634]: info: Airplay playback stop May 08 17:41:48 volumio-test-2 volumio[634]: info: Airplay Playback Stopped, clearing state May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreStateMachine::resetVolumioState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreStateMachine::getcurrentVolume May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioRetrievevolume May 08 17:41:48 volumio-test-2 shairport-sync[2252]: [158B blob data] May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreStateMachine::pushState May 08 17:41:48 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioPushState May 08 17:41:50 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 17:41:50 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 17:41:50 volumio-test-2 volumio[634]: info: Discovery: Getting this device information May 08 17:41:50 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioGetState May 08 17:41:50 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 17:41:51 volumio-test-2 volumio[634]: verbose: New Socket.io Connection to 192.168.2.52 from 192.168.2.20 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 5 May 08 17:41:53 volumio-test-2 sudo[2377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 17:41:53 volumio-test-2 sudo[2377]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 17:41:53 volumio-test-2 sudo[2377]: pam_unix(sudo:session): session closed for user root May 08 17:41:53 volumio-test-2 sudo[2379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 17:41:53 volumio-test-2 sudo[2379]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 17:41:53 volumio-test-2 sudo[2379]: pam_unix(sudo:session): session closed for user root May 08 17:41:53 volumio-test-2 volumio[634]: verbose: New Socket.io Connection to 192.168.2.52 from 192.168.2.20 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 6 May 08 17:41:53 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 08 17:41:53 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 08 17:41:53 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioGetVisibleSources May 08 17:41:53 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 17:41:53 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioGetState May 08 17:41:53 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 08 17:41:53 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 08 17:41:53 volumio-test-2 volumio[634]: info: Received Get System Info May 08 17:41:53 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 17:41:53 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 17:41:53 volumio-test-2 volumio[634]: info: Discovery: Getting this device information May 08 17:41:53 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioGetState May 08 17:41:53 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 17:41:53 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioGetState May 08 17:41:53 volumio-test-2 volumio[634]: info: Listing playlists May 08 17:41:53 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 08 17:41:53 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 08 17:41:53 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 08 17:41:55 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 17:41:55 volumio-test-2 volumio[634]: info: Received Get System Info May 08 17:41:55 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 17:41:55 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 17:41:55 volumio-test-2 volumio[634]: info: Discovery: Getting this device information May 08 17:41:55 volumio-test-2 volumio[634]: info: CoreCommandRouter::volumioGetState May 08 17:41:55 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 17:42:10 volumio-test-2 volumiologrotate[446]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 08 17:42:10 volumio-test-2 volumiologrotate[446]: ls: cannot access 'TEST': No such file or directory May 08 17:42:10 volumio-test-2 volumiologrotate[446]: ls: cannot access '2': No such file or directory May 08 17:42:16 volumio-test-2 volumio[634]: info: Airplay started streaming, receiving metadatas May 08 17:42:16 volumio-test-2 volumio[634]: info: Airplay started streaming, receiving metadatas May 08 17:42:16 volumio-test-2 volumio[634]: info: Airplay started streaming, receiving metadatas May 08 17:42:16 volumio-test-2 shairport-sync[2252]: {"time":1715161308974,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 08 17:42:16 volumio-test-2 shairport-sync[2252]: Dload Upload Total Spent Left Speed May 08 17:42:16 volumio-test-2 volumio[634]: info: May 08 17:42:16 volumio-test-2 volumio[634]: ---------------------------- Client requests Start Airplay PlaybackRoutine May 08 17:42:16 volumio-test-2 volumio[634]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback May 08 17:42:16 volumio-test-2 volumio[634]: info: Airplay playback start May 08 17:42:16 volumio-test-2 shairport-sync[2252]: [158B blob data] May 08 17:42:17 volumio-test-2 volumio[634]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 17:42:17 volumio-test-2 volumio[634]: Error: connect ETIMEDOUT 108.160.166.137:443 May 08 17:42:17 volumio-test-2 volumio[634]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 08 17:42:17 volumio-test-2 volumio[634]: errno: -110, May 08 17:42:17 volumio-test-2 volumio[634]: code: 'ETIMEDOUT', May 08 17:42:17 volumio-test-2 volumio[634]: syscall: 'connect', May 08 17:42:17 volumio-test-2 volumio[634]: address: '108.160.166.137', May 08 17:42:17 volumio-test-2 volumio[634]: port: 443 May 08 17:42:17 volumio-test-2 volumio[634]: } May 08 17:42:17 volumio-test-2 volumio[634]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 17:42:18 volumio-test-2 sudo[2447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-08 17:41 May 08 17:42:18 volumio-test-2 sudo[2447]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 24 Feb 2024 08:42:10 PM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_HASH="07dd34a1d12a1c065d46d56891837dee"