kbingham / libcamera

libcamera - Making complex cameras easy. This is a personal fork, please use the upstream repository at https://git.libcamera.org/libcamera/libcamera.git/
https://libcamera.org
Other
173 stars 69 forks source link

Android: RPI4: Need help with log analysis #31

Closed rsglobal closed 2 years ago

rsglobal commented 3 years ago

Currently I have no camera picture and this:

10-07 14:42:18.486   413  1588 E Camera3-Stream: getBuffer: wait for output buffer return timed out after 8000ms (max_buffers 3)

message from the framework.

I'm on the latest libcamera & rpi-kernel v5.10.y

I will appreciate any ideas and suggestions where to look further.

adb logcat | grep libcam
01-01 00:00:23.386   360   360 I libcamera:  INFO HAL camera3_hal.cpp:61 Initialising Android camera HAL
01-01 00:00:23.416   360   360 D libcamera: DEBUG HALConfig camera_hal_config.cpp:378 '/base/soc/i2c0mux/i2c@1/ov5647@36' (0)[0]
01-01 00:00:23.544   360   360 D libcamera: DEBUG IPAModule ipa_module.cpp:320 ipa_rpi.so: IPA module /vendor/lib64/libcamera//ipa_rpi.so is not signed
01-01 00:00:23.544   360   360 D libcamera: DEBUG IPAManager ipa_manager.cpp:241 Loaded IPA module '/vendor/lib64/libcamera//ipa_rpi.so'
01-01 00:00:23.549   360   360 I libcamera:  INFO Camera camera_manager.cpp:293 libcamera v0.0.0
01-01 00:00:23.550   360   442 D libcamera: DEBUG Camera camera_manager.cpp:106 Starting camera manager
01-01 00:00:23.551   360   442 D libcamera: DEBUG DeviceEnumerator device_enumerator.cpp:224 New media device "bcm2835-codec" created from /dev/media2
01-01 00:00:23.554   360   442 D libcamera: DEBUG DeviceEnumerator device_enumerator.cpp:252 Added device /dev/media2: bcm2835-codec
01-01 00:00:23.554   360   442 D libcamera: DEBUG DeviceEnumerator device_enumerator.cpp:224 New media device "unicam" created from /dev/media0
01-01 00:00:23.555   360   442 D libcamera: DEBUG DeviceEnumerator device_enumerator.cpp:252 Added device /dev/media0: unicam
01-01 00:00:23.556   360   442 D libcamera: DEBUG DeviceEnumerator device_enumerator.cpp:224 New media device "bcm2835-isp" created from /dev/media1
01-01 00:00:23.578   360   442 D libcamera: DEBUG DeviceEnumerator device_enumerator.cpp:252 Added device /dev/media1: bcm2835-isp
01-01 00:00:23.578   360   442 D libcamera: DEBUG Camera camera_manager.cpp:149 Found registered pipeline handler 'SimplePipelineHandler'
01-01 00:00:23.578   360   442 D libcamera: DEBUG Camera camera_manager.cpp:149 Found registered pipeline handler 'PipelineHandlerRPi'
01-01 00:00:23.579   360   442 D libcamera: DEBUG DeviceEnumerator device_enumerator.cpp:312 Successful match for media device "unicam"
01-01 00:00:23.579   360   442 D libcamera: DEBUG DeviceEnumerator device_enumerator.cpp:312 Successful match for media device "bcm2835-isp"
01-01 00:00:23.581   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 'ov5647 10-0036': Control: White Balance, Automatic (0x0098090c)
01-01 00:00:23.581   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 'ov5647 10-0036': Control: Exposure (0x00980911)
01-01 00:00:23.581   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 'ov5647 10-0036': Control: Gain, Automatic (0x00980912)
01-01 00:00:23.582   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 'ov5647 10-0036': Control: Auto Exposure (0x009a0901)
01-01 00:00:23.582   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 'ov5647 10-0036': Control: Camera Orientation (0x009a0922)
01-01 00:00:23.585   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 'ov5647 10-0036': Control: Camera Sensor Rotation (0x009a0923)
01-01 00:00:23.586   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 'ov5647 10-0036': Control: Vertical Blanking (0x009e0901)
01-01 00:00:23.586   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 'ov5647 10-0036': Control: Horizontal Blanking (0x009e0902)
01-01 00:00:23.586   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 'ov5647 10-0036': Control: Analogue Gain (0x009e0903)
01-01 00:00:23.586   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 'ov5647 10-0036': Control: Pixel Rate (0x009f0902)
01-01 00:00:23.591   360   442 D libcamera: DEBUG CameraSensor camera_sensor.cpp:318 'ov5647 10-0036': No static test pattern map for 'ov5647'
01-01 00:00:23.592   360   442 D libcamera: DEBUG IPAManager ipa_manager.cpp:299 IPA module /vendor/lib64/libcamera//ipa_rpi.so signature is not valid
01-01 00:00:23.601   360   442 D libcamera: DEBUG IPAProxy raspberrypi_ipa_proxy.cpp:45 initializing raspberrypi proxy: loading IPA from /vendor/lib64/libcamera//ipa_rpi.so
01-01 00:00:24.671   360   442 D libcamera: DEBUG RPiBlackLevel black_level.cpp:41  Read black levels red 1024 green 1024 blue 1024
01-01 00:00:24.672   360   442 D libcamera: DEBUG RPiAgc agc.cpp:191 Agc
01-01 00:00:24.672   360   442 D libcamera: DEBUG RPiAgc agc.cpp:144 AgcConfig
01-01 00:00:24.675   360   442 D libcamera: DEBUG RPiAlsc alsc.cpp:118 Read calibrations_Cr calibration for ct 3000
01-01 00:00:24.676   360   442 D libcamera: DEBUG RPiAlsc alsc.cpp:118 Read calibrations_Cr calibration for ct 5000
01-01 00:00:24.678   360   442 D libcamera: DEBUG RPiAlsc alsc.cpp:118 Read calibrations_Cr calibration for ct 6500
01-01 00:00:24.678   360   442 D libcamera: DEBUG RPiAlsc alsc.cpp:118 Read calibrations_Cb calibration for ct 3000
01-01 00:00:24.679   360   442 D libcamera: DEBUG RPiAlsc alsc.cpp:118 Read calibrations_Cb calibration for ct 5000
01-01 00:00:24.680   360   442 D libcamera: DEBUG RPiAlsc alsc.cpp:118 Read calibrations_Cb calibration for ct 6500
01-01 00:00:24.682   360   442 D libcamera: DEBUG RPiSharpen sharpen.cpp:45 Read threshold 1 strength 1 limit 1
01-01 00:00:24.722   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:588 /dev/video0[15:cap]: Opened device platform:fe801000.csi: unicam: unicam
01-01 00:00:24.723   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 /dev/video13[16:cap]: Control: Red Balance (0x0098090e)
01-01 00:00:24.723   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 /dev/video13[16:cap]: Control: Blue Balance (0x0098090f)
01-01 00:00:24.723   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 /dev/video13[16:cap]: Control: Colour Correction Matrix (0x009819e1)
01-01 00:00:24.724   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 /dev/video13[16:cap]: Control: Lens Shading (0x009819e2)
01-01 00:00:24.724   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 /dev/video13[16:cap]: Control: Black Level (0x009819e3)
01-01 00:00:24.724   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 /dev/video13[16:cap]: Control: Green Equalisation (0x009819e4)
01-01 00:00:24.724   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 /dev/video13[16:cap]: Control: Gamma (0x009819e5)
01-01 00:00:24.724   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 /dev/video13[16:cap]: Control: Denoise (0x009819e6)
01-01 00:00:24.725   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 /dev/video13[16:cap]: Control: Sharpen (0x009819e7)
01-01 00:00:24.725   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 /dev/video13[16:cap]: Control: Defective Pixel Correction (0x009819e8)
01-01 00:00:24.725   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 /dev/video13[16:cap]: Control: Colour Denoise (0x009819e9)
01-01 00:00:24.726   360   442 D libcamera: DEBUG V4L2 v4l2_device.cpp:610 /dev/video13[16:cap]: Control: Digital Gain (0x009f0905)
01-01 00:00:24.726   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:588 /dev/video13[16:out]: Opened device platform:bcm2835-isp: bcm2835-isp: bcm2835-isp
01-01 00:00:24.726   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:588 /dev/video14[17:cap]: Opened device platform:bcm2835-isp: bcm2835-isp: bcm2835-isp
01-01 00:00:24.727   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:588 /dev/video15[18:cap]: Opened device platform:bcm2835-isp: bcm2835-isp: bcm2835-isp
01-01 00:00:24.727   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:588 /dev/video16[19:cap]: Opened device platform:bcm2835-isp: bcm2835-isp: bcm2835-isp
01-01 00:00:24.727   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:99 Set a delay of 2 and priority write flag 1 for Vertical Blanking
01-01 00:00:24.727   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:99 Set a delay of 2 and priority write flag 0 for Exposure
01-01 00:00:24.727   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:99 Set a delay of 2 and priority write flag 0 for Analogue Gain
01-01 00:00:24.732   360   442 D libcamera: DEBUG Camera camera.cpp:886 streams configuration: (0) 2592x1944-NV12
01-01 00:00:24.732   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:483 Maximum supported resolution: 2592x1944
01-01 00:00:24.732   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:539 Trying to map Android format RAW16
01-01 00:00:24.732   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:562 Testing SBGGR16
01-01 00:00:24.732   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 8832 (best 8832)
01-01 00:00:24.733   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 7832 (best 7832)
01-01 00:00:24.733   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 5536 (best 5536)
01-01 00:00:24.733   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 4238.67 (best 4238.67)
01-01 00:00:24.733   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.733   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:562 Testing SGBRG16
01-01 00:00:24.734   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 8832 (best 8832)
01-01 00:00:24.734   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 7832 (best 7832)
01-01 00:00:24.734   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 5536 (best 5536)
01-01 00:00:24.734   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 4238.67 (best 4238.67)
01-01 00:00:24.734   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.735   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:562 Testing SGRBG16
01-01 00:00:24.735   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 8832 (best 8832)
01-01 00:00:24.735   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 7832 (best 7832)
01-01 00:00:24.735   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 5536 (best 5536)
01-01 00:00:24.735   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 4238.67 (best 4238.67)
01-01 00:00:24.736   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.736   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:562 Testing SRGGB16
01-01 00:00:24.736   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 8832 (best 8832)
01-01 00:00:24.736   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 7832 (best 7832)
01-01 00:00:24.736   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 5536 (best 5536)
01-01 00:00:24.737   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 4238.67 (best 4238.67)
01-01 00:00:24.737   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.737   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:539 Trying to map Android format BLOB
01-01 00:00:24.737   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:548 Mapped Android format BLOB to MJPEG (fixed mapping)
01-01 00:00:24.737   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:539 Trying to map Android format IMPLEMENTATION_DEFINED
01-01 00:00:24.737   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:562 Testing NV12
01-01 00:00:24.738   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:599 Mapped Android format IMPLEMENTATION_DEFINED to NV12
01-01 00:00:24.739   360   442 D libcamera: DEBUG Camera camera.cpp:886 streams configuration: (0) 800x600-NV12
01-01 00:00:24.740   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:432 320x240-NV12 supported
01-01 00:00:24.741   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:432 324x243-NV12 supported
01-01 00:00:24.742   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:432 640x480-NV12 supported
01-01 00:00:24.742   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:432 648x486-NV12 supported
01-01 00:00:24.743   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:432 1280x720-NV12 supported
01-01 00:00:24.744   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:432 1296x972-NV12 supported
01-01 00:00:24.745   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:432 1920x1080-NV12 supported
01-01 00:00:24.745   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:432 2592x1944-NV12 supported
01-01 00:00:24.746   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 320x240-NV12
01-01 00:00:24.746   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 2140 (best 2140)
01-01 00:00:24.746   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 1140 (best 1140)
01-01 00:00:24.747   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 1427 (best 1140)
01-01 00:00:24.747   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 1776.67 (best 1140)
01-01 00:00:24.747   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1994 (best 1140)
01-01 00:00:24.751   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 640x480-SBGGR10_1X10 - Selected unicam format: 640x480-pBAA
01-01 00:00:24.752   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 320x240-NV12
01-01 00:00:24.752   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 160x120-NV12
01-01 00:00:24.755   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19967.93us (Shutter lines: 593, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.780   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 324x243-NV12
01-01 00:00:24.781   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 2138.25 (best 2138.25)
01-01 00:00:24.781   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 1138.25 (best 1138.25)
01-01 00:00:24.782   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 1425.25 (best 1138.25)
01-01 00:00:24.782   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 1774.92 (best 1138.25)
01-01 00:00:24.783   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1992.25 (best 1138.25)
01-01 00:00:24.785   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 640x480-SBGGR10_1X10 - Selected unicam format: 640x480-pBAA
01-01 00:00:24.786   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 324x243-NV12
01-01 00:00:24.786   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 162x120-NV12
01-01 00:00:24.787   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19967.93us (Shutter lines: 593, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.790   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 640x480-NV12
01-01 00:00:24.790   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 2000 (best 2000)
01-01 00:00:24.790   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.791   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 1287 (best 1000)
01-01 00:00:24.791   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 1636.67 (best 1000)
01-01 00:00:24.791   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1854 (best 1000)
01-01 00:00:24.793   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 640x480-SBGGR10_1X10 - Selected unicam format: 640x480-pBAA
01-01 00:00:24.794   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 640x480-NV12
01-01 00:00:24.794   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 320x240-NV12
01-01 00:00:24.797   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19967.93us (Shutter lines: 593, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.800   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 648x486-NV12
01-01 00:00:24.801   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 2028 (best 2028)
01-01 00:00:24.801   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 1028 (best 1028)
01-01 00:00:24.801   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 1283.5 (best 1028)
01-01 00:00:24.801   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 1633.17 (best 1028)
01-01 00:00:24.801   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1850.5 (best 1028)
01-01 00:00:24.804   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 640x480-SBGGR10_1X10 - Selected unicam format: 640x480-pBAA
01-01 00:00:24.805   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 648x486-NV12
01-01 00:00:24.806   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 324x242-NV12
01-01 00:00:24.807   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19967.93us (Shutter lines: 593, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.811   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 1280x720-NV12
01-01 00:00:24.811   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 5093.33 (best 5093.33)
01-01 00:00:24.811   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 4093.33 (best 4093.33)
01-01 00:00:24.812   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 2400.33 (best 2400.33)
01-01 00:00:24.812   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 1250 (best 1250)
01-01 00:00:24.812   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 2967.33 (best 1250)
01-01 00:00:24.814   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 1920x1080-SBGGR10_1X10 - Selected unicam format: 1920x1080-pBAA
01-01 00:00:24.814   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 1280x720-NV12
01-01 00:00:24.815   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 640x360-NV12
01-01 00:00:24.817   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19998.56us (Shutter lines: 676, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.819   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 1296x972-NV12
01-01 00:00:24.820   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 4296 (best 4296)
01-01 00:00:24.820   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 3296 (best 3296)
01-01 00:00:24.821   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.821   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 1349.67 (best 1000)
01-01 00:00:24.821   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1567 (best 1000)
01-01 00:00:24.823   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 1296x972-SBGGR10_1X10 - Selected unicam format: 1296x972-pBAA
01-01 00:00:24.824   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 1296x972-NV12
01-01 00:00:24.824   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 648x486-NV12
01-01 00:00:24.826   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19989.25us (Shutter lines: 861, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.828   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 1920x1080-NV12
01-01 00:00:24.829   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 7093.33 (best 7093.33)
01-01 00:00:24.834   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 6093.33 (best 6093.33)
01-01 00:00:24.835   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 3797.33 (best 3797.33)
01-01 00:00:24.835   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.836   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 2717.33 (best 1000)
01-01 00:00:24.838   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 1920x1080-SBGGR10_1X10 - Selected unicam format: 1920x1080-pBAA
01-01 00:00:24.838   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 1920x1080-NV12
01-01 00:00:24.838   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 960x540-NV12
01-01 00:00:24.840   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19998.56us (Shutter lines: 676, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.845   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 2592x1944-NV12
01-01 00:00:24.845   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 8832 (best 8832)
01-01 00:00:24.845   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 7832 (best 7832)
01-01 00:00:24.846   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 5536 (best 5536)
01-01 00:00:24.846   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 4238.67 (best 4238.67)
01-01 00:00:24.846   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.848   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 2592x1944-SBGGR10_1X10 - Selected unicam format: 2592x1944-pBAA
01-01 00:00:24.848   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 2592x1944-NV12
01-01 00:00:24.850   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 1200x900-NV12
01-01 00:00:24.851   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19989.26us (Shutter lines: 615, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.853   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:539 Trying to map Android format YCbCr_420_888
01-01 00:00:24.853   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:562 Testing NV12
01-01 00:00:24.854   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:599 Mapped Android format YCbCr_420_888 to NV12
01-01 00:00:24.856   360   442 D libcamera: DEBUG Camera camera.cpp:886 streams configuration: (0) 800x600-NV12
01-01 00:00:24.857   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:432 320x240-NV12 supported
01-01 00:00:24.858   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:432 324x243-NV12 supported
01-01 00:00:24.859   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:432 640x480-NV12 supported
01-01 00:00:24.860   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:432 648x486-NV12 supported
01-01 00:00:24.862   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:432 1280x720-NV12 supported
01-01 00:00:24.863   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:432 1296x972-NV12 supported
01-01 00:00:24.864   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:432 1920x1080-NV12 supported
01-01 00:00:24.865   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:432 2592x1944-NV12 supported
01-01 00:00:24.866   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 320x240-NV12
01-01 00:00:24.867   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 2140 (best 2140)
01-01 00:00:24.867   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 1140 (best 1140)
01-01 00:00:24.867   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 1427 (best 1140)
01-01 00:00:24.868   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 1776.67 (best 1140)
01-01 00:00:24.869   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1994 (best 1140)
01-01 00:00:24.871   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 640x480-SBGGR10_1X10 - Selected unicam format: 640x480-pBAA
01-01 00:00:24.872   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 320x240-NV12
01-01 00:00:24.872   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 160x120-NV12
01-01 00:00:24.874   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19967.93us (Shutter lines: 593, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.878   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 324x243-NV12
01-01 00:00:24.879   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 2138.25 (best 2138.25)
01-01 00:00:24.879   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 1138.25 (best 1138.25)
01-01 00:00:24.879   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 1425.25 (best 1138.25)
01-01 00:00:24.880   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 1774.92 (best 1138.25)
01-01 00:00:24.880   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1992.25 (best 1138.25)
01-01 00:00:24.884   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 640x480-SBGGR10_1X10 - Selected unicam format: 640x480-pBAA
01-01 00:00:24.884   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 324x243-NV12
01-01 00:00:24.885   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 162x120-NV12
01-01 00:00:24.886   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19967.93us (Shutter lines: 593, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.889   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 640x480-NV12
01-01 00:00:24.889   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 2000 (best 2000)
01-01 00:00:24.889   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.889   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 1287 (best 1000)
01-01 00:00:24.890   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 1636.67 (best 1000)
01-01 00:00:24.890   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1854 (best 1000)
01-01 00:00:24.892   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 640x480-SBGGR10_1X10 - Selected unicam format: 640x480-pBAA
01-01 00:00:24.893   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 640x480-NV12
01-01 00:00:24.893   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 320x240-NV12
01-01 00:00:24.894   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19967.93us (Shutter lines: 593, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.897   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 648x486-NV12
01-01 00:00:24.897   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 2028 (best 2028)
01-01 00:00:24.898   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 1028 (best 1028)
01-01 00:00:24.898   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 1283.5 (best 1028)
01-01 00:00:24.898   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 1633.17 (best 1028)
01-01 00:00:24.899   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1850.5 (best 1028)
01-01 00:00:24.901   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 640x480-SBGGR10_1X10 - Selected unicam format: 640x480-pBAA
01-01 00:00:24.902   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 648x486-NV12
01-01 00:00:24.902   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 324x242-NV12
01-01 00:00:24.904   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19967.93us (Shutter lines: 593, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.908   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 1280x720-NV12
01-01 00:00:24.908   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 5093.33 (best 5093.33)
01-01 00:00:24.908   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 4093.33 (best 4093.33)
01-01 00:00:24.909   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 2400.33 (best 2400.33)
01-01 00:00:24.909   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 1250 (best 1250)
01-01 00:00:24.909   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 2967.33 (best 1250)
01-01 00:00:24.910   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 1920x1080-SBGGR10_1X10 - Selected unicam format: 1920x1080-pBAA
01-01 00:00:24.911   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 1280x720-NV12
01-01 00:00:24.911   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 640x360-NV12
01-01 00:00:24.912   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19998.56us (Shutter lines: 676, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.915   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 1296x972-NV12
01-01 00:00:24.915   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 4296 (best 4296)
01-01 00:00:24.915   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 3296 (best 3296)
01-01 00:00:24.916   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.916   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 1349.67 (best 1000)
01-01 00:00:24.916   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1567 (best 1000)
01-01 00:00:24.917   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 1296x972-SBGGR10_1X10 - Selected unicam format: 1296x972-pBAA
01-01 00:00:24.918   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 1296x972-NV12
01-01 00:00:24.918   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 648x486-NV12
01-01 00:00:24.919   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19989.25us (Shutter lines: 861, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.921   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 1920x1080-NV12
01-01 00:00:24.922   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 7093.33 (best 7093.33)
01-01 00:00:24.922   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 6093.33 (best 6093.33)
01-01 00:00:24.922   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 3797.33 (best 3797.33)
01-01 00:00:24.922   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.922   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 2717.33 (best 1000)
01-01 00:00:24.923   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 1920x1080-SBGGR10_1X10 - Selected unicam format: 1920x1080-pBAA
01-01 00:00:24.924   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 1920x1080-NV12
01-01 00:00:24.924   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 960x540-NV12
01-01 00:00:24.926   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19998.56us (Shutter lines: 676, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.929   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 2592x1944-NV12
01-01 00:00:24.929   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 8832 (best 8832)
01-01 00:00:24.929   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 7832 (best 7832)
01-01 00:00:24.929   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 5536 (best 5536)
01-01 00:00:24.929   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 4238.67 (best 4238.67)
01-01 00:00:24.930   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.931   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 2592x1944-SBGGR10_1X10 - Selected unicam format: 2592x1944-pBAA
01-01 00:00:24.931   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 2592x1944-NV12
01-01 00:00:24.932   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 1200x900-NV12
01-01 00:00:24.934   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19989.26us (Shutter lines: 615, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.936   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:539 Trying to map Android format RAW10
01-01 00:00:24.936   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:562 Testing SBGGR10_CSI2P
01-01 00:00:24.936   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 8832 (best 8832)
01-01 00:00:24.936   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 7832 (best 7832)
01-01 00:00:24.936   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 5536 (best 5536)
01-01 00:00:24.936   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 4238.67 (best 4238.67)
01-01 00:00:24.937   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.937   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:599 Mapped Android format RAW10 to SBGGR10_CSI2P
01-01 00:00:24.937   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:539 Trying to map Android format RAW12
01-01 00:00:24.937   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:562 Testing SBGGR12_CSI2P
01-01 00:00:24.937   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 8832 (best 8832)
01-01 00:00:24.938   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 7832 (best 7832)
01-01 00:00:24.938   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 5536 (best 5536)
01-01 00:00:24.938   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 4238.67 (best 4238.67)
01-01 00:00:24.938   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.938   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:562 Testing SGBRG12_CSI2P
01-01 00:00:24.938   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 8832 (best 8832)
01-01 00:00:24.939   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 7832 (best 7832)
01-01 00:00:24.939   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 5536 (best 5536)
01-01 00:00:24.939   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 4238.67 (best 4238.67)
01-01 00:00:24.939   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.939   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:562 Testing SGRBG12_CSI2P
01-01 00:00:24.940   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 8832 (best 8832)
01-01 00:00:24.940   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 7832 (best 7832)
01-01 00:00:24.940   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 5536 (best 5536)
01-01 00:00:24.940   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 4238.67 (best 4238.67)
01-01 00:00:24.940   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.941   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:562 Testing SRGGB12_CSI2P
01-01 00:00:24.941   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 8832 (best 8832)
01-01 00:00:24.941   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 7832 (best 7832)
01-01 00:00:24.941   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 5536 (best 5536)
01-01 00:00:24.941   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 4238.67 (best 4238.67)
01-01 00:00:24.941   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1000 (best 1000)
01-01 00:00:24.942   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:713 Collected stream configuration map: 
01-01 00:00:24.942   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 320x240 - 0x00000022 }
01-01 00:00:24.942   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 324x243 - 0x00000022 }
01-01 00:00:24.942   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 640x480 - 0x00000022 }
01-01 00:00:24.942   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 648x486 - 0x00000022 }
01-01 00:00:24.942   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 1280x720 - 0x00000022 }
01-01 00:00:24.943   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 1296x972 - 0x00000022 }
01-01 00:00:24.943   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 1920x1080 - 0x00000022 }
01-01 00:00:24.943   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 2592x1944 - 0x00000022 }
01-01 00:00:24.943   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 320x240 - 0x00000023 }
01-01 00:00:24.943   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 320x240 - 0x00000021 }
01-01 00:00:24.943   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 324x243 - 0x00000023 }
01-01 00:00:24.944   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 324x243 - 0x00000021 }
01-01 00:00:24.944   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 640x480 - 0x00000023 }
01-01 00:00:24.944   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 640x480 - 0x00000021 }
01-01 00:00:24.944   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 648x486 - 0x00000023 }
01-01 00:00:24.944   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 648x486 - 0x00000021 }
01-01 00:00:24.944   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 1280x720 - 0x00000023 }
01-01 00:00:24.945   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 1280x720 - 0x00000021 }
01-01 00:00:24.945   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 1296x972 - 0x00000023 }
01-01 00:00:24.945   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 1296x972 - 0x00000021 }
01-01 00:00:24.945   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 1920x1080 - 0x00000023 }
01-01 00:00:24.945   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 1920x1080 - 0x00000021 }
01-01 00:00:24.945   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 2592x1944 - 0x00000023 }
01-01 00:00:24.946   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:715 { 2592x1944 - 0x00000021 }
01-01 00:00:24.947   360   442 D libcamera: DEBUG Camera camera.cpp:886 streams configuration: (0) 800x600-NV12
01-01 00:00:24.948   360   442 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 800x600-NV12
01-01 00:00:24.948   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 2560 (best 2560)
01-01 00:00:24.948   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 1560 (best 1560)
01-01 00:00:24.948   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 1217 (best 1217)
01-01 00:00:24.949   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 1566.67 (best 1217)
01-01 00:00:24.949   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1784 (best 1217)
01-01 00:00:24.950   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 1296x972-SBGGR10_1X10 - Selected unicam format: 1296x972-pBAA
01-01 00:00:24.950   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 800x600-NV12
01-01 00:00:24.951   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:776 Setting ISP Output1 (internal) to 400x300-NV12
01-01 00:00:24.952   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19989.25us (Shutter lines: 861, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
01-01 00:00:24.954   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000022 (320x240)[33333333]@30
01-01 00:00:24.954   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000022 (324x243)[33333333]@30
01-01 00:00:24.954   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000022 (640x480)[33333333]@30
01-01 00:00:24.954   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000022 (648x486)[33333333]@30
01-01 00:00:24.954   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000022 (1280x720)[33333333]@30
01-01 00:00:24.954   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000022 (1296x972)[33333333]@30
01-01 00:00:24.955   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000022 (1920x1080)[33333333]@30
01-01 00:00:24.955   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000022 (2592x1944)[33333333]@30
01-01 00:00:24.955   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000023 (320x240)[33333333]@30
01-01 00:00:24.955   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000021 (320x240)[33333333]@30
01-01 00:00:24.955   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000023 (324x243)[33333333]@30
01-01 00:00:24.955   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000021 (324x243)[33333333]@30
01-01 00:00:24.956   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000023 (640x480)[33333333]@30
01-01 00:00:24.956   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000021 (640x480)[33333333]@30
01-01 00:00:24.956   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000023 (648x486)[33333333]@30
01-01 00:00:24.956   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000021 (648x486)[33333333]@30
01-01 00:00:24.956   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000023 (1280x720)[33333333]@30
01-01 00:00:24.957   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000021 (1280x720)[33333333]@30
01-01 00:00:24.957   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000023 (1296x972)[33333333]@30
01-01 00:00:24.957   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000021 (1296x972)[33333333]@30
01-01 00:00:24.957   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000023 (1920x1080)[33333333]@30
01-01 00:00:24.957   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000021 (1920x1080)[33333333]@30
01-01 00:00:24.958   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000023 (2592x1944)[33333333]@30
01-01 00:00:24.958   360   442 D libcamera: DEBUG HAL camera_capabilities.cpp:1305 Output Stream: 0x00000021 (2592x1944)[33333333]@30
01-01 00:00:24.958   360   442 I libcamera:  INFO CameraMetadata camera_metadata.cpp:109 Resized: old entry capacity 64, old data capacity 1024, new entry capacity 64, new data capacity 2048
01-01 00:00:24.958   360   442 I libcamera:  INFO HAL camera_capabilities.cpp:224 Manual sensor capability unavailable: missing AE mode off
01-01 00:00:24.958   360   442 I libcamera:  INFO HAL camera_capabilities.cpp:264 Manual post processing capability unavailable: missing AWB mode off
01-01 00:00:24.958   360   442 I libcamera:  INFO HAL camera_capabilities.cpp:309 Burst capture capability unavailable: missing AE lock
01-01 00:00:24.959   360   442 I libcamera:  INFO HAL camera_capabilities.cpp:1381 Hardware level: LIMITED
01-01 00:00:24.959   360   442 I libcamera:  INFO CameraMetadata camera_metadata.cpp:109 Resized: old entry capacity 64, old data capacity 2048, new entry capacity 64, new data capacity 4096
01-01 00:00:24.959   360   442 I libcamera:  INFO HAL camera_capabilities.cpp:1404 Static metadata resized: 54 entries and 2056 bytes used
01-01 00:00:24.959   360   442 D libcamera: DEBUG HAL camera_hal_manager.cpp:196 Camera ID: 0 added successfully.
01-01 00:00:24.959   360   442 D libcamera: DEBUG Camera camera_manager.cpp:161 Pipeline handler "PipelineHandlerRPi" matched
01-01 00:00:24.960   360   360 I CamPrvdr@2.4-legacy: Loaded "libcamera camera HALv3 module" camera module

10-07 14:37:45.097   360   360 D libcamera: DEBUG HAL camera3_hal.cpp:75 Open camera 0
10-07 14:37:45.097   360   360 I libcamera:  INFO HAL camera_hal_manager.cpp:95 Open camera '0'
10-07 14:37:45.502   360   459 D libcamera: DEBUG Camera camera.cpp:886 streams configuration: empty
10-07 14:37:45.505   360   459 I libcamera:  INFO HAL camera_device.cpp:550 '/base/soc/i2c0mux/i2c@1/ov5647@36': Stream #0, direction: 0, width: 2592, height: 1944, format: 0x00000023, rotation: 0 (NV12)
10-07 14:37:45.506   360   459 I libcamera:  INFO HAL camera_device.cpp:550 '/base/soc/i2c0mux/i2c@1/ov5647@36': Stream #1, direction: 0, width: 1296, height: 972, format: 0x00000022, rotation: 0 (NV12)
10-07 14:37:45.506   360   459 D libcamera: DEBUG HAL camera_device.cpp:125 Insert 2592x1944-NV12
10-07 14:37:45.508   360   459 D libcamera: DEBUG HAL camera_device.cpp:147 Insert 1296x972-NV12
10-07 14:37:45.512   360   459 I libcamera:  INFO Camera camera.cpp:945 configuring streams: (0) 2592x1944-NV12 (1) 1296x972-NV12
10-07 14:37:45.513   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR8 Score: 8832 (best 8832)
10-07 14:37:45.513   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 640x480 fmt SBGGR10 Score: 7832 (best 7832)
10-07 14:37:45.513   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1296x972 fmt SBGGR10 Score: 5536 (best 5536)
10-07 14:37:45.513   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 1920x1080 fmt SBGGR10 Score: 4238.67 (best 4238.67)
10-07 14:37:45.514   360   442 I libcamera:  INFO RPI raspberrypi.cpp:165 Format: 2592x1944 fmt SBGGR10 Score: 1000 (best 1000)
10-07 14:37:45.515   360   442 I libcamera:  INFO RPI raspberrypi.cpp:677 Sensor: /base/soc/i2c0mux/i2c@1/ov5647@36 - Selected sensor format: 2592x1944-SBGGR10_1X10 - Selected unicam format: 2592x1944-pBAA
10-07 14:37:45.516   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output0 to 2592x1944-NV12
10-07 14:37:45.517   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:708 Setting ISP Output1 to 1296x972-NV12
10-07 14:37:45.527   360   442 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 19989.26us (Shutter lines: 615, AGC requested 20000.00us) Gain: 1 (Gain Code: 16)
10-07 14:37:45.875   360   459 D libcamera: DEBUG Request request.cpp:91 Created request - cookie: 12970367415358303648
10-07 14:37:45.876   360   459 D libcamera: DEBUG HAL camera_device.cpp:899 '/base/soc/i2c0mux/i2c@1/ov5647@36': Queueing request 12970367415358303648 with 1 streams
10-07 14:37:45.876   360   459 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:45.876   360   459 D libcamera: DEBUG HAL camera_device.cpp:947 '/base/soc/i2c0mux/i2c@1/ov5647@36': 0 - (1296x972)[0x00000022] -> (1296x972)[NV12] (direct)
10-07 14:37:45.877   360   459 D libcamera: DEBUG Camera camera.cpp:1084 Starting capture
10-07 14:37:46.058   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1177 /dev/video0[15:cap]: 3 buffers requested.
10-07 14:37:46.058   360   442 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.059   360   442 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.059   360   442 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.060   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1177 /dev/video0[15:cap]: 0 buffers requested.
10-07 14:37:46.060   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1177 /dev/video0[15:cap]: 3 buffers requested.
10-07 14:37:46.060   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1444 /dev/video0[15:cap]: Prepared to import 3 buffers
10-07 14:37:46.062   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1177 /dev/video13[16:out]: 3 buffers requested.
10-07 14:37:46.062   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1444 /dev/video13[16:out]: Prepared to import 3 buffers
10-07 14:37:46.249   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1177 /dev/video14[17:cap]: 3 buffers requested.
10-07 14:37:46.250   360   442 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.250   360   442 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.250   360   442 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.251   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1177 /dev/video14[17:cap]: 0 buffers requested.
10-07 14:37:46.253   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1177 /dev/video14[17:cap]: 3 buffers requested.
10-07 14:37:46.254   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1444 /dev/video14[17:cap]: Prepared to import 3 buffers
10-07 14:37:46.283   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1177 /dev/video15[18:cap]: 3 buffers requested.
10-07 14:37:46.283   360   442 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.283   360   442 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.284   360   442 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.284   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1177 /dev/video15[18:cap]: 0 buffers requested.
10-07 14:37:46.287   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1177 /dev/video15[18:cap]: 3 buffers requested.
10-07 14:37:46.287   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1444 /dev/video15[18:cap]: Prepared to import 3 buffers
10-07 14:37:46.294   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1177 /dev/video16[19:cap]: 3 buffers requested.
10-07 14:37:46.294   360   442 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.295   360   442 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.295   360   442 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.295   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1177 /dev/video16[19:cap]: 0 buffers requested.
10-07 14:37:46.296   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1177 /dev/video16[19:cap]: 3 buffers requested.
10-07 14:37:46.296   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1444 /dev/video16[19:cap]: Prepared to import 3 buffers
10-07 14:37:46.296   360   442 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.297   360   442 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.297   360   442 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.298   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:447 ev 1 fixed_shutter 0.00us fixed_analogue_gain 0
10-07 14:37:46.299   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:480 exposure_mode normal constraint_mode normal metering_mode centre-weighted
10-07 14:37:46.299   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:779 Output written, total exposure requested is 0.00us
10-07 14:37:46.299   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:781 Camera exposure update: shutter time 1000.00us analogue gain 1
10-07 14:37:46.300   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:273 AWB results found, using 4000
10-07 14:37:46.300   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:413 ct is 4000, interpolating between 3000 and 5000
10-07 14:37:46.300   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:413 ct is 4000, interpolating between 3000 and 5000
10-07 14:37:46.300   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 975.09us (Shutter lines: 30, AGC requested 1000.00us) Gain: 1 (Gain Code: 16)
10-07 14:37:46.301   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:273 Drop 8 frames on startup
10-07 14:37:46.303   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 0 for Unicam Image
10-07 14:37:46.304   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video0[15:cap]: Queueing buffer 0
10-07 14:37:46.304   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1 for Unicam Image
10-07 14:37:46.304   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video0[15:cap]: Queueing buffer 1
10-07 14:37:46.304   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 2 for Unicam Image
10-07 14:37:46.307   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video0[15:cap]: Queueing buffer 2
10-07 14:37:46.308   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 0 for ISP Output0
10-07 14:37:46.308   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video14[17:cap]: Queueing buffer 0
10-07 14:37:46.308   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1 for ISP Output0
10-07 14:37:46.309   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video14[17:cap]: Queueing buffer 1
10-07 14:37:46.310   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 2 for ISP Output0
10-07 14:37:46.310   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video14[17:cap]: Queueing buffer 2
10-07 14:37:46.310   360   442 I libcamera:  INFO RPISTREAM rpi_stream.cpp:122 No buffers available for ISP Output0
10-07 14:37:46.310   360   442 I libcamera:  INFO RPISTREAM rpi_stream.cpp:122 No buffers available for ISP Output0
10-07 14:37:46.310   360   442 I libcamera:  INFO RPISTREAM rpi_stream.cpp:122 No buffers available for ISP Output0
10-07 14:37:46.311   360   442 I libcamera:  INFO RPISTREAM rpi_stream.cpp:122 No buffers available for ISP Output0
10-07 14:37:46.311   360   442 I libcamera:  INFO RPISTREAM rpi_stream.cpp:122 No buffers available for ISP Output0
10-07 14:37:46.311   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 0 for ISP Output1
10-07 14:37:46.311   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video15[18:cap]: Queueing buffer 0
10-07 14:37:46.311   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1 for ISP Output1
10-07 14:37:46.312   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video15[18:cap]: Queueing buffer 1
10-07 14:37:46.312   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 2 for ISP Output1
10-07 14:37:46.312   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video15[18:cap]: Queueing buffer 2
10-07 14:37:46.312   360   442 I libcamera:  INFO RPISTREAM rpi_stream.cpp:122 No buffers available for ISP Output1
10-07 14:37:46.313   360   442 I libcamera:  INFO RPISTREAM rpi_stream.cpp:122 No buffers available for ISP Output1
10-07 14:37:46.313   360   442 I libcamera:  INFO RPISTREAM rpi_stream.cpp:122 No buffers available for ISP Output1
10-07 14:37:46.313   360   442 I libcamera:  INFO RPISTREAM rpi_stream.cpp:122 No buffers available for ISP Output1
10-07 14:37:46.313   360   442 I libcamera:  INFO RPISTREAM rpi_stream.cpp:122 No buffers available for ISP Output1
10-07 14:37:46.313   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 0 for ISP Stats
10-07 14:37:46.314   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video16[19:cap]: Queueing buffer 0
10-07 14:37:46.314   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1 for ISP Stats
10-07 14:37:46.314   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video16[19:cap]: Queueing buffer 1
10-07 14:37:46.314   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 2 for ISP Stats
10-07 14:37:46.315   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video16[19:cap]: Queueing buffer 2
10-07 14:37:46.393   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 0
10-07 14:37:46.393   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 0 started
10-07 14:37:46.393   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:955 queueRequestDevice: New request.
10-07 14:37:46.393   360   442 I libcamera:  INFO RPISTREAM rpi_stream.cpp:122 No buffers available for ISP Output0
10-07 14:37:46.427   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video0[15:cap]: Dequeuing buffer 0
10-07 14:37:46.427   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1481 Stream Unicam Image buffer dequeue, buffer id 0, timestamp: 81025009000
10-07 14:37:46.428   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Analogue Gain to 16 at index 0
10-07 14:37:46.428   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Exposure to 30 at index 0
10-07 14:37:46.428   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Vertical Blanking to 24 at index 0
10-07 14:37:46.428   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1832 Could not find matching embedded buffer
10-07 14:37:46.429   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1839 Returning bayer frame without a match
10-07 14:37:46.429   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1776 Signalling signalIspPrepare: Bayer buffer id: 0
10-07 14:37:46.429   360  1602 I libcamera:  INFO IPARPI raspberrypi.cpp:626 Request ctrl: ScalerCrop = (0x0)/2592x1944
10-07 14:37:46.429   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 1
10-07 14:37:46.429   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1038 Metadata - Exposure: 975.09us Frame length: 1968 Gain: 1 Aperture: 0 Lens: 0 Flash: 0
10-07 14:37:46.429   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 1 started
10-07 14:37:46.429   360  1602 D libcamera: DEBUG RPiDpc dpc.cpp:44 strength 1
10-07 14:37:46.429   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:46.430   360  1602 D libcamera: DEBUG RPiNoise noise.cpp:64 constant 0 slope 4.25
10-07 14:37:46.430   360  1602 D libcamera: DEBUG RPiGeq geq.cpp:68 offset 401 slope 0.05619 (analogue gain 1 lux 400)
10-07 14:37:46.430   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:49 Noise profile: constant 0 slope 4.25
10-07 14:37:46.430   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:58 programmed constant 0 slope 13.6 strength 0.75
10-07 14:37:46.431   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:268 frame_count 1 speed 1
10-07 14:37:46.431   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:288 Using AWB gains r 1.27095 g 1 b 1.47244
10-07 14:37:46.431   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:332 frame_count 1 speed 1
10-07 14:37:46.431   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:151 colour temperature 4000K
10-07 14:37:46.431   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:153 CCM: 1.82464 -0.449828 -0.37482     -0.454665 1.61834 -0.163677     0.0217996 -0.939713 1.91791
10-07 14:37:46.432   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1068 Applying WB R: 1.27095 B: 1.47244
10-07 14:37:46.441   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1398 Input re-queue to ISP, buffer id 0, timestamp: 81025009000
10-07 14:37:46.441   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer -1 for ISP Input
10-07 14:37:46.443   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video13[16:out]: Queueing buffer 0
10-07 14:37:46.448   360   459 D libcamera: DEBUG Request request.cpp:91 Created request - cookie: 12970367415358302064
10-07 14:37:46.448   360   459 D libcamera: DEBUG HAL camera_device.cpp:899 '/base/soc/i2c0mux/i2c@1/ov5647@36': Queueing request 12970367415358302064 with 1 streams
10-07 14:37:46.449   360   459 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.449   360   459 D libcamera: DEBUG HAL camera_device.cpp:947 '/base/soc/i2c0mux/i2c@1/ov5647@36': 0 - (1296x972)[0x00000022] -> (1296x972)[NV12] (direct)
10-07 14:37:46.450   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:955 queueRequestDevice: New request.
10-07 14:37:46.452   360   442 I libcamera:  INFO RPISTREAM rpi_stream.cpp:122 No buffers available for ISP Output0
10-07 14:37:46.469   360   459 D libcamera: DEBUG Request request.cpp:91 Created request - cookie: 12970367415358302592
10-07 14:37:46.469   360   459 D libcamera: DEBUG HAL camera_device.cpp:899 '/base/soc/i2c0mux/i2c@1/ov5647@36': Queueing request 12970367415358302592 with 1 streams
10-07 14:37:46.469   360   459 D libcamera: DEBUG Buffer framebuffer.cpp:249 Buffer is not contiguous
10-07 14:37:46.470   360   459 D libcamera: DEBUG HAL camera_device.cpp:947 '/base/soc/i2c0mux/i2c@1/ov5647@36': 0 - (1296x972)[0x00000022] -> (1296x972)[NV12] (direct)
10-07 14:37:46.471   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:955 queueRequestDevice: New request.
10-07 14:37:46.472   360   442 I libcamera:  INFO RPISTREAM rpi_stream.cpp:122 No buffers available for ISP Output0
10-07 14:37:46.472   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video13[16:out]: Dequeuing buffer 0
10-07 14:37:46.473   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1509 Stream ISP Input buffer complete, buffer id 0, timestamp: 81025009000
10-07 14:37:46.473   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 0 for Unicam Image
10-07 14:37:46.473   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video0[15:cap]: Queueing buffer 0
10-07 14:37:46.474   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video14[17:cap]: Dequeuing buffer 0
10-07 14:37:46.474   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Output0 buffer complete, buffer id 0, timestamp: 81025009000
10-07 14:37:46.475   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 0 for ISP Output0
10-07 14:37:46.475   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video14[17:cap]: Queueing buffer 0
10-07 14:37:46.476   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video15[18:cap]: Dequeuing buffer 0
10-07 14:37:46.476   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Output1 buffer complete, buffer id 0, timestamp: 81025009000
10-07 14:37:46.477   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 0 for ISP Output1
10-07 14:37:46.477   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video15[18:cap]: Queueing buffer 0
10-07 14:37:46.477   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video16[19:cap]: Dequeuing buffer 0
10-07 14:37:46.478   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Stats buffer complete, buffer id 0, timestamp: 81025009000
10-07 14:37:46.478   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 0 for ISP Stats
10-07 14:37:46.479   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video16[19:cap]: Queueing buffer 0
10-07 14:37:46.479   360   442 I libcamera:  INFO RPI raspberrypi.cpp:1683 Dropping frame at the request of the IPA (7 left)
10-07 14:37:46.491   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video0[15:cap]: Dequeuing buffer 1
10-07 14:37:46.491   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1481 Stream Unicam Image buffer dequeue, buffer id 1, timestamp: 81088986000
10-07 14:37:46.492   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Analogue Gain to 16 at index 0
10-07 14:37:46.492   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Exposure to 30 at index 0
10-07 14:37:46.493   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Vertical Blanking to 24 at index 0
10-07 14:37:46.493   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1832 Could not find matching embedded buffer
10-07 14:37:46.493   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1839 Returning bayer frame without a match
10-07 14:37:46.494   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1776 Signalling signalIspPrepare: Bayer buffer id: 1
10-07 14:37:46.494   360  1602 I libcamera:  INFO IPARPI raspberrypi.cpp:626 Request ctrl: ScalerCrop = (0x0)/2592x1944
10-07 14:37:46.494   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1038 Metadata - Exposure: 975.09us Frame length: 1968 Gain: 1 Aperture: 0 Lens: 0 Flash: 0
10-07 14:37:46.494   360  1602 D libcamera: DEBUG RPiDpc dpc.cpp:44 strength 1
10-07 14:37:46.494   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 2
10-07 14:37:46.495   360  1602 D libcamera: DEBUG RPiNoise noise.cpp:64 constant 0 slope 4.25
10-07 14:37:46.495   360  1602 D libcamera: DEBUG RPiGeq geq.cpp:68 offset 401 slope 0.05619 (analogue gain 1 lux 400)
10-07 14:37:46.495   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:49 Noise profile: constant 0 slope 4.25
10-07 14:37:46.495   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:58 programmed constant 0 slope 13.6 strength 0.75
10-07 14:37:46.495   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:268 frame_count 2 speed 1
10-07 14:37:46.496   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:288 Using AWB gains r 1.27095 g 1 b 1.47244
10-07 14:37:46.496   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:332 frame_count 2 speed 1
10-07 14:37:46.496   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:151 colour temperature 4000K
10-07 14:37:46.496   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:153 CCM: 1.82464 -0.449828 -0.37482     -0.454665 1.61834 -0.163677     0.0217996 -0.939713 1.91791
10-07 14:37:46.496   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 2 started
10-07 14:37:46.497   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1068 Applying WB R: 1.27095 B: 1.47244
10-07 14:37:46.497   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:46.498   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1398 Input re-queue to ISP, buffer id 1, timestamp: 81088986000
10-07 14:37:46.498   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer -1 for ISP Input
10-07 14:37:46.499   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video13[16:out]: Queueing buffer 1
10-07 14:37:46.527   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video13[16:out]: Dequeuing buffer 1
10-07 14:37:46.527   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1509 Stream ISP Input buffer complete, buffer id 1, timestamp: 81088986000
10-07 14:37:46.528   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1 for Unicam Image
10-07 14:37:46.528   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video0[15:cap]: Queueing buffer 1
10-07 14:37:46.528   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video14[17:cap]: Dequeuing buffer 1
10-07 14:37:46.529   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Output0 buffer complete, buffer id 1, timestamp: 81088986000
10-07 14:37:46.529   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1 for ISP Output0
10-07 14:37:46.529   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video14[17:cap]: Queueing buffer 1
10-07 14:37:46.530   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video15[18:cap]: Dequeuing buffer 1
10-07 14:37:46.530   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Output1 buffer complete, buffer id 1, timestamp: 81088986000
10-07 14:37:46.531   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1 for ISP Output1
10-07 14:37:46.531   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video15[18:cap]: Queueing buffer 1
10-07 14:37:46.531   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video16[19:cap]: Dequeuing buffer 1
10-07 14:37:46.532   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Stats buffer complete, buffer id 1, timestamp: 81088986000
10-07 14:37:46.532   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1 for ISP Stats
10-07 14:37:46.533   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video16[19:cap]: Queueing buffer 1
10-07 14:37:46.533   360   442 I libcamera:  INFO RPI raspberrypi.cpp:1683 Dropping frame at the request of the IPA (6 left)
10-07 14:37:46.555   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video0[15:cap]: Dequeuing buffer 2
10-07 14:37:46.556   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1481 Stream Unicam Image buffer dequeue, buffer id 2, timestamp: 81152940000
10-07 14:37:46.556   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Analogue Gain to 16 at index 0
10-07 14:37:46.556   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Exposure to 30 at index 0
10-07 14:37:46.557   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Vertical Blanking to 24 at index 0
10-07 14:37:46.558   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1832 Could not find matching embedded buffer
10-07 14:37:46.558   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1839 Returning bayer frame without a match
10-07 14:37:46.558   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1776 Signalling signalIspPrepare: Bayer buffer id: 2
10-07 14:37:46.559   360  1602 I libcamera:  INFO IPARPI raspberrypi.cpp:626 Request ctrl: ScalerCrop = (0x0)/2592x1944
10-07 14:37:46.559   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1038 Metadata - Exposure: 975.09us Frame length: 1968 Gain: 1 Aperture: 0 Lens: 0 Flash: 0
10-07 14:37:46.559   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 3
10-07 14:37:46.559   360  1602 D libcamera: DEBUG RPiDpc dpc.cpp:44 strength 1
10-07 14:37:46.560   360  1602 D libcamera: DEBUG RPiNoise noise.cpp:64 constant 0 slope 4.25
10-07 14:37:46.560   360  1602 D libcamera: DEBUG RPiGeq geq.cpp:68 offset 401 slope 0.05619 (analogue gain 1 lux 400)
10-07 14:37:46.560   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:49 Noise profile: constant 0 slope 4.25
10-07 14:37:46.560   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:58 programmed constant 0 slope 13.6 strength 0.75
10-07 14:37:46.560   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:268 frame_count 3 speed 1
10-07 14:37:46.560   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:288 Using AWB gains r 1.27095 g 1 b 1.47244
10-07 14:37:46.561   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:332 frame_count 3 speed 1
10-07 14:37:46.561   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:151 colour temperature 4000K
10-07 14:37:46.561   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:153 CCM: 1.82464 -0.449828 -0.37482     -0.454665 1.61834 -0.163677     0.0217996 -0.939713 1.91791
10-07 14:37:46.561   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1068 Applying WB R: 1.27095 B: 1.47244
10-07 14:37:46.561   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 3 started
10-07 14:37:46.562   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:46.564   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1398 Input re-queue to ISP, buffer id 2, timestamp: 81152940000
10-07 14:37:46.565   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer -1 for ISP Input
10-07 14:37:46.565   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video13[16:out]: Queueing buffer 2
10-07 14:37:46.594   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video13[16:out]: Dequeuing buffer 2
10-07 14:37:46.594   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1509 Stream ISP Input buffer complete, buffer id 2, timestamp: 81152940000
10-07 14:37:46.594   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 2 for Unicam Image
10-07 14:37:46.594   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video0[15:cap]: Queueing buffer 2
10-07 14:37:46.595   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video14[17:cap]: Dequeuing buffer 2
10-07 14:37:46.595   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Output0 buffer complete, buffer id 2, timestamp: 81152940000
10-07 14:37:46.595   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 2 for ISP Output0
10-07 14:37:46.595   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video14[17:cap]: Queueing buffer 2
10-07 14:37:46.595   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video15[18:cap]: Dequeuing buffer 2
10-07 14:37:46.596   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Output1 buffer complete, buffer id 2, timestamp: 81152940000
10-07 14:37:46.596   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 2 for ISP Output1
10-07 14:37:46.596   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video15[18:cap]: Queueing buffer 2
10-07 14:37:46.597   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video16[19:cap]: Dequeuing buffer 2
10-07 14:37:46.597   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Stats buffer complete, buffer id 2, timestamp: 81152940000
10-07 14:37:46.598   360  1602 D libcamera: DEBUG RPiLux lux.cpp:89 : estimated lux 626.441
10-07 14:37:46.598   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:299 frame_phase 1
10-07 14:37:46.598   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:309 Awb lux value is 626.441
10-07 14:37:46.599   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:240 Starting AWB calculation
10-07 14:37:46.599   360   455 D libcamera: DEBUG RPiAwb awb.cpp:628 Valid zones: 0
10-07 14:37:46.600   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:447 ev 1 fixed_shutter 0.00us fixed_analogue_gain 0
10-07 14:37:46.600   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:480 exposure_mode normal constraint_mode normal metering_mode centre-weighted
10-07 14:37:46.600   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.000499534 target 0.203915 gives gain 10
10-07 14:37:46.601   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.00499534 target 0.203915 gives gain 100
10-07 14:37:46.601   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.0499534 target 0.203915 gives gain 400.2
10-07 14:37:46.601   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.199913 target 0.203915 gives gain 406.179
10-07 14:37:46.602   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.2029 target 0.203915 gives gain 406.209
10-07 14:37:46.602   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:589 Constraint has target_Y 0.625 giving gain 159.932
10-07 14:37:46.602   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:601 Final gain 406.209 (target_Y 0.203915 ev 1 base_ev 1.25)
10-07 14:37:46.603   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:635 Target total_exposure 396088.60us
10-07 14:37:46.603   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:645 after AWB, target dg 1 gain 406.209 target_Y 0.203915
10-07 14:37:46.603   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:658 Digital gain 1 desaturate? 0
10-07 14:37:46.603   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:660 Target total_exposure_no_dg 396088.60us
10-07 14:37:46.604   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:700 After filtering, total_exposure 396088.60us no dg 396088.60us
10-07 14:37:46.604   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:745 Divided up shutter and gain are 66014.77us and 6
10-07 14:37:46.604   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:779 Output written, total exposure requested is 396088.60us
10-07 14:37:46.605   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:781 Camera exposure update: shutter time 66014.77us analogue gain 6
10-07 14:37:46.605   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:361 frame_phase 12
10-07 14:37:46.605   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:296 Starting ALSC calculation
10-07 14:37:46.606   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:273 AWB results found, using 4000
10-07 14:37:46.606   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:105 Move histogram point 5.11985 to 983.04
10-07 14:37:46.606   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:413 ct is 4000, interpolating between 3000 and 5000
10-07 14:37:46.606   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:413 ct is 4000, interpolating between 3000 and 5000
10-07 14:37:46.606   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:110 Final values 983.04 -> 983.04
10-07 14:37:46.607   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:123 Move histogram point 486.402 to 62259.2
10-07 14:37:46.607   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:666 Stop after 1 iterations
10-07 14:37:46.607   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:666 Stop after 1 iterations
10-07 14:37:46.607   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:128 Final values 60259.2 -> 62259.2
10-07 14:37:46.608   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 66013.30us (Shutter lines: 2031, AGC requested 66014.77us) Gain: 6 (Gain Code: 96)
10-07 14:37:46.608   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Vertical Blanking to 91 at index 4
10-07 14:37:46.608   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Exposure to 2031 at index 4
10-07 14:37:46.609   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Analogue Gain to 96 at index 4
10-07 14:37:46.609   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 2 for ISP Stats
10-07 14:37:46.609   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video16[19:cap]: Queueing buffer 2
10-07 14:37:46.609   360   442 I libcamera:  INFO RPI raspberrypi.cpp:1683 Dropping frame at the request of the IPA (5 left)
10-07 14:37:46.619   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video0[15:cap]: Dequeuing buffer 0
10-07 14:37:46.619   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1481 Stream Unicam Image buffer dequeue, buffer id 0, timestamp: 81216906000
10-07 14:37:46.619   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Analogue Gain to 16 at index 1
10-07 14:37:46.619   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Exposure to 30 at index 1
10-07 14:37:46.620   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Vertical Blanking to 24 at index 1
10-07 14:37:46.620   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1832 Could not find matching embedded buffer
10-07 14:37:46.620   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1839 Returning bayer frame without a match
10-07 14:37:46.620   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1776 Signalling signalIspPrepare: Bayer buffer id: 0
10-07 14:37:46.621   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 4
10-07 14:37:46.621   360  1602 I libcamera:  INFO IPARPI raspberrypi.cpp:626 Request ctrl: ScalerCrop = (0x0)/2592x1944
10-07 14:37:46.621   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 4 started
10-07 14:37:46.621   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Analogue Gain to 96 at index 4
10-07 14:37:46.621   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Exposure to 2031 at index 4
10-07 14:37:46.621   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1038 Metadata - Exposure: 975.09us Frame length: 1968 Gain: 1 Aperture: 0 Lens: 0 Flash: 0
10-07 14:37:46.621   360  1602 D libcamera: DEBUG RPiDpc dpc.cpp:44 strength 1
10-07 14:37:46.622   360  1602 D libcamera: DEBUG RPiNoise noise.cpp:64 constant 0 slope 4.25
10-07 14:37:46.622   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Vertical Blanking to 91 at index 4
10-07 14:37:46.622   360  1602 D libcamera: DEBUG RPiGeq geq.cpp:68 offset 401 slope 0.05619 (analogue gain 1 lux 626.441)
10-07 14:37:46.623   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:49 Noise profile: constant 0 slope 4.25
10-07 14:37:46.623   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:58 programmed constant 0 slope 13.6 strength 0.75
10-07 14:37:46.624   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:268 frame_count 4 speed 1
10-07 14:37:46.624   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:229 Fetch AWB results
10-07 14:37:46.624   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:288 Using AWB gains r 1.27095 g 1 b 1.47244
10-07 14:37:46.624   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:352 Want total exposure 396088.60us
10-07 14:37:46.625   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:358 Actual exposure 975.09us
10-07 14:37:46.625   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:359 Use digital_gain 4
10-07 14:37:46.625   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:360 Effective exposure 3900.34us
10-07 14:37:46.626   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:430 Lock count updated to 0
10-07 14:37:46.626   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:332 frame_count 4 speed 1
10-07 14:37:46.626   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:259 Fetch ALSC results
10-07 14:37:46.627   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:151 colour temperature 4000K
10-07 14:37:46.627   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:153 CCM: 1.82464 -0.449828 -0.37482     -0.454665 1.61834 -0.163677     0.0217996 -0.939713 1.91791
10-07 14:37:46.628   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1068 Applying WB R: 1.27095 B: 1.47244
10-07 14:37:46.631   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1398 Input re-queue to ISP, buffer id 0, timestamp: 81216906000
10-07 14:37:46.631   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer -1 for ISP Input
10-07 14:37:46.631   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video13[16:out]: Queueing buffer 0
10-07 14:37:46.659   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video13[16:out]: Dequeuing buffer 0
10-07 14:37:46.659   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1509 Stream ISP Input buffer complete, buffer id 0, timestamp: 81216906000
10-07 14:37:46.659   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 0 for Unicam Image
10-07 14:37:46.659   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video0[15:cap]: Queueing buffer 0
10-07 14:37:46.660   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video14[17:cap]: Dequeuing buffer 0
10-07 14:37:46.660   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Output0 buffer complete, buffer id 0, timestamp: 81216906000
10-07 14:37:46.660   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 0 for ISP Output0
10-07 14:37:46.660   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video14[17:cap]: Queueing buffer 0
10-07 14:37:46.661   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video15[18:cap]: Dequeuing buffer 0
10-07 14:37:46.661   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Output1 buffer complete, buffer id 0, timestamp: 81216906000
10-07 14:37:46.661   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 0 for ISP Output1
10-07 14:37:46.661   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video15[18:cap]: Queueing buffer 0
10-07 14:37:46.662   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video16[19:cap]: Dequeuing buffer 0
10-07 14:37:46.662   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Stats buffer complete, buffer id 0, timestamp: 81216906000
10-07 14:37:46.662   360  1602 D libcamera: DEBUG RPiLux lux.cpp:89 : estimated lux 626.45
10-07 14:37:46.662   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:299 frame_phase 1
10-07 14:37:46.663   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:309 Awb lux value is 626.45
10-07 14:37:46.663   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:240 Starting AWB calculation
10-07 14:37:46.663   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:447 ev 1 fixed_shutter 0.00us fixed_analogue_gain 0
10-07 14:37:46.663   360   455 D libcamera: DEBUG RPiAwb awb.cpp:628 Valid zones: 0
10-07 14:37:46.663   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:480 exposure_mode normal constraint_mode normal metering_mode centre-weighted
10-07 14:37:46.663   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.000831982 target 0.203915 gives gain 10
10-07 14:37:46.664   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.00831982 target 0.203915 gives gain 100
10-07 14:37:46.664   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.0831982 target 0.203915 gives gain 242.185
10-07 14:37:46.664   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.201493 target 0.203915 gives gain 243.885
10-07 14:37:46.664   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:589 Constraint has target_Y 0.625 giving gain 159.823
10-07 14:37:46.664   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:601 Final gain 243.885 (target_Y 0.203915 ev 1 base_ev 1.25)
10-07 14:37:46.664   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:635 Target total_exposure 237809.06us
10-07 14:37:46.665   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:645 after AWB, target dg 1 gain 243.885 target_Y 0.203915
10-07 14:37:46.665   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:658 Digital gain 1 desaturate? 0
10-07 14:37:46.665   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:660 Target total_exposure_no_dg 237809.06us
10-07 14:37:46.665   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:700 After filtering, total_exposure 237809.06us no dg 237809.06us
10-07 14:37:46.665   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:745 Divided up shutter and gain are 59452.26us and 4
10-07 14:37:46.666   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:779 Output written, total exposure requested is 237809.06us
10-07 14:37:46.666   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:781 Camera exposure update: shutter time 59452.26us analogue gain 4
10-07 14:37:46.666   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:361 frame_phase 1
10-07 14:37:46.666   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:296 Starting ALSC calculation
10-07 14:37:46.666   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:273 AWB results found, using 4000
10-07 14:37:46.667   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:105 Move histogram point 5.11988 to 983.04
10-07 14:37:46.667   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:413 ct is 4000, interpolating between 3000 and 5000
10-07 14:37:46.667   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:110 Final values 983.04 -> 983.04
10-07 14:37:46.667   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:413 ct is 4000, interpolating between 3000 and 5000
10-07 14:37:46.667   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:123 Move histogram point 486.405 to 62259.2
10-07 14:37:46.667   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:128 Final values 60259.2 -> 62259.2
10-07 14:37:46.667   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:666 Stop after 1 iterations
10-07 14:37:46.667   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:666 Stop after 1 iterations
10-07 14:37:46.668   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 59447.73us (Shutter lines: 1829, AGC requested 59452.26us) Gain: 4 (Gain Code: 64)
10-07 14:37:46.668   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Vertical Blanking to 24 at index 5
10-07 14:37:46.668   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Exposure to 1829 at index 5
10-07 14:37:46.668   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Analogue Gain to 64 at index 5
10-07 14:37:46.669   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 0 for ISP Stats
10-07 14:37:46.669   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video16[19:cap]: Queueing buffer 0
10-07 14:37:46.669   360   442 I libcamera:  INFO RPI raspberrypi.cpp:1683 Dropping frame at the request of the IPA (4 left)
10-07 14:37:46.683   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video0[15:cap]: Dequeuing buffer 1
10-07 14:37:46.683   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1481 Stream Unicam Image buffer dequeue, buffer id 1, timestamp: 81280874000
10-07 14:37:46.683   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Analogue Gain to 16 at index 2
10-07 14:37:46.683   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Exposure to 30 at index 2
10-07 14:37:46.684   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Vertical Blanking to 24 at index 2
10-07 14:37:46.684   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1832 Could not find matching embedded buffer
10-07 14:37:46.684   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1839 Returning bayer frame without a match
10-07 14:37:46.684   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1776 Signalling signalIspPrepare: Bayer buffer id: 1
10-07 14:37:46.684   360  1602 I libcamera:  INFO IPARPI raspberrypi.cpp:626 Request ctrl: ScalerCrop = (0x0)/2592x1944
10-07 14:37:46.684   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 5
10-07 14:37:46.685   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1038 Metadata - Exposure: 975.09us Frame length: 1968 Gain: 1 Aperture: 0 Lens: 0 Flash: 0
10-07 14:37:46.685   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 5 started
10-07 14:37:46.685   360  1602 D libcamera: DEBUG RPiDpc dpc.cpp:44 strength 1
10-07 14:37:46.685   360  1602 D libcamera: DEBUG RPiNoise noise.cpp:64 constant 0 slope 4.25
10-07 14:37:46.685   360  1602 D libcamera: DEBUG RPiGeq geq.cpp:68 offset 401 slope 0.05619 (analogue gain 1 lux 626.45)
10-07 14:37:46.685   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:49 Noise profile: constant 0 slope 4.25
10-07 14:37:46.685   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Analogue Gain to 64 at index 5
10-07 14:37:46.686   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:58 programmed constant 0 slope 13.6 strength 0.75
10-07 14:37:46.686   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:268 frame_count 5 speed 1
10-07 14:37:46.686   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Exposure to 1829 at index 5
10-07 14:37:46.686   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:229 Fetch AWB results
10-07 14:37:46.686   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:288 Using AWB gains r 1.27095 g 1 b 1.47244
10-07 14:37:46.686   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:352 Want total exposure 237809.06us
10-07 14:37:46.686   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:358 Actual exposure 975.09us
10-07 14:37:46.687   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:359 Use digital_gain 4
10-07 14:37:46.687   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:360 Effective exposure 3900.34us
10-07 14:37:46.687   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:430 Lock count updated to 0
10-07 14:37:46.687   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:332 frame_count 5 speed 1
10-07 14:37:46.687   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:259 Fetch ALSC results
10-07 14:37:46.688   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:151 colour temperature 4000K
10-07 14:37:46.688   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:153 CCM: 1.82464 -0.449828 -0.37482     -0.454665 1.61834 -0.163677     0.0217996 -0.939713 1.91791
10-07 14:37:46.688   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1068 Applying WB R: 1.27095 B: 1.47244
10-07 14:37:46.688   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Vertical Blanking to 24 at index 5
10-07 14:37:46.692   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1398 Input re-queue to ISP, buffer id 1, timestamp: 81280874000
10-07 14:37:46.692   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer -1 for ISP Input
10-07 14:37:46.692   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video13[16:out]: Queueing buffer 1
10-07 14:37:46.721   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video13[16:out]: Dequeuing buffer 1
10-07 14:37:46.722   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1509 Stream ISP Input buffer complete, buffer id 1, timestamp: 81280874000
10-07 14:37:46.722   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1 for Unicam Image
10-07 14:37:46.722   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video0[15:cap]: Queueing buffer 1
10-07 14:37:46.723   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video14[17:cap]: Dequeuing buffer 1
10-07 14:37:46.724   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Output0 buffer complete, buffer id 1, timestamp: 81280874000
10-07 14:37:46.724   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1 for ISP Output0
10-07 14:37:46.724   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video14[17:cap]: Queueing buffer 1
10-07 14:37:46.725   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video15[18:cap]: Dequeuing buffer 1
10-07 14:37:46.725   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Output1 buffer complete, buffer id 1, timestamp: 81280874000
10-07 14:37:46.726   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1 for ISP Output1
10-07 14:37:46.726   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video15[18:cap]: Queueing buffer 1
10-07 14:37:46.726   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1048579 for ISP Output1
10-07 14:37:46.727   360   442 E libcamera: ERROR RPISTREAM rpi_stream.cpp:231 Failed to queue buffer for ISP Output1
10-07 14:37:46.727   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1048580 for ISP Output1
10-07 14:37:46.727   360   442 E libcamera: ERROR RPISTREAM rpi_stream.cpp:231 Failed to queue buffer for ISP Output1
10-07 14:37:46.727   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1048581 for ISP Output1
10-07 14:37:46.728   360   442 E libcamera: ERROR RPISTREAM rpi_stream.cpp:231 Failed to queue buffer for ISP Output1
10-07 14:37:46.728   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video16[19:cap]: Dequeuing buffer 1
10-07 14:37:46.728   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Stats buffer complete, buffer id 1, timestamp: 81280874000
10-07 14:37:46.729   360  1602 D libcamera: DEBUG RPiLux lux.cpp:89 : estimated lux 626.446
10-07 14:37:46.729   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:299 frame_phase 1
10-07 14:37:46.730   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:309 Awb lux value is 626.446
10-07 14:37:46.730   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:240 Starting AWB calculation
10-07 14:37:46.730   360   455 D libcamera: DEBUG RPiAwb awb.cpp:628 Valid zones: 0
10-07 14:37:46.731   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:447 ev 1 fixed_shutter 0.00us fixed_analogue_gain 0
10-07 14:37:46.731   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:480 exposure_mode normal constraint_mode normal metering_mode centre-weighted
10-07 14:37:46.731   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.000625198 target 0.203915 gives gain 10
10-07 14:37:46.732   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.00625198 target 0.203915 gives gain 100
10-07 14:37:46.732   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.0625198 target 0.203915 gives gain 321.026
10-07 14:37:46.732   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.200705 target 0.203915 gives gain 324.544
10-07 14:37:46.733   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.202904 target 0.203915 gives gain 324.562
10-07 14:37:46.733   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:589 Constraint has target_Y 0.625 giving gain 159.864
10-07 14:37:46.733   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:601 Final gain 324.562 (target_Y 0.203915 ev 1 base_ev 1.25)
10-07 14:37:46.734   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:635 Target total_exposure 316475.31us
10-07 14:37:46.734   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:645 after AWB, target dg 1 gain 324.562 target_Y 0.203915
10-07 14:37:46.734   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:658 Digital gain 1 desaturate? 0
10-07 14:37:46.735   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:660 Target total_exposure_no_dg 316475.31us
10-07 14:37:46.735   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:700 After filtering, total_exposure 316475.31us no dg 316475.31us
10-07 14:37:46.735   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:745 Divided up shutter and gain are 60000.00us and 5.27459
10-07 14:37:46.736   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:779 Output written, total exposure requested is 316475.31us
10-07 14:37:46.736   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:781 Camera exposure update: shutter time 60000.00us analogue gain 5.27459
10-07 14:37:46.736   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:361 frame_phase 1
10-07 14:37:46.737   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:296 Starting ALSC calculation
10-07 14:37:46.737   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:273 AWB results found, using 4000
10-07 14:37:46.737   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:105 Move histogram point 5.11987 to 983.04
10-07 14:37:46.737   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:413 ct is 4000, interpolating between 3000 and 5000
10-07 14:37:46.738   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:110 Final values 983.04 -> 983.04
10-07 14:37:46.738   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:413 ct is 4000, interpolating between 3000 and 5000
10-07 14:37:46.738   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:123 Move histogram point 486.404 to 62259.2
10-07 14:37:46.738   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:666 Stop after 1 iterations
10-07 14:37:46.738   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:128 Final values 60259.2 -> 62259.2
10-07 14:37:46.738   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:666 Stop after 1 iterations
10-07 14:37:46.739   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 59967.77us (Shutter lines: 1845, AGC requested 60000.00us) Gain: 5.27459 (Gain Code: 84)
10-07 14:37:46.740   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Vertical Blanking to 24 at index 6
10-07 14:37:46.741   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Exposure to 1845 at index 6
10-07 14:37:46.742   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Analogue Gain to 84 at index 6
10-07 14:37:46.743   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1 for ISP Stats
10-07 14:37:46.744   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video16[19:cap]: Queueing buffer 1
10-07 14:37:46.744   360   442 I libcamera:  INFO RPI raspberrypi.cpp:1683 Dropping frame at the request of the IPA (3 left)
10-07 14:37:46.747   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video0[15:cap]: Dequeuing buffer 2
10-07 14:37:46.747   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1481 Stream Unicam Image buffer dequeue, buffer id 2, timestamp: 81344844000
10-07 14:37:46.748   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Analogue Gain to 16 at index 3
10-07 14:37:46.748   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Exposure to 30 at index 3
10-07 14:37:46.749   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Vertical Blanking to 24 at index 3
10-07 14:37:46.749   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1832 Could not find matching embedded buffer
10-07 14:37:46.750   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1839 Returning bayer frame without a match
10-07 14:37:46.751   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1776 Signalling signalIspPrepare: Bayer buffer id: 2
10-07 14:37:46.751   360  1602 I libcamera:  INFO IPARPI raspberrypi.cpp:626 Request ctrl: ScalerCrop = (0x0)/2592x1944
10-07 14:37:46.752   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 6
10-07 14:37:46.752   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1038 Metadata - Exposure: 975.09us Frame length: 1968 Gain: 1 Aperture: 0 Lens: 0 Flash: 0
10-07 14:37:46.753   360  1602 D libcamera: DEBUG RPiDpc dpc.cpp:44 strength 1
10-07 14:37:46.753   360  1602 D libcamera: DEBUG RPiNoise noise.cpp:64 constant 0 slope 4.25
10-07 14:37:46.753   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 6 started
10-07 14:37:46.754   360  1602 D libcamera: DEBUG RPiGeq geq.cpp:68 offset 401 slope 0.05619 (analogue gain 1 lux 626.446)
10-07 14:37:46.754   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Analogue Gain to 84 at index 6
10-07 14:37:46.754   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Exposure to 1845 at index 6
10-07 14:37:46.755   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:49 Noise profile: constant 0 slope 4.25
10-07 14:37:46.755   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:58 programmed constant 0 slope 13.6 strength 0.75
10-07 14:37:46.756   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Vertical Blanking to 24 at index 6
10-07 14:37:46.756   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:268 frame_count 6 speed 1
10-07 14:37:46.756   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:229 Fetch AWB results
10-07 14:37:46.756   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:288 Using AWB gains r 1.27095 g 1 b 1.47244
10-07 14:37:46.757   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:352 Want total exposure 316475.31us
10-07 14:37:46.757   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:358 Actual exposure 975.09us
10-07 14:37:46.758   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:359 Use digital_gain 4
10-07 14:37:46.758   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:360 Effective exposure 3900.34us
10-07 14:37:46.759   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:430 Lock count updated to 0
10-07 14:37:46.759   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:332 frame_count 6 speed 1
10-07 14:37:46.760   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:259 Fetch ALSC results
10-07 14:37:46.760   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:151 colour temperature 4000K
10-07 14:37:46.761   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:153 CCM: 1.82464 -0.449828 -0.37482     -0.454665 1.61834 -0.163677     0.0217996 -0.939713 1.91791
10-07 14:37:46.761   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1068 Applying WB R: 1.27095 B: 1.47244
10-07 14:37:46.763   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1398 Input re-queue to ISP, buffer id 2, timestamp: 81344844000
10-07 14:37:46.763   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer -1 for ISP Input
10-07 14:37:46.763   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video13[16:out]: Queueing buffer 2
10-07 14:37:46.791   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video13[16:out]: Dequeuing buffer 2
10-07 14:37:46.791   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1509 Stream ISP Input buffer complete, buffer id 2, timestamp: 81344844000
10-07 14:37:46.791   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 2 for Unicam Image
10-07 14:37:46.791   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video0[15:cap]: Queueing buffer 2
10-07 14:37:46.792   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video14[17:cap]: Dequeuing buffer 2
10-07 14:37:46.792   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Output0 buffer complete, buffer id 2, timestamp: 81344844000
10-07 14:37:46.792   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 2 for ISP Output0
10-07 14:37:46.792   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video14[17:cap]: Queueing buffer 2
10-07 14:37:46.792   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video15[18:cap]: Dequeuing buffer 2
10-07 14:37:46.793   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Output1 buffer complete, buffer id 2, timestamp: 81344844000
10-07 14:37:46.793   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video16[19:cap]: Dequeuing buffer 2
10-07 14:37:46.793   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Stats buffer complete, buffer id 2, timestamp: 81344844000
10-07 14:37:46.793   360  1602 D libcamera: DEBUG RPiLux lux.cpp:89 : estimated lux 626.451
10-07 14:37:46.794   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:299 frame_phase 1
10-07 14:37:46.794   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:309 Awb lux value is 626.451
10-07 14:37:46.794   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:240 Starting AWB calculation
10-07 14:37:46.794   360   455 D libcamera: DEBUG RPiAwb awb.cpp:628 Valid zones: 0
10-07 14:37:46.795   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:447 ev 1 fixed_shutter 0.00us fixed_analogue_gain 0
10-07 14:37:46.795   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:480 exposure_mode normal constraint_mode normal metering_mode centre-weighted
10-07 14:37:46.795   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.000587058 target 0.203915 gives gain 10
10-07 14:37:46.795   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.00587058 target 0.203915 gives gain 100
10-07 14:37:46.795   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.0587058 target 0.203915 gives gain 341.534
10-07 14:37:46.796   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.2005 target 0.203915 gives gain 345.628
10-07 14:37:46.796   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.202903 target 0.203915 gives gain 345.648
10-07 14:37:46.796   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:589 Constraint has target_Y 0.625 giving gain 159.809
10-07 14:37:46.796   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:601 Final gain 345.648 (target_Y 0.203915 ev 1 base_ev 1.25)
10-07 14:37:46.796   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:635 Target total_exposure 337036.36us
10-07 14:37:46.797   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:645 after AWB, target dg 1 gain 345.648 target_Y 0.203915
10-07 14:37:46.797   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:658 Digital gain 1 desaturate? 0
10-07 14:37:46.797   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:660 Target total_exposure_no_dg 337036.36us
10-07 14:37:46.797   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:700 After filtering, total_exposure 337036.36us no dg 337036.36us
10-07 14:37:46.797   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:745 Divided up shutter and gain are 60000.00us and 5.61727
10-07 14:37:46.798   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:779 Output written, total exposure requested is 337036.36us
10-07 14:37:46.798   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:781 Camera exposure update: shutter time 60000.00us analogue gain 5.61727
10-07 14:37:46.798   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:361 frame_phase 1
10-07 14:37:46.798   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:296 Starting ALSC calculation
10-07 14:37:46.798   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:273 AWB results found, using 4000
10-07 14:37:46.799   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:105 Move histogram point 5.11989 to 983.04
10-07 14:37:46.799   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:413 ct is 4000, interpolating between 3000 and 5000
10-07 14:37:46.799   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:110 Final values 983.04 -> 983.04
10-07 14:37:46.799   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:413 ct is 4000, interpolating between 3000 and 5000
10-07 14:37:46.799   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:123 Move histogram point 486.406 to 62259.2
10-07 14:37:46.799   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:666 Stop after 1 iterations
10-07 14:37:46.799   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:128 Final values 60259.2 -> 62259.2
10-07 14:37:46.799   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:666 Stop after 1 iterations
10-07 14:37:46.799   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 59967.77us (Shutter lines: 1845, AGC requested 60000.00us) Gain: 5.61727 (Gain Code: 89)
10-07 14:37:46.800   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Vertical Blanking to 24 at index 7
10-07 14:37:46.800   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Exposure to 1845 at index 7
10-07 14:37:46.800   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Analogue Gain to 89 at index 7
10-07 14:37:46.801   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 2 for ISP Stats
10-07 14:37:46.801   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video16[19:cap]: Queueing buffer 2
10-07 14:37:46.801   360   442 I libcamera:  INFO RPI raspberrypi.cpp:1683 Dropping frame at the request of the IPA (2 left)
10-07 14:37:46.813   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video0[15:cap]: Dequeuing buffer 0
10-07 14:37:46.813   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1481 Stream Unicam Image buffer dequeue, buffer id 0, timestamp: 81410989000
10-07 14:37:46.813   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Analogue Gain to 96 at index 4
10-07 14:37:46.814   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Exposure to 2031 at index 4
10-07 14:37:46.814   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Vertical Blanking to 91 at index 4
10-07 14:37:46.814   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1832 Could not find matching embedded buffer
10-07 14:37:46.814   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1839 Returning bayer frame without a match
10-07 14:37:46.814   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1776 Signalling signalIspPrepare: Bayer buffer id: 0
10-07 14:37:46.815   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 7
10-07 14:37:46.815   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 7 started
10-07 14:37:46.815   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Analogue Gain to 89 at index 7
10-07 14:37:46.815   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Exposure to 1845 at index 7
10-07 14:37:46.815   360  1602 I libcamera:  INFO IPARPI raspberrypi.cpp:626 Request ctrl: ScalerCrop = (0x0)/2592x1944
10-07 14:37:46.815   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Vertical Blanking to 24 at index 7
10-07 14:37:46.816   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1038 Metadata - Exposure: 66013.30us Frame length: 2035 Gain: 6 Aperture: 0 Lens: 0 Flash: 0
10-07 14:37:46.816   360  1602 D libcamera: DEBUG RPiDpc dpc.cpp:44 strength 1
10-07 14:37:46.816   360  1602 D libcamera: DEBUG RPiNoise noise.cpp:64 constant 0 slope 10.4103
10-07 14:37:46.817   360  1602 D libcamera: DEBUG RPiGeq geq.cpp:68 offset 2406 slope 0.33714 (analogue gain 6 lux 626.451)
10-07 14:37:46.817   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:49 Noise profile: constant 0 slope 10.4103
10-07 14:37:46.818   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:58 programmed constant 0 slope 33.3131 strength 0.75
10-07 14:37:46.818   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:268 frame_count 7 speed 1
10-07 14:37:46.818   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:229 Fetch AWB results
10-07 14:37:46.818   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:288 Using AWB gains r 1.27095 g 1 b 1.47244
10-07 14:37:46.818   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:352 Want total exposure 337036.36us
10-07 14:37:46.819   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:358 Actual exposure 396079.82us
10-07 14:37:46.819   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:359 Use digital_gain 1
10-07 14:37:46.819   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:360 Effective exposure 396079.82us
10-07 14:37:46.819   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:430 Lock count updated to 0
10-07 14:37:46.819   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:332 frame_count 7 speed 1
10-07 14:37:46.820   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:259 Fetch ALSC results
10-07 14:37:46.820   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:151 colour temperature 4000K
10-07 14:37:46.820   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:153 CCM: 1.82464 -0.449828 -0.37482     -0.454665 1.61834 -0.163677     0.0217996 -0.939713 1.91791
10-07 14:37:46.820   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1068 Applying WB R: 1.27095 B: 1.47244
10-07 14:37:46.822   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1398 Input re-queue to ISP, buffer id 0, timestamp: 81410989000
10-07 14:37:46.822   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer -1 for ISP Input
10-07 14:37:46.823   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video13[16:out]: Queueing buffer 0
10-07 14:37:46.850   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video13[16:out]: Dequeuing buffer 0
10-07 14:37:46.851   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1509 Stream ISP Input buffer complete, buffer id 0, timestamp: 81410989000
10-07 14:37:46.851   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 0 for Unicam Image
10-07 14:37:46.851   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video0[15:cap]: Queueing buffer 0
10-07 14:37:46.851   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video14[17:cap]: Dequeuing buffer 0
10-07 14:37:46.851   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Output0 buffer complete, buffer id 0, timestamp: 81410989000
10-07 14:37:46.852   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 0 for ISP Output0
10-07 14:37:46.852   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video14[17:cap]: Queueing buffer 0
10-07 14:37:46.852   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video15[18:cap]: Dequeuing buffer 0
10-07 14:37:46.852   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Output1 buffer complete, buffer id 0, timestamp: 81410989000
10-07 14:37:46.853   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video16[19:cap]: Dequeuing buffer 0
10-07 14:37:46.853   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Stats buffer complete, buffer id 0, timestamp: 81410989000
10-07 14:37:46.853   360  1602 D libcamera: DEBUG RPiLux lux.cpp:89 : estimated lux 164.332
10-07 14:37:46.854   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:299 frame_phase 1
10-07 14:37:46.854   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:309 Awb lux value is 164.332
10-07 14:37:46.854   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:240 Starting AWB calculation
10-07 14:37:46.854   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:447 ev 1 fixed_shutter 0.00us fixed_analogue_gain 0
10-07 14:37:46.854   360   455 D libcamera: DEBUG RPiAwb awb.cpp:628 Valid zones: 189
10-07 14:37:46.854   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:480 exposure_mode normal constraint_mode normal metering_mode centre-weighted
10-07 14:37:46.855   360   455 D libcamera: DEBUG RPiAwb awb.cpp:566 (2000,0.78217)
10-07 14:37:46.855   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.389813 target 0.201027 gives gain 0.514382
10-07 14:37:46.855   360   455 D libcamera: DEBUG RPiAwb awb.cpp:566 (3000,0.101103)
10-07 14:37:46.855   360   455 D libcamera: DEBUG RPiAwb awb.cpp:566 (6000,0.40441)
10-07 14:37:46.855   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:589 Constraint has target_Y 0.625 giving gain 0.627815
10-07 14:37:46.855   360   455 D libcamera: DEBUG RPiAwb awb.cpp:566 (13000,0.40441)
10-07 14:37:46.855   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:593 Lower bound constraint adopted
10-07 14:37:46.855   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 2500 gain_r 0.971912 gain_b 2.22074 delta2_sum 37.8 prior 0.441636 final 37.3584
10-07 14:37:46.855   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:601 Final gain 0.627815 (target_Y 0.625 ev 1 base_ev 1.25)
10-07 14:37:46.856   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 2550 gain_r 0.985521 gain_b 2.17257 delta2_sum 37.7394 prior 0.407583 final 37.3319
10-07 14:37:46.856   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:635 Target total_exposure 248664.83us
10-07 14:37:46.856   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 2601 gain_r 0.9998 gain_b 2.12555 delta2_sum 36.9411 prior 0.372848 final 36.5682
10-07 14:37:46.856   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:645 after AWB, target dg 1 gain 0.627815 target_Y 0.625
10-07 14:37:46.856   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:658 Digital gain 2.5 desaturate? 1
10-07 14:37:46.856   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 2653.02 gain_r 1.0148 gain_b 2.07964 delta2_sum 35.1704 prior 0.337419 final 34.8329
10-07 14:37:46.856   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:660 Target total_exposure_no_dg 99465.93us
10-07 14:37:46.856   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 2706.08 gain_r 1.03057 gain_b 2.0348 delta2_sum 32.8311 prior 0.301282 final 32.5298
10-07 14:37:46.856   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:700 After filtering, total_exposure 248664.83us no dg 99465.93us
10-07 14:37:46.856   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 2760.2 gain_r 1.04716 gain_b 1.99102 delta2_sum 30.5062 prior 0.264421 final 30.2417
10-07 14:37:46.857   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:745 Divided up shutter and gain are 30000.00us and 3.31553
10-07 14:37:46.857   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 2815.41 gain_r 1.06095 gain_b 1.9569 delta2_sum 28.6845 prior 0.226823 final 28.4577
10-07 14:37:46.857   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:779 Output written, total exposure requested is 248664.83us
10-07 14:37:46.857   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 2871.71 gain_r 1.0622 gain_b 1.95322 delta2_sum 28.4941 prior 0.188474 final 28.3056
10-07 14:37:46.857   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:781 Camera exposure update: shutter time 30000.00us analogue gain 3.31553
10-07 14:37:46.857   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 2929.15 gain_r 1.066 gain_b 1.94115 delta2_sum 27.8711 prior 0.149357 final 27.7218
10-07 14:37:46.857   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:361 frame_phase 1
10-07 14:37:46.857   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 2987.73 gain_r 1.07714 gain_b 1.90595 delta2_sum 26.0077 prior 0.109458 final 25.8982
10-07 14:37:46.857   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:296 Starting ALSC calculation
10-07 14:37:46.857   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:273 AWB results found, using 4000
10-07 14:37:46.857   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 3047.49 gain_r 1.08875 gain_b 1.87135 delta2_sum 24.1761 prior 0.105904 final 24.0702
10-07 14:37:46.858   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 3108.44 gain_r 1.10086 gain_b 1.83732 delta2_sum 22.3716 prior 0.112066 final 22.2596
10-07 14:37:46.858   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:105 Move histogram point 2283 to 983.04
10-07 14:37:46.858   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 3170.6 gain_r 1.11349 gain_b 1.80386 delta2_sum 20.6117 prior 0.118351 final 20.4933
10-07 14:37:46.858   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:110 Final values 1483.04 -> 983.04
10-07 14:37:46.858   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 3234.02 gain_r 1.12667 gain_b 1.77097 delta2_sum 18.9486 prior 0.124762 final 18.8239
10-07 14:37:46.858   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:123 Move histogram point 61804.7 to 62259.2
10-07 14:37:46.858   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:128 Final values 61804.7 -> 62259.2
10-07 14:37:46.858   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 3298.7 gain_r 1.14044 gain_b 1.73863 delta2_sum 17.3711 prior 0.131302 final 17.2398
10-07 14:37:46.859   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 3364.67 gain_r 1.15484 gain_b 1.70684 delta2_sum 15.7071 prior 0.137972 final 15.5691
10-07 14:37:46.859   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 29967.63us (Shutter lines: 922, AGC requested 30000.00us) Gain: 3.31553 (Gain Code: 53)
10-07 14:37:46.859   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 3431.96 gain_r 1.1699 gain_b 1.67559 delta2_sum 14.0629 prior 0.144775 final 13.9181
10-07 14:37:46.859   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:413 ct is 4000, interpolating between 3000 and 5000
10-07 14:37:46.859   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 3500.6 gain_r 1.18568 gain_b 1.64488 delta2_sum 12.5078 prior 0.151715 final 12.356
10-07 14:37:46.859   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:413 ct is 4000, interpolating between 3000 and 5000
10-07 14:37:46.859   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 3570.62 gain_r 1.20222 gain_b 1.61468 delta2_sum 11.0443 prior 0.158793 final 10.8855
10-07 14:37:46.859   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 3642.03 gain_r 1.21593 gain_b 1.58734 delta2_sum 9.94739 prior 0.166013 final 9.78138
10-07 14:37:46.860   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:666 Stop after 14 iterations
10-07 14:37:46.860   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 3714.87 gain_r 1.22674 gain_b 1.56253 delta2_sum 9.16279 prior 0.173378 final 8.98942
10-07 14:37:46.860   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 3789.17 gain_r 1.23796 gain_b 1.53801 delta2_sum 8.48055 prior 0.180889 final 8.29966
10-07 14:37:46.860   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:666 Stop after 4 iterations
10-07 14:37:46.860   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 3864.95 gain_r 1.24962 gain_b 1.51378 delta2_sum 7.89848 prior 0.188551 final 7.70992
10-07 14:37:46.860   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 3942.25 gain_r 1.26174 gain_b 1.48984 delta2_sum 7.41461 prior 0.196366 final 7.21824
10-07 14:37:46.860   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Vertical Blanking to 24 at index 8
10-07 14:37:46.860   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 4021.09 gain_r 1.27435 gain_b 1.46619 delta2_sum 7.02726 prior 0.204338 final 6.82292
10-07 14:37:46.860   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Exposure to 922 at index 8
10-07 14:37:46.861   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 4101.51 gain_r 1.28748 gain_b 1.44283 delta2_sum 6.73506 prior 0.212469 final 6.52259
10-07 14:37:46.861   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Analogue Gain to 53 at index 8
10-07 14:37:46.861   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 0 for ISP Stats
10-07 14:37:46.861   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 4183.55 gain_r 1.30114 gain_b 1.41975 delta2_sum 6.53695 prior 0.220762 final 6.31619
10-07 14:37:46.861   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video16[19:cap]: Queueing buffer 0
10-07 14:37:46.861   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 4267.22 gain_r 1.31539 gain_b 1.39696 delta2_sum 6.43228 prior 0.229221 final 6.20306
10-07 14:37:46.861   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 4352.56 gain_r 1.33024 gain_b 1.37446 delta2_sum 6.42081 prior 0.23785 final 6.18296
10-07 14:37:46.861   360   442 I libcamera:  INFO RPI raspberrypi.cpp:1683 Dropping frame at the request of the IPA (1 left)
10-07 14:37:46.861   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 4439.61 gain_r 1.34574 gain_b 1.35224 delta2_sum 6.5028 prior 0.246651 final 6.25615
10-07 14:37:46.862   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 4528.4 gain_r 1.36193 gain_b 1.3303 delta2_sum 6.67904 prior 0.255628 final 6.42341
10-07 14:37:46.862   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 4618.97 gain_r 1.37193 gain_b 1.31448 delta2_sum 6.92178 prior 0.264785 final 6.657
10-07 14:37:46.862   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 4711.35 gain_r 1.38123 gain_b 1.29959 delta2_sum 7.20533 prior 0.274125 final 6.9312
10-07 14:37:46.862   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 4805.58 gain_r 1.39085 gain_b 1.28474 delta2_sum 7.53112 prior 0.283651 final 7.24747
10-07 14:37:46.862   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 4901.69 gain_r 1.4008 gain_b 1.26994 delta2_sum 7.89992 prior 0.293368 final 7.60655
10-07 14:37:46.863   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 4999.72 gain_r 1.4111 gain_b 1.25519 delta2_sum 8.31262 prior 0.30328 final 8.00934
10-07 14:37:46.863   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 5099.72 gain_r 1.42176 gain_b 1.24049 delta2_sum 8.77022 prior 0.313389 final 8.45684
10-07 14:37:46.863   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 5201.71 gain_r 1.43281 gain_b 1.22585 delta2_sum 9.27387 prior 0.323701 final 8.95017
10-07 14:37:46.863   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 5305.75 gain_r 1.44425 gain_b 1.21127 delta2_sum 9.82486 prior 0.334219 final 9.49064
10-07 14:37:46.863   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 5411.86 gain_r 1.45611 gain_b 1.19675 delta2_sum 10.4247 prior 0.344948 final 10.0797
10-07 14:37:46.864   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 5520.1 gain_r 1.46841 gain_b 1.18229 delta2_sum 11.075 prior 0.355891 final 10.7191
10-07 14:37:46.864   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 5630.5 gain_r 1.48117 gain_b 1.1679 delta2_sum 11.7778 prior 0.367053 final 11.4108
10-07 14:37:46.864   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 5743.11 gain_r 1.49133 gain_b 1.15635 delta2_sum 12.3863 prior 0.378438 final 12.0078
10-07 14:37:46.864   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 5857.97 gain_r 1.49688 gain_b 1.14913 delta2_sum 12.7893 prior 0.390051 final 12.3993
10-07 14:37:46.864   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 5975.13 gain_r 1.50259 gain_b 1.14185 delta2_sum 13.2087 prior 0.401896 final 12.8068
10-07 14:37:46.865   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 6094.64 gain_r 1.50846 gain_b 1.13452 delta2_sum 13.6451 prior 0.40441 final 13.2407
10-07 14:37:46.865   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 6216.53 gain_r 1.51449 gain_b 1.12715 delta2_sum 14.0989 prior 0.40441 final 13.6945
10-07 14:37:46.865   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 6340.86 gain_r 1.5207 gain_b 1.11972 delta2_sum 14.5708 prior 0.40441 final 14.1664
10-07 14:37:46.865   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 6467.68 gain_r 1.52708 gain_b 1.11224 delta2_sum 15.0615 prior 0.40441 final 14.6571
10-07 14:37:46.865   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 6597.03 gain_r 1.53364 gain_b 1.10472 delta2_sum 15.5717 prior 0.40441 final 15.1673
10-07 14:37:46.866   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 6728.97 gain_r 1.5404 gain_b 1.09715 delta2_sum 16.102 prior 0.40441 final 15.6975
10-07 14:37:46.866   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 6863.55 gain_r 1.54735 gain_b 1.08953 delta2_sum 16.6532 prior 0.40441 final 16.2487
10-07 14:37:46.866   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 7000.82 gain_r 1.5545 gain_b 1.08188 delta2_sum 17.2261 prior 0.40441 final 16.8217
10-07 14:37:46.866   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 7140.84 gain_r 1.56187 gain_b 1.07417 delta2_sum 17.8216 prior 0.40441 final 17.4171
10-07 14:37:46.866   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 7283.65 gain_r 1.56945 gain_b 1.06643 delta2_sum 18.4405 prior 0.40441 final 18.0361
10-07 14:37:46.867   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 7429.33 gain_r 1.57727 gain_b 1.05865 delta2_sum 19.0839 prior 0.40441 final 18.6795
10-07 14:37:46.867   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 7577.91 gain_r 1.58532 gain_b 1.05082 delta2_sum 19.7528 prior 0.40441 final 19.3484
10-07 14:37:46.867   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 7729.47 gain_r 1.59361 gain_b 1.04296 delta2_sum 20.4483 prior 0.40441 final 20.0439
10-07 14:37:46.867   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 7884.06 gain_r 1.60217 gain_b 1.03506 delta2_sum 21.1716 prior 0.40441 final 20.7671
10-07 14:37:46.867   360   455 D libcamera: DEBUG RPiAwb awb.cpp:444 t: 8000 gain_r 1.60864 gain_b 1.02921 delta2_sum 21.7232 prior 0.40441 final 21.3188
10-07 14:37:46.868   360   455 D libcamera: DEBUG RPiAwb awb.cpp:459 Coarse search found CT 4352.56
10-07 14:37:46.868   360   455 D libcamera: DEBUG RPiAwb awb.cpp:468 After quadratic refinement, coarse search has CT 4328.75
10-07 14:37:46.868   360   455 D libcamera: DEBUG RPiAwb awb.cpp:571 After coarse search: r 0.754112 b 0.72429 (gains r 1.32606 b 1.38066)
10-07 14:37:46.868   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4207.55 r 0.731238 b 0.682345: 3.79593
10-07 14:37:46.868   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4207.55 r 0.738853 b 0.687862: 4.2603
10-07 14:37:46.869   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4207.55 r 0.746469 b 0.693378: 4.76926
10-07 14:37:46.869   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4207.55 r 0.754085 b 0.698894: 5.32014
10-07 14:37:46.869   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4207.55 r 0.761701 b 0.70441: 5.91041
10-07 14:37:46.869   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4207.55 r 0.769317 b 0.709926: 6.53769
10-07 14:37:46.869   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4207.55 r 0.776933 b 0.715443: 7.19972
10-07 14:37:46.870   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4207.55 r 0.784549 b 0.720959: 7.89438
10-07 14:37:46.870   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4207.55 r 0.792165 b 0.726475: 8.61966
10-07 14:37:46.870   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4207.55 r 0.731238 b 0.682345: 3.79593
10-07 14:37:46.870   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4216.2 r 0.730376 b 0.683534: 3.77511
10-07 14:37:46.870   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4216.2 r 0.737992 b 0.689051: 4.24117
10-07 14:37:46.871   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4216.2 r 0.745608 b 0.694567: 4.7518
10-07 14:37:46.871   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4216.2 r 0.753224 b 0.700083: 5.30432
10-07 14:37:46.871   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4216.2 r 0.76084 b 0.705599: 5.8962
10-07 14:37:46.871   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4216.2 r 0.768456 b 0.711115: 6.52506
10-07 14:37:46.871   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4216.2 r 0.776072 b 0.716632: 7.18864
10-07 14:37:46.871   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4216.2 r 0.783688 b 0.722148: 7.88482
10-07 14:37:46.872   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4216.2 r 0.791304 b 0.727664: 8.61159
10-07 14:37:46.872   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4216.2 r 0.730376 b 0.683534: 3.77511 BEST
10-07 14:37:46.872   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4224.86 r 0.729515 b 0.684723: 3.75562
10-07 14:37:46.872   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4224.86 r 0.737131 b 0.690239: 4.2233
10-07 14:37:46.872   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4224.86 r 0.744747 b 0.695756: 4.73552
10-07 14:37:46.873   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4224.86 r 0.752363 b 0.701272: 5.28962
10-07 14:37:46.873   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4224.86 r 0.759979 b 0.706788: 5.88305
10-07 14:37:46.873   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4224.86 r 0.767595 b 0.712304: 6.51343
10-07 14:37:46.873   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4224.86 r 0.775211 b 0.71782: 7.17851
10-07 14:37:46.873   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4224.86 r 0.782827 b 0.723337: 7.87616
10-07 14:37:46.874   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4224.86 r 0.790443 b 0.728853: 8.60436
10-07 14:37:46.874   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4224.86 r 0.729515 b 0.684723: 3.75562 BEST
10-07 14:37:46.874   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4233.52 r 0.728654 b 0.685912: 3.73744
10-07 14:37:46.874   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4233.52 r 0.73627 b 0.691428: 4.20667
10-07 14:37:46.874   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4233.52 r 0.743886 b 0.696945: 4.72043
10-07 14:37:46.875   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4233.52 r 0.751502 b 0.702461: 5.27604
10-07 14:37:46.875   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4233.52 r 0.759118 b 0.707977: 5.87096
10-07 14:37:46.875   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4233.52 r 0.766734 b 0.713493: 6.50281
10-07 14:37:46.875   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4233.52 r 0.77435 b 0.719009: 7.16932
10-07 14:37:46.875   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4233.52 r 0.781966 b 0.724526: 7.86838
10-07 14:37:46.875   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4233.52 r 0.789581 b 0.730042: 8.59798
10-07 14:37:46.876   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4233.52 r 0.728654 b 0.685912: 3.73744 BEST
10-07 14:37:46.876   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4242.18 r 0.727793 b 0.687101: 3.72057
10-07 14:37:46.876   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4242.18 r 0.735409 b 0.692617: 4.19128
10-07 14:37:46.876   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4242.18 r 0.743025 b 0.698133: 4.7065
10-07 14:37:46.876   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4242.18 r 0.750641 b 0.70365: 5.26356
10-07 14:37:46.877   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4242.18 r 0.758257 b 0.709166: 5.85991
10-07 14:37:46.877   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4242.18 r 0.765873 b 0.714682: 6.49317
10-07 14:37:46.877   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video0[15:cap]: Dequeuing buffer 1
10-07 14:37:46.877   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4242.18 r 0.773489 b 0.720198: 7.16107
10-07 14:37:46.877   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1481 Stream Unicam Image buffer dequeue, buffer id 1, timestamp: 81474956000
10-07 14:37:46.877   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4242.18 r 0.781104 b 0.725714: 7.8615
10-07 14:37:46.877   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Analogue Gain to 64 at index 5
10-07 14:37:46.877   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4242.18 r 0.78872 b 0.731231: 8.59243
10-07 14:37:46.878   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Exposure to 1829 at index 5
10-07 14:37:46.878   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4242.18 r 0.727793 b 0.687101: 3.72057 BEST
10-07 14:37:46.878   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Vertical Blanking to 24 at index 5
10-07 14:37:46.878   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4250.83 r 0.726932 b 0.68829: 3.70501
10-07 14:37:46.878   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1832 Could not find matching embedded buffer
10-07 14:37:46.878   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4250.83 r 0.734548 b 0.693806: 4.17712
10-07 14:37:46.878   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1839 Returning bayer frame without a match
10-07 14:37:46.878   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4250.83 r 0.742164 b 0.699322: 4.69374
10-07 14:37:46.878   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1776 Signalling signalIspPrepare: Bayer buffer id: 1
10-07 14:37:46.878   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4250.83 r 0.74978 b 0.704839: 5.25218
10-07 14:37:46.879   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4250.83 r 0.757396 b 0.710355: 5.8499
10-07 14:37:46.879   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 8
10-07 14:37:46.879   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 8 started
10-07 14:37:46.879   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4250.83 r 0.765011 b 0.715871: 6.48451
10-07 14:37:46.879   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Analogue Gain to 53 at index 8
10-07 14:37:46.879   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4250.83 r 0.772627 b 0.721387: 7.15375
10-07 14:37:46.879   360  1602 I libcamera:  INFO IPARPI raspberrypi.cpp:626 Request ctrl: ScalerCrop = (0x0)/2592x1944
10-07 14:37:46.879   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Exposure to 922 at index 8
10-07 14:37:46.879   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4250.83 r 0.780243 b 0.726903: 7.85549
10-07 14:37:46.879   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1038 Metadata - Exposure: 59447.73us Frame length: 1968 Gain: 4 Aperture: 0 Lens: 0 Flash: 0
10-07 14:37:46.879   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4250.83 r 0.787859 b 0.73242: 8.58771
10-07 14:37:46.879   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Vertical Blanking to 24 at index 8
10-07 14:37:46.879   360  1602 D libcamera: DEBUG RPiDpc dpc.cpp:44 strength 1
10-07 14:37:46.880   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4250.83 r 0.726932 b 0.68829: 3.70501 BEST
10-07 14:37:46.880   360  1602 D libcamera: DEBUG RPiNoise noise.cpp:64 constant 0 slope 8.5
10-07 14:37:46.880   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4259.49 r 0.726071 b 0.689479: 3.69074
10-07 14:37:46.880   360  1602 D libcamera: DEBUG RPiGeq geq.cpp:68 offset 1604 slope 0.22476 (analogue gain 4 lux 164.332)
10-07 14:37:46.880   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4259.49 r 0.733687 b 0.694995: 4.16419
10-07 14:37:46.880   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:49 Noise profile: constant 0 slope 8.5
10-07 14:37:46.880   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4259.49 r 0.741303 b 0.700511: 4.68214
10-07 14:37:46.880   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:58 programmed constant 0 slope 27.2 strength 0.75
10-07 14:37:46.880   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4259.49 r 0.748919 b 0.706027: 5.2419
10-07 14:37:46.880   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:268 frame_count 8 speed 1
10-07 14:37:46.881   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4259.49 r 0.756534 b 0.711544: 5.84093
10-07 14:37:46.881   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:288 Using AWB gains r 1.27095 g 1 b 1.47244
10-07 14:37:46.881   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4259.49 r 0.76415 b 0.71706: 6.47684
10-07 14:37:46.881   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:352 Want total exposure 248664.83us
10-07 14:37:46.881   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4259.49 r 0.771766 b 0.722576: 7.14736
10-07 14:37:46.881   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:358 Actual exposure 237790.90us
10-07 14:37:46.881   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4259.49 r 0.779382 b 0.728092: 7.85035
10-07 14:37:46.881   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:359 Use digital_gain 1.04573
10-07 14:37:46.881   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4259.49 r 0.786998 b 0.733608: 8.58382
10-07 14:37:46.881   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:360 Effective exposure 248664.83us
10-07 14:37:46.881   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:430 Lock count updated to 0
10-07 14:37:46.881   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4259.49 r 0.726071 b 0.689479: 3.69074 BEST
10-07 14:37:46.882   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4268.15 r 0.72521 b 0.690668: 3.67776
10-07 14:37:46.882   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:332 frame_count 8 speed 1
10-07 14:37:46.882   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:259 Fetch ALSC results
10-07 14:37:46.882   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4268.15 r 0.732826 b 0.696184: 4.15248
10-07 14:37:46.882   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4268.15 r 0.740442 b 0.7017: 4.67169
10-07 14:37:46.882   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:151 colour temperature 4000K
10-07 14:37:46.883   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:153 CCM: 1.82464 -0.449828 -0.37482     -0.454665 1.61834 -0.163677     0.0217996 -0.939713 1.91791
10-07 14:37:46.883   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4268.15 r 0.748057 b 0.707216: 5.23271
10-07 14:37:46.883   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4268.15 r 0.755673 b 0.712733: 5.83299
10-07 14:37:46.883   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1068 Applying WB R: 1.27095 B: 1.47244
10-07 14:37:46.883   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4268.15 r 0.763289 b 0.718249: 6.47013
10-07 14:37:46.883   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4268.15 r 0.770905 b 0.723765: 7.14188
10-07 14:37:46.883   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4268.15 r 0.778521 b 0.729281: 7.84609
10-07 14:37:46.884   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4268.15 r 0.786137 b 0.734797: 8.58075
10-07 14:37:46.884   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4268.15 r 0.72521 b 0.690668: 3.67776 BEST
10-07 14:37:46.884   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4276.81 r 0.724349 b 0.691857: 3.66605
10-07 14:37:46.884   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4276.81 r 0.731964 b 0.697373: 4.14197
10-07 14:37:46.884   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4276.81 r 0.73958 b 0.702889: 4.66239
10-07 14:37:46.884   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4276.81 r 0.747196 b 0.708405: 5.2246
10-07 14:37:46.885   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4276.81 r 0.754812 b 0.713921: 5.82607
10-07 14:37:46.885   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4276.81 r 0.762428 b 0.719438: 6.4644
10-07 14:37:46.885   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4276.81 r 0.770044 b 0.724954: 7.13731
10-07 14:37:46.885   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4276.81 r 0.77766 b 0.73047: 7.84268
10-07 14:37:46.885   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4276.81 r 0.785276 b 0.735986: 8.57849
10-07 14:37:46.886   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4276.81 r 0.724349 b 0.691857: 3.66605 BEST
10-07 14:37:46.886   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4285.46 r 0.723487 b 0.693046: 3.65563
10-07 14:37:46.886   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4285.46 r 0.731103 b 0.698562: 4.13267
10-07 14:37:46.886   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4285.46 r 0.738719 b 0.704078: 4.65422
10-07 14:37:46.886   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4285.46 r 0.746335 b 0.709594: 5.21757
10-07 14:37:46.887   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4285.46 r 0.753951 b 0.71511: 5.82017
10-07 14:37:46.887   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4285.46 r 0.761567 b 0.720627: 6.45962
10-07 14:37:46.887   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4285.46 r 0.769183 b 0.726143: 7.13366
10-07 14:37:46.888   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4285.46 r 0.776799 b 0.731659: 7.84014
10-07 14:37:46.888   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4285.46 r 0.784415 b 0.737175: 8.57704
10-07 14:37:46.888   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4285.46 r 0.723487 b 0.693046: 3.65563 BEST
10-07 14:37:46.888   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4294.12 r 0.722626 b 0.694234: 3.64646
10-07 14:37:46.888   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4294.12 r 0.730242 b 0.699751: 4.12457
10-07 14:37:46.889   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1398 Input re-queue to ISP, buffer id 1, timestamp: 81474956000
10-07 14:37:46.889   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4294.12 r 0.737858 b 0.705267: 4.64718
10-07 14:37:46.889   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer -1 for ISP Input
10-07 14:37:46.889   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4294.12 r 0.745474 b 0.710783: 5.2116
10-07 14:37:46.889   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4294.12 r 0.75309 b 0.716299: 5.81528
10-07 14:37:46.889   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4294.12 r 0.760706 b 0.721815: 6.4558
10-07 14:37:46.889   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4294.12 r 0.768322 b 0.727332: 7.1309
10-07 14:37:46.889   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video13[16:out]: Queueing buffer 1
10-07 14:37:46.890   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4294.12 r 0.775938 b 0.732848: 7.83844
10-07 14:37:46.890   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4294.12 r 0.783554 b 0.738364: 8.57639
10-07 14:37:46.890   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4294.12 r 0.722626 b 0.694234: 3.64646 BEST
10-07 14:37:46.890   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4302.78 r 0.721765 b 0.695423: 3.63856
10-07 14:37:46.891   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4302.78 r 0.729381 b 0.70094: 4.11765
10-07 14:37:46.891   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4302.78 r 0.736997 b 0.706456: 4.64127
10-07 14:37:46.891   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4302.78 r 0.744613 b 0.711972: 5.2067
10-07 14:37:46.891   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4302.78 r 0.752229 b 0.717488: 5.81139
10-07 14:37:46.892   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4302.78 r 0.759845 b 0.723004: 6.45293
10-07 14:37:46.892   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4302.78 r 0.767461 b 0.728521: 7.12904
10-07 14:37:46.892   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4302.78 r 0.775077 b 0.734037: 7.83759
10-07 14:37:46.892   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4302.78 r 0.782692 b 0.739553: 8.57655
10-07 14:37:46.892   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4302.78 r 0.721765 b 0.695423: 3.63856 BEST
10-07 14:37:46.893   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4311.44 r 0.720904 b 0.696612: 3.6319
10-07 14:37:46.893   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4311.44 r 0.72852 b 0.702128: 4.11192
10-07 14:37:46.893   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4311.44 r 0.736136 b 0.707645: 4.63648
10-07 14:37:46.893   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4311.44 r 0.743752 b 0.713161: 5.20286
10-07 14:37:46.893   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4311.44 r 0.751368 b 0.718677: 5.8085
10-07 14:37:46.894   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4311.44 r 0.758984 b 0.724193: 6.451
10-07 14:37:46.894   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4311.44 r 0.7666 b 0.729709: 7.12808
10-07 14:37:46.894   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4311.44 r 0.774215 b 0.735226: 7.83758
10-07 14:37:46.894   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4311.44 r 0.781831 b 0.740742: 8.5775
10-07 14:37:46.894   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4311.44 r 0.720904 b 0.696612: 3.6319 BEST
10-07 14:37:46.895   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4320.09 r 0.720043 b 0.697801: 3.6265
10-07 14:37:46.895   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4320.09 r 0.727659 b 0.703317: 4.10737
10-07 14:37:46.895   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4320.09 r 0.735275 b 0.708834: 4.6328
10-07 14:37:46.895   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4320.09 r 0.742891 b 0.71435: 5.20006
10-07 14:37:46.895   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4320.09 r 0.750507 b 0.719866: 5.80661
10-07 14:37:46.895   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4320.09 r 0.758123 b 0.725382: 6.45001
10-07 14:37:46.896   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4320.09 r 0.765738 b 0.730898: 7.12799
10-07 14:37:46.896   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4320.09 r 0.773354 b 0.736415: 7.83841
10-07 14:37:46.896   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4320.09 r 0.78097 b 0.741931: 8.57924
10-07 14:37:46.896   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4320.09 r 0.720043 b 0.697801: 3.6265 BEST
10-07 14:37:46.896   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4328.75 r 0.719182 b 0.69899: 3.62233
10-07 14:37:46.897   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4328.75 r 0.726798 b 0.704506: 4.10398
10-07 14:37:46.897   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4328.75 r 0.734414 b 0.710022: 4.63022
10-07 14:37:46.897   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4328.75 r 0.74203 b 0.715539: 5.19832
10-07 14:37:46.897   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4328.75 r 0.749645 b 0.721055: 5.8057
10-07 14:37:46.897   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4328.75 r 0.757261 b 0.726571: 6.44995
10-07 14:37:46.898   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4328.75 r 0.764877 b 0.732087: 7.12879
10-07 14:37:46.898   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4328.75 r 0.772493 b 0.737603: 7.84007
10-07 14:37:46.898   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4328.75 r 0.780109 b 0.74312: 8.58176
10-07 14:37:46.898   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4328.75 r 0.719182 b 0.69899: 3.62233 BEST
10-07 14:37:46.898   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4337.41 r 0.718321 b 0.700179: 3.61939
10-07 14:37:46.899   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4337.41 r 0.725937 b 0.705695: 4.10177
10-07 14:37:46.899   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4337.41 r 0.733553 b 0.711211: 4.62875
10-07 14:37:46.899   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4337.41 r 0.741168 b 0.716728: 5.19761
10-07 14:37:46.899   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4337.41 r 0.748784 b 0.722244: 5.80577
10-07 14:37:46.899   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4337.41 r 0.7564 b 0.72776: 6.45082
10-07 14:37:46.899   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4337.41 r 0.764016 b 0.733276: 7.13047
10-07 14:37:46.900   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4337.41 r 0.771632 b 0.738792: 7.84256
10-07 14:37:46.900   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4337.41 r 0.779248 b 0.744309: 8.58506
10-07 14:37:46.900   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4337.41 r 0.718321 b 0.700179: 3.61939 BEST
10-07 14:37:46.900   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4346.07 r 0.71746 b 0.701368: 3.61768
10-07 14:37:46.900   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4346.07 r 0.725076 b 0.706884: 4.10071
10-07 14:37:46.901   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4346.07 r 0.732691 b 0.7124: 4.62837
10-07 14:37:46.901   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4346.07 r 0.740307 b 0.717916: 5.19793
10-07 14:37:46.901   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4346.07 r 0.747923 b 0.723433: 5.80682
10-07 14:37:46.901   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4346.07 r 0.755539 b 0.728949: 6.45261
10-07 14:37:46.901   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4346.07 r 0.763155 b 0.734465: 7.13302
10-07 14:37:46.902   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4346.07 r 0.770771 b 0.739981: 7.84587
10-07 14:37:46.902   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4346.07 r 0.778387 b 0.745497: 8.58914
10-07 14:37:46.902   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4346.07 r 0.71746 b 0.701368: 3.61768 BEST
10-07 14:37:46.902   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4354.72 r 0.716598 b 0.702557: 3.61719
10-07 14:37:46.902   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4354.72 r 0.724214 b 0.708073: 4.1008
10-07 14:37:46.903   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4354.72 r 0.73183 b 0.713589: 4.62908
10-07 14:37:46.903   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4354.72 r 0.739446 b 0.719105: 5.19929
10-07 14:37:46.903   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4354.72 r 0.747062 b 0.724622: 5.80885
10-07 14:37:46.903   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4354.72 r 0.754678 b 0.730138: 6.45532
10-07 14:37:46.903   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4354.72 r 0.762294 b 0.735654: 7.13643
10-07 14:37:46.903   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4354.72 r 0.76991 b 0.74117: 7.85
10-07 14:37:46.904   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4354.72 r 0.777526 b 0.746686: 8.59399
10-07 14:37:46.904   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4354.72 r 0.716598 b 0.702557: 3.61719 BEST
10-07 14:37:46.904   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4363.38 r 0.715737 b 0.703746: 3.61791
10-07 14:37:46.904   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4363.38 r 0.723353 b 0.709262: 4.10204
10-07 14:37:46.904   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4363.38 r 0.730969 b 0.714778: 4.63087
10-07 14:37:46.905   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4363.38 r 0.738585 b 0.720294: 5.20166
10-07 14:37:46.905   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4363.38 r 0.746201 b 0.72581: 5.81183
10-07 14:37:46.905   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4363.38 r 0.753817 b 0.731327: 6.45894
10-07 14:37:46.905   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4363.38 r 0.761433 b 0.736843: 7.1407
10-07 14:37:46.905   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4363.38 r 0.769049 b 0.742359: 7.85494
10-07 14:37:46.906   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4363.38 r 0.776665 b 0.747875: 8.59961
10-07 14:37:46.906   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4363.38 r 0.715737 b 0.703746: 3.61791
10-07 14:37:46.906   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4372.04 r 0.714876 b 0.704935: 3.61984
10-07 14:37:46.906   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4372.04 r 0.722492 b 0.710451: 4.10442
10-07 14:37:46.907   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4372.04 r 0.730108 b 0.715967: 4.63374
10-07 14:37:46.907   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4372.04 r 0.737724 b 0.721483: 5.20506
10-07 14:37:46.907   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4372.04 r 0.74534 b 0.726999: 5.81578
10-07 14:37:46.907   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4372.04 r 0.752956 b 0.732516: 6.46347
10-07 14:37:46.907   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4372.04 r 0.760572 b 0.738032: 7.14583
10-07 14:37:46.908   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4372.04 r 0.768188 b 0.743548: 7.86068
10-07 14:37:46.908   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4372.04 r 0.775804 b 0.749064: 8.60599
10-07 14:37:46.908   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4372.04 r 0.714876 b 0.704935: 3.61984
10-07 14:37:46.908   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4380.7 r 0.714015 b 0.706123: 3.62297
10-07 14:37:46.908   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4380.7 r 0.721631 b 0.71164: 4.10793
10-07 14:37:46.909   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4380.7 r 0.729247 b 0.717156: 4.63768
10-07 14:37:46.909   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4380.7 r 0.736863 b 0.722672: 5.20946
10-07 14:37:46.909   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4380.7 r 0.744479 b 0.728188: 5.82069
10-07 14:37:46.909   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4380.7 r 0.752095 b 0.733704: 6.4689
10-07 14:37:46.909   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4380.7 r 0.759711 b 0.739221: 7.15181
10-07 14:37:46.910   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4380.7 r 0.767327 b 0.744737: 7.86723
10-07 14:37:46.910   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4380.7 r 0.774942 b 0.750253: 8.61312
10-07 14:37:46.910   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4380.7 r 0.714015 b 0.706123: 3.62297
10-07 14:37:46.910   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4389.35 r 0.713154 b 0.707312: 3.6273
10-07 14:37:46.910   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4389.35 r 0.72077 b 0.712829: 4.11257
10-07 14:37:46.911   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4389.35 r 0.728386 b 0.718345: 4.64269
10-07 14:37:46.911   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4389.35 r 0.736002 b 0.723861: 5.21488
10-07 14:37:46.911   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4389.35 r 0.743618 b 0.729377: 5.82654
10-07 14:37:46.911   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4389.35 r 0.751234 b 0.734893: 6.47523
10-07 14:37:46.911   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4389.35 r 0.758849 b 0.74041: 7.15864
10-07 14:37:46.911   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4389.35 r 0.766465 b 0.745926: 7.87458
10-07 14:37:46.912   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4389.35 r 0.774081 b 0.751442: 8.62101
10-07 14:37:46.912   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4389.35 r 0.713154 b 0.707312: 3.6273
10-07 14:37:46.912   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4398.01 r 0.712293 b 0.708501: 3.63281
10-07 14:37:46.912   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4398.01 r 0.719909 b 0.714017: 4.11834
10-07 14:37:46.912   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4398.01 r 0.727525 b 0.719534: 4.64876
10-07 14:37:46.913   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4398.01 r 0.735141 b 0.72505: 5.2213
10-07 14:37:46.913   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4398.01 r 0.742757 b 0.730566: 5.83335
10-07 14:37:46.913   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4398.01 r 0.750372 b 0.736082: 6.48246
10-07 14:37:46.913   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4398.01 r 0.757988 b 0.741598: 7.16631
10-07 14:37:46.913   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4398.01 r 0.765604 b 0.747115: 7.88273
10-07 14:37:46.914   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4398.01 r 0.77322 b 0.752631: 8.62965
10-07 14:37:46.914   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4398.01 r 0.712293 b 0.708501: 3.63281
10-07 14:37:46.914   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4406.67 r 0.711432 b 0.70969: 3.63951
10-07 14:37:46.914   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4406.67 r 0.719048 b 0.715206: 4.12523
10-07 14:37:46.914   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4406.67 r 0.726664 b 0.720723: 4.65588
10-07 14:37:46.915   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4406.67 r 0.73428 b 0.726239: 5.22871
10-07 14:37:46.915   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4406.67 r 0.741895 b 0.731755: 5.84109
10-07 14:37:46.915   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4406.67 r 0.749511 b 0.737271: 6.49057
10-07 14:37:46.915   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4406.67 r 0.757127 b 0.742787: 7.17482
10-07 14:37:46.915   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4406.67 r 0.764743 b 0.748304: 7.89166
10-07 14:37:46.916   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4406.67 r 0.772359 b 0.75382: 8.63904
10-07 14:37:46.916   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4406.67 r 0.711432 b 0.70969: 3.63951
10-07 14:37:46.916   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4415.33 r 0.710571 b 0.710879: 3.64739
10-07 14:37:46.916   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4415.33 r 0.718187 b 0.716395: 4.13323
10-07 14:37:46.916   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4415.33 r 0.725802 b 0.721911: 4.66406
10-07 14:37:46.916   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4415.33 r 0.733418 b 0.727428: 5.23712
10-07 14:37:46.917   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4415.33 r 0.741034 b 0.732944: 5.84977
10-07 14:37:46.917   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4415.33 r 0.74865 b 0.73846: 6.49956
10-07 14:37:46.917   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video13[16:out]: Dequeuing buffer 1
10-07 14:37:46.917   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4415.33 r 0.756266 b 0.743976: 7.18416
10-07 14:37:46.917   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1509 Stream ISP Input buffer complete, buffer id 1, timestamp: 81474956000
10-07 14:37:46.917   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4415.33 r 0.763882 b 0.749492: 7.90138
10-07 14:37:46.917   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1 for Unicam Image
10-07 14:37:46.918   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4415.33 r 0.771498 b 0.755009: 8.64917
10-07 14:37:46.918   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video0[15:cap]: Queueing buffer 1
10-07 14:37:46.918   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4415.33 r 0.710571 b 0.710879: 3.64739
10-07 14:37:46.918   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video14[17:cap]: Dequeuing buffer 1
10-07 14:37:46.918   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4423.98 r 0.70971 b 0.712068: 3.65645
10-07 14:37:46.918   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Output0 buffer complete, buffer id 1, timestamp: 81474956000
10-07 14:37:46.918   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1 for ISP Output0
10-07 14:37:46.919   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video14[17:cap]: Queueing buffer 1
10-07 14:37:46.918   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4423.98 r 0.717325 b 0.717584: 4.14234
10-07 14:37:46.919   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4423.98 r 0.724941 b 0.7231: 4.67329
10-07 14:37:46.919   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4423.98 r 0.732557 b 0.728617: 5.24651
10-07 14:37:46.919   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4423.98 r 0.740173 b 0.734133: 5.85938
10-07 14:37:46.919   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4423.98 r 0.747789 b 0.739649: 6.50943
10-07 14:37:46.920   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4423.98 r 0.755405 b 0.745165: 7.19433
10-07 14:37:46.920   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video15[18:cap]: Dequeuing buffer 1
10-07 14:37:46.920   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4423.98 r 0.763021 b 0.750681: 7.91189
10-07 14:37:46.920   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Output1 buffer complete, buffer id 1, timestamp: 81474956000
10-07 14:37:46.920   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4423.98 r 0.770637 b 0.756198: 8.66003
10-07 14:37:46.920   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video16[19:cap]: Dequeuing buffer 1
10-07 14:37:46.920   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4423.98 r 0.70971 b 0.712068: 3.65645
10-07 14:37:46.920   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1537 Stream ISP Stats buffer complete, buffer id 1, timestamp: 81474956000
10-07 14:37:46.920   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4432.64 r 0.708848 b 0.713257: 3.66667
10-07 14:37:46.920   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4432.64 r 0.716464 b 0.718773: 4.15255
10-07 14:37:46.921   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4432.64 r 0.72408 b 0.724289: 4.68355
10-07 14:37:46.921   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4432.64 r 0.731696 b 0.729805: 5.25689
10-07 14:37:46.921   360  1602 D libcamera: DEBUG RPiLux lux.cpp:89 : estimated lux 164.242
10-07 14:37:46.921   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4432.64 r 0.739312 b 0.735322: 5.86992
10-07 14:37:46.921   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:299 frame_phase 1
10-07 14:37:46.921   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4432.64 r 0.746928 b 0.740838: 6.52018
10-07 14:37:46.921   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:309 Awb lux value is 164.242
10-07 14:37:46.921   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4432.64 r 0.754544 b 0.746354: 7.20533
10-07 14:37:46.922   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:447 ev 1 fixed_shutter 0.00us fixed_analogue_gain 0
10-07 14:37:46.922   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4432.64 r 0.76216 b 0.75187: 7.92317
10-07 14:37:46.922   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:480 exposure_mode normal constraint_mode normal metering_mode centre-weighted
10-07 14:37:46.922   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4432.64 r 0.769776 b 0.757386: 8.67163
10-07 14:37:46.922   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:578 Initial Y 0.233311 target 0.201027 gives gain 0.857947
10-07 14:37:46.922   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4432.64 r 0.708848 b 0.713257: 3.66667
10-07 14:37:46.922   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:589 Constraint has target_Y 0.625 giving gain 1.03483
10-07 14:37:46.922   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4441.3 r 0.707987 b 0.714446: 3.67806
10-07 14:37:46.922   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:593 Lower bound constraint adopted
10-07 14:37:46.922   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4441.3 r 0.715603 b 0.719962: 4.16386
10-07 14:37:46.922   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:601 Final gain 1.03483 (target_Y 0.625 ev 1 base_ev 1.25)
10-07 14:37:46.923   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4441.3 r 0.723219 b 0.725478: 4.69485
10-07 14:37:46.923   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:635 Target total_exposure 246073.04us
10-07 14:37:46.923   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4441.3 r 0.730835 b 0.730994: 5.26824
10-07 14:37:46.923   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:645 after AWB, target dg 1 gain 1.03483 target_Y 0.625
10-07 14:37:46.923   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4441.3 r 0.738451 b 0.736511: 5.88138
10-07 14:37:46.923   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:658 Digital gain 1 desaturate? 0
10-07 14:37:46.923   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4441.3 r 0.746067 b 0.742027: 6.5318
10-07 14:37:46.923   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:660 Target total_exposure_no_dg 246073.04us
10-07 14:37:46.923   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4441.3 r 0.753683 b 0.747543: 7.21715
10-07 14:37:46.924   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4441.3 r 0.761299 b 0.753059: 7.93523
10-07 14:37:46.924   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4441.3 r 0.768915 b 0.758575: 8.68395
10-07 14:37:46.924   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4441.3 r 0.707987 b 0.714446: 3.67806
10-07 14:37:46.924   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4449.96 r 0.707126 b 0.715635: 3.6906
10-07 14:37:46.924   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4449.96 r 0.714742 b 0.721151: 4.17626
10-07 14:37:46.925   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4449.96 r 0.722358 b 0.726667: 4.70719
10-07 14:37:46.925   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4449.96 r 0.729974 b 0.732183: 5.28057
10-07 14:37:46.925   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4449.96 r 0.73759 b 0.737699: 5.89377
10-07 14:37:46.925   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4449.96 r 0.745206 b 0.743216: 6.54429
10-07 14:37:46.925   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4449.96 r 0.752822 b 0.748732: 7.22979
10-07 14:37:46.925   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4449.96 r 0.760438 b 0.754248: 7.94805
10-07 14:37:46.923   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:700 After filtering, total_exposure 246073.04us no dg 246073.04us
10-07 14:37:46.926   360   455 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 4449.96 r 0.768053 b 0.759764: 8.69701
10-07 14:37:46.926   360   455 D libcamera: DEBUG RPiAwb awb.cpp:541 Finally 4449.96 r 0.707126 b 0.715635: 3.6906
10-07 14:37:46.926   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:745 Divided up shutter and gain are 60000.00us and 4.10122
10-07 14:37:46.926   360   455 D libcamera: DEBUG RPiAwb awb.cpp:551 Fine search found t 4354.72 r 0.716598 b 0.702557
10-07 14:37:46.926   360   455 D libcamera: DEBUG RPiAwb awb.cpp:581 After fine search: r 0.716598 b 0.702557 (gains r 1.39548 b 1.42337)
10-07 14:37:46.926   360   455 D libcamera: DEBUG RPiAwb awb.cpp:634 CT found is 4354.72 with gains r 1.46526 and b 1.49454
10-07 14:37:46.927   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:779 Output written, total exposure requested is 246073.04us
10-07 14:37:46.927   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:781 Camera exposure update: shutter time 60000.00us analogue gain 4.10122
10-07 14:37:46.927   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:361 frame_phase 1
10-07 14:37:46.927   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:296 Starting ALSC calculation
10-07 14:37:46.928   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:273 AWB results found, using 4000
10-07 14:37:46.929   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:105 Move histogram point 1257.28 to 983.04
10-07 14:37:46.929   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:413 ct is 4000, interpolating between 3000 and 5000
10-07 14:37:46.929   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:110 Final values 1257.28 -> 983.04
10-07 14:37:46.929   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:413 ct is 4000, interpolating between 3000 and 5000
10-07 14:37:46.929   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:123 Move histogram point 37032.2 to 62259.2
10-07 14:37:46.930   360  1602 D libcamera: DEBUG RPiContrast contrast.cpp:128 Final values 60259.2 -> 62259.2
10-07 14:37:46.930   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:666 Stop after 8 iterations
10-07 14:37:46.930   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1121 Applying AGC Exposure: 59967.77us (Shutter lines: 1845, AGC requested 60000.00us) Gain: 4.10122 (Gain Code: 65)
10-07 14:37:46.930   360   456 D libcamera: DEBUG RPiAlsc alsc.cpp:666 Stop after 4 iterations
10-07 14:37:46.930   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Vertical Blanking to 24 at index 9
10-07 14:37:46.931   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Exposure to 1845 at index 9
10-07 14:37:46.931   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:179 Queuing Analogue Gain to 65 at index 9
10-07 14:37:46.931   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer 1 for ISP Stats
10-07 14:37:46.931   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video16[19:cap]: Queueing buffer 1
10-07 14:37:46.931   360   442 I libcamera:  INFO RPI raspberrypi.cpp:1683 Dropping frame at the request of the IPA (0 left)
10-07 14:37:46.941   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video0[15:cap]: Dequeuing buffer 2
10-07 14:37:46.941   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1481 Stream Unicam Image buffer dequeue, buffer id 2, timestamp: 81538924000
10-07 14:37:46.941   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Analogue Gain to 84 at index 6
10-07 14:37:46.941   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Exposure to 1845 at index 6
10-07 14:37:46.942   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Vertical Blanking to 24 at index 6
10-07 14:37:46.942   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1832 Could not find matching embedded buffer
10-07 14:37:46.942   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1839 Returning bayer frame without a match
10-07 14:37:46.942   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1776 Signalling signalIspPrepare: Bayer buffer id: 2
10-07 14:37:46.942   360  1602 I libcamera:  INFO IPARPI raspberrypi.cpp:626 Request ctrl: ScalerCrop = (0x0)/2592x1944
10-07 14:37:46.943   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1038 Metadata - Exposure: 59967.77us Frame length: 1968 Gain: 5.25 Aperture: 0 Lens: 0 Flash: 0
10-07 14:37:46.943   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 9
10-07 14:37:46.943   360  1602 D libcamera: DEBUG RPiDpc dpc.cpp:44 strength 1
10-07 14:37:46.943   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 9 started
10-07 14:37:46.943   360  1602 D libcamera: DEBUG RPiNoise noise.cpp:64 constant 0 slope 9.73797
10-07 14:37:46.943   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Analogue Gain to 65 at index 9
10-07 14:37:46.943   360  1602 D libcamera: DEBUG RPiGeq geq.cpp:68 offset 2105 slope 0.294997 (analogue gain 5.25 lux 164.242)
10-07 14:37:46.944   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:49 Noise profile: constant 0 slope 9.73797
10-07 14:37:46.944   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Exposure to 1845 at index 9
10-07 14:37:46.944   360  1602 D libcamera: DEBUG RPiSdn sdn.cpp:58 programmed constant 0 slope 31.1615 strength 0.75
10-07 14:37:46.944   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:272 Setting Vertical Blanking to 24 at index 9
10-07 14:37:46.944   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:268 frame_count 9 speed 1
10-07 14:37:46.944   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:229 Fetch AWB results
10-07 14:37:46.944   360  1602 D libcamera: DEBUG RPiAwb awb.cpp:288 Using AWB gains r 1.46526 g 1 b 1.49454
10-07 14:37:46.945   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:352 Want total exposure 246073.04us
10-07 14:37:46.945   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:358 Actual exposure 314830.80us
10-07 14:37:46.945   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:359 Use digital_gain 1
10-07 14:37:46.945   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:360 Effective exposure 314830.80us
10-07 14:37:46.945   360  1602 D libcamera: DEBUG RPiAgc agc.cpp:430 Lock count updated to 0
10-07 14:37:46.946   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:332 frame_count 9 speed 1
10-07 14:37:46.946   360  1602 D libcamera: DEBUG RPiAlsc alsc.cpp:259 Fetch ALSC results
10-07 14:37:46.946   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:151 colour temperature 4354.72K
10-07 14:37:46.946   360  1602 D libcamera: DEBUG RPiCcm ccm.cpp:153 CCM: 1.84317 -0.460821 -0.382354     -0.445676 1.66244 -0.216759     0.0338557 -0.883459 1.8496
10-07 14:37:46.946   360  1602 D libcamera: DEBUG IPARPI raspberrypi.cpp:1068 Applying WB R: 1.46526 B: 1.49454
10-07 14:37:46.950   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1398 Input re-queue to ISP, buffer id 2, timestamp: 81538924000
10-07 14:37:46.950   360   442 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:226 Queuing buffer -1 for ISP Input
10-07 14:37:46.950   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1593 /dev/video13[16:out]: Queueing buffer 2
10-07 14:37:47.005   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video0[15:cap]: Dequeuing buffer 0
10-07 14:37:47.005   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1481 Stream Unicam Image buffer dequeue, buffer id 0, timestamp: 81602897000
10-07 14:37:47.006   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Analogue Gain to 89 at index 7
10-07 14:37:47.006   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Exposure to 1845 at index 7
10-07 14:37:47.006   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Vertical Blanking to 24 at index 7
10-07 14:37:47.007   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 10
10-07 14:37:47.007   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 10 started
10-07 14:37:47.007   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:47.069   360   442 D libcamera: DEBUG V4L2 v4l2_videodevice.cpp:1661 /dev/video0[15:cap]: Dequeuing buffer 1
10-07 14:37:47.069   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1481 Stream Unicam Image buffer dequeue, buffer id 1, timestamp: 81666857000
10-07 14:37:47.070   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Analogue Gain to 53 at index 8
10-07 14:37:47.070   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Exposure to 922 at index 8
10-07 14:37:47.070   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:217 Reading Vertical Blanking to 24 at index 8
10-07 14:37:47.071   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 11
10-07 14:37:47.071   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 11 started
10-07 14:37:47.071   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:47.133   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 12
10-07 14:37:47.134   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 12 started
10-07 14:37:47.134   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:47.197   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 13
10-07 14:37:47.198   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 13 started
10-07 14:37:47.198   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:47.261   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 14
10-07 14:37:47.262   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 14 started
10-07 14:37:47.262   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:47.325   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 15
10-07 14:37:47.326   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 15 started
10-07 14:37:47.326   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:47.389   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 16
10-07 14:37:47.390   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 16 started
10-07 14:37:47.390   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:47.454   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 17
10-07 14:37:47.454   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 17 started
10-07 14:37:47.455   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:47.517   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 18
10-07 14:37:47.517   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 18 started
10-07 14:37:47.518   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:47.581   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 19
10-07 14:37:47.581   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 19 started
10-07 14:37:47.582   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:47.645   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 20
10-07 14:37:47.645   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 20 started
10-07 14:37:47.646   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:47.709   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 21
10-07 14:37:47.709   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 21 started
10-07 14:37:47.709   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:47.773   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 22
10-07 14:37:47.773   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 22 started
10-07 14:37:47.773   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:47.837   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 23
10-07 14:37:47.837   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 23 started
10-07 14:37:47.837   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:47.901   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 24
10-07 14:37:47.901   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 24 started
10-07 14:37:47.901   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:47.965   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 25
10-07 14:37:47.965   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 25 started
10-07 14:37:47.965   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:48.029   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 26
10-07 14:37:48.029   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 26 started
10-07 14:37:48.029   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:48.093   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 27
10-07 14:37:48.093   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 27 started
10-07 14:37:48.093   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:48.157   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 28
10-07 14:37:48.157   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 28 started
10-07 14:37:48.157   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:48.221   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 29
10-07 14:37:48.221   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 29 started
10-07 14:37:48.221   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:48.285   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 30
10-07 14:37:48.285   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 30 started
10-07 14:37:48.285   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:48.349   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 31
10-07 14:37:48.349   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 31 started
10-07 14:37:48.349   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:48.413   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 32
10-07 14:37:48.413   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 32 started
10-07 14:37:48.413   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:48.477   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 33
10-07 14:37:48.477   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 33 started
10-07 14:37:48.477   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:48.541   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 34
10-07 14:37:48.541   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 34 started
10-07 14:37:48.541   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:48.605   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 35
10-07 14:37:48.605   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 35 started
10-07 14:37:48.605   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:48.669   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 36
10-07 14:37:48.669   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 36 started
10-07 14:37:48.669   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:48.733   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 37
10-07 14:37:48.733   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 37 started
10-07 14:37:48.733   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:48.797   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 38
10-07 14:37:48.797   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 38 started
10-07 14:37:48.797   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:48.877   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 39
10-07 14:37:48.877   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 39 started
10-07 14:37:48.877   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:48.924   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 40
10-07 14:37:48.925   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 40 started
10-07 14:37:48.925   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:48.988   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 41
10-07 14:37:48.989   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 41 started
10-07 14:37:48.989   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:49.052   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 42
10-07 14:37:49.053   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 42 started
10-07 14:37:49.053   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:49.116   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 43
10-07 14:37:49.117   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 43 started
10-07 14:37:49.117   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:49.180   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 44
10-07 14:37:49.181   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 44 started
10-07 14:37:49.181   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:49.244   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 45
10-07 14:37:49.245   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 45 started
10-07 14:37:49.245   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:49.308   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 46
10-07 14:37:49.309   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 46 started
10-07 14:37:49.309   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:49.372   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 47
10-07 14:37:49.372   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 47 started
10-07 14:37:49.373   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:49.436   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 48
10-07 14:37:49.436   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 48 started
10-07 14:37:49.437   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:49.500   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 49
10-07 14:37:49.500   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 49 started
10-07 14:37:49.501   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:49.564   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 50
10-07 14:37:49.564   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 50 started
10-07 14:37:49.565   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:49.628   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 51
10-07 14:37:49.628   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 51 started
10-07 14:37:49.629   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:49.692   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 52
10-07 14:37:49.692   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 52 started
10-07 14:37:49.692   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:49.756   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 53
10-07 14:37:49.756   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 53 started
10-07 14:37:49.756   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:49.820   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 54
10-07 14:37:49.820   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 54 started
10-07 14:37:49.820   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:49.884   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 55
10-07 14:37:49.884   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 55 started
10-07 14:37:49.884   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:49.948   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 56
10-07 14:37:49.948   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 56 started
10-07 14:37:49.948   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:50.012   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 57
10-07 14:37:50.012   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 57 started
10-07 14:37:50.012   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:50.076   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 58
10-07 14:37:50.076   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 58 started
10-07 14:37:50.076   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:50.140   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 59
10-07 14:37:50.140   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 59 started
10-07 14:37:50.140   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:50.204   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 60
10-07 14:37:50.204   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 60 started
10-07 14:37:50.204   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:50.268   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 61
10-07 14:37:50.268   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 61 started
10-07 14:37:50.268   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:50.332   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 62
10-07 14:37:50.332   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 62 started
10-07 14:37:50.332   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:50.396   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 63
10-07 14:37:50.396   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 63 started
10-07 14:37:50.396   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:50.460   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 64
10-07 14:37:50.460   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 64 started
10-07 14:37:50.460   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:50.524   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 65
10-07 14:37:50.524   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 65 started
10-07 14:37:50.524   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:50.588   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 66
10-07 14:37:50.588   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 66 started
10-07 14:37:50.588   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:50.652   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 67
10-07 14:37:50.652   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 67 started
10-07 14:37:50.652   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:50.716   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 68
10-07 14:37:50.716   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 68 started
10-07 14:37:50.716   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:50.780   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 69
10-07 14:37:50.780   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 69 started
10-07 14:37:50.780   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:50.844   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 70
10-07 14:37:50.844   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 70 started
10-07 14:37:50.844   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:50.908   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 71
10-07 14:37:50.908   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 71 started
10-07 14:37:50.908   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:50.971   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 72
10-07 14:37:50.972   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 72 started
10-07 14:37:50.972   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
10-07 14:37:51.035   360   442 D libcamera: DEBUG RPI raspberrypi.cpp:1281 frame start 73
10-07 14:37:51.036   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:237 frame 73 started
10-07 14:37:51.036   360   442 D libcamera: DEBUG DelayedControls delayed_controls.cpp:285 Queue is empty, auto queue no-op.
kbingham commented 3 years ago

Could you post this question to the libcamera mailling list please?

On this repository you get an audience of just me. On the libcamera mailing list you get the other libcamera developers, who have written the android layer, as well as the developers who maintain the raspberry pi pipeline handler and IPA at Raspberry Pi.

rsglobal commented 3 years ago

While debugging, got a state in which stream started for some reason. So I'm really close to get libcamera+android+rpi fully functional.

1636457691590

kbingham commented 3 years ago

Great! Did you identify what the change was that enabled the streaming?

rsglobal commented 3 years ago

No I haven't fixed it yet. It just looks like restarting camera app did the thing.

uajain commented 3 years ago

Hi @rsglobal

Can you briefly explain your OS setup with AOSP/android+Rpi ? I am interested.

rsglobal commented 3 years ago

Hi @uajain ,

Can you briefly explain your OS setup with AOSP/android+Rpi ? I am interested.

Not sure I can explain all setup briefly.

Here is our project: https://github.com/GloDroid/glodroid_manifest

You can look into glodroid.xml file to see the difference from AOSP.

rsglobal commented 3 years ago

Green row fixed by removing buffer height alignment in the gralloc. 20211109_160815

rsglobal commented 3 years ago

Color correction works pretty well.

rsglobal commented 3 years ago

I've applied this series and picture freezes again. I'm attaching the logs: libcamera_v2.log

kbingham commented 3 years ago

@naushir It seems to have crashed in RPiCameraData::ispOutputDequeue

10-07 14:39:27.028  2242  2242 F DEBUG   : backtrace:
10-07 14:39:27.028  2242  2242 F DEBUG   :       #00 pc 0000000000097c04  /apex/com.android.vndk.v31/lib64/libc++.so (std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> >::basic_string(std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&)+28) (BuildId: 0db72613bf270f11efab0a156c4fe541)
10-07 14:39:27.029  2242  2242 F DEBUG   :       #01 pc 00000000000bf378  /vendor/lib64/libcamera.so (libcamera::RPiCameraData::ispOutputDequeue(libcamera::FrameBuffer*)+192) (BuildId: 4282ee1d889803300eeb9931b3b3b9e6)
10-07 14:39:27.029  2242  2242 F DEBUG   :       #02 pc 00000000000c3680  /vendor/lib64/libcamera.so (BuildId: 4282ee1d889803300eeb9931b3b3b9e6)
10-07 14:39:27.029  2242  2242 F DEBUG   :       #03 pc 00000000000ae300  /vendor/lib64/libcamera.so (BuildId: 4282ee1d889803300eeb9931b3b3b9e6)
10-07 14:39:27.029  2242  2242 F DEBUG   :       #04 pc 00000000000af37c  /vendor/lib64/libcamera.so (BuildId: 4282ee1d889803300eeb9931b3b3b9e6)
10-07 14:39:27.029  2242  2242 F DEBUG   :       #05 pc 00000000000123c8  /vendor/lib64/libcamera-base.so (BuildId: 5609a3280ab3276ccad765984e985601)

Is there an obvious path to there which may have failed to dequeue a buffer perhaps? Or something happening due to the v4l2 buffer cache issue maybe?

rsglobal commented 3 years ago

@kbingham ,

I've appended full crash and addr2line in the end of log file.

ADD: This line:

LOG(RPI, Debug) << "Stream " << stream->name() << " buffer complete"
naushir commented 3 years ago

Is there an obvious path to there which may have failed to dequeue a buffer perhaps? Or something happening due to the v4l2 buffer cache issue maybe?

The only think I can see is if stream is null, there is an assert that checks for this. This can only happen if a buffer is dequeued by the driver that pipeline handler cannot recognise. Again, this may be something to do with the v4l2 cache being under allocated, not too sure.

From the logs, this does not seem to crash immediately, but after a few restarts, is that correct?

naushir commented 3 years ago

It would be worth running with this snippet and see if you hit the warning message:

diff --git a/src/libcamera/pipeline/raspberrypi/raspberrypi.cpp b/src/libcamera/pipeline/raspberrypi/raspberrypi.cpp
index 4f6c699a4379..815ba860b126 100644
--- a/src/libcamera/pipeline/raspberrypi/raspberrypi.cpp
+++ b/src/libcamera/pipeline/raspberrypi/raspberrypi.cpp
@@ -1551,8 +1551,10 @@ void RPiCameraData::ispOutputDequeue(FrameBuffer *buffer)
                }
        }

-       /* The buffer must belong to one of our ISP output streams. */
-       ASSERT(stream);
+       if (!stream) {
+               LOG(RPI, Fatal) << "Unrecognised stream!!";
+               return;
+       }

        LOG(RPI, Debug) << "Stream " << stream->name() << " buffer complete"
                        << ", buffer id " << index
rsglobal commented 3 years ago

Attaching new logs: It looks like this time it didn't crash for some reason. But I can't see added fatal anywhere.

libcam_3.log

naushir commented 3 years ago
10-07 14:37:46.786   381   441 E libcamera: ERROR RPISTREAM rpi_stream.cpp:239 Failed to queue buffer for ISP Output1
10-07 14:37:46.786   381   443 D libcamera: DEBUG RPiAwb awb.cpp:522 At t 6438.89 r 0.650454 b 0.895426: 0.696849
10-07 14:37:46.786   381   441 D libcamera: DEBUG RPISTREAM rpi_stream.cpp:234 Queuing buffer 1048579 for ISP Output1
10-07 14:37:46.786   381   441 E libcamera: ERROR RPISTREAM rpi_stream.cpp:239 Failed to queue buffer for ISP Output1

This error shows the same buffer cache issue again, where seem to be running out of buffer slots. There seems to be quite a bit of variability in the failure cases from what I can tell, and without knowing anything about what the Android wrapper is doing, I can't really suggest much more to try.

When I am able to, perhaps this something I can recreate on my end to debug. Do you have a set of instructions for setting your environment up so I can reproduce this?

rsglobal commented 3 years ago

When I am able to, perhaps this something I can recreate on my end to debug. Do you have a set of instructions for setting your environment up so I can reproduce this?

Building AOSP has advanced hardware requirements: Minimum: 16GB of RAM (in this case all other apps must be closed) or 32gb of RAM and 300-400GB free disk space.

In case you have all this I can prepare release/instructions for you.

naushir commented 3 years ago

Even better, would you be able to create a disk image so I can flash it directly?

With all the other things going on, I am not sure when I will be able to get to this though.

rsglobal commented 3 years ago

Even better, would you be able to create a disk image so I can flash it directly?

Yes, but you won't be able to rebuild libcamera.

kbingham commented 3 years ago

At the least, I have the build capabilities for this, so instructions are probably very useful to publish somewhere if that's possible.

But I wonder if we can try to isolate the issue and replicate it in a simpler case somehow.

@rsglobal Do I recall seeing that you said the buffers /always/ use a different FD, and so are blowing through the cache because that's how we match, and that we need to check the inodes instead?

Can we verify this by printing the fd and inodes to see them get used? If the v4l2buffercache is at fault here, is that all we need to fix?

rsglobal commented 3 years ago

At the least, I have the build capabilities for this, so instructions are probably very useful to publish somewhere if that's possible.

Sure, let me just finalize v0.7.0 GloDroid release. All instructions are already HERE

@rsglobal Do I recall seeing that you said the buffers /always/ use a different FD, and so are blowing through the cache because that's how we match, and that we need to check the inodes instead?

I just assumed that buffers may use different fd if they are received from CLIENT. But honestly I do not understand what that cache are doing, and what is the info stored there and for what purpose.

Can we verify this by printing the fd and inodes to see them get used? If the v4l2buffercache is at fault here, is that all we need to fix?

Yes, ping me later please for that. (in a few weeks).

kbingham commented 3 years ago

V4L2 uses separate structures to represent the internal buffers, and we give it a dma-buf handle ( the fd ). If those are not kept associated, so the same v4l2 buffer is not used for the same underlying buffer, there is a performance penalty.

If there really is a distinct different buffer each time, we may blow through the cache, but we should instead take the performance penalty and re-use the oldest v4l2 structure (which it doesn't do now).

However, if the fd changes, but maps to the same underlying inode (same actual dma-buf) then we should use that to map the cache entry.

To know what's happening in this use case, printing all that information before it stalls would really help. I can't replicate the issue, as both locally and in chromeos where we use the android layer, the fd's are re-used so we don't exhaust the cache. This is of course 'assuming' that the underlying issue is related to that, so again - printing out the fd's to see them would really help confirm/deny the theory.

kbingham commented 3 years ago

Also, I believe we map on the FD rather than the inode currently, as otherwise - mapping on the inode would otherwise incur an extra system call to get the inode.... but perhaps it becomes unavoidable ?

rsglobal commented 3 years ago

Hi, now you can try Android-12 on RPI4 with the libcamera. Images are available under assets list: https://github.com/GloDroid/glodroid_manifest/releases/tag/v0.7.0

To build from sources please follow this manual: https://github.com/GloDroid/glodroid_manifest#fetching-android-sources

kbingham commented 2 years ago

Is this still an issue ?