Dec 15 09:45:00 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:00 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:00 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:00 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:00 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:00 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:01 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:01 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:01 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:03 volumio4 dhcpcd[769]: eth0: requesting DHCPv6 information Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand update Dec 15 09:45:04 volumio4 volumio[1048]: info: sendMpdCommand update took 2 milliseconds Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:04 volumio4 volumio[1048]: info: Dec 15 09:45:04 volumio4 volumio[1048]: ---------------------------- MPD announces state update: update Dec 15 09:45:04 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:04 volumio4 volumio[1048]: info: Dec 15 09:45:04 volumio4 volumio[1048]: ---------------------------- MPD announces state update: update Dec 15 09:45:04 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:04 volumio4 volumio[1048]: info: Dec 15 09:45:04 volumio4 volumio[1048]: ---------------------------- MPD announces state update: update Dec 15 09:45:04 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:04 volumio4 volumio[1048]: info: Dec 15 09:45:04 volumio4 volumio[1048]: ---------------------------- MPD announces state update: update Dec 15 09:45:04 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:04 volumio4 volumio[1048]: info: sendMpdCommand status took 3 milliseconds Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:04 volumio4 volumio[1048]: info: Command Router : Notfying DB Updatetrue Dec 15 09:45:04 volumio4 volumio[1048]: info: sendMpdCommand status took 32 milliseconds Dec 15 09:45:04 volumio4 volumio[1048]: info: sendMpdCommand status took 32 milliseconds Dec 15 09:45:04 volumio4 volumio[1048]: info: sendMpdCommand status took 32 milliseconds Dec 15 09:45:04 volumio4 volumio[1048]: info: sendMpdCommand status took 32 milliseconds Dec 15 09:45:04 volumio4 volumio[1048]: info: sendMpdCommand status took 32 milliseconds Dec 15 09:45:04 volumio4 volumio[1048]: info: sendMpdCommand status took 32 milliseconds Dec 15 09:45:04 volumio4 volumio[1048]: info: sendMpdCommand status took 32 milliseconds Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:04 volumio4 volumio[1048]: info: Command Router : Notfying DB Updatetrue Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreCommandRouter::Close All Modals sent Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:04 volumio4 volumio[1048]: info: Command Router : Notfying DB Updatetrue Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreCommandRouter::Close All Modals sent Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:04 volumio4 volumio[1048]: info: Command Router : Notfying DB Updatetrue Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreCommandRouter::Close All Modals sent Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:04 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 59 milliseconds Dec 15 09:45:04 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 46 milliseconds Dec 15 09:45:04 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 29 milliseconds Dec 15 09:45:04 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:04 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:04 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:04 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:04 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17907,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track01","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:04 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:04 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:45:04 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:04 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:04 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17907,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track01","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:04 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:04 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:45:04 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:04 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:04 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17907,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track01","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:04 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:04 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:45:04 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:04 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:04 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17907,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track01","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:04 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:04 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:45:04 volumio4 volumio[1048]: info: ------------------------------ 113ms Dec 15 09:45:04 volumio4 volumio[1048]: info: ------------------------------ 111ms Dec 15 09:45:04 volumio4 volumio[1048]: info: ------------------------------ 110ms Dec 15 09:45:04 volumio4 volumio[1048]: info: ------------------------------ 111ms Dec 15 09:45:04 volumio4 volumio[1048]: info: CoreCommandRouter::volumioGetState Dec 15 09:45:04 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:05 volumio4 volumio[1048]: info: MPD Database updated - AlbumList cache refreshed Dec 15 09:45:05 volumio4 volumio[1048]: info: Dec 15 09:45:05 volumio4 volumio[1048]: ---------------------------- MPD announces state update: database Dec 15 09:45:05 volumio4 volumio[1048]: info: Dec 15 09:45:05 volumio4 volumio[1048]: ---------------------------- MPD announces system playlist update Dec 15 09:45:05 volumio4 volumio[1048]: info: Ignoring MPD Status Update Dec 15 09:45:05 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:05 volumio4 volumio[1048]: info: Dec 15 09:45:05 volumio4 volumio[1048]: ---------------------------- MPD announces state update: update Dec 15 09:45:05 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:05 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:05 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:05 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:05 volumio4 volumio[1048]: info: MPD Database updated - AlbumList cache refreshed Dec 15 09:45:05 volumio4 volumio[1048]: info: Dec 15 09:45:05 volumio4 volumio[1048]: ---------------------------- MPD announces state update: database Dec 15 09:45:05 volumio4 volumio[1048]: info: Dec 15 09:45:05 volumio4 volumio[1048]: ---------------------------- MPD announces system playlist update Dec 15 09:45:05 volumio4 volumio[1048]: info: Ignoring MPD Status Update Dec 15 09:45:05 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:05 volumio4 volumio[1048]: info: Dec 15 09:45:05 volumio4 volumio[1048]: ---------------------------- MPD announces state update: update Dec 15 09:45:05 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:05 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:05 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:05 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:05 volumio4 volumio[1048]: info: MPD Database updated - AlbumList cache refreshed Dec 15 09:45:05 volumio4 volumio[1048]: info: Dec 15 09:45:05 volumio4 volumio[1048]: ---------------------------- MPD announces state update: database Dec 15 09:45:05 volumio4 volumio[1048]: info: Dec 15 09:45:05 volumio4 volumio[1048]: ---------------------------- MPD announces system playlist update Dec 15 09:45:05 volumio4 volumio[1048]: info: Ignoring MPD Status Update Dec 15 09:45:05 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:05 volumio4 volumio[1048]: info: Dec 15 09:45:05 volumio4 volumio[1048]: ---------------------------- MPD announces state update: update Dec 15 09:45:05 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:05 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:05 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:05 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:05 volumio4 volumio[1048]: info: MPD Database updated - AlbumList cache refreshed Dec 15 09:45:05 volumio4 volumio[1048]: info: Dec 15 09:45:05 volumio4 volumio[1048]: ---------------------------- MPD announces state update: database Dec 15 09:45:05 volumio4 volumio[1048]: info: Dec 15 09:45:05 volumio4 volumio[1048]: ---------------------------- MPD announces system playlist update Dec 15 09:45:05 volumio4 volumio[1048]: info: Ignoring MPD Status Update Dec 15 09:45:05 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:05 volumio4 volumio[1048]: info: Dec 15 09:45:05 volumio4 volumio[1048]: ---------------------------- MPD announces state update: update Dec 15 09:45:05 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:05 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:05 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:05 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:07 volumio4 volumio[1048]: info: sendMpdCommand status took 2268 milliseconds Dec 15 09:45:07 volumio4 volumio[1048]: info: sendMpdCommand status took 2268 milliseconds Dec 15 09:45:07 volumio4 volumio[1048]: info: ------------------------------ 2269ms Dec 15 09:45:07 volumio4 volumio[1048]: info: sendMpdCommand status took 2268 milliseconds Dec 15 09:45:07 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:07 volumio4 volumio[1048]: info: Command Router : Notfying DB Updatefalse Dec 15 09:45:07 volumio4 volumio[1048]: info: CoreCommandRouter::Close All Modals sent Dec 15 09:45:07 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:07 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:07 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:07 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:08 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:08 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:08 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:08 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:08 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:08 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:08 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:08 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:08 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:08 volumio4 volumio[1048]: info: sendMpdCommand status took 3231 milliseconds Dec 15 09:45:08 volumio4 volumio[1048]: info: sendMpdCommand status took 3232 milliseconds Dec 15 09:45:08 volumio4 volumio[1048]: info: ------------------------------ 3233ms Dec 15 09:45:08 volumio4 volumio[1048]: info: sendMpdCommand status took 3233 milliseconds Dec 15 09:45:08 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:08 volumio4 volumio[1048]: info: Command Router : Notfying DB Updatefalse Dec 15 09:45:08 volumio4 volumio[1048]: info: CoreCommandRouter::Close All Modals sent Dec 15 09:45:08 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:08 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:08 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:08 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:09 volumio4 volumio[1048]: info: sendMpdCommand status took 4235 milliseconds Dec 15 09:45:09 volumio4 volumio[1048]: info: sendMpdCommand status took 4235 milliseconds Dec 15 09:45:09 volumio4 volumio[1048]: info: ------------------------------ 4236ms Dec 15 09:45:09 volumio4 volumio[1048]: info: sendMpdCommand status took 4235 milliseconds Dec 15 09:45:09 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:09 volumio4 volumio[1048]: info: Command Router : Notfying DB Updatefalse Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreCommandRouter::Close All Modals sent Dec 15 09:45:09 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:09 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:09 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:09 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:09 volumio4 volumio[1048]: info: Preload queue cleared Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreStateMachine::ClearQueue Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreStateMachine::stop Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreStateMachine::updateTrackBlock Dec 15 09:45:09 volumio4 volumio[1048]: info: CorePlayQueue::getTrackBlock Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:09 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreStateMachine::serviceStop Dec 15 09:45:09 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreCommandRouter::serviceStop Dec 15 09:45:09 volumio4 volumio[1048]: info: ControllerMpd::stop Dec 15 09:45:09 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Dec 15 09:45:09 volumio4 volumio[1048]: info: CorePlayQueue::clearPlayQueue Dec 15 09:45:09 volumio4 volumio[1048]: info: CorePlayQueue::saveQueue Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushQueue Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreStateMachine::addQueueItems Dec 15 09:45:09 volumio4 volumio[1048]: info: CorePlayQueue::addQueueItems Dec 15 09:45:09 volumio4 volumio[1048]: info: Preload queue cleared Dec 15 09:45:09 volumio4 volumio[1048]: info: Adding Item to queue: music-library/NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》 Dec 15 09:45:09 volumio4 volumio[1048]: info: Using cached record of: music-library/NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》 Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushQueue Dec 15 09:45:09 volumio4 volumio[1048]: info: CorePlayQueue::saveQueue Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreStateMachine::updateTrackBlock Dec 15 09:45:09 volumio4 volumio[1048]: info: CorePlayQueue::getTrackBlock Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPlay Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreStateMachine::play index 0 Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreStateMachine::stop Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreStateMachine::play index undefined Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 09:45:09 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreStateMachine::startPlaybackTimer Dec 15 09:45:09 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:09 volumio4 volumio[1048]: verbose: ControllerMpd::clearAddPlayTracks NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape Dec 15 09:45:09 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Dec 15 09:45:09 volumio4 volumio[1048]: info: Dec 15 09:45:09 volumio4 volumio[1048]: ---------------------------- MPD announces state update: player Dec 15 09:45:09 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:09 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:09 volumio4 volumio[1048]: info: Dec 15 09:45:09 volumio4 volumio[1048]: ---------------------------- MPD announces state update: player Dec 15 09:45:09 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:09 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:09 volumio4 volumio[1048]: info: Dec 15 09:45:09 volumio4 volumio[1048]: ---------------------------- MPD announces state update: player Dec 15 09:45:09 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:09 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:09 volumio4 volumio[1048]: info: CoreCommandRouter::volumioGetState Dec 15 09:45:09 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand status took 5012 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand status took 5012 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 5012ms Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand status took 5012 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:10 volumio4 volumio[1048]: info: Command Router : Notfying DB Updatefalse Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::Close All Modals sent Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 2759 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 2759 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17907,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:10 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17907,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:10 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 5056ms Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 5056ms Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 1819 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 1820 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 825 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 825 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17907,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:10 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17907,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:10 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17907,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:10 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17907,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:10 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 5098ms Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 5098ms Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 5097ms Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 5097ms Dec 15 09:45:10 volumio4 volumio[1048]: info: Dec 15 09:45:10 volumio4 volumio[1048]: ---------------------------- MPD announces state update: player Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand stop took 828 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand stop took 807 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand status took 741 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand status took 738 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand status took 737 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 80 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 80 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand clear Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17907,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:10 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17907,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:10 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 5120ms Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 5120ms Dec 15 09:45:10 volumio4 volumio[1048]: info: Dec 15 09:45:10 volumio4 volumio[1048]: ---------------------------- MPD announces system playlist update Dec 15 09:45:10 volumio4 volumio[1048]: info: Ignoring MPD Status Update Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand status took 9 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand clear took 8 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand add "NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape" Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:10 volumio4 volumio[1048]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 15 09:45:10 volumio4 volumio[1048]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 15 09:45:10 volumio4 volumio[1048]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 752ms Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 751ms Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 747ms Dec 15 09:45:10 volumio4 volumio[1048]: info: Dec 15 09:45:10 volumio4 volumio[1048]: ---------------------------- MPD announces system playlist update Dec 15 09:45:10 volumio4 volumio[1048]: info: Ignoring MPD Status Update Dec 15 09:45:10 volumio4 volumio[1048]: info: Dec 15 09:45:10 volumio4 volumio[1048]: ---------------------------- MPD announces system playlist update Dec 15 09:45:10 volumio4 volumio[1048]: info: Ignoring MPD Status Update Dec 15 09:45:10 volumio4 volumio[1048]: info: Dec 15 09:45:10 volumio4 volumio[1048]: ---------------------------- MPD announces system playlist update Dec 15 09:45:10 volumio4 volumio[1048]: info: Ignoring MPD Status Update Dec 15 09:45:10 volumio4 volumio[1048]: error: updateQueue error: null Dec 15 09:45:10 volumio4 volumio[1048]: info: Dec 15 09:45:10 volumio4 volumio[1048]: ---------------------------- MPD announces system playlist update Dec 15 09:45:10 volumio4 volumio[1048]: info: Ignoring MPD Status Update Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 10ms Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand add "NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape" took 10 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 2ms Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 2ms Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 1ms Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand play Dec 15 09:45:10 volumio4 volumio[1048]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 20ms Dec 15 09:45:10 volumio4 volumio[1048]: info: Dec 15 09:45:10 volumio4 volumio[1048]: ---------------------------- MPD announces system playlist update Dec 15 09:45:10 volumio4 volumio[1048]: info: Ignoring MPD Status Update Dec 15 09:45:10 volumio4 volumio[1048]: info: Dec 15 09:45:10 volumio4 volumio[1048]: ---------------------------- MPD announces system playlist update Dec 15 09:45:10 volumio4 volumio[1048]: info: Ignoring MPD Status Update Dec 15 09:45:10 volumio4 volumio[1048]: info: Dec 15 09:45:10 volumio4 volumio[1048]: ---------------------------- MPD announces system playlist update Dec 15 09:45:10 volumio4 volumio[1048]: info: Ignoring MPD Status Update Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 4ms Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand play took 3 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 2ms Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 2ms Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 2ms Dec 15 09:45:10 volumio4 volumio[1048]: info: Dec 15 09:45:10 volumio4 volumio[1048]: ---------------------------- MPD announces state update: player Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:10 volumio4 volumio[1048]: info: Dec 15 09:45:10 volumio4 volumio[1048]: ---------------------------- MPD announces state update: player Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:10 volumio4 volumio[1048]: info: Dec 15 09:45:10 volumio4 volumio[1048]: ---------------------------- MPD announces state update: player Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:10 volumio4 volumio[1048]: info: Dec 15 09:45:10 volumio4 volumio[1048]: ---------------------------- MPD announces state update: player Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand status took 290 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand status took 290 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand status took 289 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand status took 289 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:10 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":387,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:10 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService play Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":387,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:10 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService play Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus play Dec 15 09:45:10 volumio4 volumio[1048]: info: Received an update from plugin. extracting info from payload Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":387,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:10 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService play Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus play Dec 15 09:45:10 volumio4 volumio[1048]: info: Received an update from plugin. extracting info from payload Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:10 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":387,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:10 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService play Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus play Dec 15 09:45:10 volumio4 volumio[1048]: info: Received an update from plugin. extracting info from payload Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:10 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:10 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 332ms Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 357ms Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 358ms Dec 15 09:45:10 volumio4 volumio[1048]: info: ------------------------------ 357ms Dec 15 09:45:13 volumio4 dhcpcd[769]: eth0: requesting DHCPv6 information Dec 15 09:45:14 volumio4 volumio[1048]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 15 09:45:14 volumio4 volumio[1048]: info: Retrying Login Due to Network Error Dec 15 09:45:15 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:15 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:15 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:15 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:15 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:15 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:15 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:15 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:15 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPause Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::pause Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::servicePause Dec 15 09:45:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreCommandRouter::servicePause Dec 15 09:45:19 volumio4 volumio[1048]: info: ControllerMpd::pause Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand pause Dec 15 09:45:19 volumio4 volumio[1048]: info: Dec 15 09:45:19 volumio4 volumio[1048]: ---------------------------- MPD announces state update: player Dec 15 09:45:19 volumio4 volumio[1048]: info: sendMpdCommand pause took 2 milliseconds Dec 15 09:45:19 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:19 volumio4 volumio[1048]: info: Dec 15 09:45:19 volumio4 volumio[1048]: ---------------------------- MPD announces state update: player Dec 15 09:45:19 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:19 volumio4 volumio[1048]: info: Dec 15 09:45:19 volumio4 volumio[1048]: ---------------------------- MPD announces state update: player Dec 15 09:45:19 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:19 volumio4 volumio[1048]: info: Dec 15 09:45:19 volumio4 volumio[1048]: ---------------------------- MPD announces state update: player Dec 15 09:45:19 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:45:19 volumio4 volumio[1048]: info: sendMpdCommand status took 3 milliseconds Dec 15 09:45:19 volumio4 volumio[1048]: info: sendMpdCommand status took 3 milliseconds Dec 15 09:45:19 volumio4 volumio[1048]: info: sendMpdCommand status took 2 milliseconds Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:19 volumio4 volumio[1048]: info: sendMpdCommand status took 2 milliseconds Dec 15 09:45:19 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 15 09:45:19 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 15 09:45:19 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:19 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:19 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9375,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:19 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:45:19 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:19 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9375,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:19 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:45:19 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:19 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9375,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:19 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:45:19 volumio4 volumio[1048]: info: ------------------------------ 21ms Dec 15 09:45:19 volumio4 volumio[1048]: info: ------------------------------ 22ms Dec 15 09:45:19 volumio4 volumio[1048]: info: ------------------------------ 22ms Dec 15 09:45:19 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 15 09:45:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:45:19 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:45:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:19 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9375,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:45:19 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:45:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:45:19 volumio4 volumio[1048]: info: ------------------------------ 27ms Dec 15 09:45:19 volumio4 volumio[1048]: info: CoreCommandRouter::volumioGetState Dec 15 09:45:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:21 volumio4 volumio[1048]: info: CoreCommandRouter::volumioGetState Dec 15 09:45:21 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:23 volumio4 dhcpcd[769]: eth0: requesting DHCPv6 information Dec 15 09:45:23 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:23 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:23 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:23 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:23 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:23 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:23 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:23 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:23 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:24 volumio4 volumio[1048]: info: MyVolumio login type: Token Dec 15 09:45:26 volumio4 volumio[1048]: (node:1048) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Dec 15 09:45:30 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:30 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:30 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:30 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:30 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:30 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:30 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:30 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:30 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:33 volumio4 dhcpcd[769]: eth0: requesting DHCPv6 information Dec 15 09:45:33 volumio4 volumio[1048]: info: CoreCommandRouter::volumioGetState Dec 15 09:45:33 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:34 volumio4 volumio[1048]: info: CoreCommandRouter::volumioGetState Dec 15 09:45:34 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:39 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:39 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:39 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:39 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:39 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:39 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:39 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:39 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:39 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:43 volumio4 dhcpcd[769]: eth0: requesting DHCPv6 information Dec 15 09:45:45 volumio4 volumio[1048]: info: CoreCommandRouter::volumioGetState Dec 15 09:45:45 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:46 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:46 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:46 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:46 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:46 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:46 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:46 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:46 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:46 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:50 volumio4 volumio[1048]: info: CoreCommandRouter::volumioGetState Dec 15 09:45:50 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:53 volumio4 dhcpcd[769]: eth0: requesting DHCPv6 information Dec 15 09:45:53 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:53 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:53 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:53 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:53 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:53 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:53 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:53 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:45:53 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:45:54 volumio4 volumio[1048]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 15 09:45:54 volumio4 volumio[1048]: info: Retrying Login Due to Network Error Dec 15 09:45:57 volumio4 volumio[1048]: info: CoreCommandRouter::volumioGetState Dec 15 09:45:57 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:45:58 volumio4 ntpd[1272]: PROTO: 193.182.111.14 unlink local addr 192.168.2.87 -> Dec 15 09:46:00 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:00 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:46:00 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:00 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:00 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:46:00 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:00 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:00 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:46:00 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:03 volumio4 dhcpcd[769]: eth0: requesting DHCPv6 information Dec 15 09:46:04 volumio4 volumio[1048]: info: MyVolumio login type: Token Dec 15 09:46:05 volumio4 volumio[1048]: info: CoreCommandRouter::volumioGetState Dec 15 09:46:05 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:06 volumio4 volumio[1048]: (node:1048) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Dec 15 09:46:09 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:09 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:46:09 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:09 volumio4 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 15 09:46:09 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:09 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:46:09 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:09 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:09 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:46:09 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:09 volumio4 volumio[1048]: info: CoreCommandRouter::volumioGetState Dec 15 09:46:09 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:13 volumio4 dhcpcd[769]: eth0: requesting DHCPv6 information Dec 15 09:46:16 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:16 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:46:16 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:16 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:16 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:46:16 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:16 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:16 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:46:16 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand update Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:19 volumio4 volumio[1048]: info: Dec 15 09:46:19 volumio4 volumio[1048]: ---------------------------- MPD announces state update: update Dec 15 09:46:19 volumio4 volumio[1048]: info: sendMpdCommand update took 3 milliseconds Dec 15 09:46:19 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:19 volumio4 volumio[1048]: info: Dec 15 09:46:19 volumio4 volumio[1048]: ---------------------------- MPD announces state update: update Dec 15 09:46:19 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:19 volumio4 volumio[1048]: info: Dec 15 09:46:19 volumio4 volumio[1048]: ---------------------------- MPD announces state update: update Dec 15 09:46:19 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:19 volumio4 volumio[1048]: info: Dec 15 09:46:19 volumio4 volumio[1048]: ---------------------------- MPD announces state update: update Dec 15 09:46:19 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:19 volumio4 volumio[1048]: info: sendMpdCommand status took 6 milliseconds Dec 15 09:46:19 volumio4 volumio[1048]: info: sendMpdCommand status took 5 milliseconds Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:19 volumio4 volumio[1048]: info: Command Router : Notfying DB Updatetrue Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreCommandRouter::Close All Modals sent Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:46:19 volumio4 volumio[1048]: info: sendMpdCommand status took 40 milliseconds Dec 15 09:46:19 volumio4 volumio[1048]: info: sendMpdCommand status took 41 milliseconds Dec 15 09:46:19 volumio4 volumio[1048]: info: sendMpdCommand status took 41 milliseconds Dec 15 09:46:19 volumio4 volumio[1048]: info: sendMpdCommand status took 42 milliseconds Dec 15 09:46:19 volumio4 volumio[1048]: info: sendMpdCommand status took 41 milliseconds Dec 15 09:46:19 volumio4 volumio[1048]: info: sendMpdCommand status took 42 milliseconds Dec 15 09:46:19 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:19 volumio4 volumio[1048]: info: Command Router : Notfying DB Updatetrue Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreCommandRouter::Close All Modals sent Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:19 volumio4 volumio[1048]: info: Command Router : Notfying DB Updatetrue Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreCommandRouter::Close All Modals sent Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:19 volumio4 volumio[1048]: info: Command Router : Notfying DB Updatetrue Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreCommandRouter::Close All Modals sent Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:46:19 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:46:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:19 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9375,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:46:19 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:46:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:46:19 volumio4 volumio[1048]: info: ------------------------------ 113ms Dec 15 09:46:19 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 59 milliseconds Dec 15 09:46:19 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 42 milliseconds Dec 15 09:46:19 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 29 milliseconds Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:46:19 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:46:19 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:46:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:19 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9375,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:46:19 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:46:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:46:19 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:46:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:19 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9375,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:46:19 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:46:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:46:19 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:46:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:19 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9375,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:46:19 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:46:19 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:46:19 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:46:19 volumio4 volumio[1048]: info: ------------------------------ 142ms Dec 15 09:46:19 volumio4 volumio[1048]: info: ------------------------------ 143ms Dec 15 09:46:19 volumio4 volumio[1048]: info: ------------------------------ 143ms Dec 15 09:46:20 volumio4 volumio[1048]: info: CoreCommandRouter::volumioGetState Dec 15 09:46:20 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:20 volumio4 clearscr.sh[8000]: 7896 Dec 15 09:46:20 volumio4 systemd[1]: Stopping screensaver.service - My Shell Script... Dec 15 09:46:20 volumio4 systemd[1]: screensaver.service: Deactivated successfully. Dec 15 09:46:20 volumio4 systemd[1]: Stopped screensaver.service - My Shell Script. Dec 15 09:46:20 volumio4 systemd[1]: screensaver.service: Consumed 7.044s CPU time. Dec 15 09:46:20 volumio4 systemd[1]: Started screensaver.service - My Shell Script. Dec 15 09:46:20 volumio4 volumio[1048]: info: MPD Database updated - AlbumList cache refreshed Dec 15 09:46:20 volumio4 volumio[1048]: info: Dec 15 09:46:20 volumio4 volumio[1048]: ---------------------------- MPD announces state update: database Dec 15 09:46:20 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:20 volumio4 volumio[1048]: info: Dec 15 09:46:20 volumio4 volumio[1048]: ---------------------------- MPD announces state update: update Dec 15 09:46:20 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:46:20 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:20 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:46:20 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:20 volumio4 volumio[1048]: info: MPD Database updated - AlbumList cache refreshed Dec 15 09:46:20 volumio4 volumio[1048]: info: Dec 15 09:46:20 volumio4 volumio[1048]: ---------------------------- MPD announces state update: database Dec 15 09:46:20 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:20 volumio4 volumio[1048]: info: Dec 15 09:46:20 volumio4 volumio[1048]: ---------------------------- MPD announces state update: update Dec 15 09:46:20 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:46:20 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:20 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:46:20 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:20 volumio4 volumio[1048]: info: MPD Database updated - AlbumList cache refreshed Dec 15 09:46:20 volumio4 volumio[1048]: info: Dec 15 09:46:20 volumio4 volumio[1048]: ---------------------------- MPD announces state update: database Dec 15 09:46:20 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:20 volumio4 volumio[1048]: info: Dec 15 09:46:20 volumio4 volumio[1048]: ---------------------------- MPD announces state update: update Dec 15 09:46:20 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:46:20 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:20 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:46:20 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:20 volumio4 volumio[1048]: info: MPD Database updated - AlbumList cache refreshed Dec 15 09:46:20 volumio4 volumio[1048]: info: Dec 15 09:46:20 volumio4 volumio[1048]: ---------------------------- MPD announces state update: database Dec 15 09:46:20 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:20 volumio4 volumio[1048]: info: Dec 15 09:46:20 volumio4 volumio[1048]: ---------------------------- MPD announces state update: update Dec 15 09:46:20 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:46:20 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:20 volumio4 volumio[1048]: info: ControllerMpd::getState Dec 15 09:46:20 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Dec 15 09:46:21 volumio4 volumio[1048]: info: sendMpdCommand status took 1021 milliseconds Dec 15 09:46:21 volumio4 volumio[1048]: info: sendMpdCommand status took 1022 milliseconds Dec 15 09:46:21 volumio4 volumio[1048]: info: sendMpdCommand status took 1022 milliseconds Dec 15 09:46:21 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:21 volumio4 volumio[1048]: info: Command Router : Notfying DB Updatefalse Dec 15 09:46:21 volumio4 volumio[1048]: info: CoreCommandRouter::Close All Modals sent Dec 15 09:46:21 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:21 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:46:21 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:21 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:46:22 volumio4 volumio[1048]: info: sendMpdCommand status took 1722 milliseconds Dec 15 09:46:22 volumio4 volumio[1048]: info: sendMpdCommand status took 1722 milliseconds Dec 15 09:46:22 volumio4 volumio[1048]: info: sendMpdCommand status took 1721 milliseconds Dec 15 09:46:22 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:22 volumio4 volumio[1048]: info: Command Router : Notfying DB Updatefalse Dec 15 09:46:22 volumio4 volumio[1048]: info: CoreCommandRouter::Close All Modals sent Dec 15 09:46:22 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:22 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:46:22 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:22 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:46:23 volumio4 dhcpcd[769]: eth0: requesting DHCPv6 information Dec 15 09:46:23 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:23 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:46:23 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:23 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:23 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:46:23 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:23 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:23 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 15 09:46:23 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 15 09:46:23 volumio4 volumio[1048]: info: sendMpdCommand status took 3005 milliseconds Dec 15 09:46:23 volumio4 volumio[1048]: info: sendMpdCommand status took 3006 milliseconds Dec 15 09:46:23 volumio4 volumio[1048]: info: sendMpdCommand status took 3006 milliseconds Dec 15 09:46:23 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:23 volumio4 volumio[1048]: info: Command Router : Notfying DB Updatefalse Dec 15 09:46:23 volumio4 volumio[1048]: info: CoreCommandRouter::Close All Modals sent Dec 15 09:46:23 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:23 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:46:23 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:23 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:46:24 volumio4 systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 15 09:46:24 volumio4 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 15 09:46:24 volumio4 volumio[1048]: info: sendMpdCommand status took 3953 milliseconds Dec 15 09:46:24 volumio4 volumio[1048]: info: sendMpdCommand status took 3951 milliseconds Dec 15 09:46:24 volumio4 volumio[1048]: info: sendMpdCommand status took 3950 milliseconds Dec 15 09:46:24 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:24 volumio4 volumio[1048]: info: Command Router : Notfying DB Updatefalse Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::Close All Modals sent Dec 15 09:46:24 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:24 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:46:24 volumio4 volumio[1048]: verbose: ControllerMpd::parseState Dec 15 09:46:24 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 09:46:24 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 2946 milliseconds Dec 15 09:46:24 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 2946 milliseconds Dec 15 09:46:24 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:46:24 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:46:24 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:24 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9375,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:46:24 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:46:24 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:24 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9375,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:46:24 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:46:24 volumio4 volumio[1048]: info: ------------------------------ 4009ms Dec 15 09:46:24 volumio4 volumio[1048]: info: ------------------------------ 4009ms Dec 15 09:46:24 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 2279 milliseconds Dec 15 09:46:24 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 2281 milliseconds Dec 15 09:46:24 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:46:24 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:46:24 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:24 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9375,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:46:24 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:46:24 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:24 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9375,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:46:24 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:46:24 volumio4 volumio[1048]: info: ------------------------------ 4036ms Dec 15 09:46:24 volumio4 volumio[1048]: info: ------------------------------ 4036ms Dec 15 09:46:24 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 1020 milliseconds Dec 15 09:46:24 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 1022 milliseconds Dec 15 09:46:24 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:46:24 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:46:24 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:24 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9375,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:46:24 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:46:24 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:24 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9375,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:46:24 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:46:24 volumio4 volumio[1048]: info: ------------------------------ 4065ms Dec 15 09:46:24 volumio4 volumio[1048]: info: ------------------------------ 4071ms Dec 15 09:46:24 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 101 milliseconds Dec 15 09:46:24 volumio4 volumio[1048]: info: sendMpdCommand playlistinfo took 101 milliseconds Dec 15 09:46:24 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:46:24 volumio4 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Dec 15 09:46:24 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:24 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9375,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:46:24 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:46:24 volumio4 volumio[1048]: info: ControllerMpd::pushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::servicePushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:24 volumio4 volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9375,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Partitas, excerpt: BWV 1006, Prellidio","artist":"VA","album":"TACET AUDIOPHILE SAMPLER","uri":"NAS/MusicBox/11TACET 发烧合集/TACET-AUDIOPHILE SAMPLER《试音宝鉴》/01. Partitas, excerpt BWV 1006, Prellidio.ape","trackType":"ape"} Dec 15 09:46:24 volumio4 volumio[1048]: verbose: CURRENT POSITION 0 Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::syncState stateService pause Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:46:24 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:46:24 volumio4 volumio[1048]: info: ------------------------------ 4112ms Dec 15 09:46:24 volumio4 volumio[1048]: info: ------------------------------ 4112ms Dec 15 09:46:27 volumio4 volumio[1048]: info: Preload queue cleared Dec 15 09:46:27 volumio4 volumio[1048]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 15 09:46:27 volumio4 volumio[1048]: info: CoreStateMachine::ClearQueue Dec 15 09:46:27 volumio4 volumio[1048]: info: CoreStateMachine::stop Dec 15 09:46:27 volumio4 volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 09:46:27 volumio4 volumio[1048]: info: CoreStateMachine::updateTrackBlock Dec 15 09:46:27 volumio4 volumio[1048]: info: CorePlayQueue::getTrackBlock Dec 15 09:46:27 volumio4 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Dec 15 09:46:27 volumio4 volumio[1048]: info: CoreStateMachine::pushState Dec 15 09:46:27 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:27 volumio4 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 09:46:27 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushState Dec 15 09:46:27 volumio4 volumio[1048]: info: CoreStateMachine::serviceStop Dec 15 09:46:27 volumio4 volumio[1048]: info: CorePlayQueue::getTrack 0 Dec 15 09:46:27 volumio4 volumio[1048]: info: CoreCommandRouter::serviceStop Dec 15 09:46:27 volumio4 volumio[1048]: info: ControllerMpd::stop Dec 15 09:46:27 volumio4 volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Dec 15 09:46:27 volumio4 volumio[1048]: info: CorePlayQueue::clearPlayQueue Dec 15 09:46:27 volumio4 volumio[1048]: info: CorePlayQueue::saveQueue Dec 15 09:46:27 volumio4 volumio[1048]: info: CoreCommandRouter::volumioPushQueue Dec 15 09:46:27 volumio4 volumio[1048]: info: CoreStateMachine::addQueueItems Dec 15 09:46:27 volumio4 volumio[1048]: info: CorePlayQueue::addQueueItems Dec 15 09:46:27 volumio4 volumio[1048]: info: Preload queue cleared Dec 15 09:46:27 volumio4 volumio[1048]: info: Adding Item to queue: music-library/NAS/MusicBox/11TACET 发烧合集 Dec 15 09:46:27 volumio4 volumio[1048]: info: Exploding uri music-library/NAS/MusicBox/11TACET 发烧合集 in service mpd Dec 15 09:46:27 volumio4 volumio[1048]: info: ALBUMART /albumart?cacheid=216&web=//large&path=%2Fmnt%2FNAS%2FMusicBox%2F11TACET%20%E5%8F%91%E7%83%A7%E5%90%88%E9%9B%86%2FTACET-006%20Mozart%20-%20Tre%20Sonate%20-gerrit%20zitterbart(piano)%2FWolfgang%20Amadeus%20Mozart%20-%20Tre%20Sonate.ape&metadata=false Dec 15 09:46:27 volumio4 volumio[1048]: info: URI /mnt/NAS/MusicBox/11TACET 发烧合集/TACET-006 Mozart - Tre Sonate -gerrit zitterbart(piano)/Wolfgang Amadeus Mozart - Tre Sonate.ape Dec 15 09:46:27 volumio4 volumio[1048]: info: ALBUMART /albumart?cacheid=216&web=Wolfgang%20Amadeus%20Mozart/Tre%20Sonate/large&path=%2Fmnt%2FNAS%2FMusicBox%2F11TACET%20%E5%8F%91%E7%83%A7%E5%90%88%E9%9B%86%2FTACET-006%20Mozart%20-%20Tre%20Sonate%20-gerrit%20zitterbart(piano)%2FWolfgang%20Amadeus%20Mozart%20-%20Tre%20Sonate.cue&metadata=false Dec 15 09:46:27 volumio4 volumio[1048]: info: URI /mnt/NAS/MusicBox/11TACET 发烧合集/TACET-006 Mozart - Tre Sonate -gerrit zitterbart(piano)/Wolfgang Amadeus Mozart - Tre Sonate.cue Dec 15 09:46:27 volumio4 volumio[1048]: info: ALBUMART /albumart?cacheid=216&web=Wolfgang%20Amadeus%20Mozart/Tre%20Sonate/large&path=%2Fmnt%2FNAS%2FMusicBox%2F11TACET%20%E5%8F%91%E7%83%A7%E5%90%88%E9%9B%86%2FTACET-006%20Mozart%20-%20Tre%20Sonate%20-gerrit%20zitterbart(piano)%2FWolfgang%20Amadeus%20Mozart%20-%20Tre%20Sonate.cue&metadata=false Dec 15 09:46:27 volumio4 volumio[1048]: info: URI /mnt/NAS/MusicBox/11TACET 发烧合集/TACET-006 Mozart - Tre Sonate -gerrit zitterbart(piano)/Wolfgang Amadeus Mozart - Tre Sonate.cue Dec 15 09:46:27 volumio4 volumio[1048]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 09:46:27 volumio4 volumio[1048]: Error: Unable to resolve or reject the same promise twice Dec 15 09:46:27 volumio4 volumio[1048]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 15 09:46:27 volumio4 volumio[1048]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 15 09:46:27 volumio4 volumio[1048]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 15 09:46:27 volumio4 volumio[1048]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 15 09:46:27 volumio4 volumio[1048]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 15 09:46:27 volumio4 volumio[1048]: at Socket.emit (node:events:514:28) Dec 15 09:46:27 volumio4 volumio[1048]: at addChunk (node:internal/streams/readable:343:12) Dec 15 09:46:27 volumio4 volumio[1048]: at readableAddChunk (node:internal/streams/readable:312:11) Dec 15 09:46:27 volumio4 volumio[1048]: at Readable.push (node:internal/streams/readable:253:10) Dec 15 09:46:27 volumio4 volumio[1048]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 15 09:46:27 volumio4 volumio[1048]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 09:46:27 volumio4 sudo[8133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-15 09:45' Dec 15 09:46:27 volumio4 sudo[8133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"