-- Logs begin at Thu 2024-05-09 07:08:15 UTC, end at Thu 2024-05-09 07:12:01 UTC. -- May 09 07:11:00 volumio volumio[800]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 10 May 09 07:11:00 volumio volumio[800]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 11 May 09 07:11:00 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 09 07:11:00 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: info: CoreCommandRouter::volumioNext May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::next May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::stop May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::stPlaybackTimer May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::updateTrackBlock May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrackBlock May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:11:11 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::serviceStop May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: info: CoreCommandRouter::serviceStop May 09 07:11:11 volumio volumio[800]: info: ControllerMpd::stop May 09 07:11:11 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand stop May 09 07:11:11 volumio volumio[800]: info: sendMpdCommand stop took 18 milliseconds May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::play index undefined May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::startPlaybackTimer May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/吕方 - 你的浅笑.wav May 09 07:11:11 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand stop May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::updateTrackBlock May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrackBlock May 09 07:11:11 volumio volumio[800]: info: May 09 07:11:11 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:11 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:11 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:11 volumio volumio[800]: info: sendMpdCommand stop took 1 milliseconds May 09 07:11:11 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand clear May 09 07:11:11 volumio volumio[800]: info: May 09 07:11:11 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:11 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:11 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:11 volumio volumio[800]: info: May 09 07:11:11 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:11 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:11 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:11 volumio volumio[800]: info: May 09 07:11:11 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:11 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:11 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:11 volumio volumio[800]: info: May 09 07:11:11 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:11 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:11 volumio volumio[800]: info: sendMpdCommand status took 3 milliseconds May 09 07:11:11 volumio volumio[800]: info: sendMpdCommand clear took 2 milliseconds May 09 07:11:11 volumio volumio[800]: info: sendMpdCommand status took 2 milliseconds May 09 07:11:11 volumio volumio[800]: info: sendMpdCommand status took 2 milliseconds May 09 07:11:11 volumio volumio[800]: info: sendMpdCommand status took 2 milliseconds May 09 07:11:11 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:11 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:11 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/吕方 - 你的浅笑.wav" May 09 07:11:11 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:11 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:11 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:11 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:11 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 09 07:11:11 volumio volumio[800]: verbose: CURRENT POSITION 0 May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::syncState stateService stop May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:11 volumio volumio[800]: info: No code May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:11 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:11 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 09 07:11:11 volumio volumio[800]: verbose: CURRENT POSITION 0 May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::syncState stateService stop May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:11 volumio volumio[800]: info: No code May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:11 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:11 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 09 07:11:11 volumio volumio[800]: verbose: CURRENT POSITION 0 May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::syncState stateService stop May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:11 volumio volumio[800]: info: No code May 09 07:11:11 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:11 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:11 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 75ms May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 74ms May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 75ms May 09 07:11:12 volumio volumio[800]: info: May 09 07:11:12 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:12 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:12 volumio volumio[800]: info: May 09 07:11:12 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:12 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:12 volumio volumio[800]: info: May 09 07:11:12 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:12 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:12 volumio volumio[800]: error: updateQueue error: null May 09 07:11:12 volumio volumio[800]: info: May 09 07:11:12 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:12 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 76ms May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand playlistinfo took 75 milliseconds May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand add "INTERNAL/吕方 - 你的浅笑.wav" took 76 milliseconds May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 3ms May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 2ms May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 2ms May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand play May 09 07:11:12 volumio volumio[800]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 81ms May 09 07:11:12 volumio volumio[800]: info: May 09 07:11:12 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:12 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:12 volumio volumio[800]: info: May 09 07:11:12 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:12 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:12 volumio volumio[800]: info: May 09 07:11:12 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:12 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:12 volumio volumio[800]: info: May 09 07:11:12 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:12 volumio volumio[800]: info: May 09 07:11:12 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 17ms May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand play took 15 milliseconds May 09 07:11:12 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 15ms May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 13ms May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 12ms May 09 07:11:12 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:12 volumio volumio[800]: info: May 09 07:11:12 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:12 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:12 volumio volumio[800]: info: May 09 07:11:12 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:12 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:12 volumio volumio[800]: info: May 09 07:11:12 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:12 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand status took 3 milliseconds May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand status took 2 milliseconds May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand status took 1 milliseconds May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand status took 2 milliseconds May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand status took 1 milliseconds May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:12 volumio volumio[800]: info: May 09 07:11:12 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:12 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:12 volumio volumio[800]: info: May 09 07:11:12 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:12 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:12 volumio volumio[800]: info: May 09 07:11:12 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:12 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand playlistinfo took 4 milliseconds May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand playlistinfo took 4 milliseconds May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand playlistinfo took 3 milliseconds May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand playlistinfo took 3 milliseconds May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand playlistinfo took 4 milliseconds May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand status took 3 milliseconds May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand status took 2 milliseconds May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand status took 1 milliseconds May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:12 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"吕方 - 你的浅笑.wav","artist":null,"album":null,"uri":"INTERNAL/吕方 - 你的浅笑.wav","trackType":"wav"} May 09 07:11:12 volumio volumio[800]: verbose: CURRENT POSITION 0 May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop May 09 07:11:12 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"吕方 - 你的浅笑.wav","artist":null,"album":null,"uri":"INTERNAL/吕方 - 你的浅笑.wav","trackType":"wav"} May 09 07:11:12 volumio volumio[800]: verbose: CURRENT POSITION 0 May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus play May 09 07:11:12 volumio volumio[800]: info: Received an update from plugin. extracting info from payload May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:12 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"吕方 - 你的浅笑.wav","artist":null,"album":null,"uri":"INTERNAL/吕方 - 你的浅笑.wav","trackType":"wav"} May 09 07:11:12 volumio volumio[800]: verbose: CURRENT POSITION 0 May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus play May 09 07:11:12 volumio volumio[800]: info: Received an update from plugin. extracting info from payload May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:12 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"吕方 - 你的浅笑.wav","artist":null,"album":null,"uri":"INTERNAL/吕方 - 你的浅笑.wav","trackType":"wav"} May 09 07:11:12 volumio volumio[800]: verbose: CURRENT POSITION 0 May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus play May 09 07:11:12 volumio volumio[800]: info: Received an update from plugin. extracting info from payload May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:12 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"吕方 - 你的浅笑.wav","artist":null,"album":null,"uri":"INTERNAL/吕方 - 你的浅笑.wav","trackType":"wav"} May 09 07:11:12 volumio volumio[800]: verbose: CURRENT POSITION 0 May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus play May 09 07:11:12 volumio volumio[800]: info: Received an update from plugin. extracting info from payload May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 22ms May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 73ms May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 72ms May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 71ms May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 72ms May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand playlistinfo took 66 milliseconds May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand playlistinfo took 65 milliseconds May 09 07:11:12 volumio volumio[800]: info: sendMpdCommand playlistinfo took 65 milliseconds May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:12 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:12 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"吕方 - 你的浅笑.wav","artist":null,"album":null,"uri":"INTERNAL/吕方 - 你的浅笑.wav","trackType":"wav"} May 09 07:11:12 volumio volumio[800]: verbose: CURRENT POSITION 0 May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus play May 09 07:11:12 volumio volumio[800]: info: Received an update from plugin. extracting info from payload May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:12 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"吕方 - 你的浅笑.wav","artist":null,"album":null,"uri":"INTERNAL/吕方 - 你的浅笑.wav","trackType":"wav"} May 09 07:11:12 volumio volumio[800]: verbose: CURRENT POSITION 0 May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus play May 09 07:11:12 volumio volumio[800]: info: Received an update from plugin. extracting info from payload May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:12 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"吕方 - 你的浅笑.wav","artist":null,"album":null,"uri":"INTERNAL/吕方 - 你的浅笑.wav","trackType":"wav"} May 09 07:11:12 volumio volumio[800]: verbose: CURRENT POSITION 0 May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus play May 09 07:11:12 volumio volumio[800]: info: Received an update from plugin. extracting info from payload May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:12 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:12 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 108ms May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 107ms May 09 07:11:12 volumio volumio[800]: info: ------------------------------ 108ms May 09 07:11:18 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 09 07:11:18 volumio volumio[800]: info: CURURI: music-library/USB May 09 07:11:18 volumio volumio[800]: info: Preload queue cleared May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 09 07:11:19 volumio volumio[800]: info: CURURI: music-library/USB/CE2EE83FF80E51B4 May 09 07:11:19 volumio volumio[800]: info: Preload queue cleared May 09 07:11:19 volumio volumio[800]: info: Preload queue cleared May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioReplaceandPlayItems May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::ClearQueue May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::stop May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::stPlaybackTimer May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::updateTrackBlock May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrackBlock May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::serviceStop May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::serviceStop May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::stop May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand stop May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::clearPlayQueue May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::saveQueue May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushQueue May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::addQueueItems May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::addQueueItems May 09 07:11:19 volumio volumio[800]: info: Preload queue cleared May 09 07:11:19 volumio volumio[800]: info: Adding Item to queue: music-library/USB/CE2EE83FF80E51B4/06 千載不變.wav May 09 07:11:19 volumio volumio[800]: info: Using cached record of: music-library/USB/CE2EE83FF80E51B4/06 千載不變.wav May 09 07:11:19 volumio volumio[800]: info: Adding Item to queue: music-library/USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav May 09 07:11:19 volumio volumio[800]: info: Using cached record of: music-library/USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushQueue May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::saveQueue May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::updateTrackBlock May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrackBlock May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPlay May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::play index 1 May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::stop May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::play index undefined May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::startPlaybackTimer May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::clearAddPlayTracks USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand stop May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand stop took 23 milliseconds May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand stop took 4 milliseconds May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand clear May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:19 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand status took 3 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand clear took 3 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand status took 2 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand status took 2 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand status took 1 milliseconds May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand add "USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav" May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 09 07:11:19 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState stateService stop May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: No code May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 09 07:11:19 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState stateService stop May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: No code May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 09 07:11:19 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState stateService stop May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: No code May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 68ms May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 68ms May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 68ms May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:19 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:19 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:19 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:19 volumio volumio[800]: error: updateQueue error: null May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:19 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 70ms May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand playlistinfo took 69 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand add "USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav" took 69 milliseconds May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 3ms May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 2ms May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 2ms May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand play May 09 07:11:19 volumio volumio[800]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 74ms May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:19 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:19 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:19 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 21ms May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand play took 19 milliseconds May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 20ms May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 19ms May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 19ms May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand status took 3 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand status took 3 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand status took 1 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand status took 1 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand status took 1 milliseconds May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:19 volumio volumio[800]: info: May 09 07:11:19 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand playlistinfo took 2 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand playlistinfo took 2 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand playlistinfo took 2 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand playlistinfo took 2 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand playlistinfo took 3 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand status took 2 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand status took 2 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand status took 1 milliseconds May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":762,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"街頭霸王榜","artist":"林子祥","album":null,"uri":"USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav","trackType":"wav"} May 09 07:11:19 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":762,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"街頭霸王榜","artist":"林子祥","album":null,"uri":"USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav","trackType":"wav"} May 09 07:11:19 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus play May 09 07:11:19 volumio volumio[800]: info: Received an update from plugin. extracting info from payload May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":762,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"街頭霸王榜","artist":"林子祥","album":null,"uri":"USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav","trackType":"wav"} May 09 07:11:19 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus play May 09 07:11:19 volumio volumio[800]: info: Received an update from plugin. extracting info from payload May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":762,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"街頭霸王榜","artist":"林子祥","album":null,"uri":"USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav","trackType":"wav"} May 09 07:11:19 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus play May 09 07:11:19 volumio volumio[800]: info: Received an update from plugin. extracting info from payload May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":762,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"街頭霸王榜","artist":"林子祥","album":null,"uri":"USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav","trackType":"wav"} May 09 07:11:19 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus play May 09 07:11:19 volumio volumio[800]: info: Received an update from plugin. extracting info from payload May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 25ms May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 62ms May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 60ms May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 60ms May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 60ms May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand playlistinfo took 56 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand playlistinfo took 55 milliseconds May 09 07:11:19 volumio volumio[800]: info: sendMpdCommand playlistinfo took 55 milliseconds May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:19 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":762,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"街頭霸王榜","artist":"林子祥","album":null,"uri":"USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav","trackType":"wav"} May 09 07:11:19 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus play May 09 07:11:19 volumio volumio[800]: info: Received an update from plugin. extracting info from payload May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":762,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"街頭霸王榜","artist":"林子祥","album":null,"uri":"USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav","trackType":"wav"} May 09 07:11:19 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus play May 09 07:11:19 volumio volumio[800]: info: Received an update from plugin. extracting info from payload May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":762,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"街頭霸王榜","artist":"林子祥","album":null,"uri":"USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav","trackType":"wav"} May 09 07:11:19 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus play May 09 07:11:19 volumio volumio[800]: info: Received an update from plugin. extracting info from payload May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:19 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:19 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 102ms May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 101ms May 09 07:11:19 volumio volumio[800]: info: ------------------------------ 101ms May 09 07:11:19 volumio volumio[800]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9E%97%E5%AD%90%E7%A5%A5/1a1ab959-a6e4-42cd-939d-5b800c46deba.jpg' May 09 07:11:22 volumio volumio[800]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 12 May 09 07:11:22 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 09 07:11:22 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:23 volumio volumio[800]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 12 May 09 07:11:23 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 09 07:11:23 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:23 volumio volumio[800]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9E%97%E5%AD%90%E7%A5%A5/1a1ab959-a6e4-42cd-939d-5b800c46deba.jpg' May 09 07:11:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 07:11:37 volumio volumio[800]: info: CoreCommandRouter::Close All Modals sent May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::stop May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::stPlaybackTimer May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::updateTrackBlock May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrackBlock May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::serviceStop May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::serviceStop May 09 07:11:39 volumio volumio[800]: info: ControllerMpd::stop May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand stop May 09 07:11:39 volumio volumio[800]: info: Disabling plugin peppy_screensaver May 09 07:11:39 volumio volumio[800]: info: Preparing to generate the ALSA configuration file May 09 07:11:39 volumio volumio[800]: info: May 09 07:11:39 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:39 volumio volumio[800]: info: sendMpdCommand stop took 18 milliseconds May 09 07:11:39 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:39 volumio volumio[800]: info: May 09 07:11:39 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:39 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:39 volumio volumio[800]: info: May 09 07:11:39 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:39 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:39 volumio volumio[800]: info: May 09 07:11:39 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:39 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:39 volumio volumio[800]: info: sendMpdCommand status took 18 milliseconds May 09 07:11:39 volumio volumio[800]: info: sendMpdCommand status took 1 milliseconds May 09 07:11:39 volumio volumio[800]: info: sendMpdCommand status took 1 milliseconds May 09 07:11:39 volumio volumio[800]: info: sendMpdCommand status took 1 milliseconds May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:39 volumio volumio[800]: info: Asound.conf file written May 09 07:11:39 volumio sudo[2225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl May 09 07:11:39 volumio sudo[2225]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:11:39 volumio sudo[2225]: pam_unix(sudo:session): session closed for user root May 09 07:11:39 volumio systemd[1]: volumio-app-plugins-music_service-mpd-mpd.conf.tmpl.mount: Succeeded. May 09 07:11:39 volumio sudo[2228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 09 07:11:39 volumio sudo[2228]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:11:39 volumio sudo[2228]: pam_unix(sudo:session): session closed for user root May 09 07:11:39 volumio volumio[800]: info: Output device has changed, restarting MPD May 09 07:11:39 volumio sudo[2233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 07:11:39 volumio sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:11:39 volumio volumio[800]: info: Output device has changed, restarting Shairport Sync May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:11:39 volumio sudo[2233]: pam_unix(sudo:session): session closed for user root May 09 07:11:39 volumio sudo[2236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 07:11:39 volumio sudo[2236]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:11:39 volumio systemd[1]: Stopping Music Player Daemon... May 09 07:11:39 volumio volumio[800]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 07:11:39 volumio volumio[800]: info: Done. May 09 07:11:39 volumio volumio[800]: info: sendMpdCommand playlistinfo took 85 milliseconds May 09 07:11:39 volumio volumio[800]: info: sendMpdCommand playlistinfo took 84 milliseconds May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:39 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:39 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"街頭霸王榜","artist":"林子祥","album":null,"uri":"USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav","trackType":"wav"} May 09 07:11:39 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::syncState stateService stop May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:39 volumio volumio[800]: info: No code May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:39 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"街頭霸王榜","artist":"林子祥","album":null,"uri":"USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav","trackType":"wav"} May 09 07:11:39 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::syncState stateService stop May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:39 volumio volumio[800]: info: No code May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:39 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"街頭霸王榜","artist":"林子祥","album":null,"uri":"USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav","trackType":"wav"} May 09 07:11:39 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::syncState stateService stop May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:39 volumio volumio[800]: info: No code May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:39 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"街頭霸王榜","artist":"林子祥","album":null,"uri":"USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav","trackType":"wav"} May 09 07:11:39 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::syncState stateService stop May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:39 volumio volumio[800]: info: No code May 09 07:11:39 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:39 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:39 volumio systemd[1]: mpd.service: Succeeded. May 09 07:11:39 volumio systemd[1]: Stopped Music Player Daemon. May 09 07:11:39 volumio systemd[1]: Starting Music Player Daemon... May 09 07:11:39 volumio volumio[800]: info: ------------------------------ 207ms May 09 07:11:39 volumio volumio[800]: info: ------------------------------ 191ms May 09 07:11:39 volumio volumio[800]: info: ------------------------------ 190ms May 09 07:11:39 volumio volumio[800]: info: ------------------------------ 191ms May 09 07:11:39 volumio volumio[800]: info: MPD Permissions set May 09 07:11:39 volumio volumio[800]: error: Upnp client error: Error: This socket has been ended by the other party May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:39 volumio volumio[800]: info: Starting Shairport Sync May 09 07:11:39 volumio sudo[2251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 07:11:39 volumio sudo[2251]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:11:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 07:11:39 volumio systemd[1]: shairport-sync.service: Succeeded. May 09 07:11:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 07:11:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 07:11:39 volumio sudo[2251]: pam_unix(sudo:session): session closed for user root May 09 07:11:39 volumio volumio[800]: info: Shairport-Sync Started May 09 07:11:39 volumio mpd[2241]: May 09 07:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 09 07:11:39 volumio systemd[1]: Started Music Player Daemon. May 09 07:11:39 volumio sudo[2236]: pam_unix(sudo:session): session closed for user root May 09 07:11:39 volumio volumio[800]: error: updateQueue error: null May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:42 volumio volumio[800]: info: Enabling plugin peppy_screensaver May 09 07:11:42 volumio volumio[800]: info: Loading plugin "peppy_screensaver"... May 09 07:11:42 volumio volumio[800]: info: Applying required configuration parameters for plugin peppy_screensaver May 09 07:11:42 volumio volumio[800]: info: Preparing to generate the ALSA configuration file May 09 07:11:42 volumio volumio[800]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 09 07:11:42 volumio volumio[800]: info: Reading ALSA contributions from plugins. May 09 07:11:42 volumio volumio[800]: info: Asound.conf file written May 09 07:11:42 volumio sudo[2261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 09 07:11:42 volumio sudo[2261]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:11:42 volumio sudo[2261]: pam_unix(sudo:session): session closed for user root May 09 07:11:42 volumio volumio[800]: info: Output device has changed, restarting MPD May 09 07:11:42 volumio volumio[800]: info: Output device has changed, restarting Shairport Sync May 09 07:11:42 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:42 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:11:42 volumio sudo[2266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 07:11:42 volumio sudo[2266]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:11:42 volumio sudo[2266]: pam_unix(sudo:session): session closed for user root May 09 07:11:42 volumio sudo[2268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 07:11:42 volumio sudo[2268]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:11:42 volumio systemd[1]: Stopping Music Player Daemon... May 09 07:11:42 volumio volumio[800]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 07:11:42 volumio volumio[800]: info: PLUGIN START: peppy_screensaver May 09 07:11:42 volumio systemd[1]: mpd.service: Succeeded. May 09 07:11:42 volumio volumio[800]: info: Loading i18n strings for locale zh May 09 07:11:42 volumio systemd[1]: Stopped Music Player Daemon. May 09 07:11:42 volumio volumio[800]: info: /tmp/myfifo created May 09 07:11:42 volumio volumio[800]: info: /tmp/myfifosa created May 09 07:11:42 volumio systemd[1]: Starting Music Player Daemon... May 09 07:11:42 volumio sudo[2280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 May 09 07:11:42 volumio sudo[2280]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:11:42 volumio sudo[2280]: pam_unix(sudo:session): session closed for user root May 09 07:11:42 volumio volumio[800]: info: snd-dummy loaded May 09 07:11:42 volumio volumio[800]: info: Error: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' May 09 07:11:42 volumio volumio[800]: info: MPD Permissions set May 09 07:11:42 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:42 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:42 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:42 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:42 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:42 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:42 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:11:42 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 09 07:11:42 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:42 volumio volumio[800]: info: Starting Shairport Sync May 09 07:11:42 volumio sudo[2289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 07:11:42 volumio sudo[2289]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:11:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 07:11:42 volumio systemd[1]: shairport-sync.service: Succeeded. May 09 07:11:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 07:11:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 07:11:42 volumio sudo[2289]: pam_unix(sudo:session): session closed for user root May 09 07:11:42 volumio volumio[800]: info: Shairport-Sync Started May 09 07:11:42 volumio mpd[2274]: May 09 07:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 09 07:11:42 volumio systemd[1]: Started Music Player Daemon. May 09 07:11:42 volumio sudo[2268]: pam_unix(sudo:session): session closed for user root May 09 07:11:42 volumio volumio[800]: error: updateQueue error: null May 09 07:11:42 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:42 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:11:43 volumio volumio[800]: error: Upnp client error: Error: This socket has been ended by the other party May 09 07:11:43 volumio volumio[800]: info: CoreCommandRouter::volumioPlay May 09 07:11:43 volumio volumio[800]: info: CoreStateMachine::play index undefined May 09 07:11:43 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 07:11:43 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:43 volumio volumio[800]: info: CoreStateMachine::startPlaybackTimer May 09 07:11:43 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:43 volumio volumio[800]: verbose: ControllerMpd::clearAddPlayTracks USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav May 09 07:11:43 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand stop May 09 07:11:43 volumio volumio[800]: info: sendMpdCommand stop took 1 milliseconds May 09 07:11:43 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand clear May 09 07:11:43 volumio volumio[800]: info: May 09 07:11:43 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:43 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:43 volumio volumio[800]: info: sendMpdCommand clear took 1 milliseconds May 09 07:11:43 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand add "USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav" May 09 07:11:43 volumio volumio[800]: error: updateQueue error: null May 09 07:11:43 volumio volumio[800]: info: May 09 07:11:43 volumio volumio[800]: ---------------------------- MPD announces system playlist update May 09 07:11:43 volumio volumio[800]: info: Ignoring MPD Status Update May 09 07:11:43 volumio volumio[800]: info: ------------------------------ 1ms May 09 07:11:43 volumio volumio[800]: info: sendMpdCommand add "USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav" took 1 milliseconds May 09 07:11:43 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand play May 09 07:11:43 volumio volumio[800]: info: ------------------------------ 1ms May 09 07:11:43 volumio volumio[800]: info: sendMpdCommand play took 1 milliseconds May 09 07:11:43 volumio volumio[800]: info: May 09 07:11:43 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:43 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:43 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:43 volumio volumio[800]: info: May 09 07:11:43 volumio volumio[800]: ---------------------------- MPD announces state update: player May 09 07:11:43 volumio volumio[800]: info: sendMpdCommand status took 21 milliseconds May 09 07:11:43 volumio volumio[800]: info: ControllerMpd::getState May 09 07:11:43 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status May 09 07:11:43 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:43 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:43 volumio volumio[800]: info: sendMpdCommand status took 0 milliseconds May 09 07:11:43 volumio volumio[800]: info: sendMpdCommand playlistinfo took 0 milliseconds May 09 07:11:43 volumio volumio[800]: verbose: ControllerMpd::parseState May 09 07:11:43 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:11:43 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:43 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:43 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:43 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:43 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":762,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"街頭霸王榜","artist":"林子祥","album":null,"uri":"USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav","trackType":"wav"} May 09 07:11:43 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:43 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:43 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop May 09 07:11:43 volumio volumio[800]: info: ------------------------------ 24ms May 09 07:11:43 volumio volumio[800]: info: sendMpdCommand playlistinfo took 1 milliseconds May 09 07:11:43 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo May 09 07:11:43 volumio volumio[800]: info: ControllerMpd::pushState May 09 07:11:43 volumio volumio[800]: info: CoreCommandRouter::servicePushState May 09 07:11:43 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:43 volumio volumio[800]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":762,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"街頭霸王榜","artist":"林子祥","album":null,"uri":"USB/CE2EE83FF80E51B4/06.01 街頭霸王榜.wav","trackType":"wav"} May 09 07:11:43 volumio volumio[800]: verbose: CURRENT POSITION 1 May 09 07:11:43 volumio volumio[800]: info: CoreStateMachine::syncState stateService play May 09 07:11:43 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus play May 09 07:11:43 volumio volumio[800]: info: Received an update from plugin. extracting info from payload May 09 07:11:43 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:43 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:43 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:11:43 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:43 volumio volumio[800]: info: CoreStateMachine::pushState May 09 07:11:43 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:43 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 09 07:11:43 volumio volumio[800]: info: ------------------------------ 25ms May 09 07:11:46 volumio volumio[800]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 13 May 09 07:11:46 volumio volumio[800]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 14 May 09 07:11:46 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 09 07:11:46 volumio volumio[800]: info: CorePlayQueue::getTrack 1 May 09 07:11:46 volumio volumio[800]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9E%97%E5%AD%90%E7%A5%A5/1a1ab959-a6e4-42cd-939d-5b800c46deba.jpg' May 09 07:12:01 volumio volumio[800]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 07:12:01 volumio volumio[800]: Error: connect ETIMEDOUT 104.244.46.165:80 May 09 07:12:01 volumio volumio[800]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 09 07:12:01 volumio volumio[800]: errno: -110, May 09 07:12:01 volumio volumio[800]: code: 'ETIMEDOUT', May 09 07:12:01 volumio volumio[800]: syscall: 'connect', May 09 07:12:01 volumio volumio[800]: address: '104.244.46.165', May 09 07:12:01 volumio volumio[800]: port: 80 May 09 07:12:01 volumio volumio[800]: } May 09 07:12:01 volumio volumio[800]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 07:12:01 volumio sudo[2321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 07:11 May 09 07:12:01 volumio sudo[2321]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"