-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Tue 2024-04-16 21:35:33 CST. -- Apr 16 21:34:02 volumio volumio[2797]: info: CoreCommandRouter::volumioNext Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::next Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::stop Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::stPlaybackTimer Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:34:02 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:02 volumio volumio[2797]: info: CorePlayQueue::getTrack 18 Apr 16 21:34:02 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:02 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::serviceStop Apr 16 21:34:02 volumio volumio[2797]: info: CorePlayQueue::getTrack 18 Apr 16 21:34:02 volumio volumio[2797]: info: CoreCommandRouter::serviceStop Apr 16 21:34:02 volumio volumio[2797]: info: ControllerMpd::stop Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:34:02 volumio volumio[2797]: info: sendMpdCommand stop took 92 milliseconds Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::play index undefined Apr 16 21:34:02 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:34:02 volumio volumio[2797]: info: CorePlayQueue::getTrack 19 Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:34:02 volumio volumio[2797]: info: CorePlayQueue::getTrack 19 Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::clearAddPlayTracks NAS/ds918/单曲/02.Lost Frequencies-Are You With Me(Radio Edit).wav Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:34:02 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:34:02 volumio volumio[2797]: info: Apr 16 21:34:02 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:02 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:02 volumio volumio[2797]: info: sendMpdCommand stop took 3 milliseconds Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 21:34:02 volumio volumio[2797]: info: Apr 16 21:34:02 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:34:02 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:34:02 volumio volumio[2797]: info: sendMpdCommand status took 7 milliseconds Apr 16 21:34:02 volumio volumio[2797]: info: sendMpdCommand clear took 2 milliseconds Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand add "NAS/ds918/单曲/02.Lost Frequencies-Are You With Me(Radio Edit).wav" Apr 16 21:34:02 volumio volumio[2797]: error: updateQueue error: null Apr 16 21:34:02 volumio volumio[2797]: info: Apr 16 21:34:02 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:34:02 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:34:02 volumio volumio[2797]: info: ------------------------------ 5ms Apr 16 21:34:02 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 16 21:34:02 volumio volumio[2797]: info: sendMpdCommand add "NAS/ds918/单曲/02.Lost Frequencies-Are You With Me(Radio Edit).wav" took 4 milliseconds Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand play Apr 16 21:34:02 volumio volumio[2797]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 16 21:34:02 volumio volumio[2797]: info: ------------------------------ 15ms Apr 16 21:34:02 volumio volumio[2797]: info: ------------------------------ 5ms Apr 16 21:34:02 volumio volumio[2797]: info: sendMpdCommand play took 3 milliseconds Apr 16 21:34:02 volumio volumio[2797]: info: Apr 16 21:34:02 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:02 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:02 volumio volumio[2797]: info: Apr 16 21:34:02 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:02 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:02 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:02 volumio volumio[2797]: info: sendMpdCommand status took 4 milliseconds Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:02 volumio volumio[2797]: info: sendMpdCommand status took 3 milliseconds Apr 16 21:34:02 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:02 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:02 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:02 volumio volumio[2797]: info: CorePlayQueue::getTrack 19 Apr 16 21:34:02 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.Lost Frequencies-Are You With Me(Radio Edit).wav","artist":null,"album":null,"uri":"NAS/ds918/单曲/02.Lost Frequencies-Are You With Me(Radio Edit).wav","trackType":"wav"} Apr 16 21:34:02 volumio volumio[2797]: verbose: CURRENT POSITION 19 Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:34:02 volumio volumio[2797]: info: ------------------------------ 10ms Apr 16 21:34:02 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 16 21:34:02 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:02 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:02 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:02 volumio volumio[2797]: info: CorePlayQueue::getTrack 19 Apr 16 21:34:02 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.Lost Frequencies-Are You With Me(Radio Edit).wav","artist":null,"album":null,"uri":"NAS/ds918/单曲/02.Lost Frequencies-Are You With Me(Radio Edit).wav","trackType":"wav"} Apr 16 21:34:02 volumio volumio[2797]: verbose: CURRENT POSITION 19 Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:34:02 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:02 volumio volumio[2797]: info: CorePlayQueue::getTrack 19 Apr 16 21:34:02 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:02 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:02 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:02 volumio volumio[2797]: info: CorePlayQueue::getTrack 19 Apr 16 21:34:02 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:02 volumio volumio[2797]: info: ------------------------------ 31ms Apr 16 21:34:03 volumio volumio[2797]: info: CoreCommandRouter::volumioGetState Apr 16 21:34:03 volumio volumio[2797]: info: CorePlayQueue::getTrack 19 Apr 16 21:34:04 volumio wpa_supplicant[724]: wlan0: Failed to initiate sched scan Apr 16 21:34:05 volumio volumio[2797]: info: CoreCommandRouter::volumioSeek Apr 16 21:34:05 volumio volumio[2797]: info: CoreStateMachine::seek Apr 16 21:34:05 volumio volumio[2797]: info: CorePlayQueue::getTrack 19 Apr 16 21:34:05 volumio volumio[2797]: info: TRACKBLOCK {"uri":"mnt/NAS/ds918/单曲/02.Lost Frequencies-Are You With Me(Radio Edit).wav","service":"mpd","name":"02.Lost Frequencies-Are You With Me(Radio Edit).wav","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=572&web=//extralarge&path=%2FNAS%2Fds918%2F%E5%8D%95%E6%9B%B2&metadata=false","duration":139,"trackType":"wav","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Apr 16 21:34:05 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:34:05 volumio volumio[2797]: info: CorePlayQueue::getTrack 19 Apr 16 21:34:05 volumio volumio[2797]: info: ControllerMpd::seek Apr 16 21:34:05 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:05 volumio volumio[2797]: info: CorePlayQueue::getTrack 19 Apr 16 21:34:05 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:05 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:06 volumio volumio[2797]: error: null Apr 16 21:34:06 volumio volumio[2797]: info: Apr 16 21:34:06 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:06 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:06 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:06 volumio volumio[2797]: info: sendMpdCommand status took 1 milliseconds Apr 16 21:34:06 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:06 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:06 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 16 21:34:06 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:06 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:06 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:06 volumio volumio[2797]: info: CorePlayQueue::getTrack 19 Apr 16 21:34:06 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":56000,"duration":139,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.Lost Frequencies-Are You With Me(Radio Edit).wav","artist":null,"album":null,"uri":"NAS/ds918/单曲/02.Lost Frequencies-Are You With Me(Radio Edit).wav","trackType":"wav"} Apr 16 21:34:06 volumio volumio[2797]: verbose: CURRENT POSITION 19 Apr 16 21:34:06 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:06 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:34:06 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:34:06 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:06 volumio volumio[2797]: info: CorePlayQueue::getTrack 19 Apr 16 21:34:06 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:06 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:06 volumio volumio[2797]: info: CorePlayQueue::getTrack 19 Apr 16 21:34:06 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:06 volumio volumio[2797]: info: ------------------------------ 24ms Apr 16 21:34:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:09 volumio volumio[2797]: info: CoreCommandRouter::volumioNext Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::next Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::stop Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::stPlaybackTimer Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:34:09 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:09 volumio volumio[2797]: info: CorePlayQueue::getTrack 19 Apr 16 21:34:09 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:09 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::serviceStop Apr 16 21:34:09 volumio volumio[2797]: info: CorePlayQueue::getTrack 19 Apr 16 21:34:09 volumio volumio[2797]: info: CoreCommandRouter::serviceStop Apr 16 21:34:09 volumio volumio[2797]: info: ControllerMpd::stop Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:34:09 volumio volumio[2797]: info: Apr 16 21:34:09 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:09 volumio volumio[2797]: info: sendMpdCommand stop took 45 milliseconds Apr 16 21:34:09 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::play index undefined Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:34:09 volumio volumio[2797]: info: CorePlayQueue::getTrack 20 Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:34:09 volumio volumio[2797]: info: CorePlayQueue::getTrack 20 Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::clearAddPlayTracks NAS/ds918/单曲/02.MKJ-Time.wav Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:34:09 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:34:09 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:34:09 volumio volumio[2797]: info: sendMpdCommand status took 4 milliseconds Apr 16 21:34:09 volumio volumio[2797]: info: sendMpdCommand stop took 3 milliseconds Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 21:34:09 volumio volumio[2797]: info: Apr 16 21:34:09 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:34:09 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:34:09 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 16 21:34:09 volumio volumio[2797]: info: sendMpdCommand clear took 3 milliseconds Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand add "NAS/ds918/单曲/02.MKJ-Time.wav" Apr 16 21:34:09 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:09 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:09 volumio volumio[2797]: info: CorePlayQueue::getTrack 20 Apr 16 21:34:09 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:09 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:09 volumio volumio[2797]: info: CorePlayQueue::getTrack 20 Apr 16 21:34:09 volumio volumio[2797]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.Lost Frequencies-Are You With Me(Radio Edit).wav","artist":null,"album":null,"uri":"NAS/ds918/单曲/02.Lost Frequencies-Are You With Me(Radio Edit).wav","trackType":"wav"} Apr 16 21:34:09 volumio volumio[2797]: verbose: CURRENT POSITION 20 Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::syncState stateService stop Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:09 volumio volumio[2797]: info: CorePlayQueue::getTrack 20 Apr 16 21:34:09 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:09 volumio volumio[2797]: info: No code Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:09 volumio volumio[2797]: info: CorePlayQueue::getTrack 20 Apr 16 21:34:09 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:09 volumio wpa_supplicant[724]: wlan0: Failed to initiate sched scan Apr 16 21:34:09 volumio volumio[2797]: info: ------------------------------ 46ms Apr 16 21:34:09 volumio volumio[2797]: error: updateQueue error: null Apr 16 21:34:09 volumio volumio[2797]: info: Apr 16 21:34:09 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:34:09 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:34:09 volumio volumio[2797]: info: ------------------------------ 42ms Apr 16 21:34:09 volumio volumio[2797]: info: sendMpdCommand add "NAS/ds918/单曲/02.MKJ-Time.wav" took 40 milliseconds Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand play Apr 16 21:34:09 volumio volumio[2797]: info: ------------------------------ 14ms Apr 16 21:34:09 volumio volumio[2797]: info: sendMpdCommand play took 13 milliseconds Apr 16 21:34:09 volumio volumio[2797]: info: Apr 16 21:34:09 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:09 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:09 volumio volumio[2797]: info: Apr 16 21:34:09 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:09 volumio volumio[2797]: info: sendMpdCommand status took 4 milliseconds Apr 16 21:34:09 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:09 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:09 volumio volumio[2797]: info: sendMpdCommand status took 4 milliseconds Apr 16 21:34:09 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:09 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:09 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:09 volumio volumio[2797]: info: CorePlayQueue::getTrack 20 Apr 16 21:34:09 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.MKJ-Time.wav","artist":null,"album":null,"uri":"NAS/ds918/单曲/02.MKJ-Time.wav","trackType":"wav"} Apr 16 21:34:09 volumio volumio[2797]: verbose: CURRENT POSITION 20 Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:34:09 volumio volumio[2797]: info: ------------------------------ 15ms Apr 16 21:34:09 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 16 21:34:09 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:09 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:09 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:09 volumio volumio[2797]: info: CorePlayQueue::getTrack 20 Apr 16 21:34:09 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":365,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.MKJ-Time.wav","artist":null,"album":null,"uri":"NAS/ds918/单曲/02.MKJ-Time.wav","trackType":"wav"} Apr 16 21:34:09 volumio volumio[2797]: verbose: CURRENT POSITION 20 Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:34:09 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:09 volumio volumio[2797]: info: CorePlayQueue::getTrack 20 Apr 16 21:34:09 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:09 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:09 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:09 volumio volumio[2797]: info: CorePlayQueue::getTrack 20 Apr 16 21:34:09 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:09 volumio volumio[2797]: info: ------------------------------ 48ms Apr 16 21:34:11 volumio volumio[2797]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 16 21:34:11 volumio volumio[2797]: info: Retrying Login Due to Network Error Apr 16 21:34:11 volumio volumio[2797]: info: CoreCommandRouter::volumioNext Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::next Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::stop Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::stPlaybackTimer Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:34:11 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:11 volumio volumio[2797]: info: CorePlayQueue::getTrack 20 Apr 16 21:34:11 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:11 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::serviceStop Apr 16 21:34:11 volumio volumio[2797]: info: CorePlayQueue::getTrack 20 Apr 16 21:34:11 volumio volumio[2797]: info: CoreCommandRouter::serviceStop Apr 16 21:34:11 volumio volumio[2797]: info: ControllerMpd::stop Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:34:11 volumio volumio[2797]: info: Apr 16 21:34:11 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:11 volumio volumio[2797]: info: sendMpdCommand stop took 46 milliseconds Apr 16 21:34:11 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::play index undefined Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:34:11 volumio volumio[2797]: info: CorePlayQueue::getTrack 21 Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:34:11 volumio volumio[2797]: info: CorePlayQueue::getTrack 21 Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::clearAddPlayTracks NAS/ds918/单曲/02.Pascal Letoublon-Friendships(Original Mix)(Remix).wav Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:34:11 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:34:11 volumio volumio[2797]: info: sendMpdCommand status took 4 milliseconds Apr 16 21:34:11 volumio volumio[2797]: info: sendMpdCommand stop took 1 milliseconds Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 21:34:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:11 volumio volumio[2797]: info: Apr 16 21:34:11 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:34:11 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:34:11 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 16 21:34:11 volumio volumio[2797]: info: sendMpdCommand clear took 3 milliseconds Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand add "NAS/ds918/单曲/02.Pascal Letoublon-Friendships(Original Mix)(Remix).wav" Apr 16 21:34:11 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:11 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:11 volumio volumio[2797]: info: CorePlayQueue::getTrack 21 Apr 16 21:34:11 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:11 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:11 volumio volumio[2797]: info: CorePlayQueue::getTrack 21 Apr 16 21:34:11 volumio volumio[2797]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.MKJ-Time.wav","artist":null,"album":null,"uri":"NAS/ds918/单曲/02.MKJ-Time.wav","trackType":"wav"} Apr 16 21:34:11 volumio volumio[2797]: verbose: CURRENT POSITION 21 Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::syncState stateService stop Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:11 volumio volumio[2797]: info: CorePlayQueue::getTrack 21 Apr 16 21:34:11 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:11 volumio volumio[2797]: info: No code Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:11 volumio volumio[2797]: info: CorePlayQueue::getTrack 21 Apr 16 21:34:11 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:11 volumio volumio[2797]: info: ------------------------------ 50ms Apr 16 21:34:11 volumio volumio[2797]: error: updateQueue error: null Apr 16 21:34:11 volumio volumio[2797]: info: Apr 16 21:34:11 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:34:11 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:34:11 volumio volumio[2797]: info: ------------------------------ 45ms Apr 16 21:34:11 volumio volumio[2797]: info: sendMpdCommand add "NAS/ds918/单曲/02.Pascal Letoublon-Friendships(Original Mix)(Remix).wav" took 43 milliseconds Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand play Apr 16 21:34:11 volumio volumio[2797]: info: ------------------------------ 12ms Apr 16 21:34:11 volumio volumio[2797]: info: sendMpdCommand play took 11 milliseconds Apr 16 21:34:11 volumio volumio[2797]: info: Apr 16 21:34:11 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:11 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:11 volumio volumio[2797]: info: Apr 16 21:34:11 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:11 volumio volumio[2797]: info: sendMpdCommand status took 4 milliseconds Apr 16 21:34:11 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:11 volumio volumio[2797]: info: sendMpdCommand status took 4 milliseconds Apr 16 21:34:11 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:11 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:11 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:11 volumio volumio[2797]: info: CorePlayQueue::getTrack 21 Apr 16 21:34:11 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":242,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.Pascal Letoublon-Friendships(Original Mix)(Remix).wav","artist":null,"album":null,"uri":"NAS/ds918/单曲/02.Pascal Letoublon-Friendships(Original Mix)(Remix).wav","trackType":"wav"} Apr 16 21:34:11 volumio volumio[2797]: verbose: CURRENT POSITION 21 Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:34:11 volumio volumio[2797]: info: ------------------------------ 19ms Apr 16 21:34:11 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 16 21:34:11 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:11 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:11 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:11 volumio volumio[2797]: info: CorePlayQueue::getTrack 21 Apr 16 21:34:11 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":242,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.Pascal Letoublon-Friendships(Original Mix)(Remix).wav","artist":null,"album":null,"uri":"NAS/ds918/单曲/02.Pascal Letoublon-Friendships(Original Mix)(Remix).wav","trackType":"wav"} Apr 16 21:34:11 volumio volumio[2797]: verbose: CURRENT POSITION 21 Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:34:11 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:11 volumio volumio[2797]: info: CorePlayQueue::getTrack 21 Apr 16 21:34:11 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:11 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:11 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:11 volumio volumio[2797]: info: CorePlayQueue::getTrack 21 Apr 16 21:34:11 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:11 volumio volumio[2797]: info: ------------------------------ 48ms Apr 16 21:34:13 volumio volumio[2797]: info: CoreCommandRouter::volumioGetState Apr 16 21:34:13 volumio volumio[2797]: info: CorePlayQueue::getTrack 21 Apr 16 21:34:13 volumio volumio[2797]: info: Listing playlists Apr 16 21:34:13 volumio volumio[2797]: info: Listing playlists Apr 16 21:34:13 volumio volumio[2797]: info: CoreCommandRouter::volumioNext Apr 16 21:34:13 volumio volumio[2797]: info: CoreStateMachine::next Apr 16 21:34:13 volumio volumio[2797]: info: CoreStateMachine::stop Apr 16 21:34:13 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:34:13 volumio volumio[2797]: info: CoreStateMachine::stPlaybackTimer Apr 16 21:34:13 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:34:13 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:34:13 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:13 volumio volumio[2797]: info: CorePlayQueue::getTrack 21 Apr 16 21:34:13 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:13 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:14 volumio volumio[2797]: info: CoreStateMachine::serviceStop Apr 16 21:34:14 volumio volumio[2797]: info: CorePlayQueue::getTrack 21 Apr 16 21:34:14 volumio volumio[2797]: info: CoreCommandRouter::serviceStop Apr 16 21:34:14 volumio volumio[2797]: info: ControllerMpd::stop Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:34:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:14 volumio volumio[2797]: info: Apr 16 21:34:14 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:14 volumio volumio[2797]: info: sendMpdCommand stop took 94 milliseconds Apr 16 21:34:14 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:14 volumio volumio[2797]: info: CoreStateMachine::play index undefined Apr 16 21:34:14 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:34:14 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:14 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:34:14 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::clearAddPlayTracks NAS/ds918/单曲/021. Enigma, Orkestra Fittipaldi - Rivers Of Belief (Radio Edit).mp3 Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:34:14 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:34:14 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:34:14 volumio volumio[2797]: info: sendMpdCommand status took 4 milliseconds Apr 16 21:34:14 volumio volumio[2797]: info: sendMpdCommand stop took 2 milliseconds Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 21:34:14 volumio volumio[2797]: info: Apr 16 21:34:14 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:34:14 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:34:14 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 21:34:14 volumio volumio[2797]: info: sendMpdCommand clear took 2 milliseconds Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand add "NAS/ds918/单曲/021. Enigma, Orkestra Fittipaldi - Rivers Of Belief (Radio Edit).mp3" Apr 16 21:34:14 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:14 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:14 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:14 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:14 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:14 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:14 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:14 volumio volumio[2797]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.Pascal Letoublon-Friendships(Original Mix)(Remix).wav","artist":null,"album":null,"uri":"NAS/ds918/单曲/02.Pascal Letoublon-Friendships(Original Mix)(Remix).wav","trackType":"wav"} Apr 16 21:34:14 volumio volumio[2797]: verbose: CURRENT POSITION 22 Apr 16 21:34:14 volumio volumio[2797]: info: CoreStateMachine::syncState stateService stop Apr 16 21:34:14 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:34:14 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:14 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:14 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:14 volumio volumio[2797]: info: No code Apr 16 21:34:14 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:14 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:14 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:14 volumio volumio[2797]: info: ------------------------------ 41ms Apr 16 21:34:14 volumio volumio[2797]: error: updateQueue error: null Apr 16 21:34:14 volumio volumio[2797]: info: Apr 16 21:34:14 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:34:14 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:34:14 volumio volumio[2797]: info: ------------------------------ 37ms Apr 16 21:34:14 volumio volumio[2797]: info: sendMpdCommand add "NAS/ds918/单曲/021. Enigma, Orkestra Fittipaldi - Rivers Of Belief (Radio Edit).mp3" took 35 milliseconds Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand play Apr 16 21:34:14 volumio volumio[2797]: info: ------------------------------ 14ms Apr 16 21:34:14 volumio volumio[2797]: info: sendMpdCommand play took 13 milliseconds Apr 16 21:34:14 volumio volumio[2797]: info: Apr 16 21:34:14 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:14 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:14 volumio volumio[2797]: info: Apr 16 21:34:14 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:14 volumio volumio[2797]: info: sendMpdCommand status took 9 milliseconds Apr 16 21:34:14 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:14 volumio volumio[2797]: info: sendMpdCommand status took 3 milliseconds Apr 16 21:34:14 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:14 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:14 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:14 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:14 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rivers Of Belief (Radio Edit)","artist":"Enigma, Orkestra Fittipaldi","album":"Best Of New Age","uri":"NAS/ds918/单曲/021. Enigma, Orkestra Fittipaldi - Rivers Of Belief (Radio Edit).mp3","trackType":"mp3"} Apr 16 21:34:14 volumio volumio[2797]: verbose: CURRENT POSITION 22 Apr 16 21:34:14 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:14 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:34:14 volumio volumio[2797]: info: ------------------------------ 20ms Apr 16 21:34:14 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 16 21:34:14 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:14 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:14 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:14 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:14 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rivers Of Belief (Radio Edit)","artist":"Enigma, Orkestra Fittipaldi","album":"Best Of New Age","uri":"NAS/ds918/单曲/021. Enigma, Orkestra Fittipaldi - Rivers Of Belief (Radio Edit).mp3","trackType":"mp3"} Apr 16 21:34:14 volumio volumio[2797]: verbose: CURRENT POSITION 22 Apr 16 21:34:14 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:14 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:34:14 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:34:14 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:14 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:14 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:14 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:14 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:14 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:14 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:14 volumio volumio[2797]: info: ------------------------------ 43ms Apr 16 21:34:15 volumio wpa_supplicant[724]: wlan0: Failed to initiate sched scan Apr 16 21:34:17 volumio volumio[2797]: info: CoreCommandRouter::volumioSeek Apr 16 21:34:17 volumio volumio[2797]: info: CoreStateMachine::seek Apr 16 21:34:17 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:17 volumio volumio[2797]: info: TRACKBLOCK {"uri":"mnt/NAS/ds918/单曲/021. Enigma, Orkestra Fittipaldi - Rivers Of Belief (Radio Edit).mp3","service":"mpd","name":"Rivers Of Belief (Radio Edit)","artist":"Enigma, Orkestra Fittipaldi","album":"Best Of New Age","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=572&web=Enigma%2C%20Orkestra%20Fittipaldi/Best%20Of%20New%20Age/extralarge&path=%2FNAS%2Fds918%2F%E5%8D%95%E6%9B%B2&metadata=false","duration":264,"trackType":"mp3","samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2} Apr 16 21:34:17 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:34:17 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:17 volumio volumio[2797]: info: ControllerMpd::seek Apr 16 21:34:17 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:17 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:17 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:17 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:17 volumio volumio[2797]: error: null Apr 16 21:34:17 volumio volumio[2797]: info: Apr 16 21:34:17 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:17 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:17 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:17 volumio volumio[2797]: info: sendMpdCommand status took 1 milliseconds Apr 16 21:34:17 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:17 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:17 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 16 21:34:17 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:17 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:17 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:17 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:17 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":66000,"duration":264,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Rivers Of Belief (Radio Edit)","artist":"Enigma, Orkestra Fittipaldi","album":"Best Of New Age","uri":"NAS/ds918/单曲/021. Enigma, Orkestra Fittipaldi - Rivers Of Belief (Radio Edit).mp3","trackType":"mp3"} Apr 16 21:34:17 volumio volumio[2797]: verbose: CURRENT POSITION 22 Apr 16 21:34:17 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:17 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:34:17 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:34:17 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:17 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:17 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:17 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:17 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:17 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:17 volumio volumio[2797]: info: ------------------------------ 27ms Apr 16 21:34:17 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:20 volumio volumio[2797]: info: CoreCommandRouter::volumioNext Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::next Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::stop Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::stPlaybackTimer Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:34:20 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:20 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:20 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:20 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::serviceStop Apr 16 21:34:20 volumio volumio[2797]: info: CorePlayQueue::getTrack 22 Apr 16 21:34:20 volumio volumio[2797]: info: CoreCommandRouter::serviceStop Apr 16 21:34:20 volumio volumio[2797]: info: ControllerMpd::stop Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:34:20 volumio volumio[2797]: info: Apr 16 21:34:20 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:20 volumio volumio[2797]: info: sendMpdCommand stop took 19 milliseconds Apr 16 21:34:20 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::play index undefined Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:34:20 volumio volumio[2797]: info: CorePlayQueue::getTrack 23 Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:34:20 volumio volumio[2797]: info: CorePlayQueue::getTrack 23 Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::clearAddPlayTracks NAS/ds918/单曲/023. Gregorian - One of Us.mp3 Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:34:20 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:34:20 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:34:20 volumio volumio[2797]: info: sendMpdCommand status took 3 milliseconds Apr 16 21:34:20 volumio volumio[2797]: info: sendMpdCommand stop took 2 milliseconds Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 21:34:20 volumio volumio[2797]: info: Apr 16 21:34:20 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:34:20 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:34:20 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 21:34:20 volumio volumio[2797]: info: sendMpdCommand clear took 2 milliseconds Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand add "NAS/ds918/单曲/023. Gregorian - One of Us.mp3" Apr 16 21:34:20 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:20 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:20 volumio volumio[2797]: info: CorePlayQueue::getTrack 23 Apr 16 21:34:20 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:20 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:20 volumio volumio[2797]: info: CorePlayQueue::getTrack 23 Apr 16 21:34:20 volumio volumio[2797]: 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":"Rivers Of Belief (Radio Edit)","artist":"Enigma, Orkestra Fittipaldi","album":"Best Of New Age","uri":"NAS/ds918/单曲/021. Enigma, Orkestra Fittipaldi - Rivers Of Belief (Radio Edit).mp3","trackType":"mp3"} Apr 16 21:34:20 volumio volumio[2797]: verbose: CURRENT POSITION 23 Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::syncState stateService stop Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:20 volumio volumio[2797]: info: CorePlayQueue::getTrack 23 Apr 16 21:34:20 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:20 volumio volumio[2797]: info: No code Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:20 volumio volumio[2797]: info: CorePlayQueue::getTrack 23 Apr 16 21:34:20 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:20 volumio volumio[2797]: info: ------------------------------ 38ms Apr 16 21:34:20 volumio volumio[2797]: error: updateQueue error: null Apr 16 21:34:20 volumio volumio[2797]: info: Apr 16 21:34:20 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:34:20 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:34:20 volumio volumio[2797]: info: ------------------------------ 34ms Apr 16 21:34:20 volumio volumio[2797]: info: sendMpdCommand add "NAS/ds918/单曲/023. Gregorian - One of Us.mp3" took 32 milliseconds Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand play Apr 16 21:34:20 volumio volumio[2797]: info: Apr 16 21:34:20 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:20 volumio volumio[2797]: info: ------------------------------ 21ms Apr 16 21:34:20 volumio volumio[2797]: info: sendMpdCommand play took 19 milliseconds Apr 16 21:34:20 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:20 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:20 volumio volumio[2797]: info: Apr 16 21:34:20 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:20 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:20 volumio volumio[2797]: info: sendMpdCommand status took 7 milliseconds Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:20 volumio volumio[2797]: info: sendMpdCommand status took 51 milliseconds Apr 16 21:34:20 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 51 milliseconds Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:20 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:20 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:20 volumio volumio[2797]: info: CorePlayQueue::getTrack 23 Apr 16 21:34:20 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One of Us","artist":"Gregorian","album":"Best Of New Age","uri":"NAS/ds918/单曲/023. Gregorian - One of Us.mp3","trackType":"mp3"} Apr 16 21:34:20 volumio volumio[2797]: verbose: CURRENT POSITION 23 Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:34:20 volumio volumio[2797]: info: ------------------------------ 63ms Apr 16 21:34:20 volumio volumio[2797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Gregorian/Best%20Of%20New%20Age/6c15b5d8-1e5e-484a-b4ea-39d6a85cb97f.jpg' Apr 16 21:34:20 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 16 21:34:20 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:20 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:20 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:20 volumio volumio[2797]: info: CorePlayQueue::getTrack 23 Apr 16 21:34:20 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One of Us","artist":"Gregorian","album":"Best Of New Age","uri":"NAS/ds918/单曲/023. Gregorian - One of Us.mp3","trackType":"mp3"} Apr 16 21:34:20 volumio volumio[2797]: verbose: CURRENT POSITION 23 Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:34:20 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:20 volumio volumio[2797]: info: CorePlayQueue::getTrack 23 Apr 16 21:34:20 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:20 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:20 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:20 volumio volumio[2797]: info: CorePlayQueue::getTrack 23 Apr 16 21:34:20 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:20 volumio volumio[2797]: info: ------------------------------ 89ms Apr 16 21:34:20 volumio volumio[2797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Gregorian/Best%20Of%20New%20Age/6c15b5d8-1e5e-484a-b4ea-39d6a85cb97f.jpg' Apr 16 21:34:21 volumio volumio[2797]: info: MyVolumio login type: Token Apr 16 21:34:21 volumio wpa_supplicant[724]: wlan0: Failed to initiate sched scan Apr 16 21:34:22 volumio volumio[2797]: info: CoreCommandRouter::volumioNext Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::next Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::stop Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::stPlaybackTimer Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:34:22 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:22 volumio volumio[2797]: info: CorePlayQueue::getTrack 23 Apr 16 21:34:22 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:22 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::serviceStop Apr 16 21:34:22 volumio volumio[2797]: info: CorePlayQueue::getTrack 23 Apr 16 21:34:22 volumio volumio[2797]: info: CoreCommandRouter::serviceStop Apr 16 21:34:22 volumio volumio[2797]: info: ControllerMpd::stop Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:34:22 volumio volumio[2797]: info: Apr 16 21:34:22 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:22 volumio volumio[2797]: info: sendMpdCommand stop took 17 milliseconds Apr 16 21:34:22 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::play index undefined Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:34:22 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:34:22 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::clearAddPlayTracks NAS/ds918/单曲/03. 03.一生爱你千百回.wav Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:34:22 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:34:22 volumio volumio[2797]: info: sendMpdCommand status took 3 milliseconds Apr 16 21:34:22 volumio volumio[2797]: info: sendMpdCommand stop took 2 milliseconds Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 21:34:22 volumio volumio[2797]: info: Apr 16 21:34:22 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:34:22 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:34:22 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 21:34:22 volumio volumio[2797]: info: sendMpdCommand clear took 2 milliseconds Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand add "NAS/ds918/单曲/03. 03.一生爱你千百回.wav" Apr 16 21:34:22 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:22 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:22 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:22 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:22 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:22 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:22 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:22 volumio volumio[2797]: 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":"One of Us","artist":"Gregorian","album":"Best Of New Age","uri":"NAS/ds918/单曲/023. Gregorian - One of Us.mp3","trackType":"mp3"} Apr 16 21:34:22 volumio volumio[2797]: verbose: CURRENT POSITION 24 Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::syncState stateService stop Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:22 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:22 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:22 volumio volumio[2797]: info: No code Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:22 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:22 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:22 volumio volumio[2797]: info: ------------------------------ 39ms Apr 16 21:34:22 volumio volumio[2797]: error: updateQueue error: null Apr 16 21:34:22 volumio volumio[2797]: info: Apr 16 21:34:22 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:34:22 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:34:22 volumio volumio[2797]: info: ------------------------------ 34ms Apr 16 21:34:22 volumio volumio[2797]: info: sendMpdCommand add "NAS/ds918/单曲/03. 03.一生爱你千百回.wav" took 33 milliseconds Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand play Apr 16 21:34:22 volumio volumio[2797]: info: ------------------------------ 14ms Apr 16 21:34:22 volumio volumio[2797]: info: sendMpdCommand play took 13 milliseconds Apr 16 21:34:22 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:22 volumio volumio[2797]: info: Apr 16 21:34:22 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:22 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:22 volumio volumio[2797]: info: Apr 16 21:34:22 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:22 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:22 volumio volumio[2797]: info: sendMpdCommand status took 11 milliseconds Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:22 volumio volumio[2797]: info: sendMpdCommand status took 37 milliseconds Apr 16 21:34:22 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 36 milliseconds Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:22 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:22 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:22 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:22 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03.???????","artist":"??","album":"???????CD4","uri":"NAS/ds918/单曲/03. 03.一生爱你千百回.wav","trackType":"wav"} Apr 16 21:34:22 volumio volumio[2797]: verbose: CURRENT POSITION 24 Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:34:22 volumio volumio[2797]: info: ------------------------------ 52ms Apr 16 21:34:22 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 16 21:34:22 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:22 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:22 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:22 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:22 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03.???????","artist":"??","album":"???????CD4","uri":"NAS/ds918/单曲/03. 03.一生爱你千百回.wav","trackType":"wav"} Apr 16 21:34:22 volumio volumio[2797]: verbose: CURRENT POSITION 24 Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:34:22 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:22 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:22 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:22 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:22 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:22 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:22 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:22 volumio volumio[2797]: info: ------------------------------ 80ms Apr 16 21:34:23 volumio volumio[2797]: info: CoreCommandRouter::volumioGetState Apr 16 21:34:23 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:27 volumio wpa_supplicant[724]: wlan0: Failed to initiate sched scan Apr 16 21:34:28 volumio volumio[2797]: info: CoreCommandRouter::volumioSeek Apr 16 21:34:28 volumio volumio[2797]: info: CoreStateMachine::seek Apr 16 21:34:28 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:28 volumio volumio[2797]: info: TRACKBLOCK {"uri":"mnt/NAS/ds918/单曲/03. 03.一生爱你千百回.wav","service":"mpd","name":"03.???????","artist":"??","album":"???????CD4","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=572&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3FCD4/extralarge&path=%2FNAS%2Fds918%2F%E5%8D%95%E6%9B%B2&metadata=false","duration":287,"trackType":"wav","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Apr 16 21:34:28 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:34:28 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:28 volumio volumio[2797]: info: ControllerMpd::seek Apr 16 21:34:28 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:28 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:28 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:28 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:28 volumio volumio[2797]: error: null Apr 16 21:34:28 volumio volumio[2797]: info: Apr 16 21:34:28 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:28 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:28 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:28 volumio volumio[2797]: info: sendMpdCommand status took 1 milliseconds Apr 16 21:34:28 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:28 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:28 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 16 21:34:28 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:28 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:28 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:28 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:28 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53000,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03.???????","artist":"??","album":"???????CD4","uri":"NAS/ds918/单曲/03. 03.一生爱你千百回.wav","trackType":"wav"} Apr 16 21:34:28 volumio volumio[2797]: verbose: CURRENT POSITION 24 Apr 16 21:34:28 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:28 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:34:28 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:34:28 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:28 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:28 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:28 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:28 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:28 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:28 volumio volumio[2797]: info: ------------------------------ 36ms Apr 16 21:34:28 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:30 volumio volumio[2797]: info: CoreCommandRouter::volumioSeek Apr 16 21:34:30 volumio volumio[2797]: info: CoreStateMachine::seek Apr 16 21:34:30 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:30 volumio volumio[2797]: info: TRACKBLOCK {"uri":"mnt/NAS/ds918/单曲/03. 03.一生爱你千百回.wav","service":"mpd","name":"03.???????","artist":"??","album":"???????CD4","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=572&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3FCD4/extralarge&path=%2FNAS%2Fds918%2F%E5%8D%95%E6%9B%B2&metadata=false","duration":287,"trackType":"wav","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Apr 16 21:34:30 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:34:30 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:30 volumio volumio[2797]: info: ControllerMpd::seek Apr 16 21:34:30 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:30 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:30 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:30 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:30 volumio volumio[2797]: error: null Apr 16 21:34:30 volumio volumio[2797]: info: Apr 16 21:34:30 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:30 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:30 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:30 volumio volumio[2797]: info: sendMpdCommand status took 1 milliseconds Apr 16 21:34:30 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:30 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:30 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 16 21:34:30 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:30 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:30 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:30 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:30 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":52000,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03.???????","artist":"??","album":"???????CD4","uri":"NAS/ds918/单曲/03. 03.一生爱你千百回.wav","trackType":"wav"} Apr 16 21:34:30 volumio volumio[2797]: verbose: CURRENT POSITION 24 Apr 16 21:34:30 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:30 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:34:30 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:34:30 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:30 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:30 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:30 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:30 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:30 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:30 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:30 volumio volumio[2797]: info: ------------------------------ 30ms Apr 16 21:34:33 volumio wpa_supplicant[724]: wlan0: Failed to initiate sched scan Apr 16 21:34:33 volumio volumio[2797]: info: CoreCommandRouter::volumioGetState Apr 16 21:34:33 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:33 volumio volumio[2797]: info: Listing playlists Apr 16 21:34:33 volumio volumio[2797]: info: Listing playlists Apr 16 21:34:38 volumio wpa_supplicant[724]: wlan0: Failed to initiate sched scan Apr 16 21:34:43 volumio volumio[2797]: info: CoreCommandRouter::volumioGetState Apr 16 21:34:43 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:44 volumio wpa_supplicant[724]: wlan0: Failed to initiate sched scan Apr 16 21:34:50 volumio wpa_supplicant[724]: wlan0: Failed to initiate sched scan Apr 16 21:34:51 volumio volumio[2797]: info: CoreCommandRouter::volumioNext Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::next Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::stop Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::stPlaybackTimer Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:34:51 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:51 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:51 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:51 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::serviceStop Apr 16 21:34:51 volumio volumio[2797]: info: CorePlayQueue::getTrack 24 Apr 16 21:34:51 volumio volumio[2797]: info: CoreCommandRouter::serviceStop Apr 16 21:34:51 volumio volumio[2797]: info: ControllerMpd::stop Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:34:51 volumio volumio[2797]: info: Apr 16 21:34:51 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:51 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:51 volumio volumio[2797]: info: sendMpdCommand stop took 101 milliseconds Apr 16 21:34:51 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::play index undefined Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:34:51 volumio volumio[2797]: info: CorePlayQueue::getTrack 25 Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:34:51 volumio volumio[2797]: info: CorePlayQueue::getTrack 25 Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::clearAddPlayTracks NAS/ds918/单曲/03. Lost Frequencies - Are You With Me Radio Edit).flac Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:34:51 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:34:51 volumio volumio[2797]: info: sendMpdCommand status took 4 milliseconds Apr 16 21:34:51 volumio volumio[2797]: info: sendMpdCommand stop took 2 milliseconds Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 21:34:51 volumio volumio[2797]: info: Apr 16 21:34:51 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:34:51 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:34:51 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 16 21:34:51 volumio volumio[2797]: info: sendMpdCommand clear took 3 milliseconds Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand add "NAS/ds918/单曲/03. Lost Frequencies - Are You With Me Radio Edit).flac" Apr 16 21:34:51 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:51 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:51 volumio volumio[2797]: info: CorePlayQueue::getTrack 25 Apr 16 21:34:51 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:51 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:51 volumio volumio[2797]: info: CorePlayQueue::getTrack 25 Apr 16 21:34:51 volumio volumio[2797]: 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":"03.???????","artist":"??","album":"???????CD4","uri":"NAS/ds918/单曲/03. 03.一生爱你千百回.wav","trackType":"wav"} Apr 16 21:34:51 volumio volumio[2797]: verbose: CURRENT POSITION 25 Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::syncState stateService stop Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:51 volumio volumio[2797]: info: CorePlayQueue::getTrack 25 Apr 16 21:34:51 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:51 volumio volumio[2797]: info: No code Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:51 volumio volumio[2797]: info: CorePlayQueue::getTrack 25 Apr 16 21:34:51 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:51 volumio volumio[2797]: info: ------------------------------ 60ms Apr 16 21:34:51 volumio volumio[2797]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 16 21:34:51 volumio volumio[2797]: info: Retrying Login Due to Network Error Apr 16 21:34:51 volumio volumio[2797]: error: updateQueue error: null Apr 16 21:34:51 volumio volumio[2797]: info: Apr 16 21:34:51 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:34:51 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:34:51 volumio volumio[2797]: info: ------------------------------ 52ms Apr 16 21:34:51 volumio volumio[2797]: info: sendMpdCommand add "NAS/ds918/单曲/03. Lost Frequencies - Are You With Me Radio Edit).flac" took 50 milliseconds Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand play Apr 16 21:34:51 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:51 volumio volumio[2797]: info: Apr 16 21:34:51 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:51 volumio volumio[2797]: info: ------------------------------ 28ms Apr 16 21:34:51 volumio volumio[2797]: info: sendMpdCommand play took 24 milliseconds Apr 16 21:34:51 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:51 volumio volumio[2797]: info: Apr 16 21:34:51 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:51 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:51 volumio volumio[2797]: info: sendMpdCommand status took 3 milliseconds Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:51 volumio volumio[2797]: info: sendMpdCommand status took 6 milliseconds Apr 16 21:34:51 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:51 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:51 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:51 volumio volumio[2797]: info: CorePlayQueue::getTrack 25 Apr 16 21:34:51 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"370 Kbps","isStreaming":false,"title":"Are You With Me Radio Edit)","artist":"Lost Frequencies","album":"Club Classics - The History Of House Vol. 01","uri":"NAS/ds918/单曲/03. Lost Frequencies - Are You With Me Radio Edit).flac","trackType":"flac"} Apr 16 21:34:51 volumio volumio[2797]: verbose: CURRENT POSITION 25 Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:34:51 volumio volumio[2797]: info: ------------------------------ 12ms Apr 16 21:34:51 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 85 milliseconds Apr 16 21:34:51 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:51 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:51 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:51 volumio volumio[2797]: info: CorePlayQueue::getTrack 25 Apr 16 21:34:51 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"370 Kbps","isStreaming":false,"title":"Are You With Me Radio Edit)","artist":"Lost Frequencies","album":"Club Classics - The History Of House Vol. 01","uri":"NAS/ds918/单曲/03. Lost Frequencies - Are You With Me Radio Edit).flac","trackType":"flac"} Apr 16 21:34:51 volumio volumio[2797]: verbose: CURRENT POSITION 25 Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:34:51 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:51 volumio volumio[2797]: info: CorePlayQueue::getTrack 25 Apr 16 21:34:51 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:51 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:51 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:51 volumio volumio[2797]: info: CorePlayQueue::getTrack 25 Apr 16 21:34:51 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:51 volumio volumio[2797]: info: ------------------------------ 112ms Apr 16 21:34:53 volumio volumio[2797]: info: CoreCommandRouter::volumioGetState Apr 16 21:34:53 volumio volumio[2797]: info: CorePlayQueue::getTrack 25 Apr 16 21:34:53 volumio volumio[2797]: info: Listing playlists Apr 16 21:34:53 volumio volumio[2797]: info: Listing playlists Apr 16 21:34:53 volumio volumio[2797]: info: CoreCommandRouter::volumioNext Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::next Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::stop Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::stPlaybackTimer Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:34:53 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:53 volumio volumio[2797]: info: CorePlayQueue::getTrack 25 Apr 16 21:34:53 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:53 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::serviceStop Apr 16 21:34:53 volumio volumio[2797]: info: CorePlayQueue::getTrack 25 Apr 16 21:34:53 volumio volumio[2797]: info: CoreCommandRouter::serviceStop Apr 16 21:34:53 volumio volumio[2797]: info: ControllerMpd::stop Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:34:53 volumio volumio[2797]: info: Apr 16 21:34:53 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:53 volumio volumio[2797]: info: sendMpdCommand stop took 98 milliseconds Apr 16 21:34:53 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::play index undefined Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:34:53 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:34:53 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::clearAddPlayTracks NAS/ds918/单曲/03. 在那遥远的地方.wav Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:34:53 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:34:53 volumio volumio[2797]: info: sendMpdCommand status took 3 milliseconds Apr 16 21:34:53 volumio volumio[2797]: info: sendMpdCommand stop took 2 milliseconds Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 21:34:53 volumio volumio[2797]: info: Apr 16 21:34:53 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:34:53 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:34:53 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 21:34:53 volumio volumio[2797]: info: sendMpdCommand clear took 1 milliseconds Apr 16 21:34:53 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand add "NAS/ds918/单曲/03. 在那遥远的地方.wav" Apr 16 21:34:53 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:53 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:53 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:34:53 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:53 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:53 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:34:53 volumio volumio[2797]: 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":"Are You With Me Radio Edit)","artist":"Lost Frequencies","album":"Club Classics - The History Of House Vol. 01","uri":"NAS/ds918/单曲/03. Lost Frequencies - Are You With Me Radio Edit).flac","trackType":"flac"} Apr 16 21:34:53 volumio volumio[2797]: verbose: CURRENT POSITION 26 Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::syncState stateService stop Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:53 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:34:53 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:53 volumio volumio[2797]: info: No code Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:53 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:34:53 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:53 volumio volumio[2797]: info: ------------------------------ 52ms Apr 16 21:34:53 volumio volumio[2797]: error: updateQueue error: null Apr 16 21:34:53 volumio volumio[2797]: info: Apr 16 21:34:53 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:34:53 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:34:53 volumio volumio[2797]: info: ------------------------------ 48ms Apr 16 21:34:53 volumio volumio[2797]: info: sendMpdCommand add "NAS/ds918/单曲/03. 在那遥远的地方.wav" took 48 milliseconds Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand play Apr 16 21:34:53 volumio volumio[2797]: info: ------------------------------ 23ms Apr 16 21:34:53 volumio volumio[2797]: info: sendMpdCommand play took 23 milliseconds Apr 16 21:34:53 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:34:53 volumio volumio[2797]: info: Apr 16 21:34:53 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:53 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:53 volumio volumio[2797]: info: Apr 16 21:34:53 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:53 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:53 volumio volumio[2797]: info: sendMpdCommand status took 3 milliseconds Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:53 volumio volumio[2797]: info: sendMpdCommand status took 3 milliseconds Apr 16 21:34:53 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:53 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:53 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:53 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:34:53 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"??[linm??]","album":"????????","uri":"NAS/ds918/单曲/03. 在那遥远的地方.wav","trackType":"wav"} Apr 16 21:34:53 volumio volumio[2797]: verbose: CURRENT POSITION 26 Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:34:53 volumio volumio[2797]: info: ------------------------------ 14ms Apr 16 21:34:53 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 16 21:34:53 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:53 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:53 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:53 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:34:53 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"??[linm??]","album":"????????","uri":"NAS/ds918/单曲/03. 在那遥远的地方.wav","trackType":"wav"} Apr 16 21:34:53 volumio volumio[2797]: verbose: CURRENT POSITION 26 Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:34:53 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:53 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:34:53 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:53 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:53 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:53 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:34:53 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:53 volumio volumio[2797]: info: ------------------------------ 40ms Apr 16 21:34:56 volumio wpa_supplicant[724]: wlan0: Failed to initiate sched scan Apr 16 21:34:57 volumio volumio[2797]: info: CoreCommandRouter::volumioSeek Apr 16 21:34:57 volumio volumio[2797]: info: CoreStateMachine::seek Apr 16 21:34:57 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:34:57 volumio volumio[2797]: info: TRACKBLOCK {"uri":"mnt/NAS/ds918/单曲/03. 在那遥远的地方.wav","service":"mpd","name":"???????","artist":"??[linm??]","album":"????????","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=572&web=%3F%3F%5Blinm%3F%3F%5D/%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2FNAS%2Fds918%2F%E5%8D%95%E6%9B%B2&metadata=false","duration":279,"trackType":"wav","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Apr 16 21:34:57 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:34:57 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:34:57 volumio volumio[2797]: info: ControllerMpd::seek Apr 16 21:34:57 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:57 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:34:57 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:34:57 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:57 volumio volumio[2797]: error: null Apr 16 21:34:57 volumio volumio[2797]: info: Apr 16 21:34:57 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:34:57 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:34:57 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:34:57 volumio volumio[2797]: info: sendMpdCommand status took 2 milliseconds Apr 16 21:34:57 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:34:57 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:34:57 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 16 21:34:57 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:34:57 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:34:57 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:34:57 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:34:57 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":100000,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"??[linm??]","album":"????????","uri":"NAS/ds918/单曲/03. 在那遥远的地方.wav","trackType":"wav"} Apr 16 21:34:57 volumio volumio[2797]: verbose: CURRENT POSITION 26 Apr 16 21:34:57 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:34:57 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:34:57 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:34:57 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:57 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:34:57 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:57 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:34:57 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:34:57 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:34:57 volumio volumio[2797]: info: ------------------------------ 26ms Apr 16 21:34:57 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:35:01 volumio volumio[2797]: info: MyVolumio login type: Token Apr 16 21:35:01 volumio volumio[2797]: info: CoreCommandRouter::volumioNext Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::next Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::stop Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::stPlaybackTimer Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:35:01 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:01 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:35:01 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:01 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::serviceStop Apr 16 21:35:01 volumio volumio[2797]: info: CorePlayQueue::getTrack 26 Apr 16 21:35:01 volumio volumio[2797]: info: CoreCommandRouter::serviceStop Apr 16 21:35:01 volumio volumio[2797]: info: ControllerMpd::stop Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:35:01 volumio volumio[2797]: info: Apr 16 21:35:01 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:01 volumio volumio[2797]: info: sendMpdCommand stop took 18 milliseconds Apr 16 21:35:01 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:01 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::play index undefined Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:35:01 volumio volumio[2797]: info: CorePlayQueue::getTrack 27 Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:35:01 volumio volumio[2797]: info: CorePlayQueue::getTrack 27 Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::clearAddPlayTracks NAS/ds918/单曲/03. 无聊的游戏.wav Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:35:01 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:35:01 volumio volumio[2797]: info: sendMpdCommand status took 3 milliseconds Apr 16 21:35:01 volumio volumio[2797]: info: sendMpdCommand stop took 2 milliseconds Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 21:35:01 volumio volumio[2797]: info: Apr 16 21:35:01 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:35:01 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:35:01 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 21:35:01 volumio volumio[2797]: info: sendMpdCommand clear took 2 milliseconds Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand add "NAS/ds918/单曲/03. 无聊的游戏.wav" Apr 16 21:35:01 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:01 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:01 volumio volumio[2797]: info: CorePlayQueue::getTrack 27 Apr 16 21:35:01 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:01 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:01 volumio volumio[2797]: info: CorePlayQueue::getTrack 27 Apr 16 21:35:01 volumio volumio[2797]: 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":"???????","artist":"??[linm??]","album":"????????","uri":"NAS/ds918/单曲/03. 在那遥远的地方.wav","trackType":"wav"} Apr 16 21:35:01 volumio volumio[2797]: verbose: CURRENT POSITION 27 Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::syncState stateService stop Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:01 volumio volumio[2797]: info: CorePlayQueue::getTrack 27 Apr 16 21:35:01 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:01 volumio volumio[2797]: info: No code Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:01 volumio volumio[2797]: info: CorePlayQueue::getTrack 27 Apr 16 21:35:01 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:01 volumio volumio[2797]: info: ------------------------------ 48ms Apr 16 21:35:01 volumio volumio[2797]: error: updateQueue error: null Apr 16 21:35:01 volumio volumio[2797]: info: Apr 16 21:35:01 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:35:01 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:35:01 volumio volumio[2797]: info: ------------------------------ 43ms Apr 16 21:35:01 volumio volumio[2797]: info: sendMpdCommand add "NAS/ds918/单曲/03. 无聊的游戏.wav" took 41 milliseconds Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand play Apr 16 21:35:01 volumio volumio[2797]: info: ------------------------------ 15ms Apr 16 21:35:01 volumio volumio[2797]: info: sendMpdCommand play took 14 milliseconds Apr 16 21:35:01 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:35:01 volumio volumio[2797]: info: Apr 16 21:35:01 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:01 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:01 volumio volumio[2797]: info: Apr 16 21:35:01 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:01 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:01 volumio volumio[2797]: info: sendMpdCommand status took 2 milliseconds Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:01 volumio volumio[2797]: info: sendMpdCommand status took 4 milliseconds Apr 16 21:35:01 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:01 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:01 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:01 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:01 volumio volumio[2797]: info: CorePlayQueue::getTrack 27 Apr 16 21:35:01 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":178,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???ĵ???Ϸ","artist":"??????","album":"??ChaCha???? ???龫??CD1","uri":"NAS/ds918/单曲/03. 无聊的游戏.wav","trackType":"wav"} Apr 16 21:35:01 volumio volumio[2797]: verbose: CURRENT POSITION 27 Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:35:01 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:35:01 volumio volumio[2797]: info: ------------------------------ 13ms Apr 16 21:35:02 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 37 milliseconds Apr 16 21:35:02 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:02 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:02 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:02 volumio volumio[2797]: info: CorePlayQueue::getTrack 27 Apr 16 21:35:02 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":178,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???ĵ???Ϸ","artist":"??????","album":"??ChaCha???? ???龫??CD1","uri":"NAS/ds918/单曲/03. 无聊的游戏.wav","trackType":"wav"} Apr 16 21:35:02 volumio volumio[2797]: verbose: CURRENT POSITION 27 Apr 16 21:35:02 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:35:02 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:35:02 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:35:02 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:02 volumio volumio[2797]: info: CorePlayQueue::getTrack 27 Apr 16 21:35:02 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:02 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:02 volumio wpa_supplicant[724]: wlan0: Failed to initiate sched scan Apr 16 21:35:02 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:02 volumio volumio[2797]: info: CorePlayQueue::getTrack 27 Apr 16 21:35:02 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:02 volumio volumio[2797]: info: ------------------------------ 79ms Apr 16 21:35:03 volumio volumio[2797]: info: CoreCommandRouter::volumioGetState Apr 16 21:35:03 volumio volumio[2797]: info: CorePlayQueue::getTrack 27 Apr 16 21:35:04 volumio volumio[2797]: info: CoreCommandRouter::volumioNext Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::next Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::stop Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::stPlaybackTimer Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:35:04 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:04 volumio volumio[2797]: info: CorePlayQueue::getTrack 27 Apr 16 21:35:04 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:04 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::serviceStop Apr 16 21:35:04 volumio volumio[2797]: info: CorePlayQueue::getTrack 27 Apr 16 21:35:04 volumio volumio[2797]: info: CoreCommandRouter::serviceStop Apr 16 21:35:04 volumio volumio[2797]: info: ControllerMpd::stop Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:35:04 volumio volumio[2797]: info: Apr 16 21:35:04 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:04 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:35:04 volumio volumio[2797]: info: sendMpdCommand stop took 62 milliseconds Apr 16 21:35:04 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::play index undefined Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:35:04 volumio volumio[2797]: info: CorePlayQueue::getTrack 28 Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:35:04 volumio volumio[2797]: info: CorePlayQueue::getTrack 28 Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::clearAddPlayTracks NAS/ds918/单曲/03.母亲.WAV Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:35:04 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:35:04 volumio volumio[2797]: info: sendMpdCommand status took 3 milliseconds Apr 16 21:35:04 volumio volumio[2797]: info: sendMpdCommand stop took 2 milliseconds Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 21:35:04 volumio volumio[2797]: info: Apr 16 21:35:04 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:35:04 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:35:04 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 21:35:04 volumio volumio[2797]: info: sendMpdCommand clear took 2 milliseconds Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand add "NAS/ds918/单曲/03.母亲.WAV" Apr 16 21:35:04 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:04 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:04 volumio volumio[2797]: info: CorePlayQueue::getTrack 28 Apr 16 21:35:04 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:04 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:04 volumio volumio[2797]: info: CorePlayQueue::getTrack 28 Apr 16 21:35:04 volumio volumio[2797]: 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":"???ĵ???Ϸ","artist":"??????","album":"??ChaCha???? ???龫??CD1","uri":"NAS/ds918/单曲/03. 无聊的游戏.wav","trackType":"wav"} Apr 16 21:35:04 volumio volumio[2797]: verbose: CURRENT POSITION 28 Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::syncState stateService stop Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:04 volumio volumio[2797]: info: CorePlayQueue::getTrack 28 Apr 16 21:35:04 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:04 volumio volumio[2797]: info: No code Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:04 volumio volumio[2797]: info: CorePlayQueue::getTrack 28 Apr 16 21:35:04 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:04 volumio volumio[2797]: info: ------------------------------ 38ms Apr 16 21:35:04 volumio volumio[2797]: error: updateQueue error: null Apr 16 21:35:04 volumio volumio[2797]: info: Apr 16 21:35:04 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:35:04 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:35:04 volumio volumio[2797]: info: ------------------------------ 34ms Apr 16 21:35:04 volumio volumio[2797]: info: sendMpdCommand add "NAS/ds918/单曲/03.母亲.WAV" took 32 milliseconds Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand play Apr 16 21:35:04 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:35:04 volumio volumio[2797]: info: Apr 16 21:35:04 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:04 volumio volumio[2797]: info: ------------------------------ 20ms Apr 16 21:35:04 volumio volumio[2797]: info: sendMpdCommand play took 19 milliseconds Apr 16 21:35:04 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:04 volumio volumio[2797]: info: Apr 16 21:35:04 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:04 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:04 volumio volumio[2797]: info: sendMpdCommand status took 2 milliseconds Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:04 volumio volumio[2797]: info: sendMpdCommand status took 4 milliseconds Apr 16 21:35:04 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:04 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:04 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:04 volumio volumio[2797]: info: CorePlayQueue::getTrack 28 Apr 16 21:35:04 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03.母亲.WAV","artist":null,"album":null,"uri":"NAS/ds918/单曲/03.母亲.WAV","trackType":"WAV"} Apr 16 21:35:04 volumio volumio[2797]: verbose: CURRENT POSITION 28 Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:35:04 volumio volumio[2797]: info: ------------------------------ 9ms Apr 16 21:35:04 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 145 milliseconds Apr 16 21:35:04 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:04 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:04 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:04 volumio volumio[2797]: info: CorePlayQueue::getTrack 28 Apr 16 21:35:04 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03.母亲.WAV","artist":null,"album":null,"uri":"NAS/ds918/单曲/03.母亲.WAV","trackType":"WAV"} Apr 16 21:35:04 volumio volumio[2797]: verbose: CURRENT POSITION 28 Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:35:04 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:04 volumio volumio[2797]: info: CorePlayQueue::getTrack 28 Apr 16 21:35:04 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:04 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:04 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:04 volumio volumio[2797]: info: CorePlayQueue::getTrack 28 Apr 16 21:35:04 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:04 volumio volumio[2797]: info: ------------------------------ 182ms Apr 16 21:35:06 volumio volumio[2797]: info: CoreCommandRouter::volumioNext Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::next Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::stop Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::stPlaybackTimer Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:35:06 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:06 volumio volumio[2797]: info: CorePlayQueue::getTrack 28 Apr 16 21:35:06 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:06 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::serviceStop Apr 16 21:35:06 volumio volumio[2797]: info: CorePlayQueue::getTrack 28 Apr 16 21:35:06 volumio volumio[2797]: info: CoreCommandRouter::serviceStop Apr 16 21:35:06 volumio volumio[2797]: info: ControllerMpd::stop Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:35:06 volumio volumio[2797]: info: Apr 16 21:35:06 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:06 volumio volumio[2797]: info: sendMpdCommand stop took 50 milliseconds Apr 16 21:35:06 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::play index undefined Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:35:06 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:35:06 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::clearAddPlayTracks NAS/ds918/单曲/04. Fraver & Born in '82 - Void in Space (Born in '82 l'amour Remix).flac Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:35:06 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:35:06 volumio volumio[2797]: info: sendMpdCommand status took 3 milliseconds Apr 16 21:35:06 volumio volumio[2797]: info: sendMpdCommand stop took 1 milliseconds Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 21:35:06 volumio volumio[2797]: info: Apr 16 21:35:06 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:35:06 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:35:06 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 21:35:06 volumio volumio[2797]: info: sendMpdCommand clear took 2 milliseconds Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand add "NAS/ds918/单曲/04. Fraver & Born in '82 - Void in Space (Born in '82 l'amour Remix).flac" Apr 16 21:35:06 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:06 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:06 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:06 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:06 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:06 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:06 volumio volumio[2797]: 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":"03.母亲.WAV","artist":null,"album":null,"uri":"NAS/ds918/单曲/03.母亲.WAV","trackType":"WAV"} Apr 16 21:35:06 volumio volumio[2797]: verbose: CURRENT POSITION 29 Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::syncState stateService stop Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:06 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:06 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:06 volumio volumio[2797]: info: No code Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:06 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:06 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:06 volumio volumio[2797]: info: ------------------------------ 39ms Apr 16 21:35:06 volumio volumio[2797]: error: updateQueue error: null Apr 16 21:35:06 volumio volumio[2797]: info: Apr 16 21:35:06 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:35:06 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:35:06 volumio volumio[2797]: info: ------------------------------ 35ms Apr 16 21:35:06 volumio volumio[2797]: info: sendMpdCommand add "NAS/ds918/单曲/04. Fraver & Born in '82 - Void in Space (Born in '82 l'amour Remix).flac" took 33 milliseconds Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand play Apr 16 21:35:06 volumio volumio[2797]: info: ------------------------------ 11ms Apr 16 21:35:06 volumio volumio[2797]: info: sendMpdCommand play took 10 milliseconds Apr 16 21:35:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:35:06 volumio volumio[2797]: info: Apr 16 21:35:06 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:06 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:06 volumio volumio[2797]: info: Apr 16 21:35:06 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:06 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:06 volumio volumio[2797]: info: sendMpdCommand status took 1 milliseconds Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:06 volumio volumio[2797]: info: sendMpdCommand status took 4 milliseconds Apr 16 21:35:06 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:06 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:06 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:06 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:06 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":314,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"622 Kbps","isStreaming":false,"title":"Void in Space (Born in '82 l'amour Remix)","artist":"Fraver & Born in '82","album":"Synthwave Dreams, Vol. 24","uri":"NAS/ds918/单曲/04. Fraver & Born in '82 - Void in Space (Born in '82 l'amour Remix).flac","trackType":"flac"} Apr 16 21:35:06 volumio volumio[2797]: verbose: CURRENT POSITION 29 Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:35:06 volumio volumio[2797]: info: ------------------------------ 9ms Apr 16 21:35:06 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 40 milliseconds Apr 16 21:35:06 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:06 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:06 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:06 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:06 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":314,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"622 Kbps","isStreaming":false,"title":"Void in Space (Born in '82 l'amour Remix)","artist":"Fraver & Born in '82","album":"Synthwave Dreams, Vol. 24","uri":"NAS/ds918/单曲/04. Fraver & Born in '82 - Void in Space (Born in '82 l'amour Remix).flac","trackType":"flac"} Apr 16 21:35:06 volumio volumio[2797]: verbose: CURRENT POSITION 29 Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:35:06 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:06 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:06 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:06 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:06 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:06 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:06 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:06 volumio volumio[2797]: info: ------------------------------ 66ms Apr 16 21:35:07 volumio wpa_supplicant[724]: wlan0: Failed to initiate sched scan Apr 16 21:35:08 volumio volumio[2797]: info: CoreCommandRouter::volumioSeek Apr 16 21:35:08 volumio volumio[2797]: info: CoreStateMachine::seek Apr 16 21:35:08 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:08 volumio volumio[2797]: info: TRACKBLOCK {"uri":"mnt/NAS/ds918/单曲/04. Fraver & Born in '82 - Void in Space (Born in '82 l'amour Remix).flac","service":"mpd","name":"Void in Space (Born in '82 l'amour Remix)","artist":"Fraver & Born in '82","album":"Synthwave Dreams, Vol. 24","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=572&web=Fraver%20%26%20Born%20in%20'82/Synthwave%20Dreams%2C%20Vol.%2024/extralarge&path=%2FNAS%2Fds918%2F%E5%8D%95%E6%9B%B2&metadata=false","duration":314,"trackType":"flac","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Apr 16 21:35:08 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:35:08 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:08 volumio volumio[2797]: info: ControllerMpd::seek Apr 16 21:35:08 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:08 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:08 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:08 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:08 volumio volumio[2797]: error: null Apr 16 21:35:08 volumio volumio[2797]: info: Apr 16 21:35:08 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:08 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:08 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:08 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:35:08 volumio volumio[2797]: info: sendMpdCommand status took 6 milliseconds Apr 16 21:35:08 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:08 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:08 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 21:35:08 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:08 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:08 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:08 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:08 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":74868,"duration":314,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"800 Kbps","isStreaming":false,"title":"Void in Space (Born in '82 l'amour Remix)","artist":"Fraver & Born in '82","album":"Synthwave Dreams, Vol. 24","uri":"NAS/ds918/单曲/04. Fraver & Born in '82 - Void in Space (Born in '82 l'amour Remix).flac","trackType":"flac"} Apr 16 21:35:08 volumio volumio[2797]: verbose: CURRENT POSITION 29 Apr 16 21:35:08 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:35:08 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:35:08 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:35:08 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:08 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:08 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:08 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:08 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:08 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:08 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:08 volumio volumio[2797]: info: ------------------------------ 114ms Apr 16 21:35:13 volumio volumio[2797]: info: CoreCommandRouter::volumioGetState Apr 16 21:35:13 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:13 volumio volumio[2797]: info: Listing playlists Apr 16 21:35:13 volumio volumio[2797]: info: Listing playlists Apr 16 21:35:13 volumio wpa_supplicant[724]: wlan0: Failed to initiate sched scan Apr 16 21:35:16 volumio volumio[2797]: info: CoreCommandRouter::volumioNext Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::next Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::stop Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::stPlaybackTimer Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:35:16 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:16 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:16 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:16 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::serviceStop Apr 16 21:35:16 volumio volumio[2797]: info: CorePlayQueue::getTrack 29 Apr 16 21:35:16 volumio volumio[2797]: info: CoreCommandRouter::serviceStop Apr 16 21:35:16 volumio volumio[2797]: info: ControllerMpd::stop Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:35:16 volumio volumio[2797]: info: Apr 16 21:35:16 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:16 volumio volumio[2797]: info: sendMpdCommand stop took 32 milliseconds Apr 16 21:35:16 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::play index undefined Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:35:16 volumio volumio[2797]: info: CorePlayQueue::getTrack 30 Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:35:16 volumio volumio[2797]: info: CorePlayQueue::getTrack 30 Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::clearAddPlayTracks NAS/ds918/单曲/04. Guy Sweens - Pathway to the Past.flac Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:35:16 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:35:16 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:35:16 volumio volumio[2797]: info: sendMpdCommand status took 6 milliseconds Apr 16 21:35:16 volumio volumio[2797]: info: sendMpdCommand stop took 4 milliseconds Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 21:35:16 volumio volumio[2797]: info: Apr 16 21:35:16 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:35:16 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:35:16 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 21:35:16 volumio volumio[2797]: info: sendMpdCommand clear took 2 milliseconds Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand add "NAS/ds918/单曲/04. Guy Sweens - Pathway to the Past.flac" Apr 16 21:35:16 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:16 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:16 volumio volumio[2797]: info: CorePlayQueue::getTrack 30 Apr 16 21:35:16 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:16 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:16 volumio volumio[2797]: info: CorePlayQueue::getTrack 30 Apr 16 21:35:16 volumio volumio[2797]: 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":"Void in Space (Born in '82 l'amour Remix)","artist":"Fraver & Born in '82","album":"Synthwave Dreams, Vol. 24","uri":"NAS/ds918/单曲/04. Fraver & Born in '82 - Void in Space (Born in '82 l'amour Remix).flac","trackType":"flac"} Apr 16 21:35:16 volumio volumio[2797]: verbose: CURRENT POSITION 30 Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::syncState stateService stop Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:16 volumio volumio[2797]: info: CorePlayQueue::getTrack 30 Apr 16 21:35:16 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:16 volumio volumio[2797]: info: No code Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:16 volumio volumio[2797]: info: CorePlayQueue::getTrack 30 Apr 16 21:35:16 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:16 volumio volumio[2797]: info: ------------------------------ 38ms Apr 16 21:35:16 volumio volumio[2797]: error: updateQueue error: null Apr 16 21:35:16 volumio volumio[2797]: info: Apr 16 21:35:16 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:35:16 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:35:16 volumio volumio[2797]: info: ------------------------------ 32ms Apr 16 21:35:16 volumio volumio[2797]: info: sendMpdCommand add "NAS/ds918/单曲/04. Guy Sweens - Pathway to the Past.flac" took 31 milliseconds Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand play Apr 16 21:35:16 volumio volumio[2797]: info: ------------------------------ 18ms Apr 16 21:35:16 volumio volumio[2797]: info: sendMpdCommand play took 16 milliseconds Apr 16 21:35:16 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:35:16 volumio volumio[2797]: info: Apr 16 21:35:16 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:16 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:16 volumio volumio[2797]: info: Apr 16 21:35:16 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:16 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:16 volumio volumio[2797]: info: sendMpdCommand status took 2 milliseconds Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:16 volumio volumio[2797]: info: sendMpdCommand status took 4 milliseconds Apr 16 21:35:16 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:16 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:16 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:16 volumio volumio[2797]: info: CorePlayQueue::getTrack 30 Apr 16 21:35:16 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":384,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"721 Kbps","isStreaming":false,"title":"Pathway to the Past","artist":"Guy Sweens","album":"Best New Age 4","uri":"NAS/ds918/单曲/04. Guy Sweens - Pathway to the Past.flac","trackType":"flac"} Apr 16 21:35:16 volumio volumio[2797]: verbose: CURRENT POSITION 30 Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:35:16 volumio volumio[2797]: info: ------------------------------ 9ms Apr 16 21:35:16 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 40 milliseconds Apr 16 21:35:16 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:16 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:16 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:16 volumio volumio[2797]: info: CorePlayQueue::getTrack 30 Apr 16 21:35:16 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":384,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"721 Kbps","isStreaming":false,"title":"Pathway to the Past","artist":"Guy Sweens","album":"Best New Age 4","uri":"NAS/ds918/单曲/04. Guy Sweens - Pathway to the Past.flac","trackType":"flac"} Apr 16 21:35:16 volumio volumio[2797]: verbose: CURRENT POSITION 30 Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:35:16 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:16 volumio volumio[2797]: info: CorePlayQueue::getTrack 30 Apr 16 21:35:16 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:16 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:16 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:16 volumio volumio[2797]: info: CorePlayQueue::getTrack 30 Apr 16 21:35:16 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:16 volumio volumio[2797]: info: ------------------------------ 81ms Apr 16 21:35:18 volumio volumio[2797]: info: CoreCommandRouter::volumioNext Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::next Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::stop Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::stPlaybackTimer Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:35:18 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:18 volumio volumio[2797]: info: CorePlayQueue::getTrack 30 Apr 16 21:35:18 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:18 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::serviceStop Apr 16 21:35:18 volumio volumio[2797]: info: CorePlayQueue::getTrack 30 Apr 16 21:35:18 volumio volumio[2797]: info: CoreCommandRouter::serviceStop Apr 16 21:35:18 volumio volumio[2797]: info: ControllerMpd::stop Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:35:18 volumio volumio[2797]: info: Apr 16 21:35:18 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:18 volumio volumio[2797]: info: sendMpdCommand stop took 55 milliseconds Apr 16 21:35:18 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::play index undefined Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:35:18 volumio volumio[2797]: info: CorePlayQueue::getTrack 31 Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:35:18 volumio volumio[2797]: info: CorePlayQueue::getTrack 31 Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::clearAddPlayTracks NAS/ds918/单曲/04. 望着天空的女孩.wav Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:35:18 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:35:18 volumio volumio[2797]: info: sendMpdCommand status took 6 milliseconds Apr 16 21:35:18 volumio volumio[2797]: info: sendMpdCommand stop took 5 milliseconds Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 21:35:18 volumio volumio[2797]: info: Apr 16 21:35:18 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:35:18 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:35:18 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 16 21:35:18 volumio volumio[2797]: info: sendMpdCommand clear took 3 milliseconds Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand add "NAS/ds918/单曲/04. 望着天空的女孩.wav" Apr 16 21:35:18 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:18 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:18 volumio volumio[2797]: info: CorePlayQueue::getTrack 31 Apr 16 21:35:18 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:18 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:18 volumio volumio[2797]: info: CorePlayQueue::getTrack 31 Apr 16 21:35:18 volumio volumio[2797]: 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":"Pathway to the Past","artist":"Guy Sweens","album":"Best New Age 4","uri":"NAS/ds918/单曲/04. Guy Sweens - Pathway to the Past.flac","trackType":"flac"} Apr 16 21:35:18 volumio volumio[2797]: verbose: CURRENT POSITION 31 Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::syncState stateService stop Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:18 volumio volumio[2797]: info: CorePlayQueue::getTrack 31 Apr 16 21:35:18 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:18 volumio volumio[2797]: info: No code Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:18 volumio volumio[2797]: info: CorePlayQueue::getTrack 31 Apr 16 21:35:18 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:18 volumio volumio[2797]: info: ------------------------------ 45ms Apr 16 21:35:18 volumio volumio[2797]: error: updateQueue error: null Apr 16 21:35:18 volumio volumio[2797]: info: Apr 16 21:35:18 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:35:18 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:35:18 volumio volumio[2797]: info: ------------------------------ 37ms Apr 16 21:35:18 volumio volumio[2797]: info: sendMpdCommand add "NAS/ds918/单曲/04. 望着天空的女孩.wav" took 32 milliseconds Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand play Apr 16 21:35:18 volumio volumio[2797]: info: ------------------------------ 12ms Apr 16 21:35:18 volumio volumio[2797]: info: sendMpdCommand play took 12 milliseconds Apr 16 21:35:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:35:18 volumio volumio[2797]: info: Apr 16 21:35:18 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:18 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:18 volumio volumio[2797]: info: Apr 16 21:35:18 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:18 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:18 volumio volumio[2797]: info: sendMpdCommand status took 2 milliseconds Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:18 volumio volumio[2797]: info: sendMpdCommand status took 3 milliseconds Apr 16 21:35:18 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:18 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:18 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:18 volumio volumio[2797]: info: CorePlayQueue::getTrack 31 Apr 16 21:35:18 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":170,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"???","album":"ChaCha?? ????CD2","uri":"NAS/ds918/单曲/04. 望着天空的女孩.wav","trackType":"wav"} Apr 16 21:35:18 volumio volumio[2797]: verbose: CURRENT POSITION 31 Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:35:18 volumio volumio[2797]: info: ------------------------------ 9ms Apr 16 21:35:18 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 33 milliseconds Apr 16 21:35:18 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:18 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:18 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:18 volumio volumio[2797]: info: CorePlayQueue::getTrack 31 Apr 16 21:35:18 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":170,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"???","album":"ChaCha?? ????CD2","uri":"NAS/ds918/单曲/04. 望着天空的女孩.wav","trackType":"wav"} Apr 16 21:35:18 volumio volumio[2797]: verbose: CURRENT POSITION 31 Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:35:18 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:18 volumio volumio[2797]: info: CorePlayQueue::getTrack 31 Apr 16 21:35:18 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:18 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:18 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:18 volumio volumio[2797]: info: CorePlayQueue::getTrack 31 Apr 16 21:35:18 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:18 volumio volumio[2797]: info: ------------------------------ 67ms Apr 16 21:35:19 volumio wpa_supplicant[724]: wlan0: Failed to initiate sched scan Apr 16 21:35:23 volumio volumio[2797]: info: CoreCommandRouter::volumioGetState Apr 16 21:35:23 volumio volumio[2797]: info: CorePlayQueue::getTrack 31 Apr 16 21:35:25 volumio wpa_supplicant[724]: wlan0: Failed to initiate sched scan Apr 16 21:35:31 volumio nmbd[610]: [2024/04/16 21:35:31.004702, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 16 21:35:31 volumio nmbd[610]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.154 for name WORKGROUP<1d>. Apr 16 21:35:31 volumio nmbd[610]: This response was from IP 192.168.0.135, reporting an IP address of 192.168.0.135. Apr 16 21:35:31 volumio nmbd[610]: [2024/04/16 21:35:31.005051, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 16 21:35:31 volumio nmbd[610]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.0.154 for name WORKGROUP<1d>. Apr 16 21:35:31 volumio nmbd[610]: This response was from IP 192.168.0.135, reporting an IP address of 192.168.0.135. Apr 16 21:35:31 volumio nmbd[610]: [2024/04/16 21:35:31.005272, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 16 21:35:31 volumio nmbd[610]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.0.154 for name WORKGROUP<1d>. Apr 16 21:35:31 volumio nmbd[610]: This response was from IP 192.168.0.135, reporting an IP address of 192.168.0.135. Apr 16 21:35:31 volumio wpa_supplicant[724]: wlan0: Failed to initiate sched scan Apr 16 21:35:31 volumio volumio[2797]: info: CoreCommandRouter::volumioNext Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::next Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::stop Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::stPlaybackTimer Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:35:31 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:31 volumio volumio[2797]: info: CorePlayQueue::getTrack 31 Apr 16 21:35:31 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:31 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::serviceStop Apr 16 21:35:31 volumio volumio[2797]: info: CorePlayQueue::getTrack 31 Apr 16 21:35:31 volumio volumio[2797]: info: CoreCommandRouter::serviceStop Apr 16 21:35:31 volumio volumio[2797]: info: ControllerMpd::stop Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:35:31 volumio volumio[2797]: info: Apr 16 21:35:31 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:31 volumio volumio[2797]: info: sendMpdCommand stop took 25 milliseconds Apr 16 21:35:31 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::play index undefined Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:35:31 volumio volumio[2797]: info: CorePlayQueue::getTrack 32 Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:35:31 volumio volumio[2797]: info: CorePlayQueue::getTrack 32 Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::clearAddPlayTracks NAS/ds918/单曲/04.珊瑚颂.WAV Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:35:31 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:35:31 volumio volumio[2797]: info: sendMpdCommand status took 3 milliseconds Apr 16 21:35:31 volumio volumio[2797]: info: sendMpdCommand stop took 2 milliseconds Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 21:35:31 volumio volumio[2797]: info: Apr 16 21:35:31 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:35:31 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:35:31 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 21:35:31 volumio volumio[2797]: info: sendMpdCommand clear took 2 milliseconds Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand add "NAS/ds918/单曲/04.珊瑚颂.WAV" Apr 16 21:35:31 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:31 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:31 volumio volumio[2797]: info: CorePlayQueue::getTrack 32 Apr 16 21:35:31 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:31 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:31 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:35:31 volumio volumio[2797]: info: CorePlayQueue::getTrack 32 Apr 16 21:35:31 volumio volumio[2797]: 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":"???????","artist":"???","album":"ChaCha?? ????CD2","uri":"NAS/ds918/单曲/04. 望着天空的女孩.wav","trackType":"wav"} Apr 16 21:35:31 volumio volumio[2797]: verbose: CURRENT POSITION 32 Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::syncState stateService stop Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:31 volumio volumio[2797]: info: CorePlayQueue::getTrack 32 Apr 16 21:35:31 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:31 volumio volumio[2797]: info: No code Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:31 volumio volumio[2797]: info: CorePlayQueue::getTrack 32 Apr 16 21:35:31 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:31 volumio volumio[2797]: info: ------------------------------ 34ms Apr 16 21:35:31 volumio volumio[2797]: error: updateQueue error: null Apr 16 21:35:31 volumio volumio[2797]: info: Apr 16 21:35:31 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:35:31 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:35:31 volumio volumio[2797]: info: ------------------------------ 31ms Apr 16 21:35:31 volumio volumio[2797]: info: sendMpdCommand add "NAS/ds918/单曲/04.珊瑚颂.WAV" took 32 milliseconds Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand play Apr 16 21:35:31 volumio volumio[2797]: info: ------------------------------ 14ms Apr 16 21:35:31 volumio volumio[2797]: info: sendMpdCommand play took 12 milliseconds Apr 16 21:35:31 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:35:31 volumio volumio[2797]: info: Apr 16 21:35:31 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:31 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:31 volumio volumio[2797]: info: Apr 16 21:35:31 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:31 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:31 volumio volumio[2797]: info: sendMpdCommand status took 1 milliseconds Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:31 volumio volumio[2797]: info: sendMpdCommand status took 2 milliseconds Apr 16 21:35:31 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:31 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:31 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:31 volumio volumio[2797]: info: CorePlayQueue::getTrack 32 Apr 16 21:35:31 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04.珊瑚颂.WAV","artist":null,"album":null,"uri":"NAS/ds918/单曲/04.珊瑚颂.WAV","trackType":"WAV"} Apr 16 21:35:31 volumio volumio[2797]: verbose: CURRENT POSITION 32 Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:35:31 volumio volumio[2797]: info: ------------------------------ 7ms Apr 16 21:35:31 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 21:35:31 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:31 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:31 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:31 volumio volumio[2797]: info: CorePlayQueue::getTrack 32 Apr 16 21:35:31 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04.珊瑚颂.WAV","artist":null,"album":null,"uri":"NAS/ds918/单曲/04.珊瑚颂.WAV","trackType":"WAV"} Apr 16 21:35:31 volumio volumio[2797]: verbose: CURRENT POSITION 32 Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:35:31 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:31 volumio volumio[2797]: info: CorePlayQueue::getTrack 32 Apr 16 21:35:31 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:31 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:31 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:31 volumio volumio[2797]: info: CorePlayQueue::getTrack 32 Apr 16 21:35:31 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:31 volumio volumio[2797]: info: ------------------------------ 28ms Apr 16 21:35:31 volumio volumio[2797]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 16 21:35:31 volumio volumio[2797]: info: Retrying Login Due to Network Error Apr 16 21:35:32 volumio volumio[2797]: info: CoreCommandRouter::volumioNext Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::next Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::stop Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::stPlaybackTimer Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:35:32 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:32 volumio volumio[2797]: info: CorePlayQueue::getTrack 32 Apr 16 21:35:32 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:32 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::serviceStop Apr 16 21:35:32 volumio volumio[2797]: info: CorePlayQueue::getTrack 32 Apr 16 21:35:32 volumio volumio[2797]: info: CoreCommandRouter::serviceStop Apr 16 21:35:32 volumio volumio[2797]: info: ControllerMpd::stop Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:35:32 volumio volumio[2797]: info: Apr 16 21:35:32 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:32 volumio volumio[2797]: info: sendMpdCommand stop took 33 milliseconds Apr 16 21:35:32 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::play index undefined Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 21:35:32 volumio volumio[2797]: info: CorePlayQueue::getTrack 33 Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::startPlaybackTimer Apr 16 21:35:32 volumio volumio[2797]: info: CorePlayQueue::getTrack 33 Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::clearAddPlayTracks NAS/ds918/单曲/04.蓝眼泪 Blue tear.flac Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::updateTrackBlock Apr 16 21:35:32 volumio volumio[2797]: info: CorePlayQueue::getTrackBlock Apr 16 21:35:32 volumio volumio[2797]: info: sendMpdCommand status took 3 milliseconds Apr 16 21:35:32 volumio volumio[2797]: info: sendMpdCommand stop took 2 milliseconds Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 21:35:32 volumio volumio[2797]: info: Apr 16 21:35:32 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:35:32 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:35:32 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 16 21:35:32 volumio volumio[2797]: info: sendMpdCommand clear took 1 milliseconds Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:32 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand add "NAS/ds918/单曲/04.蓝眼泪 Blue tear.flac" Apr 16 21:35:32 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:32 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:32 volumio volumio[2797]: info: CorePlayQueue::getTrack 33 Apr 16 21:35:32 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:32 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:32 volumio volumio[2797]: info: CorePlayQueue::getTrack 33 Apr 16 21:35:32 volumio volumio[2797]: 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":"04.珊瑚颂.WAV","artist":null,"album":null,"uri":"NAS/ds918/单曲/04.珊瑚颂.WAV","trackType":"WAV"} Apr 16 21:35:32 volumio volumio[2797]: verbose: CURRENT POSITION 33 Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::syncState stateService stop Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:32 volumio volumio[2797]: info: CorePlayQueue::getTrack 33 Apr 16 21:35:32 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:32 volumio volumio[2797]: info: No code Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:32 volumio volumio[2797]: info: CorePlayQueue::getTrack 33 Apr 16 21:35:32 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:32 volumio volumio[2797]: info: ------------------------------ 37ms Apr 16 21:35:32 volumio volumio[2797]: error: updateQueue error: null Apr 16 21:35:32 volumio volumio[2797]: info: Apr 16 21:35:32 volumio volumio[2797]: ---------------------------- MPD announces system playlist update Apr 16 21:35:32 volumio volumio[2797]: info: Ignoring MPD Status Update Apr 16 21:35:32 volumio volumio[2797]: info: ------------------------------ 34ms Apr 16 21:35:32 volumio volumio[2797]: info: sendMpdCommand add "NAS/ds918/单曲/04.蓝眼泪 Blue tear.flac" took 32 milliseconds Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand play Apr 16 21:35:32 volumio volumio[2797]: info: ------------------------------ 13ms Apr 16 21:35:32 volumio volumio[2797]: info: sendMpdCommand play took 11 milliseconds Apr 16 21:35:32 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 16 21:35:32 volumio volumio[2797]: info: Apr 16 21:35:32 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:32 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:32 volumio volumio[2797]: info: Apr 16 21:35:32 volumio volumio[2797]: ---------------------------- MPD announces state update: player Apr 16 21:35:32 volumio volumio[2797]: info: ControllerMpd::getState Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand status Apr 16 21:35:32 volumio volumio[2797]: info: sendMpdCommand status took 5 milliseconds Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:32 volumio volumio[2797]: info: sendMpdCommand status took 4 milliseconds Apr 16 21:35:32 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::parseState Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:32 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:32 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:32 volumio volumio[2797]: info: CorePlayQueue::getTrack 33 Apr 16 21:35:32 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"363 Kbps","isStreaming":false,"title":"蓝眼泪 Blue tear","artist":"广州龙源影视","album":"四度空间－车载专用发烧碟","uri":"NAS/ds918/单曲/04.蓝眼泪 Blue tear.flac","trackType":"flac"} Apr 16 21:35:32 volumio volumio[2797]: verbose: CURRENT POSITION 33 Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus stop Apr 16 21:35:32 volumio volumio[2797]: info: ------------------------------ 12ms Apr 16 21:35:32 volumio volumio[2797]: info: sendMpdCommand playlistinfo took 29 milliseconds Apr 16 21:35:32 volumio volumio[2797]: verbose: ControllerMpd::parseTrackInfo Apr 16 21:35:32 volumio volumio[2797]: info: ControllerMpd::pushState Apr 16 21:35:32 volumio volumio[2797]: info: CoreCommandRouter::servicePushState Apr 16 21:35:32 volumio volumio[2797]: info: CorePlayQueue::getTrack 33 Apr 16 21:35:32 volumio volumio[2797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"363 Kbps","isStreaming":false,"title":"蓝眼泪 Blue tear","artist":"广州龙源影视","album":"四度空间－车载专用发烧碟","uri":"NAS/ds918/单曲/04.蓝眼泪 Blue tear.flac","trackType":"flac"} Apr 16 21:35:32 volumio volumio[2797]: verbose: CURRENT POSITION 33 Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::syncState stateService play Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::syncState currentStatus play Apr 16 21:35:32 volumio volumio[2797]: info: Received an update from plugin. extracting info from payload Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:32 volumio volumio[2797]: info: CorePlayQueue::getTrack 33 Apr 16 21:35:32 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 21:35:32 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:32 volumio volumio[2797]: info: CoreStateMachine::pushState Apr 16 21:35:32 volumio volumio[2797]: info: CorePlayQueue::getTrack 33 Apr 16 21:35:32 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Apr 16 21:35:32 volumio volumio[2797]: info: ------------------------------ 60ms Apr 16 21:35:32 volumio volumio[2797]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 21:35:32 volumio volumio[2797]: Error: connect ETIMEDOUT 199.59.150.43:443 Apr 16 21:35:32 volumio volumio[2797]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 16 21:35:32 volumio volumio[2797]: errno: -110, Apr 16 21:35:32 volumio volumio[2797]: code: 'ETIMEDOUT', Apr 16 21:35:32 volumio volumio[2797]: syscall: 'connect', Apr 16 21:35:32 volumio volumio[2797]: address: '199.59.150.43', Apr 16 21:35:32 volumio volumio[2797]: port: 443 Apr 16 21:35:32 volumio volumio[2797]: } Apr 16 21:35:32 volumio volumio[2797]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 21:35:33 volumio sudo[3017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-16 21:34 Apr 16 21:35:33 volumio sudo[3017]: 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="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 05:45:03 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6428d8e2c06e98483f5c5d77e8f01989"