-- Logs begin at Sat 2025-02-01 20:13:32 MST, end at Mon 2025-02-17 19:27:36 MST. -- Feb 17 19:26:29 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 17 19:26:29 deqx-gen-4 volumio[5842]: info: Received Get System Info Feb 17 19:26:29 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 19:26:29 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 19:26:29 deqx-gen-4 volumio[5842]: info: Discovery: Getting this device information Feb 17 19:26:29 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioGetState Feb 17 19:26:29 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioStop Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreStateMachine::stop Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreStateMachine::stPlaybackTimer Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreStateMachine::updateTrackBlock Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrackBlock Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreStateMachine::serviceStop Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::serviceStop Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: [1739845598363] ControllerWebradio::stop Feb 17 19:26:38 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 19:26:38 deqx-gen-4 kernel: asoc-aml-card auge_sound: tdm playback stop Feb 17 19:26:38 deqx-gen-4 kernel: spdif_a is set to disable Feb 17 19:26:38 deqx-gen-4 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Feb 17 19:26:38 deqx-gen-4 kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Feb 17 19:26:38 deqx-gen-4 kernel: tdm playback mute: 1, lane_cnt = 8 Feb 17 19:26:38 deqx-gen-4 kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:38 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces state update: player Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: sendMpdCommand stop took 33 milliseconds Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: ControllerMpd::getState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand status Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:38 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces state update: player Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: ControllerMpd::getState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand status Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:38 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces state update: player Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: ControllerMpd::getState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand status Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: sendMpdCommand status took 6 milliseconds Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: sendMpdCommand status took 3 milliseconds Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: sendMpdCommand status took 3 milliseconds Feb 17 19:26:38 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 19:26:38 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 19:26:38 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 17 19:26:38 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseTrackInfo Feb 17 19:26:38 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseTrackInfo Feb 17 19:26:38 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseTrackInfo Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: ControllerMpd::pushState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::servicePushState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: ControllerMpd::pushState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::servicePushState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: ControllerMpd::pushState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::servicePushState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: ------------------------------ 56ms Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: ------------------------------ 52ms Feb 17 19:26:38 deqx-gen-4 volumio[5842]: info: ------------------------------ 50ms Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: Executing endpoint metavolumio Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPlay Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: CoreStateMachine::play index undefined Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: CoreStateMachine::startPlaybackTimer Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: [1739845600978] ControllerWebradio::clearAddPlayTrack Feb 17 19:26:40 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: sendMpdCommand stop took 1 milliseconds Feb 17 19:26:40 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: sendMpdCommand clear took 1 milliseconds Feb 17 19:26:40 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/api/livestream-redirect/WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70" Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:40 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces system playlist update Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: Ignoring MPD Status Update Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:40 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces system playlist update Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: Ignoring MPD Status Update Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:40 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces system playlist update Feb 17 19:26:40 deqx-gen-4 volumio[5842]: info: Ignoring MPD Status Update Feb 17 19:26:43 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70" Feb 17 19:26:43 deqx-gen-4 volumio[5842]: error: updateQueue error: null Feb 17 19:26:43 deqx-gen-4 volumio[5842]: error: updateQueue error: null Feb 17 19:26:43 deqx-gen-4 volumio[5842]: error: updateQueue error: null Feb 17 19:26:43 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:43 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces system playlist update Feb 17 19:26:43 deqx-gen-4 volumio[5842]: info: Ignoring MPD Status Update Feb 17 19:26:43 deqx-gen-4 volumio[5842]: info: ------------------------------ 2256ms Feb 17 19:26:43 deqx-gen-4 volumio[5842]: info: ------------------------------ 2255ms Feb 17 19:26:43 deqx-gen-4 volumio[5842]: info: ------------------------------ 2253ms Feb 17 19:26:43 deqx-gen-4 volumio[5842]: info: sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70" took 6 milliseconds Feb 17 19:26:43 deqx-gen-4 volumio[5842]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 17 19:26:43 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand play Feb 17 19:26:43 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:43 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces system playlist update Feb 17 19:26:43 deqx-gen-4 volumio[5842]: info: Ignoring MPD Status Update Feb 17 19:26:43 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:43 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces system playlist update Feb 17 19:26:43 deqx-gen-4 volumio[5842]: info: Ignoring MPD Status Update Feb 17 19:26:43 deqx-gen-4 volumio[5842]: Upnp client error: Error: This socket has been ended by the other party Feb 17 19:26:43 deqx-gen-4 volumio[5842]: info: ------------------------------ 14ms Feb 17 19:26:43 deqx-gen-4 volumio[5842]: info: sendMpdCommand play took 11 milliseconds Feb 17 19:26:43 deqx-gen-4 volumio[5842]: info: ------------------------------ 10ms Feb 17 19:26:43 deqx-gen-4 volumio[5842]: info: ------------------------------ 9ms Feb 17 19:26:44 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:44 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces state update: player Feb 17 19:26:44 deqx-gen-4 volumio[5842]: info: ControllerMpd::getState Feb 17 19:26:44 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand status Feb 17 19:26:44 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:44 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces state update: player Feb 17 19:26:44 deqx-gen-4 kernel: aml_tdm_open Feb 17 19:26:44 deqx-gen-4 kernel: Not init audio effects Feb 17 19:26:44 deqx-gen-4 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Feb 17 19:26:44 deqx-gen-4 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Feb 17 19:26:44 deqx-gen-4 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Feb 17 19:26:44 deqx-gen-4 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Feb 17 19:26:44 deqx-gen-4 kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d2aa618, id(1), clksel(1) Feb 17 19:26:44 deqx-gen-4 kernel: aml_dai_set_tdm_fmt(), fmt not change Feb 17 19:26:44 deqx-gen-4 kernel: dump_pcm_setting(ffffffc03d2aa618) Feb 17 19:26:44 deqx-gen-4 kernel: pcm_mode(1) Feb 17 19:26:44 deqx-gen-4 kernel: sysclk(11289600) Feb 17 19:26:44 deqx-gen-4 kernel: sysclk_bclk_ratio(4) Feb 17 19:26:44 deqx-gen-4 kernel: bclk(2822400) Feb 17 19:26:44 deqx-gen-4 kernel: bclk_lrclk_ratio(64) Feb 17 19:26:44 deqx-gen-4 kernel: lrclk(44100) Feb 17 19:26:44 deqx-gen-4 kernel: tx_mask(0x3) Feb 17 19:26:44 deqx-gen-4 kernel: rx_mask(0x3) Feb 17 19:26:44 deqx-gen-4 kernel: slots(2) Feb 17 19:26:44 deqx-gen-4 kernel: slot_width(32) Feb 17 19:26:44 deqx-gen-4 kernel: lane_mask_in(0x2) Feb 17 19:26:44 deqx-gen-4 kernel: lane_mask_out(0x1) Feb 17 19:26:44 deqx-gen-4 kernel: lane_oe_mask_in(0x0) Feb 17 19:26:44 deqx-gen-4 kernel: lane_oe_mask_out(0x0) Feb 17 19:26:44 deqx-gen-4 kernel: lane_lb_mask_in(0x0) Feb 17 19:26:44 deqx-gen-4 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Feb 17 19:26:44 deqx-gen-4 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Feb 17 19:26:44 deqx-gen-4 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Feb 17 19:26:44 deqx-gen-4 kernel: aml_dai_set_clkdiv, div 4, clksel(1) Feb 17 19:26:44 deqx-gen-4 kernel: aml_dai_set_bclk_ratio, select I2S mode Feb 17 19:26:44 deqx-gen-4 kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Feb 17 19:26:44 deqx-gen-4 kernel: aml_tdm_prepare(), reset fddr Feb 17 19:26:44 deqx-gen-4 kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Feb 17 19:26:44 deqx-gen-4 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Feb 17 19:26:44 deqx-gen-4 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Feb 17 19:26:44 deqx-gen-4 kernel: tdm playback mute: 0, lane_cnt = 8 Feb 17 19:26:44 deqx-gen-4 volumio[5842]: info: ControllerMpd::getState Feb 17 19:26:44 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand status Feb 17 19:26:44 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:44 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces state update: player Feb 17 19:26:44 deqx-gen-4 volumio[5842]: info: ControllerMpd::getState Feb 17 19:26:44 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand status Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:45 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces state update: player Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand status took 14 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand status took 15 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ControllerMpd::getState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand status Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand status took 14 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:45 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces state update: player Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ControllerMpd::getState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand status Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:45 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces state update: player Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ControllerMpd::getState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand status Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand status took 12 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand status took 5 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand status took 4 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseTrackInfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseTrackInfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ControllerMpd::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::servicePushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 kernel: asoc-aml-card auge_sound: tdm playback enable Feb 17 19:26:45 deqx-gen-4 kernel: spdif_a is set to enable Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","trackType":""} Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: CURRENT POSITION 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState stateService play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState currentStatus stop Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ControllerMpd::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::servicePushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","trackType":""} Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: CURRENT POSITION 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState stateService play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState currentStatus play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Received an update from plugin. extracting info from payload Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ControllerMpd::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::servicePushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","trackType":""} Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: CURRENT POSITION 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState stateService play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState currentStatus play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Received an update from plugin. extracting info from payload Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ------------------------------ 97ms Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ------------------------------ 110ms Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ------------------------------ 110ms Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:45 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces system playlist update Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Ignoring MPD Status Update Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:45 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces state update: player Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand playlistinfo took 103 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ControllerMpd::getState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand status Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand playlistinfo took 103 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand playlistinfo took 104 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseTrackInfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseTrackInfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseTrackInfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ControllerMpd::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::servicePushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","trackType":""} Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: CURRENT POSITION 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState stateService play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState currentStatus play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Received an update from plugin. extracting info from payload Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ControllerMpd::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::servicePushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","artist":"WRTI_JAZZ","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","trackType":""} Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: CURRENT POSITION 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState stateService play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState currentStatus play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Received an update from plugin. extracting info from payload Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ControllerMpd::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::servicePushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","artist":"WRTI_JAZZ","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","trackType":""} Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: CURRENT POSITION 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState stateService play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState currentStatus play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Received an update from plugin. extracting info from payload Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ------------------------------ 158ms Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ------------------------------ 151ms Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ------------------------------ 146ms Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:45 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces system playlist update Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Ignoring MPD Status Update Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:45 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces state update: player Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ControllerMpd::getState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand status Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:45 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces system playlist update Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Ignoring MPD Status Update Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:45 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces state update: player Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ControllerMpd::getState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand status Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ------------------------------ 73ms Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand status took 71 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ------------------------------ 32ms Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand status took 31 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ------------------------------ 30ms Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand status took 28 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseTrackInfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseTrackInfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseTrackInfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ControllerMpd::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::servicePushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","artist":"WRTI_JAZZ","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","trackType":""} Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: CURRENT POSITION 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState stateService play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState currentStatus play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Received an update from plugin. extracting info from payload Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ControllerMpd::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::servicePushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","artist":"WRTI_JAZZ","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","trackType":""} Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: CURRENT POSITION 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState stateService play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState currentStatus play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Received an update from plugin. extracting info from payload Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ControllerMpd::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::servicePushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","artist":"WRTI_JAZZ","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/WRTI_JAZZ.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczODMzOTk2NywiaXNzIjoidGlzcnYifQ.HFtQZRbmuf-j90CZ0QRnQZ0NAAX23y3c45yEmlqPc70","trackType":""} Feb 17 19:26:45 deqx-gen-4 volumio[5842]: verbose: CURRENT POSITION 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState stateService play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::syncState currentStatus play Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: Received an update from plugin. extracting info from payload Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ------------------------------ 117ms Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ------------------------------ 78ms Feb 17 19:26:45 deqx-gen-4 volumio[5842]: info: ------------------------------ 76ms Feb 17 19:26:47 deqx-gen-4 volumio[5842]: info: Executing endpoint metavolumio Feb 17 19:26:47 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 17 19:26:47 deqx-gen-4 volumio[5842]: info: Executing endpoint metavolumio Feb 17 19:26:47 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioStop Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreStateMachine::stop Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreStateMachine::stPlaybackTimer Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreStateMachine::updateTrackBlock Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrackBlock Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreStateMachine::serviceStop Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::serviceStop Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: [1739845611168] ControllerWebradio::stop Feb 17 19:26:51 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 19:26:51 deqx-gen-4 kernel: asoc-aml-card auge_sound: tdm playback stop Feb 17 19:26:51 deqx-gen-4 kernel: spdif_a is set to disable Feb 17 19:26:51 deqx-gen-4 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Feb 17 19:26:51 deqx-gen-4 kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Feb 17 19:26:51 deqx-gen-4 kernel: tdm playback mute: 1, lane_cnt = 8 Feb 17 19:26:51 deqx-gen-4 kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:51 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces state update: player Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: sendMpdCommand stop took 25 milliseconds Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: ControllerMpd::getState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand status Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:51 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces state update: player Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: ControllerMpd::getState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand status Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: Feb 17 19:26:51 deqx-gen-4 volumio[5842]: ---------------------------- MPD announces state update: player Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: ControllerMpd::getState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand status Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: sendMpdCommand status took 4 milliseconds Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: sendMpdCommand status took 3 milliseconds Feb 17 19:26:51 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 19:26:51 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: sendMpdCommand status took 4 milliseconds Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 17 19:26:51 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 19:26:51 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseTrackInfo Feb 17 19:26:51 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseTrackInfo Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: ControllerMpd::pushState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::servicePushState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: ControllerMpd::pushState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::servicePushState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: ------------------------------ 23ms Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: ------------------------------ 20ms Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 17 19:26:51 deqx-gen-4 volumio[5842]: verbose: ControllerMpd::parseTrackInfo Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: ControllerMpd::pushState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::servicePushState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreStateMachine::pushState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioPushState Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 19:26:51 deqx-gen-4 volumio[5842]: info: ------------------------------ 28ms Feb 17 19:26:53 deqx-gen-4 volumio[5842]: info: Executing endpoint metavolumio Feb 17 19:26:53 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 17 19:26:56 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioGetQueue Feb 17 19:26:56 deqx-gen-4 volumio[5842]: info: CoreStateMachine::getQueue Feb 17 19:26:56 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getQueue Feb 17 19:27:00 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::volumioGetState Feb 17 19:27:00 deqx-gen-4 volumio[5842]: info: CorePlayQueue::getTrack 0 Feb 17 19:27:03 deqx-gen-4 volumio[5842]: info: Executing endpoint metavolumio Feb 17 19:27:03 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 17 19:27:18 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 19:27:18 deqx-gen-4 volumio[5842]: info: Preload queue cleared Feb 17 19:27:23 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 19:27:23 deqx-gen-4 volumio[5842]: info: Preload queue cleared Feb 17 19:27:25 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 19:27:25 deqx-gen-4 volumio[5842]: info: Preload queue cleared Feb 17 19:27:29 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 19:27:29 deqx-gen-4 volumio[5842]: info: Preload queue cleared Feb 17 19:27:31 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 19:27:31 deqx-gen-4 volumio[5842]: info: Preload queue cleared Feb 17 19:27:35 deqx-gen-4 volumio[5842]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 19:27:35 deqx-gen-4 volumio[5842]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 19:27:35 deqx-gen-4 volumio[5842]: TypeError: Cannot read property 'length' of undefined Feb 17 19:27:35 deqx-gen-4 volumio[5842]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Feb 17 19:27:35 deqx-gen-4 volumio[5842]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Feb 17 19:27:35 deqx-gen-4 volumio[5842]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Feb 17 19:27:35 deqx-gen-4 volumio[5842]: at Parser.emit (events.js:400:28) Feb 17 19:27:35 deqx-gen-4 volumio[5842]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Feb 17 19:27:35 deqx-gen-4 volumio[5842]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Feb 17 19:27:35 deqx-gen-4 volumio[5842]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Feb 17 19:27:35 deqx-gen-4 volumio[5842]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Feb 17 19:27:35 deqx-gen-4 volumio[5842]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Feb 17 19:27:35 deqx-gen-4 volumio[5842]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Feb 17 19:27:35 deqx-gen-4 volumio[5842]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Feb 17 19:27:35 deqx-gen-4 volumio[5842]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Feb 17 19:27:35 deqx-gen-4 volumio[5842]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Feb 17 19:27:35 deqx-gen-4 volumio[5842]: at IncomingMessage.emit (events.js:412:35) Feb 17 19:27:35 deqx-gen-4 volumio[5842]: at endReadableNT (internal/streams/readable.js:1333:12) Feb 17 19:27:35 deqx-gen-4 volumio[5842]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Feb 17 19:27:35 deqx-gen-4 volumio[5842]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 19:27:36 deqx-gen-4 sudo[18636]: volumio : unable to resolve host deqx-gen-4 Feb 17 19:27:36 deqx-gen-4 sudo[18636]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 17 19:27:36 deqx-gen-4 sudo[18636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-17 19:26 Feb 17 19:27:36 deqx-gen-4 sudo[18636]: 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"