-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2026-01-28 09:43:34 CET. -- Jan 28 09:42:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 28 09:42:01 volumio volumio[974]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 28 09:42:01 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Jan 28 09:42:01 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:01 volumio volumio[974]: info: Setting Geolocation for MyVolumio to eu12 Jan 28 09:42:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:42:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:42:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:42:02 volumio volumio[974]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 28 09:42:02 volumio volumio[974]: info: Updating MyVolumio device info Jan 28 09:42:02 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:42:04 volumio volumio[974]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 28 09:42:06 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:06 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 28 09:42:06 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 28 09:42:06 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 28 09:42:06 volumio volumio[974]: info: CoreCommandRouter::volumioGetBrowseSources Jan 28 09:42:06 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 09:42:07 volumio volumio[1383]: .............................................................................................................................................++++ Jan 28 09:42:08 volumio volumio[974]: info: CoreCommandRouter::volumioPause Jan 28 09:42:08 volumio volumio[974]: info: CoreStateMachine::pause Jan 28 09:42:08 volumio volumio[974]: info: CoreStateMachine::stPlaybackTimer Jan 28 09:42:08 volumio volumio[974]: info: CoreStateMachine::servicePause Jan 28 09:42:08 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:08 volumio volumio[974]: info: CoreCommandRouter::servicePause Jan 28 09:42:08 volumio volumio[974]: info: ControllerMpd::pause Jan 28 09:42:08 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand pause Jan 28 09:42:08 volumio volumio[974]: info: Jan 28 09:42:08 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:08 volumio volumio[974]: info: sendMpdCommand pause took 3 milliseconds Jan 28 09:42:08 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:08 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:08 volumio volumio[974]: info: Jan 28 09:42:08 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:08 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:08 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:08 volumio volumio[974]: info: Jan 28 09:42:08 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:08 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:08 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:08 volumio volumio[974]: info: sendMpdCommand status took 8 milliseconds Jan 28 09:42:08 volumio volumio[974]: info: sendMpdCommand status took 12 milliseconds Jan 28 09:42:08 volumio volumio[974]: info: sendMpdCommand status took 9 milliseconds Jan 28 09:42:08 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:08 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:08 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:08 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:08 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:08 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:08 volumio volumio[974]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 28 09:42:08 volumio volumio[974]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 28 09:42:08 volumio volumio[974]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 28 09:42:08 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:08 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:08 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:08 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:08 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:08 volumio volumio[974]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":11966,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"620 Kbps","isStreaming":false,"title":"Chitlins Con Carne","artist":"Kenny Burrell","album":"Midnight Blue","uri":"NAS/CDs/03-JazzBlues/Kenny Burrell - Midnight Blue/01 - Kenny Burrell - Chitlins con Carne.flac","trackType":"flac"} Jan 28 09:42:08 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:08 volumio volumio[974]: info: CoreStateMachine::syncState stateService pause Jan 28 09:42:08 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus pause Jan 28 09:42:08 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:08 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 09:42:08 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:08 volumio volumio[974]: info: CoreStateMachine::stPlaybackTimer Jan 28 09:42:08 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:08 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:08 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:08 volumio volumio[974]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":11966,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"620 Kbps","isStreaming":false,"title":"Chitlins Con Carne","artist":"Kenny Burrell","album":"Midnight Blue","uri":"NAS/CDs/03-JazzBlues/Kenny Burrell - Midnight Blue/01 - Kenny Burrell - Chitlins con Carne.flac","trackType":"flac"} Jan 28 09:42:08 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:08 volumio volumio[974]: info: CoreStateMachine::syncState stateService pause Jan 28 09:42:08 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus pause Jan 28 09:42:08 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:08 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:08 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:08 volumio volumio[974]: info: CoreStateMachine::stPlaybackTimer Jan 28 09:42:08 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:08 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:08 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:08 volumio volumio[974]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":11966,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"620 Kbps","isStreaming":false,"title":"Chitlins Con Carne","artist":"Kenny Burrell","album":"Midnight Blue","uri":"NAS/CDs/03-JazzBlues/Kenny Burrell - Midnight Blue/01 - Kenny Burrell - Chitlins con Carne.flac","trackType":"flac"} Jan 28 09:42:08 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:08 volumio volumio[974]: info: CoreStateMachine::syncState stateService pause Jan 28 09:42:08 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus pause Jan 28 09:42:08 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:08 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:08 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:08 volumio volumio[974]: info: CoreStateMachine::stPlaybackTimer Jan 28 09:42:08 volumio volumio[974]: info: ------------------------------ 130ms Jan 28 09:42:08 volumio volumio[974]: info: ------------------------------ 127ms Jan 28 09:42:08 volumio volumio[974]: info: ------------------------------ 126ms Jan 28 09:42:08 volumio volumio[974]: info: [ASDebug] CurState: pause PrevState: play Jan 28 09:42:08 volumio volumio[974]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 28 09:42:08 volumio volumio[974]: info: [ASDebug] CurState: pause PrevState: play Jan 28 09:42:08 volumio volumio[974]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 28 09:42:08 volumio volumio[974]: info: [ASDebug] CurState: pause PrevState: play Jan 28 09:42:08 volumio volumio[974]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 28 09:42:09 volumio volumio[974]: info: [ASDebug] Togle GPIO: OFF Jan 28 09:42:09 volumio volumio[974]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 28 09:42:09 volumio volumio[974]: info: Completed starting MyVolumio Plugin Jan 28 09:42:09 volumio volumio[974]: [Metrics] CommandRouter: 57s 615.16ms Jan 28 09:42:09 volumio volumio[974]: info: CoreCommandRouter::volumiosetStartupVolume Jan 28 09:42:09 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:09 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:42:09 volumio volumio[974]: info: CoreCommandRouter::Close All Modals sent Jan 28 09:42:09 volumio volumio[974]: info: CoreCommandRouter::Close All Modals sent Jan 28 09:42:09 volumio volumio[974]: info: CoreCommandRouter::volumioPrevious Jan 28 09:42:09 volumio volumio[974]: info: CoreStateMachine::previous Jan 28 09:42:09 volumio volumio[974]: info: CoreStateMachine::updateTrackBlock Jan 28 09:42:09 volumio volumio[974]: info: CorePlayQueue::getTrackBlock Jan 28 09:42:09 volumio volumio[974]: info: CoreStateMachine::serviceClearAddPlay Jan 28 09:42:09 volumio volumio[974]: info: CoreCommandRouter::serviceClearAddPlayTracks Jan 28 09:42:09 volumio volumio[974]: error: WARNING: No clearAddPlayTracks method for service mpd Jan 28 09:42:10 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 28 09:42:10 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 09:42:10 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 28 09:42:10 volumio volumio[974]: info: CoreCommandRouter::volumioPrevious Jan 28 09:42:10 volumio volumio[974]: info: CoreStateMachine::previous Jan 28 09:42:10 volumio volumio[974]: info: CoreStateMachine::updateTrackBlock Jan 28 09:42:10 volumio volumio[974]: info: CorePlayQueue::getTrackBlock Jan 28 09:42:10 volumio volumio[974]: info: CoreStateMachine::serviceClearAddPlay Jan 28 09:42:10 volumio volumio[974]: info: CoreCommandRouter::serviceClearAddPlayTracks Jan 28 09:42:10 volumio volumio[974]: error: WARNING: No clearAddPlayTracks method for service mpd Jan 28 09:42:11 volumio volumio[1383]: ..................++++ Jan 28 09:42:11 volumio volumio[1383]: e is 65537 (0x010001) Jan 28 09:42:11 volumio volumio[1383]: writing RSA key Jan 28 09:42:16 volumio volumio[974]: info: BOOT COMPLETED Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPlay Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::play index 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::stop Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::updateTrackBlock Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrackBlock Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::stPlaybackTimer Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::serviceStop Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::serviceStop Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::stop Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: pause Jan 28 09:42:17 volumio volumio[974]: info: Jan 28 09:42:17 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand stop took 20 milliseconds Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::play index undefined Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::startPlaybackTimer Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::clearAddPlayTracks NAS/CDs/03-JazzBlues/Kenny Burrell - Midnight Blue/01 - Kenny Burrell - Chitlins con Carne.flac Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand status took 7 milliseconds Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand stop took 2 milliseconds Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 09:42:17 volumio volumio[974]: info: Jan 28 09:42:17 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:17 volumio volumio[974]: info: Jan 28 09:42:17 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:17 volumio volumio[974]: info: Jan 28 09:42:17 volumio volumio[974]: ---------------------------- MPD announces system playlist update Jan 28 09:42:17 volumio volumio[974]: info: Ignoring MPD Status Update Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand clear took 11 milliseconds Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand status took 9 milliseconds Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand status took 7 milliseconds Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand add "NAS/CDs/03-JazzBlues/Kenny Burrell - Midnight Blue/01 - Kenny Burrell - Chitlins con Carne.flac" Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: 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":"Chitlins Con Carne","artist":"Kenny Burrell","album":"Midnight Blue","uri":"NAS/CDs/03-JazzBlues/Kenny Burrell - Midnight Blue/01 - Kenny Burrell - Chitlins con Carne.flac","trackType":"flac"} Jan 28 09:42:17 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState stateService stop Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus stop Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: No code Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: 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} Jan 28 09:42:17 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState stateService stop Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus stop Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: No code Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: 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} Jan 28 09:42:17 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState stateService stop Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus stop Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: No code Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: ------------------------------ 231ms Jan 28 09:42:17 volumio volumio[974]: info: ------------------------------ 219ms Jan 28 09:42:17 volumio volumio[974]: info: ------------------------------ 218ms Jan 28 09:42:17 volumio volumio[974]: info: Jan 28 09:42:17 volumio volumio[974]: ---------------------------- MPD announces system playlist update Jan 28 09:42:17 volumio volumio[974]: info: Ignoring MPD Status Update Jan 28 09:42:17 volumio volumio[974]: info: Jan 28 09:42:17 volumio volumio[974]: ---------------------------- MPD announces system playlist update Jan 28 09:42:17 volumio volumio[974]: info: Ignoring MPD Status Update Jan 28 09:42:17 volumio volumio[974]: error: updateQueue error: null Jan 28 09:42:17 volumio volumio[974]: info: Jan 28 09:42:17 volumio volumio[974]: ---------------------------- MPD announces system playlist update Jan 28 09:42:17 volumio volumio[974]: info: Ignoring MPD Status Update Jan 28 09:42:17 volumio volumio[974]: info: ------------------------------ 227ms Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand add "NAS/CDs/03-JazzBlues/Kenny Burrell - Midnight Blue/01 - Kenny Burrell - Chitlins con Carne.flac" took 221 milliseconds Jan 28 09:42:17 volumio volumio[974]: info: ------------------------------ 12ms Jan 28 09:42:17 volumio volumio[974]: info: ------------------------------ 9ms Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand play Jan 28 09:42:17 volumio volumio[974]: info: Jan 28 09:42:17 volumio volumio[974]: ---------------------------- MPD announces system playlist update Jan 28 09:42:17 volumio volumio[974]: info: Ignoring MPD Status Update Jan 28 09:42:17 volumio volumio[974]: info: Jan 28 09:42:17 volumio volumio[974]: ---------------------------- MPD announces system playlist update Jan 28 09:42:17 volumio volumio[974]: info: Ignoring MPD Status Update Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: pause Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: pause Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: pause Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: pause Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: pause Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: pause Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: pause Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: pause Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: pause Jan 28 09:42:17 volumio volumio[974]: info: Jan 28 09:42:17 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:17 volumio volumio[974]: info: ------------------------------ 68ms Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand play took 65 milliseconds Jan 28 09:42:17 volumio volumio[974]: info: ------------------------------ 65ms Jan 28 09:42:17 volumio volumio[974]: info: ------------------------------ 62ms Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:17 volumio volumio[974]: info: Jan 28 09:42:17 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:17 volumio volumio[974]: info: Jan 28 09:42:17 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:17 volumio volumio[974]: info: Jan 28 09:42:17 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand status took 13 milliseconds Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand status took 11 milliseconds Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand status took 8 milliseconds Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:17 volumio volumio[974]: info: Jan 28 09:42:17 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:17 volumio volumio[974]: info: Jan 28 09:42:17 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand status took 19 milliseconds Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand status took 10 milliseconds Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chitlins Con Carne","artist":"Kenny Burrell","album":"Midnight Blue","uri":"NAS/CDs/03-JazzBlues/Kenny Burrell - Midnight Blue/01 - Kenny Burrell - Chitlins con Carne.flac","trackType":"flac"} Jan 28 09:42:17 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus stop Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chitlins Con Carne","artist":"Kenny Burrell","album":"Midnight Blue","uri":"NAS/CDs/03-JazzBlues/Kenny Burrell - Midnight Blue/01 - Kenny Burrell - Chitlins con Carne.flac","trackType":"flac"} Jan 28 09:42:17 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus play Jan 28 09:42:17 volumio volumio[974]: info: Received an update from plugin. extracting info from payload Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chitlins Con Carne","artist":"Kenny Burrell","album":"Midnight Blue","uri":"NAS/CDs/03-JazzBlues/Kenny Burrell - Midnight Blue/01 - Kenny Burrell - Chitlins con Carne.flac","trackType":"flac"} Jan 28 09:42:17 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus play Jan 28 09:42:17 volumio volumio[974]: info: Received an update from plugin. extracting info from payload Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: ------------------------------ 99ms Jan 28 09:42:17 volumio volumio[974]: info: ------------------------------ 159ms Jan 28 09:42:17 volumio volumio[974]: info: ------------------------------ 157ms Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand status took 138 milliseconds Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand playlistinfo took 130 milliseconds Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand playlistinfo took 127 milliseconds Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chitlins Con Carne","artist":"Kenny Burrell","album":"Midnight Blue","uri":"NAS/CDs/03-JazzBlues/Kenny Burrell - Midnight Blue/01 - Kenny Burrell - Chitlins con Carne.flac","trackType":"flac"} Jan 28 09:42:17 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus play Jan 28 09:42:17 volumio volumio[974]: info: Received an update from plugin. extracting info from payload Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chitlins Con Carne","artist":"Kenny Burrell","album":"Midnight Blue","uri":"NAS/CDs/03-JazzBlues/Kenny Burrell - Midnight Blue/01 - Kenny Burrell - Chitlins con Carne.flac","trackType":"flac"} Jan 28 09:42:17 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus play Jan 28 09:42:17 volumio volumio[974]: info: Received an update from plugin. extracting info from payload Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: ------------------------------ 258ms Jan 28 09:42:17 volumio volumio[974]: info: ------------------------------ 245ms Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: pause Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] Togle GPIO: ON Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:17 volumio volumio[974]: info: sendMpdCommand playlistinfo took 146 milliseconds Jan 28 09:42:17 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:17 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chitlins Con Carne","artist":"Kenny Burrell","album":"Midnight Blue","uri":"NAS/CDs/03-JazzBlues/Kenny Burrell - Midnight Blue/01 - Kenny Burrell - Chitlins con Carne.flac","trackType":"flac"} Jan 28 09:42:17 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus play Jan 28 09:42:17 volumio volumio[974]: info: Received an update from plugin. extracting info from payload Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:17 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:17 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:17 volumio volumio[974]: info: ------------------------------ 343ms Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:17 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:21 volumio kernel: hwmon hwmon1: Voltage normalised Jan 28 09:42:22 volumio volumio[974]: info: VolumeController::SetAlsaVolume100 Jan 28 09:42:22 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:22 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:22 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 09:42:22 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:22 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:22 volumio volumio[974]: info: Jan 28 09:42:22 volumio volumio[974]: ---------------------------- MPD announces state update: mixer Jan 28 09:42:22 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:22 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:22 volumio volumio[974]: info: Jan 28 09:42:22 volumio volumio[974]: ---------------------------- MPD announces state update: mixer Jan 28 09:42:22 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:22 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:22 volumio volumio[974]: info: Jan 28 09:42:22 volumio volumio[974]: ---------------------------- MPD announces state update: mixer Jan 28 09:42:22 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:22 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:22 volumio volumio[974]: info: sendMpdCommand status took 6 milliseconds Jan 28 09:42:22 volumio volumio[974]: info: sendMpdCommand status took 5 milliseconds Jan 28 09:42:22 volumio volumio[974]: info: sendMpdCommand status took 4 milliseconds Jan 28 09:42:22 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:22 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:22 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:22 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:22 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:22 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:22 volumio volumio[974]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 28 09:42:22 volumio volumio[974]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 28 09:42:22 volumio volumio[974]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 28 09:42:22 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:22 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:22 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:22 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:22 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:22 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:22 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5971,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"719 Kbps","isStreaming":false,"title":"Chitlins Con Carne","artist":"Kenny Burrell","album":"Midnight Blue","uri":"NAS/CDs/03-JazzBlues/Kenny Burrell - Midnight Blue/01 - Kenny Burrell - Chitlins con Carne.flac","trackType":"flac"} Jan 28 09:42:22 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:22 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Jan 28 09:42:22 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus play Jan 28 09:42:22 volumio volumio[974]: info: Received an update from plugin. extracting info from payload Jan 28 09:42:22 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:22 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:22 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 09:42:22 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:22 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:22 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:22 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:22 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:22 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:22 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:22 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5971,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"885 Kbps","isStreaming":false,"title":"Chitlins Con Carne","artist":"Kenny Burrell","album":"Midnight Blue","uri":"NAS/CDs/03-JazzBlues/Kenny Burrell - Midnight Blue/01 - Kenny Burrell - Chitlins con Carne.flac","trackType":"flac"} Jan 28 09:42:22 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:22 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Jan 28 09:42:22 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus play Jan 28 09:42:22 volumio volumio[974]: info: Received an update from plugin. extracting info from payload Jan 28 09:42:22 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:22 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:22 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:22 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:22 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:22 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:22 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:22 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:22 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:22 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5971,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"885 Kbps","isStreaming":false,"title":"Chitlins Con Carne","artist":"Kenny Burrell","album":"Midnight Blue","uri":"NAS/CDs/03-JazzBlues/Kenny Burrell - Midnight Blue/01 - Kenny Burrell - Chitlins con Carne.flac","trackType":"flac"} Jan 28 09:42:22 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:22 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Jan 28 09:42:22 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus play Jan 28 09:42:22 volumio volumio[974]: info: Received an update from plugin. extracting info from payload Jan 28 09:42:22 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:22 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:22 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:22 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:22 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:22 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:22 volumio volumio[974]: info: ------------------------------ 85ms Jan 28 09:42:22 volumio volumio[974]: info: ------------------------------ 84ms Jan 28 09:42:22 volumio volumio[974]: info: ------------------------------ 84ms Jan 28 09:42:22 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:22 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:22 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:22 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:22 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:22 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:23 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 28 09:42:32 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 09:42:32 volumio volumio[974]: info: CURURI: albums:// Jan 28 09:42:32 volumio volumio[974]: info: listAlbums - loading Albums from cache Jan 28 09:42:32 volumio volumio[974]: info: Preload queue cleared Jan 28 09:42:35 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 09:42:35 volumio volumio[974]: info: CURURI: albums://Alain%20Bashung/Bashung Jan 28 09:42:35 volumio volumio[974]: info: Preload queue cleared Jan 28 09:42:35 volumio volumio[974]: info: Preloading song: music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/01 - Alain Bashung - Bijou bijou.flac Jan 28 09:42:35 volumio volumio[974]: info: Preloading song: music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/02 - Alain Bashung - Vertige de l'amour.flac Jan 28 09:42:35 volumio volumio[974]: info: Preloading song: music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/03 - Alain Bashung - Rebel.flac Jan 28 09:42:35 volumio volumio[974]: info: Preloading song: music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/04 - Alain Bashung - Gaby oh gaby.flac Jan 28 09:42:35 volumio volumio[974]: info: Preloading song: music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/05 - Alain Bashung - Elégance.flac Jan 28 09:42:35 volumio volumio[974]: info: Preloading song: music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/06 - Alain Bashung - S.O.S. amor.flac Jan 28 09:42:35 volumio volumio[974]: info: Preloading song: music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/07 - Alain Bashung - What's in a bird.flac Jan 28 09:42:35 volumio volumio[974]: info: Preloading song: music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/08 - Alain Bashung - C'est comment qu'on freine.flac Jan 28 09:42:35 volumio volumio[974]: info: Preloading song: music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/09 - Alain Bashung - Lavabo.flac Jan 28 09:42:35 volumio volumio[974]: info: Preloading song: music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/10 - Alain Bashung - Malédiction.flac Jan 28 09:42:35 volumio volumio[974]: info: Preloading song: music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/11 - Alain Bashung - Les mots bleus.flac Jan 28 09:42:35 volumio volumio[974]: info: Preloading song: music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/12 - Alain Bashung - Etrange été.flac Jan 28 09:42:35 volumio volumio[974]: info: Preloading song: music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/13 - Alain Bashung - Bombez!.flac Jan 28 09:42:35 volumio volumio[974]: info: Preloading song: music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/14 - Alain Bashung - Osez Joséphine.flac Jan 28 09:42:35 volumio volumio[974]: info: Preloading song: music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/15 - Alain Bashung - Madame rêve.flac Jan 28 09:42:35 volumio volumio[974]: info: Exploding uri music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/01 - Alain Bashung - Bijou bijou.flac in service mpd Jan 28 09:42:35 volumio volumio[974]: info: ALBUMART /albumart?cacheid=431&web=Alain%20Bashung/Bashung/large&path=%2Fmnt%2FNAS%2FCDs%2F01-Zik%20(fr)%2FAlain%20Bashung%20-%20Bashung%2F01%20-%20Alain%20Bashung%20-%20Bijou%20bijou.flac&metadata=false Jan 28 09:42:35 volumio volumio[974]: info: URI /mnt/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/01 - Alain Bashung - Bijou bijou.flac Jan 28 09:42:35 volumio volumio[974]: info: Exploding uri music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/02 - Alain Bashung - Vertige de l'amour.flac in service mpd Jan 28 09:42:35 volumio volumio[974]: info: ALBUMART /albumart?cacheid=431&web=Alain%20Bashung/Bashung/large&path=%2Fmnt%2FNAS%2FCDs%2F01-Zik%20(fr)%2FAlain%20Bashung%20-%20Bashung%2F02%20-%20Alain%20Bashung%20-%20Vertige%20de%20l'amour.flac&metadata=false Jan 28 09:42:35 volumio volumio[974]: info: URI /mnt/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/02 - Alain Bashung - Vertige de l'amour.flac Jan 28 09:42:35 volumio volumio[974]: info: Exploding uri music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/03 - Alain Bashung - Rebel.flac in service mpd Jan 28 09:42:35 volumio volumio[974]: info: ALBUMART /albumart?cacheid=431&web=Alain%20Bashung/Bashung/large&path=%2Fmnt%2FNAS%2FCDs%2F01-Zik%20(fr)%2FAlain%20Bashung%20-%20Bashung%2F03%20-%20Alain%20Bashung%20-%20Rebel.flac&metadata=false Jan 28 09:42:35 volumio volumio[974]: info: URI /mnt/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/03 - Alain Bashung - Rebel.flac Jan 28 09:42:35 volumio volumio[974]: info: No valid Plugin REST Endpoint Jan 28 09:42:35 volumio volumio[974]: info: Exploding uri music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/04 - Alain Bashung - Gaby oh gaby.flac in service mpd Jan 28 09:42:35 volumio volumio[974]: info: Exploding uri music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/05 - Alain Bashung - Elégance.flac in service mpd Jan 28 09:42:35 volumio volumio[974]: info: Exploding uri music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/06 - Alain Bashung - S.O.S. amor.flac in service mpd Jan 28 09:42:35 volumio volumio[974]: info: No valid Plugin REST Endpoint Jan 28 09:42:35 volumio volumio[974]: info: ALBUMART /albumart?cacheid=431&web=Alain%20Bashung/Bashung/large&path=%2Fmnt%2FNAS%2FCDs%2F01-Zik%20(fr)%2FAlain%20Bashung%20-%20Bashung%2F04%20-%20Alain%20Bashung%20-%20Gaby%20oh%20gaby.flac&metadata=false Jan 28 09:42:35 volumio volumio[974]: info: URI /mnt/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/04 - Alain Bashung - Gaby oh gaby.flac Jan 28 09:42:35 volumio volumio[974]: info: ALBUMART /albumart?cacheid=431&web=Alain%20Bashung/Bashung/large&path=%2Fmnt%2FNAS%2FCDs%2F01-Zik%20(fr)%2FAlain%20Bashung%20-%20Bashung%2F05%20-%20Alain%20Bashung%20-%20El%C3%A9gance.flac&metadata=false Jan 28 09:42:35 volumio volumio[974]: info: URI /mnt/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/05 - Alain Bashung - Elégance.flac Jan 28 09:42:35 volumio volumio[974]: info: ALBUMART /albumart?cacheid=431&web=Alain%20Bashung/Bashung/large&path=%2Fmnt%2FNAS%2FCDs%2F01-Zik%20(fr)%2FAlain%20Bashung%20-%20Bashung%2F06%20-%20Alain%20Bashung%20-%20S.O.S.%20amor.flac&metadata=false Jan 28 09:42:35 volumio volumio[974]: info: URI /mnt/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/06 - Alain Bashung - S.O.S. amor.flac Jan 28 09:42:35 volumio volumio[974]: info: Exploding uri music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/07 - Alain Bashung - What's in a bird.flac in service mpd Jan 28 09:42:35 volumio volumio[974]: info: Exploding uri music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/08 - Alain Bashung - C'est comment qu'on freine.flac in service mpd Jan 28 09:42:35 volumio volumio[974]: info: ALBUMART /albumart?cacheid=431&web=Alain%20Bashung/Bashung/large&path=%2Fmnt%2FNAS%2FCDs%2F01-Zik%20(fr)%2FAlain%20Bashung%20-%20Bashung%2F07%20-%20Alain%20Bashung%20-%20What's%20in%20a%20bird.flac&metadata=false Jan 28 09:42:35 volumio volumio[974]: info: URI /mnt/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/07 - Alain Bashung - What's in a bird.flac Jan 28 09:42:35 volumio volumio[974]: info: ALBUMART /albumart?cacheid=431&web=Alain%20Bashung/Bashung/large&path=%2Fmnt%2FNAS%2FCDs%2F01-Zik%20(fr)%2FAlain%20Bashung%20-%20Bashung%2F08%20-%20Alain%20Bashung%20-%20C'est%20comment%20qu'on%20freine.flac&metadata=false Jan 28 09:42:35 volumio volumio[974]: info: URI /mnt/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/08 - Alain Bashung - C'est comment qu'on freine.flac Jan 28 09:42:35 volumio volumio[974]: info: Exploding uri music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/09 - Alain Bashung - Lavabo.flac in service mpd Jan 28 09:42:35 volumio volumio[974]: info: ALBUMART /albumart?cacheid=431&web=Alain%20Bashung/Bashung/large&path=%2Fmnt%2FNAS%2FCDs%2F01-Zik%20(fr)%2FAlain%20Bashung%20-%20Bashung%2F09%20-%20Alain%20Bashung%20-%20Lavabo.flac&metadata=false Jan 28 09:42:35 volumio volumio[974]: info: URI /mnt/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/09 - Alain Bashung - Lavabo.flac Jan 28 09:42:36 volumio volumio[974]: info: Exploding uri music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/10 - Alain Bashung - Malédiction.flac in service mpd Jan 28 09:42:36 volumio volumio[974]: info: ALBUMART /albumart?cacheid=431&web=Alain%20Bashung/Bashung/large&path=%2Fmnt%2FNAS%2FCDs%2F01-Zik%20(fr)%2FAlain%20Bashung%20-%20Bashung%2F10%20-%20Alain%20Bashung%20-%20Mal%C3%A9diction.flac&metadata=false Jan 28 09:42:36 volumio volumio[974]: info: URI /mnt/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/10 - Alain Bashung - Malédiction.flac Jan 28 09:42:36 volumio volumio[974]: info: Exploding uri music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/11 - Alain Bashung - Les mots bleus.flac in service mpd Jan 28 09:42:36 volumio volumio[974]: info: ALBUMART /albumart?cacheid=431&web=Alain%20Bashung/Bashung/large&path=%2Fmnt%2FNAS%2FCDs%2F01-Zik%20(fr)%2FAlain%20Bashung%20-%20Bashung%2F11%20-%20Alain%20Bashung%20-%20Les%20mots%20bleus.flac&metadata=false Jan 28 09:42:36 volumio volumio[974]: info: URI /mnt/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/11 - Alain Bashung - Les mots bleus.flac Jan 28 09:42:36 volumio volumio[974]: info: Exploding uri music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/12 - Alain Bashung - Etrange été.flac in service mpd Jan 28 09:42:36 volumio volumio[974]: info: ALBUMART /albumart?cacheid=431&web=Alain%20Bashung/Bashung/large&path=%2Fmnt%2FNAS%2FCDs%2F01-Zik%20(fr)%2FAlain%20Bashung%20-%20Bashung%2F12%20-%20Alain%20Bashung%20-%20Etrange%20%C3%A9t%C3%A9.flac&metadata=false Jan 28 09:42:36 volumio volumio[974]: info: URI /mnt/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/12 - Alain Bashung - Etrange été.flac Jan 28 09:42:36 volumio volumio[974]: info: Exploding uri music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/13 - Alain Bashung - Bombez!.flac in service mpd Jan 28 09:42:36 volumio volumio[974]: info: ALBUMART /albumart?cacheid=431&web=Alain%20Bashung/Bashung/large&path=%2Fmnt%2FNAS%2FCDs%2F01-Zik%20(fr)%2FAlain%20Bashung%20-%20Bashung%2F13%20-%20Alain%20Bashung%20-%20Bombez!.flac&metadata=false Jan 28 09:42:36 volumio volumio[974]: info: URI /mnt/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/13 - Alain Bashung - Bombez!.flac Jan 28 09:42:36 volumio volumio[974]: info: Exploding uri music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/14 - Alain Bashung - Osez Joséphine.flac in service mpd Jan 28 09:42:36 volumio volumio[974]: info: ALBUMART /albumart?cacheid=431&web=Alain%20Bashung/Bashung/large&path=%2Fmnt%2FNAS%2FCDs%2F01-Zik%20(fr)%2FAlain%20Bashung%20-%20Bashung%2F14%20-%20Alain%20Bashung%20-%20Osez%20Jos%C3%A9phine.flac&metadata=false Jan 28 09:42:36 volumio volumio[974]: info: URI /mnt/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/14 - Alain Bashung - Osez Joséphine.flac Jan 28 09:42:36 volumio volumio[974]: info: Exploding uri music-library/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/15 - Alain Bashung - Madame rêve.flac in service mpd Jan 28 09:42:36 volumio volumio[974]: info: ALBUMART /albumart?cacheid=431&web=Alain%20Bashung/Bashung/large&path=%2Fmnt%2FNAS%2FCDs%2F01-Zik%20(fr)%2FAlain%20Bashung%20-%20Bashung%2F15%20-%20Alain%20Bashung%20-%20Madame%20r%C3%AAve.flac&metadata=false Jan 28 09:42:36 volumio volumio[974]: info: URI /mnt/NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/15 - Alain Bashung - Madame rêve.flac Jan 28 09:42:38 volumio volumio[974]: info: Preload queue cleared Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::ClearQueue Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::stop Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::stPlaybackTimer Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::updateTrackBlock Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrackBlock Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::serviceStop Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::serviceStop Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::stop Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::clearPlayQueue Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::saveQueue Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushQueue Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::addQueueItems Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::addQueueItems Jan 28 09:42:38 volumio volumio[974]: info: Preload queue cleared Jan 28 09:42:38 volumio volumio[974]: info: Adding Item to queue: albums://Alain%20Bashung/Bashung Jan 28 09:42:38 volumio volumio[974]: info: Exploding uri albums://Alain%20Bashung/Bashung in service mpd Jan 28 09:42:38 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: play Jan 28 09:42:38 volumio volumio[974]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushQueue Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::saveQueue Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::updateTrackBlock Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrackBlock Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPlay Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::play index 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::stop Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::play index undefined Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::startPlaybackTimer Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::clearAddPlayTracks NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/01 - Alain Bashung - Bijou bijou.flac Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 09:42:38 volumio volumio[974]: info: Jan 28 09:42:38 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand stop took 59 milliseconds Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand stop took 16 milliseconds Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 09:42:38 volumio volumio[974]: info: Jan 28 09:42:38 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:38 volumio volumio[974]: info: Jan 28 09:42:38 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:38 volumio volumio[974]: info: Jan 28 09:42:38 volumio volumio[974]: ---------------------------- MPD announces system playlist update Jan 28 09:42:38 volumio volumio[974]: info: Ignoring MPD Status Update Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand status took 12 milliseconds Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand clear took 11 milliseconds Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand status took 8 milliseconds Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand status took 6 milliseconds Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand add "NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/01 - Alain Bashung - Bijou bijou.flac" Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: 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} Jan 28 09:42:38 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::syncState stateService stop Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus stop Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: No code Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: 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} Jan 28 09:42:38 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::syncState stateService stop Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus stop Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: No code Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: ------------------------------ 171ms Jan 28 09:42:38 volumio volumio[974]: info: ------------------------------ 170ms Jan 28 09:42:38 volumio volumio[974]: info: Jan 28 09:42:38 volumio volumio[974]: ---------------------------- MPD announces system playlist update Jan 28 09:42:38 volumio volumio[974]: info: Ignoring MPD Status Update Jan 28 09:42:38 volumio volumio[974]: info: Jan 28 09:42:38 volumio volumio[974]: ---------------------------- MPD announces system playlist update Jan 28 09:42:38 volumio volumio[974]: info: Ignoring MPD Status Update Jan 28 09:42:38 volumio volumio[974]: error: updateQueue error: null Jan 28 09:42:38 volumio volumio[974]: info: Jan 28 09:42:38 volumio volumio[974]: ---------------------------- MPD announces system playlist update Jan 28 09:42:38 volumio volumio[974]: info: Ignoring MPD Status Update Jan 28 09:42:38 volumio volumio[974]: info: ------------------------------ 185ms Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand playlistinfo took 181 milliseconds Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand add "NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/01 - Alain Bashung - Bijou bijou.flac" took 181 milliseconds Jan 28 09:42:38 volumio volumio[974]: info: ------------------------------ 19ms Jan 28 09:42:38 volumio volumio[974]: info: ------------------------------ 16ms Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand play Jan 28 09:42:38 volumio volumio[974]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 28 09:42:38 volumio volumio[974]: info: ------------------------------ 210ms Jan 28 09:42:38 volumio volumio[974]: info: Jan 28 09:42:38 volumio volumio[974]: ---------------------------- MPD announces system playlist update Jan 28 09:42:38 volumio volumio[974]: info: Ignoring MPD Status Update Jan 28 09:42:38 volumio volumio[974]: info: Jan 28 09:42:38 volumio volumio[974]: ---------------------------- MPD announces system playlist update Jan 28 09:42:38 volumio volumio[974]: info: Ignoring MPD Status Update Jan 28 09:42:38 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: play Jan 28 09:42:38 volumio volumio[974]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 28 09:42:38 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: play Jan 28 09:42:38 volumio volumio[974]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 28 09:42:38 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: play Jan 28 09:42:38 volumio volumio[974]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 28 09:42:38 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: play Jan 28 09:42:38 volumio volumio[974]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 28 09:42:38 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: play Jan 28 09:42:38 volumio volumio[974]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 28 09:42:38 volumio volumio[974]: info: [ASDebug] CurState: stop PrevState: play Jan 28 09:42:38 volumio volumio[974]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 28 09:42:38 volumio volumio[974]: info: ------------------------------ 62ms Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand play took 56 milliseconds Jan 28 09:42:38 volumio volumio[974]: info: ------------------------------ 54ms Jan 28 09:42:38 volumio volumio[974]: info: ------------------------------ 52ms Jan 28 09:42:38 volumio volumio[974]: info: Jan 28 09:42:38 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:38 volumio volumio[974]: info: Jan 28 09:42:38 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:38 volumio volumio[974]: info: Jan 28 09:42:38 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:38 volumio volumio[974]: info: Jan 28 09:42:38 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand status took 12 milliseconds Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand status took 10 milliseconds Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand status took 9 milliseconds Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:38 volumio volumio[974]: info: Jan 28 09:42:38 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:38 volumio volumio[974]: info: Jan 28 09:42:38 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand status took 21 milliseconds Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand playlistinfo took 21 milliseconds Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand playlistinfo took 22 milliseconds Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand playlistinfo took 21 milliseconds Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand status took 17 milliseconds Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand status took 15 milliseconds Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bijou, bijou","artist":"Alain Bashung","album":"Bashung","uri":"NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/01 - Alain Bashung - Bijou bijou.flac","trackType":"flac"} Jan 28 09:42:38 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus stop Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bijou, bijou","artist":"Alain Bashung","album":"Bashung","uri":"NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/01 - Alain Bashung - Bijou bijou.flac","trackType":"flac"} Jan 28 09:42:38 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus play Jan 28 09:42:38 volumio volumio[974]: info: Received an update from plugin. extracting info from payload Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bijou, bijou","artist":"Alain Bashung","album":"Bashung","uri":"NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/01 - Alain Bashung - Bijou bijou.flac","trackType":"flac"} Jan 28 09:42:38 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus play Jan 28 09:42:38 volumio volumio[974]: info: Received an update from plugin. extracting info from payload Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: ------------------------------ 134ms Jan 28 09:42:38 volumio volumio[974]: info: ------------------------------ 269ms Jan 28 09:42:38 volumio volumio[974]: info: ------------------------------ 269ms Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand playlistinfo took 232 milliseconds Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand playlistinfo took 231 milliseconds Jan 28 09:42:38 volumio volumio[974]: info: sendMpdCommand playlistinfo took 231 milliseconds Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:38 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bijou, bijou","artist":"Alain Bashung","album":"Bashung","uri":"NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/01 - Alain Bashung - Bijou bijou.flac","trackType":"flac"} Jan 28 09:42:38 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus play Jan 28 09:42:38 volumio volumio[974]: info: Received an update from plugin. extracting info from payload Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bijou, bijou","artist":"Alain Bashung","album":"Bashung","uri":"NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/01 - Alain Bashung - Bijou bijou.flac","trackType":"flac"} Jan 28 09:42:38 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus play Jan 28 09:42:38 volumio volumio[974]: info: Received an update from plugin. extracting info from payload Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bijou, bijou","artist":"Alain Bashung","album":"Bashung","uri":"NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/01 - Alain Bashung - Bijou bijou.flac","trackType":"flac"} Jan 28 09:42:38 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus play Jan 28 09:42:38 volumio volumio[974]: info: Received an update from plugin. extracting info from payload Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:38 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:38 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:38 volumio volumio[974]: info: ------------------------------ 410ms Jan 28 09:42:38 volumio volumio[974]: info: ------------------------------ 400ms Jan 28 09:42:38 volumio volumio[974]: info: ------------------------------ 398ms Jan 28 09:42:38 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:39 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:39 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:39 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:39 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:39 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:39 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:39 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:39 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:39 volumio volumio[974]: info: [ASDebug] CurState: play PrevState: play Jan 28 09:42:49 volumio volumio[974]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 09:42:49 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 28 09:42:49 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:42:49 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 09:42:49 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:49 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:49 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:49 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:49 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:49 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:49 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:49 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 28 09:42:49 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 28 09:42:54 volumio kernel: hwmon hwmon1: Voltage normalised Jan 28 09:42:55 volumio volumio[974]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jan 28 09:42:55 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 28 09:42:55 volumio volumio[974]: info: Preparing to save Alsa Options, stopping services first Jan 28 09:42:55 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Jan 28 09:42:55 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:55 volumio volumio[974]: info: CoreCommandRouter::volumioPause Jan 28 09:42:55 volumio volumio[974]: info: CoreStateMachine::pause Jan 28 09:42:55 volumio volumio[974]: info: CoreStateMachine::stPlaybackTimer Jan 28 09:42:55 volumio volumio[974]: info: CoreStateMachine::servicePause Jan 28 09:42:55 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:55 volumio volumio[974]: info: CoreCommandRouter::servicePause Jan 28 09:42:55 volumio volumio[974]: info: ControllerMpd::pause Jan 28 09:42:55 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand pause Jan 28 09:42:55 volumio volumio[974]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB Audio Device"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jan 28 09:42:55 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 09:42:55 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 09:42:55 volumio volumio[974]: info: Setting mixer PCM for card USB Audio Device Jan 28 09:42:55 volumio volumio[974]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 28 09:42:55 volumio volumio[974]: info: Updating Volume Controller Parameters: Device: 5 Name: USB Audio Device Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 28 09:42:55 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 28 09:42:55 volumio volumio[974]: info: Disabling external Volume Control Jan 28 09:42:55 volumio volumio[974]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 09:42:55 volumio volumio[974]: info: Preparing to generate the ALSA configuration file Jan 28 09:42:55 volumio volumio[974]: info: Jan 28 09:42:55 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:55 volumio volumio[974]: info: sendMpdCommand pause took 250 milliseconds Jan 28 09:42:55 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:55 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:55 volumio volumio[974]: info: Jan 28 09:42:55 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:55 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:55 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:55 volumio volumio[974]: info: Jan 28 09:42:55 volumio volumio[974]: ---------------------------- MPD announces state update: player Jan 28 09:42:55 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:55 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:55 volumio volumio[974]: info: sendMpdCommand status took 31 milliseconds Jan 28 09:42:55 volumio volumio[974]: info: sendMpdCommand status took 29 milliseconds Jan 28 09:42:55 volumio volumio[974]: info: sendMpdCommand status took 28 milliseconds Jan 28 09:42:55 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:55 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:55 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:55 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:55 volumio volumio[974]: verbose: ControllerMpd::parseState Jan 28 09:42:55 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 09:42:55 volumio volumio[974]: info: sendMpdCommand playlistinfo took 17 milliseconds Jan 28 09:42:55 volumio volumio[974]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 28 09:42:55 volumio volumio[974]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 28 09:42:55 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:55 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:55 volumio volumio[974]: verbose: ControllerMpd::parseTrackInfo Jan 28 09:42:55 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:55 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:55 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:55 volumio volumio[974]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":18097,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"709 Kbps","isStreaming":false,"title":"Bijou, bijou","artist":"Alain Bashung","album":"Bashung","uri":"NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/01 - Alain Bashung - Bijou bijou.flac","trackType":"flac"} Jan 28 09:42:55 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:55 volumio volumio[974]: info: CoreStateMachine::syncState stateService pause Jan 28 09:42:55 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus pause Jan 28 09:42:55 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:55 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:55 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 09:42:55 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:55 volumio volumio[974]: info: CoreStateMachine::stPlaybackTimer Jan 28 09:42:55 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:55 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:55 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:55 volumio volumio[974]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":18097,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"709 Kbps","isStreaming":false,"title":"Bijou, bijou","artist":"Alain Bashung","album":"Bashung","uri":"NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/01 - Alain Bashung - Bijou bijou.flac","trackType":"flac"} Jan 28 09:42:55 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:55 volumio volumio[974]: info: CoreStateMachine::syncState stateService pause Jan 28 09:42:55 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus pause Jan 28 09:42:55 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:55 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:55 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:55 volumio volumio[974]: info: CoreStateMachine::stPlaybackTimer Jan 28 09:42:55 volumio volumio[974]: info: ControllerMpd::pushState Jan 28 09:42:55 volumio volumio[974]: info: CoreCommandRouter::servicePushState Jan 28 09:42:55 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:55 volumio volumio[974]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":18097,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"709 Kbps","isStreaming":false,"title":"Bijou, bijou","artist":"Alain Bashung","album":"Bashung","uri":"NAS/CDs/01-Zik (fr)/Alain Bashung - Bashung/01 - Alain Bashung - Bijou bijou.flac","trackType":"flac"} Jan 28 09:42:55 volumio volumio[974]: verbose: CURRENT POSITION 0 Jan 28 09:42:55 volumio volumio[974]: info: CoreStateMachine::syncState stateService pause Jan 28 09:42:55 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus pause Jan 28 09:42:55 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:55 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:55 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:55 volumio volumio[974]: info: CoreStateMachine::stPlaybackTimer Jan 28 09:42:56 volumio volumio[974]: info: ------------------------------ 320ms Jan 28 09:42:56 volumio volumio[974]: info: ------------------------------ 320ms Jan 28 09:42:56 volumio volumio[974]: info: ------------------------------ 319ms Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 28 09:42:56 volumio volumio[974]: info: [ASDebug] CurState: pause PrevState: play Jan 28 09:42:56 volumio volumio[974]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 28 09:42:56 volumio volumio[974]: info: [ASDebug] CurState: pause PrevState: play Jan 28 09:42:56 volumio volumio[974]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 28 09:42:56 volumio volumio[974]: info: [ASDebug] CurState: pause PrevState: play Jan 28 09:42:56 volumio volumio[974]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 28 09:42:56 volumio volumio[974]: info: VolumeController:: Volume=100 Mute =false Jan 28 09:42:56 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:56 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:56 volumio volumio[974]: info: [ASDebug] CurState: pause PrevState: play Jan 28 09:42:56 volumio volumio[974]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 28 09:42:56 volumio volumio[974]: info: Asound.conf file written Jan 28 09:42:56 volumio sudo[1849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 28 09:42:56 volumio sudo[1849]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:42:56 volumio sudo[1849]: pam_unix(sudo:session): session closed for user root Jan 28 09:42:56 volumio volumio[974]: info: Output device has changed, restarting MPD Jan 28 09:42:56 volumio volumio[974]: info: Output device has changed, restarting Shairport Sync Jan 28 09:42:56 volumio sudo[1854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:42:56 volumio sudo[1854]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:42:56 volumio sudo[1854]: pam_unix(sudo:session): session closed for user root Jan 28 09:42:56 volumio sudo[1856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 09:42:56 volumio sudo[1856]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:42:56 volumio systemd[1]: Stopping Music Player Daemon... Jan 28 09:42:56 volumio volumio[974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 09:42:56 volumio volumio[974]: info: Output device has changed, restarting MPD Jan 28 09:42:56 volumio volumio[974]: info: Output device has changed, restarting Shairport Sync Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:56 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:42:56 volumio sudo[1863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 09:42:56 volumio sudo[1863]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:42:56 volumio sudo[1863]: pam_unix(sudo:session): session closed for user root Jan 28 09:42:56 volumio sudo[1865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 09:42:56 volumio sudo[1865]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:42:57 volumio volumio[974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 09:42:57 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 28 09:42:57 volumio volumio[974]: info: Jan 28 09:42:57 volumio volumio[974]: ---------------------------- MPD announces state update: mixer Jan 28 09:42:57 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:57 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:57 volumio volumio[974]: error: MPD error: Error: write EPIPE Jan 28 09:42:57 volumio volumio[974]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jan 28 09:42:57 volumio systemd[1]: mpd.service: Succeeded. Jan 28 09:42:57 volumio volumio[974]: info: Jan 28 09:42:57 volumio volumio[974]: ---------------------------- MPD announces state update: mixer Jan 28 09:42:57 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 09:42:57 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:57 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:57 volumio volumio[974]: error: MPD error: Error: write EPIPE Jan 28 09:42:57 volumio volumio[974]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jan 28 09:42:57 volumio volumio[974]: info: Jan 28 09:42:57 volumio volumio[974]: ---------------------------- MPD announces state update: mixer Jan 28 09:42:57 volumio volumio[974]: info: ControllerMpd::getState Jan 28 09:42:57 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand status Jan 28 09:42:57 volumio volumio[974]: error: MPD error: Error: write EPIPE Jan 28 09:42:57 volumio volumio[974]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jan 28 09:42:57 volumio volumio[974]: info: MPD Permissions set Jan 28 09:42:57 volumio volumio[974]: info: MPD Permissions set Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio systemd[1]: Starting Music Player Daemon... Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:57 volumio volumio[974]: info: [ASDebug] Togle GPIO: OFF Jan 28 09:42:57 volumio volumio[974]: info: Starting Shairport Sync Jan 28 09:42:57 volumio volumio[974]: info: Starting Shairport Sync Jan 28 09:42:57 volumio sudo[1881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 09:42:57 volumio sudo[1881]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:42:57 volumio sudo[1883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 09:42:57 volumio sudo[1883]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:42:57 volumio sudo[1871]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 09:42:57 volumio sudo[1871]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:42:57 volumio sudo[1871]: pam_unix(sudo:session): session closed for user root Jan 28 09:42:57 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 28 09:42:57 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 28 09:42:57 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 28 09:42:57 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 09:42:57 volumio sudo[1881]: pam_unix(sudo:session): session closed for user root Jan 28 09:42:57 volumio volumio[974]: info: Shairport-Sync Started Jan 28 09:42:57 volumio sudo[1883]: pam_unix(sudo:session): session closed for user root Jan 28 09:42:57 volumio volumio[974]: info: Shairport-Sync Started Jan 28 09:42:59 volumio volumio[974]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 28 09:42:59 volumio volumio[974]: info: Preparing to save Alsa Options, stopping services first Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Jan 28 09:42:59 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::volumioPause Jan 28 09:42:59 volumio volumio[974]: info: CoreStateMachine::pause Jan 28 09:42:59 volumio volumio[974]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB Audio Device"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 09:42:59 volumio volumio[974]: info: Setting mixer PCM for card USB Audio Device Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 28 09:42:59 volumio volumio[974]: info: Updating Volume Controller Parameters: Device: 5 Name: USB Audio Device Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 28 09:42:59 volumio volumio[974]: info: Disabling external Volume Control Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 09:42:59 volumio volumio[974]: info: Preparing to generate the ALSA configuration file Jan 28 09:42:59 volumio volumio[974]: info: Asound.conf file unchanged, so no further update is needed Jan 28 09:42:59 volumio volumio[974]: info: Output device has changed, restarting MPD Jan 28 09:42:59 volumio volumio[974]: info: Output device has changed, restarting Shairport Sync Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:42:59 volumio sudo[1916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 09:42:59 volumio sudo[1916]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:42:59 volumio sudo[1916]: pam_unix(sudo:session): session closed for user root Jan 28 09:42:59 volumio sudo[1918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 09:42:59 volumio sudo[1918]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:42:59 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 28 09:42:59 volumio systemd[1]: mpd.service: Succeeded. Jan 28 09:42:59 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 09:42:59 volumio volumio[974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 09:42:59 volumio systemd[1]: Starting Music Player Daemon... Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 28 09:42:59 volumio volumio[974]: info: MPD Permissions set Jan 28 09:42:59 volumio volumio[974]: info: VolumeController:: Volume=100 Mute =false Jan 28 09:42:59 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:42:59 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 28 09:42:59 volumio volumio[974]: info: CoreStateMachine::getcurrentVolume Jan 28 09:42:59 volumio volumio[974]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 09:42:59 volumio sudo[1924]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 09:42:59 volumio sudo[1924]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:43:00 volumio sudo[1924]: pam_unix(sudo:session): session closed for user root Jan 28 09:43:00 volumio volumio[974]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 28 09:43:00 volumio volumio[974]: info: Preparing to save Alsa Options, stopping services first Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Jan 28 09:43:00 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::volumioPause Jan 28 09:43:00 volumio volumio[974]: info: CoreStateMachine::pause Jan 28 09:43:00 volumio volumio[974]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB Audio Device"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 09:43:00 volumio volumio[974]: info: Setting mixer PCM for card USB Audio Device Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 28 09:43:00 volumio volumio[974]: info: Updating Volume Controller Parameters: Device: 5 Name: USB Audio Device Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 28 09:43:00 volumio volumio[974]: info: Disabling external Volume Control Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 09:43:00 volumio volumio[974]: info: Preparing to generate the ALSA configuration file Jan 28 09:43:00 volumio volumio[974]: info: [ASDebug] CurState: pause PrevState: pause Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:43:00 volumio volumio[974]: info: VolumeController:: Volume=100 Mute =false Jan 28 09:43:00 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:43:00 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:43:00 volumio volumio[974]: info: CoreStateMachine::updateTrackBlock Jan 28 09:43:00 volumio volumio[974]: info: CorePlayQueue::getTrackBlock Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 09:43:00 volumio volumio[974]: info: VolumeController:: Volume=100 Mute =false Jan 28 09:43:00 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:43:00 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:43:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 28 09:43:01 volumio volumio[974]: info: Asound.conf file unchanged, so no further update is needed Jan 28 09:43:01 volumio volumio[974]: info: Output device has changed, restarting MPD Jan 28 09:43:01 volumio volumio[974]: info: Output device has changed, restarting Shairport Sync Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:43:01 volumio sudo[1953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 09:43:01 volumio sudo[1953]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:43:01 volumio sudo[1953]: pam_unix(sudo:session): session closed for user root Jan 28 09:43:01 volumio sudo[1955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 09:43:01 volumio sudo[1955]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:43:01 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 28 09:43:01 volumio systemd[1]: mpd.service: Succeeded. Jan 28 09:43:01 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 09:43:01 volumio volumio[974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 09:43:01 volumio volumio[974]: info: [ASDebug] CurState: pause PrevState: pause Jan 28 09:43:01 volumio volumio[974]: info: Starting Shairport Sync Jan 28 09:43:01 volumio systemd[1]: Starting Music Player Daemon... Jan 28 09:43:01 volumio sudo[1964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 09:43:01 volumio sudo[1964]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:43:01 volumio volumio[974]: info: MPD Permissions set Jan 28 09:43:01 volumio volumio[974]: info: VolumeController:: Volume=100 Mute =false Jan 28 09:43:01 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:43:01 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:43:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 28 09:43:01 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 28 09:43:01 volumio volumio[974]: info: [ASDebug] CurState: pause PrevState: pause Jan 28 09:43:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:43:01 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 09:43:01 volumio volumio[974]: info: Starting Shairport Sync Jan 28 09:43:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 09:43:01 volumio sudo[1964]: pam_unix(sudo:session): session closed for user root Jan 28 09:43:01 volumio volumio[974]: info: Shairport-Sync Started Jan 28 09:43:01 volumio sudo[1961]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 09:43:01 volumio sudo[1961]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:43:01 volumio sudo[1961]: pam_unix(sudo:session): session closed for user root Jan 28 09:43:01 volumio sudo[1972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 09:43:01 volumio sudo[1972]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:43:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 28 09:43:01 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 28 09:43:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 28 09:43:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 09:43:01 volumio sudo[1972]: pam_unix(sudo:session): session closed for user root Jan 28 09:43:01 volumio volumio[974]: info: Shairport-Sync Started Jan 28 09:43:03 volumio volumio[974]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 28 09:43:03 volumio volumio[974]: info: CoreStateMachine::getcurrentVolume Jan 28 09:43:03 volumio volumio[974]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 09:43:03 volumio mpd[1974]: Jan 28 09:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 09:43:03 volumio volumio[974]: info: VolumeController:: Volume=100 Mute =false Jan 28 09:43:03 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:43:03 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:43:03 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:43:03 volumio volumio[974]: info: CoreStateMachine::updateTrackBlock Jan 28 09:43:03 volumio volumio[974]: info: CorePlayQueue::getTrackBlock Jan 28 09:43:03 volumio volumio[974]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 09:43:03 volumio volumio[974]: info: [ASDebug] CurState: pause PrevState: pause Jan 28 09:43:03 volumio volumio[974]: info: VolumeController:: Volume=100 Mute =false Jan 28 09:43:03 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:43:03 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:43:03 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:43:03 volumio volumio[974]: info: [ASDebug] CurState: pause PrevState: pause Jan 28 09:43:03 volumio systemd[1]: Started Music Player Daemon. Jan 28 09:43:03 volumio volumio[974]: Upnp client error: Error: This socket has been ended by the other party Jan 28 09:43:03 volumio sudo[1856]: pam_unix(sudo:session): session closed for user root Jan 28 09:43:03 volumio volumio[974]: Upnp client error: Error: This socket has been ended by the other party Jan 28 09:43:03 volumio sudo[1865]: pam_unix(sudo:session): session closed for user root Jan 28 09:43:03 volumio sudo[1918]: pam_unix(sudo:session): session closed for user root Jan 28 09:43:03 volumio sudo[1955]: pam_unix(sudo:session): session closed for user root Jan 28 09:43:04 volumio volumio[974]: error: MPD error: The expression evaluated to a falsy value: Jan 28 09:43:04 volumio volumio[974]: assert.ok(self.idling) Jan 28 09:43:04 volumio volumio[974]: error: The expression evaluated to a falsy value: Jan 28 09:43:04 volumio volumio[974]: assert.ok(self.idling) Jan 28 09:43:04 volumio volumio[974]: error: MPD error: The expression evaluated to a falsy value: Jan 28 09:43:04 volumio volumio[974]: assert.ok(self.idling) Jan 28 09:43:04 volumio volumio[974]: error: The expression evaluated to a falsy value: Jan 28 09:43:04 volumio volumio[974]: assert.ok(self.idling) Jan 28 09:43:04 volumio volumio[974]: error: MPD error: The expression evaluated to a falsy value: Jan 28 09:43:04 volumio volumio[974]: assert.ok(self.idling) Jan 28 09:43:04 volumio volumio[974]: error: The expression evaluated to a falsy value: Jan 28 09:43:04 volumio volumio[974]: assert.ok(self.idling) Jan 28 09:43:04 volumio volumio[974]: error: updateQueue error: null Jan 28 09:43:04 volumio volumio[974]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 28 09:43:04 volumio volumio[974]: info: CoreStateMachine::getcurrentVolume Jan 28 09:43:04 volumio volumio[974]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 09:43:04 volumio volumio[974]: error: Upnp client error: Error: This socket has been ended by the other party Jan 28 09:43:04 volumio volumio[974]: info: VolumeController:: Volume=100 Mute =false Jan 28 09:43:04 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:43:04 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:43:04 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:43:04 volumio volumio[974]: info: CoreStateMachine::updateTrackBlock Jan 28 09:43:04 volumio volumio[974]: info: CorePlayQueue::getTrackBlock Jan 28 09:43:04 volumio volumio[974]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 09:43:04 volumio volumio[974]: info: [ASDebug] CurState: pause PrevState: pause Jan 28 09:43:04 volumio volumio[974]: info: VolumeController:: Volume=100 Mute =false Jan 28 09:43:04 volumio volumio[974]: info: CoreStateMachine::pushState Jan 28 09:43:04 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Jan 28 09:43:04 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Jan 28 09:43:04 volumio volumio[974]: info: [ASDebug] CurState: pause PrevState: pause Jan 28 09:43:06 volumio sudo[1992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 28 09:43:06 volumio sudo[1992]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:43:06 volumio sudo[1995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 28 09:43:06 volumio sudo[1995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:43:06 volumio systemd[1]: Stopping MPD Monitor Service... Jan 28 09:43:06 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 28 09:43:06 volumio systemd[1]: mpd_monitor.service: Succeeded. Jan 28 09:43:06 volumio systemd[1]: Stopped MPD Monitor Service. Jan 28 09:43:07 volumio sudo[1998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 28 09:43:07 volumio sudo[1998]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:43:07 volumio volumio[974]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 09:43:07 volumio sudo[2001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 28 09:43:07 volumio systemd[1]: Started MPD Monitor Service. Jan 28 09:43:07 volumio sudo[2001]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 09:43:07 volumio sudo[1992]: pam_unix(sudo:session): session closed for user root Jan 28 09:43:07 volumio mpd_monitor.sh[2000]: MPD Monitor Service: Starting MPD Monitor Service Jan 28 09:43:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 28 09:43:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 09:43:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 09:43:07 volumio systemd[1]: Stopping MPD Monitor Service... Jan 28 09:43:07 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 28 09:43:07 volumio systemd[1]: mpd_monitor.service: Succeeded. Jan 28 09:43:07 volumio systemd[1]: Stopped MPD Monitor Service. Jan 28 09:43:07 volumio systemd[1]: Started MPD Monitor Service. Jan 28 09:43:07 volumio sudo[1995]: pam_unix(sudo:session): session closed for user root Jan 28 09:43:07 volumio sudo[1998]: pam_unix(sudo:session): session closed for user root Jan 28 09:43:07 volumio sudo[2001]: pam_unix(sudo:session): session closed for user root Jan 28 09:43:07 volumio mpd_monitor.sh[2010]: MPD Monitor Service: Starting MPD Monitor Service Jan 28 09:43:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:43:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:43:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:43:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 09:43:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 28 09:43:07 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 28 09:43:07 volumio volumio[974]: info: Successfully started MPD Monitor Jan 28 09:43:07 volumio volumio[974]: info: Successfully started MPD Monitor Jan 28 09:43:07 volumio volumio[974]: info: Successfully started MPD Monitor Jan 28 09:43:07 volumio volumio[974]: info: Successfully started MPD Monitor Jan 28 09:43:13 volumio kernel: hwmon hwmon1: Voltage normalised Jan 28 09:43:18 volumio volumio[974]: Searching plugin music_service/mpd Jan 28 09:43:18 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jan 28 09:43:19 volumio volumio[974]: Searching plugin music_service/mpd Jan 28 09:43:19 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jan 28 09:43:19 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 28 09:43:22 volumio volumio[974]: Searching plugin music_service/mpd Jan 28 09:43:22 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jan 28 09:43:23 volumio volumio[974]: Searching plugin music_service/mpd Jan 28 09:43:23 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jan 28 09:43:24 volumio volumio[974]: Searching plugin music_service/mpd Jan 28 09:43:24 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jan 28 09:43:26 volumio volumio[974]: info: All search sources collected, pushing search results Jan 28 09:43:27 volumio volumio[974]: info: All search sources collected, pushing search results Jan 28 09:43:27 volumio volumio[974]: info: All search sources collected, pushing search results Jan 28 09:43:27 volumio volumio[974]: info: All search sources collected, pushing search results Jan 28 09:43:27 volumio volumio[974]: info: All search sources collected, pushing search results Jan 28 09:43:30 volumio volumio[974]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Patachou/7640d937-2749-4a26-a977-ffd51d688811.jpg' Jan 28 09:43:34 volumio volumio[974]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 09:43:34 volumio volumio[974]: Error: connect ECONNREFUSED 158.69.209.125:443 Jan 28 09:43:34 volumio volumio[974]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jan 28 09:43:34 volumio volumio[974]: errno: -111, Jan 28 09:43:34 volumio volumio[974]: code: 'ECONNREFUSED', Jan 28 09:43:34 volumio volumio[974]: syscall: 'connect', Jan 28 09:43:34 volumio volumio[974]: address: '158.69.209.125', Jan 28 09:43:34 volumio volumio[974]: port: 443 Jan 28 09:43:34 volumio volumio[974]: } Jan 28 09:43:34 volumio volumio[974]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 09:43:34 volumio sudo[2071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-28 09:42 Jan 28 09:43:34 volumio sudo[2071]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"