-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Fri 2025-06-27 23:58:52 CST. -- Jun 27 23:57:00 volumio volumio[8316]: info: Preload queue cleared Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::ClearQueue Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::stop Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::stPlaybackTimer Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::updateTrackBlock Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::getTrackBlock Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::pushState Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::getTrack 0 Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::volumioPushState Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::serviceStop Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::getTrack 0 Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::serviceStop Jun 27 23:57:00 volumio volumio[8316]: info: [1751039820234] ControllerUPNPBrowser::stop Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::clearPlayQueue Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::saveQueue Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::volumioPushQueue Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::addQueueItems Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::addQueueItems Jun 27 23:57:00 volumio volumio[8316]: info: Preload queue cleared Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$81 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$81 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$6E Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$6E Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$7C Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$7C Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$5B Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$5B Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$7E Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$7E Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$51 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$51 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$80 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$80 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$50 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$50 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$53 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$53 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$66 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$66 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$8B Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$8B Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$60 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$60 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$83 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$83 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$69 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$69 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$67 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$67 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$68 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$68 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$8D Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$8D Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$54 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$54 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$8F Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$8F Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$88 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$88 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$59 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$59 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$5E Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$5E Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$86 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$86 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$5C Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$5C Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::volumioPushQueue Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::saveQueue Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::updateTrackBlock Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::getTrackBlock Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::volumioPlay Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::play index 23 Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::addQueueItems Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::addQueueItems Jun 27 23:57:00 volumio volumio[8316]: info: Preload queue cleared Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$6B Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$6B Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$7F Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$7F Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$82 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$82 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$52 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$52 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$84 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$84 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$87 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$87 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$85 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$85 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$89 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$89 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$61 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$61 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$8C Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$8C Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$6C Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$6C Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$63 Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$63 Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$8E Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$8E Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$8A Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$8A Jun 27 23:57:00 volumio volumio[8316]: info: Adding Item to queue: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$6D Jun 27 23:57:00 volumio volumio[8316]: info: Using cached record of: upnp/http://192.168.0.103:8200/ctl/ContentDir@1$4$6D Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::stop Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::volumioPushQueue Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::saveQueue Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::play index undefined Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::updateTrackBlock Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::getTrackBlock Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::getTrack 23 Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::startPlaybackTimer Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::getTrack 23 Jun 27 23:57:00 volumio volumio[8316]: info: [1751039820252] ControllerUPNPBrowser::clearAddPlayTrack Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 23:57:00 volumio volumio[8316]: info: Jun 27 23:57:00 volumio volumio[8316]: ---------------------------- MPD announces state update: player Jun 27 23:57:00 volumio volumio[8316]: info: sendMpdCommand stop took 36 milliseconds Jun 27 23:57:00 volumio volumio[8316]: info: ControllerMpd::getState Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::sendMpdCommand status Jun 27 23:57:00 volumio volumio[8316]: info: sendMpdCommand stop took 18 milliseconds Jun 27 23:57:00 volumio volumio[8316]: info: sendMpdCommand status took 0 milliseconds Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::sendMpdCommand clear Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::parseState Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 23:57:00 volumio volumio[8316]: info: Jun 27 23:57:00 volumio volumio[8316]: ---------------------------- MPD announces system playlist update Jun 27 23:57:00 volumio volumio[8316]: info: Ignoring MPD Status Update Jun 27 23:57:00 volumio volumio[8316]: info: sendMpdCommand clear took 0 milliseconds Jun 27 23:57:00 volumio volumio[8316]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.103:8200/MediaItems/443.flac" Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::parseTrackInfo Jun 27 23:57:00 volumio volumio[8316]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 27 23:57:00 volumio volumio[8316]: info: ------------------------------ 3ms Jun 27 23:57:00 volumio volumio[8316]: error: updateQueue error: null Jun 27 23:57:00 volumio volumio[8316]: info: ------------------------------ 2ms Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.103:8200/MediaItems/443.flac" Jun 27 23:57:00 volumio volumio[8316]: info: Jun 27 23:57:00 volumio volumio[8316]: ---------------------------- MPD announces system playlist update Jun 27 23:57:00 volumio volumio[8316]: info: Ignoring MPD Status Update Jun 27 23:57:00 volumio volumio[8316]: info: sendMpdCommand add "http://192.168.0.103:8200/MediaItems/443.flac" took 1 milliseconds Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::sendMpdCommand play Jun 27 23:57:00 volumio volumio[8316]: info: ------------------------------ 1ms Jun 27 23:57:00 volumio volumio[8316]: info: sendMpdCommand play took 1 milliseconds Jun 27 23:57:00 volumio volumio[8316]: info: Jun 27 23:57:00 volumio volumio[8316]: ---------------------------- MPD announces state update: player Jun 27 23:57:00 volumio volumio[8316]: info: ControllerMpd::getState Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::sendMpdCommand status Jun 27 23:57:00 volumio volumio[8316]: info: Jun 27 23:57:00 volumio volumio[8316]: ---------------------------- MPD announces state update: player Jun 27 23:57:00 volumio volumio[8316]: info: sendMpdCommand status took 7 milliseconds Jun 27 23:57:00 volumio volumio[8316]: info: ControllerMpd::getState Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::sendMpdCommand status Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::parseState Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 23:57:00 volumio volumio[8316]: info: sendMpdCommand status took 1 milliseconds Jun 27 23:57:00 volumio volumio[8316]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::parseState Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::parseTrackInfo Jun 27 23:57:00 volumio volumio[8316]: info: ControllerMpd::pushState Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::servicePushState Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::getTrack 23 Jun 27 23:57:00 volumio volumio[8316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"443.flac","artist":null,"album":null,"uri":"http://192.168.0.103:8200/MediaItems/443.flac","trackType":"flac"} Jun 27 23:57:00 volumio volumio[8316]: verbose: CURRENT POSITION 23 Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::syncState stateService play Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::syncState currentStatus stop Jun 27 23:57:00 volumio volumio[8316]: info: ------------------------------ 10ms Jun 27 23:57:00 volumio volumio[8316]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::parseTrackInfo Jun 27 23:57:00 volumio volumio[8316]: info: ControllerMpd::pushState Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::servicePushState Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::getTrack 23 Jun 27 23:57:00 volumio volumio[8316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"443.flac","artist":null,"album":null,"uri":"http://192.168.0.103:8200/MediaItems/443.flac","trackType":"flac"} Jun 27 23:57:00 volumio volumio[8316]: verbose: CURRENT POSITION 23 Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::syncState stateService play Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::syncState currentStatus play Jun 27 23:57:00 volumio volumio[8316]: info: Received an update from plugin. extracting info from payload Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::pushState Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::volumioPushState Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::pushState Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::volumioPushState Jun 27 23:57:00 volumio volumio[8316]: info: ------------------------------ 9ms Jun 27 23:57:00 volumio volumio[8316]: info: Jun 27 23:57:00 volumio volumio[8316]: ---------------------------- MPD announces system playlist update Jun 27 23:57:00 volumio volumio[8316]: info: Ignoring MPD Status Update Jun 27 23:57:00 volumio volumio[8316]: info: Jun 27 23:57:00 volumio volumio[8316]: ---------------------------- MPD announces state update: player Jun 27 23:57:00 volumio volumio[8316]: info: ControllerMpd::getState Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::sendMpdCommand status Jun 27 23:57:00 volumio volumio[8316]: info: ------------------------------ 1ms Jun 27 23:57:00 volumio volumio[8316]: info: sendMpdCommand status took 2 milliseconds Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::parseState Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 23:57:00 volumio volumio[8316]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 27 23:57:00 volumio volumio[8316]: verbose: ControllerMpd::parseTrackInfo Jun 27 23:57:00 volumio volumio[8316]: info: ControllerMpd::pushState Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::servicePushState Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::getTrack 23 Jun 27 23:57:00 volumio volumio[8316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":619,"duration":259,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1165 Kbps","isStreaming":false,"title":"给你一瓶魔法药水","artist":"告五人","album":"玫瑰凭证","uri":"http://192.168.0.103:8200/MediaItems/443.flac","trackType":"flac"} Jun 27 23:57:00 volumio volumio[8316]: verbose: CURRENT POSITION 23 Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::syncState stateService play Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::syncState currentStatus play Jun 27 23:57:00 volumio volumio[8316]: info: Received an update from plugin. extracting info from payload Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::pushState Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::volumioPushState Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::pushState Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::volumioPushState Jun 27 23:57:00 volumio volumio[8316]: info: ------------------------------ 9ms Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::volumioGetQueue Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::getQueue Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::getQueue Jun 27 23:57:00 volumio volumio[8316]: info: CoreCommandRouter::volumioGetQueue Jun 27 23:57:00 volumio volumio[8316]: info: CoreStateMachine::getQueue Jun 27 23:57:00 volumio volumio[8316]: info: CorePlayQueue::getQueue Jun 27 23:57:15 volumio volumio[8316]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 27 23:57:15 volumio volumio[8316]: info: Retrying Login Due to Network Error Jun 27 23:57:25 volumio volumio[8316]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 27 23:57:25 volumio volumio[8316]: info: Retrying Login Due to Network Error Jun 27 23:57:25 volumio volumio[8316]: info: MyVolumio login type: Token Jun 27 23:57:35 volumio volumio[8316]: info: MyVolumio login type: Token Jun 27 23:57:55 volumio volumio[8316]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 27 23:57:55 volumio volumio[8316]: info: Retrying Login Due to Network Error Jun 27 23:58:05 volumio volumio[8316]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 27 23:58:05 volumio volumio[8316]: info: Retrying Login Due to Network Error Jun 27 23:58:05 volumio volumio[8316]: info: MyVolumio login type: Token Jun 27 23:58:15 volumio volumio[8316]: info: MyVolumio login type: Token Jun 27 23:58:35 volumio volumio[8316]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 27 23:58:35 volumio volumio[8316]: info: Retrying Login Due to Network Error Jun 27 23:58:45 volumio volumio[8316]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 27 23:58:45 volumio volumio[8316]: info: Retrying Login Due to Network Error Jun 27 23:58:45 volumio volumio[8316]: info: MyVolumio login type: Token Jun 27 23:58:52 volumio volumio[8316]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 23:58:52 volumio volumio[8316]: Error: connect ETIMEDOUT 168.143.171.186:443 Jun 27 23:58:52 volumio volumio[8316]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jun 27 23:58:52 volumio volumio[8316]: errno: -110, Jun 27 23:58:52 volumio volumio[8316]: code: 'ETIMEDOUT', Jun 27 23:58:52 volumio volumio[8316]: syscall: 'connect', Jun 27 23:58:52 volumio volumio[8316]: address: '168.143.171.186', Jun 27 23:58:52 volumio volumio[8316]: port: 443 Jun 27 23:58:52 volumio volumio[8316]: } Jun 27 23:58:52 volumio volumio[8316]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 23:58:52 volumio sudo[13808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-27 23:57 Jun 27 23:58:52 volumio sudo[13808]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"