libcamera
libcamera copied to clipboard
Published
20 hours ago •
kbingham
kbingham
Android: RPI4: Need help with log analysis
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 [email protected]: 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.
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.
While debugging, got a state in which stream started for some reason. So I'm really close to get libcamera+android+rpi fully functional.
No I haven't fixed it yet. It just looks like restarting camera app did the thing.
Hi @rsglobal
Can you briefly explain your OS setup with AOSP/android+Rpi ? I am interested.
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.
@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?
@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"
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?
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
Attaching new logs: It looks like this time it didn't crash for some reason. But I can't see added fatal anywhere.
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?
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.
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.
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.
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?
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).
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.
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 ?
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