-- Logs begin at Thu 2019-02-14 03:11:58 MST, end at Sat 2025-08-02 15:16:02 MDT. -- Aug 02 15:15:00 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 02 15:15:00 deqx-gen-4 volumio[5793]: info: Preload queue cleared Aug 02 15:15:00 deqx-gen-4 volumio[5793]: info: Executing endpoint getSimilarAlbums Aug 02 15:15:00 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 02 15:15:00 deqx-gen-4 volumio[5793]: info: Executing endpoint metavolumio Aug 02 15:15:00 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 02 15:15:00 deqx-gen-4 volumio[5793]: info: Executing endpoint metavolumio Aug 02 15:15:00 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 02 15:15:00 deqx-gen-4 volumio[5793]: error: Failed request for metavolumio API Aug 02 15:15:00 deqx-gen-4 volumio[5793]: error: Failed request for metavolumio API Aug 02 15:15:00 deqx-gen-4 volumio[5793]: error: Failed request for metavolumio API Aug 02 15:15:01 deqx-gen-4 volumio[5793]: info: CoreStateMachine::startPlaybackTimer Aug 02 15:15:01 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 1058 Aug 02 15:15:01 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:01 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 1058 Aug 02 15:15:01 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 02 15:15:01 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Preload queue cleared Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::ClearQueue Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::stop Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::stPlaybackTimer Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::updateTrackBlock Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrackBlock Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 1058 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::serviceStop Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 1058 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::serviceStop Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::stop Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand stop Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::clearPlayQueue Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::saveQueue Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushQueue Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::addQueueItems Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::addQueueItems Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Preload queue cleared Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Adding Item to queue: upnp/folder/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13498/m Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/folder/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13498/m in service upnp_browser Aug 02 15:15:02 deqx-gen-4 kernel: asoc-aml-card auge_sound: tdm playback stop Aug 02 15:15:02 deqx-gen-4 kernel: spdif_a is set to disable Aug 02 15:15:02 deqx-gen-4 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Aug 02 15:15:02 deqx-gen-4 kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Aug 02 15:15:02 deqx-gen-4 kernel: tdm playback mute: 1, lane_cnt = 8 Aug 02 15:15:02 deqx-gen-4 kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces state update: player Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand stop took 62 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::getState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand status Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces state update: player Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::getState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand status Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces state update: player Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::getState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand status Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand status took 6 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand status took 4 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushQueue Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::saveQueue Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::updateTrackBlock Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrackBlock Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPlay Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::play index 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::stop Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::play index undefined Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::startPlaybackTimer Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetVisibleSources Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: [1754169302579] ControllerUPNPBrowser::clearAddPlayTrack Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand stop Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand status took 43 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand playlistinfo took 40 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand playlistinfo took 40 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand stop took 4 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseTrackInfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseTrackInfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand clear Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::servicePushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: 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":"Bach Toccata And Fugue","artist":"Gregory D'Agostino","album":"Demo Classical","uri":"NAS/DScore/USB/usb1/Demos/Classical/6-Bach Toccata And Fugue.flac","trackType":"flac"} Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: CURRENT POSITION 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState stateService stop Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState currentStatus stop Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: No code Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::servicePushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: 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":"Bach Toccata And Fugue","artist":"Gregory D'Agostino","album":"Demo Classical","uri":"NAS/DScore/USB/usb1/Demos/Classical/6-Bach Toccata And Fugue.flac","trackType":"flac"} Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: CURRENT POSITION 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState stateService stop Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState currentStatus stop Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: No code Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ------------------------------ 165ms Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ------------------------------ 164ms Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces system playlist update Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Ignoring MPD Status Update Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand playlistinfo took 139 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand clear took 137 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseTrackInfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand load "http://10.1.0.150:9000/music/12401/download.flc" Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::servicePushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: 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":"Bach Toccata And Fugue","artist":"Gregory D'Agostino","album":"Demo Classical","uri":"NAS/DScore/USB/usb1/Demos/Classical/6-Bach Toccata And Fugue.flac","trackType":"flac"} Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: CURRENT POSITION 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState stateService stop Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState currentStatus stop Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: No code Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ------------------------------ 244ms Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces system playlist update Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Ignoring MPD Status Update Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces system playlist update Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Ignoring MPD Status Update Aug 02 15:15:02 deqx-gen-4 volumio[5793]: error: updateQueue error: null Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ------------------------------ 66ms Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand add "http://10.1.0.150:9000/music/12401/download.flc" Aug 02 15:15:02 deqx-gen-4 volumio[5793]: error: updateQueue error: null Aug 02 15:15:02 deqx-gen-4 volumio[5793]: error: updateQueue error: null Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces system playlist update Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Ignoring MPD Status Update Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ------------------------------ 24ms Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ------------------------------ 22ms Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand add "http://10.1.0.150:9000/music/12401/download.flc" took 17 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand play Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces system playlist update Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Ignoring MPD Status Update Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces system playlist update Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Ignoring MPD Status Update Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ------------------------------ 12ms Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand play took 8 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ------------------------------ 9ms Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ------------------------------ 7ms Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces state update: player Aug 02 15:15:02 deqx-gen-4 kernel: aml_tdm_open Aug 02 15:15:02 deqx-gen-4 kernel: Not init audio effects Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::getState Aug 02 15:15:02 deqx-gen-4 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Aug 02 15:15:02 deqx-gen-4 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Aug 02 15:15:02 deqx-gen-4 kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d27f418, id(1), clksel(1) Aug 02 15:15:02 deqx-gen-4 kernel: aml_dai_set_tdm_fmt(), fmt not change Aug 02 15:15:02 deqx-gen-4 kernel: dump_pcm_setting(ffffffc03d27f418) Aug 02 15:15:02 deqx-gen-4 kernel: pcm_mode(1) Aug 02 15:15:02 deqx-gen-4 kernel: sysclk(11289600) Aug 02 15:15:02 deqx-gen-4 kernel: sysclk_bclk_ratio(4) Aug 02 15:15:02 deqx-gen-4 kernel: bclk(2822400) Aug 02 15:15:02 deqx-gen-4 kernel: bclk_lrclk_ratio(64) Aug 02 15:15:02 deqx-gen-4 kernel: lrclk(44100) Aug 02 15:15:02 deqx-gen-4 kernel: tx_mask(0x3) Aug 02 15:15:02 deqx-gen-4 kernel: rx_mask(0x3) Aug 02 15:15:02 deqx-gen-4 kernel: slots(2) Aug 02 15:15:02 deqx-gen-4 kernel: slot_width(32) Aug 02 15:15:02 deqx-gen-4 kernel: lane_mask_in(0x2) Aug 02 15:15:02 deqx-gen-4 kernel: lane_mask_out(0x1) Aug 02 15:15:02 deqx-gen-4 kernel: lane_oe_mask_in(0x0) Aug 02 15:15:02 deqx-gen-4 kernel: lane_oe_mask_out(0x0) Aug 02 15:15:02 deqx-gen-4 kernel: lane_lb_mask_in(0x0) Aug 02 15:15:02 deqx-gen-4 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Aug 02 15:15:02 deqx-gen-4 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Aug 02 15:15:02 deqx-gen-4 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Aug 02 15:15:02 deqx-gen-4 kernel: aml_dai_set_clkdiv, div 4, clksel(1) Aug 02 15:15:02 deqx-gen-4 kernel: aml_dai_set_bclk_ratio, select I2S mode Aug 02 15:15:02 deqx-gen-4 kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Aug 02 15:15:02 deqx-gen-4 kernel: aml_tdm_prepare(), reset fddr Aug 02 15:15:02 deqx-gen-4 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Aug 02 15:15:02 deqx-gen-4 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Aug 02 15:15:02 deqx-gen-4 kernel: hdmitx: hw: set audio Aug 02 15:15:02 deqx-gen-4 kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Aug 02 15:15:02 deqx-gen-4 kernel: hdmitx: fs = 2, cd = 4, tmds_clk = 148352 Aug 02 15:15:02 deqx-gen-4 kernel: hdmitx: hw: aud_n_para = 8918 Aug 02 15:15:02 deqx-gen-4 kernel: hdmitx: hw: set channel status Aug 02 15:15:02 deqx-gen-4 kernel: hdmitx: audio: Audio Type: PCM Aug 02 15:15:02 deqx-gen-4 kernel: hdmitx: audio: set audio param Aug 02 15:15:02 deqx-gen-4 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Aug 02 15:15:02 deqx-gen-4 kernel: tdm playback mute: 0, lane_cnt = 8 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand status Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces state update: player Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::getState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand status Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces state update: player Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::getState Aug 02 15:15:02 deqx-gen-4 kernel: asoc-aml-card auge_sound: tdm playback enable Aug 02 15:15:02 deqx-gen-4 kernel: spdif_a is set to enable Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand status Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces state update: player Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces system playlist update Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Ignoring MPD Status Update Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces state update: player Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand status took 33 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::getState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand status Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand status took 29 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand status took 23 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::getState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand status Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces state update: player Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::getState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand status Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:02 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces state update: player Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::getState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand status Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand status took 15 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ------------------------------ 22ms Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand status took 12 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand status took 9 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: sendMpdCommand status took 6 milliseconds Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseTrackInfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::servicePushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"download.flc","artist":"Curandero","album":"Arás","uri":"http://10.1.0.150:9000/music/12401/download.flc","trackType":"flc"} Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: CURRENT POSITION 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState stateService play Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState currentStatus stop Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::servicePushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"download.flc","artist":"Curandero","album":"Arás","uri":"http://10.1.0.150:9000/music/12401/download.flc","trackType":"flc"} Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: CURRENT POSITION 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState stateService play Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState currentStatus play Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Received an update from plugin. extracting info from payload Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ControllerMpd::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::servicePushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":4292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"download.flc","artist":"Curandero","album":"Arás","uri":"http://10.1.0.150:9000/music/12401/download.flc","trackType":"flc"} Aug 02 15:15:02 deqx-gen-4 volumio[5793]: verbose: CURRENT POSITION 0 Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState stateService play Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState currentStatus play Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: Received an update from plugin. extracting info from payload Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:02 deqx-gen-4 volumio[5793]: info: ------------------------------ 122ms Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ------------------------------ 158ms Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ------------------------------ 155ms Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:03 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces system playlist update Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: Ignoring MPD Status Update Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:03 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces state update: player Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ControllerMpd::getState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand status Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:03 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces system playlist update Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: Ignoring MPD Status Update Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: Aug 02 15:15:03 deqx-gen-4 volumio[5793]: ---------------------------- MPD announces state update: player Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ControllerMpd::getState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand status Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: sendMpdCommand playlistinfo took 127 milliseconds Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: sendMpdCommand playlistinfo took 127 milliseconds Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: sendMpdCommand playlistinfo took 128 milliseconds Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: sendMpdCommand playlistinfo took 127 milliseconds Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ------------------------------ 25ms Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: sendMpdCommand status took 23 milliseconds Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ------------------------------ 17ms Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseTrackInfo Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseTrackInfo Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseTrackInfo Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseTrackInfo Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ControllerMpd::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::servicePushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":4292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"download.flc","artist":"Curandero","album":"Arás","uri":"http://10.1.0.150:9000/music/12401/download.flc","trackType":"flc"} Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: CURRENT POSITION 0 Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState stateService play Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState currentStatus play Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: Received an update from plugin. extracting info from payload Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ControllerMpd::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::servicePushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":4292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"download.flc","artist":"Curandero","album":"Arás","uri":"http://10.1.0.150:9000/music/12401/download.flc","trackType":"flc"} Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: CURRENT POSITION 0 Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState stateService play Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState currentStatus play Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: Received an update from plugin. extracting info from payload Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ControllerMpd::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::servicePushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":4292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"download.flc","artist":"Curandero","album":"Arás","uri":"http://10.1.0.150:9000/music/12401/download.flc","trackType":"flc"} Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: CURRENT POSITION 0 Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState stateService play Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState currentStatus play Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: Received an update from plugin. extracting info from payload Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ControllerMpd::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::servicePushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":4292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"download.flc","artist":"Curandero","album":"Arás","uri":"http://10.1.0.150:9000/music/12401/download.flc","trackType":"flc"} Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: CURRENT POSITION 0 Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState stateService play Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState currentStatus play Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: Received an update from plugin. extracting info from payload Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ------------------------------ 363ms Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ------------------------------ 351ms Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ------------------------------ 339ms Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ------------------------------ 338ms Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: sendMpdCommand status took 360 milliseconds Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: sendMpdCommand playlistinfo took 345 milliseconds Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseTrackInfo Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ControllerMpd::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::servicePushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":4292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"download.flc","artist":"Curandero","album":"Arás","uri":"http://10.1.0.150:9000/music/12401/download.flc","trackType":"flc"} Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: CURRENT POSITION 0 Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState stateService play Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState currentStatus play Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: Received an update from plugin. extracting info from payload Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ------------------------------ 415ms Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: sendMpdCommand playlistinfo took 42 milliseconds Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: ControllerMpd::parseTrackInfo Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ControllerMpd::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::servicePushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrack 0 Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":4292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"download.flc","artist":"Curandero","album":"Arás","uri":"http://10.1.0.150:9000/music/12401/download.flc","trackType":"flc"} Aug 02 15:15:03 deqx-gen-4 volumio[5793]: verbose: CURRENT POSITION 0 Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState stateService play Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::syncState currentStatus play Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: Received an update from plugin. extracting info from payload Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::pushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushState Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: ------------------------------ 447ms Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioGetQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CoreStateMachine::getQueue Aug 02 15:15:03 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getQueue Aug 02 15:15:04 deqx-gen-4 volumio[5793]: info: Executing endpoint metavolumio Aug 02 15:15:04 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 02 15:15:04 deqx-gen-4 volumio[5793]: info: Executing endpoint metavolumio Aug 02 15:15:04 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 02 15:15:04 deqx-gen-4 volumio[5793]: info: Executing endpoint metavolumio Aug 02 15:15:04 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 02 15:15:04 deqx-gen-4 volumio[5793]: error: Failed request for metavolumio API Aug 02 15:15:04 deqx-gen-4 volumio[5793]: error: Failed request for metavolumio API Aug 02 15:15:04 deqx-gen-4 volumio[5793]: error: Failed request for metavolumio API Aug 02 15:15:12 deqx-gen-4 volumio[5793]: info: Listing playlists Aug 02 15:15:15 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioAddQueueItems Aug 02 15:15:15 deqx-gen-4 volumio[5793]: info: CoreStateMachine::addQueueItems Aug 02 15:15:15 deqx-gen-4 volumio[5793]: info: CorePlayQueue::addQueueItems Aug 02 15:15:15 deqx-gen-4 volumio[5793]: info: Preload queue cleared Aug 02 15:15:15 deqx-gen-4 volumio[5793]: info: Adding Item to queue: upnp/folder/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13498/m Aug 02 15:15:15 deqx-gen-4 volumio[5793]: info: Using cached record of: upnp/folder/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13498/m Aug 02 15:15:15 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushQueue Aug 02 15:15:15 deqx-gen-4 volumio[5793]: info: CorePlayQueue::saveQueue Aug 02 15:15:15 deqx-gen-4 volumio[5793]: info: CoreStateMachine::updateTrackBlock Aug 02 15:15:15 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrackBlock Aug 02 15:15:36 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 02 15:15:37 deqx-gen-4 volumio[5793]: info: Preload queue cleared Aug 02 15:15:43 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preload queue cleared Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12402 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12403 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12404 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12405 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12406 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12407 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12408 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12409 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12410 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12411 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12412 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12413 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12414 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12415 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12416 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12417 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12418 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12419 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12420 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12421 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12422 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12423 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12424 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12425 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12426 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12427 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12428 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12429 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12430 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12431 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12432 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12433 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12434 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12435 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12436 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Preloading song: upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12437 Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12402 in service upnp_browser Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12403 in service upnp_browser Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12404 in service upnp_browser Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12405 in service upnp_browser Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12406 in service upnp_browser Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12407 in service upnp_browser Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12408 in service upnp_browser Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12409 in service upnp_browser Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12410 in service upnp_browser Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12411 in service upnp_browser Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12412 in service upnp_browser Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12413 in service upnp_browser Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12414 in service upnp_browser Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12415 in service upnp_browser Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12416 in service upnp_browser Aug 02 15:15:44 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12417 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12418 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12419 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12420 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12421 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12422 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12423 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12424 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12425 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12426 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12427 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12428 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12429 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12430 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12431 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12432 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12433 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12434 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12435 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12436 in service upnp_browser Aug 02 15:15:45 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/t/12437 in service upnp_browser Aug 02 15:15:50 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioAddQueueItems Aug 02 15:15:50 deqx-gen-4 volumio[5793]: info: CoreStateMachine::addQueueItems Aug 02 15:15:50 deqx-gen-4 volumio[5793]: info: CorePlayQueue::addQueueItems Aug 02 15:15:50 deqx-gen-4 volumio[5793]: info: Preload queue cleared Aug 02 15:15:50 deqx-gen-4 volumio[5793]: info: Adding Item to queue: upnp/folder/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/m Aug 02 15:15:50 deqx-gen-4 volumio[5793]: info: Exploding uri upnp/folder/http://10.1.0.150:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/m/13308/m/13492/m/13499/m/16225/m in service upnp_browser Aug 02 15:15:50 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::volumioPushQueue Aug 02 15:15:50 deqx-gen-4 volumio[5793]: info: CorePlayQueue::saveQueue Aug 02 15:15:50 deqx-gen-4 volumio[5793]: info: CoreStateMachine::updateTrackBlock Aug 02 15:15:50 deqx-gen-4 volumio[5793]: info: CorePlayQueue::getTrackBlock Aug 02 15:16:01 deqx-gen-4 volumio[5793]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 02 15:16:01 deqx-gen-4 volumio[5793]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 02 15:16:01 deqx-gen-4 volumio[5793]: TypeError: Cannot read property 'length' of undefined Aug 02 15:16:01 deqx-gen-4 volumio[5793]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Aug 02 15:16:01 deqx-gen-4 volumio[5793]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Aug 02 15:16:01 deqx-gen-4 volumio[5793]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Aug 02 15:16:01 deqx-gen-4 volumio[5793]: at Parser.emit (events.js:400:28) Aug 02 15:16:01 deqx-gen-4 volumio[5793]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Aug 02 15:16:01 deqx-gen-4 volumio[5793]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Aug 02 15:16:01 deqx-gen-4 volumio[5793]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Aug 02 15:16:01 deqx-gen-4 volumio[5793]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Aug 02 15:16:01 deqx-gen-4 volumio[5793]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Aug 02 15:16:01 deqx-gen-4 volumio[5793]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Aug 02 15:16:01 deqx-gen-4 volumio[5793]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Aug 02 15:16:01 deqx-gen-4 volumio[5793]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Aug 02 15:16:01 deqx-gen-4 volumio[5793]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Aug 02 15:16:01 deqx-gen-4 volumio[5793]: at IncomingMessage.emit (events.js:412:35) Aug 02 15:16:01 deqx-gen-4 volumio[5793]: at endReadableNT (internal/streams/readable.js:1333:12) Aug 02 15:16:01 deqx-gen-4 volumio[5793]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Aug 02 15:16:01 deqx-gen-4 volumio[5793]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 02 15:16:02 deqx-gen-4 sudo[12142]: volumio : unable to resolve host deqx-gen-4 Aug 02 15:16:02 deqx-gen-4 sudo[12142]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 02 15:16:02 deqx-gen-4 sudo[12142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-02 15:15 Aug 02 15:16:02 deqx-gen-4 sudo[12142]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="900c37ee0cafd620e4a37a1118cc5316b54b45b3" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="ca3c0885e14bcba58c57ef609685ae76316b61d6" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="deqx" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 16 Apr 2024 02:17:33 PM CEST" VOLUMIO_VERSION="3.624" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_HASH="ca1050ae12f3dc501a57f6b4c284f5d2"