-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Fri 2024-08-23 15:43:51 +07. -- Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::volumioPlay Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::play index undefined Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::startPlaybackTimer Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ao Anh.wav Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand stop took 2 milliseconds Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 15:42:01 volumio volumio[1051]: info: Aug 23 15:42:01 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:01 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand clear took 2 milliseconds Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ao Anh.wav" Aug 23 15:42:01 volumio volumio[1051]: info: Aug 23 15:42:01 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:01 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:01 volumio volumio[1051]: info: Aug 23 15:42:01 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:01 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:01 volumio volumio[1051]: info: Aug 23 15:42:01 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:01 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:01 volumio volumio[1051]: error: updateQueue error: null Aug 23 15:42:01 volumio volumio[1051]: info: Aug 23 15:42:01 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:01 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:01 volumio volumio[1051]: info: ------------------------------ 8ms Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand add "NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ao Anh.wav" took 6 milliseconds Aug 23 15:42:01 volumio volumio[1051]: info: ------------------------------ 5ms Aug 23 15:42:01 volumio volumio[1051]: info: ------------------------------ 4ms Aug 23 15:42:01 volumio volumio[1051]: info: ------------------------------ 3ms Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand play Aug 23 15:42:01 volumio volumio[1051]: info: Aug 23 15:42:01 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:01 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:01 volumio volumio[1051]: info: Aug 23 15:42:01 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:01 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:01 volumio volumio[1051]: info: Aug 23 15:42:01 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:01 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:01 volumio volumio[1051]: info: ------------------------------ 7ms Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand play took 4 milliseconds Aug 23 15:42:01 volumio volumio[1051]: info: ------------------------------ 3ms Aug 23 15:42:01 volumio volumio[1051]: info: ------------------------------ 3ms Aug 23 15:42:01 volumio volumio[1051]: info: ------------------------------ 2ms Aug 23 15:42:01 volumio volumio[1051]: info: Aug 23 15:42:01 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:01 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:01 volumio volumio[1051]: info: Aug 23 15:42:01 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:01 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:01 volumio volumio[1051]: info: Aug 23 15:42:01 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:01 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:01 volumio volumio[1051]: info: Aug 23 15:42:01 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:01 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:01 volumio volumio[1051]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 23 15:42:01 volumio volumio[1051]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 23 15:42:01 volumio volumio[1051]: info: camilladsp stopping service pid 1318... Aug 23 15:42:01 volumio volumio[1051]: info: camilladsp service terminated, instance 1 Aug 23 15:42:01 volumio volumio[1051]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 23 15:42:01 volumio volumio[1051]: info: FusionDsp - Effects disabled Aug 23 15:42:01 volumio volumio[1051]: info: camilladsp service started and running in background, instance 1 Aug 23 15:42:01 volumio volumio[1051]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 23 15:42:01 volumio volumio[1051]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 23 15:42:01 volumio volumio[1051]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 23 15:42:01 volumio volumio[1051]: info: FusionDsp - Effects disabled Aug 23 15:42:01 volumio volumio[1051]: info: Aug 23 15:42:01 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand status took 65 milliseconds Aug 23 15:42:01 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand status took 63 milliseconds Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand status took 62 milliseconds Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand status took 61 milliseconds Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:01 volumio volumio[1051]: info: Aug 23 15:42:01 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:01 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:01 volumio volumio[1051]: info: Aug 23 15:42:01 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:01 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:01 volumio volumio[1051]: info: Aug 23 15:42:01 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:01 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand status took 17 milliseconds Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand status took 11 milliseconds Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand status took 10 milliseconds Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand status took 9 milliseconds Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:01 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Ao Anh","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ao Anh.wav","trackType":"wav"} Aug 23 15:42:01 volumio volumio[1051]: verbose: CURRENT POSITION 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus stop Aug 23 15:42:01 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Ao Anh","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ao Anh.wav","trackType":"wav"} Aug 23 15:42:01 volumio volumio[1051]: verbose: CURRENT POSITION 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:01 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:01 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Ao Anh","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ao Anh.wav","trackType":"wav"} Aug 23 15:42:01 volumio volumio[1051]: verbose: CURRENT POSITION 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:01 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:01 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Ao Anh","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ao Anh.wav","trackType":"wav"} Aug 23 15:42:01 volumio volumio[1051]: verbose: CURRENT POSITION 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:01 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:01 volumio volumio[1051]: info: ------------------------------ 133ms Aug 23 15:42:01 volumio volumio[1051]: info: ------------------------------ 171ms Aug 23 15:42:01 volumio volumio[1051]: info: ------------------------------ 170ms Aug 23 15:42:01 volumio volumio[1051]: info: ------------------------------ 170ms Aug 23 15:42:01 volumio volumio[1051]: error: FusionDsp - WebSocket error: [object Object] Aug 23 15:42:01 volumio volumio[1051]: info: camilladsp spawned new process with pid 1424, instance 1, run: true Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 115 milliseconds Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 113 milliseconds Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 112 milliseconds Aug 23 15:42:01 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 113 milliseconds Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:01 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:01 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Ao Anh","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ao Anh.wav","trackType":"wav"} Aug 23 15:42:01 volumio volumio[1051]: verbose: CURRENT POSITION 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:01 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:01 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Ao Anh","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ao Anh.wav","trackType":"wav"} Aug 23 15:42:01 volumio volumio[1051]: verbose: CURRENT POSITION 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:01 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:01 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Ao Anh","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ao Anh.wav","trackType":"wav"} Aug 23 15:42:01 volumio volumio[1051]: verbose: CURRENT POSITION 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:01 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:01 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Ao Anh","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ao Anh.wav","trackType":"wav"} Aug 23 15:42:01 volumio volumio[1051]: verbose: CURRENT POSITION 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:01 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:01 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:01 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:01 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:01 volumio volumio[1051]: info: ------------------------------ 236ms Aug 23 15:42:01 volumio volumio[1051]: info: ------------------------------ 227ms Aug 23 15:42:01 volumio volumio[1051]: info: ------------------------------ 227ms Aug 23 15:42:01 volumio volumio[1051]: info: ------------------------------ 226ms Aug 23 15:42:01 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: stop Aug 23 15:42:01 volumio volumio[1051]: info: [ASDebug] Togle GPIO: ON Aug 23 15:42:01 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:01 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:01 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:01 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:01 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:01 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:01 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:01 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:01 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:01 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:01 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:01 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:01 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioNext Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::next Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::stop Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::stPlaybackTimer Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::updateTrackBlock Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrackBlock Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::serviceStop Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::serviceStop Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::stop Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand stop took 10 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::play index undefined Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::startPlaybackTimer Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::updateTrackBlock Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrackBlock Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand status took 2 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand stop took 2 milliseconds Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:06 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand clear took 5 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand status took 4 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand status took 3 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand status took 2 milliseconds Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav" Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: 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":"Le Quyen - Ao Anh","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ao Anh.wav","trackType":"wav"} Aug 23 15:42:06 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState stateService stop Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus stop Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: No code Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 23 15:42:06 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState stateService stop Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus stop Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: No code Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 23 15:42:06 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState stateService stop Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus stop Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: No code Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 23 15:42:06 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState stateService stop Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus stop Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: No code Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 94ms Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 90ms Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 90ms Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 90ms Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:06 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:06 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:06 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:06 volumio volumio[1051]: error: updateQueue error: null Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:06 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 96ms Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand add "NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav" took 95 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 7ms Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 6ms Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 6ms Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand play Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:06 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:06 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:06 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 57ms Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand play took 54 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 54ms Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 53ms Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 51ms Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:06 volumio volumio[1051]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 23 15:42:06 volumio volumio[1051]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 23 15:42:06 volumio volumio[1051]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 23 15:42:06 volumio volumio[1051]: info: FusionDsp - Effects disabled Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand status took 10 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand status took 8 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand status took 8 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand status took 7 milliseconds Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:06 volumio volumio[1051]: info: Aug 23 15:42:06 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand status took 10 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand status took 6 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand status took 5 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand status took 4 milliseconds Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","trackType":"wav"} Aug 23 15:42:06 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus stop Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","trackType":"wav"} Aug 23 15:42:06 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:06 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","trackType":"wav"} Aug 23 15:42:06 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:06 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","trackType":"wav"} Aug 23 15:42:06 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:06 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 44ms Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 69ms Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 68ms Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 68ms Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 50 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 49 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 49 milliseconds Aug 23 15:42:06 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 50 milliseconds Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:06 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","trackType":"wav"} Aug 23 15:42:06 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:06 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","trackType":"wav"} Aug 23 15:42:06 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:06 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","trackType":"wav"} Aug 23 15:42:06 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:06 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","trackType":"wav"} Aug 23 15:42:06 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:06 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:06 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 159ms Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 153ms Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 153ms Aug 23 15:42:06 volumio volumio[1051]: info: ------------------------------ 151ms Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:06 volumio volumio[1051]: info: FusionDsp - {"Reload":{"result":"Ok"}} Aug 23 15:42:08 volumio volumio[1051]: info: CoreCommandRouter::volumioSeek Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::seek Aug 23 15:42:08 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:08 volumio volumio[1051]: info: TRACKBLOCK {"uri":"mnt/NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","service":"mpd","name":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=231&web=Le%20Quyen/Kh%C3%BAc%20t%C3%ACnh%20x%C6%B0a%20Rip/extralarge&path=%2FNAS%2FNAS%2FMusic%2FMusic%20Rip%2FLe%20Quyen%20(Khuc%20Tinh%20Xua)&metadata=false","duration":260,"trackType":"wav","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::startPlaybackTimer Aug 23 15:42:08 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:08 volumio volumio[1051]: info: ControllerMpd::seek Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:08 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:08 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:42:08 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:08 volumio volumio[1051]: error: null Aug 23 15:42:08 volumio volumio[1051]: info: Aug 23 15:42:08 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:08 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:08 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:08 volumio volumio[1051]: info: Aug 23 15:42:08 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:08 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:08 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:08 volumio volumio[1051]: info: Aug 23 15:42:08 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:08 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:08 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:08 volumio volumio[1051]: info: Aug 23 15:42:08 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:08 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:08 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:08 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:08 volumio volumio[1051]: info: sendMpdCommand status took 14 milliseconds Aug 23 15:42:08 volumio volumio[1051]: info: sendMpdCommand status took 12 milliseconds Aug 23 15:42:08 volumio volumio[1051]: info: sendMpdCommand status took 10 milliseconds Aug 23 15:42:08 volumio volumio[1051]: info: sendMpdCommand status took 8 milliseconds Aug 23 15:42:08 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:08 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:08 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:08 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:08 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:08 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:08 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:08 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:08 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 23 15:42:08 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 23 15:42:08 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 23 15:42:08 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 23 15:42:08 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:08 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:08 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:08 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:08 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:08 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:08 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:08 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":253000,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","trackType":"wav"} Aug 23 15:42:08 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:08 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:08 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:08 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:08 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:08 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:08 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:08 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:08 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:08 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":253000,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","trackType":"wav"} Aug 23 15:42:08 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:08 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:08 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:08 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:08 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:08 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:08 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:08 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:08 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:08 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":253000,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","trackType":"wav"} Aug 23 15:42:08 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:08 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:08 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:08 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:08 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:08 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:08 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:08 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:08 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:08 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":253000,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","trackType":"wav"} Aug 23 15:42:08 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:08 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:08 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:08 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:08 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:08 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:08 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:08 volumio volumio[1051]: info: ------------------------------ 92ms Aug 23 15:42:08 volumio volumio[1051]: info: ------------------------------ 91ms Aug 23 15:42:08 volumio volumio[1051]: info: ------------------------------ 92ms Aug 23 15:42:08 volumio volumio[1051]: info: ------------------------------ 91ms Aug 23 15:42:08 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:08 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:08 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:08 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:08 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:08 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:08 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:08 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:10 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:10 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:10 volumio volumio[1051]: info: Prefetching next song Aug 23 15:42:10 volumio volumio[1051]: info: DOING PREFETCH IN MPD Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Chiec La Cuoi Cung.wav" Aug 23 15:42:10 volumio volumio[1051]: info: Aug 23 15:42:10 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:10 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:10 volumio volumio[1051]: info: sendMpdCommand add "NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Chiec La Cuoi Cung.wav" took 3 milliseconds Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 23 15:42:10 volumio volumio[1051]: info: Aug 23 15:42:10 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:10 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:10 volumio volumio[1051]: info: Aug 23 15:42:10 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:10 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:10 volumio volumio[1051]: info: Aug 23 15:42:10 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:10 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:10 volumio volumio[1051]: info: Aug 23 15:42:10 volumio volumio[1051]: ---------------------------- MPD announces state update: options Aug 23 15:42:10 volumio volumio[1051]: info: ------------------------------ 7ms Aug 23 15:42:10 volumio volumio[1051]: info: sendMpdCommand consume 1 took 5 milliseconds Aug 23 15:42:10 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:10 volumio volumio[1051]: info: ------------------------------ 5ms Aug 23 15:42:10 volumio volumio[1051]: info: ------------------------------ 4ms Aug 23 15:42:10 volumio volumio[1051]: info: ------------------------------ 3ms Aug 23 15:42:10 volumio volumio[1051]: info: Aug 23 15:42:10 volumio volumio[1051]: ---------------------------- MPD announces state update: options Aug 23 15:42:10 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:10 volumio volumio[1051]: info: Aug 23 15:42:10 volumio volumio[1051]: ---------------------------- MPD announces state update: options Aug 23 15:42:10 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:10 volumio volumio[1051]: info: Aug 23 15:42:10 volumio volumio[1051]: ---------------------------- MPD announces state update: options Aug 23 15:42:10 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:10 volumio volumio[1051]: info: sendMpdCommand status took 5 milliseconds Aug 23 15:42:10 volumio volumio[1051]: info: sendMpdCommand status took 4 milliseconds Aug 23 15:42:10 volumio volumio[1051]: info: sendMpdCommand status took 3 milliseconds Aug 23 15:42:10 volumio volumio[1051]: info: sendMpdCommand status took 2 milliseconds Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:10 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 23 15:42:10 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 23 15:42:10 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 23 15:42:10 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:10 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:10 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:10 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:10 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:10 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":255974,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","trackType":"wav"} Aug 23 15:42:10 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:10 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:10 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:10 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:10 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:10 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:10 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:42:10 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:10 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:10 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:10 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:10 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:10 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:10 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:10 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":255974,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","trackType":"wav"} Aug 23 15:42:10 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:10 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:10 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:10 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:10 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:10 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:10 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:10 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:10 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:10 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:10 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:10 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:10 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:10 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":255974,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","trackType":"wav"} Aug 23 15:42:10 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:10 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:10 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:10 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:10 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:10 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:10 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:10 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:10 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:10 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:10 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:10 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:10 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:10 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":255974,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Buon","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Buon.wav","trackType":"wav"} Aug 23 15:42:10 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:10 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:10 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:10 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:10 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:10 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:10 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:10 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:10 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:10 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:10 volumio volumio[1051]: info: ------------------------------ 103ms Aug 23 15:42:10 volumio volumio[1051]: info: ------------------------------ 103ms Aug 23 15:42:10 volumio volumio[1051]: info: ------------------------------ 102ms Aug 23 15:42:10 volumio volumio[1051]: info: ------------------------------ 101ms Aug 23 15:42:10 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:10 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:10 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:10 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:10 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:10 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:10 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:10 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 15:42:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 15:42:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 23 15:42:14 volumio volumio[1051]: error: Plugin system_hardware iractivityled failed to complete 'onStart' in a timely fashion Aug 23 15:42:14 volumio volumio[1051]: info: ------------------------------------------- Aug 23 15:42:14 volumio volumio[1051]: info: ----- MyVolumio plugins startup ---- Aug 23 15:42:14 volumio volumio[1051]: info: ------------------------------------------- Aug 23 15:42:14 volumio volumio[1051]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 23 15:42:14 volumio volumio[1051]: info: Aug 23 15:42:14 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:14 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:14 volumio volumio[1051]: info: Aug 23 15:42:14 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:14 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:14 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:14 volumio volumio[1051]: info: Aug 23 15:42:14 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:14 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:14 volumio volumio[1051]: info: Aug 23 15:42:14 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:14 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:14 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:14 volumio volumio[1051]: info: Aug 23 15:42:14 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:14 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:14 volumio volumio[1051]: info: Aug 23 15:42:14 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:14 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:14 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:14 volumio volumio[1051]: info: Aug 23 15:42:14 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:14 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:14 volumio volumio[1051]: info: Aug 23 15:42:14 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:14 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:14 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:14 volumio volumio[1051]: info: ------------------------------ 7ms Aug 23 15:42:14 volumio volumio[1051]: info: sendMpdCommand status took 7 milliseconds Aug 23 15:42:14 volumio volumio[1051]: info: ------------------------------ 6ms Aug 23 15:42:14 volumio volumio[1051]: info: sendMpdCommand status took 5 milliseconds Aug 23 15:42:14 volumio volumio[1051]: info: ------------------------------ 5ms Aug 23 15:42:14 volumio volumio[1051]: info: sendMpdCommand status took 3 milliseconds Aug 23 15:42:14 volumio volumio[1051]: info: ------------------------------ 4ms Aug 23 15:42:14 volumio volumio[1051]: info: sendMpdCommand status took 3 milliseconds Aug 23 15:42:14 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:14 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:14 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:14 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:14 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:14 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:14 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:14 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:14 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 23 15:42:14 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 23 15:42:14 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 23 15:42:14 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 23 15:42:14 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:14 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:14 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:14 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:14 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:14 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:14 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:14 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":369,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Chiec La Cuoi Cung","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Chiec La Cuoi Cung.wav","trackType":"wav"} Aug 23 15:42:14 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:14 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:14 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:14 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:14 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:14 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:42:14 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:14 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:14 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:14 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:14 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:14 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:14 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:14 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":369,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Chiec La Cuoi Cung","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Chiec La Cuoi Cung.wav","trackType":"wav"} Aug 23 15:42:14 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:14 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:14 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:14 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:14 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:14 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:14 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:14 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:14 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:14 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:14 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:14 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:14 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:14 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":369,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Chiec La Cuoi Cung","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Chiec La Cuoi Cung.wav","trackType":"wav"} Aug 23 15:42:14 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:14 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:14 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:14 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:14 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:14 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:14 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:14 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:14 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:14 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:14 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:14 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:14 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:14 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":369,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Chiec La Cuoi Cung","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Chiec La Cuoi Cung.wav","trackType":"wav"} Aug 23 15:42:14 volumio volumio[1051]: verbose: CURRENT POSITION 1 Aug 23 15:42:14 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:14 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:14 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:14 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:14 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:14 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:14 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:14 volumio volumio[1051]: info: CorePlayQueue::getTrack 1 Aug 23 15:42:14 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:14 volumio volumio[1051]: info: ------------------------------ 71ms Aug 23 15:42:14 volumio volumio[1051]: info: ------------------------------ 69ms Aug 23 15:42:14 volumio volumio[1051]: info: ------------------------------ 68ms Aug 23 15:42:14 volumio volumio[1051]: info: ------------------------------ 67ms Aug 23 15:42:14 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:14 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:14 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:14 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:14 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:14 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:15 volumio volumio[1051]: info: CoreStateMachine::startPlaybackTimer Aug 23 15:42:15 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:15 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:15 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:15 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:42:15 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:15 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:20 volumio ntpd[780]: Soliciting pool server 103.130.217.41 Aug 23 15:42:21 volumio volumio[1051]: info: CoreCommandRouter::volumioSeek Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::seek Aug 23 15:42:21 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:21 volumio volumio[1051]: info: TRACKBLOCK {"uri":"mnt/NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Chiec La Cuoi Cung.wav","service":"mpd","name":"Le Quyen - Chiec La Cuoi Cung","artist":"Le Quyen","album":"Khúc tình xưa Rip","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=231&web=Le%20Quyen/Kh%C3%BAc%20t%C3%ACnh%20x%C6%B0a%20Rip/extralarge&path=%2FNAS%2FNAS%2FMusic%2FMusic%20Rip%2FLe%20Quyen%20(Khuc%20Tinh%20Xua)&metadata=false","duration":369,"trackType":"wav"} Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::startPlaybackTimer Aug 23 15:42:21 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:21 volumio volumio[1051]: info: ControllerMpd::seek Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:21 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:21 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:42:21 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:21 volumio volumio[1051]: error: null Aug 23 15:42:21 volumio volumio[1051]: info: Aug 23 15:42:21 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:21 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:21 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:21 volumio volumio[1051]: info: Aug 23 15:42:21 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:21 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:21 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:21 volumio volumio[1051]: info: Aug 23 15:42:21 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:21 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:21 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:21 volumio volumio[1051]: info: Aug 23 15:42:21 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:21 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:21 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:21 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:21 volumio volumio[1051]: info: sendMpdCommand status took 12 milliseconds Aug 23 15:42:21 volumio volumio[1051]: info: sendMpdCommand status took 11 milliseconds Aug 23 15:42:21 volumio volumio[1051]: info: sendMpdCommand status took 9 milliseconds Aug 23 15:42:21 volumio volumio[1051]: info: sendMpdCommand status took 9 milliseconds Aug 23 15:42:21 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:21 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:21 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:21 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:21 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:21 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:21 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:21 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:21 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 23 15:42:21 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 23 15:42:21 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 23 15:42:21 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 23 15:42:21 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:21 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:21 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:21 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:21 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:21 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:21 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:21 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":365000,"duration":369,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Chiec La Cuoi Cung","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Chiec La Cuoi Cung.wav","trackType":"wav"} Aug 23 15:42:21 volumio volumio[1051]: verbose: CURRENT POSITION 2 Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:21 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:21 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:21 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:42:21 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:21 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:21 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:21 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:21 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:21 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:21 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":365000,"duration":369,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Chiec La Cuoi Cung","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Chiec La Cuoi Cung.wav","trackType":"wav"} Aug 23 15:42:21 volumio volumio[1051]: verbose: CURRENT POSITION 2 Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:21 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:21 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:21 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:21 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:21 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:21 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:21 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:21 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:21 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":365000,"duration":369,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Chiec La Cuoi Cung","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Chiec La Cuoi Cung.wav","trackType":"wav"} Aug 23 15:42:21 volumio volumio[1051]: verbose: CURRENT POSITION 2 Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:21 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:21 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:21 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:21 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:21 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:21 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:21 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:21 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:21 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":365000,"duration":369,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Chiec La Cuoi Cung","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Chiec La Cuoi Cung.wav","trackType":"wav"} Aug 23 15:42:21 volumio volumio[1051]: verbose: CURRENT POSITION 2 Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:21 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:21 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:21 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:21 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:21 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:21 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:22 volumio volumio[1051]: info: ------------------------------ 92ms Aug 23 15:42:22 volumio volumio[1051]: info: ------------------------------ 91ms Aug 23 15:42:22 volumio volumio[1051]: info: ------------------------------ 89ms Aug 23 15:42:22 volumio volumio[1051]: info: ------------------------------ 88ms Aug 23 15:42:22 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:22 volumio volumio[1051]: info: CorePlayQueue::getTrack 3 Aug 23 15:42:22 volumio volumio[1051]: info: Prefetching next song Aug 23 15:42:22 volumio volumio[1051]: info: DOING PREFETCH IN MPD Aug 23 15:42:22 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Doi Thong Hai Mo.wav" Aug 23 15:42:22 volumio volumio[1051]: info: Aug 23 15:42:22 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:22 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:22 volumio volumio[1051]: info: sendMpdCommand add "NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Doi Thong Hai Mo.wav" took 2 milliseconds Aug 23 15:42:22 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 23 15:42:22 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:22 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:22 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:22 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:22 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:22 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:22 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:22 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:22 volumio volumio[1051]: info: Aug 23 15:42:22 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:22 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:22 volumio volumio[1051]: info: Aug 23 15:42:22 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:22 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:22 volumio volumio[1051]: info: Aug 23 15:42:22 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:22 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:22 volumio volumio[1051]: info: ------------------------------ 35ms Aug 23 15:42:22 volumio volumio[1051]: info: sendMpdCommand consume 1 took 33 milliseconds Aug 23 15:42:22 volumio volumio[1051]: info: ------------------------------ 7ms Aug 23 15:42:22 volumio volumio[1051]: info: ------------------------------ 5ms Aug 23 15:42:22 volumio volumio[1051]: info: ------------------------------ 2ms Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 23 15:42:22 volumio volumio[1051]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 23 15:42:22 volumio volumio[1051]: info: Adding plugin bluetooth to MyMusic Plugins Aug 23 15:42:22 volumio volumio[1051]: info: Adding plugin multiroom to MyMusic Plugins Aug 23 15:42:22 volumio volumio[1051]: info: Adding plugin metavolumio to MyMusic Plugins Aug 23 15:42:22 volumio volumio[1051]: info: Adding plugin cd_controller to MyMusic Plugins Aug 23 15:42:23 volumio volumio[1051]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 23 15:42:23 volumio volumio[1051]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 23 15:42:23 volumio volumio[1051]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 23 15:42:23 volumio ntpd[780]: Soliciting pool server 2a0c:b847:ffff:79::e53c Aug 23 15:42:23 volumio volumio[1051]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 23 15:42:23 volumio volumio[1051]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 23 15:42:23 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 15:42:23 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 15:42:23 volumio volumio[1051]: info: Starting MyVolumio Remote Streaming Endpoints Aug 23 15:42:23 volumio volumio[1051]: info: MyVolumio login type: Token Aug 23 15:42:23 volumio volumio[1051]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 23 15:42:23 volumio volumio[1051]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 23 15:42:24 volumio volumio[1051]: info: Starting Streaming Service Transparent Proxy Aug 23 15:42:24 volumio volumio[1051]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 23 15:42:24 volumio volumio[1051]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 23 15:42:24 volumio volumio[1051]: info: Streaming services startup Aug 23 15:42:24 volumio volumio[1051]: info: Starting Streaming Daemon Aug 23 15:42:24 volumio sudo[1535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 23 15:42:24 volumio sudo[1535]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 15:42:25 volumio sudo[1535]: pam_unix(sudo:session): session closed for user root Aug 23 15:42:25 volumio volumio[1051]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 23 15:42:25 volumio volumio[1051]: info: Aug 23 15:42:25 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:25 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:25 volumio volumio[1051]: info: Aug 23 15:42:25 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:25 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:25 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:25 volumio volumio[1051]: info: Aug 23 15:42:25 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:25 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:25 volumio volumio[1051]: info: Aug 23 15:42:25 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:25 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:25 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:25 volumio volumio[1051]: info: Aug 23 15:42:25 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:25 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:25 volumio volumio[1051]: info: Aug 23 15:42:25 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:25 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:25 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:25 volumio volumio[1051]: info: Aug 23 15:42:25 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:42:25 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:42:25 volumio volumio[1051]: info: Aug 23 15:42:25 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:42:25 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:42:25 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:42:25 volumio volumio[1051]: error: Cannot start Volumio Streaming Daemon Aug 23 15:42:25 volumio volumio[1051]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 23 15:42:25 volumio volumio[1051]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 23 15:42:25 volumio volumio[1051]: info: ------------------------------ 30ms Aug 23 15:42:25 volumio volumio[1051]: info: sendMpdCommand status took 28 milliseconds Aug 23 15:42:25 volumio volumio[1051]: info: ------------------------------ 28ms Aug 23 15:42:25 volumio volumio[1051]: info: sendMpdCommand status took 26 milliseconds Aug 23 15:42:25 volumio volumio[1051]: info: ------------------------------ 25ms Aug 23 15:42:25 volumio volumio[1051]: info: sendMpdCommand status took 24 milliseconds Aug 23 15:42:25 volumio volumio[1051]: info: ------------------------------ 23ms Aug 23 15:42:25 volumio volumio[1051]: info: sendMpdCommand status took 22 milliseconds Aug 23 15:42:25 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:25 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:25 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:25 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:25 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:25 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:25 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:42:25 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:42:25 volumio volumio[1051]: Upnp client error: Error: This socket has been ended by the other party Aug 23 15:42:25 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 23 15:42:25 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 23 15:42:25 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 23 15:42:25 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 23 15:42:25 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:25 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:25 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:25 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:42:25 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:25 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:25 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Doi Thong Hai Mo.wav","artist":null,"album":null,"uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Doi Thong Hai Mo.wav","trackType":"wav"} Aug 23 15:42:25 volumio volumio[1051]: verbose: CURRENT POSITION 2 Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:25 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:25 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:42:25 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:25 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:25 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:25 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:25 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Doi Thong Hai Mo.wav","artist":null,"album":null,"uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Doi Thong Hai Mo.wav","trackType":"wav"} Aug 23 15:42:25 volumio volumio[1051]: verbose: CURRENT POSITION 2 Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:25 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:25 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:25 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:25 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:25 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:25 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Doi Thong Hai Mo.wav","artist":null,"album":null,"uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Doi Thong Hai Mo.wav","trackType":"wav"} Aug 23 15:42:25 volumio volumio[1051]: verbose: CURRENT POSITION 2 Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:25 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:25 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:25 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:25 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:42:25 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:42:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:25 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Doi Thong Hai Mo.wav","artist":null,"album":null,"uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Doi Thong Hai Mo.wav","trackType":"wav"} Aug 23 15:42:25 volumio volumio[1051]: verbose: CURRENT POSITION 2 Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:42:25 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:25 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 2 Aug 23 15:42:25 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:25 volumio volumio[1051]: info: ------------------------------ 117ms Aug 23 15:42:25 volumio volumio[1051]: info: ------------------------------ 115ms Aug 23 15:42:25 volumio volumio[1051]: info: ------------------------------ 112ms Aug 23 15:42:25 volumio volumio[1051]: info: ------------------------------ 109ms Aug 23 15:42:25 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:25 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:25 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:25 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:25 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:25 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:25 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:25 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:25 volumio volumio[1051]: info: CoreStateMachine::startPlaybackTimer Aug 23 15:42:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 3 Aug 23 15:42:25 volumio volumio[1051]: STREAMING PROXY: Starting server on port 3245 Aug 23 15:42:25 volumio volumio[1051]: Node JS runtime: 14 Aug 23 15:42:25 volumio volumio[1051]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 23 15:42:26 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:42:26 volumio volumio[1051]: info: CorePlayQueue::getTrack 3 Aug 23 15:42:26 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:42:26 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:42:26 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:42:26 volumio volumio[1051]: info: MyVolumio token set successfully Aug 23 15:42:26 volumio volumio[1051]: info: MYVOLUMIO: Adding device Aug 23 15:42:26 volumio volumio[1051]: info: MYVOLUMIO: Evaluating Server Aug 23 15:42:27 volumio volumio[1051]: info: MyVolumio status changed Aug 23 15:42:27 volumio volumio[1051]: info: Streaming services startup Aug 23 15:42:27 volumio volumio[1051]: info: Starting Streaming Daemon Aug 23 15:42:27 volumio volumio[1051]: info: Removing browser output: myVolumio user plan is not superstar Aug 23 15:42:27 volumio volumio[1051]: info: Removing audio output: Aug 23 15:42:27 volumio volumio[1051]: info: Stoppping Tunnel 1 Aug 23 15:42:27 volumio sudo[1566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 23 15:42:27 volumio sudo[1566]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 15:42:27 volumio sudo[1566]: pam_unix(sudo:session): session closed for user root Aug 23 15:42:27 volumio sudo[1569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 23 15:42:27 volumio sudo[1569]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 15:42:27 volumio volumio[1051]: error: Cannot start Volumio Streaming Daemon Aug 23 15:42:27 volumio volumio[1051]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 23 15:42:27 volumio volumio[1051]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 23 15:42:27 volumio sudo[1569]: pam_unix(sudo:session): session closed for user root Aug 23 15:42:27 volumio volumio[1051]: info: Remote SSH Stopped Aug 23 15:42:27 volumio volumio[1051]: info: Setting Geolocation for MyVolumio to as1 Aug 23 15:42:27 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 15:42:27 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 15:42:27 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 15:42:28 volumio volumio[1051]: info: Successfully Added MyVolumio device Aug 23 15:42:28 volumio volumio[1051]: info: Updating MyVolumio device info Aug 23 15:42:28 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 15:42:29 volumio volumio[1051]: info: Successfully Updated MyVolumio device Aug 23 15:43:07 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 23 15:43:07 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 23 15:43:07 volumio volumio[1051]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 23 15:43:07 volumio volumio[1051]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 23 15:43:10 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 23 15:43:10 volumio volumio[1051]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 23 15:43:10 volumio volumio[1051]: info: CoreCommandRouter::volumioGetState Aug 23 15:43:10 volumio volumio[1051]: info: CorePlayQueue::getTrack 3 Aug 23 15:43:13 volumio volumio[1051]: info: Starting Uninstall of plugin audio_interface - fusiondsp Aug 23 15:43:13 volumio volumio[1051]: info: Uninstalling plugin fusiondsp Aug 23 15:43:13 volumio volumio[1051]: info: FusionDsp - Stopping FusionDsp service Aug 23 15:43:13 volumio volumio[1051]: info: camilladsp stopping service pid 1424... Aug 23 15:43:13 volumio volumio[1051]: info: camilladsp service terminated, instance 1 Aug 23 15:43:13 volumio volumio[1051]: info: Disabling plugin fusiondsp Aug 23 15:43:13 volumio volumio[1051]: info: Preparing to generate the ALSA configuration file Aug 23 15:43:13 volumio sudo[1606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Aug 23 15:43:13 volumio sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 15:43:13 volumio systemd[1]: Stopping FusionDsp Daemon... Aug 23 15:43:13 volumio volumio[1329]: Backend configuration: Aug 23 15:43:13 volumio volumio[1329]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Aug 23 15:43:13 volumio volumio[1329]: active_config_txt: null Aug 23 15:43:13 volumio volumio[1329]: camilla_host: 127.0.0.1 Aug 23 15:43:13 volumio volumio[1329]: camilla_port: 9876 Aug 23 15:43:13 volumio volumio[1329]: coeff_dir: /data/INTERNAL/FusionDsp/filters Aug 23 15:43:13 volumio volumio[1329]: config_dir: /data/configuration/audio_interface/fusiondsp Aug 23 15:43:13 volumio volumio[1329]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Aug 23 15:43:13 volumio volumio[1329]: log_file: /tmp/camilladsp.log Aug 23 15:43:13 volumio volumio[1329]: on_get_active_config: null Aug 23 15:43:13 volumio volumio[1329]: on_set_active_config: null Aug 23 15:43:13 volumio volumio[1329]: port: 5011 Aug 23 15:43:13 volumio volumio[1329]: supported_capture_types: null Aug 23 15:43:13 volumio volumio[1329]: supported_playback_types: null Aug 23 15:43:13 volumio volumio[1329]: update_config_symlink: false Aug 23 15:43:13 volumio volumio[1329]: update_config_txt: false Aug 23 15:43:13 volumio volumio[1329]: ======== Running on http://0.0.0.0:5011 ======== Aug 23 15:43:13 volumio volumio[1329]: (Press CTRL+C to quit) Aug 23 15:43:13 volumio volumio[1051]: info: Asound.conf file written Aug 23 15:43:13 volumio sudo[1609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 23 15:43:13 volumio sudo[1609]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 15:43:13 volumio sudo[1609]: pam_unix(sudo:session): session closed for user root Aug 23 15:43:13 volumio systemd[1]: fusiondsp.service: Succeeded. Aug 23 15:43:13 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 23 15:43:13 volumio volumio[1051]: info: Output device has changed, restarting MPD Aug 23 15:43:13 volumio sudo[1606]: pam_unix(sudo:session): session closed for user root Aug 23 15:43:14 volumio sudo[1614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 15:43:14 volumio sudo[1614]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 15:43:14 volumio volumio[1051]: info: Output device has changed, restarting Shairport Sync Aug 23 15:43:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 15:43:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 15:43:14 volumio sudo[1614]: pam_unix(sudo:session): session closed for user root Aug 23 15:43:14 volumio sudo[1617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 15:43:14 volumio sudo[1617]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 15:43:14 volumio systemd[1]: Stopping Music Player Daemon... Aug 23 15:43:14 volumio volumio[1051]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 15:43:14 volumio volumio[1051]: info: Checking if uninstall.sh is present Aug 23 15:43:14 volumio volumio[1051]: info: Executing uninstall.sh Aug 23 15:43:14 volumio volumio[1051]: info: MPD Permissions set Aug 23 15:43:14 volumio volumio[1051]: info: FusionDsp - Reporting Fusion DSP Disabled Aug 23 15:43:14 volumio volumio[1051]: info: Removing fusiondspeq DSP Signal Path Element Aug 23 15:43:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 15:43:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 15:43:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 15:43:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 15:43:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 15:43:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 15:43:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 15:43:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 15:43:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 15:43:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 15:43:14 volumio volumio[1051]: error: Upnp client error: Error: This socket has been ended by the other party Aug 23 15:43:14 volumio volumio[1051]: info: Starting Shairport Sync Aug 23 15:43:14 volumio systemd[1]: mpd.service: Succeeded. Aug 23 15:43:14 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 15:43:14 volumio sudo[1630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 23 15:43:14 volumio sudo[1630]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 15:43:14 volumio sudo[1624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/uninstall.sh Aug 23 15:43:14 volumio systemd[1]: Starting Music Player Daemon... Aug 23 15:43:14 volumio sudo[1624]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 15:43:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 23 15:43:14 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 23 15:43:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 23 15:43:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 23 15:43:14 volumio sudo[1630]: pam_unix(sudo:session): session closed for user root Aug 23 15:43:14 volumio volumio[1051]: info: Shairport-Sync Started Aug 23 15:43:14 volumio sudo[1637]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /etc/systemd/system/multi-user.target.wants/fusiondsp.service Aug 23 15:43:14 volumio sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 15:43:14 volumio sudo[1637]: pam_unix(sudo:session): session closed for user root Aug 23 15:43:14 volumio sudo[1631]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 15:43:14 volumio sudo[1631]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 15:43:14 volumio sudo[1624]: pam_unix(sudo:session): session closed for user root Aug 23 15:43:14 volumio volumio[1051]: info: Uninstall script completed Aug 23 15:43:14 volumio sudo[1631]: pam_unix(sudo:session): session closed for user root Aug 23 15:43:14 volumio volumio[1051]: info: Removing plugin fusiondsp from configuration Aug 23 15:43:14 volumio volumio[1051]: info: Successfully removed fusiondsp configuration files Aug 23 15:43:14 volumio volumio[1051]: info: Plugin folders cleanup Aug 23 15:43:14 volumio volumio[1051]: info: Scanning into folder /volumio/app/plugins/ Aug 23 15:43:14 volumio volumio[1051]: info: Scanning category audio_interface Aug 23 15:43:14 volumio volumio[1051]: info: Scanning category miscellanea Aug 23 15:43:14 volumio volumio[1051]: info: Scanning category music_service Aug 23 15:43:14 volumio volumio[1051]: info: Scanning category plugins.json Aug 23 15:43:14 volumio volumio[1051]: info: Scanning category system_controller Aug 23 15:43:14 volumio volumio[1051]: info: Scanning category user_interface Aug 23 15:43:14 volumio volumio[1051]: info: Scanning into folder /data/plugins/ Aug 23 15:43:14 volumio volumio[1051]: info: Scanning category audio_interface Aug 23 15:43:14 volumio volumio[1051]: info: Cleaning folder for fusiondsp Aug 23 15:43:14 volumio volumio[1051]: info: Scanning category system_controller Aug 23 15:43:14 volumio volumio[1051]: info: Scanning category system_hardware Aug 23 15:43:14 volumio volumio[1051]: info: Plugin folders cleanup completed Aug 23 15:43:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 23 15:43:14 volumio volumio[1051]: info: [{"prettyName":"Amplifier Switch","name":"ampswitch","category":"system_controller","version":"0.2.0","icon":"fa-power-off","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Audiophonics ON/OFF","name":"audiophonicsonoff","category":"system_controller","version":"1.0.4","icon":"fa fa-cogs","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"GPIO Buttons","name":"gpio-buttons","category":"system_hardware","version":"0.8.3","icon":"fa-microchip","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"GPIO Control","name":"gpio_control","category":"system_hardware","version":"1.0.5","icon":"fa-microchip","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"IR Activity LED","name":"iractivityled","category":"system_hardware","version":"1.0.0","icon":"fa-microchip","isManuallyInstalled":false,"enabled":true,"active":false}] Aug 23 15:43:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 23 15:43:15 volumio mpd[1642]: Aug 23 15:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 15:43:15 volumio systemd[1]: Started Music Player Daemon. Aug 23 15:43:15 volumio sudo[1617]: pam_unix(sudo:session): session closed for user root Aug 23 15:43:15 volumio volumio[1051]: error: updateQueue error: null Aug 23 15:43:15 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 15:43:15 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 15:43:15 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 23 15:43:15 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 23 15:43:15 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 23 15:43:15 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 23 15:43:15 volumio volumio[1051]: info: CoreCommandRouter::volumioGetBrowseSources Aug 23 15:43:15 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 15:43:16 volumio volumio[1051]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 23 15:43:16 volumio volumio[1051]: info: Completed starting MyVolumio Plugin Aug 23 15:43:16 volumio volumio[1051]: info: BOOT COMPLETED Aug 23 15:43:16 volumio volumio[1051]: [Metrics] CommandRouter: 68s 272.49ms Aug 23 15:43:16 volumio volumio[1051]: info: CoreCommandRouter::volumiosetStartupVolume Aug 23 15:43:16 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 15:43:16 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 15:43:16 volumio volumio[1051]: info: CoreCommandRouter::Close All Modals sent Aug 23 15:43:16 volumio volumio[1051]: info: CoreCommandRouter::Close All Modals sent Aug 23 15:43:17 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 23 15:43:17 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 23 15:43:17 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 23 15:43:22 volumio volumio[1051]: info: CoreCommandRouter::volumioNext Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::next Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::stop Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::stPlaybackTimer Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::updateTrackBlock Aug 23 15:43:22 volumio volumio[1051]: info: CorePlayQueue::getTrackBlock Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:22 volumio volumio[1051]: info: CorePlayQueue::getTrack 3 Aug 23 15:43:22 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:43:22 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::serviceStop Aug 23 15:43:22 volumio volumio[1051]: info: CorePlayQueue::getTrack 3 Aug 23 15:43:22 volumio volumio[1051]: info: CoreCommandRouter::serviceStop Aug 23 15:43:22 volumio volumio[1051]: info: ControllerMpd::stop Aug 23 15:43:22 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 15:43:22 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:43:22 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:43:22 volumio volumio[1051]: info: sendMpdCommand stop took 9 milliseconds Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::play index undefined Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 15:43:22 volumio volumio[1051]: info: CorePlayQueue::getTrack 4 Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::startPlaybackTimer Aug 23 15:43:22 volumio volumio[1051]: info: CorePlayQueue::getTrack 4 Aug 23 15:43:22 volumio volumio[1051]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Mua Nua Dem.wav Aug 23 15:43:22 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::updateTrackBlock Aug 23 15:43:22 volumio volumio[1051]: info: CorePlayQueue::getTrackBlock Aug 23 15:43:22 volumio volumio[1051]: info: sendMpdCommand stop took 1 milliseconds Aug 23 15:43:22 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 15:43:22 volumio volumio[1051]: info: Aug 23 15:43:22 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:43:22 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:43:22 volumio volumio[1051]: info: sendMpdCommand clear took 1 milliseconds Aug 23 15:43:22 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Mua Nua Dem.wav" Aug 23 15:43:22 volumio volumio[1051]: error: updateQueue error: null Aug 23 15:43:22 volumio volumio[1051]: info: Aug 23 15:43:22 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:43:22 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:43:22 volumio volumio[1051]: info: ------------------------------ 2ms Aug 23 15:43:22 volumio volumio[1051]: info: sendMpdCommand add "NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Mua Nua Dem.wav" took 1 milliseconds Aug 23 15:43:22 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand play Aug 23 15:43:22 volumio volumio[1051]: info: ------------------------------ 2ms Aug 23 15:43:22 volumio volumio[1051]: info: sendMpdCommand play took 1 milliseconds Aug 23 15:43:22 volumio volumio[1051]: info: Aug 23 15:43:22 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:43:22 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:43:22 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:43:22 volumio volumio[1051]: info: Aug 23 15:43:22 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:43:22 volumio volumio[1051]: info: sendMpdCommand status took 2 milliseconds Aug 23 15:43:22 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:43:22 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:43:22 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:43:22 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:43:22 volumio volumio[1051]: info: sendMpdCommand status took 1 milliseconds Aug 23 15:43:22 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 23 15:43:22 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:43:22 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:43:22 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:43:22 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:43:22 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:43:22 volumio volumio[1051]: info: CorePlayQueue::getTrack 4 Aug 23 15:43:22 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Mua Nua Dem","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Mua Nua Dem.wav","trackType":"wav"} Aug 23 15:43:22 volumio volumio[1051]: verbose: CURRENT POSITION 4 Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus stop Aug 23 15:43:22 volumio volumio[1051]: info: ------------------------------ 7ms Aug 23 15:43:22 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 23 15:43:22 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:43:22 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:43:22 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:43:22 volumio volumio[1051]: info: CorePlayQueue::getTrack 4 Aug 23 15:43:22 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Mua Nua Dem","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Mua Nua Dem.wav","trackType":"wav"} Aug 23 15:43:22 volumio volumio[1051]: verbose: CURRENT POSITION 4 Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:43:22 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:22 volumio volumio[1051]: info: CorePlayQueue::getTrack 4 Aug 23 15:43:22 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:43:22 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:22 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:22 volumio volumio[1051]: info: CorePlayQueue::getTrack 4 Aug 23 15:43:22 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:22 volumio volumio[1051]: info: ------------------------------ 27ms Aug 23 15:43:22 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:43:22 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:43:25 volumio volumio[1051]: info: CoreCommandRouter::volumioSeek Aug 23 15:43:25 volumio volumio[1051]: info: CoreStateMachine::seek Aug 23 15:43:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 4 Aug 23 15:43:25 volumio volumio[1051]: info: TRACKBLOCK {"uri":"mnt/NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Mua Nua Dem.wav","service":"mpd","name":"Le Quyen - Mua Nua Dem","artist":"Le Quyen","album":"Khúc tình xưa Rip","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=231&web=Le%20Quyen/Kh%C3%BAc%20t%C3%ACnh%20x%C6%B0a%20Rip/extralarge&path=%2FNAS%2FNAS%2FMusic%2FMusic%20Rip%2FLe%20Quyen%20(Khuc%20Tinh%20Xua)&metadata=false","duration":284,"trackType":"wav","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Aug 23 15:43:25 volumio volumio[1051]: info: CoreStateMachine::startPlaybackTimer Aug 23 15:43:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 4 Aug 23 15:43:25 volumio volumio[1051]: info: ControllerMpd::seek Aug 23 15:43:25 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 4 Aug 23 15:43:25 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:43:25 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:25 volumio volumio[1051]: error: null Aug 23 15:43:25 volumio volumio[1051]: info: Aug 23 15:43:25 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:43:25 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:43:25 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:43:25 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:43:25 volumio volumio[1051]: info: sendMpdCommand status took 5 milliseconds Aug 23 15:43:25 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:43:25 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:43:25 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 15:43:25 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:43:25 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:43:25 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:43:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 4 Aug 23 15:43:25 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":274000,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Mua Nua Dem","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Mua Nua Dem.wav","trackType":"wav"} Aug 23 15:43:25 volumio volumio[1051]: verbose: CURRENT POSITION 4 Aug 23 15:43:25 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:43:25 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:43:25 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:43:25 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 4 Aug 23 15:43:25 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:25 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:25 volumio volumio[1051]: info: CorePlayQueue::getTrack 4 Aug 23 15:43:25 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:25 volumio volumio[1051]: info: ------------------------------ 24ms Aug 23 15:43:25 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:43:25 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:43:30 volumio volumio[1051]: info: CorePlayQueue::getTrack 4 Aug 23 15:43:30 volumio volumio[1051]: info: CorePlayQueue::getTrack 5 Aug 23 15:43:30 volumio volumio[1051]: info: Prefetching next song Aug 23 15:43:30 volumio volumio[1051]: info: DOING PREFETCH IN MPD Aug 23 15:43:30 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ngon Truc Dao.wav" Aug 23 15:43:30 volumio volumio[1051]: info: Aug 23 15:43:30 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:43:30 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:43:30 volumio volumio[1051]: info: sendMpdCommand add "NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ngon Truc Dao.wav" took 1 milliseconds Aug 23 15:43:30 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 23 15:43:30 volumio volumio[1051]: info: Aug 23 15:43:30 volumio volumio[1051]: ---------------------------- MPD announces state update: options Aug 23 15:43:30 volumio volumio[1051]: info: ------------------------------ 2ms Aug 23 15:43:30 volumio volumio[1051]: info: sendMpdCommand consume 1 took 1 milliseconds Aug 23 15:43:30 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:43:30 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:43:30 volumio volumio[1051]: info: sendMpdCommand status took 1 milliseconds Aug 23 15:43:30 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:43:30 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:43:30 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 15:43:30 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:43:30 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:43:30 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:43:30 volumio volumio[1051]: info: CorePlayQueue::getTrack 4 Aug 23 15:43:30 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":279765,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Mua Nua Dem","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Mua Nua Dem.wav","trackType":"wav"} Aug 23 15:43:30 volumio volumio[1051]: verbose: CURRENT POSITION 4 Aug 23 15:43:30 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:43:30 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:43:30 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:43:30 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:30 volumio volumio[1051]: info: CorePlayQueue::getTrack 4 Aug 23 15:43:30 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:43:30 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:30 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:30 volumio volumio[1051]: info: CorePlayQueue::getTrack 4 Aug 23 15:43:30 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:30 volumio volumio[1051]: info: ------------------------------ 24ms Aug 23 15:43:30 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:43:30 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:43:34 volumio volumio[1051]: info: CoreStateMachine::startPlaybackTimer Aug 23 15:43:34 volumio volumio[1051]: info: CorePlayQueue::getTrack 5 Aug 23 15:43:34 volumio volumio[1051]: info: Aug 23 15:43:34 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:43:34 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:43:34 volumio volumio[1051]: info: Aug 23 15:43:34 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:43:34 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:43:34 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:43:34 volumio volumio[1051]: info: Aug 23 15:43:34 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:43:34 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:43:34 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:43:34 volumio volumio[1051]: info: ------------------------------ 4ms Aug 23 15:43:34 volumio volumio[1051]: info: sendMpdCommand status took 2 milliseconds Aug 23 15:43:34 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:43:34 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:43:34 volumio volumio[1051]: info: sendMpdCommand status took 2 milliseconds Aug 23 15:43:34 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 15:43:34 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:43:34 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:43:34 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:43:34 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:43:34 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:43:34 volumio volumio[1051]: info: CorePlayQueue::getTrack 5 Aug 23 15:43:34 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Ngon Truc Dao","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ngon Truc Dao.wav","trackType":"wav"} Aug 23 15:43:34 volumio volumio[1051]: verbose: CURRENT POSITION 5 Aug 23 15:43:34 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:43:34 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:43:34 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:43:34 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:34 volumio volumio[1051]: info: CorePlayQueue::getTrack 5 Aug 23 15:43:34 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:43:34 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:34 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:34 volumio volumio[1051]: info: CorePlayQueue::getTrack 5 Aug 23 15:43:34 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:34 volumio volumio[1051]: info: ------------------------------ 31ms Aug 23 15:43:34 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 25 milliseconds Aug 23 15:43:34 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:43:34 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:43:34 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:43:34 volumio volumio[1051]: info: CorePlayQueue::getTrack 5 Aug 23 15:43:34 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Ngon Truc Dao","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ngon Truc Dao.wav","trackType":"wav"} Aug 23 15:43:34 volumio volumio[1051]: verbose: CURRENT POSITION 5 Aug 23 15:43:34 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:43:34 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:43:34 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:43:34 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:34 volumio volumio[1051]: info: CorePlayQueue::getTrack 5 Aug 23 15:43:34 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:34 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:34 volumio volumio[1051]: info: CorePlayQueue::getTrack 5 Aug 23 15:43:34 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:34 volumio volumio[1051]: info: ------------------------------ 49ms Aug 23 15:43:34 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:43:34 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:43:34 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:43:34 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:43:35 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:35 volumio volumio[1051]: info: CorePlayQueue::getTrack 5 Aug 23 15:43:35 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:43:35 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:35 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:43:41 volumio volumio[1051]: info: CoreCommandRouter::volumioNext Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::next Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::stop Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::stPlaybackTimer Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::updateTrackBlock Aug 23 15:43:41 volumio volumio[1051]: info: CorePlayQueue::getTrackBlock Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:41 volumio volumio[1051]: info: CorePlayQueue::getTrack 5 Aug 23 15:43:41 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:43:41 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::serviceStop Aug 23 15:43:41 volumio volumio[1051]: info: CorePlayQueue::getTrack 5 Aug 23 15:43:41 volumio volumio[1051]: info: CoreCommandRouter::serviceStop Aug 23 15:43:41 volumio volumio[1051]: info: ControllerMpd::stop Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 15:43:41 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:43:41 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:43:41 volumio volumio[1051]: info: Aug 23 15:43:41 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:43:41 volumio volumio[1051]: info: sendMpdCommand stop took 10 milliseconds Aug 23 15:43:41 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::play index undefined Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 15:43:41 volumio volumio[1051]: info: CorePlayQueue::getTrack 6 Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::startPlaybackTimer Aug 23 15:43:41 volumio volumio[1051]: info: CorePlayQueue::getTrack 6 Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Sau Le Bong.wav Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::updateTrackBlock Aug 23 15:43:41 volumio volumio[1051]: info: CorePlayQueue::getTrackBlock Aug 23 15:43:41 volumio volumio[1051]: info: sendMpdCommand status took 5 milliseconds Aug 23 15:43:41 volumio volumio[1051]: info: sendMpdCommand stop took 1 milliseconds Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 15:43:41 volumio volumio[1051]: info: Aug 23 15:43:41 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:43:41 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:43:41 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 23 15:43:41 volumio volumio[1051]: info: sendMpdCommand clear took 1 milliseconds Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Sau Le Bong.wav" Aug 23 15:43:41 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:43:41 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:41 volumio volumio[1051]: info: CorePlayQueue::getTrack 6 Aug 23 15:43:41 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:43:41 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:41 volumio volumio[1051]: info: CorePlayQueue::getTrack 6 Aug 23 15:43:41 volumio volumio[1051]: 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":"Le Quyen - Ngon Truc Dao","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Ngon Truc Dao.wav","trackType":"wav"} Aug 23 15:43:41 volumio volumio[1051]: verbose: CURRENT POSITION 6 Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::syncState stateService stop Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus stop Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:41 volumio volumio[1051]: info: CorePlayQueue::getTrack 6 Aug 23 15:43:41 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:41 volumio volumio[1051]: info: No code Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:41 volumio volumio[1051]: info: CorePlayQueue::getTrack 6 Aug 23 15:43:41 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:41 volumio volumio[1051]: info: ------------------------------ 40ms Aug 23 15:43:41 volumio volumio[1051]: error: updateQueue error: null Aug 23 15:43:41 volumio volumio[1051]: info: Aug 23 15:43:41 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Aug 23 15:43:41 volumio volumio[1051]: info: Ignoring MPD Status Update Aug 23 15:43:41 volumio volumio[1051]: info: ------------------------------ 35ms Aug 23 15:43:41 volumio volumio[1051]: info: sendMpdCommand add "NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Sau Le Bong.wav" took 33 milliseconds Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand play Aug 23 15:43:41 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:43:41 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:43:41 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:43:41 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:43:41 volumio volumio[1051]: info: [ASDebug] CurState: stop PrevState: play Aug 23 15:43:41 volumio volumio[1051]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 23 15:43:41 volumio volumio[1051]: info: ------------------------------ 15ms Aug 23 15:43:41 volumio volumio[1051]: info: sendMpdCommand play took 14 milliseconds Aug 23 15:43:41 volumio volumio[1051]: info: Aug 23 15:43:41 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:43:41 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:43:41 volumio volumio[1051]: info: Aug 23 15:43:41 volumio volumio[1051]: ---------------------------- MPD announces state update: player Aug 23 15:43:41 volumio volumio[1051]: info: sendMpdCommand status took 1 milliseconds Aug 23 15:43:41 volumio volumio[1051]: info: ControllerMpd::getState Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:43:41 volumio volumio[1051]: info: sendMpdCommand status took 2 milliseconds Aug 23 15:43:41 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::parseState Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:43:41 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:43:41 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:43:41 volumio volumio[1051]: info: CorePlayQueue::getTrack 6 Aug 23 15:43:41 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":319,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Sau Le Bong","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Sau Le Bong.wav","trackType":"wav"} Aug 23 15:43:41 volumio volumio[1051]: verbose: CURRENT POSITION 6 Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus stop Aug 23 15:43:41 volumio volumio[1051]: info: ------------------------------ 10ms Aug 23 15:43:41 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 23 15:43:41 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Aug 23 15:43:41 volumio volumio[1051]: info: ControllerMpd::pushState Aug 23 15:43:41 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Aug 23 15:43:41 volumio volumio[1051]: info: CorePlayQueue::getTrack 6 Aug 23 15:43:41 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":319,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Le Quyen - Sau Le Bong","artist":"Le Quyen","album":"Khúc tình xưa Rip","uri":"NAS/NAS/Music/Music Rip/Le Quyen (Khuc Tinh Xua)/Le Quyen - Sau Le Bong.wav","trackType":"wav"} Aug 23 15:43:41 volumio volumio[1051]: verbose: CURRENT POSITION 6 Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Aug 23 15:43:41 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:41 volumio volumio[1051]: info: CorePlayQueue::getTrack 6 Aug 23 15:43:41 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 15:43:41 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:41 volumio volumio[1051]: info: CoreStateMachine::pushState Aug 23 15:43:41 volumio volumio[1051]: info: CorePlayQueue::getTrack 6 Aug 23 15:43:41 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Aug 23 15:43:41 volumio volumio[1051]: info: ------------------------------ 46ms Aug 23 15:43:41 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:43:41 volumio volumio[1051]: info: [ASDebug] CurState: play PrevState: play Aug 23 15:43:50 volumio volumio[1051]: info: Starting Uninstall of plugin system_hardware - iractivityled Aug 23 15:43:50 volumio volumio[1051]: info: Uninstalling plugin iractivityled Aug 23 15:43:50 volumio volumio[1051]: info: [IrActivityLed] releasing built-in LED Aug 23 15:43:50 volumio volumio[1051]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 15:43:50 volumio volumio[1051]: TypeError: Cannot read property 'toString' of undefined Aug 23 15:43:50 volumio volumio[1051]: at IrActivityLedController.releaseLed (/data/plugins/system_hardware/iractivityled/index.js:232:61) Aug 23 15:43:50 volumio volumio[1051]: at IrActivityLedController.onStop (/data/plugins/system_hardware/iractivityled/index.js:68:17) Aug 23 15:43:50 volumio volumio[1051]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Aug 23 15:43:50 volumio volumio[1051]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Aug 23 15:43:50 volumio volumio[1051]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1465:22) Aug 23 15:43:50 volumio volumio[1051]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1246:45) Aug 23 15:43:50 volumio volumio[1051]: at Socket.emit (events.js:315:20) Aug 23 15:43:50 volumio volumio[1051]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 23 15:43:50 volumio volumio[1051]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 23 15:43:50 volumio volumio[1051]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 15:43:51 volumio sudo[1771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 15:42 Aug 23 15:43:51 volumio sudo[1771]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"