-- Logs begin at Thu 2024-05-09 13:26:17 UTC, end at Sat 2024-05-11 10:59:09 UTC. -- May 11 10:58:01 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPause May 11 10:58:01 alexandria-audio volumio[13682]: info: CoreStateMachine::pause May 11 10:58:01 alexandria-audio volumio[13682]: info: CoreStateMachine::stPlaybackTimer May 11 10:58:01 alexandria-audio volumio[13682]: info: CoreStateMachine::servicePause May 11 10:58:01 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 5 May 11 10:58:01 alexandria-audio volumio[13682]: info: CoreCommandRouter::servicePause May 11 10:58:01 alexandria-audio volumio[13682]: info: ControllerMpd::pause May 11 10:58:01 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand pause May 11 10:58:01 alexandria-audio volumio[13682]: info: May 11 10:58:01 alexandria-audio volumio[13682]: ---------------------------- MPD announces state update: player May 11 10:58:01 alexandria-audio volumio[13682]: info: sendMpdCommand pause took 99 milliseconds May 11 10:58:01 alexandria-audio volumio[13682]: info: ControllerMpd::getState May 11 10:58:01 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand status May 11 10:58:01 alexandria-audio volumio[13682]: info: sendMpdCommand status took 1 milliseconds May 11 10:58:01 alexandria-audio volumio[13682]: verbose: ControllerMpd::parseState May 11 10:58:01 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 10:58:01 alexandria-audio volumio[13682]: info: sendMpdCommand playlistinfo took 1 milliseconds May 11 10:58:01 alexandria-audio volumio[13682]: verbose: ControllerMpd::parseTrackInfo May 11 10:58:01 alexandria-audio volumio[13682]: info: ControllerMpd::pushState May 11 10:58:01 alexandria-audio volumio[13682]: info: CoreCommandRouter::servicePushState May 11 10:58:01 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 5 May 11 10:58:01 alexandria-audio volumio[13682]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":163352,"duration":259,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Photograph","artist":"Ed Sheeran","album":"x","uri":"INTERNAL/Albums/Ed Sheeran - x/06 Photograph.aif","trackType":"aif"} May 11 10:58:01 alexandria-audio volumio[13682]: verbose: CURRENT POSITION 5 May 11 10:58:01 alexandria-audio volumio[13682]: info: CoreStateMachine::syncState stateService pause May 11 10:58:01 alexandria-audio volumio[13682]: info: CoreStateMachine::syncState currentStatus pause May 11 10:58:01 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:58:01 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 5 May 11 10:58:01 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:58:01 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:58:01 alexandria-audio volumio[13682]: info: CoreStateMachine::stPlaybackTimer May 11 10:58:01 alexandria-audio volumio[13682]: info: ------------------------------ 95ms May 11 10:58:34 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 11 10:58:34 alexandria-audio volumio[13682]: info: CURURI: music-library/INTERNAL/Albums May 11 10:58:36 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 11 10:58:36 alexandria-audio volumio[13682]: info: CURURI: music-library/INTERNAL/AA Setup Tracks May 11 10:58:37 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioReplaceandPlayItems May 11 10:58:37 alexandria-audio volumio[13682]: info: CoreStateMachine::ClearQueue May 11 10:58:37 alexandria-audio volumio[13682]: info: CoreStateMachine::stop May 11 10:58:37 alexandria-audio volumio[13682]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 10:58:37 alexandria-audio volumio[13682]: verbose: UNSET VOLATILE May 11 10:58:37 alexandria-audio volumio[13682]: info: CoreStateMachine::updateTrackBlock May 11 10:58:37 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrackBlock May 11 10:58:37 alexandria-audio volumio[13682]: info: CoreStateMachine::stPlaybackTimer May 11 10:58:37 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:58:37 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 5 May 11 10:58:37 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:58:37 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:58:37 alexandria-audio volumio[13682]: info: CoreStateMachine::serviceStop May 11 10:58:37 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 5 May 11 10:58:37 alexandria-audio volumio[13682]: info: CoreCommandRouter::serviceStop May 11 10:58:37 alexandria-audio volumio[13682]: info: ControllerMpd::stop May 11 10:58:37 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand stop May 11 10:58:37 alexandria-audio volumio[13682]: info: CorePlayQueue::clearPlayQueue May 11 10:58:37 alexandria-audio volumio[13682]: info: CorePlayQueue::saveQueue May 11 10:58:37 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:58:37 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:58:37 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushQueue May 11 10:58:37 alexandria-audio volumio[13682]: info: CoreStateMachine::addQueueItems May 11 10:58:37 alexandria-audio volumio[13682]: info: CorePlayQueue::addQueueItems May 11 10:58:37 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/02 - Phase Test.wav May 11 10:58:37 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/02 - Phase Test.wav in service mpd May 11 10:58:37 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/03 - L to R Instrument.wav May 11 10:58:37 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/03 - L to R Instrument.wav in service mpd May 11 10:58:37 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/04 - Male Voice.wav May 11 10:58:37 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/04 - Male Voice.wav in service mpd May 11 10:58:37 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/05 - Female Voice.wav May 11 10:58:37 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/05 - Female Voice.wav in service mpd May 11 10:58:37 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/06 - Symphony no. 8 - Dynamic Contrast.wav May 11 10:58:37 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/06 - Symphony no. 8 - Dynamic Contrast.wav in service mpd May 11 10:58:37 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/07 - Symphony no. 4 - Harmonics Flow.wav May 11 10:58:37 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/07 - Symphony no. 4 - Harmonics Flow.wav in service mpd May 11 10:58:37 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/08 - Omnimic Mono Short Sine Sweep.m4a May 11 10:58:37 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/08 - Omnimic Mono Short Sine Sweep.m4a in service mpd May 11 10:58:37 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/09 - Omnimic Left.m4a May 11 10:58:37 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/09 - Omnimic Left.m4a in service mpd May 11 10:58:37 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/10 - Omnimic Right.m4a May 11 10:58:37 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/10 - Omnimic Right.m4a in service mpd May 11 10:58:37 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/11 - Deep Bass Test.wav May 11 10:58:37 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/11 - Deep Bass Test.wav in service mpd May 11 10:58:37 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/12 - Violin Test.wav May 11 10:58:37 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/12 - Violin Test.wav in service mpd May 11 10:58:37 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/01 - L R Channel Test.flac May 11 10:58:37 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/01 - L R Channel Test.flac in service mpd May 11 10:58:37 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/13 - System Burn In.flac May 11 10:58:37 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/13 - System Burn In.flac in service mpd May 11 10:58:38 alexandria-audio volumio[13682]: info: May 11 10:58:38 alexandria-audio volumio[13682]: ---------------------------- MPD announces state update: player May 11 10:58:38 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F02%20-%20Phase%20Test.wav&metadata=false May 11 10:58:38 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/02 - Phase Test.wav May 11 10:58:38 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F03%20-%20L%20to%20R%20Instrument.wav&metadata=false May 11 10:58:38 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/03 - L to R Instrument.wav May 11 10:58:38 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F04%20%20-%20%20Male%20Voice.wav&metadata=false May 11 10:58:38 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/04 - Male Voice.wav May 11 10:58:38 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F05%20-%20Female%20Voice.wav&metadata=false May 11 10:58:38 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/05 - Female Voice.wav May 11 10:58:38 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F06%20-%20Symphony%20no.%208%20-%20Dynamic%20Contrast.wav&metadata=false May 11 10:58:38 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/06 - Symphony no. 8 - Dynamic Contrast.wav May 11 10:58:38 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F07%20-%20Symphony%20no.%204%20-%20Harmonics%20Flow.wav&metadata=false May 11 10:58:38 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/07 - Symphony no. 4 - Harmonics Flow.wav May 11 10:58:38 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F08%20-%20Omnimic%20Mono%20Short%20Sine%20Sweep.m4a&metadata=false May 11 10:58:38 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/08 - Omnimic Mono Short Sine Sweep.m4a May 11 10:58:38 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F09%20-%20Omnimic%20Left.m4a&metadata=false May 11 10:58:38 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/09 - Omnimic Left.m4a May 11 10:58:38 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F10%20-%20Omnimic%20Right.m4a&metadata=false May 11 10:58:38 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/10 - Omnimic Right.m4a May 11 10:58:38 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F11%20-%20Deep%20Bass%20Test.wav&metadata=false May 11 10:58:38 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/11 - Deep Bass Test.wav May 11 10:58:38 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F12%20-%20Violin%20Test.wav&metadata=false May 11 10:58:38 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/12 - Violin Test.wav May 11 10:58:38 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=Various%20Artist/XLO%20%26%20Reference%20Recordings%20Test%20And%20Burn-In%20CD/medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F01%20-%20L%20R%20Channel%20Test.flac&metadata=false May 11 10:58:38 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/01 - L R Channel Test.flac May 11 10:58:38 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=Various%20Artist/XLO%20%26%20Reference%20Recordings%20Test%20And%20Burn-In%20CD/medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F13%20-%20System%20Burn%20In.flac&metadata=false May 11 10:58:38 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/13 - System Burn In.flac May 11 10:58:38 alexandria-audio volumio[13682]: info: sendMpdCommand stop took 1082 milliseconds May 11 10:58:38 alexandria-audio volumio[13682]: info: ControllerMpd::getState May 11 10:58:38 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand status May 11 10:58:38 alexandria-audio volumio[13682]: info: CorePlayQueue::saveQueue May 11 10:58:38 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushQueue May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreStateMachine::updateTrackBlock May 11 10:58:39 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrackBlock May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPlay May 11 10:58:39 alexandria-audio volumio[13682]: verbose: UNSET VOLATILE May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreStateMachine::play index 0 May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreStateMachine::stop May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 10:58:39 alexandria-audio volumio[13682]: verbose: UNSET VOLATILE May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreStateMachine::play index undefined May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 10:58:39 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 0 May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreStateMachine::startPlaybackTimer May 11 10:58:39 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 0 May 11 10:58:39 alexandria-audio volumio[13682]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/AA Setup Tracks/02 - Phase Test.wav May 11 10:58:39 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand stop May 11 10:58:39 alexandria-audio volumio[13682]: info: sendMpdCommand status took 316 milliseconds May 11 10:58:39 alexandria-audio volumio[13682]: info: sendMpdCommand stop took 269 milliseconds May 11 10:58:39 alexandria-audio volumio[13682]: verbose: ControllerMpd::parseState May 11 10:58:39 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 10:58:39 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand clear May 11 10:58:39 alexandria-audio volumio[13682]: info: May 11 10:58:39 alexandria-audio volumio[13682]: ---------------------------- MPD announces system playlist update May 11 10:58:39 alexandria-audio volumio[13682]: info: Ignoring MPD Status Update May 11 10:58:39 alexandria-audio volumio[13682]: info: sendMpdCommand playlistinfo took 69 milliseconds May 11 10:58:39 alexandria-audio volumio[13682]: info: sendMpdCommand clear took 69 milliseconds May 11 10:58:39 alexandria-audio volumio[13682]: verbose: ControllerMpd::parseTrackInfo May 11 10:58:39 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/AA Setup Tracks/02 - Phase Test.wav" May 11 10:58:39 alexandria-audio volumio[13682]: info: ControllerMpd::pushState May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreCommandRouter::servicePushState May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:58:39 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 0 May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:58:39 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 0 May 11 10:58:39 alexandria-audio volumio[13682]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Photograph","artist":"Ed Sheeran","album":"x","uri":"INTERNAL/Albums/Ed Sheeran - x/06 Photograph.aif","trackType":"aif"} May 11 10:58:39 alexandria-audio volumio[13682]: verbose: CURRENT POSITION 0 May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreStateMachine::syncState stateService stop May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreStateMachine::syncState currentStatus stop May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:58:39 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 0 May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:58:39 alexandria-audio volumio[13682]: info: No code May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:58:39 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 0 May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:58:39 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:58:39 alexandria-audio volumio[13682]: info: ------------------------------ 556ms May 11 10:58:39 alexandria-audio volumio[13682]: error: updateQueue error: null May 11 10:58:39 alexandria-audio volumio[13682]: info: May 11 10:58:39 alexandria-audio volumio[13682]: ---------------------------- MPD announces system playlist update May 11 10:58:39 alexandria-audio volumio[13682]: info: Ignoring MPD Status Update May 11 10:58:39 alexandria-audio volumio[13682]: info: ------------------------------ 230ms May 11 10:58:39 alexandria-audio volumio[13682]: info: sendMpdCommand add "INTERNAL/AA Setup Tracks/02 - Phase Test.wav" took 229 milliseconds May 11 10:58:39 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand play May 11 10:58:39 alexandria-audio volumio[13682]: info: May 11 10:58:39 alexandria-audio volumio[13682]: ---------------------------- MPD announces state update: player May 11 10:58:39 alexandria-audio volumio[13682]: info: ------------------------------ 121ms May 11 10:58:39 alexandria-audio volumio[13682]: info: sendMpdCommand play took 120 milliseconds May 11 10:58:39 alexandria-audio volumio[13682]: info: ControllerMpd::getState May 11 10:58:39 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand status May 11 10:58:39 alexandria-audio volumio[13682]: info: May 11 10:58:39 alexandria-audio volumio[13682]: ---------------------------- MPD announces state update: player May 11 10:58:39 alexandria-audio volumio[13682]: info: ControllerMpd::getState May 11 10:58:39 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand status May 11 10:58:39 alexandria-audio volumio[13682]: info: sendMpdCommand status took 54 milliseconds May 11 10:58:39 alexandria-audio volumio[13682]: verbose: ControllerMpd::parseState May 11 10:58:39 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 10:58:40 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioGetState May 11 10:58:40 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 0 May 11 10:58:40 alexandria-audio volumio[13682]: info: VolumeController::SetAlsaVolume90 May 11 10:58:40 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:58:40 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 0 May 11 10:58:40 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:58:40 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:58:40 alexandria-audio volumio[13682]: info: sendMpdCommand status took 900 milliseconds May 11 10:58:40 alexandria-audio volumio[13682]: info: sendMpdCommand playlistinfo took 900 milliseconds May 11 10:58:40 alexandria-audio volumio[13682]: verbose: ControllerMpd::parseState May 11 10:58:40 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 10:58:40 alexandria-audio volumio[13682]: verbose: ControllerMpd::parseTrackInfo May 11 10:58:40 alexandria-audio volumio[13682]: info: ControllerMpd::pushState May 11 10:58:40 alexandria-audio volumio[13682]: info: CoreCommandRouter::servicePushState May 11 10:58:40 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 0 May 11 10:58:40 alexandria-audio volumio[13682]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Phase Test.wav","artist":null,"album":null,"uri":"INTERNAL/AA Setup Tracks/02 - Phase Test.wav","trackType":"wav"} May 11 10:58:40 alexandria-audio volumio[13682]: verbose: CURRENT POSITION 0 May 11 10:58:40 alexandria-audio volumio[13682]: info: CoreStateMachine::syncState stateService play May 11 10:58:40 alexandria-audio volumio[13682]: info: CoreStateMachine::syncState currentStatus stop May 11 10:58:40 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:58:40 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 0 May 11 10:58:40 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:58:40 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:58:40 alexandria-audio volumio[13682]: info: ------------------------------ 1015ms May 11 10:58:41 alexandria-audio volumio[13682]: info: sendMpdCommand playlistinfo took 436 milliseconds May 11 10:58:41 alexandria-audio volumio[13682]: verbose: ControllerMpd::parseTrackInfo May 11 10:58:41 alexandria-audio volumio[13682]: info: ControllerMpd::pushState May 11 10:58:41 alexandria-audio volumio[13682]: info: CoreCommandRouter::servicePushState May 11 10:58:41 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 0 May 11 10:58:41 alexandria-audio volumio[13682]: verbose: STATE SERVICE {"status":"play","position":0,"seek":580,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Phase Test.wav","artist":null,"album":null,"uri":"INTERNAL/AA Setup Tracks/02 - Phase Test.wav","trackType":"wav"} May 11 10:58:41 alexandria-audio volumio[13682]: verbose: CURRENT POSITION 0 May 11 10:58:41 alexandria-audio volumio[13682]: info: CoreStateMachine::syncState stateService play May 11 10:58:41 alexandria-audio volumio[13682]: info: CoreStateMachine::syncState currentStatus play May 11 10:58:41 alexandria-audio volumio[13682]: info: Received an update from plugin. extracting info from payload May 11 10:58:41 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:58:41 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 0 May 11 10:58:41 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:58:41 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:58:41 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:58:41 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 0 May 11 10:58:41 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:58:41 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:58:41 alexandria-audio volumio[13682]: info: ------------------------------ 1437ms May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioReplaceandPlayItems May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreStateMachine::ClearQueue May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreStateMachine::stop May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 10:59:02 alexandria-audio volumio[13682]: verbose: UNSET VOLATILE May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreStateMachine::stPlaybackTimer May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreStateMachine::updateTrackBlock May 11 10:59:02 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrackBlock May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:59:02 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 0 May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreStateMachine::serviceStop May 11 10:59:02 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 0 May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreCommandRouter::serviceStop May 11 10:59:02 alexandria-audio volumio[13682]: info: ControllerMpd::stop May 11 10:59:02 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand stop May 11 10:59:02 alexandria-audio volumio[13682]: info: CorePlayQueue::clearPlayQueue May 11 10:59:02 alexandria-audio volumio[13682]: info: CorePlayQueue::saveQueue May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushQueue May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreStateMachine::addQueueItems May 11 10:59:02 alexandria-audio volumio[13682]: info: CorePlayQueue::addQueueItems May 11 10:59:02 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/02 - Phase Test.wav May 11 10:59:02 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/02 - Phase Test.wav in service mpd May 11 10:59:02 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/03 - L to R Instrument.wav May 11 10:59:02 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/03 - L to R Instrument.wav in service mpd May 11 10:59:02 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/04 - Male Voice.wav May 11 10:59:02 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/04 - Male Voice.wav in service mpd May 11 10:59:02 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/05 - Female Voice.wav May 11 10:59:02 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/05 - Female Voice.wav in service mpd May 11 10:59:02 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/06 - Symphony no. 8 - Dynamic Contrast.wav May 11 10:59:02 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/06 - Symphony no. 8 - Dynamic Contrast.wav in service mpd May 11 10:59:02 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/07 - Symphony no. 4 - Harmonics Flow.wav May 11 10:59:02 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/07 - Symphony no. 4 - Harmonics Flow.wav in service mpd May 11 10:59:02 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/08 - Omnimic Mono Short Sine Sweep.m4a May 11 10:59:02 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/08 - Omnimic Mono Short Sine Sweep.m4a in service mpd May 11 10:59:02 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/09 - Omnimic Left.m4a May 11 10:59:02 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/09 - Omnimic Left.m4a in service mpd May 11 10:59:02 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/10 - Omnimic Right.m4a May 11 10:59:02 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/10 - Omnimic Right.m4a in service mpd May 11 10:59:02 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/11 - Deep Bass Test.wav May 11 10:59:02 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/11 - Deep Bass Test.wav in service mpd May 11 10:59:02 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/12 - Violin Test.wav May 11 10:59:02 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/12 - Violin Test.wav in service mpd May 11 10:59:02 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/01 - L R Channel Test.flac May 11 10:59:02 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/01 - L R Channel Test.flac in service mpd May 11 10:59:02 alexandria-audio volumio[13682]: info: Adding Item to queue: music-library/INTERNAL/AA Setup Tracks/13 - System Burn In.flac May 11 10:59:02 alexandria-audio volumio[13682]: info: Exploding uri music-library/INTERNAL/AA Setup Tracks/13 - System Burn In.flac in service mpd May 11 10:59:02 alexandria-audio volumio[13682]: info: May 11 10:59:02 alexandria-audio volumio[13682]: ---------------------------- MPD announces state update: player May 11 10:59:02 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F02%20-%20Phase%20Test.wav&metadata=false May 11 10:59:02 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/02 - Phase Test.wav May 11 10:59:02 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F03%20-%20L%20to%20R%20Instrument.wav&metadata=false May 11 10:59:02 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/03 - L to R Instrument.wav May 11 10:59:02 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F04%20%20-%20%20Male%20Voice.wav&metadata=false May 11 10:59:02 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/04 - Male Voice.wav May 11 10:59:02 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F05%20-%20Female%20Voice.wav&metadata=false May 11 10:59:02 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/05 - Female Voice.wav May 11 10:59:02 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F06%20-%20Symphony%20no.%208%20-%20Dynamic%20Contrast.wav&metadata=false May 11 10:59:02 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/06 - Symphony no. 8 - Dynamic Contrast.wav May 11 10:59:02 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F07%20-%20Symphony%20no.%204%20-%20Harmonics%20Flow.wav&metadata=false May 11 10:59:02 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/07 - Symphony no. 4 - Harmonics Flow.wav May 11 10:59:02 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F08%20-%20Omnimic%20Mono%20Short%20Sine%20Sweep.m4a&metadata=false May 11 10:59:02 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/08 - Omnimic Mono Short Sine Sweep.m4a May 11 10:59:02 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F09%20-%20Omnimic%20Left.m4a&metadata=false May 11 10:59:02 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/09 - Omnimic Left.m4a May 11 10:59:02 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F10%20-%20Omnimic%20Right.m4a&metadata=false May 11 10:59:02 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/10 - Omnimic Right.m4a May 11 10:59:02 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F11%20-%20Deep%20Bass%20Test.wav&metadata=false May 11 10:59:02 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/11 - Deep Bass Test.wav May 11 10:59:02 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=//medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F12%20-%20Violin%20Test.wav&metadata=false May 11 10:59:02 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/12 - Violin Test.wav May 11 10:59:02 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=Various%20Artist/XLO%20%26%20Reference%20Recordings%20Test%20And%20Burn-In%20CD/medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F01%20-%20L%20R%20Channel%20Test.flac&metadata=false May 11 10:59:02 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/01 - L R Channel Test.flac May 11 10:59:02 alexandria-audio volumio[13682]: info: ALBUMART /albumart?cacheid=983&web=Various%20Artist/XLO%20%26%20Reference%20Recordings%20Test%20And%20Burn-In%20CD/medium&path=%2Fmnt%2FINTERNAL%2FAA%20Setup%20Tracks%2F13%20-%20System%20Burn%20In.flac&metadata=false May 11 10:59:02 alexandria-audio volumio[13682]: info: URI /mnt/INTERNAL/AA Setup Tracks/13 - System Burn In.flac May 11 10:59:02 alexandria-audio volumio[13682]: info: sendMpdCommand stop took 815 milliseconds May 11 10:59:02 alexandria-audio volumio[13682]: info: ControllerMpd::getState May 11 10:59:02 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand status May 11 10:59:02 alexandria-audio volumio[13682]: info: CorePlayQueue::saveQueue May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushQueue May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreStateMachine::updateTrackBlock May 11 10:59:02 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrackBlock May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPlay May 11 10:59:02 alexandria-audio volumio[13682]: verbose: UNSET VOLATILE May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreStateMachine::play index 11 May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreStateMachine::stop May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 10:59:02 alexandria-audio volumio[13682]: verbose: UNSET VOLATILE May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreStateMachine::play index undefined May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 10:59:02 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:02 alexandria-audio volumio[13682]: info: CoreStateMachine::startPlaybackTimer May 11 10:59:02 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:02 alexandria-audio volumio[13682]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/AA Setup Tracks/01 - L R Channel Test.flac May 11 10:59:02 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand stop May 11 10:59:03 alexandria-audio volumio[13682]: info: sendMpdCommand status took 76 milliseconds May 11 10:59:03 alexandria-audio volumio[13682]: info: sendMpdCommand stop took 29 milliseconds May 11 10:59:03 alexandria-audio volumio[13682]: verbose: ControllerMpd::parseState May 11 10:59:03 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 10:59:03 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand clear May 11 10:59:03 alexandria-audio volumio[13682]: info: May 11 10:59:03 alexandria-audio volumio[13682]: ---------------------------- MPD announces system playlist update May 11 10:59:03 alexandria-audio volumio[13682]: info: Ignoring MPD Status Update May 11 10:59:03 alexandria-audio volumio[13682]: info: sendMpdCommand playlistinfo took 68 milliseconds May 11 10:59:03 alexandria-audio volumio[13682]: info: sendMpdCommand clear took 68 milliseconds May 11 10:59:03 alexandria-audio volumio[13682]: verbose: ControllerMpd::parseTrackInfo May 11 10:59:03 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/AA Setup Tracks/01 - L R Channel Test.flac" May 11 10:59:03 alexandria-audio volumio[13682]: info: ControllerMpd::pushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreCommandRouter::servicePushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:59:03 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:03 alexandria-audio volumio[13682]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Phase Test.wav","artist":null,"album":null,"uri":"INTERNAL/AA Setup Tracks/02 - Phase Test.wav","trackType":"wav"} May 11 10:59:03 alexandria-audio volumio[13682]: verbose: CURRENT POSITION 11 May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreStateMachine::syncState stateService stop May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreStateMachine::syncState currentStatus stop May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:59:03 alexandria-audio volumio[13682]: info: No code May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:59:03 alexandria-audio volumio[13682]: info: ------------------------------ 183ms May 11 10:59:03 alexandria-audio volumio[13682]: error: updateQueue error: null May 11 10:59:03 alexandria-audio volumio[13682]: info: May 11 10:59:03 alexandria-audio volumio[13682]: ---------------------------- MPD announces system playlist update May 11 10:59:03 alexandria-audio volumio[13682]: info: Ignoring MPD Status Update May 11 10:59:03 alexandria-audio volumio[13682]: info: ------------------------------ 53ms May 11 10:59:03 alexandria-audio volumio[13682]: info: sendMpdCommand add "INTERNAL/AA Setup Tracks/01 - L R Channel Test.flac" took 52 milliseconds May 11 10:59:03 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand play May 11 10:59:03 alexandria-audio volumio[13682]: info: May 11 10:59:03 alexandria-audio volumio[13682]: ---------------------------- MPD announces state update: player May 11 10:59:03 alexandria-audio volumio[13682]: info: ------------------------------ 107ms May 11 10:59:03 alexandria-audio volumio[13682]: info: sendMpdCommand play took 107 milliseconds May 11 10:59:03 alexandria-audio volumio[13682]: info: ControllerMpd::getState May 11 10:59:03 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand status May 11 10:59:03 alexandria-audio volumio[13682]: info: May 11 10:59:03 alexandria-audio volumio[13682]: ---------------------------- MPD announces state update: player May 11 10:59:03 alexandria-audio volumio[13682]: info: ControllerMpd::getState May 11 10:59:03 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand status May 11 10:59:03 alexandria-audio volumio[13682]: info: sendMpdCommand status took 45 milliseconds May 11 10:59:03 alexandria-audio volumio[13682]: verbose: ControllerMpd::parseState May 11 10:59:03 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 10:59:03 alexandria-audio volumio[13682]: info: sendMpdCommand status took 185 milliseconds May 11 10:59:03 alexandria-audio volumio[13682]: info: sendMpdCommand playlistinfo took 184 milliseconds May 11 10:59:03 alexandria-audio volumio[13682]: verbose: ControllerMpd::parseState May 11 10:59:03 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 10:59:03 alexandria-audio volumio[13682]: verbose: ControllerMpd::parseTrackInfo May 11 10:59:03 alexandria-audio volumio[13682]: info: ControllerMpd::pushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreCommandRouter::servicePushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:03 alexandria-audio volumio[13682]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":32,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"170 Kbps","isStreaming":false,"title":"01 - L R Channel Test.flac","artist":"Various Artist","album":"XLO & Reference Recordings Test And Burn-In CD","uri":"INTERNAL/AA Setup Tracks/01 - L R Channel Test.flac","trackType":"flac"} May 11 10:59:03 alexandria-audio volumio[13682]: verbose: CURRENT POSITION 11 May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreStateMachine::syncState stateService play May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreStateMachine::syncState currentStatus stop May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:59:03 alexandria-audio volumio[13682]: info: ------------------------------ 246ms May 11 10:59:03 alexandria-audio volumio[13682]: info: sendMpdCommand playlistinfo took 128 milliseconds May 11 10:59:03 alexandria-audio volumio[13682]: verbose: ControllerMpd::parseTrackInfo May 11 10:59:03 alexandria-audio volumio[13682]: info: ControllerMpd::pushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreCommandRouter::servicePushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:03 alexandria-audio volumio[13682]: verbose: STATE SERVICE {"status":"play","position":0,"seek":580,"duration":32,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"166 Kbps","isStreaming":false,"title":"01 - L R Channel Test.flac","artist":"Various Artist","album":"XLO & Reference Recordings Test And Burn-In CD","uri":"INTERNAL/AA Setup Tracks/01 - L R Channel Test.flac","trackType":"flac"} May 11 10:59:03 alexandria-audio volumio[13682]: verbose: CURRENT POSITION 11 May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreStateMachine::syncState stateService play May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreStateMachine::syncState currentStatus play May 11 10:59:03 alexandria-audio volumio[13682]: info: Received an update from plugin. extracting info from payload May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:59:03 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:59:03 alexandria-audio volumio[13682]: info: ------------------------------ 322ms May 11 10:59:06 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioSeek May 11 10:59:06 alexandria-audio volumio[13682]: info: CoreStateMachine::seek May 11 10:59:06 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:06 alexandria-audio volumio[13682]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/AA Setup Tracks/01 - L R Channel Test.flac","service":"mpd","name":"01 - L R Channel Test.flac","artist":"Various Artist","album":"XLO & Reference Recordings Test And Burn-In CD","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=983&web=Various%20Artist/XLO%20%26%20Reference%20Recordings%20Test%20And%20Burn-In%20CD/medium&path=%2FINTERNAL%2FAA%20Setup%20Tracks&metadata=false","duration":32,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} May 11 10:59:06 alexandria-audio volumio[13682]: info: CoreStateMachine::startPlaybackTimer May 11 10:59:06 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:06 alexandria-audio volumio[13682]: info: ControllerMpd::seek May 11 10:59:06 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:59:06 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:06 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:59:06 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:59:06 alexandria-audio volumio[13682]: error: null May 11 10:59:06 alexandria-audio volumio[13682]: info: May 11 10:59:06 alexandria-audio volumio[13682]: ---------------------------- MPD announces state update: player May 11 10:59:06 alexandria-audio volumio[13682]: info: ControllerMpd::getState May 11 10:59:06 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand status May 11 10:59:06 alexandria-audio volumio[13682]: info: sendMpdCommand status took 228 milliseconds May 11 10:59:06 alexandria-audio volumio[13682]: verbose: ControllerMpd::parseState May 11 10:59:06 alexandria-audio volumio[13682]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 10:59:06 alexandria-audio volumio[13682]: info: sendMpdCommand playlistinfo took 57 milliseconds May 11 10:59:06 alexandria-audio volumio[13682]: verbose: ControllerMpd::parseTrackInfo May 11 10:59:06 alexandria-audio volumio[13682]: info: ControllerMpd::pushState May 11 10:59:06 alexandria-audio volumio[13682]: info: CoreCommandRouter::servicePushState May 11 10:59:06 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:06 alexandria-audio volumio[13682]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17000,"duration":32,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"203 Kbps","isStreaming":false,"title":"01 - L R Channel Test.flac","artist":"Various Artist","album":"XLO & Reference Recordings Test And Burn-In CD","uri":"INTERNAL/AA Setup Tracks/01 - L R Channel Test.flac","trackType":"flac"} May 11 10:59:06 alexandria-audio volumio[13682]: verbose: CURRENT POSITION 11 May 11 10:59:06 alexandria-audio volumio[13682]: info: CoreStateMachine::syncState stateService play May 11 10:59:06 alexandria-audio volumio[13682]: info: CoreStateMachine::syncState currentStatus play May 11 10:59:06 alexandria-audio volumio[13682]: info: Received an update from plugin. extracting info from payload May 11 10:59:06 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:59:06 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:06 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:59:06 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:59:06 alexandria-audio volumio[13682]: info: CoreStateMachine::pushState May 11 10:59:06 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:06 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioPushState May 11 10:59:06 alexandria-audio volumio[13682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 10:59:06 alexandria-audio volumio[13682]: info: ------------------------------ 308ms May 11 10:59:09 alexandria-audio volumio[13682]: info: CoreCommandRouter::volumioGetState May 11 10:59:09 alexandria-audio volumio[13682]: info: CorePlayQueue::getTrack 11 May 11 10:59:09 alexandria-audio volumio[13682]: info: VolumeController::SetAlsaVolume95 May 11 10:59:09 alexandria-audio volumio[13682]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 10:59:09 alexandria-audio volumio[13682]: { Error: spawn ENOMEM May 11 10:59:09 alexandria-audio volumio[13682]: at _errnoException (util.js:1022:11) May 11 10:59:09 alexandria-audio volumio[13682]: at ChildProcess.spawn (internal/child_process.js:323:11) May 11 10:59:09 alexandria-audio volumio[13682]: at exports.spawn (child_process.js:502:9) May 11 10:59:09 alexandria-audio volumio[13682]: at amixer (/volumio/app/volumecontrol.js:65:13) May 11 10:59:09 alexandria-audio volumio[13682]: at CoreVolumeController.self.setVolume (/volumio/app/volumecontrol.js:209:9) May 11 10:59:09 alexandria-audio volumio[13682]: at CoreVolumeController.alsavolume (/volumio/app/volumecontrol.js:457:16) May 11 10:59:09 alexandria-audio volumio[13682]: at CoreCommandRouter.volumiosetvolume (/volumio/app/index.js:125:35) May 11 10:59:09 alexandria-audio volumio[13682]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:267:33) May 11 10:59:09 alexandria-audio volumio[13682]: at emitTwo (events.js:126:13) May 11 10:59:09 alexandria-audio volumio[13682]: at Socket.emit (events.js:214:7) May 11 10:59:09 alexandria-audio volumio[13682]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 11 10:59:09 alexandria-audio volumio[13682]: at _combinedTickCallback (internal/process/next_tick.js:131:7) May 11 10:59:09 alexandria-audio volumio[13682]: at process._tickCallback (internal/process/next_tick.js:180:9) code: 'ENOMEM', errno: 'ENOMEM', syscall: 'spawn' } May 11 10:59:09 alexandria-audio volumio[13682]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 10:59:09 alexandria-audio sudo[16135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-11 10:58 May 11 10:59:09 alexandria-audio sudo[16135]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"