2023-01-02 04:36:52.673415 INFO [src/bin.rs:711] CamillaDSP version 1.0.1
2023-01-02 04:36:52.673548 INFO [src/bin.rs:712] Running on linux, arm
2023-01-02 04:36:52.673742 DEBUG [src/bin.rs:754] Read config file Some("/mnt/mmcblk0p2/tce/Camilla_Extensions/camillagui/cdsp_configs/config.yml")
2023-01-02 04:36:52.675225 DEBUG [src/bin.rs:773] Config is valid
2023-01-02 04:36:52.675368 DEBUG [src/socketserver.rs:260] Start websocket server on 127.0.0.1:1234
2023-01-02 04:36:52.675606 DEBUG [src/bin.rs:857] Wait for config
2023-01-02 04:36:52.675748 DEBUG [src/bin.rs:890] Config ready
2023-01-02 04:36:52.676197 DEBUG [src/bin.rs:213] Using channels [true, true]
2023-01-02 04:36:52.676340 DEBUG [src/filters.rs:450] Build new pipeline
2023-01-02 04:36:52.676464 DEBUG [src/alsadevice.rs:909] Buffer frames 4096
2023-01-02 04:36:52.676635 DEBUG [src/filters.rs:355] Build from config
2023-01-02 04:36:52.676794 DEBUG [src/alsadevice.rs:924] Creating resampler
2023-01-02 04:36:52.676902 DEBUG [src/audiodevice.rs:418] Creating asynchronous resampler with parameters: InterpolationParameters { sinc_len: 128, f_cutoff: 0.92591465, oversampling_factor: 1024, interpolation: Linear, window: Blackman2 }
2023-01-02 04:36:52.676921 DEBUG [src/filters.rs:355] Build from config
2023-01-02 04:36:52.677171 DEBUG [src/filters.rs:355] Build from config
2023-01-02 04:36:52.677358 DEBUG [src/filters.rs:355] Build from config
2023-01-02 04:36:52.677546 DEBUG [src/processing.rs:19] build filters, waiting to start processing loop
2023-01-02 04:36:52.680305 DEBUG [src/alsadevice.rs:412] Playback: supported channels, min: 4, max: 4, list: [4]
2023-01-02 04:36:52.680421 DEBUG [src/alsadevice.rs:413] Playback: setting channels to 4
2023-01-02 04:36:52.680673 DEBUG [src/alsadevice.rs:417] Playback: supported samplerates: Discrete([44100, 48000, 88200, 96000, 176400, 192000])
2023-01-02 04:36:52.680772 DEBUG [src/alsadevice.rs:418] Playback: setting rate to 96000
2023-01-02 04:36:52.680935 DEBUG [src/alsadevice.rs:422] Playback: supported sample formats: [S32LE]
2023-01-02 04:36:52.681038 DEBUG [src/alsadevice.rs:423] Playback: setting format to S32LE
2023-01-02 04:36:52.686393 DEBUG [src/alsadevice.rs:454] Opening audio device "hw:M4" with parameters: HwParams { channels: Ok(4), rate: "Ok(96000) Hz", format: Ok(S32LE), access: Ok(RWInterleaved), period_size: "Ok(1024) frames", buffer_size: "Ok(8192) frames" }, SwParams(avail_min: Ok(4096) frames, start_threshold: Ok(3072) frames, stop_threshold: Ok(8192) frames)
2023-01-02 04:36:52.686551 DEBUG [src/alsadevice.rs:459] Audio device "hw:M4" successfully opened
2023-01-02 04:36:52.686696 DEBUG [src/bin.rs:323] Playback thread ready to start
2023-01-02 04:36:52.725088 DEBUG [src/alsadevice.rs:412] Capture: supported channels, min: 1, max: 32, list: [1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29, 30, 31, 32]
2023-01-02 04:36:52.725173 DEBUG [src/alsadevice.rs:413] Capture: setting channels to 2
2023-01-02 04:36:52.725326 DEBUG [src/alsadevice.rs:417] Capture: supported samplerates: Range(8000, 192000)
2023-01-02 04:36:52.725386 DEBUG [src/alsadevice.rs:418] Capture: setting rate to 44100
2023-01-02 04:36:52.725462 DEBUG [src/alsadevice.rs:422] Capture: supported sample formats: [S16LE, S24LE, S24LE3, S32LE, FLOAT32LE]
2023-01-02 04:36:52.725535 DEBUG [src/alsadevice.rs:423] Capture: setting format to S32LE
2023-01-02 04:36:52.725783 DEBUG [src/alsadevice.rs:454] Opening audio device "hw:Loopback,0" with parameters: HwParams { channels: Ok(2), rate: "Ok(44100) Hz", format: Ok(S32LE), access: Ok(RWInterleaved), period_size: "Ok(1024) frames", buffer_size: "Ok(8192) frames" }, SwParams(avail_min: Ok(4096) frames, start_threshold: Ok(0) frames, stop_threshold: Ok(8192) frames)
2023-01-02 04:36:52.725871 DEBUG [src/alsadevice.rs:459] Audio device "hw:Loopback,0" successfully opened
2023-01-02 04:36:52.725962 DEBUG [src/bin.rs:333] Capture thread ready to start
2023-01-02 04:36:52.726022 DEBUG [src/bin.rs:336] Both capture and playback ready, release barrier
2023-01-02 04:36:52.726089 DEBUG [src/bin.rs:338] Supervisor loop starts now!
2023-01-02 04:36:52.726105 DEBUG [src/alsadevice.rs:950] Starting captureloop
2023-01-02 04:36:52.726114 DEBUG [src/alsadevice.rs:856] Starting playback loop
2023-01-02 04:36:52.726187 DEBUG [src/processing.rs:21] Processing loop starts now!
2023-01-02 04:36:52.726855 INFO [src/alsadevice.rs:588] Capture device supports rate adjust
2023-01-02 04:36:52.726918 WARN [src/alsadevice.rs:592] Async resampler not needed since capture device supports rate adjust. Switch to Sync type to save CPU time.
2023-01-02 04:36:52.726992 DEBUG [src/alsadevice.rs:248] Starting capture from state: SND_PCM_STATE_PREPARED, Ready to start
2023-01-02 04:36:52.794140 INFO [src/alsadevice.rs:160] Starting playback from Prepared state
2023-01-02 04:36:52.993340 DEBUG [/cargo/registry/src/github.com-1ecc6299db9ec823/tungstenite-0.16.0/src/handshake/server.rs:282] Server handshake done.
2023-01-02 04:36:52.997065 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetVersion)
2023-01-02 04:36:53.374829 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetState)
2023-01-02 04:36:53.375990 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalRms)
2023-01-02 04:36:53.377109 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalPeak)
2023-01-02 04:36:53.378182 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalRms)
2023-01-02 04:36:53.379306 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalPeak)
2023-01-02 04:36:53.380546 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureRate)
2023-01-02 04:36:53.381636 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetRateAdjust)
2023-01-02 04:36:53.382626 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetBufferLevel)
2023-01-02 04:36:53.383617 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetClippedSamples)
2023-01-02 04:36:54.374192 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetState)
2023-01-02 04:36:54.375124 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalRms)
2023-01-02 04:36:54.375992 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalPeak)
2023-01-02 04:36:54.376839 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalRms)
2023-01-02 04:36:54.377723 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalPeak)
2023-01-02 04:36:55.371304 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetVolume)
2023-01-02 04:36:55.375776 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetMute)
2023-01-02 04:36:55.377921 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetState)
2023-01-02 04:36:55.378730 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalRms)
2023-01-02 04:36:55.379487 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalPeak)
2023-01-02 04:36:55.380225 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalRms)
2023-01-02 04:36:55.381073 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalPeak)
2023-01-02 04:36:55.382351 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureRate)
2023-01-02 04:36:55.383546 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetRateAdjust)
2023-01-02 04:36:55.384665 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetBufferLevel)
2023-01-02 04:36:55.385790 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetClippedSamples)
2023-01-02 04:36:56.372209 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetState)
2023-01-02 04:36:56.373354 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalRms)
2023-01-02 04:36:56.374417 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalPeak)
2023-01-02 04:36:56.375453 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalRms)
2023-01-02 04:36:56.376520 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalPeak)
2023-01-02 04:36:57.366179 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetVolume)
2023-01-02 04:36:57.369449 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetMute)
2023-01-02 04:36:57.371983 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetState)
2023-01-02 04:36:57.373089 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalRms)
2023-01-02 04:36:57.374163 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalPeak)
2023-01-02 04:36:57.375203 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalRms)
2023-01-02 04:36:57.376290 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalPeak)
2023-01-02 04:36:57.377484 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureRate)
2023-01-02 04:36:57.378529 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetRateAdjust)
2023-01-02 04:36:57.379526 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetBufferLevel)
2023-01-02 04:36:57.380507 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetClippedSamples)
2023-01-02 04:36:58.368000 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetState)
2023-01-02 04:36:58.369080 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalRms)
2023-01-02 04:36:58.370163 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalPeak)
2023-01-02 04:36:58.371280 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalRms)
2023-01-02 04:36:58.372446 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalPeak)
2023-01-02 04:36:59.366354 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetVolume)
2023-01-02 04:36:59.369285 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetMute)
2023-01-02 04:36:59.371487 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetState)
2023-01-02 04:36:59.372490 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalRms)
2023-01-02 04:36:59.373434 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalPeak)
2023-01-02 04:36:59.374537 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalRms)
2023-01-02 04:36:59.375628 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalPeak)
2023-01-02 04:36:59.376758 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureRate)
2023-01-02 04:36:59.377683 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetRateAdjust)
2023-01-02 04:36:59.378473 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetBufferLevel)
2023-01-02 04:36:59.379247 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetClippedSamples)
2023-01-02 04:37:00.355783 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetState)
2023-01-02 04:37:00.356547 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalRms)
2023-01-02 04:37:00.357247 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalPeak)
2023-01-02 04:37:00.357923 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalRms)
2023-01-02 04:37:00.358630 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalPeak)
2023-01-02 04:37:01.371757 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetVolume)
2023-01-02 04:37:01.374858 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetMute)
2023-01-02 04:37:01.376619 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetState)
2023-01-02 04:37:01.377394 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalRms)
2023-01-02 04:37:01.378147 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalPeak)
2023-01-02 04:37:01.378876 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalRms)
2023-01-02 04:37:01.379637 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalPeak)
2023-01-02 04:37:01.380484 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureRate)
2023-01-02 04:37:01.381284 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetRateAdjust)
2023-01-02 04:37:01.382006 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetBufferLevel)
2023-01-02 04:37:01.382691 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetClippedSamples)
2023-01-02 04:37:02.369741 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetState)
2023-01-02 04:37:02.370633 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalRms)
2023-01-02 04:37:02.371727 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalPeak)
2023-01-02 04:37:02.373187 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalRms)
2023-01-02 04:37:02.374584 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalPeak)
2023-01-02 04:37:02.732634 DEBUG [src/audiodevice.rs:614] Current buffer level: 4885.9, corrected capture rate: 99.9589%
2023-01-02 04:37:02.732824 DEBUG [src/alsadevice.rs:509] Playback buffer level: 4885.9, signal rms: [-1000.0, -1000.0, -1000.0, -1000.0]
2023-01-02 04:37:02.732868 DEBUG [src/bin.rs:423] SetSpeed message received
2023-01-02 04:37:03.371258 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetVolume)
2023-01-02 04:37:03.374665 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetMute)
2023-01-02 04:37:03.376947 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetState)
2023-01-02 04:37:03.377876 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalRms)
2023-01-02 04:37:03.378755 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalPeak)
2023-01-02 04:37:03.379590 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalRms)
2023-01-02 04:37:03.380460 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalPeak)
2023-01-02 04:37:03.381511 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureRate)
2023-01-02 04:37:03.382387 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetRateAdjust)
2023-01-02 04:37:03.383206 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetBufferLevel)
2023-01-02 04:37:03.383997 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetClippedSamples)
2023-01-02 04:37:04.368671 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetState)
2023-01-02 04:37:04.369912 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalRms)
2023-01-02 04:37:04.371261 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalPeak)
2023-01-02 04:37:04.372516 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalRms)
2023-01-02 04:37:04.373709 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalPeak)
2023-01-02 04:37:05.366334 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetVolume)
2023-01-02 04:37:05.369327 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetMute)
2023-01-02 04:37:05.372481 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetState)
2023-01-02 04:37:05.374143 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalRms)
2023-01-02 04:37:05.375763 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalPeak)
2023-01-02 04:37:05.377356 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalRms)
2023-01-02 04:37:05.379063 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalPeak)
2023-01-02 04:37:05.380844 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureRate)
2023-01-02 04:37:05.382481 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetRateAdjust)
2023-01-02 04:37:05.384121 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetBufferLevel)
2023-01-02 04:37:05.385179 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetClippedSamples)
2023-01-02 04:37:06.366770 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetState)
2023-01-02 04:37:06.367742 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalRms)
2023-01-02 04:37:06.368602 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetCaptureSignalPeak)
2023-01-02 04:37:06.369410 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalRms)
2023-01-02 04:37:06.370432 DEBUG [src/socketserver.rs:340] parsed command: Ok(GetPlaybackSignalPeak)
2023-01-02 04:37:07.037741 DEBUG [src/bin.rs:289] Exit requested...
2023-01-02 04:37:07.067582 DEBUG [src/alsadevice.rs:620] Exit message received, sending EndOfStream
2023-01-02 04:37:07.102756 DEBUG [src/bin.rs:909] Exiting