-- Logs begin at Wed 2025-05-21 22:37:34 CST, end at Wed 2025-05-21 22:49:23 CST. -- May 21 22:48:07 volumio nmbd[745]: [2025/05/21 22:48:07.714135, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 21 22:48:07 volumio nmbd[745]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.6 for name WORKGROUP<1d>. May 21 22:48:07 volumio nmbd[745]: This response was from IP 192.168.1.7, reporting an IP address of 192.168.1.7. May 21 22:48:09 volumio volumio[1089]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 9 May 21 22:48:09 volumio volumio[1089]: info: May 21 22:48:09 volumio volumio[1089]: ---------------------------- MPD announces system playlist update May 21 22:48:09 volumio volumio[1089]: info: Ignoring MPD Status Update May 21 22:48:09 volumio volumio[1089]: info: May 21 22:48:09 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:09 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:09 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:09 volumio volumio[1089]: info: May 21 22:48:09 volumio volumio[1089]: ---------------------------- MPD announces system playlist update May 21 22:48:09 volumio volumio[1089]: info: Ignoring MPD Status Update May 21 22:48:09 volumio volumio[1089]: info: May 21 22:48:09 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:09 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:09 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:09 volumio volumio[1089]: info: May 21 22:48:09 volumio volumio[1089]: ---------------------------- MPD announces system playlist update May 21 22:48:09 volumio volumio[1089]: info: Ignoring MPD Status Update May 21 22:48:09 volumio volumio[1089]: info: May 21 22:48:09 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:09 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:09 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:09 volumio volumio[1089]: info: May 21 22:48:09 volumio volumio[1089]: ---------------------------- MPD announces system playlist update May 21 22:48:09 volumio volumio[1089]: info: Ignoring MPD Status Update May 21 22:48:09 volumio volumio[1089]: info: May 21 22:48:09 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:09 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:09 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:09 volumio volumio[1089]: info: ------------------------------ 3ms May 21 22:48:09 volumio volumio[1089]: info: sendMpdCommand status took 2 milliseconds May 21 22:48:09 volumio volumio[1089]: info: ------------------------------ 2ms May 21 22:48:09 volumio volumio[1089]: info: sendMpdCommand status took 2 milliseconds May 21 22:48:09 volumio volumio[1089]: info: ------------------------------ 2ms May 21 22:48:09 volumio volumio[1089]: info: sendMpdCommand status took 2 milliseconds May 21 22:48:09 volumio volumio[1089]: info: ------------------------------ 2ms May 21 22:48:09 volumio volumio[1089]: info: sendMpdCommand status took 2 milliseconds May 21 22:48:09 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:09 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 22:48:09 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:09 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 22:48:09 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:09 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 22:48:09 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:09 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 22:48:09 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 22:48:09 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 22:48:09 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 3 milliseconds May 21 22:48:09 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 3 milliseconds May 21 22:48:09 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo May 21 22:48:09 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo May 21 22:48:09 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo May 21 22:48:09 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo May 21 22:48:09 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:09 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:09 volumio volumio[1089]: info: CorePlayQueue::getTrack 2 May 21 22:48:09 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":304774,"duration":305,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"101168.mp3","artist":"< µ÷ÒôʦÌص÷ >","album":"< ĸ´ø¹àÈë>","uri":"http://192.168.1.1:2468/web/101168.mp3","trackType":"mp3"} May 21 22:48:09 volumio volumio[1089]: verbose: CURRENT POSITION 2 May 21 22:48:09 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play May 21 22:48:09 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play May 21 22:48:09 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload May 21 22:48:09 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:09 volumio volumio[1089]: info: CorePlayQueue::getTrack 2 May 21 22:48:09 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 22:48:09 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:09 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:09 volumio volumio[1089]: info: CorePlayQueue::getTrack 2 May 21 22:48:09 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:09 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:09 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:09 volumio volumio[1089]: info: CorePlayQueue::getTrack 2 May 21 22:48:09 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":304774,"duration":305,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"101168.mp3","artist":"< µ÷ÒôʦÌص÷ >","album":"< ĸ´ø¹àÈë>","uri":"http://192.168.1.1:2468/web/101168.mp3","trackType":"mp3"} May 21 22:48:09 volumio volumio[1089]: verbose: CURRENT POSITION 2 May 21 22:48:09 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play May 21 22:48:09 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play May 21 22:48:09 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload May 21 22:48:09 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:09 volumio volumio[1089]: info: CorePlayQueue::getTrack 2 May 21 22:48:09 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:09 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:09 volumio volumio[1089]: info: CorePlayQueue::getTrack 2 May 21 22:48:09 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:09 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:09 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:09 volumio volumio[1089]: info: CorePlayQueue::getTrack 2 May 21 22:48:09 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":304774,"duration":305,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"101168.mp3","artist":"< µ÷ÒôʦÌص÷ >","album":"< ĸ´ø¹àÈë>","uri":"http://192.168.1.1:2468/web/101168.mp3","trackType":"mp3"} May 21 22:48:09 volumio volumio[1089]: verbose: CURRENT POSITION 2 May 21 22:48:09 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play May 21 22:48:09 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play May 21 22:48:09 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload May 21 22:48:09 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:09 volumio volumio[1089]: info: CorePlayQueue::getTrack 2 May 21 22:48:09 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:09 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:09 volumio volumio[1089]: info: CorePlayQueue::getTrack 2 May 21 22:48:09 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:09 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:09 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:09 volumio volumio[1089]: info: CorePlayQueue::getTrack 2 May 21 22:48:09 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":304774,"duration":305,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"101168.mp3","artist":"< µ÷ÒôʦÌص÷ >","album":"< ĸ´ø¹àÈë>","uri":"http://192.168.1.1:2468/web/101168.mp3","trackType":"mp3"} May 21 22:48:09 volumio volumio[1089]: verbose: CURRENT POSITION 2 May 21 22:48:09 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play May 21 22:48:09 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play May 21 22:48:09 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload May 21 22:48:09 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:09 volumio volumio[1089]: info: CorePlayQueue::getTrack 2 May 21 22:48:09 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:09 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:09 volumio volumio[1089]: info: CorePlayQueue::getTrack 2 May 21 22:48:09 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:09 volumio volumio[1089]: info: ------------------------------ 22ms May 21 22:48:09 volumio volumio[1089]: info: ------------------------------ 23ms May 21 22:48:09 volumio volumio[1089]: info: ------------------------------ 22ms May 21 22:48:09 volumio volumio[1089]: info: ------------------------------ 22ms May 21 22:48:09 volumio volumio[1089]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 10 May 21 22:48:09 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState May 21 22:48:09 volumio volumio[1089]: info: CorePlayQueue::getTrack 2 May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand status took 1 milliseconds May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand status took 1 milliseconds May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand status took 1 milliseconds May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand status took 1 milliseconds May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 2 May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 2 May 21 22:48:11 volumio volumio[1089]: 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 21 22:48:11 volumio volumio[1089]: verbose: CURRENT POSITION 2 May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState stateService stop May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::play index undefined May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: 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 21 22:48:11 volumio volumio[1089]: verbose: CURRENT POSITION 3 May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState stateService stop May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus stop May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: No code May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: 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 21 22:48:11 volumio volumio[1089]: verbose: CURRENT POSITION 3 May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState stateService stop May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus stop May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: No code May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: 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 21 22:48:11 volumio volumio[1089]: verbose: CURRENT POSITION 3 May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState stateService stop May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus stop May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: No code May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::startPlaybackTimer May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: info: [1747838891209] ControllerUPNPBrowser::clearAddPlayTrack May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand stop May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 21ms May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 22ms May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 21ms May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand stop took 7 milliseconds May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand clear May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces system playlist update May 21 22:48:11 volumio volumio[1089]: info: Ignoring MPD Status Update May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand clear took 10 milliseconds May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.1:2468/web/101169.mp3" May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces system playlist update May 21 22:48:11 volumio volumio[1089]: info: Ignoring MPD Status Update May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces system playlist update May 21 22:48:11 volumio volumio[1089]: info: Ignoring MPD Status Update May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces system playlist update May 21 22:48:11 volumio volumio[1089]: info: Ignoring MPD Status Update May 21 22:48:11 volumio volumio[1089]: error: updateQueue error: null May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 2ms May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.1:2468/web/101169.mp3" May 21 22:48:11 volumio volumio[1089]: error: updateQueue error: null May 21 22:48:11 volumio volumio[1089]: error: updateQueue error: null May 21 22:48:11 volumio volumio[1089]: error: updateQueue error: null May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces system playlist update May 21 22:48:11 volumio volumio[1089]: info: Ignoring MPD Status Update May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 66ms May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 67ms May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 66ms May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand add "http://192.168.1.1:2468/web/101169.mp3" took 2 milliseconds May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand play May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces system playlist update May 21 22:48:11 volumio volumio[1089]: info: Ignoring MPD Status Update May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces system playlist update May 21 22:48:11 volumio volumio[1089]: info: Ignoring MPD Status Update May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces system playlist update May 21 22:48:11 volumio volumio[1089]: info: Ignoring MPD Status Update May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 3ms May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand play took 2 milliseconds May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 2ms May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 1ms May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 1ms May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces system playlist update May 21 22:48:11 volumio volumio[1089]: info: Ignoring MPD Status Update May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand status took 2 milliseconds May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand status took 2 milliseconds May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces system playlist update May 21 22:48:11 volumio volumio[1089]: info: Ignoring MPD Status Update May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces system playlist update May 21 22:48:11 volumio volumio[1089]: info: Ignoring MPD Status Update May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand status took 6 milliseconds May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand status took 5 milliseconds May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 5ms May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand status took 4 milliseconds May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 4 milliseconds May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 3 milliseconds May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 4ms May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand status took 4 milliseconds May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand status took 3 milliseconds May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 3ms May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand status took 2 milliseconds May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":375,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"101169.mp3","artist":"< 调音师特调 >","album":"< 母带灌入>","uri":"http://192.168.1.1:2468/web/101169.mp3","trackType":"mp3"} May 21 22:48:11 volumio volumio[1089]: verbose: CURRENT POSITION 3 May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus stop May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"101169.mp3","artist":"< 调音师特调 >","album":"< 母带灌入>","uri":"http://192.168.1.1:2468/web/101169.mp3","trackType":"mp3"} May 21 22:48:11 volumio volumio[1089]: verbose: CURRENT POSITION 3 May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play May 21 22:48:11 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 16ms May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 14ms May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces system playlist update May 21 22:48:11 volumio volumio[1089]: info: Ignoring MPD Status Update May 21 22:48:11 volumio volumio[1089]: info: May 21 22:48:11 volumio volumio[1089]: ---------------------------- MPD announces state update: player May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::getState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 8 milliseconds May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 8 milliseconds May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 2ms May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand status took 1 milliseconds May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseState May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"101169.mp3","artist":"< 调音师特调 >","album":"< 母带灌入>","uri":"http://192.168.1.1:2468/web/101169.mp3","trackType":"mp3"} May 21 22:48:11 volumio volumio[1089]: verbose: CURRENT POSITION 3 May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play May 21 22:48:11 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"101169.mp3","artist":"< 调音师特调 >","album":"< 母带灌入>","uri":"http://192.168.1.1:2468/web/101169.mp3","trackType":"mp3"} May 21 22:48:11 volumio volumio[1089]: verbose: CURRENT POSITION 3 May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play May 21 22:48:11 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"101169.mp3","artist":"< 调音师特调 >","album":"< 母带灌入>","uri":"http://192.168.1.1:2468/web/101169.mp3","trackType":"mp3"} May 21 22:48:11 volumio volumio[1089]: verbose: CURRENT POSITION 3 May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play May 21 22:48:11 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"101169.mp3","artist":"< 调音师特调 >","album":"< 母带灌入>","uri":"http://192.168.1.1:2468/web/101169.mp3","trackType":"mp3"} May 21 22:48:11 volumio volumio[1089]: verbose: CURRENT POSITION 3 May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play May 21 22:48:11 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"101169.mp3","artist":"< 调音师特调 >","album":"< 母带灌入>","uri":"http://192.168.1.1:2468/web/101169.mp3","trackType":"mp3"} May 21 22:48:11 volumio volumio[1089]: verbose: CURRENT POSITION 3 May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play May 21 22:48:11 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"101169.mp3","artist":"< 调音师特调 >","album":"< 母带灌入>","uri":"http://192.168.1.1:2468/web/101169.mp3","trackType":"mp3"} May 21 22:48:11 volumio volumio[1089]: verbose: CURRENT POSITION 3 May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play May 21 22:48:11 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 64ms May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 64ms May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 64ms May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 63ms May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 63ms May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 62ms May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:11 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 77 milliseconds May 21 22:48:11 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo May 21 22:48:11 volumio volumio[1089]: info: ControllerMpd::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::servicePushState May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getTrack 3 May 21 22:48:11 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"101169.mp3","artist":"< 调音师特调 >","album":"< 母带灌入>","uri":"http://192.168.1.1:2468/web/101169.mp3","trackType":"mp3"} May 21 22:48:11 volumio volumio[1089]: verbose: CURRENT POSITION 3 May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play May 21 22:48:11 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: ------------------------------ 89ms May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:11 volumio volumio[1089]: info: VolumeController::SetAlsaVolume40 May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::pushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState May 21 22:48:11 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 21 22:48:11 volumio volumio[1089]: info: CoreStateMachine::getQueue May 21 22:48:11 volumio volumio[1089]: info: CorePlayQueue::getQueue May 21 22:48:29 volumio ntpd[3647]: Soliciting pool server 162.159.200.123 May 21 22:48:30 volumio ntpd[3647]: Soliciting pool server 193.182.111.143 May 21 22:48:30 volumio volumio[1089]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 10 May 21 22:48:30 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState May 21 22:48:30 volumio volumio[1089]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 11 May 21 22:48:30 volumio volumio[1089]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 22:48:30 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 22:48:32 volumio ntpd[3647]: Soliciting pool server 211.68.71.26 May 21 22:48:36 volumio ntpd[3647]: Soliciting pool server 2001:1600:4:1::123 May 21 22:48:49 volumio volumio[1089]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 12 May 21 22:48:49 volumio volumio[1089]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 13 May 21 22:48:49 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState May 21 22:49:08 volumio volumio[1089]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 14 May 21 22:49:08 volumio volumio[1089]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 15 May 21 22:49:09 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState May 21 22:49:23 volumio volumio[1089]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 22:49:23 volumio volumio[1089]: Error: connect ETIMEDOUT 199.16.156.7:80 May 21 22:49:23 volumio volumio[1089]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 21 22:49:23 volumio volumio[1089]: errno: -110, May 21 22:49:23 volumio volumio[1089]: code: 'ETIMEDOUT', May 21 22:49:23 volumio volumio[1089]: syscall: 'connect', May 21 22:49:23 volumio volumio[1089]: address: '199.16.156.7', May 21 22:49:23 volumio volumio[1089]: port: 80 May 21 22:49:23 volumio volumio[1089]: } May 21 22:49:23 volumio volumio[1089]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 22:49:23 volumio sudo[4836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 22:48 May 21 22:49:23 volumio sudo[4836]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"