[Tracker] HAL3 Flashlight issue debugging

[ItsVixano]

Looks like after reverting the camera hal tag "https://github.com/ItsVixano/android_device_xiaomi_sakura-common/commit/cfee0c1348799a976f74be56676a97477b96b3cc" we can now see our flashlight working again, but looks like is still broken with rapid flashes times, making the image complety dark with some spams on our logs like

11-19 17:55:10.118   706  6997 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
11-19 17:55:10.123  6263  6967 D pck     : removeInflightRequest 8

Full log:

11-19 18:01:57.778  6291  6291 D GmsClearcutSvc: onBind: Intent { act=com.google.android.gms.clearcut.service.START pkg=com.google.android.gms }
11-19 18:01:57.781   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6857: Book-keeping (frame_num, ack_frame_id) = [7654, 6860] in list
11-19 18:01:57.781   579  1491 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
11-19 18:01:57.787  6291  6343 D GmsClearcutSvc: bound by: GetServiceRequest{serviceId=CLEARCUT_LOGGER, gmsVersion=20363000, packageName='com.google.android.apps.cameraslite', extras=Bundle[{}]}
11-19 18:01:57.796  6291  7138 I gle.android.gm: oneway function results will be dropped but finished with status OK and parcel size 4
11-19 18:01:57.858   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6858: Book-keeping (frame_num, ack_frame_id) = [7655, 6861] in list
11-19 18:01:57.860   579  1491 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
11-19 18:01:57.939   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6859: Book-keeping (frame_num, ack_frame_id) = [7656, 6862] in list
11-19 18:01:57.965  1692  6633 I system_server: oneway function results will be dropped but finished with status OK and parcel size 4
11-19 18:01:58.020   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6860: Book-keeping (frame_num, ack_frame_id) = [7657, 6863] in list
11-19 18:01:58.020   706  6997 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
11-19 18:01:58.099   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6861: Book-keeping (frame_num, ack_frame_id) = [7658, 6864] in list
11-19 18:01:58.100   706  6997 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
11-19 18:01:58.104  6263  6967 D pck     : removeInflightRequest 97
11-19 18:01:58.105  6263  6290 I pck     : Submit Request-100 with [Stream-0, Stream-2]
11-19 18:01:58.180   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6862: Book-keeping (frame_num, ack_frame_id) = [7659, 6865] in list
11-19 18:01:58.186   579  1491 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
11-19 18:01:58.260   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6863: Book-keeping (frame_num, ack_frame_id) = [7660, 6866] in list
11-19 18:01:58.264   579  1491 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
11-19 18:01:58.341   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6864: Book-keeping (frame_num, ack_frame_id) = [7661, 6867] in list
11-19 18:01:58.422   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6865: Book-keeping (frame_num, ack_frame_id) = [7662, 6868] in list
11-19 18:01:58.423   706  6997 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
11-19 18:01:58.501   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6866: Book-keeping (frame_num, ack_frame_id) = [7663, 6869] in list
11-19 18:01:58.502   706  6997 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
11-19 18:01:58.513  6263  6967 D pck     : removeInflightRequest 99
11-19 18:01:58.582   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6867: Book-keeping (frame_num, ack_frame_id) = [7664, 6870] in list
11-19 18:01:58.588  6263  6967 D pck     : removeInflightRequest 100
11-19 18:01:58.662   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6868: Book-keeping (frame_num, ack_frame_id) = [7665, 6871] in list
11-19 18:01:58.742   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6869: Book-keeping (frame_num, ack_frame_id) = [7666, 6872] in list
11-19 18:01:58.824   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6870: Book-keeping (frame_num, ack_frame_id) = [7667, 6873] in list
11-19 18:01:58.904   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6871: Book-keeping (frame_num, ack_frame_id) = [7668, 6874] in list
11-19 18:01:58.975   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6872: Book-keeping (frame_num, ack_frame_id) = [7669, 6875] in list
11-19 18:01:59.044   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6873: Book-keeping (frame_num, ack_frame_id) = [7670, 6876] in list
11-19 18:01:59.104   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6874: Book-keeping (frame_num, ack_frame_id) = [7671, 6877] in list
11-19 18:01:59.154   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6875: Book-keeping (frame_num, ack_frame_id) = [7672, 6878] in list
11-19 18:01:59.195   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6876: Book-keeping (frame_num, ack_frame_id) = [7673, 6879] in list
11-19 18:01:59.206   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7670, buffer = 0xf6884820
11-19 18:01:59.236   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6877: Book-keeping (frame_num, ack_frame_id) = [7674, 6880] in list
11-19 18:01:59.247   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7671, buffer = 0xf6882270
11-19 18:01:59.275   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6878: Book-keeping (frame_num, ack_frame_id) = [7675, 6881] in list
11-19 18:01:59.287   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7672, buffer = 0xf6883f20
11-19 18:01:59.310   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6879: Book-keeping (frame_num, ack_frame_id) = [7676, 6882] in list
11-19 18:01:59.321   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7673, buffer = 0xf6883260
11-19 18:01:59.325   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7673, buffer = 0xf6882f60
11-19 18:01:59.346   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6880: Book-keeping (frame_num, ack_frame_id) = [7677, 6883] in list
11-19 18:01:59.352   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7674, buffer = 0xf6883a40
11-19 18:01:59.358   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7674, buffer = 0xf6883710
11-19 18:01:59.376   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6881: Book-keeping (frame_num, ack_frame_id) = [7678, 6884] in list
11-19 18:01:59.383   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7675, buffer = 0xf68830e0
11-19 18:01:59.392   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7675, buffer = 0xf6883cb0
11-19 18:01:59.410   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6882: Book-keeping (frame_num, ack_frame_id) = [7679, 6885] in list
11-19 18:01:59.416   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7676, buffer = 0xf68821b0
11-19 18:01:59.425   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7676, buffer = 0xf6883a70
11-19 18:01:59.442   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6883: Book-keeping (frame_num, ack_frame_id) = [7680, 6886] in list
11-19 18:01:59.454   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7677, buffer = 0xf68841c0
11-19 18:01:59.458   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7677, buffer = 0xf6884310
11-19 18:01:59.477   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6884: Book-keeping (frame_num, ack_frame_id) = [7681, 6887] in list
11-19 18:01:59.487   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7678, buffer = 0xf6884400
11-19 18:01:59.492   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7678, buffer = 0xf6882f30
11-19 18:01:59.509   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6885: Book-keeping (frame_num, ack_frame_id) = [7682, 6888] in list
11-19 18:01:59.509   706  6983 I mm-camera: <ISP   >< INFO> 230: ihist_stats44_stats_config_validate: warning: Invalid IHIST ROI from 3A 0 0 0 0
11-19 18:01:59.517   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7679, buffer = 0xf6883260
11-19 18:01:59.525   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7679, buffer = 0xf68832c0
11-19 18:01:59.543   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6886: Book-keeping (frame_num, ack_frame_id) = [7683, 6889] in list
11-19 18:01:59.554   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7680, buffer = 0xf6883a40
11-19 18:01:59.560   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7680, buffer = 0xf6882660
11-19 18:01:59.577   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6887: Book-keeping (frame_num, ack_frame_id) = [7684, 6890] in list
11-19 18:01:59.588   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7681, buffer = 0xf68830e0
11-19 18:01:59.594   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7681, buffer = 0xf6884fa0
11-19 18:01:59.609   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6888: Book-keeping (frame_num, ack_frame_id) = [7685, 6891] in list
11-19 18:01:59.617   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7682, buffer = 0xf68821b0
11-19 18:01:59.625   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7682, buffer = 0xf68825a0
11-19 18:01:59.644   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6889: Book-keeping (frame_num, ack_frame_id) = [7686, 6892] in list
11-19 18:01:59.654   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7683, buffer = 0xf68841c0
11-19 18:01:59.659   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7683, buffer = 0xf6881670
11-19 18:01:59.678   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6890: Book-keeping (frame_num, ack_frame_id) = [7687, 6893] in list
11-19 18:01:59.687   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7684, buffer = 0xf6884400
11-19 18:01:59.693   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7684, buffer = 0xf6885000
11-19 18:01:59.709   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6891: Book-keeping (frame_num, ack_frame_id) = [7688, 6894] in list
11-19 18:01:59.720   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7685, buffer = 0xf6883260
11-19 18:01:59.726   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7685, buffer = 0xf68839b0
11-19 18:01:59.743   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6892: Book-keeping (frame_num, ack_frame_id) = [7689, 6895] in list
11-19 18:01:59.755   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7686, buffer = 0xf6883a40
11-19 18:01:59.759   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7686, buffer = 0xf6881f10
11-19 18:01:59.776   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6893: Book-keeping (frame_num, ack_frame_id) = [7690, 6896] in list
11-19 18:01:59.786   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7687, buffer = 0xf68830e0
11-19 18:01:59.795   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7687, buffer = 0xf6884820
11-19 18:01:59.810   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6894: Book-keeping (frame_num, ack_frame_id) = [7691, 6897] in list
11-19 18:01:59.820   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7688, buffer = 0xf68821b0
11-19 18:01:59.826   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7688, buffer = 0xf6882270
11-19 18:01:59.843   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6895: Book-keeping (frame_num, ack_frame_id) = [7692, 6898] in list
11-19 18:01:59.851   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7689, buffer = 0xf68841c0
11-19 18:01:59.859   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7689, buffer = 0xf6883f20
11-19 18:01:59.877   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6896: Book-keeping (frame_num, ack_frame_id) = [7693, 6899] in list
11-19 18:01:59.877   706  6983 I mm-camera: <ISP   >< INFO> 231: isp_hw_update_util_request_reg_update: warning: reg_cmd ioctl happened on frame boundary
11-19 18:01:59.888   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7690, buffer = 0xf6884400
11-19 18:01:59.893   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7690, buffer = 0xf6882f60
11-19 18:01:59.911   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6897: Book-keeping (frame_num, ack_frame_id) = [7694, 6900] in list
11-19 18:01:59.921   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7691, buffer = 0xf6883260
11-19 18:01:59.926   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7691, buffer = 0xf6883710
11-19 18:01:59.944   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6898: Book-keeping (frame_num, ack_frame_id) = [7695, 6901] in list
11-19 18:01:59.952   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7692, buffer = 0xf6883a40
11-19 18:01:59.960   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7692, buffer = 0xf6883cb0
11-19 18:01:59.977   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6899: Book-keeping (frame_num, ack_frame_id) = [7696, 6902] in list
11-19 18:01:59.988   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7693, buffer = 0xf68830e0
11-19 18:01:59.994   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7693, buffer = 0xf6883a70
11-19 18:02:00.010   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6900: Book-keeping (frame_num, ack_frame_id) = [7697, 6903] in list
11-19 18:02:00.018   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7694, buffer = 0xf68821b0
11-19 18:02:00.028   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7694, buffer = 0xf6884310
11-19 18:02:00.044   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6901: Book-keeping (frame_num, ack_frame_id) = [7698, 6904] in list
11-19 18:02:00.051   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7695, buffer = 0xf68841c0
11-19 18:02:00.059   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7695, buffer = 0xf6882f30
11-19 18:02:00.077   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6902: Book-keeping (frame_num, ack_frame_id) = [7699, 6905] in list
11-19 18:02:00.084   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7696, buffer = 0xf6884400
11-19 18:02:00.092   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7696, buffer = 0xf68832c0
11-19 18:02:00.110   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6903: Book-keeping (frame_num, ack_frame_id) = [7700, 6906] in list
11-19 18:02:00.118   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7697, buffer = 0xf6883260
11-19 18:02:00.126   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7697, buffer = 0xf6882660
11-19 18:02:00.132   706  6991 I mm-camera: <STATS_AF >< INFO> 439: af_util_done: af->af_mode.mode = 3, af->caf.state=4
11-19 18:02:00.132   706  6991 I mm-camera: <STATS_AF >< INFO> 469: af_util_done: MainCamera=0 final lens pos 410, status=0
11-19 18:02:00.144   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6904: Book-keeping (frame_num, ack_frame_id) = [7701, 6907] in list
11-19 18:02:00.158   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7698, buffer = 0xf6883a40
11-19 18:02:00.161   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7698, buffer = 0xf6884fa0
11-19 18:02:00.163  6263  6288 I pck     : Submit Request-103 with [Stream-0, Stream-2]
11-19 18:02:00.177   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6905: Book-keeping (frame_num, ack_frame_id) = [7702, 6908] in list
11-19 18:02:00.182   579  1392 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
11-19 18:02:00.187   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7699, buffer = 0xf68830e0
11-19 18:02:00.195   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7699, buffer = 0xf68825a0
11-19 18:02:00.258   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6906: Book-keeping (frame_num, ack_frame_id) = [7703, 6909] in list
11-19 18:02:00.263   579  1392 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
11-19 18:02:00.338   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6907: Book-keeping (frame_num, ack_frame_id) = [7704, 6910] in list
11-19 18:02:00.419   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6908: Book-keeping (frame_num, ack_frame_id) = [7705, 6911] in list
11-19 18:02:00.420   706  6997 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
11-19 18:02:00.425   579  1392 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
11-19 18:02:00.500   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6909: Book-keeping (frame_num, ack_frame_id) = [7706, 6912] in list
11-19 18:02:00.501   706  6997 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
11-19 18:02:00.509  6263  6967 D pck     : removeInflightRequest 102
11-19 18:02:00.580   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6910: Book-keeping (frame_num, ack_frame_id) = [7707, 6913] in list
11-19 18:02:00.585  6263  6311 I pck     : Created FrameStream-3 from [Stream-1, Stream-2] 8.69 using MiB/framewith 10 frames max
11-19 18:02:00.587   579  1491 E CamComm1.0-MD: Mismatched tag type when updating entry enable (-2146762752) of type byte; got type int32 data instead
11-19 18:02:00.587   579  1491 E CamComm1.0-MD: Mismatched tag type when updating entry is_main (-2146762751) of type byte; got type int32 data instead
11-19 18:02:00.589  6263  6311 I pck     : Submit Request-106 with [Stream-1, Stream-2]
11-19 18:02:00.660   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6911: Book-keeping (frame_num, ack_frame_id) = [7708, 6914] in list
11-19 18:02:00.660   706  6997 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
11-19 18:02:00.665   579  1392 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
11-19 18:02:00.669   706  6995 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=800000a
11-19 18:02:00.670   706  6995 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000018
11-19 18:02:00.670   706  6995 I mm-camera: <MCT   >< INFO> 600: mct_stream_start_link: Start linking offline stream 0x10006
11-19 18:02:00.670   706  6995 I mm-camera: <MCT   >< INFO> 669: mct_stream_start_link: iface=0x0, isp=0x0, pproc=0xeddc2fc0, imglib=0x0
11-19 18:02:00.670   706  6995 I mm-camera: <PPROC >< INFO> 446: pproc_port_add_modules_to_stream: in identity 10006 stream 9 int_link = 0xedadcc30
11-19 18:02:00.670   706  6995 I mm-camera: <PPROC >< INFO> 458: pproc_port_add_modules_to_stream: :LINK linking mods tmod and cpp for identity 10006
11-19 18:02:00.670   706  6995 I mm-camera: <CPP   >< INFO> 2155: cpp_module_notify_add_stream: :width 4000, height 3000, stride 4032, scanline 3008, framelen 18194432
11-19 18:02:00.670   706  6995 I mm-camera: <CPP   >< INFO> 2179: cpp_module_notify_add_stream: offset_x 0, offset_y 0, offset 0, meta_len 0,meta_scanline 0, meta_stride 0, plane_len 12128256
11-19 18:02:00.670   706  6995 I mm-camera: <CPP   >< INFO> 2179: cpp_module_notify_add_stream: offset_x 0, offset_y 0, offset 0, meta_len 0,meta_scanline 0, meta_stride 0, plane_len 6064128
11-19 18:02:00.670   706  6995 E mm-camera: <CPP   ><ERROR> 2248: cpp_module_notify_add_stream: failed, non supported rotation angle 0
11-19 18:02:00.670   706  6995 I mm-camera: <CPP   >< INFO> 2320: cpp_module_notify_add_stream: : stream 9, fmt 2, asf_mode 0, sharpness_level 0.000000,asf mask 1, denoise 0, denoise_mask 1, dsdn mask 0,dsdn enable 0, tnr mask 0, tnr enable 0, ds_mask 1
11-19 18:02:00.670   706  6995 I mm-camera: <MCT   >< INFO> 773: mct_stream_start_link: Linking single module
11-19 18:02:00.670   706  6995 I mm-camera: <MCT   >< INFO> 781: mct_stream_start_link: single_module=0xeddc2fc0
11-19 18:02:00.670   706  6995 I mm-camera: <MCT   >< INFO> 4005: mct_pipeline_process_set: Linking successful for stream 0x10006 stream type=9
11-19 18:02:00.671   706  6995 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000004
11-19 18:02:00.671   579  1392 E QCamera : <MCI><ERROR> mm_channel_fsm_fn_stopped: 893: invalid state (1) for evt (9)
11-19 18:02:00.671   706  6995 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000008
11-19 18:02:00.672   706  6995 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-ON on stream 0x10006 stream type=9
11-19 18:02:00.672   706  6995 I mm-camera: <CPP   >< INFO> 3836: cpp_module_handle_streamon_event: identity=0x10006, stream-on done
11-19 18:02:00.672   706  6995 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-ON success for stream 0x10006, stream type=9
11-19 18:02:00.673   706  6980 I mm-camera: <CPP   >< INFO> 359: cpp_hardware_set_clock: Set clock 320000000 BW avg 434073600 BW inst 434073600
11-19 18:02:00.692   706  6980 I mm-camera: <CPP   >< INFO> 359: cpp_hardware_set_clock: Set clock 320000000 BW avg 434073600 BW inst 434073600
11-19 18:02:00.740   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6912: Book-keeping (frame_num, ack_frame_id) = [7709, 6915] in list
11-19 18:02:00.741   706  6997 E mm-camera: <IMGLIB><ERROR> 318: faceproc_comp_set_param: Error param=523
11-19 18:02:00.742   579  1392 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
11-19 18:02:00.748  6263  6967 D pck     : removeInflightRequest 104
11-19 18:02:00.820   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6913: Book-keeping (frame_num, ack_frame_id) = [7710, 6916] in list
11-19 18:02:00.900   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6914: Book-keeping (frame_num, ack_frame_id) = [7711, 6917] in list
11-19 18:02:00.901   706  6997 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
11-19 18:02:00.937   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6915: Book-keeping (frame_num, ack_frame_id) = [7712, 6918] in list
11-19 18:02:00.939   706  6997 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
11-19 18:02:00.949  6263  6967 D pck     : removeInflightRequest 105
11-19 18:02:00.967  1692  3224 I chatty  : uid=1000(system) Binder:1692_D identical 1 line
11-19 18:02:00.967  1692  3224 I system_server: oneway function results will be dropped but finished with status OK and parcel size 4
11-19 18:02:00.967   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6916: Book-keeping (frame_num, ack_frame_id) = [7713, 6919] in list
11-19 18:02:00.975   579  7005 I QCamera : <HAL><INFO> doReprocessOffline: 4941: Offline reprocessing id = 6914 buf Id = 0 meta index = 7
11-19 18:02:00.976   706  6995 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000019
11-19 18:02:00.976   706  6995 I mm-camera: <CPP   >< INFO> 865: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS]  stream type 9, width 4000, height 3000,stride 4032, scanline 3008, planes 2
11-19 18:02:00.976   706  6995 I mm-camera: <CPP   >< INFO> 884: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS] plane 0, stride 4032, scanline 3008, offset_x 0, offset_y 0, len 12128256, offset:0
11-19 18:02:00.976   706  6995 I mm-camera: <CPP   >< INFO> 884: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS] plane 1, stride 4032, scanline 3008, offset_x 0, offset_y 0, len 6064128, offset:0
11-19 18:02:00.976   706  6995 I mm-camera: <PPROC >< INFO> 2434: pproc_module_handle_reprocess_offline: input buf fd 141 type 5 size 18194432 identity 10006, frame_id 6914
11-19 18:02:00.977   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7710, buffer = 0xf6883260
11-19 18:02:00.979   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7710, buffer = 0xf6883a70
11-19 18:02:00.979   828  2427 D NuPlayerDriver: NuPlayerDriver(0xeda80a10) created, clientPid(6263)
11-19 18:02:00.997   828  7432 D GenericSource: FileSource remote
11-19 18:02:01.001   808   922 E Utils   : did not find channel-count and/or sample-rate
11-19 18:02:01.002   808   922 E Utils   : did not find channel-count and/or sample-rate
11-19 18:02:01.003   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6917: Book-keeping (frame_num, ack_frame_id) = [7714, 6920] in list
11-19 18:02:01.011   828  7431 I GenericSource: start
11-19 18:02:01.014   579  7427 I QCamera : <HAL><INFO> streamCbRoutine: 4179: bufIndex: 0 recvd from post proc
11-19 18:02:01.014   579  7427 E QCamera : <HAL><ERROR> handleInputBufferWithLock: 3432: Could not find input request for frame number 7709
11-19 18:02:01.016   579  7005 I QCamera : <HAL><INFO> encodeData: 1849: Src Buffer cnt = 1, res = 4032X3008 len = 18194432 rot = 0 src_dim = 4000X3000 dst_dim = 4000X3000
11-19 18:02:01.016   579  7005 I QCamera : <HAL><INFO> encodeData: 1860: Src THUMB buf_cnt = 0, res = 0X0 len = 0 rot = 0 src_dim = 4000X3000, dst_dim = 0X0
11-19 18:02:01.016   579  7005 I qomx_image_core: OMX_GetHandle:243] get instance pts is 0xf3f40c30
11-19 18:02:01.016   579  7005 D qomx_image_core: OMX_GetHandle:261] handle = 0xf3f40c34 Instanceindex = 1,comp_idx 0 g_ptr 0xf40c1cd0
11-19 18:02:01.016   579  7005 I qomx_image_core: OMX_GetHandle:267] Success
11-19 18:02:01.018   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7711, buffer = 0xf6883a40
11-19 18:02:01.018   828  7436 I OMXClient: IOmx service obtained
11-19 18:02:01.019   832  2249 I OMXMaster: makeComponentInstance(OMX.google.mp3.decoder) in android.hardwar process
11-19 18:02:01.020   832  2249 E OMXNodeInstance: setConfig(0xf4c41270:google.mp3.decoder, ConfigPriority(0x6f800002)) ERROR: Undefined(0x80001001)
11-19 18:02:01.020   828  7436 I ACodec  : codec does not support config priority (err -2147483648)
11-19 18:02:01.021   579  7005 I QCamera : <HAL><INFO> encodeData: 1965: Main image idx = 0 src w/h (4000x3000), dst w/h (4000x3000)
11-19 18:02:01.021   828  7436 I MediaCodec: MediaCodec will operate in async mode
11-19 18:02:01.035   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6918: Book-keeping (frame_num, ack_frame_id) = [7715, 6921] in list
11-19 18:02:01.037   579  7007 E QCamera : <JPEG><ERROR> process_sensor_data: 354: : Unsupported flash mode
11-19 18:02:01.039   579  7437 E mm-still: startEncode:556] startEncode()
11-19 18:02:01.040   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7711, buffer = 0xf6884310
11-19 18:02:01.042   579  7437 I jpeg_hw : jpege_lib_hw_fe_buffer_cfg:295] w 4000 h 3000 stride 4032 scanline 3008
11-19 18:02:01.046   579  7016 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7712, buffer = 0xf68830e0
11-19 18:02:01.048   579  7018 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7712, buffer = 0xf6882f30
11-19 18:02:01.080   579  7437 I QCamera : <JPEG><INFO> mm_jpeg_fbd: 3553: count 0
11-19 18:02:01.081   579  7437 I QCamera : <JPEG><INFO> mm_jpeg_fbd: 3554: KPI Perf] : PROFILE_JPEG_FBD
11-19 18:02:01.082   579  7437 I QCamera : <HAL><INFO> jpegEvtHandle: 3417: Issue Jpeg Callback frameNumber = 7709 status = 0
11-19 18:02:01.082   579  7437 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 7709, buffer = 0xf6882d50
11-19 18:02:01.086  6263  6288 I pck     : Submit Request-108 with [Stream-0, Stream-2]
11-19 18:02:01.090  6263  6956 W MetricsLogger: MetricsLogger BURST_CAPTURE Latency (ms): 3320
11-19 18:02:01.091  6263  6263 W ShutterImageButton: stopCircularProgress while animation is already stopped
11-19 18:02:01.092  6263  6643 W MetricsLogger: MetricsLogger SHOT_TO_SHOT Latency (ms): 3323
11-19 18:02:01.095  6291  7138 I gle.android.gm: oneway function results will be dropped but finished with status OK and parcel size 4
11-19 18:02:01.114   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6919: Book-keeping (frame_num, ack_frame_id) = [7716, 6922] in list
11-19 18:02:01.119   579  1491 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
11-19 18:02:01.162  1692  6633 I system_server: oneway function results will be dropped but finished with status OK and parcel size 4
11-19 18:02:01.164   573   620 E FMQ     : grantorIdx must be less than 3
11-19 18:02:01.164   573   620 E FMQ     : grantorIdx must be less than 3
11-19 18:02:01.165   828  7434 D AudioTrack: stop(32): called with 9216 frames delivered
11-19 18:02:01.166   640  1132 E FMQ     : grantorIdx must be less than 3
11-19 18:02:01.166   640  1132 E FMQ     : grantorIdx must be less than 3
11-19 18:02:01.195   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6920: Book-keeping (frame_num, ack_frame_id) = [7717, 6923] in list
11-19 18:02:01.196   706  6991 I mm-camera: <STATS_AF >< INFO> 439: af_util_done: af->af_mode.mode = 3, af->caf.state=5
11-19 18:02:01.196   706  6991 I mm-camera: <STATS_AF >< INFO> 469: af_util_done: MainCamera=0 final lens pos 410, status=2
11-19 18:02:01.202   579  1491 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
11-19 18:02:01.249  6263  6959 W MetricsLogger: MetricsLogger SHUTTER_LAG Latency (ms): 3480
11-19 18:02:01.276   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6921: Book-keeping (frame_num, ack_frame_id) = [7718, 6924] in list
11-19 18:02:01.355   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6922: Book-keeping (frame_num, ack_frame_id) = [7719, 6925] in list
11-19 18:02:01.356   706  6997 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
11-19 18:02:01.436   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6923: Book-keeping (frame_num, ack_frame_id) = [7720, 6926] in list
11-19 18:02:01.436   706  6983 I mm-camera: <ISP   >< INFO> 230: ihist_stats44_stats_config_validate: warning: Invalid IHIST ROI from 3A 0 0 0 0
11-19 18:02:01.436   706  6997 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
11-19 18:02:01.440  6263  6967 D pck     : removeInflightRequest 107
11-19 18:02:01.516   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6924: Book-keeping (frame_num, ack_frame_id) = [7721, 6927] in list
11-19 18:02:01.520  6263  6967 D pck     : removeInflightRequest 108
11-19 18:02:01.530  1692  5926 I system_server: oneway function results will be dropped but finished with status OK and parcel size 4
11-19 18:02:01.581  3183  3501 W SQLiteQueryBuilder: Allowing abusive custom column: AND
11-19 18:02:01.581  3183  3501 W SQLiteQueryBuilder: Allowing abusive custom column: IN
11-19 18:02:01.586  3183  3501 I MediaProvider: Retrying database update after deleting conflicting entry
11-19 18:02:01.597   706  6997 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 6925: Book-keeping (frame_num, ack_frame_id) = [7722, 6928] in list
11-19 18:02:01.611  6263  6959 W MetricsLogger: MetricsLogger DISK_IO Latency (ms): 372
11-19 18:02:01.611  6263  6286 I NewMediaBroadcasterImpl: Sending broadcast: android.hardware.action.NEW_PICTURE
11-19 18:02:01.623  6263  6957 W MetricsLogger: MetricsLogger END_TO_END Latency (ms): 3854
11-19 18:02:01.624  6291  7138 I gle.android.gm: oneway function results will be dropped but finished with status OK and parcel size 4
11-19 18:02:01.626  6291  7138 I gle.android.gm: oneway function results will be dropped but finished with status OK and parcel size 4
11-19 18:02:02.502   706  6991 I mm-camera: <STATS_AF >< INFO> 439: af_util_done: af->af_mode.mode = 3, af->caf.state=4
11-19 18:02:02.502   706  6991 I mm-camera: <STATS_AF >< INFO> 469: af_util_done: MainCamera=0 final lens pos 427, status=1

[ItsVixano]

Logs grabbed from lineage 20

10-23 15:48:10.479 11366 11376 W System  : A resource failed to call close.
10-23 15:48:10.479 11366 11376 W System  : A resource failed to call close.
10-23 15:48:10.480 11366 11376 W System  : A resource failed to call close.
10-23 15:48:10.480 11366 11376 W System  : A resource failed to call close.
10-23 15:48:10.480 11366 11376 W System  : A resource failed to call close.
10-23 15:48:10.490   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 28: Book-keeping (frame_num, ack_frame_id) = [825, 31] in list
10-23 15:48:10.490   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 28
10-23 15:48:10.560   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 29: Book-keeping (frame_num, ack_frame_id) = [826, 32] in list
10-23 15:48:10.560   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 29
10-23 15:48:10.629   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 30: Book-keeping (frame_num, ack_frame_id) = [827, 33] in list
10-23 15:48:10.629   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 30
10-23 15:48:10.701   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 31: Book-keeping (frame_num, ack_frame_id) = [828, 34] in list
10-23 15:48:10.701   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 31
10-23 15:48:10.768   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 32: Book-keeping (frame_num, ack_frame_id) = [829, 35] in list
10-23 15:48:10.768   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 32
10-23 15:48:10.836   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 33: Book-keeping (frame_num, ack_frame_id) = [830, 36] in list
10-23 15:48:10.837   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 33
10-23 15:48:10.906   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 34: Book-keeping (frame_num, ack_frame_id) = [831, 37] in list
10-23 15:48:10.906   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 34
10-23 15:48:10.975   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 35: Book-keeping (frame_num, ack_frame_id) = [832, 38] in list
10-23 15:48:10.976   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 35
10-23 15:48:10.996   727 13019 I mm-camera: <STATS_AF >< INFO> 439: af_util_done: af->af_mode.mode = 3, af->caf.state=4
10-23 15:48:10.996   727 13019 I mm-camera: <STATS_AF >< INFO> 469: af_util_done: MainCamera=0 final lens pos 427, status=1
10-23 15:48:11.045   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 36: Book-keeping (frame_num, ack_frame_id) = [833, 39] in list
10-23 15:48:11.045   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 36
10-23 15:48:11.114   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 37: Book-keeping (frame_num, ack_frame_id) = [834, 40] in list
10-23 15:48:11.114   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 37
10-23 15:48:11.183   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 38: Book-keeping (frame_num, ack_frame_id) = [835, 41] in list
10-23 15:48:11.183   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 38
10-23 15:48:11.186   586  1188 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
10-23 15:48:11.252   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 39: Book-keeping (frame_num, ack_frame_id) = [836, 42] in list
10-23 15:48:11.253   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 39
10-23 15:48:11.254   586  1188 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
10-23 15:48:11.323   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 40: Book-keeping (frame_num, ack_frame_id) = [837, 43] in list
10-23 15:48:11.324   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 40
10-23 15:48:11.325   586  1188 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
10-23 15:48:11.391   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 41: Book-keeping (frame_num, ack_frame_id) = [838, 44] in list
10-23 15:48:11.392   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 41
10-23 15:48:11.392   727 13025 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
10-23 15:48:11.461   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 42: Book-keeping (frame_num, ack_frame_id) = [839, 45] in list
10-23 15:48:11.461   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 42
10-23 15:48:11.461   727 13025 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
10-23 15:48:11.482   727 13019 E mm-camera: <STATS_AF ><ERROR> 888: af_process_parse_stats: Invalid FV data!
10-23 15:48:11.532   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 43: Book-keeping (frame_num, ack_frame_id) = [840, 46] in list
10-23 15:48:11.532   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 43
10-23 15:48:11.533   727 13025 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
10-23 15:48:11.552   727 13019 E mm-camera: <STATS_AF ><ERROR> 888: af_process_parse_stats: Invalid FV data!
10-23 15:48:11.601   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 44: Book-keeping (frame_num, ack_frame_id) = [841, 47] in list
10-23 15:48:11.601   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 44
10-23 15:48:11.621   727 13019 E mm-camera: <STATS_AF ><ERROR> 888: af_process_parse_stats: Invalid FV data!
10-23 15:48:11.670   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 45: Book-keeping (frame_num, ack_frame_id) = [842, 48] in list
10-23 15:48:11.670   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 45
10-23 15:48:11.690   727 13019 E mm-camera: <STATS_AF ><ERROR> 888: af_process_parse_stats: Invalid FV data!
10-23 15:48:11.740   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 46: Book-keeping (frame_num, ack_frame_id) = [843, 49] in list
10-23 15:48:11.740   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 46
10-23 15:48:11.809   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 47: Book-keeping (frame_num, ack_frame_id) = [844, 50] in list
10-23 15:48:11.809   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 47
10-23 15:48:11.878   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 48: Book-keeping (frame_num, ack_frame_id) = [845, 51] in list
10-23 15:48:11.878   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 48
10-23 15:48:11.947   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 49: Book-keeping (frame_num, ack_frame_id) = [846, 52] in list
10-23 15:48:11.948   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 49
10-23 15:48:12.008   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 50: Book-keeping (frame_num, ack_frame_id) = [847, 53] in list
10-23 15:48:12.009   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 50
10-23 15:48:12.048   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 51: Book-keeping (frame_num, ack_frame_id) = [848, 54] in list
10-23 15:48:12.049   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 51
10-23 15:48:12.087   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 52: Book-keeping (frame_num, ack_frame_id) = [849, 55] in list
10-23 15:48:12.087   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 52
10-23 15:48:12.127   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 53: Book-keeping (frame_num, ack_frame_id) = [850, 56] in list
10-23 15:48:12.127   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 53
10-23 15:48:12.167   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 54: Book-keeping (frame_num, ack_frame_id) = [851, 57] in list
10-23 15:48:12.167   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 54
10-23 15:48:12.205   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 55: Book-keeping (frame_num, ack_frame_id) = [852, 58] in list
10-23 15:48:12.206   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 55
10-23 15:48:12.245   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 56: Book-keeping (frame_num, ack_frame_id) = [853, 59] in list
10-23 15:48:12.245   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 56
10-23 15:48:12.284   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 57: Book-keeping (frame_num, ack_frame_id) = [854, 60] in list
10-23 15:48:12.284   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 57
10-23 15:48:12.323   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 58: Book-keeping (frame_num, ack_frame_id) = [855, 61] in list
10-23 15:48:12.323   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 58
10-23 15:48:12.363   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 59: Book-keeping (frame_num, ack_frame_id) = [856, 62] in list
10-23 15:48:12.363   727 13025 I mm-camera: <MCT   >< INFO> 1023: mct_controller_handle_SOF_proc: (sofdelay, curr_sofdelay) = (0, 0) kptr 59
10-23 15:48:12.368   586 13046 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 853, buffer = 0xeee442b0
10-23 15:48:12.402   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 60: Book-keeping (frame_num, ack_frame_id) = [857, 63] in list
10-23 15:48:12.407   586 13046 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 854, buffer = 0xeee42cc0
10-23 15:48:12.443   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 61: Book-keeping (frame_num, ack_frame_id) = [858, 64] in list
10-23 15:48:12.448   586 13046 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 855, buffer = 0xeee42630
10-23 15:48:12.482   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 62: Book-keeping (frame_num, ack_frame_id) = [859, 65] in list
10-23 15:48:12.520   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 63: Book-keeping (frame_num, ack_frame_id) = [860, 66] in list
10-23 15:48:12.560   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 64: Book-keeping (frame_num, ack_frame_id) = [861, 67] in list
10-23 15:48:12.560   727 13008 I mm-camera: <ISP   >< INFO> 230: ihist_stats44_stats_config_validate: warning: Invalid IHIST ROI from 3A 0 0 0 0
10-23 15:48:12.564   586 13046 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 858, buffer = 0xeee43c80
10-23 15:48:12.600   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 65: Book-keeping (frame_num, ack_frame_id) = [862, 68] in list
10-23 15:48:12.609   586 11325 E CamComm1.0-MD: Mismatched tag type when updating entry enable (-2146762752) of type byte; got type int32 data instead
10-23 15:48:12.609   586 11325 E CamComm1.0-MD: Mismatched tag type when updating entry is_main (-2146762751) of type byte; got type int32 data instead
10-23 15:48:12.639   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 66: Book-keeping (frame_num, ack_frame_id) = [863, 69] in list
10-23 15:48:12.643   586  1188 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
10-23 15:48:12.646   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=800000a
10-23 15:48:12.648   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000018
10-23 15:48:12.648   727 13023 I mm-camera: <MCT   >< INFO> 600: mct_stream_start_link: Start linking offline stream 0x10005
10-23 15:48:12.648   727 13023 I mm-camera: <MCT   >< INFO> 669: mct_stream_start_link: iface=0x0, isp=0x0, pproc=0xe5f02850, imglib=0x0
10-23 15:48:12.648   727 13023 I mm-camera: <PPROC >< INFO> 446: pproc_port_add_modules_to_stream: in identity 10005 stream 9 int_link = 0xe5b5e730
10-23 15:48:12.648   727 13023 I mm-camera: <PPROC >< INFO> 458: pproc_port_add_modules_to_stream: :LINK linking mods tmod and cpp for identity 10005
10-23 15:48:12.648   727 13023 I mm-camera: <CPP   >< INFO> 2155: cpp_module_notify_add_stream: :width 4000, height 2250, stride 4032, scanline 2304, framelen 13934592
10-23 15:48:12.648   727 13023 I mm-camera: <CPP   >< INFO> 2179: cpp_module_notify_add_stream: offset_x 0, offset_y 0, offset 0, meta_len 0,meta_scanline 0, meta_stride 0, plane_len 9289728
10-23 15:48:12.648   727 13023 I mm-camera: <CPP   >< INFO> 2179: cpp_module_notify_add_stream: offset_x 0, offset_y 0, offset 0, meta_len 0,meta_scanline 0, meta_stride 0, plane_len 4644864
10-23 15:48:12.648   727 13023 E mm-camera: <CPP   ><ERROR> 2248: cpp_module_notify_add_stream: failed, non supported rotation angle 0
10-23 15:48:12.648   727 13023 I mm-camera: <CPP   >< INFO> 2320: cpp_module_notify_add_stream: : stream 9, fmt 2, asf_mode 0, sharpness_level 0.000000,asf mask 1, denoise 0, denoise_mask 1, dsdn mask 0,dsdn enable 0, tnr mask 0, tnr enable 0, ds_mask 1
10-23 15:48:12.649   727 13023 I mm-camera: <MCT   >< INFO> 773: mct_stream_start_link: Linking single module
10-23 15:48:12.649   727 13023 I mm-camera: <MCT   >< INFO> 781: mct_stream_start_link: single_module=0xe5f02850
10-23 15:48:12.649   727 13023 I mm-camera: <MCT   >< INFO> 4005: mct_pipeline_process_set: Linking successful for stream 0x10005 stream type=9
10-23 15:48:12.649   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000004
10-23 15:48:12.649   586  1188 E QCamera : <MCI><ERROR> mm_channel_fsm_fn_stopped: 893: invalid state (1) for evt (9)
10-23 15:48:12.650   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000008
10-23 15:48:12.650   727 13023 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-ON on stream 0x10005 stream type=9
10-23 15:48:12.650   727 13023 I mm-camera: <CPP   >< INFO> 3836: cpp_module_handle_streamon_event: identity=0x10005, stream-on done
10-23 15:48:12.650   727 13023 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-ON success for stream 0x10005, stream type=9
10-23 15:48:12.651   727 13012 I mm-camera: <CPP   >< INFO> 359: cpp_hardware_set_clock: Set clock 200000000 BW avg 235062000 BW inst 235062000
10-23 15:48:12.667   727 13012 I mm-camera: <CPP   >< INFO> 359: cpp_hardware_set_clock: Set clock 200000000 BW avg 235062000 BW inst 235062000
10-23 15:48:12.679   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 67: Book-keeping (frame_num, ack_frame_id) = [864, 70] in list
10-23 15:48:12.680   727 13025 E mm-camera: <IMGLIB><ERROR> 318: faceproc_comp_set_param: Error param=523
10-23 15:48:12.682   586  1188 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
10-23 15:48:12.718   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 68: Book-keeping (frame_num, ack_frame_id) = [865, 71] in list
10-23 15:48:12.757   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 69: Book-keeping (frame_num, ack_frame_id) = [866, 72] in list
10-23 15:48:12.758   727 13025 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
10-23 15:48:12.791   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 70: Book-keeping (frame_num, ack_frame_id) = [867, 73] in list
10-23 15:48:12.792   727 13025 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
10-23 15:48:12.812   586 13039 I QCamera : <HAL><INFO> doReprocessOffline: 4941: Offline reprocessing id = 69 buf Id = 0 meta index = 7
10-23 15:48:12.812   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000019
10-23 15:48:12.812   727 13023 I mm-camera: <CPP   >< INFO> 865: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS]  stream type 9, width 4000, height 2250,stride 4032, scanline 2304, planes 2
10-23 15:48:12.812   727 13023 I mm-camera: <CPP   >< INFO> 884: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS] plane 0, stride 4032, scanline 2304, offset_x 0, offset_y 0, len 9289728, offset:0
10-23 15:48:12.812   727 13023 I mm-camera: <CPP   >< INFO> 884: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS] plane 1, stride 4032, scanline 2304, offset_x 0, offset_y 0, len 4644864, offset:0
10-23 15:48:12.813   727 13023 I mm-camera: <PPROC >< INFO> 2434: pproc_module_handle_reprocess_offline: input buf fd 134 type 5 size 13934592 identity 10005, frame_id 69
10-23 15:48:12.823   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 71: Book-keeping (frame_num, ack_frame_id) = [868, 74] in list
10-23 15:48:12.840   586 13046 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 865, buffer = 0xeee43c80
10-23 15:48:12.858   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 72: Book-keeping (frame_num, ack_frame_id) = [869, 75] in list
10-23 15:48:12.865   586 13062 I QCamera : <HAL><INFO> streamCbRoutine: 4179: bufIndex: 0 recvd from post proc
10-23 15:48:12.867   586 13062 E QCamera : <HAL><ERROR> handleInputBufferWithLock: 3432: Could not find input request for frame number 864
10-23 15:48:12.871   586 13046 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 866, buffer = 0xeee442b0
10-23 15:48:12.871   586 13039 I QCamera : <HAL><INFO> encodeData: 1849: Src Buffer cnt = 1, res = 2304X4032 len = 13934592 rot = 0 src_dim = 4000X2250 dst_dim = 4000X2250
10-23 15:48:12.871   586 13039 I QCamera : <HAL><INFO> encodeData: 1860: Src THUMB buf_cnt = 0, res = 0X0 len = 0 rot = 0 src_dim = 2250X4000, dst_dim = 0X0
10-23 15:48:12.871   586 13039 I qomx_image_core: OMX_GetHandle:243] get instance pts is 0xec282a80
10-23 15:48:12.872   586 13039 D qomx_image_core: OMX_GetHandle:261] handle = 0xec282a84 Instanceindex = 1,comp_idx 0 g_ptr 0xec080330
10-23 15:48:12.872   586 13039 I qomx_image_core: OMX_GetHandle:267] Success
10-23 15:48:12.875   586 13039 I QCamera : <HAL><INFO> encodeData: 1965: Main image idx = 0 src w/h (2250x4000), dst w/h (2250x4000)
10-23 15:48:12.878   586 13040 E QCamera : <JPEG><ERROR> process_sensor_data: 354: : Unsupported flash mode
10-23 15:48:12.880   586 13064 E mm-still: startEncode:556] startEncode()
10-23 15:48:12.880   586 13064 I jpeg_hw : jpege_lib_hw_fe_buffer_cfg:295] w 2250 h 4000 stride 2304 scanline 4032
10-23 15:48:12.890   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 73: Book-keeping (frame_num, ack_frame_id) = [870, 76] in list
10-23 15:48:12.897   586 13046 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 867, buffer = 0xeee42cc0
10-23 15:48:12.908   586 13064 I QCamera : <JPEG><INFO> mm_jpeg_fbd: 3553: count 0
10-23 15:48:12.908   586 13064 I QCamera : <JPEG><INFO> mm_jpeg_fbd: 3554: KPI Perf] : PROFILE_JPEG_FBD
10-23 15:48:12.910   586 13064 I QCamera : <HAL><INFO> jpegEvtHandle: 3417: Issue Jpeg Callback frameNumber = 864 status = 0
10-23 15:48:12.910   586 13064 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 864, buffer = 0xeee42f30
10-23 15:48:12.911   727 13019 I mm-camera: <STATS_AF >< INFO> 439: af_util_done: af->af_mode.mode = 3, af->caf.state=4
10-23 15:48:12.911   727 13019 I mm-camera: <STATS_AF >< INFO> 469: af_util_done: MainCamera=0 final lens pos 405, status=1
10-23 15:48:12.941   751  3425 D Camera2ClientBase: Camera 0: start to disconnect
10-23 15:48:12.941   751  3425 D Camera2ClientBase: Camera 0: serializationLock acquired
10-23 15:48:12.941   751  3425 D Camera2ClientBase: Camera 0: Shutting down
10-23 15:48:12.941   751  3425 D Camera2ClientBase: Camera 0: start to cacheDump
10-23 15:48:12.959   727 13025 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 74: Book-keeping (frame_num, ack_frame_id) = [871, 77] in list
10-23 15:48:12.980   586 13039 V qomx_image_core: OMX_FreeHandle:338]
10-23 15:48:12.980   586 13039 D qomx_image_core: get_idx_from_handle:297] comp_idx 0 inst_idx 1
10-23 15:48:12.981   586 13039 I qomx_image_core: OMX_FreeHandle:370] Error Component is still Active
10-23 15:48:12.981   586 13039 V qomx_image_core: OMX_FreeHandle:373] Success
10-23 15:48:12.981   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-23 15:48:12.981   727 13023 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10005 stream type=9
10-23 15:48:12.981   727 13023 I mm-camera: <CPP   >< INFO> 3927: cpp_module_handle_streamoff_event: info: doing stream-off for identity 0x10005
10-23 15:48:12.982   727 13023 I mm-camera: <CPP   >< INFO> 4112: cpp_module_handle_streamoff_event: info: stream-off done for identity 0x10005
10-23 15:48:12.982   727 13023 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10005, stream type=9
10-23 15:48:12.983   727 13012 I mm-camera: <CPP   >< INFO> 359: cpp_hardware_set_clock: Set clock 100000000 BW avg 93312000 BW inst 93312000
10-23 15:48:12.985   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=800000b
10-23 15:48:12.985   727 13023 I mm-camera: <MCT   >< INFO> 4125: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x10005 and stream type=9
10-23 15:48:12.985   727 13023 I mm-camera: <MCT   >< INFO> 4136: mct_pipeline_process_set: Stream 0x10005 and stream type=9, successfully deleted
10-23 15:48:12.986   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-23 15:48:12.986   727 13023 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10004 stream type=11
10-23 15:48:12.987   727 13023 I mm-camera: <IFACE >< INFO> 1619: iface_streamoff_to_thread: iface_streamoff_to_thread: E, session id = 1, user stream id = 4
10-23 15:48:12.988   727 13006 I mm-camera: <IFACE >< INFO> 2211: iface_streamoff: E, session_id 1 mct_stream_id = 4
10-23 15:48:12.988   727 13006 I mm-camera: <IFACE >< INFO> 10995: iface_util_get_user_streams_by_bundle: streamoff, first bundled streamoff all bindled streams!
10-23 15:48:12.992   727 13012 I mm-camera: <CPP   >< INFO> 359: cpp_hardware_set_clock: Set clock 100000000 BW avg 93312000 BW inst 93312000
10-23 15:48:12.993   727 13006 I mm-camera: <IFACE >< INFO> 380: iface_ispif_get_cfg_params_from_hw_streams: interace 0 active count = 2, skip stop ispif
10-23 15:48:12.993   727 13006 I mm-camera: <IFACE >< INFO> 380: iface_ispif_get_cfg_params_from_hw_streams: interace 0 active count = 1, skip stop ispif
10-23 15:48:12.994   727 13006 I mm-camera: <IFACE >< INFO> 2401: iface_streamoff: session_id = 1, active_streams = 0
10-23 15:48:12.994   727 13006 I mm-camera: <IFACE >< INFO> 2483: iface_streamoff: X
10-23 15:48:12.994   727 13023 I mm-camera: <IFACE >< INFO> 1645: iface_streamoff_to_thread: iface_streamoff_to_thread: X, session id = 1, user stream id = 4
10-23 15:48:12.994   727 13023 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10004, stream type=11
10-23 15:48:12.999   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-23 15:48:12.999   727 13023 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10002 stream type=1
10-23 15:48:12.999   727 13023 I mm-camera: <CPP   >< INFO> 3927: cpp_module_handle_streamoff_event: info: doing stream-off for identity 0x10002
10-23 15:48:13.002   727 13012 E mm-camera: <IFACE ><ERROR> 637: iface_queue_buf_int: iface_queue_buf_int: queue buf to kernel failed, rc = -1
10-23 15:48:13.002   727 13012 E mm-camera: <IFACE ><ERROR> 1862: iface_axi_divert_ack: iface_axi_divert_ack: isp_queue_buf error = -1
10-23 15:48:13.002   727 13012 E mm-camera: <IFACE ><ERROR> 11498: iface_util_divert_ack: iface_util_divert_ack: VFE1 IFACE_AXI_ACTION_CODE_STREAM_DIVERT_ACK failed! rc = -1
10-23 15:48:13.002   727 13012 E mm-camera: <ISP   ><ERROR> 1366: isp_util_forward_event: failed: mct_port_send_event_to_peer event->type 2
10-23 15:48:13.002   727 13012 E mm-camera: <ISP   ><ERROR> 473: isp_port_process_upstream_event: failed: isp_handler_enqueue_event_to_trigger_queue
10-23 15:48:13.002   727 13012 E mm-camera: <ISP   ><ERROR> 528: isp_port_event: failed: isp_port_process_upstream_event
10-23 15:48:13.002   727 13012 E mm-camera: <PPROC ><ERROR> 678: base_port_handle_buf_divert_ack: base_port_handle_buf_divert_ack:678, failed
10-23 15:48:13.002   727 13012 E mm-camera: <CPP   ><ERROR> 900: cpp_module_send_event_upstream: send_event_to_peer failed 0
10-23 15:48:13.002   727 13012 E mm-camera: <CPP   ><ERROR> 976: cpp_module_send_buf_divert_ack: [CPP_BUF:] send_event_upstream failed -14
10-23 15:48:13.003   727 13012 I mm-camera: <CPP   >< INFO> 359: cpp_hardware_set_clock: Set clock 100000000 BW avg 0 BW inst 0
10-23 15:48:13.003   727 13023 I mm-camera: <CPP   >< INFO> 4112: cpp_module_handle_streamoff_event: info: stream-off done for identity 0x10002
10-23 15:48:13.004   727 13023 I mm-camera: <IFACE >< INFO> 1619: iface_streamoff_to_thread: iface_streamoff_to_thread: E, session id = 1, user stream id = 2
10-23 15:48:13.004   727 13006 I mm-camera: <IFACE >< INFO> 2211: iface_streamoff: E, session_id 1 mct_stream_id = 2
10-23 15:48:13.004   727 13006 I mm-camera: <IFACE >< INFO> 11001: iface_util_get_user_streams_by_bundle: skip streamoff! left 1 bundled streamoff, mct stream id 2total bundled stream num = 3
10-23 15:48:13.004   727 13006 I mm-camera: <IFACE >< INFO> 2483: iface_streamoff: X
10-23 15:48:13.004   727 13023 I mm-camera: <IFACE >< INFO> 1645: iface_streamoff_to_thread: iface_streamoff_to_thread: X, session id = 1, user stream id = 2
10-23 15:48:13.004   727 13023 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10002, stream type=1
10-23 15:48:13.004   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-23 15:48:13.004   727 13023 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10003 stream type=3
10-23 15:48:13.005   727 13023 E mm-camera: <STATS_AIS ><ERROR> 173: dsps_send_req: DSPS Send Request Timeout!!
10-23 15:48:13.005   727 13023 E mm-camera: <STATS_AIS ><ERROR> 173: dsps_send_req: DSPS Send Request Timeout!!
10-23 15:48:13.005   727 13023 E mm-camera: <STATS_AIS ><ERROR> 173: dsps_send_req: DSPS Send Request Timeout!!
10-23 15:48:13.006   727 13023 I mm-camera: <IFACE >< INFO> 1619: iface_streamoff_to_thread: iface_streamoff_to_thread: E, session id = 1, user stream id = 3
10-23 15:48:13.006   727 13006 I mm-camera: <IFACE >< INFO> 2211: iface_streamoff: E, session_id 1 mct_stream_id = 3
10-23 15:48:12.971     0     0 E msm_isp_buf_enqueue: Invalid bufq, handle 0x1000205, stream id 10002 num_plane 2
10-23 15:48:13.006   727 13006 I mm-camera: <IFACE >< INFO> 11001: iface_util_get_user_streams_by_bundle: skip streamoff! left 0 bundled streamoff, mct stream id 3total bundled stream num = 3
10-23 15:48:13.007   727 13006 I mm-camera: <IFACE >< INFO> 2420: iface_streamoff: session id = 0x1,Delta between vfe irqs 0 sec 0 usec
10-23 15:48:13.010   727 13006 I mm-camera: <IFACE >< INFO> 10046: iface_util_release_resource: camif_cnt= 1, rdi_cnt= 0, used mask 10000
10-23 15:48:13.010   727 13006 I mm-camera: <IFACE >< INFO> 10046: iface_util_release_resource: camif_cnt= 0, rdi_cnt= 0, used mask 0
10-23 15:48:13.011   727 13006 I mm-camera: <IFACE >< INFO> 10046: iface_util_release_resource: camif_cnt= 0, rdi_cnt= 0, used mask 0
10-23 15:48:13.011   727 13006 I mm-camera: <IFACE >< INFO> 2478: iface_streamoff: all bundled stream streamoff, clear hal bundled mask
10-23 15:48:13.011   727 13006 I mm-camera: <IFACE >< INFO> 2483: iface_streamoff: X
10-23 15:48:13.011   727 13023 I mm-camera: <IFACE >< INFO> 1645: iface_streamoff_to_thread: iface_streamoff_to_thread: X, session id = 1, user stream id = 3
10-23 15:48:13.012   727 13023 I mm-camera: <MCT   >< INFO> 177: stop_sof_check_thread: Stopping SOF timeout thread session =1
10-23 15:48:13.012   727 13054 I mm-camera: <MCT   >< INFO> 114: mct_bus_sof_thread_run: Closing SOF tracker thread
10-23 15:48:13.013   727 13023 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10003, stream type=3
10-23 15:48:13.013   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-23 15:48:13.013   727 13023 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10001 stream type=7
10-23 15:48:13.013   727 13023 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10001, stream type=7
10-23 15:48:13.032   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000019
10-23 15:48:13.032   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000019
10-23 15:48:13.033   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000019
10-23 15:48:13.060   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000008
10-23 15:48:13.060   727 13023 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-ON on stream 0x10001 stream type=7
10-23 15:48:13.060   727 13023 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-ON success for stream 0x10001, stream type=7
10-23 15:48:13.060   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000008
10-23 15:48:13.061   727 13023 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-ON on stream 0x10002 stream type=1
10-23 15:48:13.052   727   727 W CAM_MctServ: type=1400 audit(0.0:555): avc: denied { read } for name="u:object_r:default_prop:s0" dev="tmpfs" ino=16434 scontext=u:r:mm-qcamerad:s0 tcontext=u:object_r:default_prop:s0 tclass=file permissive=0
10-23 15:48:13.061   727 13023 W libc    : Access denied finding property "camera.clientname"
10-23 15:48:13.061   727 13023 I mm-camera: <SENSOR>< INFO> 3789: sensor_get_resolution_info: sensor info: name: sakura_imx486_ofilm, res: 1, max_fps: 30.010000, w: 4032, h: 2272 op pix clk: 336000000, FLL: 2608, LLPCK: 5008, mode: 1, PDAF support: 1
10-23 15:48:13.062   727 13023 I mm-camera: <CPP   >< INFO> 1734: cpp_module_handle_stream_cfg_event: frame_offset=2, input_fps=30.00, identity=0x1000f
10-23 15:48:13.062   727 13023 I mm-camera: <CPP   >< INFO> 1734: cpp_module_handle_stream_cfg_event: frame_offset=2, input_fps=30.00, identity=0x10002
10-23 15:48:13.064   727 13023 I mm-camera: <IFACE >< INFO> 409: iface_util_dump_sensor_cfg: iface_util_dump_sensor_cfg: sensor dim: width = 4032, heght = 2272, fmt = 30, is_bayer = 1, init_skip = 1
10-23 15:48:13.068   727 13023 E mm-camera: <IFACE ><ERROR> 4075: iface_util_set_hw_stream_config_pix: iface_util_set_hw_stream_config_pix: Assigned vfe 1 intf 0 for related cam type 1
10-23 15:48:13.068   727 13023 I mm-camera: <IFACE >< INFO> 3573: iface_util_dump_isp_resource_request:  =====DUMP RESOURCE: PIX STREAM===
10-23 15:48:13.068   727 13023 I mm-camera: <IFACE >< INFO> 3574: iface_util_dump_isp_resource_request: num pix stream = 2
10-23 15:48:13.068   727 13023 I mm-camera: <IFACE >< INFO> 3577: iface_util_dump_isp_resource_request: num_isp = 1, isp_mask = 2, request_op_pix_clk = 0
10-23 15:48:13.068   727 13023 I mm-camera: <IFACE >< INFO> 3581: iface_util_dump_isp_resource_request: ispif stripe info: split 0, overlap 0, stripe offset 0
10-23 15:48:13.068   727 13023 I mm-camera: <IFACE >< INFO> 3584: iface_util_dump_isp_resource_request: pix stream 0
10-23 15:48:13.068   727 13023 I mm-camera: <IFACE >< INFO> 3587: iface_util_dump_isp_resource_request: num mct stream mapped = 2(Mapped stream ID[0] = 2)
10-23 15:48:13.068   727 13023 I mm-camera: <IFACE >< INFO> 3590: iface_util_dump_isp_resource_request: axi path = 1(ENC-0/VIEW-1/CAMIF-2/IDEAL-3), cam_fmt 7
10-23 15:48:13.068   727 13023 I mm-camera: <IFACE >< INFO> 3584: iface_util_dump_isp_resource_request: pix stream 1
10-23 15:48:13.068   727 13023 I mm-camera: <IFACE >< INFO> 3587: iface_util_dump_isp_resource_request: num mct stream mapped = 1(Mapped stream ID[0] = 3)
10-23 15:48:13.068   727 13023 I mm-camera: <IFACE >< INFO> 3590: iface_util_dump_isp_resource_request: axi path = 0(ENC-0/VIEW-1/CAMIF-2/IDEAL-3), cam_fmt 2
10-23 15:48:13.068   727 13023 I mm-camera: <IFACE >< INFO> 3612: iface_util_dump_isp_resource_request: num STATS stream = 5
10-23 15:48:13.068   727 13023 I mm-camera: <CPP   >< INFO> 865: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS]  stream type 1, width 1280, height 720,stride 1280, scanline 720, planes 2
10-23 15:48:13.068   727 13023 I mm-camera: <CPP   >< INFO> 884: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS] plane 0, stride 1280, scanline 720, offset_x 0, offset_y 0, len 921600, offset:0
10-23 15:48:13.068   727 13023 I mm-camera: <CPP   >< INFO> 884: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS] plane 1, stride 1280, scanline 720, offset_x 0, offset_y 0, len 460800, offset:0
10-23 15:48:13.071   727 13023 I mm-camera: <IFACE >< INFO> 8904: iface_util_dump_camif_cfg: =====Camif DUMP cfg for PIX interface====
10-23 15:48:13.071   727 13023 I mm-camera: <IFACE >< INFO> 8906: iface_util_dump_camif_cfg: camif input type = 3(MIPI=3), op_pix_clk = 320581411
10-23 15:48:13.071   727 13023 I mm-camera: <IFACE >< INFO> 8908: iface_util_dump_camif_cfg: camif pix_pattern(RGRG-0/GRGR-1/BGBG-2/GBGB-3) = 0
10-23 15:48:13.071   727 13023 I mm-camera: <IFACE >< INFO> 8910: iface_util_dump_camif_cfg: camif first_pix = 0, last_pix = 4031
10-23 15:48:13.071   727 13023 I mm-camera: <IFACE >< INFO> 8912: iface_util_dump_camif_cfg: camif first_line = 0, last_line = 2271
10-23 15:48:13.071   727 13023 I mm-camera: <IFACE >< INFO> 8914: iface_util_dump_camif_cfg: camif pixels_per_line = 4032, lines_per_frame = 2272
10-23 15:48:13.071   727 13023 I mm-camera: <IFACE >< INFO> 8918: iface_util_dump_camif_cfg: camif irq subsample pattern = 0, period = 0 sof_step 1
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 554: iface_dump_axi_plane_config: === AXI DUMP: VFE session_id 1, hw_stream_id 10002 ===
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 558: iface_dump_axi_plane_config:     AXI DUMP Axi_src: VIEWFINDER hw_stream width 1280, height 720
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 560: iface_dump_axi_plane_config: need buf divert = 1, burst count = 0
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 563: iface_dump_axi_plane_config: hfr mode = 0, skip pattern = 0, init_frame_drop = 1
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 596: iface_dump_axi_plane_config: plane[0]: plane_fmt 0(Y-0/CB-1/Cr-2/CrCb-3/CbCr-4)
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 598: iface_dump_axi_plane_config: plane[0]: width = 1280, height = 720
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 600: iface_dump_axi_plane_config: plane[0]: stride = 1280, scanlines = 720
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 602: iface_dump_axi_plane_config: plane[0]: address_offset 0
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 596: iface_dump_axi_plane_config: plane[1]: plane_fmt 4(Y-0/CB-1/Cr-2/CrCb-3/CbCr-4)
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 598: iface_dump_axi_plane_config: plane[1]: width = 1280, height = 360
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 600: iface_dump_axi_plane_config: plane[1]: stride = 1280, scanlines = 360
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 602: iface_dump_axi_plane_config: plane[1]: address_offset 0
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 554: iface_dump_axi_plane_config: === AXI DUMP: VFE session_id 1, hw_stream_id 3 ===
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 558: iface_dump_axi_plane_config:     AXI DUMP Axi_src: ENCODER hw_stream width 4000, height 2250
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 560: iface_dump_axi_plane_config: need buf divert = 0, burst count = 0
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 563: iface_dump_axi_plane_config: hfr mode = 0, skip pattern = 1, init_frame_drop = 1
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 596: iface_dump_axi_plane_config: plane[0]: plane_fmt 0(Y-0/CB-1/Cr-2/CrCb-3/CbCr-4)
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 598: iface_dump_axi_plane_config: plane[0]: width = 4000, height = 2250
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 600: iface_dump_axi_plane_config: plane[0]: stride = 4032, scanlines = 2304
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 602: iface_dump_axi_plane_config: plane[0]: address_offset 0
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 596: iface_dump_axi_plane_config: plane[1]: plane_fmt 3(Y-0/CB-1/Cr-2/CrCb-3/CbCr-4)
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 598: iface_dump_axi_plane_config: plane[1]: width = 4000, height = 1125
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 600: iface_dump_axi_plane_config: plane[1]: stride = 4032, scanlines = 1152
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 602: iface_dump_axi_plane_config: plane[1]: address_offset 0
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 554: iface_dump_axi_plane_config: === AXI DUMP: VFE session_id 1, hw_stream_id 9000f ===
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 558: iface_dump_axi_plane_config:     AXI DUMP Axi_src: CAMIF_RAW hw_stream width 504, height 284
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 560: iface_dump_axi_plane_config: need buf divert = 1, burst count = 0
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 563: iface_dump_axi_plane_config: hfr mode = 0, skip pattern = 0, init_frame_drop = 1
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 596: iface_dump_axi_plane_config: plane[0]: plane_fmt 0(Y-0/CB-1/Cr-2/CrCb-3/CbCr-4)
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 598: iface_dump_axi_plane_config: plane[0]: width = 504, height = 284
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 600: iface_dump_axi_plane_config: plane[0]: stride = 512, scanlines = 284
10-23 15:48:13.072   727 13076 I mm-camera: <IFACE >< INFO> 602: iface_dump_axi_plane_config: plane[0]: address_offset 0
10-23 15:48:13.074   727 13023 I mm-camera: <IFACE >< INFO> 1562: iface_streamon_to_thread: iface_streamon_to_thread: E, session id = 1, user stream id = 2
10-23 15:48:13.074   727 13023 I mm-camera: <ISP   >< INFO> 3632: mesh_rolloff40_normalize_table: full 4032 2272 out 4032 2272 offset 0 0 scale 1
10-23 15:48:13.075   727 13006 I mm-camera: <IFACE >< INFO> 1907: iface_streamon: E, session_id 1 mct_stream_id = 2
10-23 15:48:13.076   727 13006 I mm-camera: <IFACE >< INFO> 10987: iface_util_get_user_streams_by_bundle: got No.1 bundled streamon, mct stream id 2total bundled stream num = 3
10-23 15:48:13.079   727 13020 I mm-camera: <ISP   >< INFO> 90: rs_stats44_stats_config_update: warning: max_support_h_rgn invalid 8
10-23 15:48:13.080   727 13023 I mm-camera: <CPP   >< INFO> 3836: cpp_module_handle_streamon_event: identity=0x10002, stream-on done
10-23 15:48:13.081   727 13023 I mm-camera: <IFACE >< INFO> 1593: iface_streamon_to_thread: iface_streamon_to_thread: X, session id = 1, user stream id = 2
10-23 15:48:13.081   727 13012 I mm-camera: <CPP   >< INFO> 359: cpp_hardware_set_clock: Set clock 100000000 BW avg 93312000 BW inst 93312000
10-23 15:48:13.081   727 13023 I mm-camera: <MCT   >< INFO> 154: start_sof_check_thread: Starting SOF timeout thread session id =1
10-23 15:48:13.082   727 13023 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-ON success for stream 0x10002, stream type=1
10-23 15:48:13.083   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000008
10-23 15:48:13.083   727 13023 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-ON on stream 0x10003 stream type=3
10-23 15:48:13.083   727 13023 I mm-camera: <IFACE >< INFO> 1562: iface_streamon_to_thread: iface_streamon_to_thread: E, session id = 1, user stream id = 3
10-23 15:48:13.083   727 13006 I mm-camera: <IFACE >< INFO> 1907: iface_streamon: E, session_id 1 mct_stream_id = 3
10-23 15:48:13.083   727 13006 I mm-camera: <IFACE >< INFO> 10987: iface_util_get_user_streams_by_bundle: got No.2 bundled streamon, mct stream id 3total bundled stream num = 3
10-23 15:48:13.083   727 13020 I mm-camera: <ISP   >< INFO> 90: rs_stats44_stats_config_update: warning: max_support_h_rgn invalid 8
10-23 15:48:13.084   727 13023 I mm-camera: <IFACE >< INFO> 1593: iface_streamon_to_thread: iface_streamon_to_thread: X, session id = 1, user stream id = 3
10-23 15:48:13.084   727 13023 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-ON success for stream 0x10003, stream type=3
10-23 15:48:13.085   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000008
10-23 15:48:13.085   727 13023 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-ON on stream 0x10004 stream type=11
10-23 15:48:13.085   727 13023 I mm-camera: <IFACE >< INFO> 1562: iface_streamon_to_thread: iface_streamon_to_thread: E, session id = 1, user stream id = 4
10-23 15:48:13.085   727 13006 I mm-camera: <IFACE >< INFO> 1907: iface_streamon: E, session_id 1 mct_stream_id = 4
10-23 15:48:13.085   727 13006 I mm-camera: <IFACE >< INFO> 10981: iface_util_get_user_streams_by_bundle: streamon, got all bundled streamon!
10-23 15:48:13.086   727 13020 I mm-camera: <ISP   >< INFO> 90: rs_stats44_stats_config_update: warning: max_support_h_rgn invalid 8
10-23 15:48:13.087   727 13023 E mm-camera: <IMGLIB><ERROR> 318: faceproc_comp_set_param: Error param=521
10-23 15:48:13.087   727 13023 E mm-camera: <IMGLIB><ERROR> 3396: module_faceproc_client_exec: rc -4
10-23 15:48:13.090   727 13023 I mm-camera: <ISP   >< INFO> 451: be_stats44_trigger_update: warning: Denomitor is zero hnum 0 vnum 0
10-23 15:48:13.091   727 13006 I mm-camera: <IFACE >< INFO> 2173: iface_streamon: X
10-23 15:48:13.092   727 13023 I mm-camera: <IFACE >< INFO> 1593: iface_streamon_to_thread: iface_streamon_to_thread: X, session id = 1, user stream id = 4
10-23 15:48:13.092   727 13023 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-ON success for stream 0x10004, stream type=11
10-23 15:48:13.092   751  3425 I Camera3-Device: disconnectImpl: E
10-23 15:48:13.092   751  3425 I CameraLatencyHistogram: ProcessCaptureRequest latency histogram (72) samples:
10-23 15:48:13.092   751  3425 I CameraLatencyHistogram:        40     80    120    160    200    240    280    320    360    inf (max ms)
10-23 15:48:13.092   751  3425 I CameraLatencyHistogram:      27.78   69.44   0.00   2.78   0.00   0.00   0.00   0.00   0.00   0.00 (%)
10-23 15:48:13.093   586  1188 I QCamera : <HAL><INFO> close_camera_device: 10850: [KPI Perf]: E camera id 0
10-23 15:48:13.095   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-23 15:48:13.095   727 13023 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10004 stream type=11
10-23 15:48:13.096   727 13023 I mm-camera: <IFACE >< INFO> 1619: iface_streamoff_to_thread: iface_streamoff_to_thread: E, session id = 1, user stream id = 4
10-23 15:48:13.096   727 13006 I mm-camera: <IFACE >< INFO> 2211: iface_streamoff: E, session_id 1 mct_stream_id = 4
10-23 15:48:13.096   727 13006 I mm-camera: <IFACE >< INFO> 10995: iface_util_get_user_streams_by_bundle: streamoff, first bundled streamoff all bindled streams!
10-23 15:48:13.098   727 13006 I mm-camera: <IFACE >< INFO> 380: iface_ispif_get_cfg_params_from_hw_streams: interace 0 active count = 2, skip stop ispif
10-23 15:48:13.098   727 13006 I mm-camera: <IFACE >< INFO> 380: iface_ispif_get_cfg_params_from_hw_streams: interace 0 active count = 1, skip stop ispif
10-23 15:48:13.098   727 13006 I mm-camera: <IFACE >< INFO> 2401: iface_streamoff: session_id = 1, active_streams = 0
10-23 15:48:13.098   727 13006 I mm-camera: <IFACE >< INFO> 2483: iface_streamoff: X
10-23 15:48:13.098   727 13023 I mm-camera: <IFACE >< INFO> 1645: iface_streamoff_to_thread: iface_streamoff_to_thread: X, session id = 1, user stream id = 4
10-23 15:48:13.098   727 13023 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10004, stream type=11
10-23 15:48:13.099   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-23 15:48:13.099   727 13023 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10002 stream type=1
10-23 15:48:13.099   727 13023 I mm-camera: <CPP   >< INFO> 3927: cpp_module_handle_streamoff_event: info: doing stream-off for identity 0x10002
10-23 15:48:13.100   727 13012 I mm-camera: <CPP   >< INFO> 359: cpp_hardware_set_clock: Set clock 100000000 BW avg 0 BW inst 0
10-23 15:48:13.100   727 13023 I mm-camera: <CPP   >< INFO> 4112: cpp_module_handle_streamoff_event: info: stream-off done for identity 0x10002
10-23 15:48:13.100   727 13023 I mm-camera: <IFACE >< INFO> 1619: iface_streamoff_to_thread: iface_streamoff_to_thread: E, session id = 1, user stream id = 2
10-23 15:48:13.100   727 13006 I mm-camera: <IFACE >< INFO> 2211: iface_streamoff: E, session_id 1 mct_stream_id = 2
10-23 15:48:13.100   727 13006 I mm-camera: <IFACE >< INFO> 11001: iface_util_get_user_streams_by_bundle: skip streamoff! left 1 bundled streamoff, mct stream id 2total bundled stream num = 3
10-23 15:48:13.100   727 13006 I mm-camera: <IFACE >< INFO> 2483: iface_streamoff: X
10-23 15:48:13.100   727 13023 I mm-camera: <IFACE >< INFO> 1645: iface_streamoff_to_thread: iface_streamoff_to_thread: X, session id = 1, user stream id = 2
10-23 15:48:13.100   727 13023 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10002, stream type=1
10-23 15:48:13.101   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-23 15:48:13.101   727 13023 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10003 stream type=3
10-23 15:48:13.101   727 13023 E mm-camera: <STATS_AIS ><ERROR> 173: dsps_send_req: DSPS Send Request Timeout!!
10-23 15:48:13.101   727 13023 E mm-camera: <STATS_AIS ><ERROR> 173: dsps_send_req: DSPS Send Request Timeout!!
10-23 15:48:13.101   727 13023 E mm-camera: <STATS_AIS ><ERROR> 173: dsps_send_req: DSPS Send Request Timeout!!
10-23 15:48:13.102   727 13023 I mm-camera: <IFACE >< INFO> 1619: iface_streamoff_to_thread: iface_streamoff_to_thread: E, session id = 1, user stream id = 3
10-23 15:48:13.102   727 13006 I mm-camera: <IFACE >< INFO> 2211: iface_streamoff: E, session_id 1 mct_stream_id = 3
10-23 15:48:13.102   727 13006 I mm-camera: <IFACE >< INFO> 11001: iface_util_get_user_streams_by_bundle: skip streamoff! left 0 bundled streamoff, mct stream id 3total bundled stream num = 3
10-23 15:48:13.103   727 13006 I mm-camera: <IFACE >< INFO> 2420: iface_streamoff: session id = 0x1,Delta between vfe irqs 0 sec 0 usec
10-23 15:48:13.104   727 13006 I mm-camera: <IFACE >< INFO> 10046: iface_util_release_resource: camif_cnt= 1, rdi_cnt= 0, used mask 10000
10-23 15:48:13.105   727 13006 I mm-camera: <IFACE >< INFO> 10046: iface_util_release_resource: camif_cnt= 0, rdi_cnt= 0, used mask 0
10-23 15:48:13.105   727 13006 I mm-camera: <IFACE >< INFO> 10046: iface_util_release_resource: camif_cnt= 0, rdi_cnt= 0, used mask 0
10-23 15:48:13.105   727 13006 I mm-camera: <IFACE >< INFO> 2478: iface_streamoff: all bundled stream streamoff, clear hal bundled mask
10-23 15:48:13.105   727 13006 I mm-camera: <IFACE >< INFO> 2483: iface_streamoff: X
10-23 15:48:13.105   727 13023 I mm-camera: <IFACE >< INFO> 1645: iface_streamoff_to_thread: iface_streamoff_to_thread: X, session id = 1, user stream id = 3
10-23 15:48:13.105   727 13023 I mm-camera: <MCT   >< INFO> 177: stop_sof_check_thread: Stopping SOF timeout thread session =1
10-23 15:48:13.106   727 13077 I mm-camera: <MCT   >< INFO> 114: mct_bus_sof_thread_run: Closing SOF tracker thread
10-23 15:48:13.106   727 13023 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10003, stream type=3
10-23 15:48:13.107   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-23 15:48:13.107   727 13023 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10001 stream type=7
10-23 15:48:13.107   727 13023 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10001, stream type=7
10-23 15:48:13.111   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=800000b
10-23 15:48:13.111   727 13023 I mm-camera: <MCT   >< INFO> 4125: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x10002 and stream type=1
10-23 15:48:13.111   727 13023 I mm-camera: <IFACE >< INFO> 671: iface_unreserve_sink_port: stream_id 2 mode 0
10-23 15:48:13.111   727 13023 I mm-camera: <ISP   >< INFO> 890: isp_port_check_caps_unreserve: ide 10002 port 0xe5b56150
10-23 15:48:13.112   727 13023 I mm-camera: <MCT   >< INFO> 4136: mct_pipeline_process_set: Stream 0x10002 and stream type=1, successfully deleted
10-23 15:48:13.113   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=800000b
10-23 15:48:13.113   727 13023 I mm-camera: <MCT   >< INFO> 4125: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x10003 and stream type=3
10-23 15:48:13.113   727 13023 I mm-camera: <IFACE >< INFO> 671: iface_unreserve_sink_port: stream_id 3 mode 0
10-23 15:48:13.113   727 13023 I mm-camera: <ISP   >< INFO> 890: isp_port_check_caps_unreserve: ide 10003 port 0xe5b56150
10-23 15:48:13.114   727 13023 I mm-camera: <MCT   >< INFO> 4136: mct_pipeline_process_set: Stream 0x10003 and stream type=3, successfully deleted
10-23 15:48:13.115   586  1188 V qomx_image_core: OMX_FreeHandle:338]
10-23 15:48:13.115   586  1188 D qomx_image_core: get_idx_from_handle:297] comp_idx 0 inst_idx 0
10-23 15:48:13.116   586  1188 V qomx_image_core: OMX_FreeHandle:373] Success
10-23 15:48:13.116   586  1188 I qomx_image_core: OMX_Deinit:125] Complete
10-23 15:48:13.119   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=800000b
10-23 15:48:13.119   727 13023 I mm-camera: <MCT   >< INFO> 4125: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x10004 and stream type=11
10-23 15:48:13.119   727 13023 I mm-camera: <IFACE >< INFO> 671: iface_unreserve_sink_port: stream_id 4 mode 0
10-23 15:48:13.119   727 13023 I mm-camera: <ISP   >< INFO> 890: isp_port_check_caps_unreserve: ide 10004 port 0xe5b56150
10-23 15:48:13.120   727 13023 I mm-camera: <MCT   >< INFO> 4136: mct_pipeline_process_set: Stream 0x10004 and stream type=11, successfully deleted
10-23 15:48:13.121   727 13023 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=800000b
10-23 15:48:13.121   727 13023 I mm-camera: <MCT   >< INFO> 4125: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x10001 and stream type=7
10-23 15:48:13.121   727 13023 I mm-camera: <MCT   >< INFO> 4136: mct_pipeline_process_set: Stream 0x10001 and stream type=7, successfully deleted
10-23 15:48:13.121   727  1013 E mm-camera: <IMGLIB><ERROR> 303: AllocateBuffers: Invalid dimensions 0x0
10-23 15:48:13.121   727 13023 I mm-camera: <ISP   >< INFO> 6629: isp_util_print_meta_stream_info: Deallocate resources
10-23 15:48:13.121   727  1013 E mm-camera: <IMGLIB><ERROR> 203: img_algo_preload: Preload: Failed to allocate buffer, rc -4
10-23 15:48:13.121   727  1013 E mm-camera: <IMGLIB><ERROR> 119: module_imgbase_client_preload_exec: IMG_CORE_PRELOAD failed -4
10-23 15:48:13.124   727 13023 I mm-camera: <ISP   >< INFO> 2537: isp_resource_allocate: INFO: ISP resource acquired: session 1 num_isp 1
10-23 15:48:13.124   727 13023 I mm-camera: <ISP   >< INFO> 2539: isp_resource_allocate: INFO: ISP resource acquired: hw_id 1
10-23 15:48:13.124   727 13023 I mm-camera: <ISP   >< INFO> 6479: isp_util_decide_stream_mapping: stream_port_map num streams 0
10-23 15:48:13.126   727 13023 E mm-camera: <MCT   ><ERROR> 1173: mct_pipeline_decide_hw_wakeup: Couldn't find meta stream
10-23 15:48:13.127   586  1188 I QCamera : <HAL><INFO> closeCamera: 882: [KPI Perf]: E PROFILE_CLOSE_CAMERA camera id 0
10-23 15:48:13.128   727   727 I mm-camera: <MCT   >< INFO> 216: mct_controller_destroy: Initiating destroy sequence for session = 1
10-23 15:48:13.129   727   727 I mm-camera: <MCT   >< INFO> 234: mct_controller_destroy: serv_thread closed
10-23 15:48:13.131   727   727 I mm-camera: <MCT   >< INFO> 249: mct_controller_destroy: bus_handler thread closed
10-23 15:48:13.131   727   727 I mm-camera: <MCT   >< INFO> 4886: mct_pipeline_stop_session: Initiating stop_session on session 1
10-23 15:48:13.131   727   727 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=800000b
10-23 15:48:13.131   727   727 I mm-camera: <MCT   >< INFO> 4125: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x1000f and stream type=10
10-23 15:48:13.131   727   727 I mm-camera: <IFACE >< INFO> 671: iface_unreserve_sink_port: stream_id f mode 0
10-23 15:48:13.131   727   727 I mm-camera: <ISP   >< INFO> 890: isp_port_check_caps_unreserve: ide 1000f port 0xe5b56150
10-23 15:48:13.133   727   727 I mm-camera: <MCT   >< INFO> 4136: mct_pipeline_process_set: Stream 0x1000f and stream type=10, successfully deleted
10-23 15:48:13.133   727 13082 I mm-camera: <MCT   >< INFO> 4620: mct_pipeline_stop_session_thread: Stop module name: sensor - E
10-23 15:48:13.134   727 13083 I mm-camera: <MCT   >< INFO> 4620: mct_pipeline_stop_session_thread: Stop module name: iface - E
10-23 15:48:13.134   727 13083 I mm-camera: <MCT   >< INFO> 4628: mct_pipeline_stop_session_thread: Stop module name: iface - X
10-23 15:48:13.134   727 13084 I mm-camera: <MCT   >< INFO> 4620: mct_pipeline_stop_session_thread: Stop module name: isp - E
10-23 15:48:13.135   727 13084 I mm-camera: <ISP   >< INFO> 491: isp_module_stop_session: session id 1
10-23 15:48:13.135   727 13084 I mm-camera: <ISP   >< INFO> 532: isp_module_stop_session: warning: ISP resource is not freed on time!
10-23 15:48:13.135   727 13085 I mm-camera: <MCT   >< INFO> 4620: mct_pipeline_stop_session_thread: Stop module name: stats - E
10-23 15:48:13.137   727 13086 I mm-camera: <MCT   >< INFO> 4620: mct_pipeline_stop_session_thread: Stop module name: pproc - E
10-23 15:48:13.139   727 13085 D DmbrContextAPI: VIDHANCE dmbr_free_context user=0xe5b6ccb0
10-23 15:48:13.139   727   727 I mm-camera: <MCT   >< INFO> 4908: mct_pipeline_stop_session: Modules left: 5
10-23 15:48:13.140   727 13087 I mm-camera: <MCT   >< INFO> 4620: mct_pipeline_stop_session_thread: Stop module name: imglib - E
10-23 15:48:13.140   727 13086 D QCameraQuadraCfa: img_algo_shutdown:147 : E
10-23 15:48:13.140   727 13086 E quadracfa_dummy: inside dummy remosaic_deinit 1
10-23 15:48:13.140   727 13086 D QCameraQuadraCfa: img_algo_shutdown:151 : X
10-23 15:48:13.140   727 13086 I mm-camera: <MCT   >< INFO> 4628: mct_pipeline_stop_session_thread: Stop module name: pproc - X
10-23 15:48:13.140   727   727 I mm-camera: <MCT   >< INFO> 4908: mct_pipeline_stop_session: Modules left: 4
10-23 15:48:13.140   727 13087 I mm-camera: <MCT   >< INFO> 4628: mct_pipeline_stop_session_thread: Stop module name: imglib - X
10-23 15:48:13.140   727   727 I mm-camera: <MCT   >< INFO> 4908: mct_pipeline_stop_session: Modules left: 3
10-23 15:48:13.141   727 13084 I mm-camera: <MCT   >< INFO> 4628: mct_pipeline_stop_session_thread: Stop module name: isp - X
10-23 15:48:13.142   727 13085 I mm-camera: <MCT   >< INFO> 4628: mct_pipeline_stop_session_thread: Stop module name: stats - X
10-23 15:48:13.142   727   727 I mm-camera: <MCT   >< INFO> 4908: mct_pipeline_stop_session: Modules left: 2
10-23 15:48:13.142   727   727 I mm-camera: <MCT   >< INFO> 4908: mct_pipeline_stop_session: Modules left: 1
10-23 15:48:13.142   727 13016 E libsensor1: resp_msg_cb: QMI transport error -1 from svc 0 on 0xe91960c0
10-23 15:48:13.192   467   468 D rpmb_emmc: rpmb_emmc_write: blk_cnt: 2, num_rpmb_tx: 1, frames_per_tx: 2, rel_wr_cnt: 32
10-23 15:48:13.198   467   471 D DrmLibTime: got the req here! ret=0
10-23 15:48:13.198   467   471 D DrmLibTime: command id, time_cmd_id = 770
10-23 15:48:13.198   467   471 D DrmLibTime: time_getutcsec starts!
10-23 15:48:13.198   467   471 D DrmLibTime: QSEE Time Listener: time_getutcsec
10-23 15:48:13.198   467   471 D DrmLibTime: QSEE Time Listener: get_utc_seconds
10-23 15:48:13.198   467   471 D DrmLibTime: QSEE Time Listener: time_get_modem_time
10-23 15:48:13.198   467   471 D DrmLibTime: QSEE Time Listener: Checking if ATS_MODEM is set or not.
10-23 15:48:13.198   467   471 D QC-time-services: Lib:time_genoff_operation: pargs->base = 13
10-23 15:48:13.198   467   471 D QC-time-services: Lib:time_genoff_operation: pargs->operation = 2
10-23 15:48:13.198   467   471 D QC-time-services: Lib:time_genoff_operation: pargs->ts_val = 0
10-23 15:48:13.198   467   471 D QC-time-services: Lib:time_genoff_operation: Send to server  passed!!
10-23 15:48:13.199   529   539 D QC-time-services: Daemon: Connection accepted:time_genoff
10-23 15:48:13.199   529 13096 D QC-time-services: Daemon:Received base = 13, unit = 1, operation = 2,value = 0
10-23 15:48:13.199   529 13096 D QC-time-services: Daemon:genoff_opr: Base = 13, val = 0, operation = 2
10-23 15:48:13.199   529 13096 D QC-time-services: offset is: 1 for base: 13
10-23 15:48:13.199   467   471 E QC-time-services: Receive Passed == base = 13, unit = 1, operation = 2, result = 0
10-23 15:48:13.199   467   471 D DrmLibTime: QSEE Time Listener: ATS_MODEM is set. Try to retrieve it.
10-23 15:48:13.199   529   539 E QC-time-services: Daemon: Time-services: Waiting to acceptconnection
10-23 15:48:13.199   529   539 D QC-time-services: Daemon: Connection accepted:time_genoff
10-23 15:48:13.199   529 13097 D QC-time-services: Daemon:Received base = 13, unit = 1, operation = 1,value = 0
10-23 15:48:13.199   529 13097 D QC-time-services: Daemon:genoff_opr: Base = 13, val = 0, operation = 1
10-23 15:48:13.199   529 13097 D QC-time-services: Daemon: genoff get for 13
10-23 15:48:13.199   529 13097 D QC-time-services: Daemon:Value read from QTimer mseconds = 18134558
10-23 15:48:13.199   529 13097 D QC-time-services: Daemon:Value read from RTC mseconds on boot = 38316031000
10-23 15:48:13.199   529 13097 D QC-time-services: Daemon:Value read from QTimer mseconds = 18134558
10-23 15:48:13.199   529 13097 D QC-time-services: Daemon:Value read from generic offset = 1628198773762
10-23 15:48:13.199   529 13097 D QC-time-services: Daemon:Delta read on boot mseconds = 38315984264
10-23 15:48:13.199   529 13097 D QC-time-services: Daemon:Final Time = 1666532892584
10-23 15:48:13.200   467   471 D DrmLibTime: QSEE Time Listener: Time GenOff - seconds: 1666532892
10-23 15:48:13.200   467   471 D DrmLibTime: time_getutcsec returns 0, sec = 1666532892; nsec = 0
10-23 15:48:13.200   467   471 D DrmLibTime: time_getutcsec finished!
10-23 15:48:13.200   467   471 D DrmLibTime: iotcl_continue_command finished! and return 0
10-23 15:48:13.200   467   471 D DrmLibTime: before calling ioctl to read the next time_cmd
10-23 15:48:13.202   529   539 E QC-time-services: Daemon: Time-services: Waiting to acceptconnection
10-23 15:48:13.182     0     0 E         : msm_camera_power_down error power seq type -1
10-23 15:48:13.229   467   468 D rpmb_emmc: rpmb_emmc_write: blk_cnt: 2, num_rpmb_tx: 1, frames_per_tx: 2, rel_wr_cnt: 32
10-23 15:48:13.234   727 13082 I mm-camera: <MCT   >< INFO> 4628: mct_pipeline_stop_session_thread: Stop module name: sensor - X
10-23 15:48:13.234   727   727 I mm-camera: <MCT   >< INFO> 4908: mct_pipeline_stop_session: Modules left: 0
10-23 15:48:13.234   727   727 I mm-camera: <MCT   >< INFO> 4917: mct_pipeline_stop_session: Stopped session 1 successfully
10-23 15:48:13.234   727   727 I mm-camera: <MCT   >< INFO> 5194: mct_pipeline_destroy: E: session:1
10-23 15:48:13.234   727   727 I mm-camera: <MCT   >< INFO> 5267: mct_pipeline_destroy: X: Pipeline destroyed successfully session =1
10-23 15:48:13.234   727   727 I mm-camera: <MCT   >< INFO> 282: mct_controller_destroy: X Successfully closed mct_controller session 1
10-23 15:48:13.222   586   586 W HwBinder:586_3: type=1400 audit(0.0:556): avc: denied { read } for name="u:object_r:bootanim_system_prop:s0" dev="tmpfs" ino=15356 scontext=u:r:hal_camera_default:s0 tcontext=u:object_r:bootanim_system_prop:s0 tclass=file permissive=0
10-23 15:48:13.235   586  1188 W libc    : Access denied finding property "service.bootanim.exit"
10-23 15:48:13.235   751  3425 I CameraProviderManager: Camera device device@3.3/legacy/0 torch status is now AVAILABLE_OFF
10-23 15:48:13.235   751  3425 I CameraService: onTorchStatusChangedLocked: Torch status changed for cameraId=0, newStatus=1
10-23 15:48:13.236   586  1188 I QCamera : <HAL><INFO> closeCamera: 924: [KPI Perf]: X PROFILE_CLOSE_CAMERA camera id 0, rc: 0
10-23 15:48:13.237   586  1188 I hw-BpHwBinder: onLastStrongRef automatically unlinking death recipients
10-23 15:48:13.237   586  1188 I QCamera : <HAL><INFO> close_camera_device: 10852: [KPI Perf]: X
10-23 15:48:13.241   751  3425 I CameraLatencyHistogram: Stream 0 dequeueBuffer latency histogram (71) samples:
10-23 15:48:13.242   751  3425 I CameraLatencyHistogram:         5     10     15     20     25     30     35     40     45    inf (max ms)
10-23 15:48:13.242   751  3425 I CameraLatencyHistogram:      98.59   1.41   0.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00 (%)
10-23 15:48:13.243 11366 12630 E IgnoringAnomalyNotifier: onAnomaly: GL_FRAME_BUFFER_SKIP_CREATE_FRAMEBUFFER_ZERO_WIDTH
10-23 15:48:13.244 11366 12630 E IgnoringAnomalyNotifier: onAnomaly: GL_FRAME_BUFFER_SKIP_CREATE_FRAMEBUFFER_ZERO_HEIGHT
10-23 15:48:13.244 11366 12630 E IgnoringAnomalyNotifier: onAnomaly: GL_FRAME_BUFFER_SKIP_CREATE_FRAMEBUFFER_ZERO_WIDTH
10-23 15:48:13.244 11366 12630 E IgnoringAnomalyNotifier: onAnomaly: GL_FRAME_BUFFER_SKIP_CREATE_FRAMEBUFFER_ZERO_HEIGHT
10-23 15:48:13.246   751  3425 I CameraLatencyHistogram: Stream 1 dequeueBuffer latency histogram (1) samples:
10-23 15:48:13.247   751  3425 I CameraLatencyHistogram:         5     10     15     20     25     30     35     40     45    inf (max ms)
10-23 15:48:13.247   751  3425 I CameraLatencyHistogram:      100.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00 (%)
10-23 15:48:13.247   751  3425 I Camera3-Device: disconnectImpl: X
10-23 15:48:13.248   751  3425 I CameraService: disconnect: Disconnected client for camera 0 for PID 11366
10-23 15:48:14.754   467   468 D rpmb_emmc: rpmb_emmc_write: blk_cnt: 2, num_rpmb_tx: 1, frames_per_tx: 2, rel_wr_cnt: 32
10-23 15:48:14.763   467   471 D DrmLibTime: got the req here! ret=0
10-23 15:48:14.764   467   471 D DrmLibTime: command id, time_cmd_id = 770
10-23 15:48:14.764   467   471 D DrmLibTime: time_getutcsec starts!
10-23 15:48:14.764   467   471 D DrmLibTime: QSEE Time Listener: time_getutcsec
10-23 15:48:14.764   467   471 D DrmLibTime: QSEE Time Listener: get_utc_seconds
10-23 15:48:14.764   467   471 D DrmLibTime: QSEE Time Listener: time_get_modem_time
10-23 15:48:14.764   467   471 D DrmLibTime: QSEE Time Listener: Checking if ATS_MODEM is set or not.
10-23 15:48:14.764   467   471 D QC-time-services: Lib:time_genoff_operation: pargs->base = 13
10-23 15:48:14.764   467   471 D QC-time-services: Lib:time_genoff_operation: pargs->operation = 2
10-23 15:48:14.764   467   471 D QC-time-services: Lib:time_genoff_operation: pargs->ts_val = 0
10-23 15:48:14.764   467   471 D QC-time-services: Lib:time_genoff_operation: Send to server  passed!!
10-23 15:48:14.764   529   539 D QC-time-services: Daemon: Connection accepted:time_genoff
10-23 15:48:14.765   529 13098 D QC-time-services: Daemon:Received base = 13, unit = 1, operation = 2,value = 0
10-23 15:48:14.765   529 13098 D QC-time-services: Daemon:genoff_opr: Base = 13, val = 0, operation = 2
10-23 15:48:14.765   529 13098 D QC-time-services: offset is: 1 for base: 13
10-23 15:48:14.765   467   471 E QC-time-services: Receive Passed == base = 13, unit = 1, operation = 2, result = 0
10-23 15:48:14.765   467   471 D DrmLibTime: QSEE Time Listener: ATS_MODEM is set. Try to retrieve it.
10-23 15:48:14.766   529   539 E QC-time-services: Daemon: Time-services: Waiting to acceptconnection
10-23 15:48:14.766   529   539 D QC-time-services: Daemon: Connection accepted:time_genoff
10-23 15:48:14.767   529 13099 D QC-time-services: Daemon:Received base = 13, unit = 1, operation = 1,value = 0
10-23 15:48:14.767   529 13099 D QC-time-services: Daemon:genoff_opr: Base = 13, val = 0, operation = 1
10-23 15:48:14.767   529 13099 D QC-time-services: Daemon: genoff get for 13
10-23 15:48:14.767   529 13099 D QC-time-services: Daemon:Value read from QTimer mseconds = 18136125
10-23 15:48:14.767   529 13099 D QC-time-services: Daemon:Value read from RTC mseconds on boot = 38316031000
10-23 15:48:14.767   529 13099 D QC-time-services: Daemon:Value read from QTimer mseconds = 18136125
10-23 15:48:14.767   529 13099 D QC-time-services: Daemon:Value read from generic offset = 1628198773762
10-23 15:48:14.767   529 13099 D QC-time-services: Daemon:Delta read on boot mseconds = 38315984264
10-23 15:48:14.767   529 13099 D QC-time-services: Daemon:Final Time = 1666532894151
10-23 15:48:14.767   467   471 D DrmLibTime: QSEE Time Listener: Time GenOff - seconds: 1666532894
10-23 15:48:14.767   467   471 D DrmLibTime: time_getutcsec returns 0, sec = 1666532894; nsec = 0
10-23 15:48:14.767   467   471 D DrmLibTime: time_getutcsec finished!
10-23 15:48:14.767   467   471 D DrmLibTime: iotcl_continue_command finished! and return 0
10-23 15:48:14.767   467   471 D DrmLibTime: before calling ioctl to read the next time_cmd
10-23 15:48:14.768   529   539 E QC-time-services: Daemon: Time-services: Waiting to acceptconnection
10-23 15:48:14.784   467   468 D rpmb_emmc: rpmb_emmc_write: blk_cnt: 2, num_rpmb_tx: 1, frames_per_tx: 2, rel_wr_cnt: 32
10-23 15:48:24.797   478 13034 I keystore2: keystore2::watchdog: Watchdog thread idle -> terminating. Have a great day.
10-23 15:48:25.876 11366 11385 E nightwatch-target: unknown nightwatch data: 92
10-23 15:48:25.876 11366 11385 E nightwatch-target: unknown nightwatch data: 1044738048
10-23 15:48:25.880 11366 11385 E nightwatch-target: unknown nightwatch data: 1044738048
10-23 15:48:25.882 11366 11385 E nightwatch-target: unknown nightwatch data: 317
10-23 15:48:25.884 11366 11385 E nightwatch-target: unknown nightwatch data: 310729161
10-23 15:48:25.885 11366 11385 E nightwatch-target: unknown nightwatch data: 4871087

[ItsVixano]

Logcat after backporting https://git.codelinaro.org/clo/la/platform/hardware/qcom/camera/-/commits/LA.UM.9.6.4.r1-03200-89xx.QSSI12.0/QCamera2/stack/mm-jpeg-interface/src/mm_jpeg_exif.c

Unsupported flash mode error is now gone, is still buggy tho D:

10-24 20:05:31.803   725  5173 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 95: Book-keeping (frame_num, ack_frame_id) = [892, 98] in list
10-24 20:05:31.811   582  5191 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 889, buffer = 0xf0442cc0
10-24 20:05:31.815   582  3262 E CamComm1.0-MD: Mismatched tag type when updating entry enable (-2146762752) of type byte; got type int32 data instead
10-24 20:05:31.815   582  3262 E CamComm1.0-MD: Mismatched tag type when updating entry is_main (-2146762751) of type byte; got type int32 data instead
10-24 20:05:31.836   725  5173 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 96: Book-keeping (frame_num, ack_frame_id) = [893, 99] in list
10-24 20:05:31.843   582  3262 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
10-24 20:05:31.846   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=800000a
10-24 20:05:31.848   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000018
10-24 20:05:31.848   725  5171 I mm-camera: <MCT   >< INFO> 600: mct_stream_start_link: Start linking offline stream 0x10005
10-24 20:05:31.848   725  5171 I mm-camera: <MCT   >< INFO> 669: mct_stream_start_link: iface=0x0, isp=0x0, pproc=0xe7dc1cf0, imglib=0x0
10-24 20:05:31.848   725  5171 I mm-camera: <PPROC >< INFO> 446: pproc_port_add_modules_to_stream: in identity 10005 stream 9 int_link = 0xe799d0b0
10-24 20:05:31.848   725  5171 I mm-camera: <PPROC >< INFO> 458: pproc_port_add_modules_to_stream: :LINK linking mods tmod and cpp for identity 10005
10-24 20:05:31.848   725  5171 I mm-camera: <CPP   >< INFO> 2155: cpp_module_notify_add_stream: :width 4000, height 2250, stride 4032, scanline 2304, framelen 13934592
10-24 20:05:31.848   725  5171 I mm-camera: <CPP   >< INFO> 2179: cpp_module_notify_add_stream: offset_x 0, offset_y 0, offset 0, meta_len 0,meta_scanline 0, meta_stride 0, plane_len 9289728
10-24 20:05:31.848   725  5171 I mm-camera: <CPP   >< INFO> 2179: cpp_module_notify_add_stream: offset_x 0, offset_y 0, offset 0, meta_len 0,meta_scanline 0, meta_stride 0, plane_len 4644864
10-24 20:05:31.848   725  5171 E mm-camera: <CPP   ><ERROR> 2248: cpp_module_notify_add_stream: failed, non supported rotation angle 0
10-24 20:05:31.848   725  5171 I mm-camera: <CPP   >< INFO> 2320: cpp_module_notify_add_stream: : stream 9, fmt 2, asf_mode 0, sharpness_level 0.000000,asf mask 1, denoise 0, denoise_mask 1, dsdn mask 0,dsdn enable 0, tnr mask 0, tnr enable 0, ds_mask 1
10-24 20:05:31.848   725  5171 I mm-camera: <MCT   >< INFO> 773: mct_stream_start_link: Linking single module
10-24 20:05:31.848   725  5171 I mm-camera: <MCT   >< INFO> 781: mct_stream_start_link: single_module=0xe7dc1cf0
10-24 20:05:31.848   725  5171 I mm-camera: <MCT   >< INFO> 4005: mct_pipeline_process_set: Linking successful for stream 0x10005 stream type=9
10-24 20:05:31.849   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000004
10-24 20:05:31.849   582  3262 E QCamera : <MCI><ERROR> mm_channel_fsm_fn_stopped: 893: invalid state (1) for evt (9)
10-24 20:05:31.850   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000008
10-24 20:05:31.850   725  5171 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-ON on stream 0x10005 stream type=9
10-24 20:05:31.850   725  5171 I mm-camera: <CPP   >< INFO> 3836: cpp_module_handle_streamon_event: identity=0x10005, stream-on done
10-24 20:05:31.850   725  5171 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-ON success for stream 0x10005, stream type=9
10-24 20:05:31.851   725  5163 I mm-camera: <CPP   >< INFO> 359: cpp_hardware_set_clock: Set clock 200000000 BW avg 235062000 BW inst 235062000
10-24 20:05:31.855   582  5191 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 890, buffer = 0xf0441eb0
10-24 20:05:31.865   725  5163 I mm-camera: <CPP   >< INFO> 359: cpp_hardware_set_clock: Set clock 200000000 BW avg 235062000 BW inst 235062000
10-24 20:05:31.870   725  5173 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 97: Book-keeping (frame_num, ack_frame_id) = [894, 100] in list
10-24 20:05:31.870   725  5173 E mm-camera: <IMGLIB><ERROR> 318: faceproc_comp_set_param: Error param=523
10-24 20:05:31.873   582  3262 E QCamera3HWI: CAM_DEBUG: Setting compensation:0
10-24 20:05:31.903   725  5173 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 98: Book-keeping (frame_num, ack_frame_id) = [895, 101] in list
10-24 20:05:31.907   582  5191 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 892, buffer = 0xf0443bc0
10-24 20:05:31.936   725  5173 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 99: Book-keeping (frame_num, ack_frame_id) = [896, 102] in list
10-24 20:05:31.936   725  5173 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
10-24 20:05:31.943   582  5191 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 893, buffer = 0xf04425d0
10-24 20:05:31.969   725  5173 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 100: Book-keeping (frame_num, ack_frame_id) = [897, 103] in list
10-24 20:05:31.970   725  5173 E mm-camera: <STATS_AF ><ERROR> 5152: af_port_handle_set_focus_manual_pos_evt: not in CAM_FOCUS_MODE_OFF(Manual) mode,           ignore the settings
10-24 20:05:31.983   582  5184 I QCamera : <HAL><INFO> doReprocessOffline: 4941: Offline reprocessing id = 99 buf Id = 0 meta index = 7
10-24 20:05:31.983   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000019
10-24 20:05:31.983   725  5171 I mm-camera: <CPP   >< INFO> 865: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS]  stream type 9, width 4000, height 2250,stride 4032, scanline 2304, planes 2
10-24 20:05:31.983   725  5171 I mm-camera: <CPP   >< INFO> 884: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS] plane 0, stride 4032, scanline 2304, offset_x 0, offset_y 0, len 9289728, offset:0
10-24 20:05:31.983   725  5171 I mm-camera: <CPP   >< INFO> 884: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS] plane 1, stride 4032, scanline 2304, offset_x 0, offset_y 0, len 4644864, offset:0
10-24 20:05:31.983   725  5171 I mm-camera: <PPROC >< INFO> 2434: pproc_module_handle_reprocess_offline: input buf fd 134 type 5 size 13934592 identity 10005, frame_id 99
10-24 20:05:32.004   725  5173 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 101: Book-keeping (frame_num, ack_frame_id) = [898, 104] in list
10-24 20:05:32.021   582  5191 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 895, buffer = 0xf0442f90
10-24 20:05:32.032   582  5206 I QCamera : <HAL><INFO> streamCbRoutine: 4179: bufIndex: 0 recvd from post proc
10-24 20:05:32.032   582  5206 E QCamera : <HAL><ERROR> handleInputBufferWithLock: 3432: Could not find input request for frame number 894
10-24 20:05:32.034   582  5184 I QCamera : <HAL><INFO> encodeData: 1849: Src Buffer cnt = 1, res = 2304X4032 len = 13934592 rot = 0 src_dim = 4000X2250 dst_dim = 4000X2250
10-24 20:05:32.034   582  5184 I QCamera : <HAL><INFO> encodeData: 1860: Src THUMB buf_cnt = 0, res = 0X0 len = 0 rot = 0 src_dim = 2250X4000, dst_dim = 0X0
10-24 20:05:32.034   582  5184 I qomx_image_core: OMX_GetHandle:243] get instance pts is 0xed8c2a80
10-24 20:05:32.035   582  5184 D qomx_image_core: OMX_GetHandle:261] handle = 0xed8c2a84 Instanceindex = 1,comp_idx 0 g_ptr 0xed6c19b0
10-24 20:05:32.035   582  5184 I qomx_image_core: OMX_GetHandle:267] Success
10-24 20:05:32.036   725  5173 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 102: Book-keeping (frame_num, ack_frame_id) = [899, 105] in list
10-24 20:05:32.038   582  5184 I QCamera : <HAL><INFO> encodeData: 1965: Main image idx = 0 src w/h (2250x4000), dst w/h (2250x4000)
10-24 20:05:32.043   582  5208 E mm-still: startEncode:556] startEncode()
10-24 20:05:32.043   582  5208 I jpeg_hw : jpege_lib_hw_fe_buffer_cfg:295] w 2250 h 4000 stride 2304 scanline 4032
10-24 20:05:32.058   725  5167 I mm-camera: <STATS_AF >< INFO> 439: af_util_done: af->af_mode.mode = 3, af->caf.state=4
10-24 20:05:32.058   725  5167 I mm-camera: <STATS_AF >< INFO> 469: af_util_done: MainCamera=0 final lens pos 392, status=1
10-24 20:05:32.069   725  5173 I mm-camera: <MCT   >< INFO> 1105: mct_stream_map_frame_number: SOF 103: Book-keeping (frame_num, ack_frame_id) = [900, 106] in list
10-24 20:05:32.074   582  5208 I QCamera : <JPEG><INFO> mm_jpeg_fbd: 3553: count 0
10-24 20:05:32.074   582  5208 I QCamera : <JPEG><INFO> mm_jpeg_fbd: 3554: KPI Perf] : PROFILE_JPEG_FBD
10-24 20:05:32.075   582  5191 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 897, buffer = 0xf0442cc0
10-24 20:05:32.075   582  5208 I QCamera : <HAL><INFO> jpegEvtHandle: 3417: Issue Jpeg Callback frameNumber = 894 status = 0
10-24 20:05:32.076   582  5208 E QCamera : <HAL><ERROR> handleBufferWithLock: 3497: result frame_number = 894, buffer = 0xf0445360
10-24 20:05:32.106   753  2496 D Camera2ClientBase: Camera 0: start to disconnect
10-24 20:05:32.106   753  2496 D Camera2ClientBase: Camera 0: serializationLock acquired
10-24 20:05:32.106   753  2496 D Camera2ClientBase: Camera 0: Shutting down
10-24 20:05:32.106   753  2496 D Camera2ClientBase: Camera 0: start to cacheDump
10-24 20:05:32.126   582  5184 V qomx_image_core: OMX_FreeHandle:338]
10-24 20:05:32.126   582  5184 D qomx_image_core: get_idx_from_handle:297] comp_idx 0 inst_idx 1
10-24 20:05:32.127   582  5184 I qomx_image_core: OMX_FreeHandle:370] Error Component is still Active
10-24 20:05:32.127   582  5184 V qomx_image_core: OMX_FreeHandle:373] Success
10-24 20:05:32.127   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-24 20:05:32.127   725  5171 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10005 stream type=9
10-24 20:05:32.127   725  5171 I mm-camera: <CPP   >< INFO> 3927: cpp_module_handle_streamoff_event: info: doing stream-off for identity 0x10005
10-24 20:05:32.127   725  5171 I mm-camera: <CPP   >< INFO> 4112: cpp_module_handle_streamoff_event: info: stream-off done for identity 0x10005
10-24 20:05:32.127   725  5171 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10005, stream type=9
10-24 20:05:32.129   725  5163 I mm-camera: <CPP   >< INFO> 359: cpp_hardware_set_clock: Set clock 100000000 BW avg 93312000 BW inst 93312000
10-24 20:05:32.133   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=800000b
10-24 20:05:32.133   725  5171 I mm-camera: <MCT   >< INFO> 4125: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x10005 and stream type=9
10-24 20:05:32.133   725  5171 I mm-camera: <MCT   >< INFO> 4136: mct_pipeline_process_set: Stream 0x10005 and stream type=9, successfully deleted
10-24 20:05:32.134   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-24 20:05:32.134   725  5171 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10004 stream type=11
10-24 20:05:32.135   725  5171 I mm-camera: <IFACE >< INFO> 1619: iface_streamoff_to_thread: iface_streamoff_to_thread: E, session id = 1, user stream id = 4
10-24 20:05:32.135   725  5154 I mm-camera: <IFACE >< INFO> 2211: iface_streamoff: E, session_id 1 mct_stream_id = 4
10-24 20:05:32.135   725  5154 I mm-camera: <IFACE >< INFO> 10995: iface_util_get_user_streams_by_bundle: streamoff, first bundled streamoff all bindled streams!
10-24 20:05:32.137   725  5154 I mm-camera: <IFACE >< INFO> 380: iface_ispif_get_cfg_params_from_hw_streams: interace 0 active count = 2, skip stop ispif
10-24 20:05:32.137   725  5154 I mm-camera: <IFACE >< INFO> 380: iface_ispif_get_cfg_params_from_hw_streams: interace 0 active count = 1, skip stop ispif
10-24 20:05:32.138   725  5154 I mm-camera: <IFACE >< INFO> 2401: iface_streamoff: session_id = 1, active_streams = 0
10-24 20:05:32.138   725  5154 I mm-camera: <IFACE >< INFO> 2483: iface_streamoff: X
10-24 20:05:32.138   725  5171 I mm-camera: <IFACE >< INFO> 1645: iface_streamoff_to_thread: iface_streamoff_to_thread: X, session id = 1, user stream id = 4
10-24 20:05:32.138   725  5171 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10004, stream type=11
10-24 20:05:32.138   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-24 20:05:32.139   725  5171 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10002 stream type=1
10-24 20:05:32.139   725  5171 I mm-camera: <CPP   >< INFO> 3927: cpp_module_handle_streamoff_event: info: doing stream-off for identity 0x10002
10-24 20:05:32.140   725  5163 I mm-camera: <CPP   >< INFO> 359: cpp_hardware_set_clock: Set clock 100000000 BW avg 0 BW inst 0
10-24 20:05:32.140   725  5171 I mm-camera: <CPP   >< INFO> 4112: cpp_module_handle_streamoff_event: info: stream-off done for identity 0x10002
10-24 20:05:32.140   725  5171 I mm-camera: <IFACE >< INFO> 1619: iface_streamoff_to_thread: iface_streamoff_to_thread: E, session id = 1, user stream id = 2
10-24 20:05:32.140   725  5154 I mm-camera: <IFACE >< INFO> 2211: iface_streamoff: E, session_id 1 mct_stream_id = 2
10-24 20:05:32.140   725  5154 I mm-camera: <IFACE >< INFO> 11001: iface_util_get_user_streams_by_bundle: skip streamoff! left 1 bundled streamoff, mct stream id 2total bundled stream num = 3
10-24 20:05:32.140   725  5154 I mm-camera: <IFACE >< INFO> 2483: iface_streamoff: X
10-24 20:05:32.140   725  5171 I mm-camera: <IFACE >< INFO> 1645: iface_streamoff_to_thread: iface_streamoff_to_thread: X, session id = 1, user stream id = 2
10-24 20:05:32.140   725  5171 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10002, stream type=1
10-24 20:05:32.141   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-24 20:05:32.141   725  5171 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10003 stream type=3
10-24 20:05:32.141   725  5171 E mm-camera: <STATS_AIS ><ERROR> 173: dsps_send_req: DSPS Send Request Timeout!!
10-24 20:05:32.141   725  5171 E mm-camera: <STATS_AIS ><ERROR> 173: dsps_send_req: DSPS Send Request Timeout!!
10-24 20:05:32.141   725  5171 E mm-camera: <STATS_AIS ><ERROR> 173: dsps_send_req: DSPS Send Request Timeout!!
10-24 20:05:32.142   725  5171 I mm-camera: <IFACE >< INFO> 1619: iface_streamoff_to_thread: iface_streamoff_to_thread: E, session id = 1, user stream id = 3
10-24 20:05:32.142   725  5154 I mm-camera: <IFACE >< INFO> 2211: iface_streamoff: E, session_id 1 mct_stream_id = 3
10-24 20:05:32.142   725  5154 I mm-camera: <IFACE >< INFO> 11001: iface_util_get_user_streams_by_bundle: skip streamoff! left 0 bundled streamoff, mct stream id 3total bundled stream num = 3
10-24 20:05:32.143   725  5154 I mm-camera: <IFACE >< INFO> 2420: iface_streamoff: session id = 0x1,Delta between vfe irqs 0 sec 0 usec
10-24 20:05:32.145   725  5154 I mm-camera: <IFACE >< INFO> 10046: iface_util_release_resource: camif_cnt= 1, rdi_cnt= 0, used mask 10000
10-24 20:05:32.145   725  5154 I mm-camera: <IFACE >< INFO> 10046: iface_util_release_resource: camif_cnt= 0, rdi_cnt= 0, used mask 0
10-24 20:05:32.147   725  5154 I mm-camera: <IFACE >< INFO> 10046: iface_util_release_resource: camif_cnt= 0, rdi_cnt= 0, used mask 0
10-24 20:05:32.147   725  5154 I mm-camera: <IFACE >< INFO> 2478: iface_streamoff: all bundled stream streamoff, clear hal bundled mask
10-24 20:05:32.147   725  5154 I mm-camera: <IFACE >< INFO> 2483: iface_streamoff: X
10-24 20:05:32.148   725  5171 I mm-camera: <IFACE >< INFO> 1645: iface_streamoff_to_thread: iface_streamoff_to_thread: X, session id = 1, user stream id = 3
10-24 20:05:32.148   725  5171 I mm-camera: <MCT   >< INFO> 177: stop_sof_check_thread: Stopping SOF timeout thread session =1
10-24 20:05:32.148   725  5199 I mm-camera: <MCT   >< INFO> 114: mct_bus_sof_thread_run: Closing SOF tracker thread
10-24 20:05:32.148   725  5171 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10003, stream type=3
10-24 20:05:32.149   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-24 20:05:32.149   725  5171 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10001 stream type=7
10-24 20:05:32.149   725  5171 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10001, stream type=7
10-24 20:05:32.157   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000019
10-24 20:05:32.158   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000019
10-24 20:05:32.158   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000019
10-24 20:05:32.188   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000008
10-24 20:05:32.188   725  5171 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-ON on stream 0x10001 stream type=7
10-24 20:05:32.188   725  5171 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-ON success for stream 0x10001, stream type=7
10-24 20:05:32.190   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000008
10-24 20:05:32.190   725  5171 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-ON on stream 0x10002 stream type=1
10-24 20:05:32.190   725  5171 W libc    : Access denied finding property "camera.clientname"
10-24 20:05:32.190   725  5171 I mm-camera: <SENSOR>< INFO> 3789: sensor_get_resolution_info: sensor info: name: sakura_imx486_ofilm, res: 1, max_fps: 30.010000, w: 4032, h: 2272 op pix clk: 336000000, FLL: 2608, LLPCK: 5008, mode: 1, PDAF support: 1
10-24 20:05:32.191   725  5171 I mm-camera: <CPP   >< INFO> 1734: cpp_module_handle_stream_cfg_event: frame_offset=2, input_fps=30.00, identity=0x1000f
10-24 20:05:32.191   725  5171 I mm-camera: <CPP   >< INFO> 1734: cpp_module_handle_stream_cfg_event: frame_offset=2, input_fps=30.00, identity=0x10002
10-24 20:05:32.182   725   725 W CAM_MctServ: type=1400 audit(0.0:257): avc: denied { read } for name="u:object_r:default_prop:s0" dev="tmpfs" ino=15099 scontext=u:r:mm-qcamerad:s0 tcontext=u:object_r:default_prop:s0 tclass=file permissive=0
10-24 20:05:32.193   725  5171 I mm-camera: <IFACE >< INFO> 409: iface_util_dump_sensor_cfg: iface_util_dump_sensor_cfg: sensor dim: width = 4032, heght = 2272, fmt = 30, is_bayer = 1, init_skip = 1
10-24 20:05:32.197   725  5171 E mm-camera: <IFACE ><ERROR> 4075: iface_util_set_hw_stream_config_pix: iface_util_set_hw_stream_config_pix: Assigned vfe 1 intf 0 for related cam type 1
10-24 20:05:32.197   725  5171 I mm-camera: <IFACE >< INFO> 3573: iface_util_dump_isp_resource_request:  =====DUMP RESOURCE: PIX STREAM===
10-24 20:05:32.197   725  5171 I mm-camera: <IFACE >< INFO> 3574: iface_util_dump_isp_resource_request: num pix stream = 2
10-24 20:05:32.197   725  5171 I mm-camera: <IFACE >< INFO> 3577: iface_util_dump_isp_resource_request: num_isp = 1, isp_mask = 2, request_op_pix_clk = 0
10-24 20:05:32.197   725  5171 I mm-camera: <IFACE >< INFO> 3581: iface_util_dump_isp_resource_request: ispif stripe info: split 0, overlap 0, stripe offset 0
10-24 20:05:32.197   725  5171 I mm-camera: <IFACE >< INFO> 3584: iface_util_dump_isp_resource_request: pix stream 0
10-24 20:05:32.197   725  5171 I mm-camera: <IFACE >< INFO> 3587: iface_util_dump_isp_resource_request: num mct stream mapped = 2(Mapped stream ID[0] = 2)
10-24 20:05:32.198   725  5171 I mm-camera: <IFACE >< INFO> 3590: iface_util_dump_isp_resource_request: axi path = 1(ENC-0/VIEW-1/CAMIF-2/IDEAL-3), cam_fmt 7
10-24 20:05:32.198   725  5171 I mm-camera: <IFACE >< INFO> 3584: iface_util_dump_isp_resource_request: pix stream 1
10-24 20:05:32.198   725  5171 I mm-camera: <IFACE >< INFO> 3587: iface_util_dump_isp_resource_request: num mct stream mapped = 1(Mapped stream ID[0] = 3)
10-24 20:05:32.198   725  5171 I mm-camera: <IFACE >< INFO> 3590: iface_util_dump_isp_resource_request: axi path = 0(ENC-0/VIEW-1/CAMIF-2/IDEAL-3), cam_fmt 2
10-24 20:05:32.198   725  5171 I mm-camera: <IFACE >< INFO> 3612: iface_util_dump_isp_resource_request: num STATS stream = 5
10-24 20:05:32.198   725  5171 I mm-camera: <CPP   >< INFO> 865: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS]  stream type 1, width 1280, height 720,stride 1280, scanline 720, planes 2
10-24 20:05:32.198   725  5171 I mm-camera: <CPP   >< INFO> 884: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS] plane 0, stride 1280, scanline 720, offset_x 0, offset_y 0, len 921600, offset:0
10-24 20:05:32.198   725  5171 I mm-camera: <CPP   >< INFO> 884: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS] plane 1, stride 1280, scanline 720, offset_x 0, offset_y 0, len 460800, offset:0
10-24 20:05:32.201   725  5171 I mm-camera: <IFACE >< INFO> 8904: iface_util_dump_camif_cfg: =====Camif DUMP cfg for PIX interface====
10-24 20:05:32.201   725  5171 I mm-camera: <IFACE >< INFO> 8906: iface_util_dump_camif_cfg: camif input type = 3(MIPI=3), op_pix_clk = 320581411
10-24 20:05:32.202   725  5171 I mm-camera: <IFACE >< INFO> 8908: iface_util_dump_camif_cfg: camif pix_pattern(RGRG-0/GRGR-1/BGBG-2/GBGB-3) = 0
10-24 20:05:32.202   725  5171 I mm-camera: <IFACE >< INFO> 8910: iface_util_dump_camif_cfg: camif first_pix = 0, last_pix = 4031
10-24 20:05:32.202   725  5171 I mm-camera: <IFACE >< INFO> 8912: iface_util_dump_camif_cfg: camif first_line = 0, last_line = 2271
10-24 20:05:32.202   725  5171 I mm-camera: <IFACE >< INFO> 8914: iface_util_dump_camif_cfg: camif pixels_per_line = 4032, lines_per_frame = 2272
10-24 20:05:32.202   725  5171 I mm-camera: <IFACE >< INFO> 8918: iface_util_dump_camif_cfg: camif irq subsample pattern = 0, period = 0 sof_step 1
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 554: iface_dump_axi_plane_config: === AXI DUMP: VFE session_id 1, hw_stream_id 10002 ===
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 558: iface_dump_axi_plane_config:     AXI DUMP Axi_src: VIEWFINDER hw_stream width 1280, height 720
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 560: iface_dump_axi_plane_config: need buf divert = 1, burst count = 0
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 563: iface_dump_axi_plane_config: hfr mode = 0, skip pattern = 0, init_frame_drop = 1
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 596: iface_dump_axi_plane_config: plane[0]: plane_fmt 0(Y-0/CB-1/Cr-2/CrCb-3/CbCr-4)
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 598: iface_dump_axi_plane_config: plane[0]: width = 1280, height = 720
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 600: iface_dump_axi_plane_config: plane[0]: stride = 1280, scanlines = 720
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 602: iface_dump_axi_plane_config: plane[0]: address_offset 0
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 596: iface_dump_axi_plane_config: plane[1]: plane_fmt 4(Y-0/CB-1/Cr-2/CrCb-3/CbCr-4)
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 598: iface_dump_axi_plane_config: plane[1]: width = 1280, height = 360
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 600: iface_dump_axi_plane_config: plane[1]: stride = 1280, scanlines = 360
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 602: iface_dump_axi_plane_config: plane[1]: address_offset 0
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 554: iface_dump_axi_plane_config: === AXI DUMP: VFE session_id 1, hw_stream_id 3 ===
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 558: iface_dump_axi_plane_config:     AXI DUMP Axi_src: ENCODER hw_stream width 4000, height 2250
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 560: iface_dump_axi_plane_config: need buf divert = 0, burst count = 0
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 563: iface_dump_axi_plane_config: hfr mode = 0, skip pattern = 1, init_frame_drop = 1
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 596: iface_dump_axi_plane_config: plane[0]: plane_fmt 0(Y-0/CB-1/Cr-2/CrCb-3/CbCr-4)
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 598: iface_dump_axi_plane_config: plane[0]: width = 4000, height = 2250
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 600: iface_dump_axi_plane_config: plane[0]: stride = 4032, scanlines = 2304
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 602: iface_dump_axi_plane_config: plane[0]: address_offset 0
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 596: iface_dump_axi_plane_config: plane[1]: plane_fmt 3(Y-0/CB-1/Cr-2/CrCb-3/CbCr-4)
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 598: iface_dump_axi_plane_config: plane[1]: width = 4000, height = 1125
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 600: iface_dump_axi_plane_config: plane[1]: stride = 4032, scanlines = 1152
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 602: iface_dump_axi_plane_config: plane[1]: address_offset 0
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 554: iface_dump_axi_plane_config: === AXI DUMP: VFE session_id 1, hw_stream_id 9000f ===
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 558: iface_dump_axi_plane_config:     AXI DUMP Axi_src: CAMIF_RAW hw_stream width 504, height 284
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 560: iface_dump_axi_plane_config: need buf divert = 1, burst count = 0
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 563: iface_dump_axi_plane_config: hfr mode = 0, skip pattern = 0, init_frame_drop = 1
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 596: iface_dump_axi_plane_config: plane[0]: plane_fmt 0(Y-0/CB-1/Cr-2/CrCb-3/CbCr-4)
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 598: iface_dump_axi_plane_config: plane[0]: width = 504, height = 284
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 600: iface_dump_axi_plane_config: plane[0]: stride = 512, scanlines = 284
10-24 20:05:32.202   725  5219 I mm-camera: <IFACE >< INFO> 602: iface_dump_axi_plane_config: plane[0]: address_offset 0
10-24 20:05:32.204   725  5171 I mm-camera: <IFACE >< INFO> 1562: iface_streamon_to_thread: iface_streamon_to_thread: E, session id = 1, user stream id = 2
10-24 20:05:32.204   725  5154 I mm-camera: <IFACE >< INFO> 1907: iface_streamon: E, session_id 1 mct_stream_id = 2
10-24 20:05:32.204   725  5154 I mm-camera: <IFACE >< INFO> 10987: iface_util_get_user_streams_by_bundle: got No.1 bundled streamon, mct stream id 2total bundled stream num = 3
10-24 20:05:32.205   725  5171 I mm-camera: <ISP   >< INFO> 3632: mesh_rolloff40_normalize_table: full 4032 2272 out 4032 2272 offset 0 0 scale 1
10-24 20:05:32.209   725  5168 I mm-camera: <ISP   >< INFO> 90: rs_stats44_stats_config_update: warning: max_support_h_rgn invalid 8
10-24 20:05:32.209   725  5171 I mm-camera: <CPP   >< INFO> 3836: cpp_module_handle_streamon_event: identity=0x10002, stream-on done
10-24 20:05:32.209   725  5171 I mm-camera: <IFACE >< INFO> 1593: iface_streamon_to_thread: iface_streamon_to_thread: X, session id = 1, user stream id = 2
10-24 20:05:32.210   725  5171 I mm-camera: <MCT   >< INFO> 154: start_sof_check_thread: Starting SOF timeout thread session id =1
10-24 20:05:32.211   725  5171 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-ON success for stream 0x10002, stream type=1
10-24 20:05:32.211   725  5163 I mm-camera: <CPP   >< INFO> 359: cpp_hardware_set_clock: Set clock 100000000 BW avg 93312000 BW inst 93312000
10-24 20:05:32.211   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000008
10-24 20:05:32.211   725  5171 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-ON on stream 0x10003 stream type=3
10-24 20:05:32.211   725  5171 I mm-camera: <IFACE >< INFO> 1562: iface_streamon_to_thread: iface_streamon_to_thread: E, session id = 1, user stream id = 3
10-24 20:05:32.211   725  5154 I mm-camera: <IFACE >< INFO> 1907: iface_streamon: E, session_id 1 mct_stream_id = 3
10-24 20:05:32.212   725  5154 I mm-camera: <IFACE >< INFO> 10987: iface_util_get_user_streams_by_bundle: got No.2 bundled streamon, mct stream id 3total bundled stream num = 3
10-24 20:05:32.212   725  5168 I mm-camera: <ISP   >< INFO> 90: rs_stats44_stats_config_update: warning: max_support_h_rgn invalid 8
10-24 20:05:32.213   725  5171 I mm-camera: <IFACE >< INFO> 1593: iface_streamon_to_thread: iface_streamon_to_thread: X, session id = 1, user stream id = 3
10-24 20:05:32.213   725  5171 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-ON success for stream 0x10003, stream type=3
10-24 20:05:32.213   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000008
10-24 20:05:32.213   725  5171 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-ON on stream 0x10004 stream type=11
10-24 20:05:32.213   725  5171 I mm-camera: <IFACE >< INFO> 1562: iface_streamon_to_thread: iface_streamon_to_thread: E, session id = 1, user stream id = 4
10-24 20:05:32.214   725  5154 I mm-camera: <IFACE >< INFO> 1907: iface_streamon: E, session_id 1 mct_stream_id = 4
10-24 20:05:32.214   725  5154 I mm-camera: <IFACE >< INFO> 10981: iface_util_get_user_streams_by_bundle: streamon, got all bundled streamon!
10-24 20:05:32.214   725  5168 I mm-camera: <ISP   >< INFO> 90: rs_stats44_stats_config_update: warning: max_support_h_rgn invalid 8
10-24 20:05:32.214   725  5171 E mm-camera: <IMGLIB><ERROR> 318: faceproc_comp_set_param: Error param=521
10-24 20:05:32.214   725  5171 E mm-camera: <IMGLIB><ERROR> 3396: module_faceproc_client_exec: rc -4
10-24 20:05:32.219   725  5171 I mm-camera: <ISP   >< INFO> 451: be_stats44_trigger_update: warning: Denomitor is zero hnum 0 vnum 0
10-24 20:05:32.221   725  5154 I mm-camera: <IFACE >< INFO> 2173: iface_streamon: X
10-24 20:05:32.221   725  5171 I mm-camera: <IFACE >< INFO> 1593: iface_streamon_to_thread: iface_streamon_to_thread: X, session id = 1, user stream id = 4
10-24 20:05:32.221   725  5171 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-ON success for stream 0x10004, stream type=11
10-24 20:05:32.222   753  2496 I Camera3-Device: disconnectImpl: E
10-24 20:05:32.223   753  2496 I CameraLatencyHistogram: ProcessCaptureRequest latency histogram (102) samples:
10-24 20:05:32.223   753  2496 I CameraLatencyHistogram:        40     80    120    160    200    240    280    320    360    inf (max ms)
10-24 20:05:32.223   753  2496 I CameraLatencyHistogram:      28.43   68.63   0.00   2.94   0.00   0.00   0.00   0.00   0.00   0.00 (%)
10-24 20:05:32.224   582  3262 I QCamera : <HAL><INFO> close_camera_device: 10850: [KPI Perf]: E camera id 0
10-24 20:05:32.230   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-24 20:05:32.231   725  5171 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10004 stream type=11
10-24 20:05:32.231   725  5171 I mm-camera: <IFACE >< INFO> 1619: iface_streamoff_to_thread: iface_streamoff_to_thread: E, session id = 1, user stream id = 4
10-24 20:05:32.232   725  5154 I mm-camera: <IFACE >< INFO> 2211: iface_streamoff: E, session_id 1 mct_stream_id = 4
10-24 20:05:32.232   725  5154 I mm-camera: <IFACE >< INFO> 10995: iface_util_get_user_streams_by_bundle: streamoff, first bundled streamoff all bindled streams!
10-24 20:05:32.234   725  5154 I mm-camera: <IFACE >< INFO> 380: iface_ispif_get_cfg_params_from_hw_streams: interace 0 active count = 2, skip stop ispif
10-24 20:05:32.234   725  5154 I mm-camera: <IFACE >< INFO> 380: iface_ispif_get_cfg_params_from_hw_streams: interace 0 active count = 1, skip stop ispif
10-24 20:05:32.234   725  5154 I mm-camera: <IFACE >< INFO> 2401: iface_streamoff: session_id = 1, active_streams = 0
10-24 20:05:32.234   725  5154 I mm-camera: <IFACE >< INFO> 2483: iface_streamoff: X
10-24 20:05:32.234   725  5171 I mm-camera: <IFACE >< INFO> 1645: iface_streamoff_to_thread: iface_streamoff_to_thread: X, session id = 1, user stream id = 4
10-24 20:05:32.234   725  5171 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10004, stream type=11
10-24 20:05:32.235   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-24 20:05:32.235   725  5171 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10002 stream type=1
10-24 20:05:32.235   725  5171 I mm-camera: <CPP   >< INFO> 3927: cpp_module_handle_streamoff_event: info: doing stream-off for identity 0x10002
10-24 20:05:32.236   725  5163 I mm-camera: <CPP   >< INFO> 359: cpp_hardware_set_clock: Set clock 100000000 BW avg 0 BW inst 0
10-24 20:05:32.236   725  5171 I mm-camera: <CPP   >< INFO> 4112: cpp_module_handle_streamoff_event: info: stream-off done for identity 0x10002
10-24 20:05:32.236   725  5171 I mm-camera: <IFACE >< INFO> 1619: iface_streamoff_to_thread: iface_streamoff_to_thread: E, session id = 1, user stream id = 2
10-24 20:05:32.237   725  5154 I mm-camera: <IFACE >< INFO> 2211: iface_streamoff: E, session_id 1 mct_stream_id = 2
10-24 20:05:32.237   725  5154 I mm-camera: <IFACE >< INFO> 11001: iface_util_get_user_streams_by_bundle: skip streamoff! left 1 bundled streamoff, mct stream id 2total bundled stream num = 3
10-24 20:05:32.237   725  5154 I mm-camera: <IFACE >< INFO> 2483: iface_streamoff: X
10-24 20:05:32.237   725  5171 I mm-camera: <IFACE >< INFO> 1645: iface_streamoff_to_thread: iface_streamoff_to_thread: X, session id = 1, user stream id = 2
10-24 20:05:32.237   725  5171 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10002, stream type=1
10-24 20:05:32.237   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-24 20:05:32.237   725  5171 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10003 stream type=3
10-24 20:05:32.240   725  5171 E mm-camera: <STATS_AIS ><ERROR> 173: dsps_send_req: DSPS Send Request Timeout!!
10-24 20:05:32.240   725  5171 E mm-camera: <STATS_AIS ><ERROR> 173: dsps_send_req: DSPS Send Request Timeout!!
10-24 20:05:32.240   725  5171 E mm-camera: <STATS_AIS ><ERROR> 173: dsps_send_req: DSPS Send Request Timeout!!
10-24 20:05:32.241   725  5171 I mm-camera: <IFACE >< INFO> 1619: iface_streamoff_to_thread: iface_streamoff_to_thread: E, session id = 1, user stream id = 3
10-24 20:05:32.241   725  5154 I mm-camera: <IFACE >< INFO> 2211: iface_streamoff: E, session_id 1 mct_stream_id = 3
10-24 20:05:32.241   725  5154 I mm-camera: <IFACE >< INFO> 11001: iface_util_get_user_streams_by_bundle: skip streamoff! left 0 bundled streamoff, mct stream id 3total bundled stream num = 3
10-24 20:05:32.242   725  5154 I mm-camera: <IFACE >< INFO> 2420: iface_streamoff: session id = 0x1,Delta between vfe irqs 0 sec 0 usec
10-24 20:05:32.244   725  5154 I mm-camera: <IFACE >< INFO> 10046: iface_util_release_resource: camif_cnt= 1, rdi_cnt= 0, used mask 10000
10-24 20:05:32.244   725  5154 I mm-camera: <IFACE >< INFO> 10046: iface_util_release_resource: camif_cnt= 0, rdi_cnt= 0, used mask 0
10-24 20:05:32.244   725  5154 I mm-camera: <IFACE >< INFO> 10046: iface_util_release_resource: camif_cnt= 0, rdi_cnt= 0, used mask 0
10-24 20:05:32.244   725  5154 I mm-camera: <IFACE >< INFO> 2478: iface_streamoff: all bundled stream streamoff, clear hal bundled mask
10-24 20:05:32.244   725  5154 I mm-camera: <IFACE >< INFO> 2483: iface_streamoff: X
10-24 20:05:32.244   725  5171 I mm-camera: <IFACE >< INFO> 1645: iface_streamoff_to_thread: iface_streamoff_to_thread: X, session id = 1, user stream id = 3
10-24 20:05:32.245   725  5171 I mm-camera: <MCT   >< INFO> 177: stop_sof_check_thread: Stopping SOF timeout thread session =1
10-24 20:05:32.245   725  5220 I mm-camera: <MCT   >< INFO> 114: mct_bus_sof_thread_run: Closing SOF tracker thread
10-24 20:05:32.245   725  5171 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10003, stream type=3
10-24 20:05:32.246   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=8000009
10-24 20:05:32.246   725  5171 I mm-camera: <MCT   >< INFO> 4045: mct_pipeline_process_set:  STREAM-OFF on stream 0x10001 stream type=7
10-24 20:05:32.246   725  5171 I mm-camera: <MCT   >< INFO> 4060: mct_pipeline_process_set:  STREAM-OFF success for stream 0x10001, stream type=7
10-24 20:05:32.252   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=800000b
10-24 20:05:32.252   725  5171 I mm-camera: <MCT   >< INFO> 4125: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x10002 and stream type=1
10-24 20:05:32.252   725  5171 I mm-camera: <IFACE >< INFO> 671: iface_unreserve_sink_port: stream_id 2 mode 0
10-24 20:05:32.252   725  5171 I mm-camera: <ISP   >< INFO> 890: isp_port_check_caps_unreserve: ide 10002 port 0xe7994f50
10-24 20:05:32.253   725  5171 I mm-camera: <MCT   >< INFO> 4136: mct_pipeline_process_set: Stream 0x10002 and stream type=1, successfully deleted
10-24 20:05:32.255   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=800000b
10-24 20:05:32.255   725  5171 I mm-camera: <MCT   >< INFO> 4125: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x10003 and stream type=3
10-24 20:05:32.255   725  5171 I mm-camera: <IFACE >< INFO> 671: iface_unreserve_sink_port: stream_id 3 mode 0
10-24 20:05:32.255   725  5171 I mm-camera: <ISP   >< INFO> 890: isp_port_check_caps_unreserve: ide 10003 port 0xe7994f50
10-24 20:05:32.256   725  5171 I mm-camera: <MCT   >< INFO> 4136: mct_pipeline_process_set: Stream 0x10003 and stream type=3, successfully deleted
10-24 20:05:32.257   582  3262 V qomx_image_core: OMX_FreeHandle:338]
10-24 20:05:32.257   582  3262 D qomx_image_core: get_idx_from_handle:297] comp_idx 0 inst_idx 0
10-24 20:05:32.259   582  3262 V qomx_image_core: OMX_FreeHandle:373] Success
10-24 20:05:32.259   582  3262 I qomx_image_core: OMX_Deinit:125] Complete
10-24 20:05:32.261   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=800000b
10-24 20:05:32.261   725  5171 I mm-camera: <MCT   >< INFO> 4125: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x10004 and stream type=11
10-24 20:05:32.261   725  5171 I mm-camera: <IFACE >< INFO> 671: iface_unreserve_sink_port: stream_id 4 mode 0
10-24 20:05:32.261   725  5171 I mm-camera: <ISP   >< INFO> 890: isp_port_check_caps_unreserve: ide 10004 port 0xe7994f50
10-24 20:05:32.262   725  5171 I mm-camera: <MCT   >< INFO> 4136: mct_pipeline_process_set: Stream 0x10004 and stream type=11, successfully deleted
10-24 20:05:32.263   725  5171 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=800000b
10-24 20:05:32.263   725  5171 I mm-camera: <MCT   >< INFO> 4125: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x10001 and stream type=7
10-24 20:05:32.263   725  5171 I mm-camera: <MCT   >< INFO> 4136: mct_pipeline_process_set: Stream 0x10001 and stream type=7, successfully deleted
10-24 20:05:32.264   725  5171 I mm-camera: <ISP   >< INFO> 6629: isp_util_print_meta_stream_info: Deallocate resources
10-24 20:05:32.264   725   993 E mm-camera: <IMGLIB><ERROR> 303: AllocateBuffers: Invalid dimensions 0x0
10-24 20:05:32.264   725   993 E mm-camera: <IMGLIB><ERROR> 203: img_algo_preload: Preload: Failed to allocate buffer, rc -4
10-24 20:05:32.264   725   993 E mm-camera: <IMGLIB><ERROR> 119: module_imgbase_client_preload_exec: IMG_CORE_PRELOAD failed -4
10-24 20:05:32.267   725  5171 I mm-camera: <ISP   >< INFO> 2537: isp_resource_allocate: INFO: ISP resource acquired: session 1 num_isp 1
10-24 20:05:32.267   725  5171 I mm-camera: <ISP   >< INFO> 2539: isp_resource_allocate: INFO: ISP resource acquired: hw_id 1
10-24 20:05:32.267   725  5171 I mm-camera: <ISP   >< INFO> 6479: isp_util_decide_stream_mapping: stream_port_map num streams 0
10-24 20:05:32.269   725  5171 E mm-camera: <MCT   ><ERROR> 1173: mct_pipeline_decide_hw_wakeup: Couldn't find meta stream
10-24 20:05:32.271   582  3262 I QCamera : <HAL><INFO> closeCamera: 882: [KPI Perf]: E PROFILE_CLOSE_CAMERA camera id 0
10-24 20:05:32.272   725   725 I mm-camera: <MCT   >< INFO> 216: mct_controller_destroy: Initiating destroy sequence for session = 1
10-24 20:05:32.272   725   725 I mm-camera: <MCT   >< INFO> 234: mct_controller_destroy: serv_thread closed
10-24 20:05:32.272   725   725 I mm-camera: <MCT   >< INFO> 249: mct_controller_destroy: bus_handler thread closed
10-24 20:05:32.272   725   725 I mm-camera: <MCT   >< INFO> 4886: mct_pipeline_stop_session: Initiating stop_session on session 1
10-24 20:05:32.273   725   725 I mm-camera: <MCT   >< INFO> 3907: mct_pipeline_process_set: command=800000b
10-24 20:05:32.273   725   725 I mm-camera: <MCT   >< INFO> 4125: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x1000f and stream type=10
10-24 20:05:32.273   725   725 I mm-camera: <IFACE >< INFO> 671: iface_unreserve_sink_port: stream_id f mode 0
10-24 20:05:32.273   725   725 I mm-camera: <ISP   >< INFO> 890: isp_port_check_caps_unreserve: ide 1000f port 0xe7994f50
10-24 20:05:32.274   725   725 I mm-camera: <MCT   >< INFO> 4136: mct_pipeline_process_set: Stream 0x1000f and stream type=10, successfully deleted
10-24 20:05:32.275   725  5226 I mm-camera: <MCT   >< INFO> 4620: mct_pipeline_stop_session_thread: Stop module name: sensor - E
10-24 20:05:32.275   725  5228 I mm-camera: <MCT   >< INFO> 4620: mct_pipeline_stop_session_thread: Stop module name: isp - E
10-24 20:05:32.275   725  5227 I mm-camera: <MCT   >< INFO> 4620: mct_pipeline_stop_session_thread: Stop module name: iface - E
10-24 20:05:32.275   725  5228 I mm-camera: <ISP   >< INFO> 491: isp_module_stop_session: session id 1
10-24 20:05:32.275   725  5228 I mm-camera: <ISP   >< INFO> 532: isp_module_stop_session: warning: ISP resource is not freed on time!
10-24 20:05:32.275   725  5229 I mm-camera: <MCT   >< INFO> 4620: mct_pipeline_stop_session_thread: Stop module name: stats - E
10-24 20:05:32.276   725  5227 I mm-camera: <MCT   >< INFO> 4628: mct_pipeline_stop_session_thread: Stop module name: iface - X
10-24 20:05:32.278   725  5230 I mm-camera: <MCT   >< INFO> 4620: mct_pipeline_stop_session_thread: Stop module name: pproc - E
10-24 20:05:32.278   725  5228 I mm-camera: <MCT   >< INFO> 4628: mct_pipeline_stop_session_thread: Stop module name: isp - X
10-24 20:05:32.279   725  5230 D QCameraQuadraCfa: img_algo_shutdown:147 : E
10-24 20:05:32.279   725  5230 E quadracfa_dummy: inside dummy remosaic_deinit 1
10-24 20:05:32.279   725  5230 D QCameraQuadraCfa: img_algo_shutdown:151 : X
10-24 20:05:32.279   725  5230 I mm-camera: <MCT   >< INFO> 4628: mct_pipeline_stop_session_thread: Stop module name: pproc - X
10-24 20:05:32.279   725  5231 I mm-camera: <MCT   >< INFO> 4620: mct_pipeline_stop_session_thread: Stop module name: imglib - E
10-24 20:05:32.279   725   725 I mm-camera: <MCT   >< INFO> 4908: mct_pipeline_stop_session: Modules left: 5
10-24 20:05:32.279   725   725 I mm-camera: <MCT   >< INFO> 4908: mct_pipeline_stop_session: Modules left: 4
10-24 20:05:32.279   725   725 I mm-camera: <MCT   >< INFO> 4908: mct_pipeline_stop_session: Modules left: 3
10-24 20:05:32.280   725  5229 D DmbrContextAPI: VIDHANCE dmbr_free_context user=0xe79ab870
10-24 20:05:32.280   725  5231 I mm-camera: <MCT   >< INFO> 4628: mct_pipeline_stop_session_thread: Stop module name: imglib - X
10-24 20:05:32.281   725   725 I mm-camera: <MCT   >< INFO> 4908: mct_pipeline_stop_session: Modules left: 2
10-24 20:05:32.282   725  5229 I mm-camera: <MCT   >< INFO> 4628: mct_pipeline_stop_session_thread: Stop module name: stats - X
10-24 20:05:32.283   725   725 I mm-camera: <MCT   >< INFO> 4908: mct_pipeline_stop_session: Modules left: 1
10-24 20:05:32.349   464   465 D rpmb_emmc: rpmb_emmc_write: blk_cnt: 2, num_rpmb_tx: 1, frames_per_tx: 2, rel_wr_cnt: 32
10-24 20:05:32.353   464   468 D DrmLibTime: got the req here! ret=0
10-24 20:05:32.353   464   468 D DrmLibTime: command id, time_cmd_id = 770
10-24 20:05:32.353   464   468 D DrmLibTime: time_getutcsec starts!
10-24 20:05:32.353   464   468 D DrmLibTime: QSEE Time Listener: time_getutcsec
10-24 20:05:32.353   464   468 D DrmLibTime: QSEE Time Listener: get_utc_seconds
10-24 20:05:32.353   464   468 D DrmLibTime: QSEE Time Listener: time_get_modem_time
10-24 20:05:32.353   464   468 D DrmLibTime: QSEE Time Listener: Checking if ATS_MODEM is set or not.
10-24 20:05:32.353   464   468 D QC-time-services: Lib:time_genoff_operation: pargs->base = 13
10-24 20:05:32.353   464   468 D QC-time-services: Lib:time_genoff_operation: pargs->operation = 2
10-24 20:05:32.353   464   468 D QC-time-services: Lib:time_genoff_operation: pargs->ts_val = 0
10-24 20:05:32.353   464   468 D QC-time-services: Lib:time_genoff_operation: Send to server  passed!!
10-24 20:05:32.354   527   537 D QC-time-services: Daemon: Connection accepted:time_genoff
10-24 20:05:32.358   527  5241 D QC-time-services: Daemon:Received base = 13, unit = 1, operation = 2,value = 0
10-24 20:05:32.358   527  5241 D QC-time-services: Daemon:genoff_opr: Base = 13, val = 0, operation = 2
10-24 20:05:32.358   527  5241 D QC-time-services: offset is: 1 for base: 13
10-24 20:05:32.358   464   468 E QC-time-services: Receive Passed == base = 13, unit = 1, operation = 2, result = 0
10-24 20:05:32.358   464   468 D DrmLibTime: QSEE Time Listener: ATS_MODEM is set. Try to retrieve it.
10-24 20:05:32.360   527   537 E QC-time-services: Daemon: Time-services: Waiting to acceptconnection
10-24 20:05:32.361   527   537 D QC-time-services: Daemon: Connection accepted:time_genoff
10-24 20:05:32.361   527  5242 D QC-time-services: Daemon:Received base = 13, unit = 1, operation = 1,value = 0
10-24 20:05:32.361   527  5242 D QC-time-services: Daemon:genoff_opr: Base = 13, val = 0, operation = 1
10-24 20:05:32.361   527  5242 D QC-time-services: Daemon: genoff get for 13
10-24 20:05:32.361   527  5242 D QC-time-services: Daemon:Value read from QTimer mseconds = 376932
10-24 20:05:32.361   527  5242 D QC-time-services: Daemon:Value read from RTC mseconds on boot = 38435628000
10-24 20:05:32.361   527  5242 D QC-time-services: Daemon:Value read from QTimer mseconds = 376932
10-24 20:05:32.361   527  5242 D QC-time-services: Daemon:Value read from generic offset = 1628198773762
10-24 20:05:32.361   527  5242 D QC-time-services: Daemon:Delta read on boot mseconds = 38435581055
10-24 20:05:32.361   527  5242 D QC-time-services: Daemon:Final Time = 1666634731749
10-24 20:05:32.361   464   468 D DrmLibTime: QSEE Time Listener: Time GenOff - seconds: 1666634731
10-24 20:05:32.361   464   468 D DrmLibTime: time_getutcsec returns 0, sec = 1666634731; nsec = 0
10-24 20:05:32.361   464   468 D DrmLibTime: time_getutcsec finished!
10-24 20:05:32.361   464   468 D DrmLibTime: iotcl_continue_command finished! and return 0
10-24 20:05:32.361   464   468 D DrmLibTime: before calling ioctl to read the next time_cmd
10-24 20:05:32.362   527   537 E QC-time-services: Daemon: Time-services: Waiting to acceptconnection
10-24 20:05:32.370   725  5226 I mm-camera: <MCT   >< INFO> 4628: mct_pipeline_stop_session_thread: Stop module name: sensor - X
10-24 20:05:32.370   725   725 I mm-camera: <MCT   >< INFO> 4908: mct_pipeline_stop_session: Modules left: 0
10-24 20:05:32.370   725   725 I mm-camera: <MCT   >< INFO> 4917: mct_pipeline_stop_session: Stopped session 1 successfully
10-24 20:05:32.370   725   725 I mm-camera: <MCT   >< INFO> 5194: mct_pipeline_destroy: E: session:1
10-24 20:05:32.370   725   725 I mm-camera: <MCT   >< INFO> 5267: mct_pipeline_destroy: X: Pipeline destroyed successfully session =1
10-24 20:05:32.370   725   725 I mm-camera: <MCT   >< INFO> 282: mct_controller_destroy: X Successfully closed mct_controller session 1
10-24 20:05:32.362   582   582 W HwBinder:582_3: type=1400 audit(0.0:258): avc: denied { read } for name="u:object_r:bootanim_system_prop:s0" dev="tmpfs" ino=15045 scontext=u:r:hal_camera_default:s0 tcontext=u:object_r:bootanim_system_prop:s0 tclass=file permissive=0
10-24 20:05:32.371   582  3262 W libc    : Access denied finding property "service.bootanim.exit"
10-24 20:05:32.372   753  2496 I CameraProviderManager: Camera device device@3.3/legacy/0 torch status is now AVAILABLE_OFF
10-24 20:05:32.372   753  2496 I CameraService: onTorchStatusChangedLocked: Torch status changed for cameraId=0, newStatus=1
10-24 20:05:32.373   582  3262 I QCamera : <HAL><INFO> closeCamera: 924: [KPI Perf]: X PROFILE_CLOSE_CAMERA camera id 0, rc: 0
10-24 20:05:32.377   582  3262 I hw-BpHwBinder: onLastStrongRef automatically unlinking death recipients
10-24 20:05:32.378   582  3262 I QCamera : <HAL><INFO> close_camera_device: 10852: [KPI Perf]: X
10-24 20:05:32.380   464   465 D rpmb_emmc: rpmb_emmc_write: blk_cnt: 2, num_rpmb_tx: 1, frames_per_tx: 2, rel_wr_cnt: 32
10-24 20:05:32.398   753  2496 I CameraLatencyHistogram: Stream 0 dequeueBuffer latency histogram (101) samples:
10-24 20:05:32.398   753  2496 I CameraLatencyHistogram:         5     10     15     20     25     30     35     40     45    inf (max ms)
10-24 20:05:32.398   753  2496 I CameraLatencyHistogram:      100.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00 (%)
10-24 20:05:32.399   753  2496 I CameraLatencyHistogram: Stream 1 dequeueBuffer latency histogram (1) samples:
10-24 20:05:32.399   753  2496 I CameraLatencyHistogram:         5     10     15     20     25     30     35     40     45    inf (max ms)
10-24 20:05:32.399   753  2496 I CameraLatencyHistogram:      100.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00 (%)
10-24 20:05:32.399   753  2496 I Camera3-Device: disconnectImpl: X
10-24 20:05:32.403   753  2496 I CameraService: disconnect: Disconnected client for camera 0 for PID 2769
10-24 20:05:32.405   464   465 D rpmb_emmc: rpmb_emmc_write: blk_cnt: 2, num_rpmb_tx: 1, frames_per_tx: 2, rel_wr_cnt: 32
10-24 20:05:32.410   464   468 D DrmLibTime: got the req here! ret=0
10-24 20:05:32.410   464   468 D DrmLibTime: command id, time_cmd_id = 770
10-24 20:05:32.410   464   468 D DrmLibTime: time_getutcsec starts!
10-24 20:05:32.410   464   468 D DrmLibTime: QSEE Time Listener: time_getutcsec
10-24 20:05:32.410   464   468 D DrmLibTime: QSEE Time Listener: get_utc_seconds
10-24 20:05:32.410   464   468 D DrmLibTime: QSEE Time Listener: time_get_modem_time
10-24 20:05:32.410   464   468 D DrmLibTime: QSEE Time Listener: Checking if ATS_MODEM is set or not.
10-24 20:05:32.410   464   468 D QC-time-services: Lib:time_genoff_operation: pargs->base = 13
10-24 20:05:32.410   464   468 D QC-time-services: Lib:time_genoff_operation: pargs->operation = 2
10-24 20:05:32.410   464   468 D QC-time-services: Lib:time_genoff_operation: pargs->ts_val = 0
10-24 20:05:32.411   464   468 D QC-time-services: Lib:time_genoff_operation: Send to server  passed!!
10-24 20:05:32.411   527   537 D QC-time-services: Daemon: Connection accepted:time_genoff
10-24 20:05:32.412   527  5244 D QC-time-services: Daemon:Received base = 13, unit = 1, operation = 2,value = 0
10-24 20:05:32.412   527  5244 D QC-time-services: Daemon:genoff_opr: Base = 13, val = 0, operation = 2
10-24 20:05:32.412   527  5244 D QC-time-services: offset is: 1 for base: 13
10-24 20:05:32.412   464   468 E QC-time-services: Receive Passed == base = 13, unit = 1, operation = 2, result = 0
10-24 20:05:32.412   464   468 D DrmLibTime: QSEE Time Listener: ATS_MODEM is set. Try to retrieve it.
10-24 20:05:32.412   527   537 E QC-time-services: Daemon: Time-services: Waiting to acceptconnection
10-24 20:05:32.412   527   537 D QC-time-services: Daemon: Connection accepted:time_genoff
10-24 20:05:32.412   527  5245 D QC-time-services: Daemon:Received base = 13, unit = 1, operation = 1,value = 0
10-24 20:05:32.412   527  5245 D QC-time-services: Daemon:genoff_opr: Base = 13, val = 0, operation = 1
10-24 20:05:32.412   527  5245 D QC-time-services: Daemon: genoff get for 13
10-24 20:05:32.412   527  5245 D QC-time-services: Daemon:Value read from QTimer mseconds = 376983
10-24 20:05:32.412   527  5245 D QC-time-services: Daemon:Value read from RTC mseconds on boot = 38435628000
10-24 20:05:32.412   527  5245 D QC-time-services: Daemon:Value read from QTimer mseconds = 376983
10-24 20:05:32.412   527  5245 D QC-time-services: Daemon:Value read from generic offset = 1628198773762
10-24 20:05:32.412   527  5245 D QC-time-services: Daemon:Delta read on boot mseconds = 38435581055
10-24 20:05:32.412   527  5245 D QC-time-services: Daemon:Final Time = 1666634731800
10-24 20:05:32.412   464   468 D DrmLibTime: QSEE Time Listener: Time GenOff - seconds: 1666634731
10-24 20:05:32.412   464   468 D DrmLibTime: time_getutcsec returns 0, sec = 1666634731; nsec = 0
10-24 20:05:32.412   464   468 D DrmLibTime: time_getutcsec finished!
10-24 20:05:32.412   464   468 D DrmLibTime: iotcl_continue_command finished! and return 0
10-24 20:05:32.412   464   468 D DrmLibTime: before calling ioctl to read the next time_cmd
10-24 20:05:32.413   527   537 E QC-time-services: Daemon: Time-services: Waiting to acceptconnection
10-24 20:05:32.421  2769  4746 E IgnoringAnomalyNotifier: onAnomaly: GL_FRAME_BUFFER_SKIP_CREATE_FRAMEBUFFER_ZERO_WIDTH
10-24 20:05:32.421  2769  4746 E IgnoringAnomalyNotifier: onAnomaly: GL_FRAME_BUFFER_SKIP_CREATE_FRAMEBUFFER_ZERO_HEIGHT
10-24 20:05:32.421  2769  4746 E IgnoringAnomalyNotifier: onAnomaly: GL_FRAME_BUFFER_SKIP_CREATE_FRAMEBUFFER_ZERO_WIDTH
10-24 20:05:32.421  2769  4746 E IgnoringAnomalyNotifier: onAnomaly: GL_FRAME_BUFFER_SKIP_CREATE_FRAMEBUFFER_ZERO_HEIGHT
10-24 20:05:32.431   464   465 D rpmb_emmc: rpmb_emmc_write: blk_cnt: 2, num_rpmb_tx: 1, frames_per_tx: 2, rel_wr_cnt: 32
10-24 20:05:32.753  2769  2791 I stagram.android: NativeAlloc concurrent copying GC freed 640686(29MB) AllocSpace objects, 196(10MB) LOS objects, 63% free, 28MB/76MB, paused 423us,195us total 418.041ms
10-24 20:05:32.756   753  2496 D Camera2ClientBase: Camera 0: start to disconnect
10-24 20:05:32.756   753  2496 D Camera2ClientBase: Camera 0: serializationLock acquired
10-24 20:05:32.756   753  2496 D Camera2ClientBase: Camera 0: Shutting down
10-24 20:05:32.756   753  2496 I Camera3-Device: disconnectImpl: E
10-24 20:05:32.756   753  2496 I Camera2ClientBase: Closed Camera 0. Client was: com.instagram.android (PID 2769, UID 10136)
10-24 20:05:32.757   753  2496 I Camera3-Device: disconnectImpl: E
10-24 20:05:32.757   753  2496 I Camera3DeviceInjectionMethods: injectionDisconnectImpl: Injection camera disconnect
10-24 20:05:32.758  2769  2795 I BpBinder: onLastStrongRef automatically unlinking death recipients: <uncached descriptor>
10-24 20:05:32.810   753  2496 D Camera2ClientBase: Camera 0: start to disconnect
10-24 20:05:32.810   753  2496 D Camera2ClientBase: Camera 0: serializationLock acquired
10-24 20:05:32.810   753  2496 D Camera2ClientBase: Camera 0: Shutting down
10-24 20:05:32.810   753  2496 I Camera3-Device: disconnectImpl: E
10-24 20:05:32.810   753  2496 I Camera2ClientBase: Closed Camera 0. Client was: com.instagram.android (PID 2769, UID 10136)
10-24 20:05:32.811   753  2496 I Camera3-Device: disconnectImpl: E
10-24 20:05:32.811   753  2496 I Camera3DeviceInjectionMethods: injectionDisconnectImpl: Injection camera disconnect
10-24 20:05:32.811  2769  4415 I BpBinder: onLastStrongRef automatically unlinking death recipients: <uncached descriptor>
10-24 20:05:32.846   753  2496 D Camera2ClientBase: Camera 0: start to disconnect
10-24 20:05:32.846   753  2496 D Camera2ClientBase: Camera 0: serializationLock acquired
10-24 20:05:32.846   753  2496 D Camera2ClientBase: Camera 0: Shutting down
10-24 20:05:32.846   753  2496 I Camera3-Device: disconnectImpl: E
10-24 20:05:32.846   753  2496 I Camera2ClientBase: Closed Camera 0. Client was: com.instagram.android (PID 2769, UID 10136)
10-24 20:05:32.847   753  2496 I Camera3-Device: disconnectImpl: E
10-24 20:05:32.847   753  2496 I Camera3DeviceInjectionMethods: injectionDisconnectImpl: Injection camera disconnect
10-24 20:05:32.847  2769  2795 I BpBinder: onLastStrongRef automatically unlinking death recipients: <uncached descriptor>