-- Logs begin at Thu 2019-02-14 13:12:00 MSK, end at Fri 2025-06-27 00:44:18 MSK. -- Jun 27 00:43:00 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:00 volumio volumio[847]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::ClearQueue Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::stop Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::stPlaybackTimer Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock Jun 27 00:43:00 volumio volumio[847]: info: CorePlayQueue::getTrackBlock Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:00 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:00 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 00:43:00 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::serviceStop Jun 27 00:43:00 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:00 volumio volumio[847]: info: CoreCommandRouter::serviceStop Jun 27 00:43:00 volumio volumio[847]: info: [1750974180366] ControllerUPNPBrowser::stop Jun 27 00:43:00 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 00:43:00 volumio volumio[847]: info: CorePlayQueue::clearPlayQueue Jun 27 00:43:00 volumio volumio[847]: info: CorePlayQueue::saveQueue Jun 27 00:43:00 volumio volumio[847]: info: CoreCommandRouter::volumioPushQueue Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::addQueueItems Jun 27 00:43:00 volumio volumio[847]: info: CorePlayQueue::addQueueItems Jun 27 00:43:00 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:00 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$1$0 Jun 27 00:43:00 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$1$0 Jun 27 00:43:00 volumio volumio[847]: info: CoreCommandRouter::volumioPushQueue Jun 27 00:43:00 volumio volumio[847]: info: CorePlayQueue::saveQueue Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock Jun 27 00:43:00 volumio volumio[847]: info: CorePlayQueue::getTrackBlock Jun 27 00:43:00 volumio volumio[847]: info: CoreCommandRouter::volumioPlay Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::play index 0 Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::addQueueItems Jun 27 00:43:00 volumio volumio[847]: info: CorePlayQueue::addQueueItems Jun 27 00:43:00 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:00 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$1$1 Jun 27 00:43:00 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$1$1 Jun 27 00:43:00 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$1$2 Jun 27 00:43:00 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$1$2 Jun 27 00:43:00 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$1$3 Jun 27 00:43:00 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$1$3 Jun 27 00:43:00 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$1$4 Jun 27 00:43:00 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$1$4 Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::stop Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:00 volumio volumio[847]: info: CoreCommandRouter::volumioPushQueue Jun 27 00:43:00 volumio volumio[847]: info: CorePlayQueue::saveQueue Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::play index undefined Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock Jun 27 00:43:00 volumio volumio[847]: info: CorePlayQueue::getTrackBlock Jun 27 00:43:00 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::startPlaybackTimer Jun 27 00:43:00 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:00 volumio volumio[847]: info: [1750974180420] ControllerUPNPBrowser::clearAddPlayTrack Jun 27 00:43:00 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 00:43:00 volumio volumio[847]: info: Jun 27 00:43:00 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:00 volumio volumio[847]: info: sendMpdCommand stop took 80 milliseconds Jun 27 00:43:00 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:00 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:00 volumio volumio[847]: info: sendMpdCommand stop took 28 milliseconds Jun 27 00:43:00 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand clear Jun 27 00:43:00 volumio volumio[847]: info: Jun 27 00:43:00 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:00 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:00 volumio volumio[847]: info: sendMpdCommand status took 8 milliseconds Jun 27 00:43:00 volumio volumio[847]: info: sendMpdCommand clear took 6 milliseconds Jun 27 00:43:00 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:00 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:00 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.100:8200/MediaItems/7487.mp3" Jun 27 00:43:00 volumio volumio[847]: error: updateQueue error: null Jun 27 00:43:00 volumio volumio[847]: info: ------------------------------ 65ms Jun 27 00:43:00 volumio volumio[847]: info: sendMpdCommand playlistinfo took 61 milliseconds Jun 27 00:43:00 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:00 volumio volumio[847]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 27 00:43:00 volumio volumio[847]: info: ------------------------------ 80ms Jun 27 00:43:00 volumio volumio[847]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 27 00:43:00 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.100:8200/MediaItems/7487.mp3" Jun 27 00:43:00 volumio volumio[847]: info: Jun 27 00:43:00 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:00 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:00 volumio volumio[847]: info: sendMpdCommand add "http://192.168.1.100:8200/MediaItems/7487.mp3" took 3 milliseconds Jun 27 00:43:00 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 27 00:43:00 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand play Jun 27 00:43:00 volumio volumio[847]: info: ------------------------------ 5ms Jun 27 00:43:00 volumio volumio[847]: info: sendMpdCommand play took 3 milliseconds Jun 27 00:43:00 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:43:00 volumio volumio[847]: info: Jun 27 00:43:00 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:00 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:00 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:01 volumio volumio[847]: info: sendMpdCommand status took 69 milliseconds Jun 27 00:43:01 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:01 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:01 volumio volumio[847]: info: Jun 27 00:43:01 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:01 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:01 volumio volumio[847]: info: Jun 27 00:43:01 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:01 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:01 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:01 volumio volumio[847]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 27 00:43:01 volumio volumio[847]: info: ------------------------------ 7ms Jun 27 00:43:01 volumio volumio[847]: info: sendMpdCommand status took 6 milliseconds Jun 27 00:43:01 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:01 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:01 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:01 volumio volumio[847]: info: ControllerMpd::pushState Jun 27 00:43:01 volumio volumio[847]: info: CoreCommandRouter::servicePushState Jun 27 00:43:01 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:01 volumio volumio[847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"curl up and die","artist":"death before dishonor","album":"death before dishonor / black friday '29 split 7\"","uri":"http://192.168.1.100:8200/MediaItems/7487.mp3","trackType":"mp3"} Jun 27 00:43:01 volumio volumio[847]: verbose: CURRENT POSITION 0 Jun 27 00:43:01 volumio volumio[847]: info: CoreStateMachine::syncState stateService play Jun 27 00:43:01 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus stop Jun 27 00:43:01 volumio volumio[847]: info: ------------------------------ 88ms Jun 27 00:43:01 volumio volumio[847]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 27 00:43:01 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:01 volumio volumio[847]: info: ControllerMpd::pushState Jun 27 00:43:01 volumio volumio[847]: info: CoreCommandRouter::servicePushState Jun 27 00:43:01 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:01 volumio volumio[847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"curl up and die","artist":"death before dishonor","album":"death before dishonor / black friday '29 split 7\"","uri":"http://192.168.1.100:8200/MediaItems/7487.mp3","trackType":"mp3"} Jun 27 00:43:01 volumio volumio[847]: verbose: CURRENT POSITION 0 Jun 27 00:43:01 volumio volumio[847]: info: CoreStateMachine::syncState stateService play Jun 27 00:43:01 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus play Jun 27 00:43:01 volumio volumio[847]: info: Received an update from plugin. extracting info from payload Jun 27 00:43:01 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:01 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 00:43:01 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:01 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:01 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:01 volumio volumio[847]: info: ------------------------------ 41ms Jun 27 00:43:01 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue Jun 27 00:43:01 volumio volumio[847]: info: CoreStateMachine::getQueue Jun 27 00:43:01 volumio volumio[847]: info: CorePlayQueue::getQueue Jun 27 00:43:01 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue Jun 27 00:43:01 volumio volumio[847]: info: CoreStateMachine::getQueue Jun 27 00:43:01 volumio volumio[847]: info: CorePlayQueue::getQueue Jun 27 00:43:04 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:43:08 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 27 00:43:08 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:08 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$0 Jun 27 00:43:08 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$1 Jun 27 00:43:08 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$2 Jun 27 00:43:08 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$3 Jun 27 00:43:08 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$4 Jun 27 00:43:08 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$5 Jun 27 00:43:08 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$6 Jun 27 00:43:08 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$7 Jun 27 00:43:08 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$8 Jun 27 00:43:08 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$9 Jun 27 00:43:08 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$A Jun 27 00:43:08 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$B Jun 27 00:43:08 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$C Jun 27 00:43:08 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$D Jun 27 00:43:08 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$0 in service upnp_browser Jun 27 00:43:08 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$1 in service upnp_browser Jun 27 00:43:08 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$2 in service upnp_browser Jun 27 00:43:08 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$3 in service upnp_browser Jun 27 00:43:08 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:43:08 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$4 in service upnp_browser Jun 27 00:43:09 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$5 in service upnp_browser Jun 27 00:43:09 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$6 in service upnp_browser Jun 27 00:43:09 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$7 in service upnp_browser Jun 27 00:43:09 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$8 in service upnp_browser Jun 27 00:43:09 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$9 in service upnp_browser Jun 27 00:43:09 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$A in service upnp_browser Jun 27 00:43:09 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$B in service upnp_browser Jun 27 00:43:09 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$C in service upnp_browser Jun 27 00:43:09 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$D in service upnp_browser Jun 27 00:43:11 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:11 volumio volumio[847]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 27 00:43:11 volumio volumio[847]: info: CoreStateMachine::ClearQueue Jun 27 00:43:11 volumio volumio[847]: info: CoreStateMachine::stop Jun 27 00:43:11 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:11 volumio volumio[847]: info: CoreStateMachine::stPlaybackTimer Jun 27 00:43:11 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock Jun 27 00:43:11 volumio volumio[847]: info: CorePlayQueue::getTrackBlock Jun 27 00:43:11 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:11 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:11 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 00:43:11 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:11 volumio volumio[847]: info: CoreStateMachine::serviceStop Jun 27 00:43:11 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:11 volumio volumio[847]: info: CoreCommandRouter::serviceStop Jun 27 00:43:11 volumio volumio[847]: info: [1750974191925] ControllerUPNPBrowser::stop Jun 27 00:43:11 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 00:43:11 volumio volumio[847]: info: CorePlayQueue::clearPlayQueue Jun 27 00:43:11 volumio volumio[847]: info: CorePlayQueue::saveQueue Jun 27 00:43:11 volumio volumio[847]: info: CoreCommandRouter::volumioPushQueue Jun 27 00:43:11 volumio volumio[847]: info: CoreStateMachine::addQueueItems Jun 27 00:43:11 volumio volumio[847]: info: CorePlayQueue::addQueueItems Jun 27 00:43:11 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:11 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$0 Jun 27 00:43:11 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$0 Jun 27 00:43:11 volumio volumio[847]: info: CoreCommandRouter::volumioPushQueue Jun 27 00:43:11 volumio volumio[847]: info: CorePlayQueue::saveQueue Jun 27 00:43:11 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock Jun 27 00:43:11 volumio volumio[847]: info: CorePlayQueue::getTrackBlock Jun 27 00:43:11 volumio volumio[847]: info: CoreCommandRouter::volumioPlay Jun 27 00:43:11 volumio volumio[847]: info: CoreStateMachine::play index 0 Jun 27 00:43:11 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:11 volumio volumio[847]: info: CoreStateMachine::addQueueItems Jun 27 00:43:11 volumio volumio[847]: info: CorePlayQueue::addQueueItems Jun 27 00:43:11 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:11 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$1 Jun 27 00:43:11 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$1 in service upnp_browser Jun 27 00:43:11 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$2 Jun 27 00:43:11 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$2 in service upnp_browser Jun 27 00:43:11 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$3 Jun 27 00:43:11 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$3 in service upnp_browser Jun 27 00:43:11 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$4 Jun 27 00:43:11 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$4 in service upnp_browser Jun 27 00:43:11 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$5 Jun 27 00:43:11 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$5 in service upnp_browser Jun 27 00:43:11 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$6 Jun 27 00:43:11 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$6 in service upnp_browser Jun 27 00:43:12 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$7 Jun 27 00:43:12 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$7 in service upnp_browser Jun 27 00:43:12 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$8 Jun 27 00:43:12 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$8 in service upnp_browser Jun 27 00:43:12 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$9 Jun 27 00:43:12 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$9 in service upnp_browser Jun 27 00:43:12 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$A Jun 27 00:43:12 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$A in service upnp_browser Jun 27 00:43:12 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$B Jun 27 00:43:12 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$B in service upnp_browser Jun 27 00:43:12 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$C Jun 27 00:43:12 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$C in service upnp_browser Jun 27 00:43:12 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$D Jun 27 00:43:12 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$0$D in service upnp_browser Jun 27 00:43:12 volumio volumio[847]: info: CoreStateMachine::stop Jun 27 00:43:12 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:12 volumio volumio[847]: info: CoreStateMachine::play index undefined Jun 27 00:43:12 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:12 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:12 volumio volumio[847]: info: CoreStateMachine::startPlaybackTimer Jun 27 00:43:12 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:12 volumio volumio[847]: info: [1750974192133] ControllerUPNPBrowser::clearAddPlayTrack Jun 27 00:43:12 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 00:43:12 volumio volumio[847]: info: Jun 27 00:43:12 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:12 volumio volumio[847]: info: sendMpdCommand stop took 229 milliseconds Jun 27 00:43:12 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:12 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:12 volumio volumio[847]: info: sendMpdCommand stop took 25 milliseconds Jun 27 00:43:12 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand clear Jun 27 00:43:12 volumio volumio[847]: info: Jun 27 00:43:12 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:12 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:12 volumio volumio[847]: info: sendMpdCommand status took 65 milliseconds Jun 27 00:43:12 volumio volumio[847]: info: sendMpdCommand clear took 65 milliseconds Jun 27 00:43:12 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:12 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:12 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.100:8200/MediaItems/7469.mp3" Jun 27 00:43:12 volumio volumio[847]: error: updateQueue error: null Jun 27 00:43:12 volumio volumio[847]: info: ------------------------------ 24ms Jun 27 00:43:12 volumio volumio[847]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 27 00:43:13 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:43:13 volumio volumio[847]: info: CoreCommandRouter::volumioPushQueue Jun 27 00:43:13 volumio volumio[847]: info: CorePlayQueue::saveQueue Jun 27 00:43:13 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock Jun 27 00:43:13 volumio volumio[847]: info: CorePlayQueue::getTrackBlock Jun 27 00:43:13 volumio volumio[847]: info: sendMpdCommand playlistinfo took 1137 milliseconds Jun 27 00:43:13 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.100:8200/MediaItems/7469.mp3" Jun 27 00:43:13 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:13 volumio volumio[847]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 27 00:43:13 volumio volumio[847]: info: ------------------------------ 1216ms Jun 27 00:43:13 volumio volumio[847]: info: Jun 27 00:43:13 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:13 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:13 volumio volumio[847]: info: sendMpdCommand add "http://192.168.1.100:8200/MediaItems/7469.mp3" took 8 milliseconds Jun 27 00:43:13 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 27 00:43:13 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand play Jun 27 00:43:13 volumio volumio[847]: info: ------------------------------ 4ms Jun 27 00:43:13 volumio volumio[847]: info: sendMpdCommand play took 3 milliseconds Jun 27 00:43:13 volumio volumio[847]: info: Jun 27 00:43:13 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:13 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:13 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:13 volumio volumio[847]: info: Jun 27 00:43:13 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:13 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:13 volumio volumio[847]: info: Jun 27 00:43:13 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:13 volumio volumio[847]: info: sendMpdCommand status took 58 milliseconds Jun 27 00:43:13 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:13 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:13 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:13 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:13 volumio volumio[847]: info: ------------------------------ 10ms Jun 27 00:43:13 volumio volumio[847]: info: sendMpdCommand status took 5 milliseconds Jun 27 00:43:13 volumio volumio[847]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 27 00:43:13 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:13 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:13 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:13 volumio volumio[847]: info: ControllerMpd::pushState Jun 27 00:43:13 volumio volumio[847]: info: CoreCommandRouter::servicePushState Jun 27 00:43:13 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:13 volumio volumio[847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":36,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"742617000027","artist":"Slipknot","album":"Slipknot","uri":"http://192.168.1.100:8200/MediaItems/7469.mp3","trackType":"mp3"} Jun 27 00:43:13 volumio volumio[847]: verbose: CURRENT POSITION 0 Jun 27 00:43:13 volumio volumio[847]: info: CoreStateMachine::syncState stateService play Jun 27 00:43:13 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus stop Jun 27 00:43:13 volumio volumio[847]: info: ------------------------------ 74ms Jun 27 00:43:13 volumio volumio[847]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 27 00:43:13 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:13 volumio volumio[847]: info: ControllerMpd::pushState Jun 27 00:43:13 volumio volumio[847]: info: CoreCommandRouter::servicePushState Jun 27 00:43:13 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:13 volumio volumio[847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":36,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"742617000027","artist":"Slipknot","album":"Slipknot","uri":"http://192.168.1.100:8200/MediaItems/7469.mp3","trackType":"mp3"} Jun 27 00:43:13 volumio volumio[847]: verbose: CURRENT POSITION 0 Jun 27 00:43:13 volumio volumio[847]: info: CoreStateMachine::syncState stateService play Jun 27 00:43:13 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus play Jun 27 00:43:13 volumio volumio[847]: info: Received an update from plugin. extracting info from payload Jun 27 00:43:13 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:13 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 00:43:13 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:13 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:13 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:13 volumio volumio[847]: info: ------------------------------ 64ms Jun 27 00:43:13 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue Jun 27 00:43:13 volumio volumio[847]: info: CoreStateMachine::getQueue Jun 27 00:43:13 volumio volumio[847]: info: CorePlayQueue::getQueue Jun 27 00:43:13 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue Jun 27 00:43:13 volumio volumio[847]: info: CoreStateMachine::getQueue Jun 27 00:43:13 volumio volumio[847]: info: CorePlayQueue::getQueue Jun 27 00:43:17 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:43:21 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:43:24 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 27 00:43:24 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:25 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:43:26 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 27 00:43:27 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:29 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:43:30 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 27 00:43:31 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:31 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$0 Jun 27 00:43:31 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$0 in service upnp_browser Jun 27 00:43:33 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:43:34 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::ClearQueue Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::stop Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::stPlaybackTimer Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock Jun 27 00:43:34 volumio volumio[847]: info: CorePlayQueue::getTrackBlock Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:34 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::serviceStop Jun 27 00:43:34 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::serviceStop Jun 27 00:43:34 volumio volumio[847]: info: [1750974214028] ControllerUPNPBrowser::stop Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 00:43:34 volumio volumio[847]: info: CorePlayQueue::clearPlayQueue Jun 27 00:43:34 volumio volumio[847]: info: CorePlayQueue::saveQueue Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::volumioPushQueue Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::addQueueItems Jun 27 00:43:34 volumio volumio[847]: info: CorePlayQueue::addQueueItems Jun 27 00:43:34 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:34 volumio volumio[847]: info: Adding Item to queue: upnp/folder/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1 Jun 27 00:43:34 volumio volumio[847]: info: Exploding uri upnp/folder/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1 in service upnp_browser Jun 27 00:43:34 volumio volumio[847]: info: Adding Item to queue: upnp/folder/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$2 Jun 27 00:43:34 volumio volumio[847]: info: Exploding uri upnp/folder/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$2 in service upnp_browser Jun 27 00:43:34 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$0 Jun 27 00:43:34 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$0 Jun 27 00:43:34 volumio volumio[847]: info: Jun 27 00:43:34 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:34 volumio volumio[847]: info: sendMpdCommand stop took 93 milliseconds Jun 27 00:43:34 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:34 volumio volumio[847]: info: sendMpdCommand status took 7 milliseconds Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:34 volumio volumio[847]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:34 volumio volumio[847]: info: ControllerMpd::pushState Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::servicePushState Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:34 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:34 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:34 volumio volumio[847]: 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":"742617000027","artist":"Slipknot","album":"Slipknot","uri":"http://192.168.1.100:8200/MediaItems/7469.mp3","trackType":"mp3"} Jun 27 00:43:34 volumio volumio[847]: verbose: CURRENT POSITION 0 Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::syncState stateService stop Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus stop Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:34 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:34 volumio volumio[847]: info: No code Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:34 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:34 volumio volumio[847]: info: ------------------------------ 99ms Jun 27 00:43:34 volumio volumio[847]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::volumioPushQueue Jun 27 00:43:34 volumio volumio[847]: info: CorePlayQueue::saveQueue Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock Jun 27 00:43:34 volumio volumio[847]: info: CorePlayQueue::getTrackBlock Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::volumioPlay Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::play index 2 Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::stop Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::play index undefined Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:34 volumio volumio[847]: info: CorePlayQueue::getTrack 2 Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::startPlaybackTimer Jun 27 00:43:34 volumio volumio[847]: info: CorePlayQueue::getTrack 2 Jun 27 00:43:34 volumio volumio[847]: info: [1750974214564] ControllerUPNPBrowser::clearAddPlayTrack Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 00:43:34 volumio volumio[847]: info: sendMpdCommand stop took 40 milliseconds Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand clear Jun 27 00:43:34 volumio volumio[847]: info: Jun 27 00:43:34 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:34 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:34 volumio volumio[847]: info: sendMpdCommand clear took 7 milliseconds Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.100:8200/MediaItems/7535.mp3" Jun 27 00:43:34 volumio volumio[847]: error: updateQueue error: null Jun 27 00:43:34 volumio volumio[847]: info: ------------------------------ 6ms Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.100:8200/MediaItems/7535.mp3" Jun 27 00:43:34 volumio volumio[847]: info: Jun 27 00:43:34 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:34 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:34 volumio volumio[847]: info: sendMpdCommand add "http://192.168.1.100:8200/MediaItems/7535.mp3" took 4 milliseconds Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand play Jun 27 00:43:34 volumio volumio[847]: info: ------------------------------ 7ms Jun 27 00:43:34 volumio volumio[847]: info: sendMpdCommand play took 6 milliseconds Jun 27 00:43:34 volumio volumio[847]: info: Jun 27 00:43:34 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:34 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:34 volumio volumio[847]: info: Jun 27 00:43:34 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:34 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:34 volumio volumio[847]: info: Jun 27 00:43:34 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:34 volumio volumio[847]: info: sendMpdCommand status took 17 milliseconds Jun 27 00:43:34 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:34 volumio volumio[847]: info: ------------------------------ 26ms Jun 27 00:43:34 volumio volumio[847]: info: sendMpdCommand status took 24 milliseconds Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:34 volumio volumio[847]: info: sendMpdCommand playlistinfo took 32 milliseconds Jun 27 00:43:34 volumio volumio[847]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:34 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:34 volumio volumio[847]: info: ControllerMpd::pushState Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::servicePushState Jun 27 00:43:34 volumio volumio[847]: info: CorePlayQueue::getTrack 2 Jun 27 00:43:34 volumio volumio[847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1002,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"J'suis Partout-z3rtyy.sky'","artist":"Alibi Montana","album":"Insurrection","uri":"http://192.168.1.100:8200/MediaItems/7535.mp3","trackType":"mp3"} Jun 27 00:43:34 volumio volumio[847]: verbose: CURRENT POSITION 2 Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::syncState stateService play Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus stop Jun 27 00:43:34 volumio volumio[847]: info: ControllerMpd::pushState Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::servicePushState Jun 27 00:43:34 volumio volumio[847]: info: CorePlayQueue::getTrack 2 Jun 27 00:43:34 volumio volumio[847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":605,"duration":1002,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"J'suis Partout-z3rtyy.sky'","artist":"Alibi Montana","album":"Insurrection","uri":"http://192.168.1.100:8200/MediaItems/7535.mp3","trackType":"mp3"} Jun 27 00:43:34 volumio volumio[847]: verbose: CURRENT POSITION 2 Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::syncState stateService play Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus play Jun 27 00:43:34 volumio volumio[847]: info: Received an update from plugin. extracting info from payload Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:34 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:34 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:34 volumio volumio[847]: info: ------------------------------ 157ms Jun 27 00:43:35 volumio volumio[847]: info: ------------------------------ 181ms Jun 27 00:43:35 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue Jun 27 00:43:35 volumio volumio[847]: info: CoreStateMachine::getQueue Jun 27 00:43:35 volumio volumio[847]: info: CorePlayQueue::getQueue Jun 27 00:43:35 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue Jun 27 00:43:35 volumio volumio[847]: info: CoreStateMachine::getQueue Jun 27 00:43:35 volumio volumio[847]: info: CorePlayQueue::getQueue Jun 27 00:43:37 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:43:41 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:41 volumio volumio[847]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 27 00:43:41 volumio volumio[847]: info: CoreStateMachine::ClearQueue Jun 27 00:43:41 volumio volumio[847]: info: CoreStateMachine::stop Jun 27 00:43:41 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:41 volumio volumio[847]: info: CoreStateMachine::stPlaybackTimer Jun 27 00:43:41 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock Jun 27 00:43:41 volumio volumio[847]: info: CorePlayQueue::getTrackBlock Jun 27 00:43:41 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:41 volumio volumio[847]: info: CorePlayQueue::getTrack 2 Jun 27 00:43:41 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 00:43:41 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:41 volumio volumio[847]: info: CoreStateMachine::serviceStop Jun 27 00:43:41 volumio volumio[847]: info: CorePlayQueue::getTrack 2 Jun 27 00:43:41 volumio volumio[847]: info: CoreCommandRouter::serviceStop Jun 27 00:43:41 volumio volumio[847]: info: [1750974221092] ControllerUPNPBrowser::stop Jun 27 00:43:41 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 00:43:41 volumio volumio[847]: info: CorePlayQueue::clearPlayQueue Jun 27 00:43:41 volumio volumio[847]: info: CorePlayQueue::saveQueue Jun 27 00:43:41 volumio volumio[847]: info: CoreCommandRouter::volumioPushQueue Jun 27 00:43:41 volumio volumio[847]: info: CoreStateMachine::addQueueItems Jun 27 00:43:41 volumio volumio[847]: info: CorePlayQueue::addQueueItems Jun 27 00:43:41 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:41 volumio volumio[847]: info: Adding Item to queue: upnp/folder/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$2 Jun 27 00:43:41 volumio volumio[847]: info: Using cached record of: upnp/folder/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$2 Jun 27 00:43:41 volumio volumio[847]: info: CoreCommandRouter::volumioPushQueue Jun 27 00:43:41 volumio volumio[847]: info: CorePlayQueue::saveQueue Jun 27 00:43:41 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock Jun 27 00:43:41 volumio volumio[847]: info: CorePlayQueue::getTrackBlock Jun 27 00:43:41 volumio volumio[847]: info: CoreCommandRouter::volumioPlay Jun 27 00:43:41 volumio volumio[847]: info: CoreStateMachine::play index 0 Jun 27 00:43:41 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:41 volumio volumio[847]: info: CoreStateMachine::stop Jun 27 00:43:41 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:41 volumio volumio[847]: info: CoreStateMachine::play index undefined Jun 27 00:43:41 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:41 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:41 volumio volumio[847]: info: CoreStateMachine::startPlaybackTimer Jun 27 00:43:41 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:41 volumio volumio[847]: info: [1750974221132] ControllerUPNPBrowser::clearAddPlayTrack Jun 27 00:43:41 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 00:43:41 volumio volumio[847]: info: Jun 27 00:43:41 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:41 volumio volumio[847]: info: sendMpdCommand stop took 61 milliseconds Jun 27 00:43:41 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:41 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:41 volumio volumio[847]: info: sendMpdCommand stop took 22 milliseconds Jun 27 00:43:41 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand clear Jun 27 00:43:41 volumio volumio[847]: info: Jun 27 00:43:41 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:41 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:41 volumio volumio[847]: info: sendMpdCommand status took 9 milliseconds Jun 27 00:43:41 volumio volumio[847]: info: sendMpdCommand clear took 6 milliseconds Jun 27 00:43:41 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:41 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:41 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.100:8200/MediaItems/7619.mp3" Jun 27 00:43:41 volumio volumio[847]: error: updateQueue error: null Jun 27 00:43:41 volumio volumio[847]: info: ------------------------------ 77ms Jun 27 00:43:41 volumio volumio[847]: info: sendMpdCommand playlistinfo took 74 milliseconds Jun 27 00:43:41 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:41 volumio volumio[847]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 27 00:43:41 volumio volumio[847]: info: ------------------------------ 93ms Jun 27 00:43:41 volumio volumio[847]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 27 00:43:41 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:43:43 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.100:8200/MediaItems/7619.mp3" Jun 27 00:43:43 volumio volumio[847]: info: Jun 27 00:43:43 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:43 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:43 volumio volumio[847]: info: sendMpdCommand add "http://192.168.1.100:8200/MediaItems/7619.mp3" took 4 milliseconds Jun 27 00:43:43 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 27 00:43:43 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand play Jun 27 00:43:43 volumio volumio[847]: info: ------------------------------ 9ms Jun 27 00:43:43 volumio volumio[847]: info: sendMpdCommand play took 6 milliseconds Jun 27 00:43:43 volumio volumio[847]: info: Jun 27 00:43:43 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:43 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:43 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:43 volumio volumio[847]: info: Jun 27 00:43:43 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:43 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:43 volumio volumio[847]: info: Jun 27 00:43:43 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:43 volumio volumio[847]: info: sendMpdCommand status took 75 milliseconds Jun 27 00:43:43 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:43 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:43 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:43 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:43 volumio volumio[847]: info: ------------------------------ 17ms Jun 27 00:43:43 volumio volumio[847]: info: sendMpdCommand status took 11 milliseconds Jun 27 00:43:43 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:43 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:43 volumio volumio[847]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 27 00:43:43 volumio volumio[847]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 27 00:43:43 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:43 volumio volumio[847]: info: ControllerMpd::pushState Jun 27 00:43:43 volumio volumio[847]: info: CoreCommandRouter::servicePushState Jun 27 00:43:43 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:43 volumio volumio[847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":81,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Préface","artist":"LA BRIGADE","album":"Il était une fois...","uri":"http://192.168.1.100:8200/MediaItems/7619.mp3","trackType":"mp3"} Jun 27 00:43:43 volumio volumio[847]: verbose: CURRENT POSITION 0 Jun 27 00:43:43 volumio volumio[847]: info: CoreStateMachine::syncState stateService play Jun 27 00:43:43 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus stop Jun 27 00:43:43 volumio volumio[847]: info: ControllerMpd::pushState Jun 27 00:43:43 volumio volumio[847]: info: CoreCommandRouter::servicePushState Jun 27 00:43:43 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:43 volumio volumio[847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":81,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Préface","artist":"LA BRIGADE","album":"Il était une fois...","uri":"http://192.168.1.100:8200/MediaItems/7619.mp3","trackType":"mp3"} Jun 27 00:43:43 volumio volumio[847]: verbose: CURRENT POSITION 0 Jun 27 00:43:43 volumio volumio[847]: info: CoreStateMachine::syncState stateService play Jun 27 00:43:43 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus play Jun 27 00:43:43 volumio volumio[847]: info: Received an update from plugin. extracting info from payload Jun 27 00:43:43 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:43 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 00:43:43 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:43 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:43 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:43 volumio volumio[847]: info: ------------------------------ 119ms Jun 27 00:43:43 volumio volumio[847]: info: ------------------------------ 60ms Jun 27 00:43:43 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue Jun 27 00:43:43 volumio volumio[847]: info: CoreStateMachine::getQueue Jun 27 00:43:43 volumio volumio[847]: info: CorePlayQueue::getQueue Jun 27 00:43:43 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue Jun 27 00:43:43 volumio volumio[847]: info: CoreStateMachine::getQueue Jun 27 00:43:43 volumio volumio[847]: info: CorePlayQueue::getQueue Jun 27 00:43:43 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 27 00:43:44 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$0 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$2 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$3 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$4 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$5 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$6 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$7 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$8 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$9 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$A Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$B Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$11 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$D Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$E Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$F Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$10 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$C Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$12 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$13 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$14 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$15 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$16 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$17 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$18 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$19 Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1A Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1B Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1C Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1D Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1E Jun 27 00:43:44 volumio volumio[847]: info: Preloading song: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1F Jun 27 00:43:44 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$0 in service upnp_browser Jun 27 00:43:44 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1 in service upnp_browser Jun 27 00:43:44 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$2 in service upnp_browser Jun 27 00:43:44 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$3 in service upnp_browser Jun 27 00:43:44 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$4 in service upnp_browser Jun 27 00:43:44 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$5 in service upnp_browser Jun 27 00:43:44 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$6 in service upnp_browser Jun 27 00:43:44 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$7 in service upnp_browser Jun 27 00:43:44 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$8 in service upnp_browser Jun 27 00:43:44 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$9 in service upnp_browser Jun 27 00:43:44 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$A in service upnp_browser Jun 27 00:43:44 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$B in service upnp_browser Jun 27 00:43:44 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$11 in service upnp_browser Jun 27 00:43:44 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$D in service upnp_browser Jun 27 00:43:44 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$E in service upnp_browser Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$F in service upnp_browser Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$10 in service upnp_browser Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$C in service upnp_browser Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$12 in service upnp_browser Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$13 in service upnp_browser Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$14 in service upnp_browser Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$15 in service upnp_browser Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$16 in service upnp_browser Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$17 in service upnp_browser Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$18 in service upnp_browser Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$19 in service upnp_browser Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1A in service upnp_browser Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1B in service upnp_browser Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1C in service upnp_browser Jun 27 00:43:45 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1D in service upnp_browser Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1E in service upnp_browser Jun 27 00:43:45 volumio volumio[847]: info: Exploding uri upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1F in service upnp_browser Jun 27 00:43:46 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:46 volumio volumio[847]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 27 00:43:46 volumio volumio[847]: info: CoreStateMachine::ClearQueue Jun 27 00:43:46 volumio volumio[847]: info: CoreStateMachine::stop Jun 27 00:43:46 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:46 volumio volumio[847]: info: CoreStateMachine::stPlaybackTimer Jun 27 00:43:46 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock Jun 27 00:43:46 volumio volumio[847]: info: CorePlayQueue::getTrackBlock Jun 27 00:43:46 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:46 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:46 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 00:43:46 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:46 volumio volumio[847]: info: CoreStateMachine::serviceStop Jun 27 00:43:46 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:46 volumio volumio[847]: info: CoreCommandRouter::serviceStop Jun 27 00:43:46 volumio volumio[847]: info: [1750974226928] ControllerUPNPBrowser::stop Jun 27 00:43:46 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 00:43:46 volumio volumio[847]: info: CorePlayQueue::clearPlayQueue Jun 27 00:43:46 volumio volumio[847]: info: CorePlayQueue::saveQueue Jun 27 00:43:46 volumio volumio[847]: info: CoreCommandRouter::volumioPushQueue Jun 27 00:43:46 volumio volumio[847]: info: CoreStateMachine::addQueueItems Jun 27 00:43:46 volumio volumio[847]: info: CorePlayQueue::addQueueItems Jun 27 00:43:46 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$0 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$0 Jun 27 00:43:46 volumio volumio[847]: info: CoreCommandRouter::volumioPushQueue Jun 27 00:43:46 volumio volumio[847]: info: CorePlayQueue::saveQueue Jun 27 00:43:46 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock Jun 27 00:43:46 volumio volumio[847]: info: CorePlayQueue::getTrackBlock Jun 27 00:43:46 volumio volumio[847]: info: CoreCommandRouter::volumioPlay Jun 27 00:43:46 volumio volumio[847]: info: CoreStateMachine::play index 0 Jun 27 00:43:46 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:46 volumio volumio[847]: info: CoreStateMachine::addQueueItems Jun 27 00:43:46 volumio volumio[847]: info: CorePlayQueue::addQueueItems Jun 27 00:43:46 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$2 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$2 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$3 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$3 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$4 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$4 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$5 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$5 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$6 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$6 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$7 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$7 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$8 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$8 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$9 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$9 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$A Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$A Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$B Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$B Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$11 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$11 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$D Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$D Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$E Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$E Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$F Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$F Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$10 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$10 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$C Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$C Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$12 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$12 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$13 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$13 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$14 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$14 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$15 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$15 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$16 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$16 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$17 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$17 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$18 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$18 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$19 Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$19 Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1A Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1A Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1B Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1B Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1C Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1C Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1D Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1D Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1E Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1E Jun 27 00:43:46 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1F Jun 27 00:43:46 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1F Jun 27 00:43:46 volumio volumio[847]: info: CoreStateMachine::stop Jun 27 00:43:46 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:46 volumio volumio[847]: info: CoreCommandRouter::volumioPushQueue Jun 27 00:43:47 volumio volumio[847]: info: CorePlayQueue::saveQueue Jun 27 00:43:47 volumio volumio[847]: info: CoreStateMachine::play index undefined Jun 27 00:43:47 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:47 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock Jun 27 00:43:47 volumio volumio[847]: info: CorePlayQueue::getTrackBlock Jun 27 00:43:47 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:47 volumio volumio[847]: info: CoreStateMachine::startPlaybackTimer Jun 27 00:43:47 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:47 volumio volumio[847]: info: [1750974227019] ControllerUPNPBrowser::clearAddPlayTrack Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 00:43:47 volumio volumio[847]: info: Jun 27 00:43:47 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:47 volumio volumio[847]: info: sendMpdCommand stop took 137 milliseconds Jun 27 00:43:47 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:47 volumio volumio[847]: info: sendMpdCommand stop took 46 milliseconds Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand clear Jun 27 00:43:47 volumio volumio[847]: info: Jun 27 00:43:47 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:47 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:47 volumio volumio[847]: info: sendMpdCommand status took 55 milliseconds Jun 27 00:43:47 volumio volumio[847]: info: sendMpdCommand clear took 55 milliseconds Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.100:8200/MediaItems/7525.mp3" Jun 27 00:43:47 volumio volumio[847]: error: updateQueue error: null Jun 27 00:43:47 volumio volumio[847]: info: ------------------------------ 11ms Jun 27 00:43:47 volumio volumio[847]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:47 volumio volumio[847]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 27 00:43:47 volumio volumio[847]: info: ------------------------------ 70ms Jun 27 00:43:47 volumio volumio[847]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.100:8200/MediaItems/7525.mp3" Jun 27 00:43:47 volumio volumio[847]: info: Jun 27 00:43:47 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:47 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:47 volumio volumio[847]: info: sendMpdCommand add "http://192.168.1.100:8200/MediaItems/7525.mp3" took 3 milliseconds Jun 27 00:43:47 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand play Jun 27 00:43:47 volumio volumio[847]: info: ------------------------------ 4ms Jun 27 00:43:47 volumio volumio[847]: info: sendMpdCommand play took 4 milliseconds Jun 27 00:43:47 volumio volumio[847]: info: Jun 27 00:43:47 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:47 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:47 volumio volumio[847]: info: Jun 27 00:43:47 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:47 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:47 volumio volumio[847]: info: Jun 27 00:43:47 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:47 volumio volumio[847]: info: sendMpdCommand status took 76 milliseconds Jun 27 00:43:47 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:47 volumio volumio[847]: info: ------------------------------ 18ms Jun 27 00:43:47 volumio volumio[847]: info: sendMpdCommand status took 15 milliseconds Jun 27 00:43:47 volumio volumio[847]: info: sendMpdCommand playlistinfo took 17 milliseconds Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:47 volumio volumio[847]: info: ControllerMpd::pushState Jun 27 00:43:47 volumio volumio[847]: info: CoreCommandRouter::servicePushState Jun 27 00:43:47 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:47 volumio volumio[847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mix Up","artist":"357 MP","album":"Hip Hop Underground n°3","uri":"http://192.168.1.100:8200/MediaItems/7525.mp3","trackType":"mp3"} Jun 27 00:43:47 volumio volumio[847]: verbose: CURRENT POSITION 0 Jun 27 00:43:47 volumio volumio[847]: info: CoreStateMachine::syncState stateService play Jun 27 00:43:47 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus stop Jun 27 00:43:47 volumio volumio[847]: info: ------------------------------ 104ms Jun 27 00:43:47 volumio volumio[847]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 27 00:43:47 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:47 volumio volumio[847]: info: ControllerMpd::pushState Jun 27 00:43:47 volumio volumio[847]: info: CoreCommandRouter::servicePushState Jun 27 00:43:47 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:47 volumio volumio[847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":754,"duration":283,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mix Up","artist":"357 MP","album":"Hip Hop Underground n°3","uri":"http://192.168.1.100:8200/MediaItems/7525.mp3","trackType":"mp3"} Jun 27 00:43:47 volumio volumio[847]: verbose: CURRENT POSITION 0 Jun 27 00:43:47 volumio volumio[847]: info: CoreStateMachine::syncState stateService play Jun 27 00:43:47 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus play Jun 27 00:43:47 volumio volumio[847]: info: Received an update from plugin. extracting info from payload Jun 27 00:43:47 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:47 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 00:43:47 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:47 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:47 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:47 volumio volumio[847]: info: ------------------------------ 58ms Jun 27 00:43:47 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue Jun 27 00:43:47 volumio volumio[847]: info: CoreStateMachine::getQueue Jun 27 00:43:47 volumio volumio[847]: info: CorePlayQueue::getQueue Jun 27 00:43:47 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue Jun 27 00:43:47 volumio volumio[847]: info: CoreStateMachine::getQueue Jun 27 00:43:47 volumio volumio[847]: info: CorePlayQueue::getQueue Jun 27 00:43:49 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:43:53 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:53 volumio volumio[847]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::ClearQueue Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::stop Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::stPlaybackTimer Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock Jun 27 00:43:53 volumio volumio[847]: info: CorePlayQueue::getTrackBlock Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:53 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:53 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 00:43:53 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::serviceStop Jun 27 00:43:53 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 27 00:43:53 volumio volumio[847]: info: CoreCommandRouter::serviceStop Jun 27 00:43:53 volumio volumio[847]: info: [1750974233189] ControllerUPNPBrowser::stop Jun 27 00:43:53 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 00:43:53 volumio volumio[847]: info: CorePlayQueue::clearPlayQueue Jun 27 00:43:53 volumio volumio[847]: info: CorePlayQueue::saveQueue Jun 27 00:43:53 volumio volumio[847]: info: CoreCommandRouter::volumioPushQueue Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::addQueueItems Jun 27 00:43:53 volumio volumio[847]: info: CorePlayQueue::addQueueItems Jun 27 00:43:53 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$0 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$0 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$2 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$2 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$3 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$3 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$4 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$4 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$5 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$5 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$6 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$6 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$7 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$7 Jun 27 00:43:53 volumio volumio[847]: info: CoreCommandRouter::volumioPushQueue Jun 27 00:43:53 volumio volumio[847]: info: CorePlayQueue::saveQueue Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock Jun 27 00:43:53 volumio volumio[847]: info: CorePlayQueue::getTrackBlock Jun 27 00:43:53 volumio volumio[847]: info: CoreCommandRouter::volumioPlay Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::play index 7 Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::addQueueItems Jun 27 00:43:53 volumio volumio[847]: info: CorePlayQueue::addQueueItems Jun 27 00:43:53 volumio volumio[847]: info: Preload queue cleared Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$8 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$8 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$9 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$9 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$A Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$A Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$B Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$B Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$11 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$11 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$D Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$D Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$E Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$E Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$F Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$F Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$10 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$10 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$C Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$C Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$12 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$12 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$13 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$13 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$14 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$14 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$15 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$15 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$16 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$16 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$17 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$17 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$18 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$18 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$19 Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$19 Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1A Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1A Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1B Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1B Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1C Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1C Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1D Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1D Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1E Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1E Jun 27 00:43:53 volumio volumio[847]: info: Adding Item to queue: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1F Jun 27 00:43:53 volumio volumio[847]: info: Using cached record of: upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$1F Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::stop Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:53 volumio volumio[847]: info: CoreCommandRouter::volumioPushQueue Jun 27 00:43:53 volumio volumio[847]: info: CorePlayQueue::saveQueue Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::play index undefined Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock Jun 27 00:43:53 volumio volumio[847]: info: CorePlayQueue::getTrackBlock Jun 27 00:43:53 volumio volumio[847]: info: CorePlayQueue::getTrack 7 Jun 27 00:43:53 volumio volumio[847]: info: CoreStateMachine::startPlaybackTimer Jun 27 00:43:53 volumio volumio[847]: info: CorePlayQueue::getTrack 7 Jun 27 00:43:53 volumio volumio[847]: info: [1750974233286] ControllerUPNPBrowser::clearAddPlayTrack Jun 27 00:43:53 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 00:43:53 volumio volumio[847]: info: Jun 27 00:43:53 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:53 volumio volumio[847]: info: sendMpdCommand stop took 144 milliseconds Jun 27 00:43:53 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:53 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:53 volumio volumio[847]: info: sendMpdCommand stop took 49 milliseconds Jun 27 00:43:53 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand clear Jun 27 00:43:53 volumio volumio[847]: info: Jun 27 00:43:53 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:53 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:53 volumio volumio[847]: info: sendMpdCommand status took 9 milliseconds Jun 27 00:43:53 volumio volumio[847]: info: sendMpdCommand clear took 8 milliseconds Jun 27 00:43:53 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:53 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:53 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.100:8200/MediaItems/7555.mp3" Jun 27 00:43:53 volumio volumio[847]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 27 00:43:53 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:43:54 volumio volumio[847]: error: updateQueue error: null Jun 27 00:43:54 volumio volumio[847]: info: ------------------------------ 1266ms Jun 27 00:43:54 volumio volumio[847]: info: sendMpdCommand playlistinfo took 1261 milliseconds Jun 27 00:43:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.100:8200/MediaItems/7555.mp3" Jun 27 00:43:54 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:54 volumio volumio[847]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 27 00:43:54 volumio volumio[847]: info: ------------------------------ 1280ms Jun 27 00:43:54 volumio volumio[847]: info: Jun 27 00:43:54 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:54 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:54 volumio volumio[847]: info: sendMpdCommand add "http://192.168.1.100:8200/MediaItems/7555.mp3" took 5 milliseconds Jun 27 00:43:54 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 27 00:43:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand play Jun 27 00:43:54 volumio volumio[847]: info: ------------------------------ 5ms Jun 27 00:43:54 volumio volumio[847]: info: sendMpdCommand play took 5 milliseconds Jun 27 00:43:54 volumio volumio[847]: info: Jun 27 00:43:54 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:54 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:54 volumio volumio[847]: info: sendMpdCommand status took 99 milliseconds Jun 27 00:43:54 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:54 volumio volumio[847]: info: Jun 27 00:43:54 volumio volumio[847]: ---------------------------- MPD announces system playlist update Jun 27 00:43:54 volumio volumio[847]: info: Ignoring MPD Status Update Jun 27 00:43:54 volumio volumio[847]: info: Jun 27 00:43:54 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:43:54 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:43:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:43:54 volumio volumio[847]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 27 00:43:54 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:54 volumio volumio[847]: info: ControllerMpd::pushState Jun 27 00:43:54 volumio volumio[847]: info: CoreCommandRouter::servicePushState Jun 27 00:43:54 volumio volumio[847]: info: CorePlayQueue::getTrack 7 Jun 27 00:43:54 volumio volumio[847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"La Rue C'est Bang Bang","artist":"Dontcha","album":"Etat Brut","uri":"http://192.168.1.100:8200/MediaItems/7555.mp3","trackType":"mp3"} Jun 27 00:43:54 volumio volumio[847]: verbose: CURRENT POSITION 7 Jun 27 00:43:54 volumio volumio[847]: info: CoreStateMachine::syncState stateService play Jun 27 00:43:54 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus stop Jun 27 00:43:54 volumio volumio[847]: info: ------------------------------ 118ms Jun 27 00:43:54 volumio volumio[847]: info: ------------------------------ 12ms Jun 27 00:43:54 volumio volumio[847]: info: sendMpdCommand status took 11 milliseconds Jun 27 00:43:54 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:43:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:43:54 volumio volumio[847]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 27 00:43:54 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:43:54 volumio volumio[847]: info: ControllerMpd::pushState Jun 27 00:43:54 volumio volumio[847]: info: CoreCommandRouter::servicePushState Jun 27 00:43:54 volumio volumio[847]: info: CorePlayQueue::getTrack 7 Jun 27 00:43:54 volumio volumio[847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"La Rue C'est Bang Bang","artist":"Dontcha","album":"Etat Brut","uri":"http://192.168.1.100:8200/MediaItems/7555.mp3","trackType":"mp3"} Jun 27 00:43:54 volumio volumio[847]: verbose: CURRENT POSITION 7 Jun 27 00:43:54 volumio volumio[847]: info: CoreStateMachine::syncState stateService play Jun 27 00:43:54 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus play Jun 27 00:43:54 volumio volumio[847]: info: Received an update from plugin. extracting info from payload Jun 27 00:43:54 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:54 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 00:43:54 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:54 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:43:54 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:43:54 volumio volumio[847]: info: ------------------------------ 60ms Jun 27 00:43:54 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue Jun 27 00:43:54 volumio volumio[847]: info: CoreStateMachine::getQueue Jun 27 00:43:54 volumio volumio[847]: info: CorePlayQueue::getQueue Jun 27 00:43:54 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue Jun 27 00:43:54 volumio volumio[847]: info: CoreStateMachine::getQueue Jun 27 00:43:54 volumio volumio[847]: info: CorePlayQueue::getQueue Jun 27 00:43:57 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:44:00 volumio volumio[847]: info: CoreCommandRouter::volumioSeek Jun 27 00:44:00 volumio volumio[847]: info: CoreStateMachine::seek Jun 27 00:44:00 volumio volumio[847]: info: CorePlayQueue::getTrack 7 Jun 27 00:44:00 volumio volumio[847]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.1.100:8200/MediaItems/7555.mp3","realUri":"upnp/http://192.168.1.100:8200/ctl/ContentDir@1$14$5$2$1$1$7","type":"song","albumart":"/albumart?cacheid=528&web=Dontcha/Etat%20Brut/extralarge&path=&metadata=false","artist":"Dontcha","album":"Etat Brut","name":"La Rue C'est Bang Bang","title":"La Rue C'est Bang Bang","duration":238} Jun 27 00:44:00 volumio volumio[847]: info: CoreStateMachine::startPlaybackTimer Jun 27 00:44:00 volumio volumio[847]: info: CorePlayQueue::getTrack 7 Jun 27 00:44:00 volumio volumio[847]: info: [1750974240881] ControllerUPNPBrowser::seek Jun 27 00:44:00 volumio volumio[847]: info: ControllerMpd::seek Jun 27 00:44:00 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:44:00 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 00:44:00 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:44:00 volumio volumio[847]: error: null Jun 27 00:44:00 volumio volumio[847]: info: Jun 27 00:44:00 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 27 00:44:00 volumio volumio[847]: info: ControllerMpd::getState Jun 27 00:44:00 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 27 00:44:00 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue Jun 27 00:44:00 volumio volumio[847]: info: CoreStateMachine::getQueue Jun 27 00:44:00 volumio volumio[847]: info: CorePlayQueue::getQueue Jun 27 00:44:00 volumio volumio[847]: info: sendMpdCommand status took 23 milliseconds Jun 27 00:44:00 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 27 00:44:00 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 00:44:00 volumio volumio[847]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 27 00:44:00 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 27 00:44:00 volumio volumio[847]: info: ControllerMpd::pushState Jun 27 00:44:00 volumio volumio[847]: info: CoreCommandRouter::servicePushState Jun 27 00:44:00 volumio volumio[847]: info: CorePlayQueue::getTrack 7 Jun 27 00:44:00 volumio volumio[847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":72000,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"La Rue C'est Bang Bang","artist":"Dontcha","album":"Etat Brut","uri":"http://192.168.1.100:8200/MediaItems/7555.mp3","trackType":"mp3"} Jun 27 00:44:00 volumio volumio[847]: verbose: CURRENT POSITION 7 Jun 27 00:44:00 volumio volumio[847]: info: CoreStateMachine::syncState stateService play Jun 27 00:44:00 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus play Jun 27 00:44:00 volumio volumio[847]: info: Received an update from plugin. extracting info from payload Jun 27 00:44:00 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:44:00 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 00:44:00 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:44:00 volumio volumio[847]: info: CoreStateMachine::pushState Jun 27 00:44:00 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 27 00:44:00 volumio volumio[847]: info: ------------------------------ 89ms Jun 27 00:44:01 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue Jun 27 00:44:01 volumio volumio[847]: info: CoreStateMachine::getQueue Jun 27 00:44:01 volumio volumio[847]: info: CorePlayQueue::getQueue Jun 27 00:44:01 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue Jun 27 00:44:01 volumio volumio[847]: info: CoreStateMachine::getQueue Jun 27 00:44:01 volumio volumio[847]: info: CorePlayQueue::getQueue Jun 27 00:44:02 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:44:06 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:44:10 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:44:14 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:44:14 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 27 00:44:14 volumio volumio[847]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 00:44:14 volumio volumio[847]: TypeError: Cannot read property 'length' of undefined Jun 27 00:44:14 volumio volumio[847]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 27 00:44:14 volumio volumio[847]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 27 00:44:14 volumio volumio[847]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 27 00:44:14 volumio volumio[847]: at Parser.emit (events.js:315:20) Jun 27 00:44:14 volumio volumio[847]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 27 00:44:14 volumio volumio[847]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 27 00:44:14 volumio volumio[847]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 27 00:44:14 volumio volumio[847]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 27 00:44:14 volumio volumio[847]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 27 00:44:14 volumio volumio[847]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 27 00:44:14 volumio volumio[847]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 27 00:44:14 volumio volumio[847]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 27 00:44:14 volumio volumio[847]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 27 00:44:14 volumio volumio[847]: at IncomingMessage.emit (events.js:327:22) Jun 27 00:44:14 volumio volumio[847]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 27 00:44:14 volumio volumio[847]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 27 00:44:14 volumio volumio[847]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 00:44:18 volumio kernel: mt7601u 1-1.3:1.0: Error: TSSI upper saturation Jun 27 00:44:18 volumio sudo[6330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-27 00:43 Jun 27 00:44:18 volumio sudo[6330]: 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"