-- Logs begin at Sat 2025-04-19 21:23:12 CEST, end at Sat 2025-04-19 21:25:51 CEST. -- Apr 19 21:24:00 primo volumio[3106]: info: MyVolumio status changed Apr 19 21:24:00 primo volumio[3106]: info: Streaming services startup Apr 19 21:24:00 primo volumio[3106]: info: Starting Streaming Daemon Apr 19 21:24:00 primo volumio[3106]: info: Removing browser output: myVolumio user plan is not superstar Apr 19 21:24:00 primo volumio[3106]: info: Removing audio output: Apr 19 21:24:00 primo volumio[3106]: info: Stoppping Tunnel 1 Apr 19 21:24:00 primo sudo[3725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 19 21:24:00 primo sudo[3725]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:24:00 primo sudo[3725]: pam_unix(sudo:session): session closed for user root Apr 19 21:24:00 primo sudo[3728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 19 21:24:00 primo volumio[3106]: error: Cannot start Volumio Streaming Daemon Apr 19 21:24:00 primo volumio[3106]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 19 21:24:00 primo volumio[3106]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 19 21:24:00 primo sudo[3728]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:24:00 primo sudo[3728]: pam_unix(sudo:session): session closed for user root Apr 19 21:24:00 primo volumio[3106]: info: Remote SSH Stopped Apr 19 21:24:00 primo volumio[3106]: info: Setting Geolocation for MyVolumio to eu2 Apr 19 21:24:00 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:24:00 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:24:00 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:24:01 primo volumio[3106]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 19 21:24:01 primo kernel: fb: mem_free_work, free memory: addr:800000 Apr 19 21:24:01 primo volumio[3106]: info: Updating MyVolumio device info Apr 19 21:24:01 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:24:01 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:24:01 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:24:02 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Apr 19 21:24:02 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:02 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:24:02 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:02 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:24:02 primo volumio[3106]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 19 21:24:02 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:02 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:24:02 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:02 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:24:02 primo volumio[3444]: ......................................................................................................................................................++++ Apr 19 21:24:02 primo volumio[3444]: e is 65537 (0x010001) Apr 19 21:24:02 primo volumio[3444]: writing RSA key Apr 19 21:24:05 primo volumio[3106]: info: MYVOLUMIO: Adding device Apr 19 21:24:05 primo volumio[3106]: info: MYVOLUMIO: Evaluating Server Apr 19 21:24:06 primo volumio[3106]: info: Setting Geolocation for MyVolumio to eu9 Apr 19 21:24:06 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:24:06 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:24:06 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:24:06 primo volumio[3106]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 19 21:24:07 primo volumio[3106]: info: Updating MyVolumio device info Apr 19 21:24:07 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:24:07 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:24:07 primo volumio[3106]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 19 21:24:08 primo volumio[3106]: error: MyVolumio Plugin failed to start in a timely fashion Apr 19 21:24:08 primo volumio[3106]: [Metrics] CommandRouter: 46s 961.38ms Apr 19 21:24:08 primo volumio[3106]: info: CoreCommandRouter::volumiosetStartupVolume Apr 19 21:24:08 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:24:08 primo volumio[3106]: info: VolumeController:: Setting startup Volume 40 Apr 19 21:24:08 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Apr 19 21:24:08 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:24:08 primo volumio[3106]: info: CoreCommandRouter::Close All Modals sent Apr 19 21:24:08 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:08 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:24:08 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:08 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:24:08 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:08 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:08 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:24:08 primo volumio[3106]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 19 21:24:08 primo volumio[3106]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 19 21:24:08 primo volumio[3106]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 19 21:24:08 primo volumio[3106]: info: FusionDsp - Effects disabled Apr 19 21:24:08 primo volumio[3106]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 19 21:24:09 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 19 21:24:09 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 19 21:24:09 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 19 21:24:12 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Apr 19 21:24:12 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:12 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:24:12 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:12 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:24:12 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:12 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:12 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:24:12 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 21:24:12 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:24:12 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 21:24:12 primo volumio-remote-updater[2827]: No test mode Apr 19 21:24:12 primo volumio-remote-updater[2827]: No alpha test mode Apr 19 21:24:12 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 19 21:24:13 primo volumio[3106]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 19 21:24:13 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 19 21:24:13 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 21:24:13 primo volumio[3106]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 19 21:24:13 primo volumio[3106]: info: CoreCommandRouter::volumioGetState Apr 19 21:24:14 primo volumio[3106]: info: CoreCommandRouter::volumioPause Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::pause Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::servicePause Apr 19 21:24:14 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:14 primo volumio[3106]: info: CoreCommandRouter::servicePause Apr 19 21:24:14 primo volumio[3106]: info: [1745090654779] ControllerTidal::pause Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:24:14 primo volumio[3106]: info: ControllerMpd::pause Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand pause Apr 19 21:24:14 primo volumio[3106]: info: Apr 19 21:24:14 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:24:14 primo volumio[3106]: info: sendMpdCommand pause took 4 milliseconds Apr 19 21:24:14 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:24:14 primo volumio[3106]: info: sendMpdCommand status took 3 milliseconds Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:24:14 primo volumio[3106]: info: Apr 19 21:24:14 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:24:14 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:24:14 primo volumio[3106]: info: Apr 19 21:24:14 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:24:14 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:24:14 primo volumio[3106]: info: Apr 19 21:24:14 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:24:14 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:24:14 primo volumio[3106]: info: sendMpdCommand playlistinfo took 24 milliseconds Apr 19 21:24:14 primo volumio[3106]: info: sendMpdCommand status took 22 milliseconds Apr 19 21:24:14 primo volumio[3106]: info: sendMpdCommand status took 20 milliseconds Apr 19 21:24:14 primo volumio[3106]: info: sendMpdCommand status took 5 milliseconds Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:24:14 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:24:14 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:24:14 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:14 primo volumio[3106]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22582,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"749 Kbps","isStreaming":false,"title":"94554966&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554966&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:24:14 primo volumio[3106]: verbose: CURRENT POSITION 0 Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::syncState stateService pause Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus pause Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:14 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:24:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:24:14 primo volumio[3106]: info: ------------------------------ 74ms Apr 19 21:24:14 primo volumio[3106]: info: sendMpdCommand playlistinfo took 49 milliseconds Apr 19 21:24:14 primo volumio[3106]: info: sendMpdCommand playlistinfo took 47 milliseconds Apr 19 21:24:14 primo volumio[3106]: info: sendMpdCommand playlistinfo took 46 milliseconds Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:24:14 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:24:14 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:24:14 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:24:14 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:14 primo volumio[3106]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22582,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"749 Kbps","isStreaming":false,"title":"94554966&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554966&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:24:14 primo volumio[3106]: verbose: CURRENT POSITION 0 Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::syncState stateService pause Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus pause Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:24:14 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:24:14 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:24:14 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:14 primo volumio[3106]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22582,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"749 Kbps","isStreaming":false,"title":"94554966&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554966&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:24:14 primo volumio[3106]: verbose: CURRENT POSITION 0 Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::syncState stateService pause Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus pause Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:24:14 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:24:14 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:24:14 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:14 primo volumio[3106]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22582,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"749 Kbps","isStreaming":false,"title":"94554966&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554966&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:24:14 primo volumio[3106]: verbose: CURRENT POSITION 0 Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::syncState stateService pause Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus pause Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:14 primo volumio[3106]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:24:14 primo volumio[3106]: info: ------------------------------ 144ms Apr 19 21:24:14 primo volumio[3106]: info: ------------------------------ 142ms Apr 19 21:24:14 primo volumio[3106]: info: ------------------------------ 128ms Apr 19 21:24:15 primo volumio[3106]: info: MCU Signalled Playback Inactive Apr 19 21:24:15 primo volumio[3106]: info: BOOT COMPLETED Apr 19 21:24:18 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:24:18 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:24:18 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 19 21:24:18 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 19 21:24:18 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 19 21:24:18 primo volumio[3106]: info: CoreCommandRouter::volumioGetBrowseSources Apr 19 21:24:18 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 21:24:18 primo kernel: asoc-aml-card auge_sound: tdm playback stop Apr 19 21:24:18 primo kernel: spdif_a is set to disable Apr 19 21:24:18 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 19 21:24:18 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Apr 19 21:24:18 primo kernel: tdm playback mute: 1, lane_cnt = 8 Apr 19 21:24:18 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Apr 19 21:24:18 primo volumio[3106]: info: camilladsp spawned new process with pid 3876, instance 1, run: true Apr 19 21:24:18 primo kernel: aml_tdm_open Apr 19 21:24:18 primo kernel: Not init audio effects Apr 19 21:24:18 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Apr 19 21:24:18 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 19 21:24:18 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 19 21:24:18 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 19 21:24:18 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d2b5c18, id(1), clksel(1) Apr 19 21:24:18 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Apr 19 21:24:18 primo kernel: dump_pcm_setting(ffffffc03d2b5c18) Apr 19 21:24:18 primo kernel: pcm_mode(1) Apr 19 21:24:18 primo kernel: sysclk(11289600) Apr 19 21:24:18 primo kernel: sysclk_bclk_ratio(4) Apr 19 21:24:18 primo kernel: bclk(2822400) Apr 19 21:24:18 primo kernel: bclk_lrclk_ratio(64) Apr 19 21:24:18 primo kernel: lrclk(44100) Apr 19 21:24:18 primo kernel: tx_mask(0x3) Apr 19 21:24:18 primo kernel: rx_mask(0x3) Apr 19 21:24:18 primo kernel: slots(2) Apr 19 21:24:18 primo kernel: slot_width(32) Apr 19 21:24:18 primo kernel: lane_mask_in(0x2) Apr 19 21:24:18 primo kernel: lane_mask_out(0x1) Apr 19 21:24:18 primo kernel: lane_oe_mask_in(0x0) Apr 19 21:24:18 primo kernel: lane_oe_mask_out(0x0) Apr 19 21:24:18 primo kernel: lane_lb_mask_in(0x0) Apr 19 21:24:18 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 19 21:24:18 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 19 21:24:18 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 19 21:24:18 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Apr 19 21:24:18 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Apr 19 21:24:18 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Apr 19 21:24:18 primo kernel: aml_tdm_prepare(), reset fddr Apr 19 21:24:18 primo kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Apr 19 21:24:18 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Apr 19 21:24:18 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 19 21:24:18 primo kernel: tdm playback mute: 0, lane_cnt = 8 Apr 19 21:24:19 primo volumio[3106]: info: CoreCommandRouter::volumioSeek Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::seek Apr 19 21:24:19 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:19 primo volumio[3106]: info: TRACKBLOCK {"album":"True Meanings","albumUri":"tidal://album/94554965","albumart":"https://resources.tidal.com/images/662bca27/7958/4041/95b9/d30f2a685094/640x640.jpg","artist":"Paul Weller","artistUri":"tidal://artist/27554","audioQuality":"LOSSLESS","bitdepth":"16 bit","duration":302,"explicit":false,"name":"The Soul Searchers","samplerate":"44.1 KHz","service":"tidal","title":"The Soul Searchers","trackType":"tidal","tracknumber":1,"type":"track","uri":"tidal://song/94554966","volumeNumber":1} Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::startPlaybackTimer Apr 19 21:24:19 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:19 primo volumio[3106]: info: [1745090659623] ControllerTidal::seek Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:24:19 primo volumio[3106]: info: ControllerMpd::seek Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:19 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:24:19 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:19 primo volumio[3106]: error: null Apr 19 21:24:19 primo volumio[3106]: info: Apr 19 21:24:19 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:24:19 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:24:19 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:24:19 primo volumio[3106]: info: Apr 19 21:24:19 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:24:19 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:24:19 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:24:19 primo volumio[3106]: info: Apr 19 21:24:19 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:24:19 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:24:19 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:24:19 primo volumio[3106]: info: Apr 19 21:24:19 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:24:19 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:24:19 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:24:19 primo volumio[3106]: info: sendMpdCommand status took 18 milliseconds Apr 19 21:24:19 primo volumio[3106]: info: sendMpdCommand status took 9 milliseconds Apr 19 21:24:19 primo volumio[3106]: info: sendMpdCommand status took 8 milliseconds Apr 19 21:24:19 primo volumio[3106]: info: sendMpdCommand status took 6 milliseconds Apr 19 21:24:19 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:24:19 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:24:19 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:24:19 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:24:19 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:24:19 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:24:19 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:24:19 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:24:19 primo volumio[3106]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 19 21:24:19 primo volumio[3106]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 19 21:24:19 primo volumio[3106]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 19 21:24:19 primo volumio[3106]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 19 21:24:19 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:24:19 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:24:19 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:24:19 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:24:19 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:24:19 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:24:19 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:19 primo volumio[3106]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"749 Kbps","isStreaming":false,"title":"94554966&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554966&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:24:19 primo volumio[3106]: verbose: CURRENT POSITION 0 Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::syncState stateService pause Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus pause Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:19 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:24:19 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:24:19 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:24:19 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:19 primo volumio[3106]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"749 Kbps","isStreaming":false,"title":"94554966&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554966&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:24:19 primo volumio[3106]: verbose: CURRENT POSITION 0 Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::syncState stateService pause Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus pause Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:19 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:24:19 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:24:19 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:24:19 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:19 primo volumio[3106]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"749 Kbps","isStreaming":false,"title":"94554966&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554966&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:24:19 primo volumio[3106]: verbose: CURRENT POSITION 0 Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::syncState stateService pause Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus pause Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:19 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:24:19 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:24:19 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:24:19 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:19 primo volumio[3106]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"749 Kbps","isStreaming":false,"title":"94554966&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554966&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:24:19 primo volumio[3106]: verbose: CURRENT POSITION 0 Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::syncState stateService pause Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus pause Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:19 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:19 primo volumio[3106]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:24:19 primo volumio[3106]: info: ------------------------------ 108ms Apr 19 21:24:19 primo volumio[3106]: info: ------------------------------ 100ms Apr 19 21:24:19 primo volumio[3106]: info: ------------------------------ 99ms Apr 19 21:24:19 primo volumio[3106]: info: ------------------------------ 97ms Apr 19 21:24:21 primo volumio[3106]: info: CoreCommandRouter::volumioPlay Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::play index undefined Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:24:21 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::startPlaybackTimer Apr 19 21:24:21 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:21 primo volumio[3106]: info: [1745090661647] ControllerTidal::resume Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:24:21 primo volumio[3106]: info: ControllerMpd::resume Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand play Apr 19 21:24:21 primo volumio[3106]: info: FusionDsp - ---- read samplerate, raw: Apr 19 21:24:21 primo volumio[3106]: error: FusionDsp - invalid sample rate Apr 19 21:24:21 primo volumio[3106]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 19 21:24:21 primo volumio[3106]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 19 21:24:21 primo volumio[3106]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 19 21:24:21 primo volumio[3106]: info: FusionDsp - Effects disabled Apr 19 21:24:21 primo volumio[3106]: info: Apr 19 21:24:21 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:24:21 primo volumio[3106]: info: sendMpdCommand play took 38 milliseconds Apr 19 21:24:21 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:24:21 primo volumio[3106]: info: Apr 19 21:24:21 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:24:21 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:24:21 primo volumio[3106]: info: Apr 19 21:24:21 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:24:21 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:24:21 primo volumio[3106]: info: Apr 19 21:24:21 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:24:21 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:24:21 primo volumio[3106]: info: sendMpdCommand status took 9 milliseconds Apr 19 21:24:21 primo volumio[3106]: info: sendMpdCommand status took 8 milliseconds Apr 19 21:24:21 primo volumio[3106]: info: sendMpdCommand status took 7 milliseconds Apr 19 21:24:21 primo volumio[3106]: info: sendMpdCommand status took 5 milliseconds Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:24:21 primo kernel: asoc-aml-card auge_sound: tdm playback enable Apr 19 21:24:21 primo kernel: spdif_a is set to enable Apr 19 21:24:21 primo volumio[3106]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 19 21:24:21 primo volumio[3106]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 19 21:24:21 primo volumio[3106]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:24:21 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:24:21 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:24:21 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:24:21 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:21 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"604 Kbps","isStreaming":false,"title":"94554966&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554966&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:24:21 primo volumio[3106]: verbose: CURRENT POSITION 0 Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus pause Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:21 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:24:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:21 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:24:21 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:24:21 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:21 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"601 Kbps","isStreaming":false,"title":"94554966&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554966&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:24:21 primo volumio[3106]: verbose: CURRENT POSITION 0 Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:24:21 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:21 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:24:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:21 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:24:21 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:24:21 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:21 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"601 Kbps","isStreaming":false,"title":"94554966&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554966&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:24:21 primo volumio[3106]: verbose: CURRENT POSITION 0 Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:24:21 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:21 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:24:21 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:24:21 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:24:21 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"601 Kbps","isStreaming":false,"title":"94554966&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554966&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:24:21 primo volumio[3106]: verbose: CURRENT POSITION 0 Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:24:21 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:24:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:24:21 primo volumio[3106]: info: ------------------------------ 139ms Apr 19 21:24:21 primo volumio[3106]: info: ------------------------------ 137ms Apr 19 21:24:21 primo volumio[3106]: info: ------------------------------ 136ms Apr 19 21:24:21 primo volumio[3106]: info: ------------------------------ 136ms Apr 19 21:24:21 primo volumio[3106]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 19 21:24:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:24:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:24:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:24:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:24:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:24:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:24:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:24:21 primo volumio[3106]: info: MCU Signalled Playback Active Apr 19 21:25:10 primo volumio[3106]: verbose: New Socket.io Connection to 192.168.178.220 from 192.168.178.206 UA: Mozilla/5.0 (Linux; Android 14; SM-S906B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.99 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 19 21:25:12 primo volumio[3106]: verbose: New Socket.io Connection to 192.168.178.220 from 192.168.178.206 UA: Mozilla/5.0 (Linux; Android 14; SM-S906B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.99 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 19 21:25:12 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 21:25:12 primo volumio[3106]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 21:25:12 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 21:25:12 primo volumio[3106]: info: CoreCommandRouter::volumioGetState Apr 19 21:25:12 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 19 21:25:12 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 21:25:12 primo volumio[3106]: info: Received Get System Info Apr 19 21:25:12 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 21:25:12 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 21:25:12 primo volumio[3106]: info: Discovery: Getting this device information Apr 19 21:25:12 primo volumio[3106]: info: CoreCommandRouter::volumioGetState Apr 19 21:25:12 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 21:25:12 primo volumio[3106]: info: CoreCommandRouter::volumioGetState Apr 19 21:25:12 primo volumio[3106]: info: Listing playlists Apr 19 21:25:12 primo volumio[3106]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:25:12 primo volumio[3106]: info: CoreStateMachine::getQueue Apr 19 21:25:12 primo volumio[3106]: info: CorePlayQueue::getQueue Apr 19 21:25:12 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 19 21:25:12 primo volumio[3106]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 21:25:12 primo volumio[3106]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 21:25:12 primo volumio[3106]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 21:25:13 primo volumio[3106]: info: CoreCommandRouter::volumioNext Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::next Apr 19 21:25:13 primo volumio[3106]: info: [1745090713359] ControllerTidal::next Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::next Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::stop Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::updateTrackBlock Apr 19 21:25:13 primo volumio[3106]: info: CorePlayQueue::getTrackBlock Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:13 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:25:13 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:13 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::serviceStop Apr 19 21:25:13 primo volumio[3106]: info: CorePlayQueue::getTrack 0 Apr 19 21:25:13 primo volumio[3106]: info: CoreCommandRouter::serviceStop Apr 19 21:25:13 primo volumio[3106]: info: [1745090713385] ControllerTidal::stop Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:25:13 primo volumio[3106]: info: ControllerMpd::stop Apr 19 21:25:13 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:25:13 primo volumio[3106]: info: Apr 19 21:25:13 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:13 primo volumio[3106]: info: sendMpdCommand stop took 55 milliseconds Apr 19 21:25:13 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:13 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::play index undefined Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:25:13 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::startPlaybackTimer Apr 19 21:25:13 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:13 primo volumio[3106]: info: [1745090713450] ControllerTidal::clearAddPlayTrack Apr 19 21:25:13 primo volumio[3106]: info: Getting stream with soundQuality LOSSLESS Apr 19 21:25:13 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::updateTrackBlock Apr 19 21:25:13 primo volumio[3106]: info: CorePlayQueue::getTrackBlock Apr 19 21:25:13 primo volumio[3106]: info: sendMpdCommand status took 20 milliseconds Apr 19 21:25:13 primo volumio[3106]: info: sendMpdCommand stop took 7 milliseconds Apr 19 21:25:13 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:13 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:13 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 21:25:13 primo volumio[3106]: info: Apr 19 21:25:13 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:13 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:13 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:13 primo volumio[3106]: info: Apr 19 21:25:13 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:13 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:13 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:13 primo volumio[3106]: info: Apr 19 21:25:13 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:13 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:13 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:13 primo volumio[3106]: info: Apr 19 21:25:13 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:13 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:13 primo volumio[3106]: info: sendMpdCommand playlistinfo took 40 milliseconds Apr 19 21:25:13 primo volumio[3106]: info: sendMpdCommand clear took 39 milliseconds Apr 19 21:25:13 primo volumio[3106]: info: sendMpdCommand status took 12 milliseconds Apr 19 21:25:13 primo volumio[3106]: info: sendMpdCommand status took 10 milliseconds Apr 19 21:25:13 primo volumio[3106]: info: sendMpdCommand status took 8 milliseconds Apr 19 21:25:13 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:13 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/94554967&soundQuality=LOSSLESS" Apr 19 21:25:13 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:13 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:13 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:13 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:13 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:13 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:13 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:13 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:13 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:13 primo volumio[3106]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 19 21:25:13 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:13 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:13 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:13 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:13 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:13 primo volumio[3106]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 19 21:25:13 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:13 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:13 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:13 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:13 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:13 primo volumio[3106]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 19 21:25:13 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:13 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:13 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:13 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:13 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:13 primo volumio[3106]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 19 21:25:13 primo volumio[3106]: info: ------------------------------ 132ms Apr 19 21:25:13 primo volumio[3106]: info: ------------------------------ 79ms Apr 19 21:25:13 primo volumio[3106]: info: ------------------------------ 77ms Apr 19 21:25:13 primo volumio[3106]: info: ------------------------------ 75ms Apr 19 21:25:13 primo volumio[3106]: info: Apr 19 21:25:13 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:13 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:13 primo volumio[3106]: info: Apr 19 21:25:13 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:13 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:13 primo volumio[3106]: info: Apr 19 21:25:13 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:13 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:13 primo volumio[3106]: error: updateQueue error: null Apr 19 21:25:13 primo volumio[3106]: info: Apr 19 21:25:13 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:13 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:13 primo volumio[3106]: info: ------------------------------ 84ms Apr 19 21:25:13 primo volumio[3106]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/94554967&soundQuality=LOSSLESS" took 76 milliseconds Apr 19 21:25:13 primo volumio[3106]: info: ------------------------------ 13ms Apr 19 21:25:13 primo volumio[3106]: info: ------------------------------ 11ms Apr 19 21:25:13 primo volumio[3106]: info: ------------------------------ 10ms Apr 19 21:25:13 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:25:13 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand play Apr 19 21:25:13 primo volumio[3106]: info: Apr 19 21:25:13 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:13 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:13 primo volumio[3106]: info: Apr 19 21:25:13 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:13 primo volumio[3106]: STREAMING PROXY: Handling url /?data=tidal://song/94554967&soundQuality=LOSSLESS Apr 19 21:25:13 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:13 primo volumio[3106]: info: Apr 19 21:25:13 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:13 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:13 primo volumio[3106]: info: MCU Signalled Playback Inactive Apr 19 21:25:13 primo volumio[3106]: info: ------------------------------ 40ms Apr 19 21:25:13 primo volumio[3106]: info: sendMpdCommand play took 35 milliseconds Apr 19 21:25:13 primo volumio[3106]: info: ------------------------------ 35ms Apr 19 21:25:13 primo volumio[3106]: info: ------------------------------ 32ms Apr 19 21:25:13 primo volumio[3106]: info: ------------------------------ 31ms Apr 19 21:25:13 primo volumio[3106]: info: Executing endpoint getStreamUrltidal Apr 19 21:25:13 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 19 21:25:14 primo volumio[3106]: info: getStreamUrl took 502 milliseconds Apr 19 21:25:14 primo volumio[3106]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEicwZjZlYjA3MmJmNGNlMTI0YmZmZjdlZDdmZDQxOGY4ZV82MS5tcDQ/0.flac?Expires=1745094314&Signature=oeIb~6B3ZU0iOOWpKS3PO22qt9DaMDMZ9H5E20NwCAGedemRW2h1uShUNBTLn3KvR6LytvN5W1VWLfHBxd~OkfcvycYln3rIndVo1GewxagAw6wYe5R2cfEnRKQbvtScI3skS3qnXzjh-DrIj~SouGk5EXDwlYquG7ymf64nfZKzYTmJAmF49HRQuFSGD9zCRL1caB7jwzxTwp4Ajfzz3dw6HaeARdBZAvn3tt1ji956FX~TJT2bmTzmtB39AqX5fZfFF1JRWF6Wbj0d~phR3ptHo~vha8ECG4xgU3-FbWDYyRv9PFRxvgDECuOIw5KUz~gfAXkOcAboW9GZ-S6eCg__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 19 21:25:14 primo kernel: asoc-aml-card auge_sound: tdm playback stop Apr 19 21:25:14 primo kernel: spdif_a is set to disable Apr 19 21:25:14 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 19 21:25:14 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Apr 19 21:25:14 primo kernel: tdm playback mute: 1, lane_cnt = 8 Apr 19 21:25:14 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Apr 19 21:25:14 primo volumio[3106]: info: camilladsp spawned new process with pid 4089, instance 1, run: true Apr 19 21:25:14 primo kernel: aml_tdm_open Apr 19 21:25:14 primo kernel: Not init audio effects Apr 19 21:25:14 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Apr 19 21:25:14 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 19 21:25:14 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 19 21:25:14 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 19 21:25:14 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d2b5c18, id(1), clksel(1) Apr 19 21:25:14 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Apr 19 21:25:14 primo kernel: dump_pcm_setting(ffffffc03d2b5c18) Apr 19 21:25:14 primo kernel: pcm_mode(1) Apr 19 21:25:14 primo kernel: sysclk(11289600) Apr 19 21:25:14 primo kernel: sysclk_bclk_ratio(4) Apr 19 21:25:14 primo kernel: bclk(2822400) Apr 19 21:25:14 primo kernel: bclk_lrclk_ratio(64) Apr 19 21:25:14 primo kernel: lrclk(44100) Apr 19 21:25:14 primo kernel: tx_mask(0x3) Apr 19 21:25:14 primo kernel: rx_mask(0x3) Apr 19 21:25:14 primo kernel: slots(2) Apr 19 21:25:14 primo kernel: slot_width(32) Apr 19 21:25:14 primo kernel: lane_mask_in(0x2) Apr 19 21:25:14 primo kernel: lane_mask_out(0x1) Apr 19 21:25:14 primo kernel: lane_oe_mask_in(0x0) Apr 19 21:25:14 primo kernel: lane_oe_mask_out(0x0) Apr 19 21:25:14 primo kernel: lane_lb_mask_in(0x0) Apr 19 21:25:14 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 19 21:25:14 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 19 21:25:14 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 19 21:25:14 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Apr 19 21:25:14 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Apr 19 21:25:14 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Apr 19 21:25:14 primo kernel: aml_tdm_prepare(), reset fddr Apr 19 21:25:14 primo kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Apr 19 21:25:14 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Apr 19 21:25:14 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 19 21:25:14 primo kernel: tdm playback mute: 0, lane_cnt = 8 Apr 19 21:25:14 primo volumio[3106]: STREAMING PROXY: Response: 200, length: 14137271 Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:14 primo volumio[3106]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 19 21:25:14 primo volumio[3106]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 19 21:25:14 primo volumio[3106]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 19 21:25:14 primo volumio[3106]: info: FusionDsp - Effects disabled Apr 19 21:25:14 primo volumio[3106]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 19 21:25:14 primo volumio[3106]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 19 21:25:14 primo volumio[3106]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 19 21:25:14 primo volumio[3106]: info: FusionDsp - Effects disabled Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand status took 33 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand status took 31 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand status took 30 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand status took 28 milliseconds Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand status took 17 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand status took 11 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand status took 9 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand status took 7 milliseconds Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:14 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:14 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":138,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554967&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554967&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:14 primo volumio[3106]: verbose: CURRENT POSITION 1 Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus stop Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:14 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:14 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":138,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554967&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554967&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:14 primo volumio[3106]: verbose: CURRENT POSITION 1 Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:14 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:14 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:14 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":138,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554967&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554967&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:14 primo volumio[3106]: verbose: CURRENT POSITION 1 Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:14 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:14 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:14 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":138,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554967&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554967&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:14 primo volumio[3106]: verbose: CURRENT POSITION 1 Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:14 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 110ms Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 155ms Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 153ms Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 153ms Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand playlistinfo took 104 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand playlistinfo took 103 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand playlistinfo took 95 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand playlistinfo took 95 milliseconds Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:14 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:14 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":138,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554967&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554967&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:14 primo volumio[3106]: verbose: CURRENT POSITION 1 Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:14 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:14 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:14 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":138,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554967&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554967&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:14 primo volumio[3106]: verbose: CURRENT POSITION 1 Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:14 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:14 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:14 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":138,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554967&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554967&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:14 primo volumio[3106]: verbose: CURRENT POSITION 1 Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:14 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:14 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:14 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":138,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554967&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554967&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:14 primo volumio[3106]: verbose: CURRENT POSITION 1 Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:14 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 247ms Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 238ms Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 237ms Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 235ms Apr 19 21:25:14 primo kernel: asoc-aml-card auge_sound: tdm playback enable Apr 19 21:25:14 primo kernel: spdif_a is set to enable Apr 19 21:25:14 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:14 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:14 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:14 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:14 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:14 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:14 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:14 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:14 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:14 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:14 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:14 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:14 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:14 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioNext Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::next Apr 19 21:25:14 primo volumio[3106]: info: [1745090714852] ControllerTidal::next Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::next Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::stop Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::updateTrackBlock Apr 19 21:25:14 primo volumio[3106]: info: CorePlayQueue::getTrackBlock Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:14 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::serviceStop Apr 19 21:25:14 primo volumio[3106]: info: CorePlayQueue::getTrack 1 Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::serviceStop Apr 19 21:25:14 primo volumio[3106]: info: [1745090714865] ControllerTidal::stop Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::stop Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:25:14 primo volumio[3106]: STREAMING PROXY: Client dropped request, destroying Apr 19 21:25:14 primo volumio[3106]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 19 21:25:14 primo volumio[3106]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand stop took 32 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::play index undefined Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:25:14 primo volumio[3106]: info: CorePlayQueue::getTrack 2 Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::startPlaybackTimer Apr 19 21:25:14 primo volumio[3106]: info: CorePlayQueue::getTrack 2 Apr 19 21:25:14 primo volumio[3106]: info: [1745090714902] ControllerTidal::clearAddPlayTrack Apr 19 21:25:14 primo volumio[3106]: info: Getting stream with soundQuality LOSSLESS Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::updateTrackBlock Apr 19 21:25:14 primo volumio[3106]: info: CorePlayQueue::getTrackBlock Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand status took 14 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand stop took 10 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand status took 8 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand status took 6 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand status took 5 milliseconds Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:14 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand clear took 9 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/94554968&soundQuality=LOSSLESS" Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:14 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:14 primo volumio[3106]: info: CorePlayQueue::getTrack 2 Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:14 primo volumio[3106]: info: CorePlayQueue::getTrack 2 Apr 19 21:25:14 primo volumio[3106]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 19 21:25:14 primo volumio[3106]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 19 21:25:14 primo volumio[3106]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 19 21:25:14 primo volumio[3106]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 54ms Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 48ms Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 45ms Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 44ms Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:14 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:14 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:14 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:14 primo volumio[3106]: error: updateQueue error: null Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:14 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 41ms Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/94554968&soundQuality=LOSSLESS" took 36 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 13ms Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 11ms Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 9ms Apr 19 21:25:14 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:25:14 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand play Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:14 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:14 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:14 primo volumio[3106]: STREAMING PROXY: Handling url /?data=tidal://song/94554968&soundQuality=LOSSLESS Apr 19 21:25:14 primo volumio[3106]: info: Apr 19 21:25:14 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:14 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 21ms Apr 19 21:25:14 primo volumio[3106]: info: sendMpdCommand play took 18 milliseconds Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 16ms Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 16ms Apr 19 21:25:14 primo volumio[3106]: info: ------------------------------ 14ms Apr 19 21:25:14 primo volumio[3106]: info: MCU Signalled Playback Active Apr 19 21:25:14 primo volumio[3106]: info: MCU Signalled Playback Inactive Apr 19 21:25:14 primo volumio[3106]: info: Executing endpoint getStreamUrltidal Apr 19 21:25:14 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 19 21:25:15 primo volumio[3106]: info: getStreamUrl took 283 milliseconds Apr 19 21:25:15 primo volumio[3106]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEicxNmExYjJiMmRhNTg2NzI0NmQ5YjBhODBjNjRmNTNkM182MS5tcDQ/0.flac?Expires=1745094315&Signature=vOFOuuX-HfvlcQ1eZMQM0N2Pc3SS~sY5GG4GXA68~dWhCAZDarY0RdsPsVzHZK0sHVjb0ncPqb~KZQZEZP00BVB6fFz2nv0REqMxD2tsHbZgTTJU9YsRfJrihY30pktJ2e4R757~amdvzDqhWh6XDbAwL80UBXrpGDUBX9QbGv9TDzHifz1zYJocWf9bYds1Gjd3Aw8wzyavC27axKdpNstTsZkQKUQYpOAc4sZ-SohtmXXiIglfmrPBSsBLdbICrezKP2V5pw-zIbjEwJOy9Ojofh-6YLiNLlIlXwzxzcsMmMHkvyLbitASY9kWEz4AcD1M0XvaeaDPhMASgfIF0A__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 19 21:25:15 primo kernel: asoc-aml-card auge_sound: tdm playback stop Apr 19 21:25:15 primo kernel: spdif_a is set to disable Apr 19 21:25:15 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 19 21:25:15 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Apr 19 21:25:15 primo kernel: tdm playback mute: 1, lane_cnt = 8 Apr 19 21:25:15 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Apr 19 21:25:15 primo volumio[3106]: info: camilladsp spawned new process with pid 4105, instance 1, run: true Apr 19 21:25:15 primo kernel: aml_tdm_open Apr 19 21:25:15 primo kernel: Not init audio effects Apr 19 21:25:15 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Apr 19 21:25:15 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 19 21:25:15 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 19 21:25:15 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 19 21:25:15 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d2b5c18, id(1), clksel(1) Apr 19 21:25:15 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Apr 19 21:25:15 primo kernel: dump_pcm_setting(ffffffc03d2b5c18) Apr 19 21:25:15 primo kernel: pcm_mode(1) Apr 19 21:25:15 primo kernel: sysclk(11289600) Apr 19 21:25:15 primo kernel: sysclk_bclk_ratio(4) Apr 19 21:25:15 primo kernel: bclk(2822400) Apr 19 21:25:15 primo kernel: bclk_lrclk_ratio(64) Apr 19 21:25:15 primo kernel: lrclk(44100) Apr 19 21:25:15 primo kernel: tx_mask(0x3) Apr 19 21:25:15 primo kernel: rx_mask(0x3) Apr 19 21:25:15 primo kernel: slots(2) Apr 19 21:25:15 primo kernel: slot_width(32) Apr 19 21:25:15 primo kernel: lane_mask_in(0x2) Apr 19 21:25:15 primo kernel: lane_mask_out(0x1) Apr 19 21:25:15 primo kernel: lane_oe_mask_in(0x0) Apr 19 21:25:15 primo kernel: lane_oe_mask_out(0x0) Apr 19 21:25:15 primo kernel: lane_lb_mask_in(0x0) Apr 19 21:25:15 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 19 21:25:15 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 19 21:25:15 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 19 21:25:15 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Apr 19 21:25:15 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Apr 19 21:25:15 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Apr 19 21:25:15 primo kernel: aml_tdm_prepare(), reset fddr Apr 19 21:25:15 primo kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Apr 19 21:25:15 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Apr 19 21:25:15 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 19 21:25:15 primo kernel: tdm playback mute: 0, lane_cnt = 8 Apr 19 21:25:15 primo volumio[3106]: STREAMING PROXY: Response: 200, length: 23282387 Apr 19 21:25:15 primo volumio[3106]: info: Apr 19 21:25:15 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:15 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:15 primo volumio[3106]: info: Apr 19 21:25:15 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:15 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:15 primo volumio[3106]: info: Apr 19 21:25:15 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:15 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:15 primo volumio[3106]: info: Apr 19 21:25:15 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:15 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:15 primo volumio[3106]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 19 21:25:15 primo volumio[3106]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 19 21:25:15 primo volumio[3106]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 19 21:25:15 primo volumio[3106]: info: FusionDsp - Effects disabled Apr 19 21:25:15 primo volumio[3106]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 19 21:25:15 primo volumio[3106]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 19 21:25:15 primo volumio[3106]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 19 21:25:15 primo volumio[3106]: info: FusionDsp - Effects disabled Apr 19 21:25:15 primo volumio[3106]: info: Apr 19 21:25:15 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:15 primo volumio[3106]: info: sendMpdCommand status took 46 milliseconds Apr 19 21:25:15 primo volumio[3106]: info: sendMpdCommand status took 44 milliseconds Apr 19 21:25:15 primo volumio[3106]: info: sendMpdCommand status took 43 milliseconds Apr 19 21:25:15 primo volumio[3106]: info: sendMpdCommand status took 42 milliseconds Apr 19 21:25:15 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:15 primo volumio[3106]: info: Apr 19 21:25:15 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:15 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:15 primo volumio[3106]: info: Apr 19 21:25:15 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:15 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:15 primo volumio[3106]: info: Apr 19 21:25:15 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:15 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:15 primo volumio[3106]: info: sendMpdCommand status took 16 milliseconds Apr 19 21:25:15 primo volumio[3106]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 19 21:25:15 primo volumio[3106]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 19 21:25:15 primo volumio[3106]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 19 21:25:15 primo volumio[3106]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 19 21:25:15 primo volumio[3106]: info: sendMpdCommand status took 11 milliseconds Apr 19 21:25:15 primo volumio[3106]: info: sendMpdCommand status took 9 milliseconds Apr 19 21:25:15 primo volumio[3106]: info: sendMpdCommand status took 6 milliseconds Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:15 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:15 primo volumio[3106]: info: CorePlayQueue::getTrack 2 Apr 19 21:25:15 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554968&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554968&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:15 primo volumio[3106]: verbose: CURRENT POSITION 2 Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus stop Apr 19 21:25:15 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:15 primo volumio[3106]: info: CorePlayQueue::getTrack 2 Apr 19 21:25:15 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554968&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554968&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:15 primo volumio[3106]: verbose: CURRENT POSITION 2 Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:15 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:15 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:15 primo volumio[3106]: info: CorePlayQueue::getTrack 2 Apr 19 21:25:15 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554968&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554968&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:15 primo volumio[3106]: verbose: CURRENT POSITION 2 Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:15 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:15 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:15 primo volumio[3106]: info: CorePlayQueue::getTrack 2 Apr 19 21:25:15 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554968&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554968&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:15 primo volumio[3106]: verbose: CURRENT POSITION 2 Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:15 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:15 primo volumio[3106]: info: ------------------------------ 110ms Apr 19 21:25:15 primo volumio[3106]: info: ------------------------------ 173ms Apr 19 21:25:15 primo volumio[3106]: info: ------------------------------ 173ms Apr 19 21:25:15 primo volumio[3106]: info: ------------------------------ 172ms Apr 19 21:25:15 primo volumio[3106]: info: sendMpdCommand playlistinfo took 113 milliseconds Apr 19 21:25:15 primo volumio[3106]: info: sendMpdCommand playlistinfo took 111 milliseconds Apr 19 21:25:15 primo volumio[3106]: info: sendMpdCommand playlistinfo took 111 milliseconds Apr 19 21:25:15 primo volumio[3106]: info: sendMpdCommand playlistinfo took 111 milliseconds Apr 19 21:25:15 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:15 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:15 primo volumio[3106]: info: CorePlayQueue::getTrack 2 Apr 19 21:25:15 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554968&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554968&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:15 primo volumio[3106]: verbose: CURRENT POSITION 2 Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:15 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:15 primo kernel: asoc-aml-card auge_sound: tdm playback enable Apr 19 21:25:15 primo kernel: spdif_a is set to enable Apr 19 21:25:15 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:15 primo volumio[3106]: info: CorePlayQueue::getTrack 2 Apr 19 21:25:15 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554968&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554968&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:15 primo volumio[3106]: verbose: CURRENT POSITION 2 Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:15 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:15 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:15 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:16 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:16 primo volumio[3106]: info: CorePlayQueue::getTrack 2 Apr 19 21:25:16 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554968&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554968&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:16 primo volumio[3106]: verbose: CURRENT POSITION 2 Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:16 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:16 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:16 primo volumio[3106]: info: CorePlayQueue::getTrack 2 Apr 19 21:25:16 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554968&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554968&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:16 primo volumio[3106]: verbose: CURRENT POSITION 2 Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:16 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:16 primo volumio[3106]: info: ------------------------------ 262ms Apr 19 21:25:16 primo volumio[3106]: info: ------------------------------ 254ms Apr 19 21:25:16 primo volumio[3106]: info: ------------------------------ 252ms Apr 19 21:25:16 primo volumio[3106]: info: ------------------------------ 250ms Apr 19 21:25:16 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:16 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:16 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:16 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:16 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:16 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:16 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:16 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:16 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:16 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:16 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:16 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:16 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:16 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:16 primo volumio[3106]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 19 21:25:16 primo volumio[3106]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 19 21:25:16 primo volumio[3106]: info: MCU Signalled Playback Active Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::volumioNext Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::next Apr 19 21:25:16 primo volumio[3106]: info: [1745090716254] ControllerTidal::next Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::next Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::stop Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::updateTrackBlock Apr 19 21:25:16 primo volumio[3106]: info: CorePlayQueue::getTrackBlock Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:16 primo volumio[3106]: info: CorePlayQueue::getTrack 2 Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::serviceStop Apr 19 21:25:16 primo volumio[3106]: info: CorePlayQueue::getTrack 2 Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::serviceStop Apr 19 21:25:16 primo volumio[3106]: info: [1745090716276] ControllerTidal::stop Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:25:16 primo volumio[3106]: info: ControllerMpd::stop Apr 19 21:25:16 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:25:16 primo volumio[3106]: STREAMING PROXY: Client dropped request, destroying Apr 19 21:25:16 primo volumio[3106]: info: Apr 19 21:25:16 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:16 primo volumio[3106]: info: sendMpdCommand stop took 17 milliseconds Apr 19 21:25:16 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:16 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::play index undefined Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:25:16 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::startPlaybackTimer Apr 19 21:25:16 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:16 primo volumio[3106]: info: [1745090716297] ControllerTidal::clearAddPlayTrack Apr 19 21:25:16 primo volumio[3106]: info: Getting stream with soundQuality LOSSLESS Apr 19 21:25:16 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::updateTrackBlock Apr 19 21:25:16 primo volumio[3106]: info: CorePlayQueue::getTrackBlock Apr 19 21:25:16 primo volumio[3106]: info: sendMpdCommand status took 6 milliseconds Apr 19 21:25:16 primo volumio[3106]: info: sendMpdCommand stop took 3 milliseconds Apr 19 21:25:16 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:16 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:16 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 21:25:16 primo volumio[3106]: info: Apr 19 21:25:16 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:16 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:16 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:16 primo volumio[3106]: info: Apr 19 21:25:16 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:16 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:16 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:16 primo volumio[3106]: info: Apr 19 21:25:16 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:16 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:16 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:16 primo volumio[3106]: info: Apr 19 21:25:16 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:16 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:16 primo volumio[3106]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 19 21:25:16 primo volumio[3106]: info: sendMpdCommand clear took 14 milliseconds Apr 19 21:25:16 primo volumio[3106]: info: sendMpdCommand status took 13 milliseconds Apr 19 21:25:16 primo volumio[3106]: info: sendMpdCommand status took 10 milliseconds Apr 19 21:25:16 primo volumio[3106]: info: sendMpdCommand status took 9 milliseconds Apr 19 21:25:16 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:16 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/94554969&soundQuality=LOSSLESS" Apr 19 21:25:16 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:16 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:16 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:16 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:16 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:16 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:16 primo volumio[3106]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 19 21:25:16 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:16 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:16 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:16 primo volumio[3106]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 19 21:25:16 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:16 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:16 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:16 primo volumio[3106]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 19 21:25:16 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:16 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:16 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:16 primo volumio[3106]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 19 21:25:16 primo volumio[3106]: info: ------------------------------ 87ms Apr 19 21:25:16 primo volumio[3106]: info: ------------------------------ 77ms Apr 19 21:25:16 primo volumio[3106]: info: ------------------------------ 76ms Apr 19 21:25:16 primo volumio[3106]: info: ------------------------------ 74ms Apr 19 21:25:16 primo volumio[3106]: info: Apr 19 21:25:16 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:16 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:16 primo volumio[3106]: info: Apr 19 21:25:16 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:16 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:16 primo volumio[3106]: info: Apr 19 21:25:16 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:16 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:16 primo volumio[3106]: error: updateQueue error: null Apr 19 21:25:16 primo volumio[3106]: info: Apr 19 21:25:16 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:16 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:16 primo volumio[3106]: info: ------------------------------ 80ms Apr 19 21:25:16 primo volumio[3106]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/94554969&soundQuality=LOSSLESS" took 76 milliseconds Apr 19 21:25:16 primo volumio[3106]: info: ------------------------------ 12ms Apr 19 21:25:16 primo volumio[3106]: info: ------------------------------ 10ms Apr 19 21:25:16 primo volumio[3106]: info: ------------------------------ 10ms Apr 19 21:25:16 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:25:16 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand play Apr 19 21:25:16 primo volumio[3106]: info: Apr 19 21:25:16 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:16 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:16 primo volumio[3106]: info: Apr 19 21:25:16 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:16 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:16 primo volumio[3106]: STREAMING PROXY: Handling url /?data=tidal://song/94554969&soundQuality=LOSSLESS Apr 19 21:25:16 primo volumio[3106]: info: Apr 19 21:25:16 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:16 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:16 primo volumio[3106]: info: ------------------------------ 29ms Apr 19 21:25:16 primo volumio[3106]: info: sendMpdCommand play took 25 milliseconds Apr 19 21:25:16 primo volumio[3106]: info: ------------------------------ 25ms Apr 19 21:25:16 primo volumio[3106]: info: ------------------------------ 23ms Apr 19 21:25:16 primo volumio[3106]: info: ------------------------------ 21ms Apr 19 21:25:16 primo volumio[3106]: info: MCU Signalled Playback Inactive Apr 19 21:25:16 primo volumio[3106]: info: Executing endpoint getStreamUrltidal Apr 19 21:25:16 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 19 21:25:16 primo volumio[3106]: info: getStreamUrl took 281 milliseconds Apr 19 21:25:16 primo volumio[3106]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEicxOTgzYmUxY2I5MzA1YWE0NTUxM2QwYTU1MzNjYmVkY182MS5tcDQ/0.flac?Expires=1745094316&Signature=eOLhl2wETSPHWjaUo9GslCNHVGySa6xQwWV9LTdHhgBdSQA7~h2w6QyFmSsP0WW-8tRl7dvNvXJnMcN-JGFt-iITQPvzaR2DS-C-bGcOxNIgq7-BTyVGgZok6iO7Q9Mrsl66n4mOxJ7owAs~svuRN6daf-oQMAItT1EzvD-547K5lUroqiqDcvcW2YPGaFsNHbbWGmZLT~Fjgs2QxZc6GJ97IX3jEz0xlfySRxuWhVSll9yH~q~v2zGqEwKmXbJZoaRMkeBP3xBx1l5WX4-MLUdOzWyfP7ZYEO~gOuNUU9tLs5HObYtXwHAkz2tBUyFn3P3QA7B7bWSYoFdHFzv~WQ__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 19 21:25:17 primo kernel: asoc-aml-card auge_sound: tdm playback stop Apr 19 21:25:17 primo kernel: spdif_a is set to disable Apr 19 21:25:17 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 19 21:25:17 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Apr 19 21:25:17 primo kernel: tdm playback mute: 1, lane_cnt = 8 Apr 19 21:25:17 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Apr 19 21:25:17 primo volumio[3106]: info: camilladsp spawned new process with pid 4177, instance 1, run: true Apr 19 21:25:17 primo volumio[3106]: STREAMING PROXY: Response: 200, length: 15855430 Apr 19 21:25:17 primo volumio[3106]: info: Apr 19 21:25:17 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:17 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:17 primo volumio[3106]: info: Apr 19 21:25:17 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:17 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:17 primo volumio[3106]: info: Apr 19 21:25:17 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:17 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:17 primo volumio[3106]: info: Apr 19 21:25:17 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:17 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:17 primo volumio[3106]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 19 21:25:17 primo volumio[3106]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 19 21:25:17 primo volumio[3106]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 19 21:25:17 primo volumio[3106]: info: FusionDsp - Effects disabled Apr 19 21:25:17 primo volumio[3106]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 19 21:25:17 primo volumio[3106]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 19 21:25:17 primo volumio[3106]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 19 21:25:17 primo volumio[3106]: info: FusionDsp - Effects disabled Apr 19 21:25:17 primo volumio[3106]: info: Apr 19 21:25:17 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:17 primo volumio[3106]: info: sendMpdCommand status took 30 milliseconds Apr 19 21:25:17 primo volumio[3106]: info: sendMpdCommand status took 29 milliseconds Apr 19 21:25:17 primo volumio[3106]: info: sendMpdCommand status took 27 milliseconds Apr 19 21:25:17 primo volumio[3106]: info: sendMpdCommand status took 26 milliseconds Apr 19 21:25:17 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:17 primo volumio[3106]: info: Apr 19 21:25:17 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:17 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:17 primo kernel: aml_tdm_open Apr 19 21:25:17 primo kernel: Not init audio effects Apr 19 21:25:17 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Apr 19 21:25:17 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 19 21:25:17 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 19 21:25:17 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 19 21:25:17 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d2b5c18, id(1), clksel(1) Apr 19 21:25:17 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Apr 19 21:25:17 primo kernel: dump_pcm_setting(ffffffc03d2b5c18) Apr 19 21:25:17 primo kernel: pcm_mode(1) Apr 19 21:25:17 primo kernel: sysclk(11289600) Apr 19 21:25:17 primo kernel: sysclk_bclk_ratio(4) Apr 19 21:25:17 primo kernel: bclk(2822400) Apr 19 21:25:17 primo kernel: bclk_lrclk_ratio(64) Apr 19 21:25:17 primo kernel: lrclk(44100) Apr 19 21:25:17 primo kernel: tx_mask(0x3) Apr 19 21:25:17 primo kernel: rx_mask(0x3) Apr 19 21:25:17 primo kernel: slots(2) Apr 19 21:25:17 primo kernel: slot_width(32) Apr 19 21:25:17 primo kernel: lane_mask_in(0x2) Apr 19 21:25:17 primo kernel: lane_mask_out(0x1) Apr 19 21:25:17 primo kernel: lane_oe_mask_in(0x0) Apr 19 21:25:17 primo kernel: lane_oe_mask_out(0x0) Apr 19 21:25:17 primo kernel: lane_lb_mask_in(0x0) Apr 19 21:25:17 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 19 21:25:17 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 19 21:25:17 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 19 21:25:17 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Apr 19 21:25:17 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Apr 19 21:25:17 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Apr 19 21:25:17 primo kernel: aml_tdm_prepare(), reset fddr Apr 19 21:25:17 primo kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Apr 19 21:25:17 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Apr 19 21:25:17 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 19 21:25:17 primo kernel: tdm playback mute: 0, lane_cnt = 8 Apr 19 21:25:17 primo volumio[3106]: info: Apr 19 21:25:17 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:17 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:17 primo volumio[3106]: info: Apr 19 21:25:17 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:17 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:17 primo volumio[3106]: info: sendMpdCommand status took 18 milliseconds Apr 19 21:25:17 primo volumio[3106]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 19 21:25:17 primo volumio[3106]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 19 21:25:17 primo volumio[3106]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 19 21:25:17 primo volumio[3106]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 19 21:25:17 primo volumio[3106]: info: sendMpdCommand status took 15 milliseconds Apr 19 21:25:17 primo volumio[3106]: info: sendMpdCommand status took 14 milliseconds Apr 19 21:25:17 primo volumio[3106]: info: sendMpdCommand status took 10 milliseconds Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:17 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:17 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:17 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554969&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554969&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:17 primo volumio[3106]: verbose: CURRENT POSITION 3 Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus stop Apr 19 21:25:17 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:17 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:17 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554969&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554969&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:17 primo volumio[3106]: verbose: CURRENT POSITION 3 Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:17 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:17 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:17 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:17 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554969&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554969&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:17 primo volumio[3106]: verbose: CURRENT POSITION 3 Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:17 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:17 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:17 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:17 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554969&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554969&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:17 primo volumio[3106]: verbose: CURRENT POSITION 3 Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:17 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:17 primo volumio[3106]: info: ------------------------------ 93ms Apr 19 21:25:17 primo volumio[3106]: info: ------------------------------ 138ms Apr 19 21:25:17 primo volumio[3106]: info: ------------------------------ 138ms Apr 19 21:25:17 primo volumio[3106]: info: ------------------------------ 137ms Apr 19 21:25:17 primo volumio[3106]: info: sendMpdCommand playlistinfo took 89 milliseconds Apr 19 21:25:17 primo volumio[3106]: info: sendMpdCommand playlistinfo took 87 milliseconds Apr 19 21:25:17 primo volumio[3106]: info: sendMpdCommand playlistinfo took 86 milliseconds Apr 19 21:25:17 primo volumio[3106]: info: sendMpdCommand playlistinfo took 86 milliseconds Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:17 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:17 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:17 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:17 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554969&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554969&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:17 primo volumio[3106]: verbose: CURRENT POSITION 3 Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:17 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:17 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:17 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:17 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554969&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554969&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:17 primo volumio[3106]: verbose: CURRENT POSITION 3 Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:17 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:17 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:17 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:17 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554969&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554969&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:17 primo volumio[3106]: verbose: CURRENT POSITION 3 Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:17 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:17 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:17 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:17 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554969&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554969&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:17 primo volumio[3106]: verbose: CURRENT POSITION 3 Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:17 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:17 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:17 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:17 primo kernel: asoc-aml-card auge_sound: tdm playback enable Apr 19 21:25:17 primo kernel: spdif_a is set to enable Apr 19 21:25:17 primo volumio[3106]: info: ------------------------------ 221ms Apr 19 21:25:17 primo volumio[3106]: info: ------------------------------ 213ms Apr 19 21:25:17 primo volumio[3106]: info: ------------------------------ 212ms Apr 19 21:25:17 primo volumio[3106]: info: ------------------------------ 211ms Apr 19 21:25:17 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:17 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:17 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:17 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:17 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:17 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:17 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:17 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:17 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:17 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:17 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:17 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:17 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:17 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:17 primo volumio[3106]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 19 21:25:17 primo volumio[3106]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 19 21:25:17 primo volumio[3106]: info: MCU Signalled Playback Active Apr 19 21:25:19 primo volumio[3106]: STREAMING PROXY: Client dropped request, destroying Apr 19 21:25:20 primo volumio[3106]: info: CoreCommandRouter::volumioNext Apr 19 21:25:20 primo volumio[3106]: info: CoreStateMachine::next Apr 19 21:25:20 primo volumio[3106]: info: [1745090720174] ControllerTidal::next Apr 19 21:25:20 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:25:20 primo volumio[3106]: info: CoreStateMachine::next Apr 19 21:25:20 primo volumio[3106]: info: CoreStateMachine::stop Apr 19 21:25:20 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:25:20 primo volumio[3106]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:25:20 primo volumio[3106]: info: CoreStateMachine::updateTrackBlock Apr 19 21:25:20 primo volumio[3106]: info: CorePlayQueue::getTrackBlock Apr 19 21:25:20 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:20 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:20 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:20 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:20 primo volumio[3106]: info: CoreStateMachine::serviceStop Apr 19 21:25:20 primo volumio[3106]: info: CorePlayQueue::getTrack 3 Apr 19 21:25:20 primo volumio[3106]: info: CoreCommandRouter::serviceStop Apr 19 21:25:20 primo volumio[3106]: info: [1745090720187] ControllerTidal::stop Apr 19 21:25:20 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:25:20 primo volumio[3106]: info: ControllerMpd::stop Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:25:20 primo volumio[3106]: info: Apr 19 21:25:20 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:20 primo volumio[3106]: info: sendMpdCommand stop took 25 milliseconds Apr 19 21:25:20 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:20 primo volumio[3106]: info: CoreStateMachine::play index undefined Apr 19 21:25:20 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:25:20 primo volumio[3106]: info: CorePlayQueue::getTrack 4 Apr 19 21:25:20 primo volumio[3106]: info: CoreStateMachine::startPlaybackTimer Apr 19 21:25:20 primo volumio[3106]: info: CorePlayQueue::getTrack 4 Apr 19 21:25:20 primo volumio[3106]: info: [1745090720217] ControllerTidal::clearAddPlayTrack Apr 19 21:25:20 primo volumio[3106]: info: Getting stream with soundQuality LOSSLESS Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:25:20 primo volumio[3106]: info: CoreStateMachine::updateTrackBlock Apr 19 21:25:20 primo volumio[3106]: info: CorePlayQueue::getTrackBlock Apr 19 21:25:20 primo volumio[3106]: info: Apr 19 21:25:20 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:20 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:20 primo volumio[3106]: info: Apr 19 21:25:20 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:20 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:20 primo volumio[3106]: info: Apr 19 21:25:20 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:20 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:20 primo volumio[3106]: info: sendMpdCommand status took 12 milliseconds Apr 19 21:25:20 primo volumio[3106]: info: sendMpdCommand stop took 9 milliseconds Apr 19 21:25:20 primo volumio[3106]: info: sendMpdCommand status took 7 milliseconds Apr 19 21:25:20 primo volumio[3106]: info: sendMpdCommand status took 6 milliseconds Apr 19 21:25:20 primo volumio[3106]: info: sendMpdCommand status took 4 milliseconds Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:20 primo volumio[3106]: info: Apr 19 21:25:20 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:20 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:20 primo volumio[3106]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 19 21:25:20 primo volumio[3106]: info: sendMpdCommand clear took 10 milliseconds Apr 19 21:25:20 primo volumio[3106]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 19 21:25:20 primo volumio[3106]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 19 21:25:20 primo volumio[3106]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/94554970&soundQuality=LOSSLESS" Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:20 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:20 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:20 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:20 primo volumio[3106]: info: CorePlayQueue::getTrack 4 Apr 19 21:25:20 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:20 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:20 primo volumio[3106]: info: CorePlayQueue::getTrack 4 Apr 19 21:25:20 primo volumio[3106]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 19 21:25:20 primo volumio[3106]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 19 21:25:20 primo volumio[3106]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 19 21:25:20 primo volumio[3106]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 19 21:25:20 primo volumio[3106]: info: ------------------------------ 49ms Apr 19 21:25:20 primo volumio[3106]: info: ------------------------------ 43ms Apr 19 21:25:20 primo volumio[3106]: info: ------------------------------ 42ms Apr 19 21:25:20 primo volumio[3106]: info: ------------------------------ 40ms Apr 19 21:25:20 primo volumio[3106]: info: Apr 19 21:25:20 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:20 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:20 primo volumio[3106]: info: Apr 19 21:25:20 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:20 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:20 primo volumio[3106]: info: Apr 19 21:25:20 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:20 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:20 primo volumio[3106]: error: updateQueue error: null Apr 19 21:25:20 primo volumio[3106]: info: Apr 19 21:25:20 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:20 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:20 primo volumio[3106]: info: ------------------------------ 38ms Apr 19 21:25:20 primo volumio[3106]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/94554970&soundQuality=LOSSLESS" took 35 milliseconds Apr 19 21:25:20 primo volumio[3106]: info: ------------------------------ 13ms Apr 19 21:25:20 primo volumio[3106]: info: ------------------------------ 11ms Apr 19 21:25:20 primo volumio[3106]: info: ------------------------------ 11ms Apr 19 21:25:20 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:25:20 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand play Apr 19 21:25:20 primo volumio[3106]: info: Apr 19 21:25:20 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:20 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:20 primo volumio[3106]: info: Apr 19 21:25:20 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:20 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:20 primo volumio[3106]: STREAMING PROXY: Handling url /?data=tidal://song/94554970&soundQuality=LOSSLESS Apr 19 21:25:20 primo volumio[3106]: info: Apr 19 21:25:20 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:20 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:20 primo volumio[3106]: info: ------------------------------ 20ms Apr 19 21:25:20 primo volumio[3106]: info: sendMpdCommand play took 15 milliseconds Apr 19 21:25:20 primo volumio[3106]: info: ------------------------------ 15ms Apr 19 21:25:20 primo volumio[3106]: info: ------------------------------ 13ms Apr 19 21:25:20 primo volumio[3106]: info: ------------------------------ 11ms Apr 19 21:25:20 primo volumio[3106]: info: MCU Signalled Playback Inactive Apr 19 21:25:20 primo volumio[3106]: info: Executing endpoint getStreamUrltidal Apr 19 21:25:20 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 19 21:25:20 primo volumio[3106]: info: getStreamUrl took 391 milliseconds Apr 19 21:25:20 primo volumio[3106]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEicxZWEwYmM2NTViMGQyMzg0MjU2MWFkNTA0ZjUwNDFmYV82MS5tcDQ/0.flac?Expires=1745094320&Signature=aMnb8hfSSMO5QVmc6o1qq54s3dvm7G~Zf02~oiL6nkA2HNCedd1OWd-VS~v4EI8zU31nnxaTDfJho7OnS3yeLnHgZv1sUeK0a~eZM4RA-DRduo7QaVjE0i~t6tOK53cN21KihYR0zXB9s6UV0SVyuKfpwNLXFk78b6s38G1ViS2gaXmyI2uhFFKKKa7-6mjT9~gThrVkcPP7gOR7MIaReU3tqhP0gCmgmHW-v~yLwFpet1MQV9e~j97Pt3am522Reocpp-EqwLOjdU8DgweFXEjo~0Tm18TI4X59NZxlP8Yjc4XaJMjhUFnnlNILzm0S0V24uXFSgOqPfIfU-lgHfg__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 19 21:25:20 primo volumio[3106]: STREAMING PROXY: Response: 200, length: 21317998 Apr 19 21:25:21 primo volumio[3106]: info: Apr 19 21:25:21 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:21 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:21 primo volumio[3106]: info: Apr 19 21:25:21 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:21 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:21 primo volumio[3106]: info: Apr 19 21:25:21 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:21 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:21 primo volumio[3106]: info: Apr 19 21:25:21 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:21 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:21 primo volumio[3106]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 19 21:25:21 primo volumio[3106]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 19 21:25:21 primo volumio[3106]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 19 21:25:21 primo volumio[3106]: info: FusionDsp - Effects disabled Apr 19 21:25:21 primo volumio[3106]: info: Apr 19 21:25:21 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:21 primo volumio[3106]: info: sendMpdCommand status took 27 milliseconds Apr 19 21:25:21 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:21 primo volumio[3106]: info: sendMpdCommand status took 26 milliseconds Apr 19 21:25:21 primo volumio[3106]: info: sendMpdCommand status took 25 milliseconds Apr 19 21:25:21 primo volumio[3106]: info: sendMpdCommand status took 24 milliseconds Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:21 primo volumio[3106]: info: Apr 19 21:25:21 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:21 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:21 primo volumio[3106]: info: Apr 19 21:25:21 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:21 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:21 primo volumio[3106]: info: Apr 19 21:25:21 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:21 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:21 primo kernel: asoc-aml-card auge_sound: tdm playback stop Apr 19 21:25:21 primo kernel: spdif_a is set to disable Apr 19 21:25:21 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 19 21:25:21 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Apr 19 21:25:21 primo kernel: tdm playback mute: 1, lane_cnt = 8 Apr 19 21:25:21 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Apr 19 21:25:21 primo volumio[3106]: info: sendMpdCommand status took 14 milliseconds Apr 19 21:25:21 primo volumio[3106]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 19 21:25:21 primo volumio[3106]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 19 21:25:21 primo volumio[3106]: info: sendMpdCommand status took 8 milliseconds Apr 19 21:25:21 primo volumio[3106]: info: sendMpdCommand status took 7 milliseconds Apr 19 21:25:21 primo volumio[3106]: info: sendMpdCommand status took 5 milliseconds Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:21 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:21 primo volumio[3106]: info: CorePlayQueue::getTrack 4 Apr 19 21:25:21 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554970&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554970&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:21 primo volumio[3106]: verbose: CURRENT POSITION 4 Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus stop Apr 19 21:25:21 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:21 primo volumio[3106]: info: CorePlayQueue::getTrack 4 Apr 19 21:25:21 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554970&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554970&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:21 primo volumio[3106]: verbose: CURRENT POSITION 4 Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:21 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:21 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:21 primo volumio[3106]: info: CorePlayQueue::getTrack 4 Apr 19 21:25:21 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554970&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554970&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:21 primo volumio[3106]: verbose: CURRENT POSITION 4 Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:21 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:21 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:21 primo volumio[3106]: info: CorePlayQueue::getTrack 4 Apr 19 21:25:21 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554970&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554970&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:21 primo volumio[3106]: verbose: CURRENT POSITION 4 Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:21 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:21 primo volumio[3106]: info: ------------------------------ 78ms Apr 19 21:25:21 primo volumio[3106]: info: ------------------------------ 125ms Apr 19 21:25:21 primo volumio[3106]: info: ------------------------------ 124ms Apr 19 21:25:21 primo volumio[3106]: info: ------------------------------ 124ms Apr 19 21:25:21 primo volumio[3106]: error: FusionDsp - WebSocket error: [object Object] Apr 19 21:25:21 primo volumio[3106]: info: sendMpdCommand playlistinfo took 90 milliseconds Apr 19 21:25:21 primo volumio[3106]: info: sendMpdCommand playlistinfo took 88 milliseconds Apr 19 21:25:21 primo volumio[3106]: info: sendMpdCommand playlistinfo took 87 milliseconds Apr 19 21:25:21 primo volumio[3106]: info: sendMpdCommand playlistinfo took 88 milliseconds Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:21 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:21 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:21 primo volumio[3106]: info: CorePlayQueue::getTrack 4 Apr 19 21:25:21 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554970&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554970&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:21 primo volumio[3106]: verbose: CURRENT POSITION 4 Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:21 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:21 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:21 primo volumio[3106]: info: CorePlayQueue::getTrack 4 Apr 19 21:25:21 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554970&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554970&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:21 primo volumio[3106]: verbose: CURRENT POSITION 4 Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:21 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:21 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:21 primo volumio[3106]: info: CorePlayQueue::getTrack 4 Apr 19 21:25:21 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554970&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554970&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:21 primo volumio[3106]: verbose: CURRENT POSITION 4 Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:21 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:21 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:21 primo volumio[3106]: info: CorePlayQueue::getTrack 4 Apr 19 21:25:21 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554970&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554970&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:21 primo volumio[3106]: verbose: CURRENT POSITION 4 Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:21 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:21 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:21 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:21 primo volumio[3106]: info: ------------------------------ 212ms Apr 19 21:25:21 primo volumio[3106]: info: ------------------------------ 205ms Apr 19 21:25:21 primo volumio[3106]: info: ------------------------------ 204ms Apr 19 21:25:21 primo volumio[3106]: info: ------------------------------ 203ms Apr 19 21:25:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:21 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:21 primo volumio[3106]: info: camilladsp spawned new process with pid 4201, instance 1, run: true Apr 19 21:25:21 primo volumio[3106]: info: MCU Signalled Playback Active Apr 19 21:25:21 primo kernel: aml_tdm_open Apr 19 21:25:21 primo kernel: Not init audio effects Apr 19 21:25:21 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Apr 19 21:25:21 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 19 21:25:21 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 19 21:25:21 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 19 21:25:21 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d2b5c18, id(1), clksel(1) Apr 19 21:25:21 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Apr 19 21:25:21 primo kernel: dump_pcm_setting(ffffffc03d2b5c18) Apr 19 21:25:21 primo kernel: pcm_mode(1) Apr 19 21:25:21 primo kernel: sysclk(11289600) Apr 19 21:25:21 primo kernel: sysclk_bclk_ratio(4) Apr 19 21:25:21 primo kernel: bclk(2822400) Apr 19 21:25:21 primo kernel: bclk_lrclk_ratio(64) Apr 19 21:25:21 primo kernel: lrclk(44100) Apr 19 21:25:21 primo kernel: tx_mask(0x3) Apr 19 21:25:21 primo kernel: rx_mask(0x3) Apr 19 21:25:21 primo kernel: slots(2) Apr 19 21:25:21 primo kernel: slot_width(32) Apr 19 21:25:21 primo kernel: lane_mask_in(0x2) Apr 19 21:25:21 primo kernel: lane_mask_out(0x1) Apr 19 21:25:21 primo kernel: lane_oe_mask_in(0x0) Apr 19 21:25:21 primo kernel: lane_oe_mask_out(0x0) Apr 19 21:25:21 primo kernel: lane_lb_mask_in(0x0) Apr 19 21:25:21 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 19 21:25:21 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 19 21:25:21 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 19 21:25:21 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Apr 19 21:25:21 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Apr 19 21:25:21 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Apr 19 21:25:21 primo kernel: aml_tdm_prepare(), reset fddr Apr 19 21:25:21 primo kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Apr 19 21:25:21 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Apr 19 21:25:21 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 19 21:25:21 primo kernel: tdm playback mute: 0, lane_cnt = 8 Apr 19 21:25:21 primo kernel: asoc-aml-card auge_sound: tdm playback enable Apr 19 21:25:21 primo kernel: spdif_a is set to enable Apr 19 21:25:23 primo volumio[3106]: STREAMING PROXY: Client dropped request, destroying Apr 19 21:25:23 primo volumio[3106]: info: CoreCommandRouter::volumioNext Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::next Apr 19 21:25:23 primo volumio[3106]: info: [1745090723818] ControllerTidal::next Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::next Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::stop Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::updateTrackBlock Apr 19 21:25:23 primo volumio[3106]: info: CorePlayQueue::getTrackBlock Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:23 primo volumio[3106]: info: CorePlayQueue::getTrack 4 Apr 19 21:25:23 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:23 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::serviceStop Apr 19 21:25:23 primo volumio[3106]: info: CorePlayQueue::getTrack 4 Apr 19 21:25:23 primo volumio[3106]: info: CoreCommandRouter::serviceStop Apr 19 21:25:23 primo volumio[3106]: info: [1745090723838] ControllerTidal::stop Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:25:23 primo volumio[3106]: info: ControllerMpd::stop Apr 19 21:25:23 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:25:23 primo volumio[3106]: info: Apr 19 21:25:23 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:23 primo volumio[3106]: info: sendMpdCommand stop took 28 milliseconds Apr 19 21:25:23 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:23 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::play index undefined Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:25:23 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::startPlaybackTimer Apr 19 21:25:23 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:23 primo volumio[3106]: info: [1745090723872] ControllerTidal::clearAddPlayTrack Apr 19 21:25:23 primo volumio[3106]: info: Getting stream with soundQuality LOSSLESS Apr 19 21:25:23 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::updateTrackBlock Apr 19 21:25:23 primo volumio[3106]: info: CorePlayQueue::getTrackBlock Apr 19 21:25:23 primo volumio[3106]: info: sendMpdCommand status took 6 milliseconds Apr 19 21:25:23 primo volumio[3106]: info: sendMpdCommand stop took 2 milliseconds Apr 19 21:25:23 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:23 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:23 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 21:25:23 primo volumio[3106]: info: Apr 19 21:25:23 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:23 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:23 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:23 primo volumio[3106]: info: Apr 19 21:25:23 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:23 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:23 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:23 primo volumio[3106]: info: Apr 19 21:25:23 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:23 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:23 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:23 primo volumio[3106]: info: Apr 19 21:25:23 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:23 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:23 primo volumio[3106]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 19 21:25:23 primo volumio[3106]: info: sendMpdCommand clear took 14 milliseconds Apr 19 21:25:23 primo volumio[3106]: info: sendMpdCommand status took 12 milliseconds Apr 19 21:25:23 primo volumio[3106]: info: sendMpdCommand status took 10 milliseconds Apr 19 21:25:23 primo volumio[3106]: info: sendMpdCommand status took 9 milliseconds Apr 19 21:25:23 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:23 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/94554971&soundQuality=LOSSLESS" Apr 19 21:25:23 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:23 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:23 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:23 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:23 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:23 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:23 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:23 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:23 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:23 primo volumio[3106]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 19 21:25:23 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:23 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:23 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:23 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:23 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:23 primo volumio[3106]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 19 21:25:23 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:23 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:23 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:23 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:23 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:23 primo volumio[3106]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 19 21:25:23 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:23 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:23 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:23 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:23 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:23 primo volumio[3106]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 19 21:25:23 primo volumio[3106]: info: ------------------------------ 81ms Apr 19 21:25:23 primo volumio[3106]: info: ------------------------------ 71ms Apr 19 21:25:23 primo volumio[3106]: info: ------------------------------ 70ms Apr 19 21:25:23 primo volumio[3106]: info: ------------------------------ 69ms Apr 19 21:25:23 primo volumio[3106]: info: Apr 19 21:25:23 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:23 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:23 primo volumio[3106]: info: Apr 19 21:25:23 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:23 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:23 primo volumio[3106]: info: Apr 19 21:25:23 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:23 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:23 primo volumio[3106]: error: updateQueue error: null Apr 19 21:25:23 primo volumio[3106]: info: Apr 19 21:25:23 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:23 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:23 primo volumio[3106]: info: ------------------------------ 74ms Apr 19 21:25:23 primo volumio[3106]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/94554971&soundQuality=LOSSLESS" took 70 milliseconds Apr 19 21:25:23 primo volumio[3106]: info: ------------------------------ 11ms Apr 19 21:25:23 primo volumio[3106]: info: ------------------------------ 10ms Apr 19 21:25:23 primo volumio[3106]: info: ------------------------------ 9ms Apr 19 21:25:23 primo volumio[3106]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:25:23 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand play Apr 19 21:25:23 primo volumio[3106]: info: Apr 19 21:25:23 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:23 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:23 primo volumio[3106]: info: Apr 19 21:25:23 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:23 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:23 primo volumio[3106]: STREAMING PROXY: Handling url /?data=tidal://song/94554971&soundQuality=LOSSLESS Apr 19 21:25:23 primo volumio[3106]: info: Apr 19 21:25:23 primo volumio[3106]: ---------------------------- MPD announces system playlist update Apr 19 21:25:23 primo volumio[3106]: info: Ignoring MPD Status Update Apr 19 21:25:23 primo volumio[3106]: info: ------------------------------ 27ms Apr 19 21:25:23 primo volumio[3106]: info: sendMpdCommand play took 25 milliseconds Apr 19 21:25:23 primo volumio[3106]: info: ------------------------------ 24ms Apr 19 21:25:23 primo volumio[3106]: info: ------------------------------ 23ms Apr 19 21:25:23 primo volumio[3106]: info: ------------------------------ 21ms Apr 19 21:25:23 primo volumio[3106]: info: MCU Signalled Playback Inactive Apr 19 21:25:23 primo volumio[3106]: info: Executing endpoint getStreamUrltidal Apr 19 21:25:23 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 19 21:25:24 primo volumio[3106]: info: getStreamUrl took 666 milliseconds Apr 19 21:25:24 primo volumio[3106]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic5ZTg2MGE1ZjFhNTJiM2MzZTIyMjZjM2FkMTg5ZjA1NV82MS5tcDQ/0.flac?Expires=1745094324&Signature=XjcD9-JOEvCGK1XGe8kqvxzU3eSH2VXp47l0acRCa0gq7VkOKY3AoY5xSQ7AIdI0OzQwi8IvC2eb-YBVhWQgZ26uxUoX-qDVU-EkQOXZwvwC6fT0WNKivy7UhUBkMHCzMW0NXd2X0UB-yKxR5GBhacEv-hvD2Z1ciy5yfFWiXvP8DQgzyTbJfu~fzwDBI9OP4A4TkGYPM1XJo6EIS3Zl-3hrwdxbQKjjJRFiJD0rThAa4LIO9wH~KjvR2ZNvBSVjDVBZ3T3LAdjJiGuNILfqglXX8xRXPErAolxCc~2IzBKpXe1ah6AGjgWQekoU-pMX-ci1tCl3JcI6cdptmSuilQ__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 19 21:25:24 primo kernel: asoc-aml-card auge_sound: tdm playback stop Apr 19 21:25:24 primo kernel: spdif_a is set to disable Apr 19 21:25:24 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 19 21:25:24 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Apr 19 21:25:24 primo kernel: tdm playback mute: 1, lane_cnt = 8 Apr 19 21:25:24 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Apr 19 21:25:24 primo volumio[3106]: info: camilladsp spawned new process with pid 4223, instance 1, run: true Apr 19 21:25:24 primo kernel: aml_tdm_open Apr 19 21:25:24 primo kernel: Not init audio effects Apr 19 21:25:24 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Apr 19 21:25:24 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 19 21:25:24 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 19 21:25:24 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 19 21:25:24 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d2b5c18, id(1), clksel(1) Apr 19 21:25:24 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Apr 19 21:25:24 primo kernel: dump_pcm_setting(ffffffc03d2b5c18) Apr 19 21:25:24 primo kernel: pcm_mode(1) Apr 19 21:25:24 primo kernel: sysclk(11289600) Apr 19 21:25:24 primo kernel: sysclk_bclk_ratio(4) Apr 19 21:25:24 primo kernel: bclk(2822400) Apr 19 21:25:24 primo kernel: bclk_lrclk_ratio(64) Apr 19 21:25:24 primo kernel: lrclk(44100) Apr 19 21:25:24 primo kernel: tx_mask(0x3) Apr 19 21:25:24 primo kernel: rx_mask(0x3) Apr 19 21:25:24 primo kernel: slots(2) Apr 19 21:25:24 primo kernel: slot_width(32) Apr 19 21:25:24 primo kernel: lane_mask_in(0x2) Apr 19 21:25:24 primo kernel: lane_mask_out(0x1) Apr 19 21:25:24 primo kernel: lane_oe_mask_in(0x0) Apr 19 21:25:24 primo kernel: lane_oe_mask_out(0x0) Apr 19 21:25:24 primo kernel: lane_lb_mask_in(0x0) Apr 19 21:25:24 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 19 21:25:24 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 19 21:25:24 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 19 21:25:24 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Apr 19 21:25:24 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Apr 19 21:25:24 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Apr 19 21:25:24 primo kernel: aml_tdm_prepare(), reset fddr Apr 19 21:25:24 primo kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Apr 19 21:25:24 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Apr 19 21:25:24 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 19 21:25:24 primo kernel: tdm playback mute: 0, lane_cnt = 8 Apr 19 21:25:24 primo volumio[3106]: STREAMING PROXY: Response: 200, length: 25172303 Apr 19 21:25:24 primo volumio[3106]: info: Apr 19 21:25:24 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:24 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:24 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:24 primo volumio[3106]: info: Apr 19 21:25:24 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:24 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:24 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:24 primo volumio[3106]: info: Apr 19 21:25:24 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:24 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:24 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:24 primo volumio[3106]: info: Apr 19 21:25:24 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:24 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:24 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:24 primo volumio[3106]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 19 21:25:24 primo volumio[3106]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 19 21:25:24 primo volumio[3106]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 19 21:25:24 primo volumio[3106]: info: FusionDsp - Effects disabled Apr 19 21:25:24 primo volumio[3106]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 19 21:25:24 primo volumio[3106]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 19 21:25:24 primo volumio[3106]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 19 21:25:24 primo volumio[3106]: info: FusionDsp - Effects disabled Apr 19 21:25:25 primo volumio[3106]: info: Apr 19 21:25:25 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:25 primo volumio[3106]: info: sendMpdCommand status took 28 milliseconds Apr 19 21:25:25 primo volumio[3106]: info: sendMpdCommand status took 28 milliseconds Apr 19 21:25:25 primo volumio[3106]: info: sendMpdCommand status took 23 milliseconds Apr 19 21:25:25 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:25 primo volumio[3106]: info: sendMpdCommand status took 22 milliseconds Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:25 primo volumio[3106]: info: Apr 19 21:25:25 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:25 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:25 primo volumio[3106]: info: Apr 19 21:25:25 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:25 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:25 primo volumio[3106]: info: Apr 19 21:25:25 primo volumio[3106]: ---------------------------- MPD announces state update: player Apr 19 21:25:25 primo volumio[3106]: info: ControllerMpd::getState Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:25:25 primo volumio[3106]: info: sendMpdCommand status took 13 milliseconds Apr 19 21:25:25 primo volumio[3106]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 19 21:25:25 primo volumio[3106]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 19 21:25:25 primo volumio[3106]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 19 21:25:25 primo volumio[3106]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 19 21:25:25 primo volumio[3106]: info: sendMpdCommand status took 9 milliseconds Apr 19 21:25:25 primo volumio[3106]: info: sendMpdCommand status took 7 milliseconds Apr 19 21:25:25 primo volumio[3106]: info: sendMpdCommand status took 6 milliseconds Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::parseState Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:25:25 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:25 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:25 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554971&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554971&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:25 primo volumio[3106]: verbose: CURRENT POSITION 5 Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus stop Apr 19 21:25:25 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:25 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:25 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554971&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554971&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:25 primo volumio[3106]: verbose: CURRENT POSITION 5 Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:25 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:25 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:25 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:25 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554971&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554971&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:25 primo volumio[3106]: verbose: CURRENT POSITION 5 Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:25 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:25 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:25 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:25 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554971&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554971&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:25 primo volumio[3106]: verbose: CURRENT POSITION 5 Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:25 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:25 primo volumio[3106]: info: ------------------------------ 79ms Apr 19 21:25:25 primo volumio[3106]: info: ------------------------------ 126ms Apr 19 21:25:25 primo volumio[3106]: info: ------------------------------ 126ms Apr 19 21:25:25 primo volumio[3106]: info: ------------------------------ 122ms Apr 19 21:25:25 primo volumio[3106]: info: sendMpdCommand playlistinfo took 88 milliseconds Apr 19 21:25:25 primo volumio[3106]: info: sendMpdCommand playlistinfo took 86 milliseconds Apr 19 21:25:25 primo volumio[3106]: info: sendMpdCommand playlistinfo took 86 milliseconds Apr 19 21:25:25 primo volumio[3106]: info: sendMpdCommand playlistinfo took 86 milliseconds Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:25 primo volumio[3106]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:25:25 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:25 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:25 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554971&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554971&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:25 primo volumio[3106]: verbose: CURRENT POSITION 5 Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:25 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:25 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:25 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:25 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554971&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554971&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:25 primo volumio[3106]: verbose: CURRENT POSITION 5 Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:25 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:25 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:25 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:25 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554971&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554971&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:25 primo volumio[3106]: verbose: CURRENT POSITION 5 Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:25 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:25 primo volumio[3106]: info: ControllerMpd::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::servicePushState Apr 19 21:25:25 primo volumio[3106]: info: CorePlayQueue::getTrack 5 Apr 19 21:25:25 primo volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94554971&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/94554971&soundQuality=LOSSLESS","trackType":"tidal"} Apr 19 21:25:25 primo volumio[3106]: verbose: CURRENT POSITION 5 Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::syncState stateService play Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:25:25 primo volumio[3106]: info: Received an update from plugin. extracting info from payload Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:25 primo volumio[3106]: info: CoreStateMachine::pushState Apr 19 21:25:25 primo volumio[3106]: info: CoreCommandRouter::volumioPushState Apr 19 21:25:25 primo volumio[3106]: info: ------------------------------ 218ms Apr 19 21:25:25 primo volumio[3106]: info: ------------------------------ 211ms Apr 19 21:25:25 primo volumio[3106]: info: ------------------------------ 210ms Apr 19 21:25:25 primo kernel: asoc-aml-card auge_sound: tdm playback enable Apr 19 21:25:25 primo kernel: spdif_a is set to enable Apr 19 21:25:25 primo volumio[3106]: info: ------------------------------ 210ms Apr 19 21:25:25 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:25 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:25 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:25 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:25 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:25 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:25 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:25 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:25 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:25 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:25 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:25 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:25 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:25 primo volumio[3106]: info: Signalling Playback active due to playback status change Apr 19 21:25:25 primo volumio[3106]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 19 21:25:25 primo volumio[3106]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 19 21:25:25 primo volumio[3106]: info: MCU Signalled Playback Active Apr 19 21:25:27 primo volumio[3106]: STREAMING PROXY: Client dropped request, destroying Apr 19 21:25:42 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 21:25:42 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 21:25:42 primo volumio[3106]: info: Discovery: Getting this device information Apr 19 21:25:42 primo volumio[3106]: info: CoreCommandRouter::volumioGetState Apr 19 21:25:42 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 21:25:42 primo volumio[3106]: verbose: New Socket.io Connection to 192.168.178.220:3000 from 192.168.178.206 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Apr 19 21:25:42 primo volumio[3106]: info: CoreCommandRouter::volumioGetState Apr 19 21:25:42 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 19 21:25:42 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 19 21:25:43 primo sudo[4314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 21:25:43 primo sudo[4314]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:25:43 primo sudo[4314]: pam_unix(sudo:session): session closed for user root Apr 19 21:25:43 primo sudo[4316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 21:25:43 primo sudo[4316]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:25:43 primo sudo[4316]: pam_unix(sudo:session): session closed for user root Apr 19 21:25:44 primo volumio[3106]: verbose: New Socket.io Connection to 192.168.178.220 from 192.168.178.206 UA: Mozilla/5.0 (Linux; Android 14; SM-S906B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.99 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 19 21:25:44 primo sudo[4322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 21:25:44 primo sudo[4322]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:25:44 primo sudo[4322]: pam_unix(sudo:session): session closed for user root Apr 19 21:25:44 primo sudo[4324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 21:25:44 primo sudo[4324]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:25:44 primo sudo[4324]: pam_unix(sudo:session): session closed for user root Apr 19 21:25:44 primo volumio[3106]: verbose: New Socket.io Connection to 192.168.178.220 from 192.168.178.206 UA: Mozilla/5.0 (Linux; Android 14; SM-S906B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.99 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Apr 19 21:25:44 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 21:25:44 primo volumio[3106]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 21:25:44 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 21:25:44 primo volumio[3106]: info: CoreCommandRouter::volumioGetState Apr 19 21:25:44 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 19 21:25:44 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 21:25:44 primo volumio[3106]: info: Received Get System Info Apr 19 21:25:44 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 21:25:44 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 21:25:44 primo volumio[3106]: info: Discovery: Getting this device information Apr 19 21:25:44 primo volumio[3106]: info: CoreCommandRouter::volumioGetState Apr 19 21:25:44 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 21:25:44 primo volumio[3106]: info: CoreCommandRouter::volumioGetState Apr 19 21:25:44 primo volumio[3106]: info: Listing playlists Apr 19 21:25:44 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 19 21:25:44 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 21:25:44 primo volumio[3106]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 21:25:44 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 19 21:25:45 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 19 21:25:46 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 21:25:46 primo volumio[3106]: info: Received Get System Info Apr 19 21:25:46 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 21:25:46 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 21:25:46 primo volumio[3106]: info: Discovery: Getting this device information Apr 19 21:25:46 primo volumio[3106]: info: CoreCommandRouter::volumioGetState Apr 19 21:25:46 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 21:25:46 primo volumio[3106]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:25:46 primo volumio[3106]: info: CoreStateMachine::getQueue Apr 19 21:25:46 primo volumio[3106]: info: CorePlayQueue::getQueue Apr 19 21:25:46 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 21:25:46 primo volumio[3106]: info: Received Get System Info Apr 19 21:25:46 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 21:25:46 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 21:25:46 primo volumio[3106]: info: Discovery: Getting this device information Apr 19 21:25:46 primo volumio[3106]: info: CoreCommandRouter::volumioGetState Apr 19 21:25:46 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 21:25:47 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 21:25:47 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 21:25:47 primo volumio[3106]: info: Discovery: Getting this device information Apr 19 21:25:47 primo volumio[3106]: info: CoreCommandRouter::volumioGetState Apr 19 21:25:47 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 21:25:50 primo volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Apr 19 21:25:50 primo volumio[3106]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 21:25:50 primo volumio[3106]: TypeError: Cannot read property 'then' of undefined Apr 19 21:25:50 primo volumio[3106]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Apr 19 21:25:50 primo volumio[3106]: at Socket.emit (events.js:400:28) Apr 19 21:25:50 primo volumio[3106]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 19 21:25:50 primo volumio[3106]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 19 21:25:50 primo volumio[3106]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 21:25:51 primo sudo[4367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 21:24 Apr 19 21:25:51 primo sudo[4367]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 08:39:28 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="53aa76f285b1e43196da79c067ac0c05"