-- Logs begin at Thu 2025-05-15 14:11:56 MSK, end at Thu 2025-05-15 14:14:51 MSK. -- May 15 14:13:01 volumio volumio[1342]: info: CoreCommandRouter::volumioPlay May 15 14:13:01 volumio volumio[1342]: info: CoreStateMachine::play index undefined May 15 14:13:01 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:13:01 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 May 15 14:13:01 volumio volumio[1342]: info: CoreStateMachine::startPlaybackTimer May 15 14:13:01 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 May 15 14:13:01 volumio volumio[1342]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/01 - So far away.dsf May 15 14:13:01 volumio volumio[1342]: info: Setting Volume to 100 automatically for DSD May 15 14:13:01 volumio volumio[1342]: info: VolumeController::SetAlsaVolume100 May 15 14:13:01 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand stop May 15 14:13:01 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:13:01 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 May 15 14:13:01 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:13:01 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:13:01 volumio volumio[1342]: info: sendMpdCommand stop took 7 milliseconds May 15 14:13:01 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand clear May 15 14:13:01 volumio volumio[1342]: info: May 15 14:13:01 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:01 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:01 volumio volumio[1342]: info: sendMpdCommand clear took 1 milliseconds May 15 14:13:01 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/01 - So far away.dsf" May 15 14:13:01 volumio volumio[1342]: info: May 15 14:13:01 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:01 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:01 volumio volumio[1342]: info: May 15 14:13:01 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:01 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:01 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:01 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:01 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:01 volumio volumio[1342]: info: ------------------------------ 3ms May 15 14:13:01 volumio volumio[1342]: info: ------------------------------ 2ms May 15 14:13:01 volumio volumio[1342]: info: ------------------------------ 1ms May 15 14:13:02 volumio volumio[1342]: info: CoreCommandRouter::volumioNext May 15 14:13:02 volumio volumio[1342]: info: CoreStateMachine::next May 15 14:13:02 volumio volumio[1342]: info: CoreStateMachine::stop May 15 14:13:02 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:13:02 volumio volumio[1342]: info: CoreStateMachine::play index undefined May 15 14:13:02 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:13:02 volumio volumio[1342]: info: CorePlayQueue::getTrack 1 May 15 14:13:02 volumio volumio[1342]: info: CoreStateMachine::startPlaybackTimer May 15 14:13:02 volumio volumio[1342]: info: CorePlayQueue::getTrack 1 May 15 14:13:02 volumio volumio[1342]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/02 - Money for nothing.dsf May 15 14:13:02 volumio volumio[1342]: info: Setting Volume to 100 automatically for DSD May 15 14:13:02 volumio volumio[1342]: info: VolumeController::SetAlsaVolume100 May 15 14:13:02 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand stop May 15 14:13:02 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:13:02 volumio volumio[1342]: info: CorePlayQueue::getTrack 1 May 15 14:13:02 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 14:13:02 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:13:02 volumio volumio[1342]: info: CoreStateMachine::updateTrackBlock May 15 14:13:02 volumio volumio[1342]: info: CorePlayQueue::getTrackBlock May 15 14:13:02 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:13:02 volumio volumio[1342]: info: sendMpdCommand stop took 7 milliseconds May 15 14:13:02 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand clear May 15 14:13:02 volumio volumio[1342]: info: May 15 14:13:02 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:02 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:02 volumio volumio[1342]: info: sendMpdCommand clear took 0 milliseconds May 15 14:13:02 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/02 - Money for nothing.dsf" May 15 14:13:02 volumio volumio[1342]: info: May 15 14:13:02 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:02 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:02 volumio volumio[1342]: info: May 15 14:13:02 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:02 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:02 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:02 volumio volumio[1342]: info: ------------------------------ 3ms May 15 14:13:02 volumio volumio[1342]: info: ------------------------------ 2ms May 15 14:13:03 volumio volumio[1342]: info: CoreCommandRouter::volumioPlay May 15 14:13:03 volumio volumio[1342]: info: CoreStateMachine::play index undefined May 15 14:13:03 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:13:03 volumio volumio[1342]: info: CorePlayQueue::getTrack 1 May 15 14:13:03 volumio volumio[1342]: info: CoreStateMachine::startPlaybackTimer May 15 14:13:03 volumio volumio[1342]: info: CorePlayQueue::getTrack 1 May 15 14:13:03 volumio volumio[1342]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/02 - Money for nothing.dsf May 15 14:13:03 volumio volumio[1342]: info: Setting Volume to 100 automatically for DSD May 15 14:13:03 volumio volumio[1342]: info: VolumeController::SetAlsaVolume100 May 15 14:13:03 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand stop May 15 14:13:03 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:13:03 volumio volumio[1342]: info: CorePlayQueue::getTrack 1 May 15 14:13:03 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:13:03 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:13:03 volumio volumio[1342]: info: sendMpdCommand stop took 7 milliseconds May 15 14:13:03 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand clear May 15 14:13:03 volumio volumio[1342]: info: May 15 14:13:03 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:03 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:03 volumio volumio[1342]: info: sendMpdCommand clear took 0 milliseconds May 15 14:13:03 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/02 - Money for nothing.dsf" May 15 14:13:03 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:03 volumio volumio[1342]: info: ------------------------------ 3ms May 15 14:13:03 volumio volumio[1342]: info: May 15 14:13:03 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:03 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:03 volumio volumio[1342]: info: May 15 14:13:03 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:03 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:03 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:03 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:03 volumio volumio[1342]: info: ------------------------------ 2ms May 15 14:13:03 volumio volumio[1342]: info: ------------------------------ 2ms May 15 14:13:07 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 14:13:07 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 15 14:13:08 volumio ntpd[1070]: Soliciting pool server 195.90.182.235 May 15 14:13:08 volumio ntpd[1070]: Soliciting pool server 176.123.165.98 May 15 14:13:09 volumio ntpd[1070]: Soliciting pool server 188.134.76.192 May 15 14:13:09 volumio ntpd[1070]: Soliciting pool server 92.255.126.4 May 15 14:13:10 volumio ntpd[1070]: Soliciting pool server 82.142.168.18 May 15 14:13:10 volumio ntpd[1070]: Soliciting pool server 89.109.251.21 May 15 14:13:10 volumio ntpd[1070]: Soliciting pool server 188.227.94.15 May 15 14:13:10 volumio ntpd[1070]: Soliciting pool server 151.0.2.53 May 15 14:13:11 volumio volumio[1342]: info: [ASDebug] Port: 24 May 15 14:13:11 volumio volumio[1342]: info: [ASDebug] Inverted: false May 15 14:13:11 volumio volumio[1342]: info: [ASDebug] Delay: 720 May 15 14:13:11 volumio ntpd[1070]: Soliciting pool server 94.247.111.10 May 15 14:13:11 volumio ntpd[1070]: Soliciting pool server 185.211.244.47 May 15 14:13:11 volumio ntpd[1070]: Soliciting pool server 91.201.254.110 May 15 14:13:11 volumio ntpd[1070]: Soliciting pool server 93.95.98.77 May 15 14:13:12 volumio ntpd[1070]: Soliciting pool server 46.138.254.88 May 15 14:13:12 volumio ntpd[1070]: Soliciting pool server 89.110.95.134 May 15 14:13:13 volumio ntpd[1070]: Soliciting pool server 91.207.136.55 May 15 14:13:13 volumio ntpd[1070]: Soliciting pool server 2a02:6bf:f000:1:1::3 May 15 14:13:17 volumio ntpd[1070]: receive: Unexpected origin timestamp 0xebd04acc.8411439e does not match aorg 0000000000.00000000 from server@89.110.95.134 xmt 0xebd04acd.51b1e340 May 15 14:13:17 volumio ntpd[1070]: receive: Unexpected origin timestamp 0xebd04acc.84136859 does not match aorg 0000000000.00000000 from server@93.95.98.77 xmt 0xebd04acd.519de6ac May 15 14:13:17 volumio ntpd[1070]: receive: Unexpected origin timestamp 0xebd04acc.84154d46 does not match aorg 0000000000.00000000 from server@195.90.182.235 xmt 0xebd04acd.51b10fd7 May 15 14:13:17 volumio ntpd[1070]: receive: Unexpected origin timestamp 0xebd04acc.8412a087 does not match aorg 0000000000.00000000 from server@91.201.254.110 xmt 0xebd04acd.52361cac May 15 14:13:17 volumio ntpd[1070]: receive: Unexpected origin timestamp 0xebd04acc.840f7a5a does not match aorg 0000000000.00000000 from server@91.207.136.55 xmt 0xebd04acd.565b222d May 15 14:13:17 volumio ntpd[1070]: receive: Unexpected origin timestamp 0xebd04acc.8411f940 does not match aorg 0000000000.00000000 from server@94.247.111.10 xmt 0xebd04acd.57641ec5 May 15 14:13:24 volumio volumio[1342]: info: CoreCommandRouter::volumioPlay May 15 14:13:24 volumio volumio[1342]: info: CoreStateMachine::play index undefined May 15 14:13:24 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:13:24 volumio volumio[1342]: info: CorePlayQueue::getTrack 1 May 15 14:13:24 volumio volumio[1342]: info: CoreStateMachine::startPlaybackTimer May 15 14:13:24 volumio volumio[1342]: info: CorePlayQueue::getTrack 1 May 15 14:13:24 volumio volumio[1342]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/02 - Money for nothing.dsf May 15 14:13:24 volumio volumio[1342]: info: Setting Volume to 100 automatically for DSD May 15 14:13:24 volumio volumio[1342]: info: VolumeController::SetAlsaVolume100 May 15 14:13:24 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand stop May 15 14:13:24 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:13:24 volumio volumio[1342]: info: CorePlayQueue::getTrack 1 May 15 14:13:24 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:13:24 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:13:24 volumio volumio[1342]: info: sendMpdCommand stop took 6 milliseconds May 15 14:13:24 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand clear May 15 14:13:24 volumio volumio[1342]: info: May 15 14:13:24 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:24 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:24 volumio volumio[1342]: info: sendMpdCommand clear took 0 milliseconds May 15 14:13:24 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/02 - Money for nothing.dsf" May 15 14:13:24 volumio volumio[1342]: info: May 15 14:13:24 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:24 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:24 volumio volumio[1342]: info: May 15 14:13:24 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:24 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:24 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:24 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:24 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:24 volumio volumio[1342]: info: ------------------------------ 2ms May 15 14:13:24 volumio volumio[1342]: info: ------------------------------ 1ms May 15 14:13:24 volumio volumio[1342]: info: ------------------------------ 1ms May 15 14:13:26 volumio volumio[1342]: info: CoreCommandRouter::volumioNext May 15 14:13:26 volumio volumio[1342]: info: CoreStateMachine::next May 15 14:13:26 volumio volumio[1342]: info: CoreStateMachine::stop May 15 14:13:26 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:13:26 volumio volumio[1342]: info: CoreStateMachine::play index undefined May 15 14:13:26 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:13:26 volumio volumio[1342]: info: CorePlayQueue::getTrack 2 May 15 14:13:26 volumio volumio[1342]: info: CoreStateMachine::startPlaybackTimer May 15 14:13:26 volumio volumio[1342]: info: CorePlayQueue::getTrack 2 May 15 14:13:26 volumio volumio[1342]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/03 - Walk of Life.dsf May 15 14:13:26 volumio volumio[1342]: info: Setting Volume to 100 automatically for DSD May 15 14:13:26 volumio volumio[1342]: info: VolumeController::SetAlsaVolume100 May 15 14:13:26 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand stop May 15 14:13:26 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:13:26 volumio volumio[1342]: info: CorePlayQueue::getTrack 2 May 15 14:13:26 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 14:13:26 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:13:26 volumio volumio[1342]: info: CoreStateMachine::updateTrackBlock May 15 14:13:26 volumio volumio[1342]: info: CorePlayQueue::getTrackBlock May 15 14:13:26 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:13:26 volumio volumio[1342]: info: sendMpdCommand stop took 9 milliseconds May 15 14:13:26 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand clear May 15 14:13:26 volumio volumio[1342]: info: May 15 14:13:26 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:26 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:26 volumio volumio[1342]: info: sendMpdCommand clear took 0 milliseconds May 15 14:13:26 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/03 - Walk of Life.dsf" May 15 14:13:26 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:26 volumio volumio[1342]: info: ------------------------------ 1ms May 15 14:13:26 volumio volumio[1342]: info: May 15 14:13:26 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:26 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:26 volumio volumio[1342]: info: May 15 14:13:26 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:26 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:26 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:26 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:26 volumio volumio[1342]: info: ------------------------------ 1ms May 15 14:13:26 volumio volumio[1342]: info: ------------------------------ 0ms May 15 14:13:28 volumio volumio[1342]: info: CoreCommandRouter::volumioPlay May 15 14:13:28 volumio volumio[1342]: info: CoreStateMachine::play index undefined May 15 14:13:28 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:13:28 volumio volumio[1342]: info: CorePlayQueue::getTrack 2 May 15 14:13:28 volumio volumio[1342]: info: CoreStateMachine::startPlaybackTimer May 15 14:13:28 volumio volumio[1342]: info: CorePlayQueue::getTrack 2 May 15 14:13:28 volumio volumio[1342]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/03 - Walk of Life.dsf May 15 14:13:28 volumio volumio[1342]: info: Setting Volume to 100 automatically for DSD May 15 14:13:28 volumio volumio[1342]: info: VolumeController::SetAlsaVolume100 May 15 14:13:28 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand stop May 15 14:13:28 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:13:28 volumio volumio[1342]: info: CorePlayQueue::getTrack 2 May 15 14:13:28 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:13:28 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:13:28 volumio volumio[1342]: info: sendMpdCommand stop took 6 milliseconds May 15 14:13:28 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand clear May 15 14:13:28 volumio volumio[1342]: info: May 15 14:13:28 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:28 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:28 volumio volumio[1342]: info: sendMpdCommand clear took 0 milliseconds May 15 14:13:28 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/03 - Walk of Life.dsf" May 15 14:13:28 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:28 volumio volumio[1342]: info: ------------------------------ 1ms May 15 14:13:28 volumio volumio[1342]: info: May 15 14:13:28 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:28 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:28 volumio volumio[1342]: info: May 15 14:13:28 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:28 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:28 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:28 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:28 volumio volumio[1342]: info: ------------------------------ 1ms May 15 14:13:28 volumio volumio[1342]: info: ------------------------------ 1ms May 15 14:13:31 volumio volumio[1342]: info: Enabling plugin fusiondsp May 15 14:13:31 volumio volumio[1342]: info: Loading plugin "fusiondsp"... May 15 14:13:32 volumio volumio[1342]: info: Applying required configuration parameters for plugin fusiondsp May 15 14:13:32 volumio volumio[1342]: info: Preparing to generate the ALSA configuration file May 15 14:13:32 volumio volumio[1342]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 15 14:13:32 volumio volumio[1342]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 15 14:13:32 volumio volumio[1342]: info: Reading ALSA contributions from plugins. May 15 14:13:32 volumio volumio[1342]: info: Asound.conf file written May 15 14:13:32 volumio sudo[1839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 15 14:13:32 volumio sudo[1839]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:13:32 volumio sudo[1839]: pam_unix(sudo:session): session closed for user root May 15 14:13:32 volumio volumio[1342]: info: Output device has changed, restarting MPD May 15 14:13:32 volumio sudo[1844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 14:13:32 volumio sudo[1844]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:13:32 volumio volumio[1342]: info: Output device has changed, restarting Shairport Sync May 15 14:13:32 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:32 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:13:32 volumio sudo[1844]: pam_unix(sudo:session): session closed for user root May 15 14:13:32 volumio sudo[1847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 14:13:32 volumio sudo[1847]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:13:32 volumio systemd[1]: Stopping Music Player Daemon... May 15 14:13:32 volumio volumio[1342]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 14:13:32 volumio systemd[1]: mpd.service: Succeeded. May 15 14:13:32 volumio systemd[1]: Stopped Music Player Daemon. May 15 14:13:32 volumio volumio[1342]: info: PLUGIN START: fusiondsp May 15 14:13:32 volumio volumio[1342]: info: Loading i18n strings for locale ru May 15 14:13:32 volumio systemd[1]: Starting Music Player Daemon... May 15 14:13:32 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 15 14:13:32 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:32 volumio volumio[1342]: info: FusionDsp - mixtype--------------------- Software May 15 14:13:32 volumio volumio[1342]: info: Preparing to generate the ALSA configuration file May 15 14:13:32 volumio volumio[1342]: info: Done. May 15 14:13:32 volumio volumio[1342]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 15 14:13:32 volumio volumio[1342]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 15 14:13:32 volumio volumio[1342]: info: Reading ALSA contributions from plugins. May 15 14:13:32 volumio volumio[1342]: info: MPD Permissions set May 15 14:13:32 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:32 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:32 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:32 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:32 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:32 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:32 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:32 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:32 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:13:32 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:32 volumio volumio[1342]: info: Starting Shairport Sync May 15 14:13:32 volumio sudo[1852]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 15 14:13:32 volumio sudo[1852]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:13:32 volumio sudo[1852]: pam_unix(sudo:session): session closed for user root May 15 14:13:32 volumio volumio[1342]: info: Asound.conf file unchanged, so no further update is needed May 15 14:13:33 volumio volumio[1342]: info: Output device has changed, restarting MPD May 15 14:13:33 volumio sudo[1860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 14:13:33 volumio sudo[1860]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:13:33 volumio sudo[1863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 14:13:33 volumio sudo[1863]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:13:33 volumio sudo[1863]: pam_unix(sudo:session): session closed for user root May 15 14:13:33 volumio volumio[1342]: info: Output device has changed, restarting Shairport Sync May 15 14:13:33 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:33 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:13:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 14:13:33 volumio sudo[1866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 14:13:33 volumio sudo[1866]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:13:33 volumio systemd[1]: shairport-sync.service: Succeeded. May 15 14:13:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 14:13:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 14:13:33 volumio sudo[1860]: pam_unix(sudo:session): session closed for user root May 15 14:13:33 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 15 14:13:33 volumio systemd[1]: mpd.service: Succeeded. May 15 14:13:33 volumio systemd[1]: Stopped Music Player Daemon. May 15 14:13:33 volumio volumio[1342]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 14:13:33 volumio systemd[1]: Starting Music Player Daemon... May 15 14:13:33 volumio volumio[1342]: info: MPD Permissions set May 15 14:13:33 volumio volumio[1342]: info: Shairport-Sync Started May 15 14:13:33 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:33 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:33 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:33 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:33 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:33 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:33 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:13:33 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:33 volumio volumio[1342]: info: Starting Shairport Sync May 15 14:13:33 volumio sudo[1880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 14:13:33 volumio sudo[1880]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:13:33 volumio sudo[1872]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 15 14:13:33 volumio sudo[1872]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:13:33 volumio sudo[1872]: pam_unix(sudo:session): session closed for user root May 15 14:13:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 14:13:33 volumio systemd[1]: shairport-sync.service: Succeeded. May 15 14:13:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 14:13:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 14:13:33 volumio sudo[1880]: pam_unix(sudo:session): session closed for user root May 15 14:13:33 volumio volumio[1342]: info: Shairport-Sync Started May 15 14:13:33 volumio mpd[1883]: May 15 14:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 14:13:33 volumio systemd[1]: Started Music Player Daemon. May 15 14:13:33 volumio sudo[1866]: pam_unix(sudo:session): session closed for user root May 15 14:13:33 volumio sudo[1847]: pam_unix(sudo:session): session closed for user root May 15 14:13:33 volumio volumio[1342]: error: MPD error: The expression evaluated to a falsy value: May 15 14:13:33 volumio volumio[1342]: assert.ok(self.idling) May 15 14:13:33 volumio volumio[1342]: error: The expression evaluated to a falsy value: May 15 14:13:33 volumio volumio[1342]: assert.ok(self.idling) May 15 14:13:33 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:35 volumio volumio[1342]: info: camilladsp service started and running in background, instance 1 May 15 14:13:35 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:13:35 volumio volumio[1342]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA May 15 14:13:35 volumio volumio[1342]: info: FusionDsp loaded May 15 14:13:35 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 14:13:35 volumio sudo[1899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 15 14:13:35 volumio sudo[1899]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:13:35 volumio volumio[1342]: info: FusionDsp - Reporting Fusion DSP Enabled May 15 14:13:35 volumio volumio[1342]: info: Adding Signal Path Element [object Object] May 15 14:13:35 volumio volumio[1342]: info: Adding fusiondspeq DSP Signal Path Element May 15 14:13:35 volumio volumio[1342]: info: FusionDsp - ---- installed callbackRead May 15 14:13:35 volumio volumio[1342]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 15 14:13:35 volumio volumio[1342]: info: FusionDsp - Nulleq applied May 15 14:13:35 volumio volumio[1342]: error: FusionDsp - WebSocket error: [object Object] May 15 14:13:35 volumio volumio[1342]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 15 14:13:35 volumio systemd[1]: Started FusionDsp Daemon. May 15 14:13:35 volumio sudo[1899]: pam_unix(sudo:session): session closed for user root May 15 14:13:39 volumio volumio[1342]: info: CoreCommandRouter::volumioPlay May 15 14:13:39 volumio volumio[1342]: info: CoreStateMachine::play index undefined May 15 14:13:39 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:13:39 volumio volumio[1342]: info: CorePlayQueue::getTrack 2 May 15 14:13:39 volumio volumio[1342]: info: CoreStateMachine::startPlaybackTimer May 15 14:13:39 volumio volumio[1342]: info: CorePlayQueue::getTrack 2 May 15 14:13:39 volumio volumio[1342]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/03 - Walk of Life.dsf May 15 14:13:39 volumio volumio[1342]: info: Setting Volume to 100 automatically for DSD May 15 14:13:39 volumio volumio[1342]: info: VolumeController::SetAlsaVolume100 May 15 14:13:39 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand stop May 15 14:13:39 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:13:39 volumio volumio[1342]: info: CorePlayQueue::getTrack 2 May 15 14:13:39 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:13:39 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:13:39 volumio volumio[1342]: info: sendMpdCommand stop took 7 milliseconds May 15 14:13:39 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand clear May 15 14:13:39 volumio volumio[1342]: info: May 15 14:13:39 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:39 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:39 volumio volumio[1342]: info: sendMpdCommand clear took 0 milliseconds May 15 14:13:39 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/03 - Walk of Life.dsf" May 15 14:13:39 volumio volumio[1342]: info: May 15 14:13:39 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:39 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:39 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:39 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:39 volumio volumio[1342]: info: ------------------------------ 1ms May 15 14:13:39 volumio volumio[1342]: info: ------------------------------ 0ms May 15 14:13:40 volumio volumio[1342]: info: CoreCommandRouter::volumioNext May 15 14:13:40 volumio volumio[1342]: info: CoreStateMachine::next May 15 14:13:40 volumio volumio[1342]: info: CoreStateMachine::stop May 15 14:13:40 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:13:40 volumio volumio[1342]: info: CoreStateMachine::play index undefined May 15 14:13:40 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:13:40 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:13:40 volumio volumio[1342]: info: CoreStateMachine::startPlaybackTimer May 15 14:13:40 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:13:40 volumio volumio[1342]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/04 - Your latest Trick.dsf May 15 14:13:40 volumio volumio[1342]: info: Setting Volume to 100 automatically for DSD May 15 14:13:40 volumio volumio[1342]: info: VolumeController::SetAlsaVolume100 May 15 14:13:40 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand stop May 15 14:13:40 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:13:40 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:13:40 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 14:13:40 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:13:40 volumio volumio[1342]: info: CoreStateMachine::updateTrackBlock May 15 14:13:40 volumio volumio[1342]: info: CorePlayQueue::getTrackBlock May 15 14:13:40 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:13:40 volumio volumio[1342]: info: sendMpdCommand stop took 8 milliseconds May 15 14:13:40 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand clear May 15 14:13:40 volumio volumio[1342]: info: May 15 14:13:40 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:40 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:40 volumio volumio[1342]: info: sendMpdCommand clear took 0 milliseconds May 15 14:13:40 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/04 - Your latest Trick.dsf" May 15 14:13:40 volumio volumio[1342]: info: May 15 14:13:40 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:40 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:40 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:40 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:40 volumio volumio[1342]: info: ------------------------------ 1ms May 15 14:13:40 volumio volumio[1342]: info: ------------------------------ 0ms May 15 14:13:43 volumio volumio[1342]: info: CoreCommandRouter::volumioSeek May 15 14:13:43 volumio volumio[1342]: info: CoreStateMachine::seek May 15 14:13:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:13:43 volumio volumio[1342]: info: TRACKBLOCK {"uri":"music-library/USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/04 - Your latest Trick.dsf","service":"mpd","name":"04 - Your latest Trick","artist":"Dire Straits","album":"Brothers in Arms","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=535&web=Dire%20Straits/Brothers%20in%20Arms/large&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2FDire%20Straits%20-%20Brothers%20in%20Arms%20(Japan)%20(1985)&metadata=false","duration":279,"trackType":"dsf"} May 15 14:13:43 volumio volumio[1342]: info: CoreStateMachine::startPlaybackTimer May 15 14:13:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:13:43 volumio volumio[1342]: info: ControllerMpd::seek May 15 14:13:43 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:13:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:13:43 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 14:13:43 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:13:43 volumio volumio[1342]: error: [2@0] {seek} Bad song index May 15 14:13:43 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:13:45 volumio volumio[1342]: info: CoreCommandRouter::volumioPlay May 15 14:13:45 volumio volumio[1342]: info: CoreStateMachine::play index undefined May 15 14:13:45 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:13:45 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:13:45 volumio volumio[1342]: info: CoreStateMachine::startPlaybackTimer May 15 14:13:45 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:13:45 volumio volumio[1342]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/04 - Your latest Trick.dsf May 15 14:13:45 volumio volumio[1342]: info: Setting Volume to 100 automatically for DSD May 15 14:13:45 volumio volumio[1342]: info: VolumeController::SetAlsaVolume100 May 15 14:13:45 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand stop May 15 14:13:45 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:13:45 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:13:45 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 14:13:45 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:13:45 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:13:45 volumio volumio[1342]: info: sendMpdCommand stop took 6 milliseconds May 15 14:13:45 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand clear May 15 14:13:45 volumio volumio[1342]: info: May 15 14:13:45 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:45 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:45 volumio volumio[1342]: info: sendMpdCommand clear took 0 milliseconds May 15 14:13:45 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/04 - Your latest Trick.dsf" May 15 14:13:45 volumio volumio[1342]: info: May 15 14:13:45 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:45 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:45 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:45 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:45 volumio volumio[1342]: info: ------------------------------ 1ms May 15 14:13:45 volumio volumio[1342]: info: ------------------------------ 1ms May 15 14:13:47 volumio volumio[1342]: info: CoreCommandRouter::volumioPlay May 15 14:13:47 volumio volumio[1342]: info: CoreStateMachine::play index undefined May 15 14:13:47 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:13:47 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:13:47 volumio volumio[1342]: info: CoreStateMachine::startPlaybackTimer May 15 14:13:47 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:13:47 volumio volumio[1342]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/04 - Your latest Trick.dsf May 15 14:13:47 volumio volumio[1342]: info: Setting Volume to 100 automatically for DSD May 15 14:13:47 volumio volumio[1342]: info: VolumeController::SetAlsaVolume100 May 15 14:13:47 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand stop May 15 14:13:47 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:13:47 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:13:47 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:13:47 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:13:47 volumio volumio[1342]: info: sendMpdCommand stop took 10 milliseconds May 15 14:13:47 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand clear May 15 14:13:47 volumio volumio[1342]: info: May 15 14:13:47 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:47 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:47 volumio volumio[1342]: info: sendMpdCommand clear took 0 milliseconds May 15 14:13:47 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Dire Straits - Brothers in Arms (Japan) (1985)/04 - Your latest Trick.dsf" May 15 14:13:47 volumio volumio[1342]: info: May 15 14:13:47 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:13:47 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:13:47 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:47 volumio volumio[1342]: error: updateQueue error: null May 15 14:13:47 volumio volumio[1342]: info: ------------------------------ 2ms May 15 14:13:47 volumio volumio[1342]: info: ------------------------------ 1ms May 15 14:13:51 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 15 14:13:51 volumio volumio[1342]: info: CURURI: albums:// May 15 14:13:51 volumio volumio[1342]: info: listAlbums - loading Albums from cache May 15 14:13:51 volumio volumio[1342]: info: Preload queue cleared May 15 14:13:51 volumio volumio[1342]: info: CoreCommandRouter::volumioGetQueue May 15 14:13:51 volumio volumio[1342]: info: CoreStateMachine::getQueue May 15 14:13:51 volumio volumio[1342]: info: CorePlayQueue::getQueue May 15 14:13:55 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 15 14:13:55 volumio volumio[1342]: info: CURURI: albums:// May 15 14:13:55 volumio volumio[1342]: info: listAlbums - loading Albums from cache May 15 14:13:55 volumio volumio[1342]: info: Preload queue cleared May 15 14:13:56 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 15 14:13:56 volumio volumio[1342]: info: CURURI: artists:// May 15 14:13:56 volumio volumio[1342]: info: Preload queue cleared May 15 14:13:58 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 15 14:13:58 volumio volumio[1342]: info: CURURI: genres:// May 15 14:13:58 volumio volumio[1342]: info: Preload queue cleared May 15 14:14:02 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 15 14:14:02 volumio volumio[1342]: info: CURURI: albums:// May 15 14:14:02 volumio volumio[1342]: info: listAlbums - loading Albums from cache May 15 14:14:02 volumio volumio[1342]: info: Preload queue cleared May 15 14:14:33 volumio kernel: usb 3-2: USB disconnect, device number 2 May 15 14:14:33 volumio volumio[1342]: info: Partition removed: {"syspath":"/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-xhci-hcd.1-usb-0:2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-partuuid/d9dda18c-01 /dev/disk/by-uuid/60D3-06D9 /dev/disk/by-id/usb-Mass_Storage_Device_116AC2101219-0:0-part1 /dev/disk/by-label/Новый\\x20том","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"26","ID_BUS":"usb","ID_FS_LABEL":"Новый_том","ID_FS_LABEL_ENC":"Новый\\x20том","ID_FS_TYPE":"exfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"60D3-06D9","ID_FS_UUID_ENC":"60D3-06D9","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"Storage_Device","ID_MODEL_ENC":"Storage\\x20Device\\x20\\x20","ID_MODEL_ID":"6116","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"468856832","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"d9dda18c-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"d9dda18c","ID_PATH":"platform-xhci-hcd.1-usb-0:2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-xhci-hcd_1-usb-0_2_1_0-scsi-0_0_0_0","ID_REVISION":"0220","ID_SERIAL":"Mass_Storage_Device_116AC2101219-0:0","ID_SERIAL_SHORT":"116AC2101219","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Mass","ID_VENDOR_ENC":"\\x20\\x20\\x20Mass\\x20","ID_VENDOR_ID":"14cd","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"3124","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3303931"} May 15 14:14:33 volumio systemd[1]: media-\xd0\x9d\xd0\xbe\xd0\xb2\xd1\x8b\xd0\xb9_\xd1\x82\xd0\xbe\xd0\xbc.mount: Succeeded. May 15 14:14:33 volumio sudo[2047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 May 15 14:14:33 volumio sudo[2047]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:14:33 volumio sudo[2047]: pam_unix(sudo:session): session closed for user root May 15 14:14:33 volumio mount.exfat[1407]: failed to write super block May 15 14:14:37 volumio volumio[1342]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/Новый_том May 15 14:14:38 volumio volumio[1342]: info: Scanning removed location : "USB/Новый_том" May 15 14:14:38 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:38 volumio volumio[1342]: info: May 15 14:14:38 volumio volumio[1342]: ---------------------------- MPD announces state update: update May 15 14:14:38 volumio volumio[1342]: info: ControllerMpd::getState May 15 14:14:38 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:38 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:38 volumio volumio[1342]: info: May 15 14:14:38 volumio volumio[1342]: ---------------------------- MPD announces state update: update May 15 14:14:38 volumio volumio[1342]: info: ControllerMpd::getState May 15 14:14:38 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:38 volumio volumio[1342]: info: sendMpdCommand status took 2 milliseconds May 15 14:14:38 volumio volumio[1342]: info: sendMpdCommand status took 2 milliseconds May 15 14:14:38 volumio volumio[1342]: info: sendMpdCommand status took 1 milliseconds May 15 14:14:38 volumio volumio[1342]: info: sendMpdCommand status took 1 milliseconds May 15 14:14:38 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:38 volumio kernel: usb 3-2: new high-speed USB device number 3 using xhci-hcd May 15 14:14:38 volumio volumio[1342]: info: Command Router : Notfying DB Updatetrue May 15 14:14:38 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:38 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:38 volumio volumio[1342]: info: Command Router : Notfying DB Updatetrue May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::Close All Modals sent May 15 14:14:38 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:38 volumio volumio[1342]: info: ControllerMpd::pushState May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::servicePushState May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:38 volumio volumio[1342]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 15 14:14:38 volumio volumio[1342]: verbose: CURRENT POSITION 3 May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::syncState stateService stop May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus stop May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:38 volumio volumio[1342]: info: No code May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:38 volumio volumio[1342]: info: ControllerMpd::pushState May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::servicePushState May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:38 volumio volumio[1342]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 15 14:14:38 volumio volumio[1342]: verbose: CURRENT POSITION 3 May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::syncState stateService stop May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus stop May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:38 volumio volumio[1342]: info: No code May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:38 volumio volumio[1342]: info: ------------------------------ 130ms May 15 14:14:38 volumio volumio[1342]: info: ------------------------------ 130ms May 15 14:14:38 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:38 volumio volumio[1342]: info: May 15 14:14:38 volumio volumio[1342]: ---------------------------- MPD announces state update: update May 15 14:14:38 volumio volumio[1342]: info: ControllerMpd::getState May 15 14:14:38 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:38 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:38 volumio volumio[1342]: info: May 15 14:14:38 volumio volumio[1342]: ---------------------------- MPD announces state update: update May 15 14:14:38 volumio volumio[1342]: info: ControllerMpd::getState May 15 14:14:38 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:38 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:38 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:38 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:38 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:38 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:38 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:38 volumio volumio[1342]: info: sendMpdCommand status took 7 milliseconds May 15 14:14:38 volumio volumio[1342]: info: sendMpdCommand status took 7 milliseconds May 15 14:14:38 volumio volumio[1342]: info: sendMpdCommand status took 7 milliseconds May 15 14:14:38 volumio volumio[1342]: info: sendMpdCommand status took 7 milliseconds May 15 14:14:38 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:38 volumio volumio[1342]: info: Command Router : Notfying DB Updatefalse May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::Close All Modals sent May 15 14:14:38 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:38 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:38 volumio volumio[1342]: info: Command Router : Notfying DB Updatefalse May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::Close All Modals sent May 15 14:14:38 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:38 volumio volumio[1342]: info: ControllerMpd::pushState May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::servicePushState May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:38 volumio volumio[1342]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 15 14:14:38 volumio volumio[1342]: verbose: CURRENT POSITION 3 May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::syncState stateService stop May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus stop May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:38 volumio volumio[1342]: info: No code May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:38 volumio volumio[1342]: info: ControllerMpd::pushState May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::servicePushState May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:38 volumio volumio[1342]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 15 14:14:38 volumio volumio[1342]: verbose: CURRENT POSITION 3 May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::syncState stateService stop May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus stop May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:38 volumio volumio[1342]: info: No code May 15 14:14:38 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:38 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:38 volumio volumio[1342]: info: ------------------------------ 45ms May 15 14:14:38 volumio volumio[1342]: info: ------------------------------ 44ms May 15 14:14:38 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:38 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:38 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:38 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:38 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:38 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:38 volumio kernel: usb 3-2: New USB device found, idVendor=14cd, idProduct=6116, bcdDevice= 2.20 May 15 14:14:38 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 May 15 14:14:38 volumio kernel: usb 3-2: Product: USB Mass Storage Device May 15 14:14:38 volumio kernel: usb 3-2: Manufacturer: Generic May 15 14:14:38 volumio kernel: usb 3-2: SerialNumber: 116AC2101219 May 15 14:14:38 volumio kernel: usb-storage 3-2:1.0: USB Mass Storage device detected May 15 14:14:38 volumio kernel: scsi host0: usb-storage 3-2:1.0 May 15 14:14:39 volumio kernel: scsi 0:0:0:0: Direct-Access Mass Storage Device PQ: 0 ANSI: 0 May 15 14:14:39 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 May 15 14:14:39 volumio kernel: sd 0:0:0:0: [sda] 468862126 512-byte logical blocks: (240 GB/224 GiB) May 15 14:14:39 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off May 15 14:14:39 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 May 15 14:14:39 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found May 15 14:14:39 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through May 15 14:14:39 volumio kernel: sda: sda1 May 15 14:14:39 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk May 15 14:14:40 volumio volumio[1342]: info: Mounting Device Новый_том May 15 14:14:40 volumio sudo[2073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Новый_том -o noatime May 15 14:14:40 volumio sudo[2073]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:14:40 volumio mount.exfat[2075]: volume was not unmounted cleanly May 15 14:14:40 volumio sudo[2073]: pam_unix(sudo:session): session closed for user root May 15 14:14:40 volumio volumio[1342]: WARN: volume was not unmounted cleanly. May 15 14:14:40 volumio volumio[1342]: info: Scanning new location : "USB/Новый_том" May 15 14:14:40 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:40 volumio volumio[1342]: info: May 15 14:14:40 volumio volumio[1342]: ---------------------------- MPD announces state update: update May 15 14:14:40 volumio volumio[1342]: info: ControllerMpd::getState May 15 14:14:40 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:40 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:40 volumio volumio[1342]: info: May 15 14:14:40 volumio volumio[1342]: ---------------------------- MPD announces state update: update May 15 14:14:40 volumio volumio[1342]: info: ControllerMpd::getState May 15 14:14:40 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:40 volumio volumio[1342]: info: sendMpdCommand status took 1 milliseconds May 15 14:14:40 volumio volumio[1342]: info: sendMpdCommand status took 1 milliseconds May 15 14:14:40 volumio volumio[1342]: info: sendMpdCommand status took 1 milliseconds May 15 14:14:40 volumio volumio[1342]: info: sendMpdCommand status took 0 milliseconds May 15 14:14:40 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:40 volumio volumio[1342]: info: Command Router : Notfying DB Updatetrue May 15 14:14:40 volumio volumio[1342]: info: CoreCommandRouter::Close All Modals sent May 15 14:14:40 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:40 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:40 volumio volumio[1342]: info: Command Router : Notfying DB Updatetrue May 15 14:14:40 volumio volumio[1342]: info: CoreCommandRouter::Close All Modals sent May 15 14:14:40 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:40 volumio volumio[1342]: info: ControllerMpd::pushState May 15 14:14:40 volumio volumio[1342]: info: CoreCommandRouter::servicePushState May 15 14:14:40 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:40 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:40 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 14:14:40 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:40 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:40 volumio volumio[1342]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 15 14:14:40 volumio volumio[1342]: verbose: CURRENT POSITION 3 May 15 14:14:40 volumio volumio[1342]: info: CoreStateMachine::syncState stateService stop May 15 14:14:40 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus stop May 15 14:14:40 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:40 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:40 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 14:14:40 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:40 volumio volumio[1342]: info: No code May 15 14:14:40 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:40 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:40 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:40 volumio volumio[1342]: info: ControllerMpd::pushState May 15 14:14:40 volumio volumio[1342]: info: CoreCommandRouter::servicePushState May 15 14:14:40 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:40 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:40 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:40 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:40 volumio volumio[1342]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 15 14:14:40 volumio volumio[1342]: verbose: CURRENT POSITION 3 May 15 14:14:40 volumio volumio[1342]: info: CoreStateMachine::syncState stateService stop May 15 14:14:40 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus stop May 15 14:14:40 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:40 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:40 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:40 volumio volumio[1342]: info: No code May 15 14:14:40 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:40 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:40 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:40 volumio volumio[1342]: info: ------------------------------ 65ms May 15 14:14:40 volumio volumio[1342]: info: ------------------------------ 65ms May 15 14:14:40 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:40 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:40 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:40 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:40 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:40 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:43 volumio volumio[1342]: info: MPD Database updated - AlbumList cache refreshed May 15 14:14:43 volumio volumio[1342]: info: May 15 14:14:43 volumio volumio[1342]: ---------------------------- MPD announces state update: database May 15 14:14:43 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:43 volumio volumio[1342]: info: May 15 14:14:43 volumio volumio[1342]: ---------------------------- MPD announces state update: update May 15 14:14:43 volumio volumio[1342]: info: ControllerMpd::getState May 15 14:14:43 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:43 volumio volumio[1342]: info: ControllerMpd::getState May 15 14:14:43 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:43 volumio volumio[1342]: info: MPD Database updated - AlbumList cache refreshed May 15 14:14:43 volumio volumio[1342]: info: May 15 14:14:43 volumio volumio[1342]: ---------------------------- MPD announces state update: database May 15 14:14:43 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:43 volumio volumio[1342]: info: May 15 14:14:43 volumio volumio[1342]: ---------------------------- MPD announces state update: update May 15 14:14:43 volumio volumio[1342]: info: ControllerMpd::getState May 15 14:14:43 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:43 volumio volumio[1342]: info: ControllerMpd::getState May 15 14:14:43 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:43 volumio volumio[1342]: info: sendMpdCommand status took 15 milliseconds May 15 14:14:43 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:43 volumio volumio[1342]: info: Command Router : Notfying DB Updatefalse May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::Close All Modals sent May 15 14:14:43 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:43 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:43 volumio volumio[1342]: info: ControllerMpd::pushState May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::servicePushState May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:43 volumio volumio[1342]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 15 14:14:43 volumio volumio[1342]: verbose: CURRENT POSITION 3 May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::syncState stateService stop May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus stop May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:43 volumio volumio[1342]: info: No code May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:43 volumio volumio[1342]: info: ControllerMpd::pushState May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::servicePushState May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:43 volumio volumio[1342]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 15 14:14:43 volumio volumio[1342]: verbose: CURRENT POSITION 3 May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::syncState stateService stop May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus stop May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:43 volumio volumio[1342]: info: No code May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:43 volumio volumio[1342]: info: ------------------------------ 72ms May 15 14:14:43 volumio volumio[1342]: info: ------------------------------ 72ms May 15 14:14:43 volumio volumio[1342]: info: sendMpdCommand status took 80 milliseconds May 15 14:14:43 volumio volumio[1342]: info: sendMpdCommand status took 80 milliseconds May 15 14:14:43 volumio volumio[1342]: info: sendMpdCommand status took 80 milliseconds May 15 14:14:43 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:43 volumio volumio[1342]: info: Command Router : Notfying DB Updatefalse May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::Close All Modals sent May 15 14:14:43 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:43 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:43 volumio volumio[1342]: info: ControllerMpd::pushState May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::servicePushState May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:43 volumio volumio[1342]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 15 14:14:43 volumio volumio[1342]: verbose: CURRENT POSITION 3 May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::syncState stateService stop May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus stop May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:43 volumio volumio[1342]: info: No code May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:43 volumio volumio[1342]: info: ControllerMpd::pushState May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::servicePushState May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:43 volumio volumio[1342]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 15 14:14:43 volumio volumio[1342]: verbose: CURRENT POSITION 3 May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::syncState stateService stop May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus stop May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:43 volumio volumio[1342]: info: No code May 15 14:14:43 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:43 volumio volumio[1342]: info: CorePlayQueue::getTrack 3 May 15 14:14:43 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:43 volumio volumio[1342]: info: ------------------------------ 107ms May 15 14:14:43 volumio volumio[1342]: info: ------------------------------ 107ms May 15 14:14:43 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:43 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:43 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:43 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:43 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:43 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:43 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:43 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:43 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:43 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:43 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:43 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:48 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 15 14:14:48 volumio volumio[1342]: info: CURURI: albums:// May 15 14:14:48 volumio volumio[1342]: info: listAlbums - loading Albums from cache May 15 14:14:48 volumio volumio[1342]: info: Preload queue cleared May 15 14:14:51 volumio volumio[1342]: info: Preload queue cleared May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::volumioAddQueueItems May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::addQueueItems May 15 14:14:51 volumio volumio[1342]: info: CorePlayQueue::addQueueItems May 15 14:14:51 volumio volumio[1342]: info: Preload queue cleared May 15 14:14:51 volumio volumio[1342]: info: Adding Item to queue: albums://Chilly/Secret%20Lies%2C(HANA%2BSAEC-407%2BGN-10(100)%2BDDL-120) May 15 14:14:51 volumio volumio[1342]: info: Exploding uri albums://Chilly/Secret%20Lies%2C(HANA%2BSAEC-407%2BGN-10(100)%2BDDL-120) in service mpd May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::volumioPushQueue May 15 14:14:51 volumio volumio[1342]: info: CorePlayQueue::saveQueue May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::updateTrackBlock May 15 14:14:51 volumio volumio[1342]: info: CorePlayQueue::getTrackBlock May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::volumioPlay May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::play index 44 May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::stop May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::play index undefined May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:14:51 volumio volumio[1342]: info: CorePlayQueue::getTrack 44 May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::startPlaybackTimer May 15 14:14:51 volumio volumio[1342]: info: CorePlayQueue::getTrack 44 May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Chilly - Secret Lies - 1982,(Germany),DSF(tracks),(HANA+SAEC-407+GN-10(100)+DDL-120)/A1 Dimension.dsf May 15 14:14:51 volumio volumio[1342]: info: Setting Volume to 100 automatically for DSD May 15 14:14:51 volumio volumio[1342]: info: VolumeController::SetAlsaVolume100 May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand stop May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:51 volumio volumio[1342]: info: CorePlayQueue::getTrack 44 May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:51 volumio volumio[1342]: info: sendMpdCommand stop took 5 milliseconds May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand clear May 15 14:14:51 volumio volumio[1342]: info: [ASDebug] CurState: stop PrevState: stop May 15 14:14:51 volumio volumio[1342]: info: May 15 14:14:51 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:14:51 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:14:51 volumio volumio[1342]: info: sendMpdCommand clear took 14 milliseconds May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Chilly - Secret Lies - 1982,(Germany),DSF(tracks),(HANA+SAEC-407+GN-10(100)+DDL-120)/A1 Dimension.dsf" May 15 14:14:51 volumio volumio[1342]: info: May 15 14:14:51 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:14:51 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:14:51 volumio volumio[1342]: error: updateQueue error: null May 15 14:14:51 volumio volumio[1342]: info: May 15 14:14:51 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:14:51 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:14:51 volumio volumio[1342]: info: ------------------------------ 2ms May 15 14:14:51 volumio volumio[1342]: info: sendMpdCommand add "USB/Новый_том/Chilly - Secret Lies - 1982,(Germany),DSF(tracks),(HANA+SAEC-407+GN-10(100)+DDL-120)/A1 Dimension.dsf" took 1 milliseconds May 15 14:14:51 volumio volumio[1342]: info: ------------------------------ 1ms May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand play May 15 14:14:51 volumio volumio[1342]: info: May 15 14:14:51 volumio volumio[1342]: ---------------------------- MPD announces system playlist update May 15 14:14:51 volumio volumio[1342]: info: Ignoring MPD Status Update May 15 14:14:51 volumio volumio[1342]: info: ------------------------------ 1ms May 15 14:14:51 volumio volumio[1342]: info: sendMpdCommand play took 0 milliseconds May 15 14:14:51 volumio volumio[1342]: info: ------------------------------ 0ms May 15 14:14:51 volumio volumio[1342]: info: May 15 14:14:51 volumio volumio[1342]: ---------------------------- MPD announces state update: player May 15 14:14:51 volumio volumio[1342]: info: ControllerMpd::getState May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:51 volumio volumio[1342]: info: May 15 14:14:51 volumio volumio[1342]: ---------------------------- MPD announces state update: player May 15 14:14:51 volumio volumio[1342]: info: ControllerMpd::getState May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:51 volumio volumio[1342]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 May 15 14:14:51 volumio volumio[1342]: info: FusionDsp - ---- read samplerate from file: 384000 May 15 14:14:51 volumio volumio[1342]: info: camilladsp stopping service pid 1895... May 15 14:14:51 volumio volumio[1342]: info: camilladsp service terminated, instance 1 May 15 14:14:51 volumio volumio[1342]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 15 14:14:51 volumio volumio[1342]: info: FusionDsp - Nulleq applied May 15 14:14:51 volumio volumio[1342]: info: camilladsp service started and running in background, instance 1 May 15 14:14:51 volumio volumio[1342]: info: May 15 14:14:51 volumio volumio[1342]: ---------------------------- MPD announces state update: player May 15 14:14:51 volumio volumio[1342]: info: sendMpdCommand status took 303 milliseconds May 15 14:14:51 volumio volumio[1342]: info: ControllerMpd::getState May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:51 volumio volumio[1342]: info: sendMpdCommand status took 303 milliseconds May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 14:14:51 volumio volumio[1342]: info: May 15 14:14:51 volumio volumio[1342]: ---------------------------- MPD announces state update: player May 15 14:14:51 volumio volumio[1342]: info: ControllerMpd::getState May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status May 15 14:14:51 volumio volumio[1342]: info: sendMpdCommand status took 3 milliseconds May 15 14:14:51 volumio volumio[1342]: info: sendMpdCommand playlistinfo took 3 milliseconds May 15 14:14:51 volumio volumio[1342]: info: sendMpdCommand playlistinfo took 2 milliseconds May 15 14:14:51 volumio volumio[1342]: info: sendMpdCommand status took 2 milliseconds May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::parseTrackInfo May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::parseTrackInfo May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::parseState May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 14:14:51 volumio volumio[1342]: info: ControllerMpd::pushState May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::servicePushState May 15 14:14:51 volumio volumio[1342]: info: CorePlayQueue::getTrack 44 May 15 14:14:51 volumio volumio[1342]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":212,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1 Dimension","artist":"Chilly","album":"Secret Lies,(HANA+SAEC-407+GN-10(100)+DDL-120)","uri":"USB/Новый_том/Chilly - Secret Lies - 1982,(Germany),DSF(tracks),(HANA+SAEC-407+GN-10(100)+DDL-120)/A1 Dimension.dsf","trackType":"dsf"} May 15 14:14:51 volumio volumio[1342]: verbose: CURRENT POSITION 44 May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::syncState stateService play May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus stop May 15 14:14:51 volumio volumio[1342]: info: ControllerMpd::pushState May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::servicePushState May 15 14:14:51 volumio volumio[1342]: info: CorePlayQueue::getTrack 44 May 15 14:14:51 volumio volumio[1342]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":212,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1 Dimension","artist":"Chilly","album":"Secret Lies,(HANA+SAEC-407+GN-10(100)+DDL-120)","uri":"USB/Новый_том/Chilly - Secret Lies - 1982,(Germany),DSF(tracks),(HANA+SAEC-407+GN-10(100)+DDL-120)/A1 Dimension.dsf","trackType":"dsf"} May 15 14:14:51 volumio volumio[1342]: verbose: CURRENT POSITION 44 May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::syncState stateService play May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus play May 15 14:14:51 volumio volumio[1342]: info: Received an update from plugin. extracting info from payload May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:51 volumio volumio[1342]: info: CorePlayQueue::getTrack 44 May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:51 volumio volumio[1342]: info: CorePlayQueue::getTrack 44 May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:51 volumio volumio[1342]: info: ------------------------------ 312ms May 15 14:14:51 volumio volumio[1342]: info: ------------------------------ 317ms May 15 14:14:51 volumio volumio[1342]: info: sendMpdCommand playlistinfo took 21 milliseconds May 15 14:14:51 volumio volumio[1342]: info: sendMpdCommand playlistinfo took 20 milliseconds May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::parseTrackInfo May 15 14:14:51 volumio volumio[1342]: verbose: ControllerMpd::parseTrackInfo May 15 14:14:51 volumio volumio[1342]: info: ControllerMpd::pushState May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::servicePushState May 15 14:14:51 volumio volumio[1342]: info: CorePlayQueue::getTrack 44 May 15 14:14:51 volumio volumio[1342]: verbose: STATE SERVICE {"status":"play","position":0,"seek":337,"duration":212,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A1 Dimension","artist":"Chilly","album":"Secret Lies,(HANA+SAEC-407+GN-10(100)+DDL-120)","uri":"USB/Новый_том/Chilly - Secret Lies - 1982,(Germany),DSF(tracks),(HANA+SAEC-407+GN-10(100)+DDL-120)/A1 Dimension.dsf","trackType":"dsf"} May 15 14:14:51 volumio volumio[1342]: verbose: CURRENT POSITION 44 May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::syncState stateService play May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus play May 15 14:14:51 volumio volumio[1342]: info: Received an update from plugin. extracting info from payload May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:51 volumio volumio[1342]: info: CorePlayQueue::getTrack 44 May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:51 volumio volumio[1342]: info: CorePlayQueue::getTrack 44 May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:51 volumio volumio[1342]: info: ControllerMpd::pushState May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::servicePushState May 15 14:14:51 volumio volumio[1342]: info: CorePlayQueue::getTrack 44 May 15 14:14:51 volumio volumio[1342]: verbose: STATE SERVICE {"status":"play","position":0,"seek":337,"duration":212,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A1 Dimension","artist":"Chilly","album":"Secret Lies,(HANA+SAEC-407+GN-10(100)+DDL-120)","uri":"USB/Новый_том/Chilly - Secret Lies - 1982,(Germany),DSF(tracks),(HANA+SAEC-407+GN-10(100)+DDL-120)/A1 Dimension.dsf","trackType":"dsf"} May 15 14:14:51 volumio volumio[1342]: verbose: CURRENT POSITION 44 May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::syncState stateService play May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus play May 15 14:14:51 volumio volumio[1342]: info: Received an update from plugin. extracting info from payload May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:51 volumio volumio[1342]: info: CorePlayQueue::getTrack 44 May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:51 volumio volumio[1342]: info: CoreStateMachine::pushState May 15 14:14:51 volumio volumio[1342]: info: CorePlayQueue::getTrack 44 May 15 14:14:51 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState May 15 14:14:51 volumio volumio[1342]: info: ------------------------------ 39ms May 15 14:14:51 volumio volumio[1342]: info: ------------------------------ 37ms May 15 14:14:51 volumio volumio[1342]: info: [ASDebug] CurState: play PrevState: stop May 15 14:14:51 volumio volumio[1342]: info: [ASDebug] Togle GPIO: ON May 15 14:14:51 volumio volumio[1342]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 14:14:51 volumio volumio[1342]: Error: ESPIPE: invalid seek, write May 15 14:14:51 volumio volumio[1342]: at Object.writeSync (fs.js:711:3) May 15 14:14:51 volumio volumio[1342]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) May 15 14:14:51 volumio volumio[1342]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) May 15 14:14:51 volumio volumio[1342]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) May 15 14:14:51 volumio volumio[1342]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) May 15 14:14:51 volumio volumio[1342]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) May 15 14:14:51 volumio volumio[1342]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) May 15 14:14:51 volumio volumio[1342]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) May 15 14:14:51 volumio volumio[1342]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) May 15 14:14:51 volumio volumio[1342]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) May 15 14:14:51 volumio volumio[1342]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) May 15 14:14:51 volumio volumio[1342]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) May 15 14:14:51 volumio volumio[1342]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) May 15 14:14:51 volumio volumio[1342]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) May 15 14:14:51 volumio volumio[1342]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) May 15 14:14:51 volumio volumio[1342]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { May 15 14:14:51 volumio volumio[1342]: errno: -29, May 15 14:14:51 volumio volumio[1342]: syscall: 'write', May 15 14:14:51 volumio volumio[1342]: code: 'ESPIPE' May 15 14:14:51 volumio volumio[1342]: } May 15 14:14:51 volumio volumio[1342]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 14:14:51 volumio sudo[2128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-15 14:13 May 15 14:14:51 volumio sudo[2128]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"