Switching between the front and the rear camera is very slow on eOS.
Does anyone else experience this?
If the camera app is opened and I switch from the front to the rear camera, the picture often stays black for more than 5 seconds.
Same when switching from back to front camera.
On the stock ROM the picture stays black for only less than a second.
First flashed & tested with the most recent stock ROM:
FP3-REL-Q-3.A.0054-20200929.092252-user-fastbootimage.7z
Then tested with the most recent eOS ROM:
IMG-e-0.12-q-2020111084008-dev-FP3.zip
On both ROMs tested with OpenCamera (from F-Droid) and with the respective preinstalled camera app and with CameraAPI version 1 and 2 (see OpenCamera settings).
P.S.
Sometimes opening the camera app is also slow (>5 seconds).
But most of the time opening the camera app is fast.
On the other hand, sometimes switching the camera is fast too (<1 second).
But most of the time switching front<->back is slow.
And I also flashed the older stock rom FP3-REL-Q-3.A.0045-20200905.155001-user-fastbootimage.7z and then tested with the older eOS FP3+ beta IMG-e-0.12-q-2020110282350-dev-FP3.zip.
But the result was the same.
logcat output (inserted some newlines where the app was not reacting)
11-30 16:39:36.358 880 1969 I CameraLatencyHistogram: ProcessCaptureRequest latency histogram (28) samples:
11-30 16:39:36.358 880 1969 I CameraLatencyHistogram: 40 80 120 160 200 240 280 320 360 inf (max ms)
11-30 16:39:36.358 880 1969 I CameraLatencyHistogram: 92.86 0.00 3.57 3.57 0.00 0.00 0.00 0.00 0.00 0.00 (%)
11-30 16:39:36.358 688 688 I QCamera : <HAL><INFO> close_camera_device: 17317: [KPI Perf]: E camera id 0
11-30 16:39:36.361 688 8688 I mm-camera: <MCT >< INFO> 4234: mct_pipeline_process_set: command=8000009
11-30 16:39:36.361 688 8688 I mm-camera: <MCT >< INFO> 4397: mct_pipeline_process_set: STREAM-OFF on stream 0x10004 stream type=11
11-30 16:39:36.361 688 8688 I mm-camera: <IFACE >< INFO> 1836: iface_streamoff_to_thread: iface_streamoff_to_thread: E, session id = 1, user stream id = 4
11-30 16:39:36.361 688 8694 I mm-camera: <IFACE >< INFO> 2467: iface_streamoff: E, session_id 1 mct_stream_id = 4
11-30 16:39:36.361 688 8694 I mm-camera: <IFACE >< INFO> 12254: iface_util_get_user_streams_by_bundle: streamoff, first bundled streamoff all bindled streams!
11-30 16:39:36.361 688 8694 I mm-camera: <IFACE >< INFO> 452: iface_ispif_get_cfg_params_from_hw_streams: interace 0 active count = 1, skip stop ispif
11-30 16:39:36.368 688 8694 I mm-camera: <IFACE >< INFO> 2713: iface_streamoff: session_id = 1, active_streams = 0
11-30 16:39:36.368 688 8694 I mm-camera: <IFACE >< INFO> 2722: iface_streamoff: X
11-30 16:39:36.368 688 8688 I mm-camera: <IFACE >< INFO> 1862: iface_streamoff_to_thread: iface_streamoff_to_thread: X, session id = 1, user stream id = 4
11-30 16:39:36.369 688 8688 I mm-camera: <MCT >< INFO> 4414: mct_pipeline_process_set: STREAM-OFF success for stream 0x10004, stream type=11
11-30 16:39:36.370 688 8688 I mm-camera: <MCT >< INFO> 4234: mct_pipeline_process_set: command=8000009
11-30 16:39:36.370 688 8688 I mm-camera: <MCT >< INFO> 4397: mct_pipeline_process_set: STREAM-OFF on stream 0x10002 stream type=1
11-30 16:39:36.370 688 8688 I mm-camera: <IFACE >< INFO> 1836: iface_streamoff_to_thread: iface_streamoff_to_thread: E, session id = 1, user stream id = 2
11-30 16:39:36.370 688 8694 I mm-camera: <IFACE >< INFO> 2467: iface_streamoff: E, session_id 1 mct_stream_id = 2
11-30 16:39:36.370 688 8694 I mm-camera: <IFACE >< INFO> 12260: iface_util_get_user_streams_by_bundle: skip streamoff! left 1 bundled streamoff, mct stream id 2total bundled stream num = 3
11-30 16:39:36.370 688 8694 I mm-camera: <IFACE >< INFO> 2722: iface_streamoff: X
11-30 16:39:36.370 688 8688 I mm-camera: <IFACE >< INFO> 1862: iface_streamoff_to_thread: iface_streamoff_to_thread: X, session id = 1, user stream id = 2
11-30 16:39:36.370 688 8688 I mm-camera: <CPP >< INFO> 4821: cpp_module_handle_streamoff_event: info: doing stream-off for identity 0x10002
11-30 16:39:36.371 688 8688 I mm-camera: <CPP >< INFO> 5019: cpp_module_handle_streamoff_event: info: stream-off done for identity 0x10002 rc:0
11-30 16:39:36.371 688 8701 I mm-camera: <CPP >< INFO> 374: cpp_hardware_set_clock: Set clock 100000000 BW avg 0 BW inst 0
11-30 16:39:36.371 688 8688 I mm-camera: <MCT >< INFO> 4414: mct_pipeline_process_set: STREAM-OFF success for stream 0x10002, stream type=1
11-30 16:39:36.372 688 8688 I mm-camera: <MCT >< INFO> 4234: mct_pipeline_process_set: command=8000009
11-30 16:39:36.372 688 8688 I mm-camera: <MCT >< INFO> 4397: mct_pipeline_process_set: STREAM-OFF on stream 0x10003 stream type=3
11-30 16:39:36.372 688 8688 I mm-camera: <IFACE >< INFO> 1836: iface_streamoff_to_thread: iface_streamoff_to_thread: E, session id = 1, user stream id = 3
11-30 16:39:36.372 688 8694 I mm-camera: <IFACE >< INFO> 2467: iface_streamoff: E, session_id 1 mct_stream_id = 3
11-30 16:39:36.372 688 8694 I mm-camera: <IFACE >< INFO> 12260: iface_util_get_user_streams_by_bundle: skip streamoff! left 0 bundled streamoff, mct stream id 3total bundled stream num = 3
11-30 16:39:36.372 688 8694 I mm-camera: <IFACE >< INFO> 2717: iface_streamoff: all bundled stream streamoff, clear hal bundled mask
11-30 16:39:36.372 688 8694 I mm-camera: <IFACE >< INFO> 2722: iface_streamoff: X
11-30 16:39:36.372 688 8688 I mm-camera: <IFACE >< INFO> 1862: iface_streamoff_to_thread: iface_streamoff_to_thread: X, session id = 1, user stream id = 3
11-30 16:39:36.379 688 8688 I mm-camera: <IFACE >< INFO> 11003: iface_util_release_resource: camif_cnt= 1, rdi_cnt= 1, used mask 30000
11-30 16:39:36.379 688 8688 I mm-camera: <IFACE >< INFO> 11003: iface_util_release_resource: camif_cnt= 0, rdi_cnt= 1, used mask 20000
11-30 16:39:36.379 688 8688 I mm-camera: <IFACE >< INFO> 11003: iface_util_release_resource: camif_cnt= 0, rdi_cnt= 0, used mask 20000
11-30 16:39:36.379 688 8688 I mm-camera: <IFACE >< INFO> 4042: iface_destroy_hw: iface_destroy_hw: Destroy axi hw on VFE :1
11-30 16:39:36.380 688 8688 I mm-camera: <MCT >< INFO> 188: stop_sof_check_thread: Stopping SOF timeout thread session =1
11-30 16:39:36.380 688 8768 I mm-camera: <MCT >< INFO> 113: mct_bus_sof_thread_run: Closing SOF tracker thread
11-30 16:39:36.387 688 8688 I mm-camera: <MCT >< INFO> 4414: mct_pipeline_process_set: STREAM-OFF success for stream 0x10003, stream type=3
11-30 16:39:36.388 688 8688 I mm-camera: <MCT >< INFO> 4234: mct_pipeline_process_set: command=8000009
11-30 16:39:36.388 688 8688 I mm-camera: <MCT >< INFO> 4397: mct_pipeline_process_set: STREAM-OFF on stream 0x10001 stream type=7
11-30 16:39:36.388 688 8688 I mm-camera: <MCT >< INFO> 4414: mct_pipeline_process_set: STREAM-OFF success for stream 0x10001, stream type=7
11-30 16:39:36.389 688 1136 I ServiceManager: Waiting for service 'android.IRemosaicDaemon' on '/dev/vndbinder'...
11-30 16:39:36.395 688 8688 I mm-camera: <MCT >< INFO> 4234: mct_pipeline_process_set: command=800000b
11-30 16:39:36.395 688 8688 I mm-camera: <MCT >< INFO> 4479: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x10002 and stream type=1
11-30 16:39:36.396 688 8688 I mm-camera: <IFACE >< INFO> 700: iface_unreserve_sink_port: stream_id 2 mode 0
11-30 16:39:36.396 688 8688 I mm-camera: <ISP >< INFO> 941: isp_port_check_caps_unreserve: ide 10002 port 0xe9c053c0
11-30 16:39:36.396 688 8688 I mm-camera: <MCT >< INFO> 4502: mct_pipeline_process_set: Stream 0x10002 and stream type=1, successfully deleted
11-30 16:39:36.397 688 8688 I mm-camera: <MCT >< INFO> 4234: mct_pipeline_process_set: command=800000b
11-30 16:39:36.397 688 8688 I mm-camera: <MCT >< INFO> 4479: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x10003 and stream type=3
11-30 16:39:36.397 688 8688 I mm-camera: <IFACE >< INFO> 700: iface_unreserve_sink_port: stream_id 3 mode 0
11-30 16:39:36.397 688 8688 I mm-camera: <ISP >< INFO> 941: isp_port_check_caps_unreserve: ide 10003 port 0xe9c053c0
11-30 16:39:36.397 688 8688 I mm-camera: <MCT >< INFO> 4502: mct_pipeline_process_set: Stream 0x10003 and stream type=3, successfully deleted
11-30 16:39:36.398 688 688 I fastcv_lib_log: QCVAQ6_deinit
11-30 16:39:36.399 688 688 D fastcv_lib_log: Enter dlclose_stub completed
11-30 16:39:36.402 688 688 V qomx_image_core: OMX_FreeHandle:338]
11-30 16:39:36.402 688 688 D qomx_image_core: get_idx_from_handle:297] comp_idx 0 inst_idx 0
11-30 16:39:36.403 688 688 V qomx_image_core: OMX_FreeHandle:373] Success
11-30 16:39:36.403 688 688 I qomx_image_core: OMX_Deinit:125] Complete
11-30 16:39:36.414 688 8688 I mm-camera: <MCT >< INFO> 4234: mct_pipeline_process_set: command=800000b
11-30 16:39:36.414 688 8688 I mm-camera: <MCT >< INFO> 4479: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x10004 and stream type=11
11-30 16:39:36.415 688 8688 I mm-camera: <IFACE >< INFO> 700: iface_unreserve_sink_port: stream_id 4 mode 0
11-30 16:39:36.415 688 8688 I mm-camera: <ISP >< INFO> 941: isp_port_check_caps_unreserve: ide 10004 port 0xe9c053c0
11-30 16:39:36.415 688 8688 I mm-camera: <MCT >< INFO> 4502: mct_pipeline_process_set: Stream 0x10004 and stream type=11, successfully deleted
11-30 16:39:36.415 688 8688 I mm-camera: <MCT >< INFO> 4234: mct_pipeline_process_set: command=800000b
11-30 16:39:36.415 688 8688 I mm-camera: <MCT >< INFO> 4479: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x10001 and stream type=7
11-30 16:39:36.415 688 8688 I mm-camera: <MCT >< INFO> 4502: mct_pipeline_process_set: Stream 0x10001 and stream type=7, successfully deleted
11-30 16:39:36.416 688 8688 I mm-camera: <ISP >< INFO> 7159: isp_util_print_meta_stream_info: Deallocate resources
11-30 16:39:36.418 688 8688 I mm-camera: <ISP >< INFO> 7490: isp_util_handle_stream_info: streams_desc num_streams is 0
11-30 16:39:36.418 688 8688 I mm-camera: <IFACE >< INFO> 6384: iface_util_handle_meta_stream_info: iface_util_handle_meta_stream_info: ms_type 0 num streams 0
11-30 16:39:36.419 688 688 I QCamera : <HAL><INFO> closeCamera: 1333: [KPI Perf]: E PROFILE_CLOSE_CAMERA camera id 0
11-30 16:39:36.419 688 688 I Thermal-Lib: thermal_bandwidth_client_cancel_request: Removing all bw request for camera_bw
11-30 16:39:36.419 688 688 I Thermal-Lib: Thermal-Lib-Client: Unregisteration is successfull for handle:1
11-30 16:39:36.419 815 992 I ThermalEngine: Thermal-Server: removing client on fd 33
11-30 16:39:36.420 688 688 I Thermal-Lib: Thermal-Lib-Client: Unregisteration is successfull for handle:2
11-30 16:39:36.420 688 688 I mm-camera: <MCT >< INFO> 310: mct_controller_destroy: Initiating destroy sequence for session = 1
11-30 16:39:36.423 688 688 I mm-camera: <MCT >< INFO> 328: mct_controller_destroy: serv_thread closed
11-30 16:39:36.424 688 688 I mm-camera: <MCT >< INFO> 343: mct_controller_destroy: bus_handler thread closed
11-30 16:39:36.424 688 688 I mm-camera: <MCT >< INFO> 5350: mct_pipeline_stop_session: Initiating stop_session on session 1
11-30 16:39:36.424 688 688 I mm-camera: <MCT >< INFO> 4234: mct_pipeline_process_set: command=800000b
11-30 16:39:36.424 688 688 I mm-camera: <MCT >< INFO> 4479: mct_pipeline_process_set: Issuing DEL_STREAM on stream 0x1000f and stream type=10
11-30 16:39:36.424 688 688 I mm-camera: <IFACE >< INFO> 700: iface_unreserve_sink_port: stream_id f mode 0
11-30 16:39:36.424 688 688 I mm-camera: <ISP >< INFO> 941: isp_port_check_caps_unreserve: ide 1000f port 0xe9c053c0
11-30 16:39:36.489 688 1136 I ServiceManager: Waiting for service 'android.IRemosaicDaemon' on '/dev/vndbinder'...
11-30 16:39:38.909 688 1136 I chatty : uid=1047(cameraserver) CAM_imgTh identical 24 lines
11-30 16:39:39.011 688 1136 I ServiceManager: Waiting for service 'android.IRemosaicDaemon' on '/dev/vndbinder'...
11-30 16:39:39.051 685 685 D audio_hw_primary: out_standby: enter: stream (0xe9c0f800) usecase(1: low-latency-playback)
11-30 16:39:39.099 685 685 D audio_hw_primary: disable_audio_route: reset and update mixer path: low-latency-playback
11-30 16:39:39.102 685 685 D soundtrigger: audio_extn_sound_trigger_update_stream_status: uc_info->id 1 of type 0 for Event 2, with Raise=0
11-30 16:39:39.102 685 685 D msm8916_platform: platform_split_snd_device: snd_device(2) num devices(0) new_snd_devices(0)
11-30 16:39:39.102 685 685 I msm8916_platform: platform_get_custom_mtmx_params: no matching param with id 0 ip_ch 2 op_ch 1 uc_id 1 snd_dev 2
11-30 16:39:39.102 685 685 D hardware_info: hw_info_append_hw_type : device_name = speaker
11-30 16:39:39.102 685 685 D audio_hw_primary: disable_snd_device: snd_device(2: speaker)
11-30 16:39:39.102 685 685 D msm8916_platform: platform_split_snd_device: snd_device(2) num devices(0) new_snd_devices(0)
11-30 16:39:39.102 685 685 I soundtrigger: audio_extn_sound_trigger_update_device_status: device 0x2 of type 0 for Event 0, with Raise=0
11-30 16:39:39.102 685 685 D audio_hw_primary: out_standby: exit
11-30 16:39:39.112 688 1136 I ServiceManager: Waiting for service 'android.IRemosaicDaemon' on '/dev/vndbinder'...
11-30 16:39:39.213 688 1136 W ServiceManager: Service android.IRemosaicDaemon didn't start. Returning NULL
11-30 16:39:39.213 688 1136 D QCameraQuadraCfa: PresetConfig:480 custom_cal_data is NULL, to use temp_custom_data
11-30 16:39:39.213 688 1136 D QCameraQuadraCfa: PresetConfig:483 : remosaic_gainmap_gen
11-30 16:39:39.213 688 1136 D QCameraQuadraCfa: PresetConfig:486 : X
11-30 16:39:39.213 688 1136 D QCameraQuadraCfa: img_algo_preload:128 : X
11-30 16:39:39.214 688 1136 D QCameraQuadraCfa: img_algo_preload:126 : E
11-30 16:39:39.214 688 1136 D QCameraQuadraCfa: PresetConfig:462 : E
11-30 16:39:39.214 688 1136 D QCameraQuadraCfa: PresetConfig:466 : custom_cal_data 0
11-30 16:39:39.214 688 1136 D QCameraQuadraCfa: PresetConfig:467 : full_height bb8
11-30 16:39:39.214 688 1136 D QCameraQuadraCfa: PresetConfig:468 : full_width fa0
11-30 16:39:39.214 688 1136 D QCameraQuadraCfa: PresetConfig:469 : filter_arrangement 2
11-30 16:39:39.214 688 1136 D QCameraQuadraCfa: PresetConfig:470 : pedestal 40
11-30 16:39:39.214 688 1136 D QCameraQuadraCfa: PresetConfig:471 : remosaic_init
11-30 16:39:39.215 688 1136 I ServiceManager: Waiting for service 'android.IRemosaicDaemon' on '/dev/vndbinder'...
11-30 16:39:43.968 688 1136 I chatty : uid=1047(cameraserver) CAM_imgTh identical 47 lines
11-30 16:39:44.069 688 1136 I ServiceManager: Waiting for service 'android.IRemosaicDaemon' on '/dev/vndbinder'...
11-30 16:39:44.112 1596 1631 E LightsService: Light requested not available on this device. 2
11-30 16:39:44.170 688 1136 I ServiceManager: Waiting for service 'android.IRemosaicDaemon' on '/dev/vndbinder'...
11-30 16:39:44.271 688 1136 W ServiceManager: Service android.IRemosaicDaemon didn't start. Returning NULL
11-30 16:39:44.271 688 1136 D QCameraQuadraCfa: PresetConfig:480 custom_cal_data is NULL, to use temp_custom_data
11-30 16:39:44.271 688 1136 D QCameraQuadraCfa: PresetConfig:483 : remosaic_gainmap_gen
11-30 16:39:44.271 688 1136 D QCameraQuadraCfa: PresetConfig:486 : X
11-30 16:39:44.271 688 1136 D QCameraQuadraCfa: img_algo_preload:128 : X
11-30 16:39:44.283 688 688 I mm-camera: <MCT >< INFO> 4502: mct_pipeline_process_set: Stream 0x1000f and stream type=10, successfully deleted
11-30 16:39:44.284 688 8775 I mm-camera: <MCT >< INFO> 5084: mct_pipeline_stop_session_thread: Stop module name: sensor - E
11-30 16:39:44.284 688 8775 I mm-camera: <MCT >< INFO> 5092: mct_pipeline_stop_session_thread: Stop module name: sensor - X
11-30 16:39:44.286 688 8776 I mm-camera: <MCT >< INFO> 5084: mct_pipeline_stop_session_thread: Stop module name: iface - E
11-30 16:39:44.288 688 8776 I mm-camera: <MCT >< INFO> 5092: mct_pipeline_stop_session_thread: Stop module name: iface - X
11-30 16:39:44.299 688 8777 I mm-camera: <MCT >< INFO> 5084: mct_pipeline_stop_session_thread: Stop module name: isp - E
11-30 16:39:44.299 688 8777 I mm-camera: <ISP >< INFO> 510: isp_module_stop_session: session id 1
11-30 16:39:44.302 688 8778 I mm-camera: <MCT >< INFO> 5084: mct_pipeline_stop_session_thread: Stop module name: stats - E
11-30 16:39:44.304 688 8779 I mm-camera: <MCT >< INFO> 5084: mct_pipeline_stop_session_thread: Stop module name: pproc - E
11-30 16:39:44.306 688 8780 I mm-camera: <MCT >< INFO> 5084: mct_pipeline_stop_session_thread: Stop module name: imglib - E
11-30 16:39:44.306 688 688 I mm-camera: <MCT >< INFO> 5372: mct_pipeline_stop_session: Modules left: 5
11-30 16:39:44.306 688 688 I mm-camera: <MCT >< INFO> 5372: mct_pipeline_stop_session: Modules left: 4
11-30 16:39:44.307 688 8779 D QCameraQuadraCfa: img_algo_shutdown:147 : E
11-30 16:39:44.307 688 8779 D QCameraQuadraCfa: img_algo_shutdown:152 : X
11-30 16:39:44.307 688 8779 I mm-camera: <MCT >< INFO> 5092: mct_pipeline_stop_session_thread: Stop module name: pproc - X
11-30 16:39:44.307 688 688 I mm-camera: <MCT >< INFO> 5372: mct_pipeline_stop_session: Modules left: 3
11-30 16:39:44.308 688 8780 I mm-camera: <MCT >< INFO> 5092: mct_pipeline_stop_session_thread: Stop module name: imglib - X
11-30 16:39:44.308 688 688 I mm-camera: <MCT >< INFO> 5372: mct_pipeline_stop_session: Modules left: 2
11-30 16:39:44.309 688 8777 I mm-camera: <MCT >< INFO> 5092: mct_pipeline_stop_session_thread: Stop module name: isp - X
11-30 16:39:44.309 688 688 I mm-camera: <MCT >< INFO> 5372: mct_pipeline_stop_session: Modules left: 1
11-30 16:39:44.317 688 8778 I mm-camera: <MCT >< INFO> 5092: mct_pipeline_stop_session_thread: Stop module name: stats - X
11-30 16:39:44.317 688 688 I mm-camera: <MCT >< INFO> 5372: mct_pipeline_stop_session: Modules left: 0
11-30 16:39:44.317 688 688 I mm-camera: <MCT >< INFO> 5382: mct_pipeline_stop_session: Stopped session 1 successfully
11-30 16:39:44.317 688 688 I mm-camera: <MCT >< INFO> 5686: mct_pipeline_destroy: E: session:1
11-30 16:39:44.317 688 688 E mm-camera: <MCT ><ERROR> 179: stop_sof_check_thread: Returning as SOF timer thread not yet initialized
11-30 16:39:44.317 688 688 I mm-camera: <MCT >< INFO> 5764: mct_pipeline_destroy: X: Pipeline destroyed successfully session =1
11-30 16:39:44.317 688 688 I mm-camera: <MCT >< INFO> 422: mct_controller_destroy: X Successfully closed mct_controller session 1
11-30 16:39:44.326 880 1969 I CameraProviderManager: Camera device device@3.4/legacy/0 torch status is now AVAILABLE_OFF
11-30 16:39:44.326 880 1969 I CameraService: onTorchStatusChangedLocked: Torch status changed for cameraId=0, newStatus=1
11-30 16:39:44.327 688 688 I QCamera : <HAL><INFO> closeCamera: 1442: [KPI Perf]: X PROFILE_CLOSE_CAMERA camera id 0, rc: 0
11-30 16:39:44.329 688 688 I QCamera : <HAL><INFO> close_camera_device: 17335: [KPI Perf]: X
11-30 16:39:44.334 880 1969 I CameraLatencyHistogram: Stream 0 dequeueBuffer latency histogram (28) samples:
11-30 16:39:44.334 880 1969 I CameraLatencyHistogram: 5 10 15 20 25 30 35 40 45 inf (max ms)
11-30 16:39:44.334 880 1969 I CameraLatencyHistogram: 100.00 0.00 0.00 0.00 0.00 0.00 0.00 0.00 0.00 0.00 (%)
11-30 16:39:44.335 880 1969 I Camera3-Device: disconnectImpl: X
11-30 16:39:44.336 487 487 I hwservicemanager: getTransport: Cannot find entry vendor.lineage.camera.motor@1.0::ICameraMotor/default in either framework or device manifest.
11-30 16:39:44.343 880 1969 I CameraService: disconnect: Disconnected client for camera 0 for PID 5101
11-30 16:39:44.344 5101 5227 W dation.e.camer: Long monitor contention with owner AsyncTask #11 (7900) at void android.hardware.camera2.impl.CameraDeviceImpl.close()(CameraDeviceImpl.java:1191) waiters=0 in void android.hardware.camera2.impl.CameraDeviceImpl$CameraDeviceCallbacks.onCaptureStarted(android.hardware.camera2.impl.CaptureResultExtras, long) for 8.438s
11-30 16:39:44.365 880 1969 I CameraService: CameraService::connect call (PID -1 "foundation.e.camera", camera ID 1) for HAL version default and Camera API version 2
11-30 16:39:44.366 880 1969 I Camera2ClientBase: Camera 1: Opened. Client: foundation.e.camera (PID 5101, UID 10077)
11-30 16:39:44.366 880 1969 I CameraDeviceClient: CameraDeviceClient 1: Opened
11-30 16:39:44.370 688 688 I QCamera : <HAL><INFO> cameraDeviceOpen: 407: Open camera id 1 API version 768
11-30 16:39:44.370 685 685 D audio_hw_primary: adev_set_parameters: enter: cameraFacing=front
11-30 16:39:44.370 685 685 D audio_hw_extn: audio_extn_set_anc_parameters: anc_enabled:0
11-30 16:39:44.370 685 685 D audio_hw_spkr_prot: audio_extn_fbsp_set_parameters: Speaker protection disabled
11-30 16:39:44.375 688 688 I QCamera : <HAL><INFO> QCamera3HardwareInterface: 662: AV timer enabled: 0
11-30 16:39:44.375 688 688 I QCamera : <HAL><INFO> QCamera3HardwareInterface: 698: Sensor support Quadra CFA mode in sensor cqfa 0
11-30 16:39:44.375 688 688 E QCamera : <HAL><ERROR> QCamera3HardwareInterface: 715: arc_vs [CameraId:1] focal_length = 4.05, hor_view_angle = 60.00
11-30 16:39:44.375 688 688 E QCamera : <HAL><ERROR> QCamera3HardwareInterface: 722: arc_vs [CameraId:1] active_array_size = [0, 0, 4608, 3456]
11-30 16:39:44.376 2289 2289 D QtiCarrierConfigHelper: WARNING, no carrier configs on phone Id: 0
11-30 16:39:44.381 688 688 I QCamera : <HAL><INFO> openCamera: 1121: [KPI Perf]: E PROFILE_OPEN_CAMERA camera id 1
11-30 16:39:44.382 688 688 D QCamera : Debug log file is not enabled
11-30 16:39:44.382 688 688 I mm-camera: <MCT >< INFO> 118: mct_controller_new: Creating new mct_controller with session-id 2
11-30 16:39:44.383 688 8787 I mm-camera: <MCT >< INFO> 5272: mct_pipeline_start_session_thread: E sensor
11-30 16:39:44.383 688 8787 I mm-camera: <MCT >< INFO> 5279: mct_pipeline_start_session_thread: Calling start_session on Module sensor
11-30 16:39:44.383 688 8788 I mm-camera: <MCT >< INFO> 5272: mct_pipeline_start_session_thread: E iface
11-30 16:39:44.383 688 8788 I mm-camera: <MCT >< INFO> 5279: mct_pipeline_start_session_thread: Calling start_session on Module iface
11-30 16:39:44.384 688 8790 I mm-camera: <MCT >< INFO> 5272: mct_pipeline_start_session_thread: E isp
11-30 16:39:44.384 688 8788 I mm-camera: <MCT >< INFO> 5282: mct_pipeline_start_session_thread: Module iface start_session rc = 1
11-30 16:39:44.384 688 8790 I mm-camera: <MCT >< INFO> 5279: mct_pipeline_start_session_thread: Calling start_session on Module isp
11-30 16:39:44.384 688 8790 I mm-camera: <ISP >< INFO> 214: isp_module_start_session: session id 2
11-30 16:39:44.384 688 8788 I mm-camera: <MCT >< INFO> 5290: mct_pipeline_start_session_thread: started_num = 1, success = 1
11-30 16:39:44.384 688 8788 I mm-camera: <MCT >< INFO> 5297: mct_pipeline_start_session_thread: X iface
11-30 16:39:44.384 688 8792 I mm-camera: <MCT >< INFO> 5272: mct_pipeline_start_session_thread: E stats
11-30 16:39:44.384 688 8792 I mm-camera: <MCT >< INFO> 5279: mct_pipeline_start_session_thread: Calling start_session on Module stats
11-30 16:39:44.385 688 8793 I mm-camera: <MCT >< INFO> 5272: mct_pipeline_start_session_thread: E pproc
11-30 16:39:44.385 688 8793 I mm-camera: <MCT >< INFO> 5279: mct_pipeline_start_session_thread: Calling start_session on Module pproc
11-30 16:39:44.386 688 8795 I mm-camera: <MCT >< INFO> 5272: mct_pipeline_start_session_thread: E imglib
11-30 16:39:44.387 688 8795 I mm-camera: <MCT >< INFO> 5279: mct_pipeline_start_session_thread: Calling start_session on Module imglib
11-30 16:39:44.387 688 8795 I mm-camera: <MCT >< INFO> 5282: mct_pipeline_start_session_thread: Module imglib start_session rc = 1
11-30 16:39:44.387 688 8795 I mm-camera: <MCT >< INFO> 5290: mct_pipeline_start_session_thread: started_num = 2, success = 2
11-30 16:39:44.387 688 8795 I mm-camera: <MCT >< INFO> 5297: mct_pipeline_start_session_thread: X imglib
11-30 16:39:44.387 688 8790 I mm-camera: <MCT >< INFO> 5282: mct_pipeline_start_session_thread: Module isp start_session rc = 1
11-30 16:39:44.387 688 8790 I mm-camera: <MCT >< INFO> 5290: mct_pipeline_start_session_thread: started_num = 3, success = 3
11-30 16:39:44.387 688 8790 I mm-camera: <MCT >< INFO> 5297: mct_pipeline_start_session_thread: X isp
11-30 16:39:44.388 688 8792 I mm-camera: <MCT >< INFO> 5282: mct_pipeline_start_session_thread: Module stats start_session rc = 1
11-30 16:39:44.388 688 8792 I mm-camera: <MCT >< INFO> 5290: mct_pipeline_start_session_thread: started_num = 4, success = 4
11-30 16:39:44.388 688 8792 I mm-camera: <MCT >< INFO> 5297: mct_pipeline_start_session_thread: X stats
11-30 16:39:44.390 688 8793 I mm-camera: <MCT >< INFO> 5282: mct_pipeline_start_session_thread: Module pproc start_session rc = 1
11-30 16:39:44.390 688 8793 I mm-camera: <MCT >< INFO> 5290: mct_pipeline_start_session_thread: started_num = 5, success = 5
11-30 16:39:44.390 688 8793 I mm-camera: <MCT >< INFO> 5297: mct_pipeline_start_session_thread: X pproc
11-30 16:39:44.401 2289 2289 D QtiCarrierConfigHelper: WARNING, no carrier configs on phone Id: 0
11-30 16:39:44.631 688 8787 E mm-camera: <SENSOR><ERROR> 247: module_sensor_offload_init_config: func_tbl for submodule 4 is NULL
11-30 16:39:44.631 688 8787 E mm-camera: <SENSOR><ERROR> 249: module_sensor_offload_init_config: failed to get led off frame skip time
11-30 16:39:44.631 688 8787 I mm-camera: <MCT >< INFO> 5282: mct_pipeline_start_session_thread: Module sensor start_session rc = 1
11-30 16:39:44.631 688 8787 I mm-camera: <MCT >< INFO> 5290: mct_pipeline_start_session_thread: started_num = 6, success = 6
11-30 16:39:44.631 688 8787 I mm-camera: <MCT >< INFO> 5297: mct_pipeline_start_session_thread: X sensor
11-30 16:39:44.646 688 688 I mm-camera: <MCT >< INFO> 5193: mct_pipeline_start_stream_internal: Adding session stream streamid= 0xf for session=2
11-30 16:39:44.646 688 688 I mm-camera: <MCT >< INFO> 5241: mct_pipeline_start_stream_internal: Linking session stream for session 2
11-30 16:39:44.646 688 688 I mm-camera: <MCT >< INFO> 526: mct_stream_start_link: Start linking Session-stream 0x2000f
11-30 16:39:44.646 688 688 I mm-camera: <ISP >< INFO> 851: isp_port_check_caps_reserve: port 0xe9c053c0 ide 2000f type 10 dim 0 0
11-30 16:39:44.646 688 688 I mm-camera: <PPROC >< INFO> 449: pproc_port_add_modules_to_stream: in identity 2000f stream 10 int_link = 0xe9c16b40
11-30 16:39:44.646 688 688 I mm-camera: <PPROC >< INFO> 461: pproc_port_add_modules_to_stream: :LINK linking mods tmod and paaf for identity 2000f
11-30 16:39:44.647 688 688 I mm-camera: <PPROC >< INFO> 461: pproc_port_add_modules_to_stream: :LINK linking mods paaf and ppeiscore for identity 2000f
11-30 16:39:44.648 688 688 I mm-camera: <PPROC >< INFO> 461: pproc_port_add_modules_to_stream: :LINK linking mods ppeiscore and c2d for identity 2000f
11-30 16:39:44.648 688 688 I mm-camera: <C2D >< INFO> 1557: c2d_module_notify_add_stream: width 0, height 0, stride 0, scanline 0, is_type 0
11-30 16:39:44.648 688 688 I mm-camera: <PPROC >< INFO> 461: pproc_port_add_modules_to_stream: :LINK linking mods c2d and cpp for identity 2000f
11-30 16:39:44.648 688 688 I mm-camera: <CPP >< INFO> 2536: cpp_module_notify_add_stream: :stream 10, width 0, height 0, stride 0,scanline 0, framelen 0
11-30 16:39:44.648 688 688 I mm-camera: <CPP >< INFO> 2693: cpp_module_notify_add_stream: : stream 10, fmt 1, asf_mode 0, sharpness_level 0.000000,asf mask 0, denoise 0, denoise_mask 0, dsdn mask 0,dsdn enable 0, tnr mask 0, tnr enable 0, ds_mask 0, luma dsdn 0
11-30 16:39:44.649 688 688 I mm-camera: <PPROC >< INFO> 461: pproc_port_add_modules_to_stream: :LINK linking mods cpp and depth_map for identity 2000f
11-30 16:39:44.650 688 688 I mm-camera: <PPROC >< INFO> 461: pproc_port_add_modules_to_stream: :LINK linking mods depth_map and ezt for identity 2000f
11-30 16:39:44.650 688 688 I mm-camera: <PPROC >< INFO> 461: pproc_port_add_modules_to_stream: :LINK linking mods ezt and quadracfa for identity 2000f
11-30 16:39:44.651 688 688 E mm-camera: <STATS ><ERROR> 3027: stats_port_check_caps_reserve: Invalid Port capability type!
11-30 16:39:44.651 688 688 I chatty : uid=1047(cameraserver) provider@2.4-se identical 3 lines
11-30 16:39:44.651 688 688 E mm-camera: <STATS ><ERROR> 3027: stats_port_check_caps_reserve: Invalid Port capability type!
11-30 16:39:44.655 688 688 I mm-camera: <MCT >< INFO> 5250: mct_pipeline_start_stream_internal: Session stream linked successfully session 2
11-30 16:39:44.659 688 688 I Thermal-Lib: Thermal-Lib-Client: Registration successful for camera with handle:1
11-30 16:39:44.659 688 688 I Thermal-Lib: Thermal-Lib-Client: Registration successful for camcorder with handle:2
11-30 16:39:44.659 688 688 I QCamera : <HAL><INFO> openCamera: 1149: [KPI Perf]: X PROFILE_OPEN_CAMERA camera id 1, rc: 0
11-30 16:39:44.659 688 688 I QCamera : <HAL><INFO> initialize: 1463: E :mCameraId = 1 mState = 1
11-30 16:39:44.659 815 992 I ThermalEngine: Thermal-Server: Adding thermal event listener on fd 33
11-30 16:39:44.659 688 8811 I Thermal-Lib: Thermal-Lib-Client: Client received msg camera 0
11-30 16:39:44.659 688 8811 I Thermal-Lib: Thermal-Lib-Client: Client received msg camcorder 0
11-30 16:39:44.660 688 688 I QCamera : <HAL><INFO> initialize: 1489: X
11-30 16:39:44.660 688 688 E libc : Access denied finding property "ro.camera.req.fmq.size"
11-30 16:39:44.660 688 688 E libc : Access denied finding property "ro.camera.res.fmq.size"
11-30 16:39:44.663 487 487 I hwservicemanager: getTransport: Cannot find entry vendor.lineage.camera.motor@1.0::ICameraMotor/default in either framework or device manifest.
11-30 16:39:44.665 688 1360 E CamComm1.0-MD: Mismatched tag type when updating entry enable (-2146762752) of type byte; got type int32 data instead
11-30 16:39:44.665 688 1360 E CamComm1.0-MD: Mismatched tag type when updating entry is_main (-2146762751) of type byte; got type int32 data instead
11-30 16:39:44.679 5101 8816 D CCodec : allocate(c2.android.vorbis.decoder)
11-30 16:39:44.679 1001 2219 V C2Store : in init
11-30 16:39:44.680 1001 2219 V C2Store : loading dll
11-30 16:39:44.683 5101 8816 I CCodec : Created component [c2.android.vorbis.decoder]
11-30 16:39:44.683 5101 8816 D CCodecConfig: read media type: audio/vorbis
11-30 16:39:44.649 688 688 W provider@2.4-se: type=1400 audit(0.0:99): avc: denied { read } for name="u:object_r:default_prop:s0" dev="tmpfs" ino=17551 scontext=u:r:hal_camera_default:s0 tcontext=u:object_r:default_prop:s0 tclass=file permissive=0
11-30 16:39:44.649 688 688 W provider@2.4-se: type=1400 audit(0.0:100): avc: denied { read } for name="u:object_r:default_prop:s0" dev="tmpfs" ino=17551 scontext=u:r:hal_camera_default:s0 tcontext=u:object_r:default_prop:s0 tclass=file permissive=0
11-30 16:39:44.686 5101 8816 D ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.max-count.values
11-30 16:39:44.687 5101 8816 D ReflectedParamUpdater: extent() != 1 for single value type: output.subscribed-indices.values
11-30 16:39:44.687 5101 8816 D ReflectedParamUpdater: extent() != 1 for single value type: input.buffers.allocator-ids.values
11-30 16:39:44.687 5101 8816 D ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.allocator-ids.values
11-30 16:39:44.688 5101 8816 D ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.allocator-ids.values
11-30 16:39:44.688 5101 8816 D ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.pool-ids.values
11-30 16:39:44.688 5101 8816 D ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.pool-ids.values
11-30 16:39:44.689 5101 8816 I CCodecConfig: query failed after returning 7 values (BAD_INDEX)
11-30 16:39:44.690 5101 8816 D CCodecConfig: c2 config diff is Dict {
11-30 16:39:44.690 5101 8816 D CCodecConfig: c2::u32 coded.bitrate.value = 64000
11-30 16:39:44.690 5101 8816 D CCodecConfig: c2::u32 input.buffers.max-size.value = 32768
11-30 16:39:44.690 5101 8816 D CCodecConfig: c2::u32 input.delay.value = 0
11-30 16:39:44.690 5101 8816 D CCodecConfig: string input.media-type.value = "audio/vorbis"
11-30 16:39:44.690 5101 8816 D CCodecConfig: string output.media-type.value = "audio/raw"
11-30 16:39:44.690 5101 8816 D CCodecConfig: c2::u32 raw.channel-count.value = 1
11-30 16:39:44.690 5101 8816 D CCodecConfig: c2::u32 raw.sample-rate.value = 48000
11-30 16:39:44.690 5101 8816 D CCodecConfig: }
11-30 16:39:44.690 5101 8816 D CCodecConfig: no c2 equivalents for durationUs
11-30 16:39:44.690 5101 8816 D CCodecConfig: no c2 equivalents for csd-1
11-30 16:39:44.690 5101 8816 D CCodecConfig: no c2 equivalents for track-id
11-30 16:39:44.690 5101 8816 D CCodecConfig: no c2 equivalents for channel-mask
11-30 16:39:44.691 5101 8816 D CCodecConfig: c2 config diff is c2::u32 raw.channel-count.value = 2
11-30 16:39:44.691 5101 8816 D CCodecConfig: c2::u32 raw.sample-rate.value = 44100
11-30 16:39:44.692 5101 8816 W Codec2Client: query -- param skipped: index = 1107298332.
11-30 16:39:44.692 5101 8816 D CCodec : setup formats input: AMessage(what = 0x00000000) = {
11-30 16:39:44.692 5101 8816 D CCodec : int32_t channel-count = 2
11-30 16:39:44.692 5101 8816 D CCodec : int32_t max-input-size = 32768
11-30 16:39:44.692 5101 8816 D CCodec : string mime = "audio/vorbis"
11-30 16:39:44.692 5101 8816 D CCodec : int32_t sample-rate = 44100
11-30 16:39:44.692 5101 8816 D CCodec : } and output: AMessage(what = 0x00000000) = {
11-30 16:39:44.692 5101 8816 D CCodec : int32_t channel-count = 2
11-30 16:39:44.692 5101 8816 D CCodec : string mime = "audio/raw"
11-30 16:39:44.692 5101 8816 D CCodec : int32_t sample-rate = 44100
11-30 16:39:44.692 5101 8816 D CCodec : int32_t channel-mask = 3
11-30 16:39:44.692 5101 8816 D CCodec : }
11-30 16:39:44.693 5101 8816 W Codec2Client: query -- param skipped: index = 1342179345.
11-30 16:39:44.693 5101 8816 W Codec2Client: query -- param skipped: index = 2415921170.
