14:28:47.865 I awb_engine.cc:215: Load lut file /apex/com.google.pixel.camera.hal/etc/camera/ghawb_para_lut_rear_wide.bin 14:28:47.865 I slc_driver.cc:49: SLC setup: pid = 5 : Gdc-Scaler : 5 ways (buf fd 148) 14:28:47.865 I stream_interface_controller.cc:522: Compression(Lossy SBWC High Compression) enabled for ITSC -> TNR for camera RearWide with instance id 0 14:28:47.866 I tuning_node.cc:535: The Tuning configuration deadline = {.config_deadline_frame_offset = -1, sof_to_config_deadline = -2.82ms} 14:28:47.866 I stream_interface_controller.cc:522: Compression(Lossy SBWC High Compression) enabled for ITSC -> TNR for camera Rear with instance id 0 14:28:47.866 I common_scaler_driver.cc:256: whi-scsc: AcquireProcessingContext(): 5 outputs, 4160x3120, fps 30 (VOTF) 14:28:47.866 I generic_scaler_controller.cc:270: OUT_OF_RANGE: Requested 5 outputs, but whi-scsc only supports up to 2 outputs. 14:28:47.866 I broker_scaler_controller.cc:85: Note: failed to acquire context for RearWide from whi-scsc because OUT_OF_RANGE: Requested 5 outputs, but whi-scsc only supports up to 2 outputs. 14:28:47.866 I common_scaler_driver.cc:256: whi-mcsc: AcquireProcessingContext(): 5 outputs, 4160x3120, fps 30 (VOTF) 14:28:47.866 I slc_driver.cc:49: SLC setup: pid = 3 : GTNR iVOTF : 7 ways (buf fd 136) 14:28:47.866 I stream_interface_controller.cc:522: Compression(Lossy SBWC High Compression) enabled for GDC0 -> MCSC for camera Rear with instance id 0 14:28:47.866 I stream_interface_controller.cc:522: Compression(Lossy SBWC High Compression) enabled for TNR -> GDC0 for camera Rear with instance id 0 14:28:47.866 I slc_driver.cc:49: SLC setup: pid = 8 : GTNR Prev : 4 ways (buf fd 166) 14:28:47.866 I stream_interface_controller.cc:522: Compression(Lossy SBWC High Compression) enabled for ITSC -> TNR for camera RearVirtual with instance id 0 14:28:47.867 W ae_engine.cc:152: AE-related data fail to be registered in data manager. 14:28:47.867 W ae_engine.cc:152: AE-related data fail to be registered in data manager. 14:28:47.867 I ae_engine.cc:2778: GABC fatp_enable_ = 0 14:28:47.867 I ae_engine.cc:2778: GABC fatp_enable_ = 0 14:28:47.867 I ae_engine.cc:170: [0, 3] AeEngine_0_3 initialized 14:28:47.867 I ae_engine.cc:170: [0, 2] AeEngine_0_2 initialized 14:28:47.868 W ae_engine.cc:152: AE-related data fail to be registered in data manager. 14:28:47.868 I ae_engine.cc:2778: GABC fatp_enable_ = 0 14:28:47.868 I ae_engine.cc:170: [0, 4] AeEngine_0_4 initialized 14:28:47.868 I face_detection_engine_face_ssd.cc:226: FaceDetectionEngineFaceSsd::Initialize::CreateDetector takes 10.030233ms 14:28:47.868 I face_detection_engine_face_ssd.cc:226: FaceDetectionEngineFaceSsd::Initialize::CreateDetector takes 10.149902ms 14:28:47.868 I stream_interface_controller.cc:522: Compression(Lossy SBWC High Compression) enabled for GDC0 -> MCSC for camera RearVirtual with instance id 0 14:28:47.868 I stream_interface_controller.cc:522: Compression(Lossy SBWC High Compression) enabled for TNR -> GDC0 for camera RearVirtual with instance id 0 14:28:47.868 I stream_interface_controller.cc:522: Compression(Lossy SBWC High Compression) enabled for GDC0 -> MCSC for camera RearWide with instance id 0 14:28:47.868 I stream_interface_controller.cc:522: Compression(Lossy SBWC High Compression) enabled for TNR -> GDC0 for camera RearWide with instance id 0 14:28:47.869 I face_detection_engine_face_ssd.cc:226: FaceDetectionEngineFaceSsd::Initialize::CreateDetector takes 10.566935ms 14:28:47.869 I async_face_detection_engine.cc:215: Face detection worker thread enabled 14:28:47.869 W stream_interface_controller.cc:459: Note: processing_context->consumer is being re-assigned from 8 to 13 14:28:47.869 I tuning_node.cc:535: The Tuning configuration deadline = {.config_deadline_frame_offset = -1, sof_to_config_deadline = -2.82ms} 14:28:47.869 I stream_interface_controller.cc:522: Compression(Lossy SBWC High Compression) enabled for GDC0 -> SCSC for camera Rear with instance id 0 14:28:47.869 I common_scaler_driver.cc:256: whi-scsc: AcquireProcessingContext(): 5 outputs, 4160x3120, fps 30 (VOTF) 14:28:47.869 I generic_scaler_controller.cc:270: OUT_OF_RANGE: Requested 5 outputs, but whi-scsc only supports up to 2 outputs. 14:28:47.869 I broker_scaler_controller.cc:85: Note: failed to acquire context for Rear from whi-scsc because OUT_OF_RANGE: Requested 5 outputs, but whi-scsc only supports up to 2 outputs. 14:28:47.869 W stream_interface_controller.cc:459: Note: processing_context->consumer is being re-assigned from 13 to 8 14:28:47.869 I stream_interface_controller.cc:522: Compression(Lossy SBWC High Compression) enabled for GDC0 -> MCSC for camera Rear with instance id 0 14:28:47.869 I common_scaler_driver.cc:256: whi-mcsc: AcquireProcessingContext(): 5 outputs, 4160x3120, fps 30 (VOTF) 14:28:47.869 I tuning_node.cc:535: The Tuning configuration deadline = {.config_deadline_frame_offset = -1, sof_to_config_deadline = -2.82ms} 14:28:47.869 I common_scaler_driver.cc:690: whi-mcsc: entering processing thread. 14:28:47.869 W stream_interface_controller.cc:459: Note: processing_context->consumer is being re-assigned from 8 to 13 14:28:47.869 I stream_interface_controller.cc:522: Compression(Lossy SBWC High Compression) enabled for GDC0 -> SCSC for camera RearVirtual with instance id 0 14:28:47.869 I common_scaler_driver.cc:256: whi-scsc: AcquireProcessingContext(): 5 outputs, 4160x3120, fps 30 (VOTF) 14:28:47.869 I generic_scaler_controller.cc:270: OUT_OF_RANGE: Requested 5 outputs, but whi-scsc only supports up to 2 outputs. 14:28:47.869 I broker_scaler_controller.cc:85: Note: failed to acquire context for RearVirtual from whi-scsc because OUT_OF_RANGE: Requested 5 outputs, but whi-scsc only supports up to 2 outputs. 14:28:47.869 W stream_interface_controller.cc:459: Note: processing_context->consumer is being re-assigned from 13 to 8 14:28:47.869 I stream_interface_controller.cc:522: Compression(Lossy SBWC High Compression) enabled for GDC0 -> MCSC for camera RearVirtual with instance id 0 14:28:47.869 I common_scaler_driver.cc:256: whi-mcsc: AcquireProcessingContext(): 5 outputs, 4160x3120, fps 30 (VOTF) 14:28:47.870 I async_face_detection_engine.cc:215: Face detection worker thread enabled 14:28:47.870 I awb_engine.cc:237: Camera 4 Initial decision [2.0356984 1 1.6469268] CCT [5594] CCM [1.9546461 -0.6406652 -0.31398097, -0.16255367 1.3268516 -0.16429791, 0.058709275 -0.8310105 1.7723012, 0 0 0] 14:28:47.871 I async_face_detection_engine.cc:215: Face detection worker thread enabled 14:28:47.871 I awb_engine.cc:237: Camera 3 Initial decision [2.04786 1 1.75743] CCT [5000] CCM [1.46208 -0.40016 -0.06192, -0.14466 1.34698 -0.20232, 0.04367 -0.7023 1.65863, 0 0 0] 14:28:47.871 I awb_engine.cc:237: Camera 2 Initial decision [1.9248253 1 1.7188144] CCT [5178] CCM [1.6745 -0.6068 -0.0677, -0.18935 1.3607 -0.17135, 0.0628 -0.8706 1.8078, 0 0 0] 14:28:47.872 I ois_driver.cc:351: ois-rear-normal hall temperature compensation is enabled 14:28:47.878 I generic_actuator_controller.cc:431: AF LTC: act-rear-normal is enabled with firmware LTC 14:28:47.878 I external_auto_focus_controller_proxy.cc:885: ExternalAutoFocusControllerProxy [3A Coordinator]: Creating context for camera 4; long query warning threshold 250us; long set focus warning threshold 500us 14:28:47.878 I external_auto_focus_controller_proxy.cc:885: ExternalAutoFocusControllerProxy [3A Coordinator]: Creating context for camera 2; long query warning threshold 250us; long set focus warning threshold 500us 14:28:47.878 I PhoneUpdate.cc:1597: LC898129dp update SSC parameters 14:28:47.882 I ois_driver.cc:700: ois-rear-normal OIS operation mode changed to: Still 14:28:47.882 I ois_driver.cc:367: ois-rear-normal Event thread is enabled 14:28:47.883 I ois_driver.cc:1039: ois-rear-normal The device uses system IMU 14:28:47.884 I I0000 00:00:1695986927.874114 13989 segmenter_node.cc:375] 14:28:47.887 I auto_scene_detection_engine.cc:128: [9, 2] AutoSceneDetectionEngine_9_2 initialized 14:28:47.888 I I0000 00:00:1695986927.888195 14020 node_export.cc:194] CatNodeCreate(LlvNode) took 11.7143 ms 14:28:47.889 I ois_driver.cc:1196: ois-rear-normal Monitor aoc status is enabled 14:28:47.889 I auto_scene_detection_engine.cc:128: [9, 4] AutoSceneDetectionEngine_9_4 initialized 14:28:47.891 I I0000 00:00:1695986927.891125 14082 llv_node.cc:1491] EnableGLlvOpenCL 14:28:47.891 I I0000 00:00:1695986927.891189 14082 llv_gpu.cc:693] GLlvInitCl 14:28:47.891 I I0000 00:00:1695986927.891579 1139 node_export.cc:194] CatNodeCreate(AlscNode) took 16.79 ms 14:28:47.891 I I0000 00:00:1695986927.891593 1141 node_export.cc:194] CatNodeCreate(AlscNode) took 17.6545 ms 14:28:47.891 I I0000 00:00:1695986927.891578 1140 node_export.cc:194] CatNodeCreate(AlscNode) took 16.9669 ms 14:28:47.892 I I0000 00:00:1695986927.892308 13989 node_export.cc:194] CatNodeCreate(SegmenterNode) took 7.73592 ms 14:28:47.892 I I0000 00:00:1695986927.892363 13991 segmenter_node.cc:375] 14:28:47.892 I I0000 00:00:1695986927.892479 14085 xeno_dsp_segmenter.cc:585] Creating DSP segmenter. 14:28:47.892 I I0000 00:00:1695986927.892491 13991 node_export.cc:194] CatNodeCreate(SegmenterNode) took 0.0354 ms 14:28:47.892 I I0000 00:00:1695986927.892538 13996 segmenter_node.cc:375] 14:28:47.892 I I0000 00:00:1695986927.892608 13996 node_export.cc:194] CatNodeCreate(SegmenterNode) took 0.027832 ms 14:28:47.893 I auto_scene_detection_engine.cc:128: [9, 3] AutoSceneDetectionEngine_9_3 initialized 14:28:47.900 W Reducing the number of considered missed Gc histogram windows from 1012 to 100 14:28:47.903 I I0000 00:00:1695986927.903407 13995 node_export.cc:194] CatNodeCreate(HdrnetV2Node) took 27.3475 ms 14:28:47.903 I I0000 00:00:1695986927.903420 14052 node_export.cc:194] CatNodeCreate(RectifaceNode) took 7.16956 ms 14:28:47.903 I I0000 00:00:1695986927.903825 1142 node_export.cc:194] CatNodeCreate(HdrnetV2Node) took 29.0286 ms 14:28:47.903 I I0000 00:00:1695986927.903898 14030 node_export.cc:194] CatNodeCreate(HdrnetV2Node) took 27.1933 ms 14:28:47.910 D Compat change id reported: 160794467; UID 10128; state: ENABLED 14:28:47.912 I I0000 00:00:1695986927.892834 14048 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.912 I I0000 00:00:1695986927.892790 14046 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.912 I I0000 00:00:1695986927.912246 14048 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Oksoko remosaic ... 14:28:47.912 I I0000 00:00:1695986927.912282 14046 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Oksoko 4sum ... 14:28:47.912 I I0000 00:00:1695986927.892786 14049 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.912 I I0000 00:00:1695986927.912424 14049 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Nagual 4sum ... 14:28:47.913 I I0000 00:00:1695986927.912281 14048 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.913 I I0000 00:00:1695986927.912309 14046 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.913 I I0000 00:00:1695986927.912482 14049 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.914 I whi_autocal_controller.cc:384: WhiAutocalController: acquire context for camera 4 14:28:47.914 I whi_autocal_controller.cc:384: WhiAutocalController: acquire context for camera 3 14:28:47.914 I I0000 00:00:1695986927.914396 14052 node_export.cc:194] CatNodeCreate(EisNode) took 5.70557 ms 14:28:47.916 D gyroscope: Enter Batch. period = 5000000, latency = 0. 14:28:47.916 D IMU temperature: Enter Batch. period = 533334000, latency = 0. 14:28:47.917 D gyroscope: Enter Activate. Enable = 1. 14:28:47.917 D A3:MSG: usf_sensor.cc, 1368: USF: Received start sampling request for LSM6DSV Gyroscope. 14:28:47.918 I binder_motion_sensor_reader.cc:66: Enabled BinderMotionSensorReader, type: gyroscope 14:28:47.919 I I0000 00:00:1695986927.919840 14082 llv_node.cc:1429] Create Llv with default parameters. 14:28:47.921 I I0000 00:00:1695986927.921823 14082 llv_manager.cc:189] Use default parameters. 14:28:47.921 I I0000 00:00:1695986927.921853 14082 llv_manager.cc:192] LLV DIMENSION: 1920x1080 14:28:47.921 I I0000 00:00:1695986927.921869 14082 llv_manager.cc:289] LLV=OFF 14:28:47.921 I I0000 00:00:1695986927.921875 14082 llv_manager.cc:290] EAF=OFF 14:28:47.921 I I0000 00:00:1695986927.921881 14082 llv_manager.cc:291] EAF_LITE=ON 14:28:47.924 D Compat change id reported: 194532703; UID 10128; state: ENABLED 14:28:47.925 I I0000 00:00:1695986927.925594 14082 llv_manager.cc:335] Use separate kernels for Laplace Processing. 14:28:47.925 D Compat change id reported: 194532703; UID 10128; state: ENABLED 14:28:47.929 W Job didn't exist in JobStore: c6ccdc1 #u0a128/10631 com.google.android.apps.gcs/androidx.work.impl.background.systemjob.SystemJobService 14:28:47.931 D Compat change id reported: 168419799; UID 1000; state: DISABLED 14:28:47.932 D Compat change id reported: 273564678; UID 1000; state: DISABLED 14:28:47.937 I I0000 00:00:1695986927.937215 14082 noise_model.cc:479] Profile resolution 120 x 68 14:28:47.943 I I0000 00:00:1695986927.943013 14052 eis_node.cc:1410] Contizoom calibration file: /data/vendor/camera/auto_calibration.combined.proto 14:28:47.945 I I0000 00:00:1695986927.945132 14052 cpu_feature_extractor.cc:115] Extracting FREAK descriptors. 14:28:47.945 I I0000 00:00:1695986927.945345 14052 brute_force_feature_matching.cc:49] Number of bins each image dimension is divided into: 5 14:28:47.947 I I0000 00:00:1695986927.945371 14052 continuous_zoom_factory.cc:586] Check enable_image_based_homography false 14:28:47.948 I I0000 00:00:1695986927.948420 14052 eis_node.cc:1089] eis_align_mode_ = 0 14:28:47.949 I I0000 00:00:1695986927.948975 14085 wakelock_manager.cc:90] GXP Interop Adapter acquired the TPU firmware wakelock. 14:28:47.951 I Replacing 4 node(s) with delegate (TfLiteXNNPackDelegate) node, yielding 5 partitions. 14:28:47.951 I Replacing 4 node(s) with delegate (TfLiteXNNPackDelegate) node, yielding 5 partitions. 14:28:47.951 I Replacing 4 node(s) with delegate (TfLiteXNNPackDelegate) node, yielding 5 partitions. 14:28:47.952 I I0000 00:00:1695986927.952411 14085 segmenter_node.cc:287] 14:28:47.955 W awb_engine.cc:266: Failed to register data types in data manager. 14:28:47.955 I awb_engine.cc:269: [2, 4] AwbEngine_2_4 initialized 14:28:47.955 W awb_engine.cc:266: Failed to register data types in data manager. 14:28:47.955 W awb_engine.cc:266: Failed to register data types in data manager. 14:28:47.955 I awb_engine.cc:269: [2, 3] AwbEngine_2_3 initialized 14:28:47.955 I awb_engine.cc:269: [2, 2] AwbEngine_2_2 initialized 14:28:47.955 I coordinator.cc:725: AF data manager has already been registered. 14:28:47.955 I coordinator.cc:725: AF data manager has already been registered. 14:28:47.956 I I0000 00:00:1695986927.956636 14046 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.956 I I0000 00:00:1695986927.956665 14049 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.956 I I0000 00:00:1695986927.956699 14049 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Nagual 4sum ... 14:28:47.956 I I0000 00:00:1695986927.956696 14046 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Oksoko 4sum ... 14:28:47.956 I I0000 00:00:1695986927.956716 14049 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.956 I I0000 00:00:1695986927.956727 14046 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.956 I I0000 00:00:1695986927.956935 14048 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.956 I I0000 00:00:1695986927.956959 14048 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Oksoko remosaic ... 14:28:47.956 I I0000 00:00:1695986927.956969 14048 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.957 I I0000 00:00:1695986927.957704 14049 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.957 I I0000 00:00:1695986927.957719 14046 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.957 I I0000 00:00:1695986927.957737 14049 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Nagual 4sum ... 14:28:47.957 I I0000 00:00:1695986927.957745 14046 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Oksoko 4sum ... 14:28:47.957 I I0000 00:00:1695986927.957748 14049 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.957 I I0000 00:00:1695986927.957755 14046 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.957 I I0000 00:00:1695986927.957943 14048 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.957 I I0000 00:00:1695986927.957982 14048 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Oksoko remosaic ... 14:28:47.958 I I0000 00:00:1695986927.958001 14048 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.958 I I0000 00:00:1695986927.958424 14049 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.958 I I0000 00:00:1695986927.958444 14049 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Nagual 4sum ... 14:28:47.958 I I0000 00:00:1695986927.958452 14049 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.958 I I0000 00:00:1695986927.958480 14046 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.958 I I0000 00:00:1695986927.958503 14046 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Oksoko 4sum ... 14:28:47.958 I I0000 00:00:1695986927.958515 14046 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.958 I I0000 00:00:1695986927.958932 14048 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.958 I I0000 00:00:1695986927.958970 14048 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Oksoko remosaic ... 14:28:47.959 I I0000 00:00:1695986927.958991 14048 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.959 I I0000 00:00:1695986927.959075 14049 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.959 I I0000 00:00:1695986927.959094 14049 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Nagual 4sum ... 14:28:47.959 I I0000 00:00:1695986927.959103 14049 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.959 I I0000 00:00:1695986927.959223 14046 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.959 I I0000 00:00:1695986927.959242 14046 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Oksoko 4sum ... 14:28:47.959 I I0000 00:00:1695986927.959252 14046 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.959 I I0000 00:00:1695986927.959866 13992 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.959 I I0000 00:00:1695986927.959919 13992 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Oksoko 4sum ... 14:28:47.959 I I0000 00:00:1695986927.959941 13992 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.959 I I0000 00:00:1695986927.959946 14048 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.959 I I0000 00:00:1695986927.959974 14048 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Oksoko remosaic ... 14:28:47.960 I I0000 00:00:1695986927.959989 14048 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.960 I I0000 00:00:1695986927.960574 13992 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.960 I I0000 00:00:1695986927.960602 13992 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Oksoko 4sum ... 14:28:47.960 I I0000 00:00:1695986927.960618 13992 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.961 I I0000 00:00:1695986927.961206 13992 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.961 I I0000 00:00:1695986927.961231 13992 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Oksoko 4sum ... 14:28:47.961 I I0000 00:00:1695986927.961244 13992 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.961 I I0000 00:00:1695986927.961849 13992 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.961 I I0000 00:00:1695986927.961882 13992 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Oksoko 4sum ... 14:28:47.961 I I0000 00:00:1695986927.961897 13992 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.962 I rls_manager.cc:420: RlsManager::Configure(): Clients for mode: 2 is unavailable 14:28:47.962 I rls_manager.cc:420: RlsManager::Configure(): Clients for mode: 2 is unavailable 14:28:47.962 I I0000 00:00:1695986927.962534 13992 utils.cc:407] [GOOG_PDAF]: Device detected: MP1.0. Using EEPROM calibration. 14:28:47.962 I I0000 00:00:1695986927.962568 13992 utils.cc:425] [GOOG_PDAF]: PDDBG cal camid: Oksoko 4sum ... 14:28:47.962 I I0000 00:00:1695986927.962602 13992 utils.cc:426] [GOOG_PDAF]: PDDBG dcc data: 187.25 ... 14:28:47.968 I graph_runner_deferring_work_queue.cc:90: GraphRunner: Using GraphRunnerDeferringWorkQueue 14:28:47.968 I graph_runner_deferring_work_queue.cc:92: Thread pool config , Input Priority: High, affinity: Big Core, process group: CameraServicePerformance, # of threads:5 14:28:47.968 I graph_runner_deferring_work_queue.cc:92: Thread pool config , Input Priority: Normal, affinity: Big Core, process group: CameraServicePerformance, # of threads:1 14:28:47.968 I graph_runner_deferring_work_queue.cc:92: Thread pool config , Input Priority: Normal, affinity: Auto, process group: CameraServicePerformance, # of threads:7 14:28:47.968 I graph_runner_deferring_work_queue.cc:92: Thread pool config , Input Priority: Normal, affinity: Auto, process group: CameraServicePower, # of threads:7 14:28:47.968 I graph_runner_deferring_work_queue.cc:92: Thread pool config , Input Priority: High, affinity: Medium Core, process group: CameraServicePerformance, # of threads:5 14:28:47.968 I graph_runner_deferring_work_queue.cc:92: Thread pool config , Input Priority: High, affinity: Auto, process group: CameraServicePerformance, # of threads:12 14:28:47.968 I graph_runner_deferring_work_queue.cc:92: Thread pool config , Input Priority: Low, affinity: Auto, process group: CameraServicePerformance, # of threads:2 14:28:47.968 I graph_runner_deferring_work_queue.cc:92: Thread pool config , Input Priority: High, affinity: Auto, process group: CameraServicePower, # of threads:1 14:28:47.968 I camera_device_session_manager_impl.cc:1042: Successfully started graph:top_graph_for_camera_RearMultiFov 14:28:47.968 I multicam_whi_capture_session.cc:986: success 14:28:47.968 I I0000 00:00:1695986927.968665 1697 node_export.cc:194] CatNodeCreate(EspVideoNode) took 0.0566 ms 14:28:47.969 I Initialize Active size (4624 x 3472). 14:28:47.983 I Create: Created StreamBufferCacheManager. 14:28:47.984 I RegisterStreamsIntoCacheManagerLocked: [sbc] Registered stream 0 into SBC manager. 14:28:47.984 I RegisterStreamsIntoCacheManagerLocked: [sbc] Registered stream 1 into SBC manager. 14:28:47.984 I RegisterStreamsIntoCacheManagerLocked: [sbc] Registered stream 2 into SBC manager. 14:28:47.987 D Set real time priority for request queue thread (tid 14002) 14:28:47.988 D Attempting to send capture request onConfigured 14:28:47.988 D Issuing request for session. 14:28:47.990 I ProcessCaptureRequest: [sbc] Stream 0 1st req arrived, notify SBC Manager. 14:28:47.990 D CameraCaptureSession.onConfigured() mState=OPENED 14:28:47.990 I ProcessCaptureRequest: [sbc] Stream 2 1st req arrived, notify SBC Manager. 14:28:47.990 I ProcessRequest: First request with preview intent. ZSL starts. 14:28:47.990 D CameraCaptureSession.onReady() OPENED 14:28:47.990 I multicam_whi_request_processor.cc:839: ********************dpm: put camera 2 on NORMAL, frame 0, zoom ratio 1.00 ******************** 14:28:47.990 I multicam_whi_request_processor.cc:962: ******************** new lead: camera 2(Wide), frame 0, zoom ratio 1.00 ******************* 14:28:47.990 I multicam_whi_request_processor.cc:1119: ====== Multicam status update ====== 14:28:47.990 I multicam_whi_request_processor.cc:1120: Frame number: 0, Lead cam: 2, Zoom ratio: 1.00, APP requested streams: 0, 2, 66112, 14:28:47.990 I multicam_whi_request_processor.cc:1127: cam: 2, dpm_mode: Normal, activation_flags: None 14:28:47.990 I camera_powerhal.cc:140: SetPowerBoost Trigger CAMERA_LAUNCH_EXTENDED for 2000ms 14:28:47.990 I retiming_node.cc:953: ******** dpm: cam2_retiming change DPM mode from -1 to 0, is virtual transition: 0, framework request id 0, frame number: 0 14:28:47.990 I generic_power_throttle_controller.cc:787: camera thermal status: global frame rate at 30fps for camera 2 at frame 0 14:28:47.991 I generic_power_throttle_controller.cc:787: camera thermal status: 3A at 30fps for camera 2 at frame 0 14:28:47.991 I conditional_dpm_node.cc:51: cam2_conditional_dpm turn to dpm mode: 0 14:28:47.991 I multicam_whi_request_processor.cc:1119: ====== Multicam status update ====== 14:28:47.991 I actuator_driver.cc:200: act-rear-normal Actuator operation mode changed to: Active 14:28:47.991 I multicam_whi_request_processor.cc:1120: Frame number: 1, Lead cam: 2, Zoom ratio: 1.00, APP requested streams: 0, 2, 66112, 14:28:47.991 I multicam_whi_request_processor.cc:1127: cam: 2, dpm_mode: Undefined, activation_flags: None 14:28:47.991 W external_auto_focus_controller_proxy.cc:1484: ExternalAutoFocusControllerProxy: camera_id = 2; The total number of frames missing CDAF, HW PDAF, SW PDAF and Sparse PDAF statistics is 1 14:28:47.991 W external_auto_focus_controller_proxy.cc:1831: Not able to get shutter notification timestamp 14:28:47.991 E external_auto_focus_controller_proxy.cc:2049: Not able to get shutter notification timestamp. No actuator data is collected. 14:28:47.991 I actuator_driver.cc:710: act-rear-normal Event thread is enabled 14:28:47.992 W auto_exposure_node.cc:638: cam2_ae about to start waiting on inputs, but the inputs deadline has already passed. Request frame number: 0, ID 0. Time to inputs deadline: -148h20m48.217999939s. Time to processing deadline: -148h20m48.217999939s. Late count: 1 / 1 14:28:47.993 I Display 4619827677550801152 policy changed Previous: {{defaultModeId=0, allowGroupSwitching=false, primaryRange=[0.00 Hz, 60.00 Hz], appRequestRange=[0.00 Hz, 60.00 Hz]}} Current: {{defaultModeId=0, allowGroupSwitching=false, primaryRange=[60.00 Hz, 60.00 Hz], appRequestRange=[60.00 Hz, 60.00 Hz]}} 1 mode changes were performed under the previous policy 14:28:47.995 W data_manager.cc:154: [5, 25, 9001] can't be found 14:28:47.995 I ae_input_parser_lyric.cc:426: Starter frame: rel_frame_num [-3] 14:28:47.995 I ae_engine.cc:216: Starter Frames 14:28:47.996 E NoneNormal: gaf_metric_algos_3a.cc:74] ProcessStatsAe: invalid aec stats: skip aec stats processing 14:28:47.997 W auto_exposure_node.cc:721: cam2_ae: ProcessInput is finishing after the processing deadline. Request frame number: 0, ID: 0. Time to processing deadline: -148h20m48.223588359s. Late count: 1 / 1 14:28:47.997 I sensor_generic_driver.cc:3704: OKSOKO waiting for init and mode setting to finish. 14:28:47.997 I sensor_generic_driver.cc:3737: OKSOKO init and mode setting done. 14:28:47.997 I auto_scene_detection_input_parser_lyric.cc:387: Starter frame: rel_frame_num [-3] 14:28:47.997 I awb_input_parser_lyric.cc:1001: Starter frame: rel_frame_num [-3] 14:28:47.997 W tuning_manager.h:67: Tuning key not found: Usecase: 13 Sensor mode: -1 Camera position: 1 Camera lens_type: 0 Camera spectrum: -1 load default tuning Usecase: 0 Sensor mode: -1 Camera position: 1 Camera lens_type: 0 Camera spectrum: -1 14:28:47.997 I awb_engine.cc:1148: Load tuning with previous: Usecase: 0 Sensor mode: -1 Camera position: 1 Camera lens_type: 0 Camera spectrum: -1 current: Usecase: 13 Sensor mode: -1 Camera position: 1 Camera lens_type: 0 Camera spectrum: -1 14:28:47.998 I front_end_controller.cc:1919: dpm: frontend context 2 updating power mode to normal mode 14:28:47.998 I multisensor_controller.cc:489: Rear StartStreaming, waiting on barrier 14:28:47.998 I front_end_controller.cc:149: Acquiring PDP with requirement: votf_enable:0, bayer_size:{width: 4624, height: 3472}, pd_size:{width: 4624, height: 868}, enable_bayer_output_to_ipp:1, enable_bayer_output_to_g3aa:1, enable_pd_output_to_g3aa:1, enable_pdstats_output_to_wdma:0, input_type:0, bayer_scaler_type:0, lic_configuration:lic_input_context_mode:3, lic_input_bit_mode:0, yext_hdr_mode:0, pdp_channel:1, secure_operation_mode:NonSecure 14:28:47.998 I pdp_driver.cc:1044: PDP: about to SetHwEventsEnable to 1 for context 1 14:28:47.999 I pdp_driver.cc:304: PDP: acquired votf_enable:0, bayer_size:{width: 4624, height: 3472}, pd_size:{width: 4624, height: 868}, enable_bayer_output_to_ipp:1, enable_bayer_output_to_g3aa:1, enable_pd_output_to_g3aa:1, enable_pdstats_output_to_wdma:0, input_type:0, bayer_scaler_type:0, lic_configuration:lic_input_context_mode:3, lic_input_bit_mode:0, yext_hdr_mode:0, pdp_channel:1, secure_operation_mode:NonSecure address 0xb400007dd927dd70 as context 1 14:28:47.999 I csi_driver.cc:806: CSI: created new processing context 0xb400007d892ea060 for binding 2 14:28:47.999 I csi_driver.cc:1004: Trying to initialize context with these resource assignments: Camera ID:N/A, CSIS LINK:-1, CSIS EBUF:-1, WDMA INPUT:-1, CSIS WDMA:-1, PDP WDMA:-1, ZSL WDMA:-1, EBUF CH:-1, PDP CH:1, USAGE:0x20, sensor image: 0x0, sensor pd: has value:false: 0x0 14:28:47.999 I csi_driver.cc:1234: CSI-1: Acquire processing context done. 14:28:47.999 I g3aa_driver.cc:960: ------------------------------------------------------ 14:28:47.999 I g3aa_driver.cc:961: Acquiring context 1. Requirements: Context 1, Bayer Enabled-OTF at 4624x3472, PDAF Enabled-OTF at 4624x868. Sensor OKSOKO, max-fps 30, batch size 1, camera_id=2 14:28:47.999 I g3aa_driver.cc:963: ------------------------------------------------------ 14:28:47.999 I rls_manager.cc:420: RlsManager::Configure(): Clients for mode: 2 is unavailable 14:28:48.000 I g3aa_pipeline.cc:314: [PDAF1] Acquiring pipeline 14:28:48.000 I g3aa_pdaf_pipeline.cc:327: [PDAF1] G3AA can NOT gracefully drop the first frame. Last HW programming changed Disabled;[0x0] --> Enabled;[4624x868] 14:28:48.000 I g3aa_pipeline.cc:1204: [PDAF1] Submitting repeating SOF-Sync transaction 14:28:48.000 I g3aa_pipeline.cc:634: [PDAF1] Acquired pipeline. last_hw_frame_num=-1, last_sw_sof_event_count=-1, next_frame_number=1, next_trigger_count=0, next_shadow_set=0, session_first_frame_number=1, batch_size=1 14:28:48.000 I g3aa_pipeline.cc:771: [PDAF1] Enabling pipeline. Next frame number will be 1 14:28:48.000 I per_camera_barrier_impl.cc:44: Blocker for G3aaPDAF1BlockCam2ToStart acquired 14:28:48.000 I g3aa_pipeline.cc:314: [Bayer1] Acquiring pipeline 14:28:48.000 I g3aa_bayer_pipeline.cc:1114: [Bayer1] G3AA can NOT gracefully drop the first frame. Last HW programming changed Disabled;[0x0] --> Enabled;[4624x3472] 14:28:48.000 I g3aa_pipeline.cc:1204: [Bayer1] Submitting repeating SOF-Sync transaction 14:28:48.000 I g3aa_pipeline.cc:634: [Bayer1] Acquired pipeline. last_hw_frame_num=-1, last_sw_sof_event_count=-1, next_frame_number=1, next_trigger_count=0, next_shadow_set=0, session_first_frame_number=1, batch_size=1 14:28:48.000 I g3aa_pipeline.cc:771: [Bayer1] Enabling pipeline. Next frame number will be 1 14:28:48.000 I per_camera_barrier_impl.cc:44: Blocker for G3aaBayer1BlockCam2ToStart acquired 14:28:48.000 I g3aa_driver.cc:2100: Number of active HW contexts: 1, power-up-only contexts: 1. Details: 14:28:48.000 I g3aa_driver.cc:2111: Context 1, Bayer1 pipeline. Acquired: true. Enabled: true 14:28:48.000 I g3aa_driver.cc:2111: Context 1, PDAF1 pipeline. Acquired: true. Enabled: true 14:28:48.000 I per_camera_barrier_impl.cc:44: Blocker for IppBlockCam2ToStart acquired 14:28:48.000 I stream_interface_controller.cc:522: Compression(SBWC) enabled for CSIS -> DNS for camera Rear with instance id 0 14:28:48.000 I csi_driver.cc:779: CSI: using the existing processing context 0xb400007d892ea060 for binding 2 14:28:48.000 I csi_driver.cc:1004: Trying to initialize context with these resource assignments: Camera ID:N/A, CSIS LINK:-1, CSIS EBUF:-1, WDMA INPUT:-1, CSIS WDMA:-1, PDP WDMA:-1, ZSL WDMA:1, EBUF CH:-1, PDP CH:1, USAGE:0x21, sensor image: 0x0, sensor pd: has value:false: 0x0 14:28:48.000 I csi_context.cc:2156: [CSI BW] Camera ID(0) wdma: CsisZsl1, wdma_data_per_line 9248 bandwidth_peak 1019934720 width: 0 pixel rate 0 pixel sample rate 0 active line time 0 required bandwidth 1019934720 14:28:48.000 I csi_driver.cc:1507: [CSI BW] CSIS_D0 BW: 1019934720 wdmas: 1, 14:28:48.000 I csi_driver.cc:1509: [CSI BW] CSIS_D1 BW: 0 wdmas: 14:28:48.000 I csi_driver.cc:1511: [CSI BW] Update Required BW: 1019934720, Required Clock: 0, Required peak BW: 1019934720 14:28:48.000 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device csi bandwidth_read: 0 bandwidth_write: 996030 bandwidth_peak: 996030 bandwidth_rt: 996030 14:28:48.000 I csi_driver.cc:1234: CSI-1: Acquire processing context done. 14:28:48.000 I csi_driver.cc:779: CSI: using the existing processing context 0xb400007d892ea060 for binding 2 14:28:48.001 I csi_context.cc:188: Context's CAMERA ID #2 14:28:48.001 I csi_driver.cc:1004: Trying to initialize context with these resource assignments: Camera ID:2, CSIS LINK:1, CSIS EBUF:0, WDMA INPUT:0, CSIS WDMA:6, PDP WDMA:-1, ZSL WDMA:1, EBUF CH:0, PDP CH:1, USAGE:0x35, sensor image: 4624x3472, sensor pd: has value:true: 4624x868 14:28:48.001 I csi_driver.cc:1598: Successfully configured EBUF for 3 cameras! 14:28:48.001 I csi_context.cc:2156: [CSI BW] Camera ID(2) wdma: CsisZsl1, wdma_data_per_line 9248 bandwidth_peak 1314383172 width: 4624 pixel rate 947930000 pixel sample rate 1359870000 active line time 7.036e-06 required bandwidth 1170445920 14:28:48.001 I csi_context.cc:2156: [CSI BW] Camera ID(2) wdma: CsisDma0, wdma_data_per_line 5780 bandwidth_peak 821489482 width: 4624 pixel rate 947930000 pixel sample rate 1359870000 active line time 7.036e-06 required bandwidth 1170445920 14:28:48.001 I csi_context.cc:2129: dpm: sensor width 4624 h: 3472 fps: 30 pixel rate 947930000 headroom: 0 14:28:48.001 I csi_driver.cc:1507: [CSI BW] CSIS_D0 BW: 1314383172 wdmas: 1, 14:28:48.001 I csi_driver.cc:1509: [CSI BW] CSIS_D1 BW: 821489482 wdmas: 6, 14:28:48.001 I csi_driver.cc:1511: [CSI BW] Update Required BW: 1170445920, Required Clock: 236982500, Required peak BW: 1314383172 14:28:48.001 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device csi bandwidth_read: 0 bandwidth_write: 1143013 bandwidth_peak: 1283577 bandwidth_rt: 2085813 14:28:48.001 I csi_driver.cc:1234: CSI1: Acquire processing context done. 14:28:48.001 I per_camera_barrier_impl.cc:44: Blocker for CsiBlockCam2ToStop acquired 14:28:48.001 I csi_context.cc:2156: [CSI BW] Camera ID(2) wdma: CsisZsl1, wdma_data_per_line 9248 bandwidth_peak 1314383172 width: 4624 pixel rate 947930000 pixel sample rate 1359870000 active line time 7.036e-06 required bandwidth 1170445920 14:28:48.001 I csi_context.cc:2156: [CSI BW] Camera ID(2) wdma: CsisDma0, wdma_data_per_line 5780 bandwidth_peak 821489482 width: 4624 pixel rate 947930000 pixel sample rate 1359870000 active line time 7.036e-06 required bandwidth 1170445920 14:28:48.001 I csi_context.cc:2129: dpm: sensor width 4624 h: 3472 fps: 30 pixel rate 947930000 headroom: 0 14:28:48.001 I g3aa_driver.cc:1763: ------------------------------------------------------ 14:28:48.001 I g3aa_driver.cc:1764: G3AA power mode for context 1 changing from Normal(0) -> Normal(0) 14:28:48.001 I g3aa_driver.cc:1769: ------------------------------------------------------ 14:28:48.001 I front_end_controller.cc:1966: dpm: frontend context 2 power mode changed to normal mode 14:28:48.001 I per_camera_barrier_impl.cc:49: Blocker for FrontEndBlockCam2ToStart released with status: OK 14:28:48.001 W auto_exposure_node.cc:638: cam2_ae about to start waiting on inputs, but the inputs deadline has already passed. Request frame number: 1, ID 1. Time to inputs deadline: -148h20m48.227089498s. Time to processing deadline: -148h20m48.227089498s. Late count: 2 / 2 14:28:48.001 I ae_input_parser_lyric.cc:426: Starter frame: rel_frame_num [-2] 14:28:48.001 I ae_engine.cc:216: Starter Frames 14:28:48.001 W auto_exposure_node.cc:721: cam2_ae: ProcessInput is finishing after the processing deadline. Request frame number: 1, ID: 1. Time to processing deadline: -148h20m48.227688212s. Late count: 2 / 2 14:28:48.001 I awb_input_parser_lyric.cc:1001: Starter frame: rel_frame_num [-2] 14:28:48.001 I auto_scene_detection_input_parser_lyric.cc:387: Starter frame: rel_frame_num [-2] 14:28:48.005 I generic_power_throttle_controller.cc:787: camera thermal status: dysco at 30fps for camera 2 at frame 0 14:28:48.005 W dysco_node.cc:137: LscStats payload is missing in frame 0 due to frame drop! 14:28:48.014 I front_end_controller.cc:1727: dpm: node type 1 frontend context 2 was already in activate mode 14:28:48.014 I statistics_node.cc:1119: cam2_statistics, tuning point drop: 0, frame number: 0, tuning input enabled: 1, bypass tnr align stats: 0, is request frame drop: 0 14:28:48.014 I front_end_controller.cc:1727: dpm: node type 0 frontend context 2 was already in activate mode 14:28:48.014 I statistics_node.cc:1018: LocalToneMappingDataV1 payload is dropped. 14:28:48.014 I raw_preprocessor_node.cc:637: HalPixelFormat for RawPreprocessorNode RAW16_BAYER_SBWC 14:28:48.015 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device ipp bandwidth_read: 0 bandwidth_write: 32474 bandwidth_peak: 32474 bandwidth_rt: 32474 14:28:48.017 I ipp_driver.cc:2568: IPP(1) block sensor since ipp configurations is not ready is_raw_config_ready? 1 is_gp_config_ready? 0 14:28:48.017 I csi_context.cc:1935: Camera ID(2) CSI PHY configuration. 14:28:48.017 I csi_wdma.cc:2202: Successfully configured common DMA! 14:28:48.017 I csi_context.cc:538: csi_link_info_.csis_link: 1 14:28:48.018 I csi_context.cc:539: w: 4624 h: 3472 14:28:48.019 I per_camera_barrier_impl.cc:49: Blocker for IppBlockCam2ToStart released with status: OK 14:28:48.019 I ipp_driver.cc:2557: IPP(1) release sensor start blocker 14:28:48.019 I per_camera_barrier_impl.cc:44: Blocker for IppBlockCam2ToStop acquired 14:28:48.020 I per_camera_barrier_impl.cc:49: Blocker for CsiBlockCam2ToStart released with status: OK 14:28:48.020 I csi_context.cc:1333: Camera ID(2) WDMA(CsisDma0) released sensor start blocker 14:28:48.020 W per_camera_barrier_impl.cc:128: It seems new blockers were acquired while waiting. Keep waiting for the new ones. number of fences before wait 2 after 5 14:28:48.026 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device g3aa bandwidth_read: 0 bandwidth_write: 84673 bandwidth_peak: 429687 bandwidth_rt: 429687 14:28:48.027 I g3aa_pipeline.cc:2154: [Bayer1][Frame 1] Submitted transaction on shadow 0. TriggerAt: Immediate. Last received HW Frame -1 at SOF -1. Queue: [1]. Result: OK 14:28:48.028 I g3aa_pipeline.cc:2154: [PDAF1][Frame 1] Submitted transaction on shadow 0. TriggerAt: Immediate. Last received HW Frame -1 at SOF -1. Queue: [1]. Result: OK 14:28:48.028 I generic_power_throttle_controller.cc:787: camera thermal status: face detection at 30fps for camera 2 at frame 0 14:28:48.029 I g3aa_pipeline.h:143: Releasing sensor start blocker for pipeline Bayer1 with status OK 14:28:48.029 I per_camera_barrier_impl.cc:49: Blocker for G3aaBayer1BlockCam2ToStart released with status: OK 14:28:48.029 I g3aa_pipeline.h:143: Releasing sensor start blocker for pipeline PDAF1 with status OK 14:28:48.029 I per_camera_barrier_impl.cc:49: Blocker for G3aaPDAF1BlockCam2ToStart released with status: OK 14:28:48.029 I dpm_bandwidth_manager.cc:407: Update minimum MIF clock to 1000000000 number of active sensor: 1 14:28:48.029 I dpm_bandwidth_manager.cc:97: dpm: required_clock is 223509825 min_frontend_clock_requirement_: 0 sensor streaming: 1 14:28:48.029 I dpm_bandwidth_manager.cc:103: Vote for front-end clock for freq 223509825 14:28:48.030 W actuator_driver.cc:640: act-rear-normal: Periodic IO is already started. 14:28:48.030 I sensor_generic_driver.cc:3958: OKSOKO Load virtual transition settings, transit_to_virtual 0 14:28:48.032 I Replacing 1 node(s) with delegate (edgetpu-custom-op-2) node, yielding 1 partitions for the whole graph. 14:28:48.036 I sensor_generic_driver.cc:769: OKSOKO stream on, size: {width: 4624, height: 3472}, frame rate range: [7.5, 30] 14:28:48.036 I per_camera_barrier_impl.cc:44: Blocker for MultiSensorController_Stop acquired 14:28:48.036 W dysco_node.cc:137: LscStats payload is missing in frame 1 due to frame drop! 14:28:48.036 I statistics_node.cc:1018: LocalToneMappingDataV1 payload is dropped. 14:28:48.037 I ae_input_parser_lyric.cc:426: Starter frame: rel_frame_num [-1] 14:28:48.037 I ae_engine.cc:216: Starter Frames 14:28:48.038 I csi_wdma.cc:1322: Camera ID(2) WDMA(CsisZsl1) drops(1) frames!! last frame(1) next frame(3) target frame = 3, batch size = 1 14:28:48.041 I pdp_driver.cc:745: Pdp context(1), SOF 1 timestamp 534048267504495 14:28:48.041 I ipp_driver.cc:1242: Ipp context(1), SOF 1 timestamp 534048267472676 14:28:48.041 I sensor_generic_driver.cc:470: OKSOKO 1st SOF arrived. Delay from stream on: 11.527465ms Delay from stream on transaction: 5.677327ms (Delay - exposure time): 8.769353ms 14:28:48.041 I auto_focus_meta_node.cc:149: The camera_id = 2; The total number of frames received after exposure time is 1 14:28:48.049 I csi_context.cc:930: Camera ID(2) Csi Link(1), SOF 1, frame seq count:1, timestamp 534048267410949 14:28:48.049 I g3aa_driver.cc:1479: [FEIRQ] Received G3AA bayer event: G3aa.Interrupts.Context1BayerStartOfFrame counter: 1 time: 534048267559793 14:28:48.049 I dpm_bandwidth_manager.cc:342: Current MIF clock 3172000 14:28:48.049 I dpm_bandwidth_manager.cc:342: Current INT clock 533000 14:28:48.049 I g3aa_pipeline.cc:2126: [Bayer1][Frame 1][SOF 1][ VALID ] frame_id: [1]:2763950143, Timestamp: 534048267590352, HW was last programmed at SOF of Frame 0 Queue: [1]. 14:28:48.049 I g3aa_driver.cc:1505: [FEIRQ] Received G3AA bayer event: G3aa.Interrupts.Context1PdafStartOfFrame counter: 1 time: 534048267593810 14:28:48.049 I g3aa_pipeline.cc:2126: [PDAF1][Frame 1][SOF 1][ VALID ] frame_id: [1]:503581539, Timestamp: 534048267602925, HW was last programmed at SOF of Frame 0 Queue: [1]. 14:28:48.049 I dpm_bandwidth_manager.cc:342: Current CAM clock 233000 14:28:48.049 I dpm_bandwidth_manager.cc:342: Current INTCAM clock 664000 14:28:48.049 I dpm_bandwidth_manager.cc:342: Current TNR clock 664000 14:28:48.049 I csi_wdma.cc:1831: Camera ID(2) WDMA(CsisDma0) Channel(0) dma enable = 0, expected frame = 0, active frame ptr = 31, active_dma_ctr = 7c, current fro frame = 0, dma_addr = 0xdeadbeef, dma_ctrl = 0x0 14:28:48.049 I csi_wdma.cc:1831: Camera ID(2) WDMA(CsisDma0) Channel(1) dma enable = 1, expected frame = 0, active frame ptr = 0, active_dma_ctr = f81, current fro frame = 0, dma_addr = 0xce800000, dma_ctrl = 0x1 14:28:48.050 I csi_wdma.cc:1831: Camera ID(2) WDMA(CsisDma0) Channel(2) dma enable = 0, expected frame = 0, active frame ptr = 31, active_dma_ctr = 7c, current fro frame = 0, dma_addr = 0x0, dma_ctrl = 0x0 14:28:48.050 I csi_wdma.cc:1831: Camera ID(2) WDMA(CsisZsl1) Channel(0) dma enable = 1, expected frame = 1, active frame ptr = 0, active_dma_ctr = f81, current fro frame = 0, dma_addr = 0xd0636400, dma_ctrl = 0x1 14:28:48.050 I awb_input_parser_lyric.cc:1001: Starter frame: rel_frame_num [-1] 14:28:48.050 I auto_scene_detection_input_parser_lyric.cc:387: Starter frame: rel_frame_num [-1] 14:28:48.050 W dysco_node.cc:137: LscStats payload is missing in frame 2 due to frame drop! 14:28:48.055 W g3aa_pipeline.cc:893: [Bayer1][Frame 3] Skipping configuration of frames 2-2. Last received HW Frame 1 at SOF 1. Next trigger count updated from 1 to 2. Queue: [1]. 14:28:48.055 I g3aa_pipeline.cc:2154: [Bayer1][Frame 3] Submitted transaction on shadow 1. TriggerAt: SOF 2. Last received HW Frame 1 at SOF 1. Queue: [1 3]. Result: OK 14:28:48.055 W g3aa_pipeline.cc:893: [PDAF1][Frame 3] Skipping configuration of frames 2-2. Last received HW Frame 1 at SOF 1. Next trigger count updated from 1 to 2. Queue: [1]. 14:28:48.055 I g3aa_pipeline.cc:2154: [PDAF1][Frame 3] Submitted transaction on shadow 1. TriggerAt: SOF 2. Last received HW Frame 1 at SOF 1. Queue: [1 3]. Result: OK 14:28:48.056 I statistics_node.cc:1018: LocalToneMappingDataV1 payload is dropped. 14:28:48.057 I I0000 00:00:1695986928.056957 14093 node_export.cc:194] CatNodeCreate(EspNode) took 87.9303 ms 14:28:48.060 I g3aa_pipeline.cc:2154: [Bayer1][Frame 4] Submitted transaction on shadow 0. TriggerAt: SOF 3. Last received HW Frame 1 at SOF 1. Queue: [1 3 4]. Result: OK 14:28:48.060 I g3aa_pipeline.cc:2154: [PDAF1][Frame 4] Submitted transaction on shadow 0. TriggerAt: SOF 3. Last received HW Frame 1 at SOF 1. Queue: [1 3 4]. Result: OK 14:28:48.066 I csi_context.cc:1005: Camera ID(2) Csi Link(1), EOF 1, timestamp 534048291966165 14:28:48.066 I ipp_driver.cc:1263: Ipp context(1), EOF 1 timestamp 534048292082132 14:28:48.066 I g3aa_driver.cc:1479: [FEIRQ] Received G3AA bayer event: G3aa.Interrupts.Context1BayerEndOfFrame counter: 1 time: 534048292216816 14:28:48.066 I g3aa_bayer_pipeline.cc:630: [Bayer1][Frame 1][EOF 1] Queue: [1 3 4] 14:28:48.066 I g3aa_driver.cc:1505: [FEIRQ] Received G3AA bayer event: G3aa.Interrupts.Context1PdafEndOfFrame counter: 1 time: 534048292503397 14:28:48.066 I g3aa_pdaf_pipeline.cc:239: [PDAF1][Frame 1][EOF 1] Queue: [1 3 4] 14:28:48.066 I pdp_driver.cc:761: Pdp context(1), EOF 1 timestamp 534048292476582 14:28:48.067 W external_auto_focus_controller_proxy.cc:396: No SW PD stats are valid; Status: NOT_FOUND: No final software pdaf config returned by AF algorithm; Error waiting for final config 14:28:48.067 I I0000 00:00:1695986928.067245 14033 segmenter_node.cc:426] SegmenterNode: Creating subject segmenter. 14:28:48.067 I I0000 00:00:1695986928.067275 14033 segmenter_node.cc:427] SegmenterNode: frame_size = [640,480] 14:28:48.067 I I0000 00:00:1695986928.067456 14098 xeno_dsp_segmenter.cc:585] Creating DSP segmenter. 14:28:48.071 E [motion_lib.cc:212]: MeasureMotion: Motion could not be computed anywhere. Skip copying of motion magnitudes to outputs. 14:28:48.074 W tnr_align_node.cc:495: Using previous gaussian pyramid from frame -1 as the reference gaussian pyramid for frame 0 14:28:48.074 I raw_to_yuv_node.cc:650: ITP decide to switch dma_sync from 0 to 1 on raw frame num : 0 exposure frame num 0 gain = 3.01693 power mode = 0 camera id : 2 14:28:48.075 I g3aa_pipeline.cc:2126: [Bayer1][Frame 2][SOF 2][DROPPED] frame_id: [1]:2763950143, Timestamp: 534048301533060, HW was last programmed at SOF of Frame 0 Queue: [3 4]. 14:28:48.076 I g3aa_pipeline.cc:2126: [PDAF1][Frame 2][SOF 2][DROPPED] frame_id: [1]:503581539, Timestamp: 534048301970641, HW was last programmed at SOF of Frame 0 Queue: [3 4]. 14:28:48.076 W tnr_align_node.cc:293: TnrAlignNode producing alignment and noise frame drops for batch with first frame number: 0 due to error: INVALID_ARGUMENT: Failed on MSA requirements. Pyramid and alignment inputs do not match!; Error calling process on controller; Produced 1 alignment and noise frame drops since started. The corresponding image frame(s) will still be processed without denoising in TNR merge. 14:28:48.076 I raw_to_yuv_node.cc:735: HalPixelFormat for RawToYuvNode YCBCR_420_SP_M_S10B_SBWC_L40_STRIDE_ALIGNED_64 14:28:48.076 I stream_interface_controller.cc:161: Image format set to {type: ImageFormat, HalPixelFormat: YCBCR_420_SP_M_S10B_SBWC_L40_STRIDE_ALIGNED_64, Width: 4160, Height: 3120} for producer TNR, consumer ITSC at frame 0 by the producer 14:28:48.080 I tnr_merge_node.cc:864: TNR merge decide to switch dpm_mode from -1 to 0 on frame : 0 exposure frame num : 0 gain = 3.01693 camera id : 2 14:28:48.080 I gtnr_merge_driver.cc:2419: update power mode = 0 for context 0xb400007e492859f0 14:28:48.080 I gtnr_merge_driver.cc:2419: update power mode = 0 for context 0xb400007e492853e0 14:28:48.080 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device gtnr-merge bandwidth_read: 760500 bandwidth_write: 760500 bandwidth_peak: 760500 bandwidth_rt: 0 14:28:48.081 I tnr_merge_node.cc:1184: HalPixelFormat for TnrMergeNode YCBCR_420_SP_M_S10B_SBWC_L40 14:28:48.081 I gtnr_merge_controller.cc:311: Force sharpen only process, due to: FAILED_PRECONDITION: Invalid feedback image, force sharpen only process! 14:28:48.081 I stream_interface_controller.cc:278: WhiStreamInterfaceController: VOTF connected, producer: 7 consumer: 4 14:28:48.081 W async_face_detection_engine.cc:180: the workload can not be finished successfully in time: DEADLINE_EXCEEDED: timed out waiting for fence with a timeout duration: 15ms Will return last valid result instead. 14:28:48.082 I I0000 00:00:1695986928.082069 13998 rectiface_node.cc:1873] LiveRectiface: focal_length (mm) = 5.43 14:28:48.082 I gtnr_merge_driver.cc:1213: Enqueue a frame command with votf enable = 1, ack = 0xad200000 14:28:48.082 I I0000 00:00:1695986928.082143 13998 rectiface_node.cc:1875] LiveRectiface: sensor_width (mm) = 7.3984 14:28:48.082 I I0000 00:00:1695986928.082160 13998 rectiface_node.cc:1877] LiveRectiface: sensor_aspect_ratio (h/w) = 0.750865 14:28:48.082 I I0000 00:00:1695986928.082172 13998 rectiface_node.cc:1879] LiveRectiface: FOV (deg) = 80.8566 14:28:48.082 I gtnr_merge_driver.cc:1465: GTNR merge frame start! curr time: 534048308 ms 14:28:48.082 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device itp bandwidth_read: 996030 bandwidth_write: 28519 bandwidth_peak: 996030 bandwidth_rt: 0 14:28:48.084 W W0000 00:00:1695986928.084335 13998 plane.cc:283] Memory re-allocation. 14:28:48.084 W W0000 00:00:1695986928.084522 13998 plane.cc:283] Memory re-allocation. 14:28:48.084 W W0000 00:00:1695986928.084609 13998 plane.cc:283] Memory re-allocation. 14:28:48.085 W W0000 00:00:1695986928.085417 13998 plane.cc:283] Memory re-allocation. 14:28:48.085 I I0000 00:00:1695986928.085452 13998 rectiface_node.cc:1200] Live rectiface init status: enabled. 14:28:48.093 I gtnr_merge_driver.cc:1507: GTNR merge all frame(s) done! curr time: 534048319 ms 14:28:48.093 I gtnr_merge_driver.cc:1560: GTNR merge SW frame(s) done transaction complete! 14:28:48.095 I mesh_warp_node.cc:299: HalPixelFormat for MeshWarpNode YCBCR_420_SP_M_S10B_SBWC_L40_STRIDE_ALIGNED_64 14:28:48.096 I stream_interface_controller.cc:161: Image format set to {type: ImageFormat, HalPixelFormat: YCBCR_420_SP_M_S10B_SBWC_L40_STRIDE_ALIGNED_64, Width: 4160, Height: 3120} for producer MCSC, consumer GDC0 at frame 0 by the producer 14:28:48.096 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device mcsc bandwidth_read: 8952 bandwidth_write: 69862 bandwidth_peak: 69862 bandwidth_rt: 0 14:28:48.096 I stream_interface_controller.cc:278: WhiStreamInterfaceController: VOTF connected, producer: 10 consumer: 8 14:28:48.097 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device mcsc bandwidth_read: 28519 bandwidth_write: 506486 bandwidth_peak: 506486 bandwidth_rt: 0 14:28:48.099 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device gdc0 bandwidth_read: 285191 bandwidth_write: 28519 bandwidth_peak: 285191 bandwidth_rt: 0 14:28:48.100 I statistics_node.cc:1018: LocalToneMappingDataV1 payload is dropped. 14:28:48.104 I g3aa_pipeline.cc:2154: [Bayer1][Frame 5] Submitted transaction on shadow 1. TriggerAt: SOF 4. Last received HW Frame 2 at SOF 2. Queue: [3 4 5]. Result: OK 14:28:48.104 I g3aa_pipeline.cc:2154: [PDAF1][Frame 5] Submitted transaction on shadow 1. TriggerAt: SOF 4. Last received HW Frame 2 at SOF 2. Queue: [3 4 5]. Result: OK 14:28:48.105 W node.cc:76: cam2_eaf: align_map is dropped on frame: 0 14:28:48.105 E E0000 00:00:1695986928.105934 14042 llv_node.cc:1273] Dimension is not expected! width = 1920, height = 1080, stride_pixels = 1920, stride_bytes = 1920, width_uv = 960, height_uv = 540, stride_uv_pixels = 960, stride_uv_bytes = 1920 14:28:48.106 I ProcessCaptureResult: First frame done 14:28:48.107 D Update Preview stream state to STREAMING 14:28:48.109 W shutter_notification.cc:65: vsync time has larger than expected drift: 34224082 ns. Max drift: 10000000 ns. 14:28:48.109 W shutter_notification_manager.cc:178: vsync unavailable for frame 3 14:28:48.109 I shutter_notification_manager.cc:226: vsync_timestamp unavailable. Apply previous vsync_sof_offset -268716 14:28:48.109 I g3aa_pipeline.cc:2126: [Bayer1][Frame 3][SOF 3][ VALID ] frame_id: 1:[3], Timestamp: 534048335480814, HW was last programmed at SOF of Frame 2 Queue: [3 4 5]. 14:28:48.109 I g3aa_pipeline.cc:2126: [PDAF1][Frame 3][SOF 3][ VALID ] frame_id: 1:[3], Timestamp: 534048335540750, HW was last programmed at SOF of Frame 2 Queue: [3 4 5]. 14:28:48.110 I multicam_whi_stprocessor.cc:256: ST: Received new 3A overwrite vote: 4, frame: 5 14:28:48.115 W type=1400 audit(0.0:100438): avc: denied { getattr } for name="/" dev="dmabuf" ino=1 scontext=u:r:surfaceflinger:s0 tcontext=u:object_r:unlabeled:s0 tclass=filesystem permissive=0 14:28:48.117 D Layer: im.status.ethereum.pr/im.status.ethereum.MainActivity#51380 had an out of bounds transparent region 14:28:48.117 D Region transparentRegionHint (this=0xb4000072c34074c0, count=2) 14:28:48.117 D [-117, 0, 0, 1080] 14:28:48.117 D [1080, 0, 1803, 1080] 14:28:48.134 I g3aa_bayer_pipeline.cc:630: [Bayer1][Frame 3][EOF 2] Queue: [3 4 5] 14:28:48.134 D requestNetwork for uid/pid:10128/13968 activeRequest: null callbackRequest: 9206 [NetworkRequest [ REQUEST id=9207, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10128 RequestorUid: 10128 RequestorPkg: com.google.android.apps.gcs UnderlyingNetworks: Null] ]] callback flags: 0 order: 2147483647 14:28:48.134 I g3aa_pdaf_pipeline.cc:239: [PDAF1][Frame 3][EOF 2] Queue: [3 4 5] 14:28:48.139 I Worker result SUCCESS for Work [ id=cc710064-4472-476a-8610-394327e1a0c1, tags={ com.google.android.apps.gcs.service.worker.GcsWorker } ] 14:28:48.140 I Considering local module com.google.mlkit.dynamite.barcode:10000 and remote module com.google.mlkit.dynamite.barcode:0 14:28:48.140 I Selected local version of com.google.mlkit.dynamite.barcode 14:28:48.140 D got request NetworkRequest [ REQUEST id=9207, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10128 RequestorUid: 10128 RequestorPkg: com.google.android.apps.gcs UnderlyingNetworks: Null] ] 14:28:48.141 D got request NetworkRequest [ REQUEST id=9207, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10128 RequestorUid: 10128 RequestorPkg: com.google.android.apps.gcs UnderlyingNetworks: Null] ] 14:28:48.141 D NetReassign [9207 : null → 251] [c 0] [a 2] [i 4] 14:28:48.142 D got request NetworkRequest [ REQUEST id=9207, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10128 RequestorUid: 10128 RequestorPkg: com.google.android.apps.gcs UnderlyingNetworks: Null] ] 14:28:48.142 D got request NetworkRequest [ REQUEST id=9207, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10128 RequestorUid: 10128 RequestorPkg: com.google.android.apps.gcs UnderlyingNetworks: Null] ] 14:28:48.143 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device gtnr-align bandwidth_read: 64926 bandwidth_write: 3521 bandwidth_peak: 64926 bandwidth_rt: 0 14:28:48.144 I g3aa_pipeline.cc:2126: [Bayer1][Frame 4][SOF 4][ VALID ] frame_id: [4]:3, Timestamp: 534048369445332, HW was last programmed at SOF of Frame 3 Queue: [4 5 6]. 14:28:48.144 I g3aa_pipeline.cc:2126: [PDAF1][Frame 4][SOF 4][ VALID ] frame_id: [4]:3, Timestamp: 534048369922871, HW was last programmed at SOF of Frame 3 Queue: [4 5 6]. 14:28:48.152 D Storing event with priority=VERY_LOW, name=FIREBASE_ML_SDK for destination cct 14:28:48.153 W tnr_merge_node.cc:1068: TNR merge uses the previous initial image frame instead of merge feedback for denoise for frame/batch: 1 in context 0xb400007cf92ba868 14:28:48.155 I tnr_merge_node.cc:1235: HalPixelFormat for TnrMergeNode feedback YCBCR_420_SP_M_S10B_SBWC_L40_STRIDE_ALIGNED_64 14:28:48.155 D Scheduling upload for context TransportContext(cct, VERY_LOW, MSRodHRwczovL2ZpcmViYXNlbG9nZ2luZy5nb29nbGVhcGlzLmNvbS92MGNjL2xvZy9iYXRjaD9mb3JtYXQ9anNvbl9wcm90bzNc) with jobId=-2132335188 in 86400000ms(Backend next call timestamp 0). Attempt 1 14:28:48.155 D Compat change id reported: 194532703; UID 10417; state: DISABLED 14:28:48.156 I gtnr_merge_driver.cc:2215: Created luma module with input size 4160 x 3120 14:28:48.156 D Compat change id reported: 194532703; UID 10417; state: DISABLED 14:28:48.156 D requestNetwork for uid/pid:1000/1410 asUid: 10417 activeRequest: null callbackRequest: 9208 [NetworkRequest [ REQUEST id=9209, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10417 RequestorUid: 1000 RequestorPkg: android UnderlyingNetworks: Null] ]] callback flags: 0 order: 2147483647 14:28:48.159 I luma_denoise_module.cc:201: GTNR merge allocate luma buffer for pyramid level 0 FD: 455 14:28:48.159 I luma_denoise_module.cc:201: GTNR merge allocate luma buffer for pyramid level 1 FD: 455 14:28:48.159 I luma_denoise_module.cc:201: GTNR merge allocate luma buffer for pyramid level 2 FD: 455 14:28:48.159 I luma_denoise_module.cc:201: GTNR merge allocate luma buffer for pyramid level 3 FD: 455 14:28:48.159 I luma_denoise_module.cc:201: GTNR merge allocate luma buffer for pyramid level 4 FD: 455 14:28:48.160 D Storing event with priority=DEFAULT, name=FIREBASE_ML_SDK for destination cct 14:28:48.160 I luma_denoise_module.cc:232: GTNR merge enroll luma buffer for pyramid level 0 FD: 455 addr: 2321547264 14:28:48.160 I luma_denoise_module.cc:274: GTNR merge enroll luma buffer for pyramid level1 FD: 455 addr: 2347505664 14:28:48.160 I luma_denoise_module.cc:274: GTNR merge enroll luma buffer for pyramid level2 FD: 455 addr: 2352397824 14:28:48.160 I luma_denoise_module.cc:274: GTNR merge enroll luma buffer for pyramid level3 FD: 455 addr: 2353645824 14:28:48.160 I luma_denoise_module.cc:274: GTNR merge enroll luma buffer for pyramid level4 FD: 455 addr: 2353970304 14:28:48.160 I gtnr_merge_driver.cc:2250: Created chroma module with input size 4160 x 3120 14:28:48.161 D Scheduling upload for context TransportContext(cct, DEFAULT, MSRodHRwczovL2ZpcmViYXNlbG9nZ2luZy5nb29nbGVhcGlzLmNvbS92MGNjL2xvZy9iYXRjaD9mb3JtYXQ9anNvbl9wcm90bzNc) with jobId=-2137315925 in 30000ms(Backend next call timestamp 0). Attempt 1 14:28:48.161 I chroma_denoise_module.cc:92: GTNR merge allocate chroma buffer FD: 456 14:28:48.161 I chroma_denoise_module.cc:103: GTNR merge enroll chroma buffer FD: 456 addr: 2306867200 14:28:48.162 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device gtnr-merge bandwidth_read: 450501 bandwidth_write: 703652 bandwidth_peak: 703652 bandwidth_rt: 0 14:28:48.162 I gtnr_merge_driver.cc:1213: Enqueue a frame command with votf enable = 1, ack = 0xad200000 14:28:48.163 D got request NetworkRequest [ REQUEST id=9209, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10417 RequestorUid: 1000 RequestorPkg: android UnderlyingNetworks: Null] ] 14:28:48.163 D got request NetworkRequest [ REQUEST id=9209, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10417 RequestorUid: 1000 RequestorPkg: android UnderlyingNetworks: Null] ] 14:28:48.164 D Storing event with priority=VERY_LOW, name=FIREBASE_ML_SDK for destination cct 14:28:48.164 D got request NetworkRequest [ REQUEST id=9209, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10417 RequestorUid: 1000 RequestorPkg: android UnderlyingNetworks: Null] ] 14:28:48.164 D got request NetworkRequest [ REQUEST id=9209, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10417 RequestorUid: 1000 RequestorPkg: android UnderlyingNetworks: Null] ] 14:28:48.164 D NetReassign [9209 : null → 251] [c 1] [a 2] [i 4] 14:28:48.164 D Upload for context TransportContext(cct, VERY_LOW, MSRodHRwczovL2ZpcmViYXNlbG9nZ2luZy5nb29nbGVhcGlzLmNvbS92MGNjL2xvZy9iYXRjaD9mb3JtYXQ9anNvbl9wcm90bzNc) is already scheduled. Returning... 14:28:48.168 I g3aa_bayer_pipeline.cc:630: [Bayer1][Frame 4][EOF 3] Queue: [4 5 6] 14:28:48.168 I g3aa_pdaf_pipeline.cc:239: [PDAF1][Frame 4][EOF 3] Queue: [4 5 6] 14:28:48.172 I Initialized TensorFlow Lite runtime. 14:28:48.172 E INFO: Initialized TensorFlow Lite runtime. 14:28:48.177 I g3aa_pipeline.cc:2126: [Bayer1][Frame 5][SOF 5][ VALID ] frame_id: 4:[5], Timestamp: 534048403544412, HW was last programmed at SOF of Frame 4 Queue: [5 6 7]. 14:28:48.178 I g3aa_pipeline.cc:2126: [PDAF1][Frame 5][SOF 5][ VALID ] frame_id: 4:[5], Timestamp: 534048403894388, HW was last programmed at SOF of Frame 4 Queue: [5 6 7]. 14:28:48.182 I I0929 14:28:48.182367 14111 oned_decoder_client.cc:685] barhopper::deep_learning::OnedDecoderClient is created successfully. 14:28:48.182 E I0929 14:28:48.182367 14111 oned_decoder_client.cc:685] barhopper::deep_learning::OnedDecoderClient is created successfully. 14:28:48.186 W Access denied finding property "ro.mediatek.platform" 14:28:48.186 W Access denied finding property "ro.chipname" 14:28:48.186 W Access denied finding property "ro.hardware.chipname" 14:28:48.190 I Worker result SUCCESS for Work [ id=d591118e-1bdc-4791-a2bb-ba14cfcc6ee9, tags={ com.google.android.apps.gcs.service.worker.GcsWorker } ] 14:28:48.196 D Storing event with priority=VERY_LOW, name=FIREBASE_ML_SDK for destination cct 14:28:48.198 D Upload for context TransportContext(cct, VERY_LOW, MSRodHRwczovL2ZpcmViYXNlbG9nZ2luZy5nb29nbGVhcGlzLmNvbS92MGNjL2xvZy9iYXRjaD9mb3JtYXQ9anNvbl9wcm90bzNc) is already scheduled. Returning... 14:28:48.199 D Storing event with priority=VERY_LOW, name=FIREBASE_ML_SDK for destination cct 14:28:48.200 D Upload for context TransportContext(cct, VERY_LOW, MSRodHRwczovL2ZpcmViYXNlbG9nZ2luZy5nb29nbGVhcGlzLmNvbS92MGNjL2xvZy9iYXRjaD9mb3JtYXQ9anNvbl9wcm90bzNc) is already scheduled. Returning... 14:28:48.199 W type=1400 audit(0.0:100439): avc: denied { getattr } for name="/" dev="dmabuf" ino=1 scontext=u:r:surfaceflinger:s0 tcontext=u:object_r:unlabeled:s0 tclass=filesystem permissive=0 14:28:48.202 I g3aa_bayer_pipeline.cc:630: [Bayer1][Frame 5][EOF 4] Queue: [5 6 7] 14:28:48.202 I g3aa_pdaf_pipeline.cc:239: [PDAF1][Frame 5][EOF 4] Queue: [5 6 7] 14:28:48.203 I Work [ id=dbff50b0-5718-4d66-947e-48e6b216b41e, tags={ com.google.android.apps.gcs.service.worker.GcsWorker } ] was cancelled java.util.concurrent.CancellationException: Task was cancelled. at ajk.m(PG:2) at ajk.get(PG:3) at adt.run(PG:1) at aiz.run(PG:1) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:644) at java.lang.Thread.run(Thread.java:1012) 14:28:48.204 D Storing event with priority=DEFAULT, name=FIREBASE_ML_SDK for destination cct 14:28:48.205 D Upload for context TransportContext(cct, DEFAULT, MSRodHRwczovL2ZpcmViYXNlbG9nZ2luZy5nb29nbGVhcGlzLmNvbS92MGNjL2xvZy9iYXRjaD9mb3JtYXQ9anNvbl9wcm90bzNc) is already scheduled. Returning... 14:28:48.214 I Worker result SUCCESS for Work [ id=b12d5071-e3d1-4ec2-b781-369271d0a20c, tags={ com.google.android.libraries.vpn.gcs.core.common.work.framework.GcsLibWorker, cgalvgcGcsLib } ] 14:28:48.226 D Storing event with priority=DEFAULT, name=FIREBASE_ML_SDK for destination cct 14:28:48.227 D Upload for context TransportContext(cct, DEFAULT, MSRodHRwczovL2ZpcmViYXNlbG9nZ2luZy5nb29nbGVhcGlzLmNvbS92MGNjL2xvZy9iYXRjaD9mb3JtYXQ9anNvbl9wcm90bzNc) is already scheduled. Returning... 14:28:48.231 W type=1400 audit(0.0:100440): avc: denied { getattr } for name="/" dev="dmabuf" ino=1 scontext=u:r:surfaceflinger:s0 tcontext=u:object_r:unlabeled:s0 tclass=filesystem permissive=0 14:28:48.249 D Storing event with priority=DEFAULT, name=FIREBASE_ML_SDK for destination cct 14:28:48.250 D Upload for context TransportContext(cct, DEFAULT, MSRodHRwczovL2ZpcmViYXNlbG9nZ2luZy5nb29nbGVhcGlzLmNvbS92MGNjL2xvZy9iYXRjaD9mb3JtYXQ9anNvbl9wcm90bzNc) is already scheduled. Returning... 14:28:48.257 D requestNetwork for uid/pid:10128/13968 activeRequest: null callbackRequest: 9210 [NetworkRequest [ REQUEST id=9211, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10128 RequestorUid: 10128 RequestorPkg: com.google.android.apps.gcs UnderlyingNetworks: Null] ]] callback flags: 0 order: 2147483647 14:28:48.260 I Worker result SUCCESS for Work [ id=c016be86-f260-4f74-aad4-443d3da2edcb, tags={ com.google.android.apps.gcs.service.worker.GcsWorker } ] 14:28:48.264 D got request NetworkRequest [ REQUEST id=9211, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10128 RequestorUid: 10128 RequestorPkg: com.google.android.apps.gcs UnderlyingNetworks: Null] ] 14:28:48.265 D got request NetworkRequest [ REQUEST id=9211, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10128 RequestorUid: 10128 RequestorPkg: com.google.android.apps.gcs UnderlyingNetworks: Null] ] 14:28:48.265 D got request NetworkRequest [ REQUEST id=9211, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10128 RequestorUid: 10128 RequestorPkg: com.google.android.apps.gcs UnderlyingNetworks: Null] ] 14:28:48.265 D got request NetworkRequest [ REQUEST id=9211, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10128 RequestorUid: 10128 RequestorPkg: com.google.android.apps.gcs UnderlyingNetworks: Null] ] 14:28:48.265 D NetReassign [9211 : null → 251] [c 2] [a 1] [i 5] 14:28:48.282 D Storing event with priority=DEFAULT, name=FIREBASE_ML_SDK for destination cct 14:28:48.283 D Upload for context TransportContext(cct, DEFAULT, MSRodHRwczovL2ZpcmViYXNlbG9nZ2luZy5nb29nbGVhcGlzLmNvbS92MGNjL2xvZy9iYXRjaD9mb3JtYXQ9anNvbl9wcm90bzNc) is already scheduled. Returning... 14:28:48.283 W type=1400 audit(0.0:100441): avc: denied { getattr } for name="/" dev="dmabuf" ino=1 scontext=u:r:surfaceflinger:s0 tcontext=u:object_r:unlabeled:s0 tclass=filesystem permissive=0 14:28:48.317 D Storing event with priority=DEFAULT, name=FIREBASE_ML_SDK for destination cct 14:28:48.318 D Upload for context TransportContext(cct, DEFAULT, MSRodHRwczovL2ZpcmViYXNlbG9nZ2luZy5nb29nbGVhcGlzLmNvbS92MGNjL2xvZy9iYXRjaD9mb3JtYXQ9anNvbl9wcm90bzNc) is already scheduled. Returning... 14:28:48.365 D Surface destroyed. 14:28:48.365 D Surface invalidated androidx.camera.core.SurfaceRequest@e8c5ed3 14:28:48.365 D surface closed, useCount=1 closed=true androidx.camera.core.SurfaceRequest$2@dada10 14:28:48.368 D Storing event with priority=DEFAULT, name=FIREBASE_ML_SDK for destination cct 14:28:48.368 D accelerometer: Enter Batch. period = 66667000, latency = 0. 14:28:48.368 D IMU temperature: Enter Batch. period = 533334000, latency = 0. 14:28:48.369 D Upload for context TransportContext(cct, DEFAULT, MSRodHRwczovL2ZpcmViYXNlbG9nZ2luZy5nb29nbGVhcGlzLmNvbS92MGNjL2xvZy9iYXRjaD9mb3JtYXQ9anNvbl9wcm90bzNc) is already scheduled. Returning... 14:28:48.369 E [SurfaceView[im.status.ethereum.pr/im.status.ethereum.MainActivity]#1(BLAST Consumer)1](id:359800000004,api:4,p:898,c:13720) dequeueBuffer: BufferQueue has been abandoned 14:28:48.369 E getBufferLockedCommon: Stream 0: Can't dequeue next output buffer: No such device (-19) 14:28:48.369 W RequestStreamBuffers: Requesting stream buffer failed. (buffer_returns has 1 entries) 14:28:48.369 D {Camera@c44877b[id=0]} Use cases [androidx.camera.core.Preview-90d1b21f-f124-4809-8d46-98a1cef70e2583349098, androidx.camera.core.ImageCapture-b1e47fd8-8ae3-430c-8074-7d11e8825386241393499, androidx.camera.core.ImageAnalysis-74676a0c-2744-4804-9854-d0b12e5c988746105592] now DETACHED for camera 14:28:48.369 I RequestStreamBuffers: stream -1, buffer request error 3 14:28:48.369 I DumpStatus: Buffers (including dummy) pending return from HWL: {{2: 6},{1: 0},{0: 6},}. Buffers proactively acquired from the framework: {{2: 1},{1: 0},{0: 1},}. 14:28:48.369 D surface closed, useCount=1 closed=true androidx.camera.core.impl.ImmediateSurface@3b4152f 14:28:48.369 D All use case: [] for camera: 0 14:28:48.369 D {Camera@c44877b[id=0]} Resetting Capture Session 14:28:48.369 D surface closed, useCount=1 closed=true androidx.camera.core.impl.ImmediateSurface@d17db28 14:28:48.370 I AllocateDummyBufferLocked: [sbc] Dummy buffer allocated: strm 0 buffer 0xb400007d2930bdb0 14:28:48.370 W Refill: Failed to acquire buffer for stream 0, error 3 14:28:48.370 W Refill: Stream 0 is disconnected or unknown error observed.This stream is marked as inactive. 14:28:48.370 I Refill: Stream 0 begin to use dummy buffer. 14:28:48.370 D getRelativeImageRotation: destRotationDegrees=0, sourceRotationDegrees=90, isOppositeFacing=true, result=90 14:28:48.370 D {Camera@c44877b[id=0]} Releasing session in state OPENED 14:28:48.370 I Refill: Stream already deactived. 14:28:48.370 D Active and attached use case: [] for camera: 0 14:28:48.371 D Active and attached use case: [] for camera: 0 14:28:48.371 D {Camera@c44877b[id=0]} Closing camera. 14:28:48.371 D {Camera@c44877b[id=0]} Transitioning camera internal state: OPENED --> CLOSING 14:28:48.371 D Recalculating open cameras: Camera State ------------------------------------------------------------------- Camera@d747744[id=1] UNKNOWN Camera@c44877b[id=0] CLOSING ------------------------------------------------------------------- Open count: 1 (Max allowed: 1) 14:28:48.371 D New public camera state CameraState{type=CLOSING, error=null} from CLOSING and null 14:28:48.372 D Publishing new public camera state CameraState{type=CLOSING, error=null} 14:28:48.372 D {Camera@c44877b[id=0]} Resetting Capture Session 14:28:48.372 D {Camera@c44877b[id=0]} Releasing session in state CLOSING 14:28:48.373 D onSessionFinished() 14:28:48.373 D Camera 0: start to disconnect 14:28:48.373 D Camera 0: serializationLock acquired 14:28:48.373 D Camera 0: Shutting down 14:28:48.373 D Camera 0: start to cacheDump 14:28:48.375 D getRelativeImageRotation: destRotationDegrees=0, sourceRotationDegrees=90, isOppositeFacing=true, result=90 14:28:48.380 I camera_device_session_hwl_impl.cc:986: Flush: entered, camera_id: 0 14:28:48.380 I camera_device_session_manager_impl.cc:1326: Attempting to flush graph:top_graph_for_camera_RearMultiFov 14:28:48.380 W tnr_merge_node.cc:912: TNR merge in context 0xb400007cf92ba868 uses the last valid alignment map from frame 7 because the alignment map for frame 8 is missing. 14:28:48.380 W tnr_merge_node.cc:957: TNR merge in context 0xb400007cf92ba868 uses the last valid noise map from frame 7 because the noise map for frame 8 is missing. 14:28:48.380 I gtnr_merge_controller.cc:311: Force sharpen only process, due to: FAILED_PRECONDITION: Invalid feedback image, force sharpen only process! 14:28:48.381 W input_utils.h:570: cam2_ae: Failed to query data: INTERNAL: Missing information to get exposure time. 14:28:48.381 W input_utils.h:570: cam2_ae: Failed to query data: INTERNAL: Missing information to get exposure time. 14:28:48.382 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 7, and stream id: 66122, on graph: top_graph_for_camera_RearMultiFov 14:28:48.382 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 7 stream 66122 14:28:48.382 W auto_focus_node.cc:1039: cam2_af, frame_number: 9, shutter notification is not available. Update vsync with current boottime and previous offset. 14:28:48.382 I generic_mesh_warp_controller.cc:326: Flush votf and whi-common-gdc0 14:28:48.382 I generic_mesh_warp_controller.cc:333: whi-common-gdc0 flush completed. 14:28:48.382 I statistics_node.cc:671: Statistics output frame stats (ignores fence-signaled drops): {process_input_count = 12, dropped_all_outputs = 0, valid_ae = 12, valid_pre_lsc_ae = 12, valid_uae = 0, valid_awb = 12, valid_histogram = 12, valid_flicker = 12, valid_motion = 12, valid_cdaf = 12, valid_pdaf = 12, valid_align = 12, valid_alias_map = 12, valid_gaussian_pyramid = 12, valid_scaled_rgb_stats = 12, valid_scaled_yuv_stats = 12, valid_lsc = 12, valid_bilateral_grid = 0, valid_scaled_rgb = 0, valid_scaled_yuv = 0, valid_tuning_dump_data = 0, valid_pixel_patch = 0} 14:28:48.382 I csi_wdma.cc:2232: Camera ID(2) WDMA(CsisZsl1) Flush() ++++++++++++++++++++++++++++++++++++++++++++ 14:28:48.382 I csi_wdma.cc:2260: Camera ID(2) Failed to cancel transaction ID: 70531: NOT_FOUND: IOCTL failed; TransactionCancel failed for csi 14:28:48.382 I csi_wdma.cc:2260: Camera ID(2) Failed to cancel transaction ID: 70527: NOT_FOUND: IOCTL failed; TransactionCancel failed for csi 14:28:48.382 I camera_device_session_manager_impl.cc:1221: Reporting metadata frame drop for request 7, partial result 2 14:28:48.382 I csi_wdma.cc:2355: [CSI DMA] Camera ID(2) dma_addr: 0xd0636400 is signaled due to flush 14:28:48.382 I csi_wdma.cc:2370: [CSI DMA] Camera ID(2) infight buffer queue size: IMG(2), PD(0) 14:28:48.382 I csi_wdma.cc:2376: [CSI DMA] Camera ID(2) dma_to_buffer size: (5) 14:28:48.382 I csi_wdma.cc:2380: [CSI DMA] Camera ID(2) dma_to_buffer dma_addr: 0xb1000000) 14:28:48.382 I csi_wdma.cc:2380: [CSI DMA] Camera ID(2) dma_to_buffer dma_addr: 0x87636400) 14:28:48.382 I csi_wdma.cc:2380: [CSI DMA] Camera ID(2) dma_to_buffer dma_addr: 0xa8e00000) 14:28:48.382 I csi_wdma.cc:2380: [CSI DMA] Camera ID(2) dma_to_buffer dma_addr: 0xb9c00000) 14:28:48.382 I csi_wdma.cc:2380: [CSI DMA] Camera ID(2) dma_to_buffer dma_addr: 0xbac36400) 14:28:48.382 I csi_wdma.cc:2385: Camera ID(2) WDMA(CsisZsl1) Flush() -------------------------------------------- 14:28:48.382 I csi_wdma.cc:2232: Camera ID(2) WDMA(CsisDma0) Flush() ++++++++++++++++++++++++++++++++++++++++++++ 14:28:48.382 I csi_wdma.cc:2260: Camera ID(2) Failed to cancel transaction ID: 70533: NOT_FOUND: IOCTL failed; TransactionCancel failed for csi 14:28:48.382 I csi_wdma.cc:2260: Camera ID(2) Failed to cancel transaction ID: 70529: NOT_FOUND: IOCTL failed; TransactionCancel failed for csi 14:28:48.382 E notify: Frame 7: Invalid error stream id 66112 14:28:48.382 I csi_wdma.cc:2355: [CSI DMA] Camera ID(2) dma_addr: 0xa8e00000 is signaled due to flush 14:28:48.382 I csi_wdma.cc:2370: [CSI DMA] Camera ID(2) infight buffer queue size: IMG(0), PD(2) 14:28:48.382 I csi_wdma.cc:2376: [CSI DMA] Camera ID(2) dma_to_buffer size: (4) 14:28:48.382 I csi_wdma.cc:2380: [CSI DMA] Camera ID(2) dma_to_buffer dma_addr: 0xb1000000) 14:28:48.382 I csi_wdma.cc:2380: [CSI DMA] Camera ID(2) dma_to_buffer dma_addr: 0x87636400) 14:28:48.382 I csi_wdma.cc:2380: [CSI DMA] Camera ID(2) dma_to_buffer dma_addr: 0xb9c00000) 14:28:48.382 I csi_wdma.cc:2380: [CSI DMA] Camera ID(2) dma_to_buffer dma_addr: 0xbac36400) 14:28:48.382 I csi_wdma.cc:2385: Camera ID(2) WDMA(CsisDma0) Flush() -------------------------------------------- 14:28:48.382 W auto_exposure_node.cc:1124: cam2_ae: error waiting on ExposureStatistics: DEADLINE_EXCEEDED: Error waiting on Bundle payload.Status: ABORTED: Fence was aborted 14:28:48.382 W auto_exposure_node.cc:1144: cam2_ae: error waiting on MotionMeteringStats: DEADLINE_EXCEEDED: Error waiting on Bundle payload.Status: ABORTED: Fence was aborted 14:28:48.382 W auto_exposure_node.cc:1162: cam2_ae: error waiting on face detect data: DEADLINE_EXCEEDED: Error waiting on Bundle payload.Status: ABORTED: Fence was aborted 14:28:48.382 I ipp_driver.cc:2142: IPP(1) flushing gp2: 0xb9400000, alias: 0xd811e000 14:28:48.382 I ipp_driver.cc:2615: IPP(1) flush, count of remaining GP: 2 14:28:48.382 I ae_input_parser_lyric.cc:442: AeInputParserLyric: exposure_stats = 0x0. Lead camera. Frame drop. 14:28:48.382 I g3aa_driver.cc:1860: Flush() begin 14:28:48.382 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 7, and stream id: 66119, on graph: top_graph_for_camera_RearMultiFov 14:28:48.382 I g3aa_driver.cc:1884: Flush() end 14:28:48.382 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 7 stream 66119 14:28:48.382 I statistics_node.cc:671: Statistics output frame stats (ignores fence-signaled drops): {process_input_count = 0, dropped_all_outputs = 0, valid_ae = 0, valid_pre_lsc_ae = 0, valid_uae = 0, valid_awb = 0, valid_histogram = 0, valid_flicker = 0, valid_motion = 0, valid_cdaf = 0, valid_pdaf = 0, valid_align = 0, valid_alias_map = 0, valid_gaussian_pyramid = 0, valid_scaled_rgb_stats = 0, valid_scaled_yuv_stats = 0, valid_lsc = 0, valid_bilateral_grid = 0, valid_scaled_rgb = 0, valid_scaled_yuv = 0, valid_tuning_dump_data = 0, valid_pixel_patch = 0} 14:28:48.383 I ipp_driver.cc:2615: IPP(1) flush, count of remaining GP: 2 14:28:48.383 E GABC-HistogramAnalysis: histogram_analysis.cc:403] IsValidHistogram: Invalid histogram input 14:28:48.383 E GABC-GcamAeAdapter: gcam_ae_adapter.cc:367] RunGcamAlgorithm: bayer input is invalid 14:28:48.383 W generic_actuator_controller.cc:316: read position failed: INTERNAL: async readout data timeout 2 14:28:48.383 W sensor_node.cc:390: SensorNode producing drops due to frame request drop on frame 12 14:28:48.383 W tuning_node.cc:336: TuningNode producing frame drop because request frame is drop 14:28:48.383 E dysco_node.cc:78: tuning_point payload is missing due to frame drop! 14:28:48.383 W dysco_node.cc:137: LscStats payload is missing in frame 12 due to frame drop! 14:28:48.383 E dysco_controller_impl.cc:395: One of InputData fields is nullptr. 14:28:48.383 W statistics_node.cc:358: cam2_statistics producing a frame drop for frame 12:INVALID_ARGUMENT: Sensor image input is dropped. 14:28:48.383 I raw_preprocessor_node.cc:1074: cam2_raw_preprocessor received incomplete request at frame number: 12, request dropped: 1, raw_otf dropped: 1, sensor_properties dropped: 1 14:28:48.384 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 7, and stream id: 66113, on graph: top_graph_for_camera_RearMultiFov 14:28:48.384 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 7 stream 66113 14:28:48.384 I camera_device_session_manager_impl.cc:1221: Reporting metadata frame drop for request 9, partial result 1 14:28:48.384 I camera_device_session_manager_impl.cc:1221: Reporting metadata frame drop for request 10, partial result 1 14:28:48.384 I g3aa_driver.cc:1860: Flush() begin 14:28:48.384 I g3aa_driver.cc:1884: Flush() end 14:28:48.384 I statistics_node.cc:671: Statistics output frame stats (ignores fence-signaled drops): {process_input_count = 0, dropped_all_outputs = 0, valid_ae = 0, valid_pre_lsc_ae = 0, valid_uae = 0, valid_awb = 0, valid_histogram = 0, valid_flicker = 0, valid_motion = 0, valid_cdaf = 0, valid_pdaf = 0, valid_align = 0, valid_alias_map = 0, valid_gaussian_pyramid = 0, valid_scaled_rgb_stats = 0, valid_scaled_yuv_stats = 0, valid_lsc = 0, valid_bilateral_grid = 0, valid_scaled_rgb = 0, valid_scaled_yuv = 0, valid_tuning_dump_data = 0, valid_pixel_patch = 0} 14:28:48.384 I itp_driver.cc:1313: ITP skip flushing because it's a standby context 0xb400007d193267f0, dimension: 4208x3120 (VOTF) 14:28:48.384 E [SurfaceView[im.status.ethereum.pr/im.status.ethereum.MainActivity]#1(BLAST Consumer)1](id:359800000004,api:4,p:898,c:13720) cancelBuffer: BufferQueue has been abandoned 14:28:48.384 I gtnr_merge_cmd_processor.cc:311: Flushing in-process frame 8 14:28:48.384 I gtnr_merge_cmd_processor.cc:311: Flushing in-process frame 8 14:28:48.384 I itp_driver.cc:1327: ITP continue flushing context 0xb400007d19318db0, dimension: 4624x3472 (VOTF) 14:28:48.384 I camera_device_session_manager_impl.cc:1221: Reporting metadata frame drop for request 11, partial result 1 14:28:48.385 I itp_core_whi_pro.cc:74: Applying ITP/DNS WA SwReset sequence 14:28:48.385 I camera_device_session_manager_impl.cc:1221: Reporting metadata frame drop for request 12, partial result 1 14:28:48.386 I itp_core_whi_pro.cc:181: ITP Version: 1.0.0 14:28:48.386 I itp_core_whi_pro.cc:183: ITSC Version: 8.7.0 14:28:48.386 I itp_driver.cc:1370: ITP driver flush completed for context 0xb400007d19318db0, dimension: 4624x3472 (VOTF) 14:28:48.386 I itp_driver.cc:1299: ITP skip flushing because driver is not running; context 0xb400007d193363f0, dimension: 4624x3472 (VOTF) 14:28:48.386 I gtnr_merge_cmd_processor.cc:311: Flushing in-process frame 8 14:28:48.386 I gtnr_merge_cmd_processor.cc:311: Flushing in-process frame 8 14:28:48.386 I gtnr_merge_process_cmd.cc:450: gtnr frame processing command match the flush context, flush hw now for image [0]: 0xb400007d19303348 14:28:48.386 I gtnr_merge_cmd_processor.cc:322: successfully flush inflight frame command 14:28:48.386 I gtnr_align_driver.cc:1641: About to flush 14:28:48.386 E gtnr_merge_driver.cc:1282: Error! TNR sharpener output error in sync mode due to CANCELLED: gtnr frame drop due to flush operation Input current image is 0xb400007d19303348 from context 0xb400007e492859f0 14:28:48.386 I gtnr_align_driver.cc:1671: Flushed 0 un-processed frames from processing context 0xb400007d793cef90 14:28:48.386 W gtnr_align_driver.cc:1681: Did not flush the in-process frame from processing context 0xb400007d793cef90 14:28:48.386 I gtnr_align_driver.cc:1644: Finished flushing with status OK 14:28:48.386 W tnr_merge_node.cc:464: TnrMergeNode producing frame drops from context 0xb400007cf92ba868 starting at frame number: 8 due to error: CANCELLED: gtnr frame drop due to flush operation; Error calling process on controller in context 0xb400007cf92ba868 14:28:48.386 I generic_scaler_controller.cc:147: flush votf and scaler 14:28:48.386 I common_scaler_driver.cc:646: whi-mcsc: flushed 0 frames; took 4.11us 14:28:48.386 I generic_scaler_controller.cc:156: flush votf and scaler completed 14:28:48.386 I generic_scaler_controller.cc:147: flush votf and scaler 14:28:48.386 I common_scaler_driver.cc:646: whi-mcsc: flushed 0 frames; took 407ns 14:28:48.386 I generic_scaler_controller.cc:156: flush votf and scaler completed 14:28:48.386 I generic_scaler_controller.cc:147: flush votf and scaler 14:28:48.386 I common_scaler_driver.cc:646: whi-mcsc: flushed 0 frames; took 406ns 14:28:48.386 I generic_scaler_controller.cc:156: flush votf and scaler completed 14:28:48.386 I auto_exposure_node.cc:854: {type: Node, name: top_graph_for_camera_RearMultiFov.cam2_ae, class_name: AutoExposureNode}: AE input statistics: {process_input_count = 13, started_late_count = 4, completed_late_count = 4, no_deadline_count = 4, controller_timed_out = 0, dropped_requests = 0, stats_not_ready_count = 1, motion_stats_not_ready_count = 1, face_detect_data_not_ready_count = 1, eis_result_not_ready_count = 0, segmentation_mask_not_ready_count = 0, stage_detection_result_not_ready_count = 0, valid_flash_state = 13, valid_exposure_timestamp = 9, valid_lens_info = 13, valid_sensor_properties = 10, valid_crop_roi = 13, valid_ae_stats = 9, valid_uae_stats = 0, valid_awb_stats = 9, valid_histogram = 9, valid_flicker_stats = 9, valid_motion_stats = 9, valid_motion_stats_config = 0, valid_wb_config = 12, valid_color_correction = 12, valid_face_rois = 7} 14:28:48.386 W mesh_warp_node.cc:163: cam2_preview_mesh_warp Request frame is drop, drop all output for frame 8 14:28:48.386 I auto_exposure_node.cc:854: {type: Node, name: top_graph_for_camera_RearMultiFov.cam3_ae, class_name: AutoExposureNode}: AE input statistics: {process_input_count = 0, started_late_count = 0, completed_late_count = 0, no_deadline_count = 0, controller_timed_out = 0, dropped_requests = 0, stats_not_ready_count = 0, motion_stats_not_ready_count = 0, face_detect_data_not_ready_count = 0, eis_result_not_ready_count = 0, segmentation_mask_not_ready_count = 0, stage_detection_result_not_ready_count = 0, valid_flash_state = 0, valid_exposure_timestamp = 0, valid_lens_info = 0, valid_sensor_properties = 0, valid_crop_roi = 0, valid_ae_stats = 0, valid_uae_stats = 0, valid_awb_stats = 0, valid_histogram = 0, valid_flicker_stats = 0, valid_motion_stats = 0, valid_motion_stats_config = 0, valid_wb_config = 0, valid_color_correction = 0, valid_face_rois = 0} 14:28:48.386 W tnr_merge_node.cc:464: TnrMergeNode producing frame drops from context 0xb400007cf92ba868 starting at frame number: 9 due to error: INVALID_ARGUMENT: TNR merge tuning frame is dropped.; TNR merge failed to get tuning data in context 0xb400007cf92ba868 14:28:48.386 I auto_white_balance_node.cc:761: cam2_awb: AWB debug statistics: {process_input_count = 13, started_late_count = 0, completed_late_count = 0, no_deadline_count = 3, controller_timed_out = 0, dropped_requests = 1, stats_not_ready_count = 0, face_detect_data_not_ready_count = 0, motion_metering_stats_not_ready_count = 0, scene_detect_data_not_ready_count = 0} 14:28:48.386 I auto_white_balance_node.cc:761: cam3_awb: AWB debug statistics: {process_input_count = 0, started_late_count = 0, completed_late_count = 0, no_deadline_count = 0, controller_timed_out = 0, dropped_requests = 0, stats_not_ready_count = 0, face_detect_data_not_ready_count = 0, motion_metering_stats_not_ready_count = 0, scene_detect_data_not_ready_count = 0} 14:28:48.386 I auto_exposure_node.cc:854: {type: Node, name: top_graph_for_camera_RearMultiFov.cam4_ae, class_name: AutoExposureNode}: AE input statistics: {process_input_count = 0, started_late_count = 0, completed_late_count = 0, no_deadline_count = 0, controller_timed_out = 0, dropped_requests = 0, stats_not_ready_count = 0, motion_stats_not_ready_count = 0, face_detect_data_not_ready_count = 0, eis_result_not_ready_count = 0, segmentation_mask_not_ready_count = 0, stage_detection_result_not_ready_count = 0, valid_flash_state = 0, valid_exposure_timestamp = 0, valid_lens_info = 0, valid_sensor_properties = 0, valid_crop_roi = 0, valid_ae_stats = 0, valid_uae_stats = 0, valid_awb_stats = 0, valid_histogram = 0, valid_flicker_stats = 0, valid_motion_stats = 0, valid_motion_stats_config = 0, valid_wb_config = 0, valid_color_correction = 0, valid_face_rois = 0} 14:28:48.386 I auto_white_balance_node.cc:761: cam4_awb: AWB debug statistics: {process_input_count = 0, started_late_count = 0, completed_late_count = 0, no_deadline_count = 0, controller_timed_out = 0, dropped_requests = 0, stats_not_ready_count = 0, face_detect_data_not_ready_count = 0, motion_metering_stats_not_ready_count = 0, scene_detect_data_not_ready_count = 0} 14:28:48.386 I auto_focus_node.cc:1553: cam4_af: session statistics: {process_input_count = 0, started_late_count = 0, completed_late_count = 0, no_deadline_count = 0, controller_timed_out = 0, dropped_requests = 0, exposure_stats_not_ready_count = 0, face_detect_data_not_ready_count = 0} 14:28:48.386 I auto_focus_node.cc:1553: cam2_af: session statistics: {process_input_count = 13, started_late_count = 0, completed_late_count = 0, no_deadline_count = 3, controller_timed_out = 0, dropped_requests = 1, exposure_stats_not_ready_count = 0, face_detect_data_not_ready_count = 0} 14:28:48.386 W tnr_merge_node.cc:464: TnrMergeNode producing frame drops from context 0xb400007cf92ba868 starting at frame number: 10 due to error: INVALID_ARGUMENT: TNR merge tuning frame is dropped.; TNR merge failed to get tuning data in context 0xb400007cf92ba868 14:28:48.386 W mesh_warp_node.cc:163: cam2_preview_mesh_warp Request frame is drop, drop all output for frame 9 14:28:48.386 W tnr_merge_node.cc:464: TnrMergeNode producing frame drops from context 0xb400007cf92ba868 starting at frame number: 11 due to error: INVALID_ARGUMENT: TNR merge tuning frame is dropped.; TNR merge failed to get tuning data in context 0xb400007cf92ba868 14:28:48.387 W mesh_warp_node.cc:163: cam2_preview_mesh_warp Request frame is drop, drop all output for frame 10 14:28:48.387 W scaler_node.cc:231: cam2_preview_scaler Input is drop, drop all outputs for frame 8 14:28:48.387 W tnr_merge_node.cc:464: TnrMergeNode producing frame drops from context 0xb400007cf92ba868 starting at frame number: 12 due to error: INVALID_ARGUMENT: TNR merge tuning frame is dropped.; TNR merge failed to get tuning data in context 0xb400007cf92ba868 14:28:48.387 W scaler_node.cc:418: FAILED_PRECONDITION: cam2_preview_scaler producing drops as all input frames are dropped 14:28:48.387 W mesh_warp_node.cc:163: cam2_preview_mesh_warp Request frame is drop, drop all output for frame 11 14:28:48.387 W mesh_warp_node.cc:163: cam2_preview_mesh_warp Request frame is drop, drop all output for frame 12 14:28:48.387 I camera_device_session_manager_impl.cc:1221: Reporting metadata frame drop for request 8, partial result 2 14:28:48.387 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 8, and stream id: 66119, on graph: top_graph_for_camera_RearMultiFov 14:28:48.387 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 8 stream 66119 14:28:48.387 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 8, and stream id: 66113, on graph: top_graph_for_camera_RearMultiFov 14:28:48.387 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 8 stream 66113 14:28:48.388 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 8, and stream id: 66122, on graph: top_graph_for_camera_RearMultiFov 14:28:48.388 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 8 stream 66122 14:28:48.388 E notify: Frame 8: Invalid error stream id 66112 14:28:48.388 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 9, and stream id: 66113, on graph: top_graph_for_camera_RearMultiFov 14:28:48.388 I camera_device_session_manager_impl.cc:1221: Reporting metadata frame drop for request 9, partial result 2 14:28:48.388 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 9 stream 66113 14:28:48.388 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 10, and stream id: 66113, on graph: top_graph_for_camera_RearMultiFov 14:28:48.388 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 10 stream 66113 14:28:48.388 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 9, and stream id: 66119, on graph: top_graph_for_camera_RearMultiFov 14:28:48.388 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 9 stream 66119 14:28:48.388 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 10, and stream id: 66119, on graph: top_graph_for_camera_RearMultiFov 14:28:48.388 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 10 stream 66119 14:28:48.388 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 11, and stream id: 66119, on graph: top_graph_for_camera_RearMultiFov 14:28:48.388 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 11 stream 66119 14:28:48.388 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 11, and stream id: 66113, on graph: top_graph_for_camera_RearMultiFov 14:28:48.388 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 11 stream 66113 14:28:48.389 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 12, and stream id: 66119, on graph: top_graph_for_camera_RearMultiFov 14:28:48.389 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 12 stream 66119 14:28:48.389 I camera_device_session_manager_impl.cc:1221: Reporting metadata frame drop for request 10, partial result 2 14:28:48.389 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 9, and stream id: 66122, on graph: top_graph_for_camera_RearMultiFov 14:28:48.389 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 9 stream 66122 14:28:48.389 I camera_device_session_manager_impl.cc:1221: Reporting metadata frame drop for request 11, partial result 2 14:28:48.389 E notify: Frame 9: Invalid error stream id 66112 14:28:48.389 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 10, and stream id: 66122, on graph: top_graph_for_camera_RearMultiFov 14:28:48.389 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 10 stream 66122 14:28:48.389 I camera_device_session_manager_impl.cc:1221: Reporting metadata frame drop for request 12, partial result 2 14:28:48.389 E notify: Frame 10: Invalid error stream id 66112 14:28:48.389 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 12, and stream id: 66113, on graph: top_graph_for_camera_RearMultiFov 14:28:48.389 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 12 stream 66113 14:28:48.389 I UpdatePendingRequest: [sbc] All inflight requests/streams cleared. Notified SBC for flushing. 14:28:48.389 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 11, and stream id: 66122, on graph: top_graph_for_camera_RearMultiFov 14:28:48.389 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 11 stream 66122 14:28:48.389 E notify: Frame 11: Invalid error stream id 66112 14:28:48.390 I camera_device_session_manager_impl.cc:1294: Reporting frame drop to framework for request_id: 12, and stream id: 66122, on graph: top_graph_for_camera_RearMultiFov 14:28:48.390 I camera_device_session_hwl_impl.cc:1733: OnImageFrameDone: NotifyError for frame 12 stream 66122 14:28:48.390 E notify: Frame 12: Invalid error stream id 66112 14:28:48.390 E TrackReturnedAcquiredBuffers: stream 2 should not have any pending acquired buffers. 14:28:48.390 I operator(): [sbc] Flushed buf[0xb400007d292d0d10] bid[6] strm[2] frm[xx] 14:28:48.394 I Display 4619827677550801152 policy changed Previous: {{defaultModeId=0, allowGroupSwitching=false, primaryRange=[60.00 Hz, 60.00 Hz], appRequestRange=[60.00 Hz, 60.00 Hz]}} Current: {{defaultModeId=0, allowGroupSwitching=false, primaryRange=[0.00 Hz, 60.00 Hz], appRequestRange=[0.00 Hz, 60.00 Hz]}} 0 mode changes were performed under the previous policy 14:28:48.394 I camera_device_session_manager_impl.cc:1369: Successfully flushed graph:top_graph_for_camera_RearMultiFov 14:28:48.394 I disconnectImpl: E 14:28:48.394 I ProcessCaptureRequest latency histogram (13) samples: 14:28:48.394 I 40 80 120 160 200 240 280 320 360 inf (max ms) 14:28:48.394 I 84.62 15.38 0.00 0.00 0.00 0.00 0.00 0.00 0.00 0.00 (%) 14:28:48.395 I a callback has been unregistered to ThermalHAL, isFilter: 0 Type: UNKNOWN 14:28:48.395 I camera_device_session_hwl_impl.cc:767: DestroyPipelines: entered, camera_id: 0 14:28:48.395 I camera_powerhal.cc:240: CAMERA_STREAMING_STANDARD mode unset 14:28:48.395 I camera_powerhal.cc:240: CAMERA_GPU_STANDARD mode unset 14:28:48.395 I camera_device_session_manager_impl.cc:1064: Attempting to stop graph:top_graph_for_camera_RearMultiFov 14:28:48.396 I generic_mesh_warp_controller.cc:326: Flush votf and whi-common-gdc0 14:28:48.396 I generic_mesh_warp_controller.cc:333: whi-common-gdc0 flush completed. 14:28:48.396 I statistics_node.cc:671: Statistics output frame stats (ignores fence-signaled drops): {process_input_count = 1, dropped_all_outputs = 1, valid_ae = 0, valid_pre_lsc_ae = 0, valid_uae = 0, valid_awb = 0, valid_histogram = 0, valid_flicker = 0, valid_motion = 0, valid_cdaf = 0, valid_pdaf = 0, valid_align = 0, valid_alias_map = 0, valid_gaussian_pyramid = 0, valid_scaled_rgb_stats = 0, valid_scaled_yuv_stats = 0, valid_lsc = 0, valid_bilateral_grid = 0, valid_scaled_rgb = 0, valid_scaled_yuv = 0, valid_tuning_dump_data = 0, valid_pixel_patch = 0} 14:28:48.396 I ipp_driver.cc:2615: IPP(1) flush, count of remaining GP: 2 14:28:48.396 I g3aa_driver.cc:1860: Flush() begin 14:28:48.396 I g3aa_driver.cc:1884: Flush() end 14:28:48.396 I statistics_node.cc:671: Statistics output frame stats (ignores fence-signaled drops): {process_input_count = 0, dropped_all_outputs = 0, valid_ae = 0, valid_pre_lsc_ae = 0, valid_uae = 0, valid_awb = 0, valid_histogram = 0, valid_flicker = 0, valid_motion = 0, valid_cdaf = 0, valid_pdaf = 0, valid_align = 0, valid_alias_map = 0, valid_gaussian_pyramid = 0, valid_scaled_rgb_stats = 0, valid_scaled_yuv_stats = 0, valid_lsc = 0, valid_bilateral_grid = 0, valid_scaled_rgb = 0, valid_scaled_yuv = 0, valid_tuning_dump_data = 0, valid_pixel_patch = 0} 14:28:48.396 I ipp_driver.cc:2615: IPP(1) flush, count of remaining GP: 2 14:28:48.396 I g3aa_driver.cc:1860: Flush() begin 14:28:48.396 I g3aa_driver.cc:1884: Flush() end 14:28:48.396 I statistics_node.cc:671: Statistics output frame stats (ignores fence-signaled drops): {process_input_count = 0, dropped_all_outputs = 0, valid_ae = 0, valid_pre_lsc_ae = 0, valid_uae = 0, valid_awb = 0, valid_histogram = 0, valid_flicker = 0, valid_motion = 0, valid_cdaf = 0, valid_pdaf = 0, valid_align = 0, valid_alias_map = 0, valid_gaussian_pyramid = 0, valid_scaled_rgb_stats = 0, valid_scaled_yuv_stats = 0, valid_lsc = 0, valid_bilateral_grid = 0, valid_scaled_rgb = 0, valid_scaled_yuv = 0, valid_tuning_dump_data = 0, valid_pixel_patch = 0} 14:28:48.396 I itp_driver.cc:1299: ITP skip flushing because driver is not running; context 0xb400007d193267f0, dimension: 4208x3120 (VOTF) 14:28:48.396 I itp_driver.cc:1299: ITP skip flushing because driver is not running; context 0xb400007d19318db0, dimension: 4624x3472 (VOTF) 14:28:48.396 I itp_driver.cc:1299: ITP skip flushing because driver is not running; context 0xb400007d193363f0, dimension: 4624x3472 (VOTF) 14:28:48.396 I generic_scaler_controller.cc:147: flush votf and scaler 14:28:48.396 I common_scaler_driver.cc:646: whi-mcsc: flushed 0 frames; took 3.906us 14:28:48.396 I generic_scaler_controller.cc:156: flush votf and scaler completed 14:28:48.396 I generic_scaler_controller.cc:147: flush votf and scaler 14:28:48.396 I common_scaler_driver.cc:646: whi-mcsc: flushed 0 frames; took 325ns 14:28:48.396 I generic_scaler_controller.cc:156: flush votf and scaler completed 14:28:48.396 I generic_scaler_controller.cc:147: flush votf and scaler 14:28:48.396 I common_scaler_driver.cc:646: whi-mcsc: flushed 0 frames; took 285ns 14:28:48.396 I generic_scaler_controller.cc:156: flush votf and scaler completed 14:28:48.396 I auto_exposure_node.cc:854: {type: Node, name: top_graph_for_camera_RearMultiFov.cam2_ae, class_name: AutoExposureNode}: AE input statistics: {process_input_count = 0, started_late_count = 0, completed_late_count = 0, no_deadline_count = 0, controller_timed_out = 0, dropped_requests = 0, stats_not_ready_count = 0, motion_stats_not_ready_count = 0, face_detect_data_not_ready_count = 0, eis_result_not_ready_count = 0, segmentation_mask_not_ready_count = 0, stage_detection_result_not_ready_count = 0, valid_flash_state = 0, valid_exposure_timestamp = 0, valid_lens_info = 0, valid_sensor_properties = 0, valid_crop_roi = 0, valid_ae_stats = 0, valid_uae_stats = 0, valid_awb_stats = 0, valid_histogram = 0, valid_flicker_stats = 0, valid_motion_stats = 0, valid_motion_stats_config = 0, valid_wb_config = 0, valid_color_correction = 0, valid_face_rois = 0} 14:28:48.396 I auto_exposure_node.cc:854: {type: Node, name: top_graph_for_camera_RearMultiFov.cam3_ae, class_name: AutoExposureNode}: AE input statistics: {process_input_count = 0, started_late_count = 0, completed_late_count = 0, no_deadline_count = 0, controller_timed_out = 0, dropped_requests = 0, stats_not_ready_count = 0, motion_stats_not_ready_count = 0, face_detect_data_not_ready_count = 0, eis_result_not_ready_count = 0, segmentation_mask_not_ready_count = 0, stage_detection_result_not_ready_count = 0, valid_flash_state = 0, valid_exposure_timestamp = 0, valid_lens_info = 0, valid_sensor_properties = 0, valid_crop_roi = 0, valid_ae_stats = 0, valid_uae_stats = 0, valid_awb_stats = 0, valid_histogram = 0, valid_flicker_stats = 0, valid_motion_stats = 0, valid_motion_stats_config = 0, valid_wb_config = 0, valid_color_correction = 0, valid_face_rois = 0} 14:28:48.396 I auto_white_balance_node.cc:761: cam2_awb: AWB debug statistics: {process_input_count = 0, started_late_count = 0, completed_late_count = 0, no_deadline_count = 0, controller_timed_out = 0, dropped_requests = 0, stats_not_ready_count = 0, face_detect_data_not_ready_count = 0, motion_metering_stats_not_ready_count = 0, scene_detect_data_not_ready_count = 0} 14:28:48.396 I auto_white_balance_node.cc:761: cam3_awb: AWB debug statistics: {process_input_count = 0, started_late_count = 0, completed_late_count = 0, no_deadline_count = 0, controller_timed_out = 0, dropped_requests = 0, stats_not_ready_count = 0, face_detect_data_not_ready_count = 0, motion_metering_stats_not_ready_count = 0, scene_detect_data_not_ready_count = 0} 14:28:48.396 I auto_exposure_node.cc:854: {type: Node, name: top_graph_for_camera_RearMultiFov.cam4_ae, class_name: AutoExposureNode}: AE input statistics: {process_input_count = 0, started_late_count = 0, completed_late_count = 0, no_deadline_count = 0, controller_timed_out = 0, dropped_requests = 0, stats_not_ready_count = 0, motion_stats_not_ready_count = 0, face_detect_data_not_ready_count = 0, eis_result_not_ready_count = 0, segmentation_mask_not_ready_count = 0, stage_detection_result_not_ready_count = 0, valid_flash_state = 0, valid_exposure_timestamp = 0, valid_lens_info = 0, valid_sensor_properties = 0, valid_crop_roi = 0, valid_ae_stats = 0, valid_uae_stats = 0, valid_awb_stats = 0, valid_histogram = 0, valid_flicker_stats = 0, valid_motion_stats = 0, valid_motion_stats_config = 0, valid_wb_config = 0, valid_color_correction = 0, valid_face_rois = 0} 14:28:48.396 I auto_white_balance_node.cc:761: cam4_awb: AWB debug statistics: {process_input_count = 0, started_late_count = 0, completed_late_count = 0, no_deadline_count = 0, controller_timed_out = 0, dropped_requests = 0, stats_not_ready_count = 0, face_detect_data_not_ready_count = 0, motion_metering_stats_not_ready_count = 0, scene_detect_data_not_ready_count = 0} 14:28:48.396 I auto_focus_node.cc:1553: cam4_af: session statistics: {process_input_count = 0, started_late_count = 0, completed_late_count = 0, no_deadline_count = 0, controller_timed_out = 0, dropped_requests = 0, exposure_stats_not_ready_count = 0, face_detect_data_not_ready_count = 0} 14:28:48.396 I auto_focus_node.cc:1553: cam2_af: session statistics: {process_input_count = 0, started_late_count = 0, completed_late_count = 0, no_deadline_count = 0, controller_timed_out = 0, dropped_requests = 0, exposure_stats_not_ready_count = 0, face_detect_data_not_ready_count = 0} 14:28:48.397 I multisensor_controller.cc:616: Resetting stream_on_framework_request_id for camera 2 14:28:48.397 I multisensor_controller.cc:642: OKSOKO StopStreaming, waiting on barrier 14:28:48.397 I whi_autocal_controller.cc:460: WhiAutocalController: reset context, after which active_contexts=2 14:28:48.397 I whi_autocal_controller.cc:460: WhiAutocalController: reset context, after which active_contexts=1 14:28:48.397 I eaf_node.cc:192: cam2_eaf: Total 13, Drop counts: Image 5, Request 5, Alignmap 0, Warp_mesh 0, Scaled_yuv 0, segmentation 0, Eco active counts 0 14:28:48.397 I whi_autocal_controller.cc:460: WhiAutocalController: reset context, after which active_contexts=0 14:28:48.397 I eaf_node.cc:192: cam3_eaf: Total 0, Drop counts: Image 0, Request 0, Alignmap 0, Warp_mesh 0, Scaled_yuv 0, segmentation 0, Eco active counts 0 14:28:48.398 I I0000 00:00:1695986928.398122 14049 llv_manager.cc:243] ~LlvManager 14:28:48.398 I I0000 00:00:1695986928.398226 14049 timer_utils.cc:343] Thread ID: 14042 Thread ID: 13998 Thread ID: 13988 Thread ID: 14033 Thread ID: 14098 Thread ID: 13990 Thread ID: 14020 Thread ID: 14082 Thread ID: 14085 Thread ID: 14048 14:28:48.398 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device gdc0 bandwidth_read: 0 bandwidth_write: 0 bandwidth_peak: 0 bandwidth_rt: 0 14:28:48.398 I front_end_controller.cc:1727: dpm: node type 0 frontend context 4 was already in deactivate mode 14:28:48.398 I front_end_controller.cc:1727: dpm: node type 2 frontend context 4 was already in deactivate mode 14:28:48.398 D gyroscope: Enter Activate. Enable = 0. 14:28:48.398 I front_end_controller.cc:1727: dpm: node type 0 frontend context 3 was already in deactivate mode 14:28:48.398 I front_end_controller.cc:1727: dpm: node type 2 frontend context 3 was already in deactivate mode 14:28:48.398 I front_end_controller.cc:1727: dpm: node type 1 frontend context 3 was already in deactivate mode 14:28:48.398 I per_camera_barrier_impl.cc:49: Blocker for FrontEndBlockCam3ToStart released with status: OK 14:28:48.399 I front_end_controller.cc:1991: dpm: frontend context 2 updating power mode to standby mode 14:28:48.399 I csi_context.cc:2441: CSI context 2 start to wait [Number of pending buffers:4. Pending dma_to_buffer dma_addr: (0xb1000000) target frame count 12 target frame time: 33.96677925ms Pending dma_to_buffer dma_addr: (0x87636400) target frame count 11 target frame time: 33.96677925ms Pending dma_to_buffer dma_addr: (0xb9c00000) target frame count 11 target frame time: 33.96677925ms Pending dma_to_buffer dma_addr: (0xbac36400) target frame count 12 target frame time: 33.96677925ms] for 203.8006755ms 14:28:48.399 I gtnr_align_driver.cc:1487: About to flush in ~WhiGtnrAlignDriverContext 14:28:48.399 I gtnr_align_driver.cc:1489: Finished flushing in ~WhiGtnrAlignDriverContext 14:28:48.399 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device itp bandwidth_read: 0 bandwidth_write: 0 bandwidth_peak: 0 bandwidth_rt: 0 14:28:48.399 I gtnr_merge_driver.cc:874: WhiGtnrMergeDriver dump acquired contexts context: 0xb400007e49283590 power-up-only context: 0xb400007e49283ba0 votf-enabled context: 0xb400007e492847c0 votf-enabled context: 0xb400007e49284dd0 votf-enabled context: 0xb400007e492853e0 votf-enabled context: 0xb400007e492859f0 votf-enabled ivotf slc buffer pointer: &{type: CachePartition, Size: 1835008, FD: 136, PartitionId: 3, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} curr rdma slc buffer pointer: &{type: CachePartition, Size: 1310720, FD: 142, PartitionId: 4, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} prev rdma slc buffer pointer: &{type: CachePartition, Size: 1048576, FD: 166, PartitionId: 8, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} End of WhiGtnrMergeDriver dump 14:28:48.399 I gtnr_merge_driver.cc:874: WhiGtnrMergeDriver dump acquired contexts context: 0xb400007e49283590 power-up-only context: 0xb400007e492847c0 votf-enabled context: 0xb400007e49284dd0 votf-enabled context: 0xb400007e492853e0 votf-enabled context: 0xb400007e492859f0 votf-enabled ivotf slc buffer pointer: &{type: CachePartition, Size: 1835008, FD: 136, PartitionId: 3, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} curr rdma slc buffer pointer: &{type: CachePartition, Size: 1310720, FD: 142, PartitionId: 4, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} prev rdma slc buffer pointer: &{type: CachePartition, Size: 1048576, FD: 166, PartitionId: 8, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} End of WhiGtnrMergeDriver dump 14:28:48.399 D IMU temperature: Enter Batch. period = 533334000, latency = 0. 14:28:48.399 I gtnr_merge_driver.cc:874: WhiGtnrMergeDriver dump acquired contexts context: 0xb400007e49283590 power-up-only context: 0xb400007e492847c0 votf-enabled context: 0xb400007e49284dd0 votf-enabled context: 0xb400007e492853e0 votf-enabled ivotf slc buffer pointer: &{type: CachePartition, Size: 1835008, FD: 136, PartitionId: 3, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} curr rdma slc buffer pointer: &{type: CachePartition, Size: 1310720, FD: 142, PartitionId: 4, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} prev rdma slc buffer pointer: &{type: CachePartition, Size: 1048576, FD: 166, PartitionId: 8, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} End of WhiGtnrMergeDriver dump 14:28:48.399 I luma_denoise_module.cc:374: GTNR merge disenroll luma buffers 14:28:48.400 I I0000 00:00:1695986928.400202 14049 llv_gpu.cc:709] GLlvDeinitCl 14:28:48.400 I gtnr_align_driver.cc:1487: About to flush in ~WhiGtnrAlignDriverContext 14:28:48.400 I gtnr_align_driver.cc:1489: Finished flushing in ~WhiGtnrAlignDriverContext 14:28:48.400 I I0000 00:00:1695986928.400244 14049 llv_gpu_opencl.cc:279] ~GLlvOpenCl 14:28:48.400 D A3:MSG: usf_sensor.cc, 1375: USF: Received stop sampling request for LSM6DSV Gyroscope. 14:28:48.400 I chroma_denoise_module.cc:111: GTNR merge disenroll chroma buffers 14:28:48.400 I gtnr_align_driver.cc:1487: About to flush in ~WhiGtnrAlignDriverContext 14:28:48.400 I gtnr_align_driver.cc:1489: Finished flushing in ~WhiGtnrAlignDriverContext 14:28:48.400 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device gtnr-align bandwidth_read: 0 bandwidth_write: 0 bandwidth_peak: 0 bandwidth_rt: 0 14:28:48.400 I gtnr_align_driver.cc:560: Canceling 0 pending transaction(s) in TNR align driver for safe power down. 14:28:48.401 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device mcsc bandwidth_read: 0 bandwidth_write: 0 bandwidth_peak: 0 bandwidth_rt: 0 14:28:48.401 I three_a_request_node.cc:651: cam3_three_a_request: Performance statistics for this session: {process_input_count = 0, dropped_request_count = 0, started_late_count = 0, no_deadline_count = 0, eis_result_timeout_count = 0} 14:28:48.401 I gtnr_merge_driver.cc:874: WhiGtnrMergeDriver dump acquired contexts context: 0xb400007e49283590 power-up-only context: 0xb400007e492847c0 votf-enabled context: 0xb400007e49284dd0 votf-enabled ivotf slc buffer pointer: &{type: CachePartition, Size: 1835008, FD: 136, PartitionId: 3, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} curr rdma slc buffer pointer: &{type: CachePartition, Size: 1310720, FD: 142, PartitionId: 4, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} prev rdma slc buffer pointer: &{type: CachePartition, Size: 1048576, FD: 166, PartitionId: 8, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} End of WhiGtnrMergeDriver dump 14:28:48.401 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device gtnr-merge bandwidth_read: 0 bandwidth_write: 0 bandwidth_peak: 0 bandwidth_rt: 0 14:28:48.401 I common_scaler_driver.cc:770: whi-mcsc: exiting processing thread. 14:28:48.402 I gtnr_merge_driver.cc:874: WhiGtnrMergeDriver dump acquired contexts context: 0xb400007e49283590 power-up-only context: 0xb400007e492847c0 votf-enabled ivotf slc buffer pointer: &{type: CachePartition, Size: 1835008, FD: 136, PartitionId: 3, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} curr rdma slc buffer pointer: &{type: CachePartition, Size: 1310720, FD: 142, PartitionId: 4, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} prev rdma slc buffer pointer: &{type: CachePartition, Size: 1048576, FD: 166, PartitionId: 8, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} End of WhiGtnrMergeDriver dump 14:28:48.402 I gtnr_merge_driver.cc:874: WhiGtnrMergeDriver dump acquired contexts context: 0xb400007e49283590 power-up-only ivotf slc buffer pointer: &{type: CachePartition, Size: 1835008, FD: 136, PartitionId: 3, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} curr rdma slc buffer pointer: &{type: CachePartition, Size: 1310720, FD: 142, PartitionId: 4, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} prev rdma slc buffer pointer: &{type: CachePartition, Size: 1048576, FD: 166, PartitionId: 8, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} End of WhiGtnrMergeDriver dump 14:28:48.402 I three_a_request_node.cc:651: cam4_three_a_request: Performance statistics for this session: {process_input_count = 0, dropped_request_count = 0, started_late_count = 0, no_deadline_count = 0, eis_result_timeout_count = 0} 14:28:48.402 I three_a_request_node.cc:651: cam2_three_a_request: Performance statistics for this session: {process_input_count = 13, dropped_request_count = 0, started_late_count = 0, no_deadline_count = 0, eis_result_timeout_count = 0} 14:28:48.402 I rls_manager.cc:420: RlsManager::Configure(): Clients for mode: 2 is unavailable 14:28:48.402 I external_auto_focus_controller_proxy.cc:690: Camera 4: session statistics: {early_cdaf_stats_not_ready_count = 0, early_hw_pdaf_stats_not_ready_count = 0, early_sw_pdaf_stats_not_ready_count = 0, final_cdaf_stats_not_ready_count = 0, final_hw_pdaf_stats_not_ready_count = 0, final_sw_pdaf_stats_not_ready_count = 0, final_sparse_pdaf_stats_not_ready_count = 0, motion_stats_not_ready_count = 0, smart_roi_not_ready_count = 0, saliency_data_not_ready_count = 0} 14:28:48.403 I lwis_client_with_event_thread.cc:130: LwisClient(gtnr-align) event thread is ending . . . 14:28:48.404 I async_face_detection_engine.cc:218: Face detection worker thread disabled 14:28:48.404 I async_face_detection_engine.cc:59: FaceDetectionEngine::CleanUp takes 977ns 14:28:48.404 I face_detection_engine_face_ssd.cc:248: ~FaceDetectionEngineFaceSsd::delete ensemble detector takes 0 14:28:48.405 I async_face_detection_engine.cc:218: Face detection worker thread disabled 14:28:48.405 I async_face_detection_engine.cc:218: Face detection worker thread disabled 14:28:48.405 I async_face_detection_engine.cc:59: FaceDetectionEngine::CleanUp takes 610ns 14:28:48.405 I async_face_detection_engine.cc:59: FaceDetectionEngine::CleanUp takes 488ns 14:28:48.405 I face_detection_engine_face_ssd.cc:248: ~FaceDetectionEngineFaceSsd::delete ensemble detector takes 41ns 14:28:48.405 I face_detection_engine_face_ssd.cc:248: ~FaceDetectionEngineFaceSsd::delete ensemble detector takes 41ns 14:28:48.405 I csi_context.cc:1074: Camera ID(2) WDMA(CsisDma0), logical channel(1), [EOF] SW frame count:10, inflight buf =Inflight size: 2 14:28:48.405 I csi_wdma.cc:2029: [CSI DMA] Camera ID(2) WDMA(CsisDma0) logical channel(1) DequeueBuffer() -> inflight buffer queue size: 1, dequeued dma_addr: 0xb9c00000 14:28:48.405 I csi_context.cc:1074: Camera ID(2) WDMA(CsisZsl1), logical channel(0), [EOF] SW frame count:10, inflight buf =Inflight size: 2 14:28:48.405 I csi_wdma.cc:2029: [CSI DMA] Camera ID(2) WDMA(CsisZsl1) logical channel(0) DequeueBuffer() -> inflight buffer queue size: 1, dequeued dma_addr: 0x87636400 14:28:48.406 I external_auto_focus_controller_proxy.cc:690: Camera 2: session statistics: {early_cdaf_stats_not_ready_count = 0, early_hw_pdaf_stats_not_ready_count = 0, early_sw_pdaf_stats_not_ready_count = 0, final_cdaf_stats_not_ready_count = 0, final_hw_pdaf_stats_not_ready_count = 0, final_sw_pdaf_stats_not_ready_count = 9, final_sparse_pdaf_stats_not_ready_count = 0, motion_stats_not_ready_count = 0, smart_roi_not_ready_count = 0, saliency_data_not_ready_count = 0} 14:28:48.406 I eaf_node.cc:192: cam4_eaf: Total 0, Drop counts: Image 0, Request 0, Alignmap 0, Warp_mesh 0, Scaled_yuv 0, segmentation 0, Eco active counts 0 14:28:48.415 I csi_context.cc:930: Camera ID(2) Csi Link(1), SOF 12, frame seq count:12, timestamp 534048640932678 14:28:48.415 I csi_wdma.cc:1831: Camera ID(2) WDMA(CsisDma0) Channel(0) dma enable = 0, expected frame = 0, active frame ptr = 31, active_dma_ctr = 7c, current fro frame = 0, dma_addr = 0xdeadbeef, dma_ctrl = 0x0 14:28:48.415 I csi_wdma.cc:1831: Camera ID(2) WDMA(CsisDma0) Channel(1) dma enable = 1, expected frame = 0, active frame ptr = 0, active_dma_ctr = f81, current fro frame = 0, dma_addr = 0xb1000000, dma_ctrl = 0x1 14:28:48.415 I csi_wdma.cc:1831: Camera ID(2) WDMA(CsisDma0) Channel(2) dma enable = 0, expected frame = 0, active frame ptr = 31, active_dma_ctr = 7c, current fro frame = 0, dma_addr = 0x0, dma_ctrl = 0x0 14:28:48.415 I csi_wdma.cc:1831: Camera ID(2) WDMA(CsisZsl1) Channel(0) dma enable = 1, expected frame = 1, active frame ptr = 0, active_dma_ctr = f81, current fro frame = 0, dma_addr = 0xbac36400, dma_ctrl = 0x1 14:28:48.415 I csi_wdma.cc:1387: Camera ID(2) Build transaction to disable WDMA(CsisZsl1) logical channel(0) 14:28:48.415 I csi_wdma.cc:1387: Camera ID(2) Build transaction to disable WDMA(CsisDma0) logical channel(1) 14:28:48.415 I csi_context.cc:1045: Camera ID(2) WDMA(CsisZsl1), logical channel(0), [SOF] SW frame count:11, CSIS LINK frame count:12, inflight buf =Inflight size: 1 14:28:48.415 I csi_context.cc:1045: Camera ID(2) WDMA(CsisDma0), logical channel(1), [SOF] SW frame count:11, CSIS LINK frame count:12, inflight buf =Inflight size: 1 14:28:48.415 I csi_context.cc:1058: CSI context 2 is processing the last inflight request, release sensor blocker now 14:28:48.415 I per_camera_barrier_impl.cc:49: Blocker for CsiBlockCam2ToStop released with status: OK 14:28:48.415 I per_camera_barrier_impl.cc:44: Blocker for CsiBlockCam2ToStart acquired 14:28:48.415 I csi_context.cc:2383: Starting FlushMonitor for CSI1 14:28:48.426 D Surface created. 14:28:48.426 D Surface changed. Size: 1920x1080 14:28:48.433 D Surface destroyed. 14:28:48.434 D Layer: im.status.ethereum.pr/im.status.ethereum.MainActivity#51380 had an out of bounds transparent region 14:28:48.434 D Region transparentRegionHint (this=0xb4000072c34074c0, count=2) 14:28:48.434 D [-117, 0, 0, 1080] 14:28:48.434 D [1080, 0, 1803, 1080] 14:28:48.439 I csi_context.cc:1074: Camera ID(2) WDMA(CsisDma0), logical channel(1), [EOF] SW frame count:11, inflight buf =Inflight size: 1 14:28:48.439 I csi_wdma.cc:2029: [CSI DMA] Camera ID(2) WDMA(CsisDma0) logical channel(1) DequeueBuffer() -> inflight buffer queue size: 0, dequeued dma_addr: 0xb1000000 14:28:48.439 I csi_context.cc:1074: Camera ID(2) WDMA(CsisZsl1), logical channel(0), [EOF] SW frame count:11, inflight buf =Inflight size: 1 14:28:48.439 I csi_wdma.cc:2029: [CSI DMA] Camera ID(2) WDMA(CsisZsl1) logical channel(0) DequeueBuffer() -> inflight buffer queue size: 0, dequeued dma_addr: 0xbac36400 14:28:48.439 I csi_context.cc:2129: dpm: sensor width 4624 h: 3472 fps: 30 pixel rate 947930000 headroom: 0 14:28:48.439 I csi_driver.cc:1507: [CSI BW] CSIS_D0 BW: 0 wdmas: 14:28:48.439 I csi_driver.cc:1509: [CSI BW] CSIS_D1 BW: 0 wdmas: 14:28:48.439 I csi_driver.cc:1511: [CSI BW] Update Required BW: 0, Required Clock: 236982500, Required peak BW: 0 14:28:48.439 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device csi bandwidth_read: 0 bandwidth_write: 0 bandwidth_peak: 0 bandwidth_rt: 0 14:28:48.439 I per_camera_barrier_impl.cc:49: Blocker for IppBlockCam2ToStop released with status: OK 14:28:48.439 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device ipp bandwidth_read: 0 bandwidth_write: 0 bandwidth_peak: 0 bandwidth_rt: 0 14:28:48.439 I g3aa_driver.cc:1763: ------------------------------------------------------ 14:28:48.439 I g3aa_driver.cc:1764: G3AA power mode for context 1 changing from Normal(0) -> Standby(2) 14:28:48.439 I g3aa_driver.cc:1769: ------------------------------------------------------ 14:28:48.440 I g3aa_pipeline.cc:796: [Bayer1] Disabling pipeline 14:28:48.440 I g3aa_pipeline.cc:796: [PDAF1] Disabling pipeline 14:28:48.440 I g3aa_pipeline.cc:656: [Bayer1] Drain(timeout=0) entered. next_frame_number=14, next_shadow_set=0, last_hw_frame_num=12, last_sw_sof_event_count=12 14:28:48.440 W g3aa_pipeline.cc:670: [Bayer1] Draining the pipeline without waiting for the HW to finish. This is safe only if the HW has already been globally reset 14:28:48.440 I g3aa_pipeline.cc:387: [Bayer1] There are 1 pending transactions to cancel for frames: [13] 14:28:48.440 W g3aa_pipeline.cc:419: [Bayer1][Frame 13] Canceling transaction id 34504 for shadow 1: [FAILED] 14:28:48.440 I g3aa_pipeline.cc:744: [Bayer1] Waiting for pipeline to drain. There are 1 pending transactions for frames: [13] 14:28:48.440 W g3aa_pipeline.cc:685: [Bayer1] WaitUntilDrained() failed: DEADLINE_EXCEEDED: [Bayer1] Timed out waiting for the pipeline to drain. 1 pending requests remain for frames: [13] 14:28:48.440 E g3aa_pipeline.cc:1165: [Bayer1][Frame 13] Pending interrupt mask: 0X1FFF 14:28:48.440 W g3aa_pipeline.cc:456: [Bayer1] Clearing pending requests for frames: [13] 14:28:48.440 I g3aa_pipeline.cc:710: [Bayer1] Drained; next_frame_number=14, next_shadow_set=0, last_hw_frame_num=12, last_sw_sof_event_count=12 14:28:48.440 I g3aa_pdaf_pipeline.cc:239: [PDAF1][Frame 12][EOF 11] Queue: [12 13] 14:28:48.440 I g3aa_pipeline.cc:656: [PDAF1] Drain(timeout=0) entered. next_frame_number=14, next_shadow_set=0, last_hw_frame_num=12, last_sw_sof_event_count=12 14:28:48.440 W g3aa_pipeline.cc:670: [PDAF1] Draining the pipeline without waiting for the HW to finish. This is safe only if the HW has already been globally reset 14:28:48.440 I g3aa_pipeline.cc:387: [PDAF1] There are 1 pending transactions to cancel for frames: [13] 14:28:48.440 W g3aa_pipeline.cc:419: [PDAF1][Frame 13] Canceling transaction id 34505 for shadow 1: [FAILED] 14:28:48.440 I sensor_generic_driver.cc:4053: OKSOKO is IDLE : is_reading_frame:0, frame count:255, eof event count:12 14:28:48.440 I g3aa_pipeline.cc:744: [PDAF1] Waiting for pipeline to drain. There are 1 pending transactions for frames: [13] 14:28:48.440 W g3aa_pipeline.cc:685: [PDAF1] WaitUntilDrained() failed: DEADLINE_EXCEEDED: [PDAF1] Timed out waiting for the pipeline to drain. 1 pending requests remain for frames: [13] 14:28:48.440 E g3aa_pipeline.cc:1165: [PDAF1][Frame 13] Pending interrupt mask: 0XE000 14:28:48.440 W g3aa_pipeline.cc:456: [PDAF1] Clearing pending requests for frames: [13] 14:28:48.440 I g3aa_pipeline.cc:710: [PDAF1] Drained; next_frame_number=14, next_shadow_set=0, last_hw_frame_num=12, last_sw_sof_event_count=12 14:28:48.440 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device g3aa bandwidth_read: 0 bandwidth_write: 0 bandwidth_peak: 0 bandwidth_rt: 0 14:28:48.440 I g3aa_driver.cc:1828: G3AA power mode for context 1 changed to Standby(2) done 14:28:48.440 I g3aa_driver.cc:2100: Number of active HW contexts: 1, power-up-only contexts: 1. Details: 14:28:48.440 I g3aa_driver.cc:2111: Context 1, Bayer1 pipeline. Acquired: true. Enabled: false 14:28:48.440 I g3aa_driver.cc:2111: Context 1, PDAF1 pipeline. Acquired: true. Enabled: false 14:28:48.440 I sensor_generic_driver.cc:858: OKSOKO stream off, size: {width: 4624, height: 3472}, frame rate range: [7.5, 30] 14:28:48.440 I per_camera_barrier_impl.cc:49: Blocker for MultiSensorController_Stop released with status: OK 14:28:48.440 I csi_context.cc:704: Camera ID(2) CleanupContextLocked() +++ 14:28:48.440 I multisensor_controller.cc:1160: Rear created transition fence, stream-on ID: 4294967295 14:28:48.440 I csi_context.cc:749: Camera ID(2) dma_to_buffer size: (0) 14:28:48.440 I multisensor_controller.cc:1181: Rear signalled transition fence with stream-on ID: 4294967295 14:28:48.440 I dpm_bandwidth_manager.cc:407: Update minimum MIF clock to 0 number of active sensor: 0 14:28:48.440 I dpm_bandwidth_manager.cc:97: dpm: required_clock is 223509825 min_frontend_clock_requirement_: 223509825 sensor streaming: 0 14:28:48.440 I csi_wdma.cc:1387: Camera ID(2) Build transaction to disable WDMA(CsisDma0) logical channel(0) 14:28:48.440 I csi_wdma.cc:1387: Camera ID(2) Build transaction to disable WDMA(CsisDma0) logical channel(1) 14:28:48.440 I csi_wdma.cc:1387: Camera ID(2) Build transaction to disable WDMA(CsisDma0) logical channel(2) 14:28:48.440 I csi_wdma.cc:1387: Camera ID(2) Build transaction to disable WDMA(CsisDma0) logical channel(3) 14:28:48.440 I csi_wdma.cc:1387: Camera ID(2) Build transaction to disable WDMA(CsisZsl1) logical channel(0) 14:28:48.440 I csi_wdma.cc:1387: Camera ID(2) Build transaction to disable WDMA(CsisZsl1) logical channel(1) 14:28:48.440 I csi_wdma.cc:1387: Camera ID(2) Build transaction to disable WDMA(CsisZsl1) logical channel(2) 14:28:48.440 I csi_wdma.cc:1387: Camera ID(2) Build transaction to disable WDMA(CsisZsl1) logical channel(3) 14:28:48.440 I dpm_bandwidth_manager.cc:97: dpm: required_clock is 0 min_frontend_clock_requirement_: 223509825 sensor streaming: 0 14:28:48.440 I dpm_bandwidth_manager.cc:103: Vote for front-end clock for freq 0 14:28:48.441 I csi_context.cc:813: Camera ID(2) CleanupContextLocked() --- 14:28:48.441 I csi_driver.cc:1507: [CSI BW] CSIS_D0 BW: 0 wdmas: 14:28:48.441 I csi_driver.cc:1509: [CSI BW] CSIS_D1 BW: 0 wdmas: 14:28:48.441 I csi_driver.cc:1511: [CSI BW] Update Required BW: 0, Required Clock: 0, Required peak BW: 0 14:28:48.441 I dpm_bandwidth_manager.cc:167: dpm: updating bandwidth for device csi bandwidth_read: 0 bandwidth_write: 0 bandwidth_peak: 0 bandwidth_rt: 0 14:28:48.441 I csi_driver.cc:1299: Successfully cleaned up CSI context 0xb400007d892ea060 14:28:48.441 I pdp_driver.cc:314: PDP: releasing context 0xb400007dd927dd70 14:28:48.441 I pdp_driver.cc:1044: PDP: about to SetHwEventsEnable to 0 for context 1 14:28:48.441 I g3aa_driver.cc:1014: ------------------------------------------------------ 14:28:48.441 I g3aa_driver.cc:1015: Releasing context 1 14:28:48.441 I g3aa_driver.cc:1019: ------------------------------------------------------ 14:28:48.441 I g3aa_pipeline.cc:796: [Bayer1] Disabling pipeline 14:28:48.441 I g3aa_pipeline.cc:799: [Bayer1] Already disabled 14:28:48.441 I g3aa_pipeline.cc:796: [PDAF1] Disabling pipeline 14:28:48.441 I g3aa_pipeline.cc:799: [PDAF1] Already disabled 14:28:48.441 I g3aa_pipeline.cc:656: [Bayer1] Drain(timeout=0) entered. next_frame_number=14, next_shadow_set=0, last_hw_frame_num=12, last_sw_sof_event_count=12 14:28:48.441 I g3aa_pipeline.cc:387: [Bayer1] There are 0 pending transactions to cancel for frames: [] 14:28:48.441 I g3aa_pipeline.cc:744: [Bayer1] Waiting for pipeline to drain. There are 0 pending transactions for frames: [] 14:28:48.441 I g3aa_pipeline.cc:759: [Bayer1] Pipeline drained. 14:28:48.441 I g3aa_pipeline.cc:710: [Bayer1] Drained; next_frame_number=14, next_shadow_set=0, last_hw_frame_num=12, last_sw_sof_event_count=12 14:28:48.441 I g3aa_pipeline.cc:656: [PDAF1] Drain(timeout=0) entered. next_frame_number=14, next_shadow_set=0, last_hw_frame_num=12, last_sw_sof_event_count=12 14:28:48.441 I g3aa_pipeline.cc:387: [PDAF1] There are 0 pending transactions to cancel for frames: [] 14:28:48.441 I g3aa_pipeline.cc:744: [PDAF1] Waiting for pipeline to drain. There are 0 pending transactions for frames: [] 14:28:48.441 I g3aa_pipeline.cc:759: [PDAF1] Pipeline drained. 14:28:48.441 I g3aa_pipeline.cc:710: [PDAF1] Drained; next_frame_number=14, next_shadow_set=0, last_hw_frame_num=12, last_sw_sof_event_count=12 14:28:48.441 I g3aa_pipeline.cc:337: [Bayer1] Releasing pipeline 14:28:48.441 I g3aa_pipeline.cc:361: [Bayer1] clearing dropped_frames_ queue with [13] since graph stop was called 14:28:48.441 I g3aa_pipeline.cc:2192: [Bayer1][Frame 13][Submission Status: On time] No SOF event generated by HW yet. [Bayer1] Frame Drop Dump; Skipped: [2]. Frame drop rate: 6.67% 14:28:48.441 I g3aa_pipeline.cc:337: [PDAF1] Releasing pipeline 14:28:48.441 I g3aa_pipeline.cc:361: [PDAF1] clearing dropped_frames_ queue with [13] since graph stop was called 14:28:48.441 I g3aa_pipeline.cc:2192: [PDAF1][Frame 13][Submission Status: On time] No SOF event generated by HW yet. [PDAF1] Frame Drop Dump; Skipped: [2]. Frame drop rate: 6.67% 14:28:48.441 I g3aa_driver.cc:2100: Number of active HW contexts: 0, power-up-only contexts: 1. Details: 14:28:48.441 I front_end_controller.cc:2001: dpm: frontend context (all sub-drivers) 2 power mode changed to standy mode 14:28:48.441 I front_end_controller.cc:2005: dpm: signal done_fence 14:28:48.441 I front_end_controller.cc:1727: dpm: node type 1 frontend context 4 was already in deactivate mode 14:28:48.443 I gtnr_merge_driver.cc:874: WhiGtnrMergeDriver dump acquired contexts curr rdma slc buffer pointer: &{type: CachePartition, Size: 1310720, FD: 142, PartitionId: 4, Attributes: Transport: DMA, Access: CPU, Security: None, Compression: None} End of WhiGtnrMergeDriver dump 14:28:48.443 I gtnr_merge_driver.cc:891: Canceling 0 pending command(s) in TNR merge driver for safe power down. 14:28:48.443 I lwis_client_with_event_thread.cc:130: LwisClient(gtnr-merge) event thread is ending . . . 14:28:48.443 I gtnr_merge_driver.cc:1405: TNR powering down hardware 14:28:48.443 I gtnr_merge_driver.cc:1428: TNR disabling hardware 14:28:48.444 I itp_driver.cc:321: ITP powering down and disabling hardware 14:28:48.444 I pdp_driver.cc:314: PDP: releasing context 0xb400007d793ea550 14:28:48.444 I csi_context.cc:704: Camera ID(0) CleanupContextLocked() +++ 14:28:48.444 I csi_context.cc:749: Camera ID(0) dma_to_buffer size: (0) 14:28:48.444 I csi_context.cc:813: Camera ID(0) CleanupContextLocked() --- 14:28:48.444 I csi_driver.cc:1299: Successfully cleaned up CSI context 0xb400007d893353f0 14:28:48.445 I front_end_controller.cc:1587: ResetActivations: session is now empty. clean activations: context 2 14:28:48.445 I csi_context.cc:704: Camera ID(0) CleanupContextLocked() +++ 14:28:48.445 I csi_context.cc:749: Camera ID(0) dma_to_buffer size: (0) 14:28:48.445 I csi_context.cc:813: Camera ID(0) CleanupContextLocked() --- 14:28:48.445 I csi_driver.cc:1299: Successfully cleaned up CSI context 0xb400007d89326680 14:28:48.445 I lwis_client_with_event_thread.cc:130: LwisClient(csi) event thread is ending . . . 14:28:48.446 I pdp_driver.cc:314: PDP: releasing context 0xb400007d793e9200 14:28:48.446 I pdp_driver.cc:485: PDP: about to power down 14:28:48.446 I pdp_driver.cc:495: PDP: about to stop hardware 14:28:48.446 I pdp_driver.cc:498: PDP: finish stopping hardware 14:28:48.446 I pdp_driver.cc:1070: PDP: about to SetLwisEventsEnable to 0 14:28:48.446 I pdp_driver.cc:1086: PDP: finished SetLwisEventsEnable to 0 14:28:48.446 I pdp_driver.cc:506: PDP: about to disable device 14:28:48.446 I pdp_driver.cc:510: PDP: device disabled 14:28:48.446 I lwis_client_with_event_thread.cc:130: LwisClient(pdp) event thread is ending . . . 14:28:48.446 I pdp_driver.cc:527: PDP: finished power down 14:28:48.446 I lwis_client_with_event_thread.cc:130: LwisClient(ipp) event thread is ending . . . 14:28:48.446 I g3aa_driver.cc:1014: ------------------------------------------------------ 14:28:48.446 I g3aa_driver.cc:1015: Releasing context [Power Up] 14:28:48.446 I g3aa_driver.cc:1019: ------------------------------------------------------ 14:28:48.446 I g3aa_driver.cc:2100: Number of active HW contexts: 0, power-up-only contexts: 0. Details: 14:28:48.446 I lwis_client_with_event_thread.cc:130: LwisClient(g3aa) event thread is ending . . . 14:28:48.447 W g3aa_driver.cc:363: DisconnectFromPdp() function is called for Sysreg. 14:28:48.447 W g3aa_driver.cc:376: G3AA disconnected from PDP via Sysreg. G3AA will no longer receive data from PDP. 14:28:48.447 I g3aa_driver.cc:442: Powering down . . . 14:28:48.447 I g3aa_driver.cc:1155: Disabling LWIS events for context 0 14:28:48.447 I g3aa_pipeline.cc:2257: [Bayer0] Pipeline received power down notification 14:28:48.447 I g3aa_pipeline.cc:430: [Bayer0] There is no repeating SOF-sync transaction to cancel 14:28:48.447 I g3aa_pipeline.cc:2257: [PDAF0] Pipeline received power down notification 14:28:48.447 I g3aa_pipeline.cc:430: [PDAF0] There is no repeating SOF-sync transaction to cancel 14:28:48.447 I g3aa_driver.cc:1155: Disabling LWIS events for context 1 14:28:48.447 I g3aa_pipeline.cc:2257: [Bayer1] Pipeline received power down notification 14:28:48.447 I g3aa_pipeline.cc:443: [Bayer1] Canceling repeating SOF-sync transaction id 34481: [OK] 14:28:48.447 I g3aa_pipeline.cc:2257: [PDAF1] Pipeline received power down notification 14:28:48.447 I g3aa_pipeline.cc:443: [PDAF1] Canceling repeating SOF-sync transaction id 34480: [OK] 14:28:48.447 I g3aa_driver.cc:1155: Disabling LWIS events for context 2 14:28:48.447 I g3aa_pipeline.cc:2257: [Bayer2] Pipeline received power down notification 14:28:48.447 I g3aa_pipeline.cc:430: [Bayer2] There is no repeating SOF-sync transaction to cancel 14:28:48.448 I front_end_controller.cc:1315: dpm: successfully released powered up driver contexts. 14:28:48.448 I per_camera_barrier_impl.cc:49: Blocker for FrontEndBlockCam4ToStart released with status: OK 14:28:48.448 I camera_device_session_manager_impl.cc:1087: Successfully stopped graph:top_graph_for_camera_RearMultiFov 14:28:48.448 I processing_graph.cc:302: Starting OutputPort production audit... 14:28:48.449 I processing_graph.cc:366: OutputPort production audit passed, no anomalies detected :) 14:28:48.449 I camera_device_session_manager_impl.cc:923: Attempting to destroy graph:top_graph_for_camera_RearMultiFov 14:28:48.450 D accelerometer: Enter Flush 14:28:48.451 D Layer: im.status.ethereum.pr/im.status.ethereum.MainActivity#51380 had an out of bounds transparent region 14:28:48.451 D Region transparentRegionHint (this=0xb4000072c34074c0, count=2) 14:28:48.451 D [-117, 0, 0, 1080] 14:28:48.451 D [1080, 0, 1803, 1080] 14:28:48.451 D Update Preview stream state to IDLE 14:28:48.452 D accelerometer: Enter Flush 14:28:48.463 I dpm_bandwidth_manager.cc:407: Update minimum MIF clock to 0 number of active sensor: 0 14:28:48.463 I dpm_bandwidth_manager.cc:97: dpm: required_clock is 0 min_frontend_clock_requirement_: 0 sensor streaming: 0 14:28:48.463 I dpm_bandwidth_manager.cc:407: Update minimum MIF clock to 0 number of active sensor: 0 14:28:48.463 I dpm_bandwidth_manager.cc:97: dpm: required_clock is 0 min_frontend_clock_requirement_: 0 sensor streaming: 0 14:28:48.463 D camera vsync: Enter Activate. Enable = 0. 14:28:48.463 D A3:MSG: usf_sensor.cc, 1375: USF: Received stop sampling request for Camera V-Sync 3. 14:28:48.467 D Layer: im.status.ethereum.pr/im.status.ethereum.MainActivity#51380 had an out of bounds transparent region 14:28:48.467 D Region transparentRegionHint (this=0xb4000072c34074c0, count=2) 14:28:48.467 D [-117, 0, 0, 1080] 14:28:48.467 D [1080, 0, 1803, 1080] 14:28:48.469 I dpm_bandwidth_manager.cc:407: Update minimum MIF clock to 0 number of active sensor: 0 14:28:48.469 I dpm_bandwidth_manager.cc:97: dpm: required_clock is 0 min_frontend_clock_requirement_: 0 sensor streaming: 0 14:28:48.469 D camera vsync: Enter Activate. Enable = 0. 14:28:48.470 D A3:MSG: usf_sensor.cc, 1375: USF: Received stop sampling request for Camera V-Sync 1. 14:28:48.484 D Layer: im.status.ethereum.pr/im.status.ethereum.MainActivity#51380 had an out of bounds transparent region 14:28:48.484 D Region transparentRegionHint (this=0xb4000072c34074c0, count=2) 14:28:48.484 D [-117, 0, 0, 1080] 14:28:48.484 D [1080, 0, 1803, 1080] 14:28:48.489 I camera_device_session_manager_impl.cc:935: Successfully destroyed graph:top_graph_for_camera_RearMultiFov 14:28:48.489 I ~ZslSnapshotCaptureSession: finished 14:28:48.490 I camera_device_session_hwl_impl.cc:767: DestroyPipelines: entered, camera_id: 0 14:28:48.490 E camera_device_session_hwl_impl.cc:1451: WaitAndStopLyricGraphs: Failed to session_manager->StopProcessingGraph(), FAILED_PRECONDITION: graph not initialized 14:28:48.490 E camera_device_session_hwl_impl.cc:773: DestroyPipelines: WaitAndStopLyricGraphs() failed!, errno: -19 14:28:48.490 E camera_device_session_hwl_impl.cc:782: DestroyPipelines: Remove camera 0 from ActiveCameraDeviceTracker failed!, errno: -22 14:28:48.490 I I0000 00:00:1695986928.490315 1697 node_profiler.cc:117] enabled=0, broad_cast_period=150, frame_drop_thresh=45 14:28:48.491 I camera_powerhal.cc:159: CancelPowerBoost CAMERA_LAUNCH 14:28:48.491 I camera_powerhal.cc:159: CancelPowerBoost CAMERA_BACKEND_BOOST 14:28:48.491 I camera_powerhal.cc:159: CancelPowerBoost CAMERA_THERMAL_CPU_THROTTLE 14:28:48.491 I camera_powerhal.cc:159: CancelPowerBoost CAMERA_CAPTURE_CPU_THROTTLE 14:28:48.491 I camera_powerhal.cc:159: CancelPowerBoost CAMERA_LAUNCH_EXTENDED 14:28:48.491 I camera_powerhal.cc:159: CancelPowerBoost CAMERA_VIDEO_RECORDING 14:28:48.491 I camera_device_session_hwl_impl.cc:361: ~CameraDeviceSessionHwlImpl: Destroyed CameraDeviceSessionHwlImpl for camera 0 14:28:48.491 I actuator_driver.cc:720: act-rear-normal: Total events dequeued: 61, failed events: 0 14:28:48.496 I ois_driver.cc:1075: ois-rear-normal Success to close IMU gyro chain 14:28:48.496 I ois_driver.cc:377: ois-rear-normal Total event deuqued: 62, failed event: 0 14:28:48.500 W device_context_base.cc:325: NotifyCaptureSessionClose: No OisController found for physical camera RearWide. Status: NOT_FOUND: Failed to find controller by type and camera id(RearWide); failed to get controller with ID: optical image stabilization controller for camera: RearWide from device_context 14:28:48.500 I coordinator.cc:1165: Coordinator::NotifyCaptureSessionClose called for camera: {position: rear, lens_type: multi_fov, spectrum: visible} 14:28:48.500 I rls_manager.cc:234: RlsManager::RlsUnregister(): Rls and Als are not supported, haven't registered 14:28:48.500 I rls_manager.cc:310: RlsManager::TofUnregister(): Tof is not supported, haven't registered 14:28:48.500 I coordinator.cc:1165: Coordinator::NotifyCaptureSessionClose called for camera: {position: rear, lens_type: multi_fov, spectrum: visible} 14:28:48.500 I rls_manager.cc:234: RlsManager::RlsUnregister(): Rls and Als are not supported, haven't registered 14:28:48.500 I rls_manager.cc:310: RlsManager::TofUnregister(): Tof is not supported, haven't registered 14:28:48.500 I coordinator.cc:1165: Coordinator::NotifyCaptureSessionClose called for camera: {position: rear, lens_type: multi_fov, spectrum: visible} 14:28:48.500 I rls_manager.cc:234: RlsManager::RlsUnregister(): Rls and Als are not supported, haven't registered 14:28:48.500 I rls_manager.cc:310: RlsManager::TofUnregister(): Tof is not supported, haven't registered 14:28:48.500 I coordinator.cc:1165: Coordinator::NotifyCaptureSessionClose called for camera: {position: rear, lens_type: multi_fov, spectrum: visible} 14:28:48.500 I rls_manager.cc:234: RlsManager::RlsUnregister(): Rls and Als are not supported, haven't registered 14:28:48.500 I rls_manager.cc:310: RlsManager::TofUnregister(): Tof is not supported, haven't registered 14:28:48.500 I coordinator.cc:1165: Coordinator::NotifyCaptureSessionClose called for camera: {position: rear, lens_type: multi_fov, spectrum: visible} 14:28:48.500 I rls_manager.cc:234: RlsManager::RlsUnregister(): Rls and Als are not supported, haven't registered 14:28:48.500 I rls_manager.cc:310: RlsManager::TofUnregister(): Tof is not supported, haven't registered 14:28:48.501 D ambient light: Enter Activate. Enable = 0. 14:28:48.502 D A3:MSG: usf_sensor.cc, 1375: USF: Received stop sampling request for TMD3719 Ambient Light. 14:28:48.503 I front_als_manager.cc:174: Front ALS sensor disabled 14:28:48.503 D gravity: Enter Activate. Enable = 0. 14:28:48.504 D A3:MSG: usf_sensor.cc, 1375: USF: Received stop sampling request for Gravity Sensor. 14:28:48.504 D A3:MSG: vsc_sensor.cc, 68: VSC: [Gravity Sensor] disable. 14:28:48.505 D A3:MSG: vsc_controller.cc, 555: VSC: [Gravity Sensor] stopSensorEvents: sensor 0x10001 14:28:48.505 D A3:MSG: usf_sensor.cc, 1375: USF: Received stop sampling request for LSM6DSV Accelerometer. 14:28:48.505 D A3:MSG: lsm6dsv_device.cc, 734: USF: Lsm6dsvDevice: Sensor 0: Enabled 1, odr_index 5, period_ns 20000000. 14:28:48.506 D A3:MSG: lsm6dsv_fifo.cc, 752: USF: Lsm6dsvFifo: ODR: XL/G/T/TS: 60/240/15/30 WM: 1 Intr: 240. 14:28:48.506 D A3:MSG: lsm6dsv_device.cc, 643: USF: Lsm6dsvDevice: Fifo was disabled for 612305ns. 14:28:48.507 D A3:MSG: vsc_controller.cc, 555: VSC: [Gravity Sensor] stopSensorEvents: sensor 0x10004 14:28:48.508 D A3:MSG: usf_sensor.cc, 1375: USF: Received stop sampling request for LSM6DSV Gyroscope. 14:28:48.509 D A3:MSG: usf_sensor.cc, 1375: USF: Received stop sampling request for LSM6DSV Gyroscope. 14:28:48.510 D A3:MSG: lsm6dsv_device.cc, 734: USF: Lsm6dsvDevice: Sensor 1: Enabled 1, odr_index 5, period_ns 20000000. 14:28:48.510 D A3:MSG: lsm6dsv_fifo.cc, 752: USF: Lsm6dsvFifo: ODR: XL/G/T/TS: 60/60/15/7 WM: 2 Intr: 60. 14:28:48.511 D A3:MSG: lsm6dsv_device.cc, 643: USF: Lsm6dsvDevice: Fifo was disabled for 504802ns. 14:28:48.511 D device orientation: Enter Batch. period = 66667000, latency = 0. 14:28:48.512 D A3:MSG: usf_sensor.cc, 1883: USF: Received reconfig sampling request for Device Orientation. 14:28:48.512 D A3:MSG: vsc_sensor.cc, 37: VSC: [Device Orientation] enable: period 66667000, latency = 0 14:28:48.513 D ~GoogSensorEnvironment 60 destroy sensor device_orientation 14:28:48.514 D A3:MSG: controller.cc, 273: CNTRL: cmd ID: 0x00d4, tag: 0xa6 [cntr = 91260] 14:28:48.514 D A3:MSG: controller.cc, 811: CNTRL: ipc: control, cmd ID: 0x00d4, tag: 0xa6, rc: 0 14:28:48.516 W graph_runner.cc:179: GraphRunner::StopGraph(1) called on a graph that is not running. 14:28:48.517 I ~StreamBufferCacheManager: Destroying stream buffer cache manager. 14:28:48.518 I WorkloadThreadLoop: Exiting stream buffer cache manager workload thread. 14:28:48.515 W type=1400 audit(0.0:100442): avc: denied { create } for name="profiler" scontext=u:r:hal_camera_default:s0 tcontext=u:object_r:vendor_camera_data_file:s0 tclass=dir permissive=0 14:28:48.519 E camera_latency_analyzer.cc:122: [CPA] Not able to write camera-ended Proto to disk 14:28:48.521 I Stream 0 dequeueBuffer latency histogram (9) samples: 14:28:48.521 I 5 10 15 20 25 30 35 40 45 inf (max ms) 14:28:48.521 I 100.00 0.00 0.00 0.00 0.00 0.00 0.00 0.00 0.00 0.00 (%) 14:28:48.522 I Stream 2 dequeueBuffer latency histogram (9) samples: 14:28:48.522 I 5 10 15 20 25 30 35 40 45 inf (max ms) 14:28:48.522 I 100.00 0.00 0.00 0.00 0.00 0.00 0.00 0.00 0.00 0.00 (%) 14:28:48.522 I disconnectImpl: X 14:28:48.523 I onTorchStatusChangedLocked: Torch status changed for cameraId=0, newStatus=1 14:28:48.524 I disconnect: Disconnected client for camera 0 for PID 13720 14:28:48.525 D Active and attached use case: [] for camera: 0 14:28:48.525 D use count-1, useCount=0 closed=true androidx.camera.core.SurfaceRequest$2@dada10 14:28:48.525 D Surface no longer in use[total_surfaces=3, used_surfaces=2](androidx.camera.core.SurfaceRequest$2@dada10} 14:28:48.525 D Surface terminated[total_surfaces=2, used_surfaces=2](androidx.camera.core.SurfaceRequest$2@dada10} 14:28:48.526 D use count-1, useCount=0 closed=true androidx.camera.core.impl.ImmediateSurface@3b4152f 14:28:48.526 D Surface no longer in use[total_surfaces=2, used_surfaces=1](androidx.camera.core.impl.ImmediateSurface@3b4152f} 14:28:48.526 D Surface terminated[total_surfaces=1, used_surfaces=1](androidx.camera.core.impl.ImmediateSurface@3b4152f} 14:28:48.526 D use count-1, useCount=0 closed=true androidx.camera.core.impl.ImmediateSurface@d17db28 14:28:48.526 D Surface no longer in use[total_surfaces=1, used_surfaces=0](androidx.camera.core.impl.ImmediateSurface@d17db28} 14:28:48.526 D Surface terminated[total_surfaces=0, used_surfaces=0](androidx.camera.core.impl.ImmediateSurface@d17db28} 14:28:48.526 D {Camera@c44877b[id=0]} CameraDevice.onClosed() 14:28:48.526 D Safe to release surface. 14:28:48.526 D {Camera@c44877b[id=0]} Transitioning camera internal state: CLOSING --> INITIALIZED 14:28:48.526 D Recalculating open cameras: Camera State ------------------------------------------------------------------- Camera@d747744[id=1] UNKNOWN Camera@c44877b[id=0] CLOSED ------------------------------------------------------------------- Open count: 0 (Max allowed: 1) 14:28:48.526 D New public camera state CameraState{type=CLOSED, error=null} from CLOSED and null 14:28:48.526 D Publishing new public camera state CameraState{type=CLOSED, error=null} 14:28:48.526 D [6886]> UPDATE_DEVICE_STATE DeviceState: Usb:1, Wifi: 1, Wifi AP: 0, Earpiece: 0, Bluetooth: 0, Wireless charging: 0, Screen: 1, Camera: 0, 0, -, -, Motion: 1, Bluetooth tethering: 0, Voice: 0, Wifi 2.4G: 0, Radio: 1, Bluetooth connected: 0, Bluetooth media: 0, Wifi Band: 2, Wifi AP Band: 0, Wifi call: 0, mmWave: 0, mCellBackhaul: 0, mHallSensor: 0 14:28:48.527 I (REDACTED) Settings changed for uri: %s 14:28:48.527 W Permission failure: android.permission.CAMERA from uid=10162 pid=23423 14:28:48.529 I (REDACTED) Predicting[%d]: %s 14:28:48.531 I (REDACTED) EchoTargets: %s Filtered by AiAi flag check: %s 14:28:48.531 I (REDACTED) Ranked targets strategy: %s, count: %d, ranking metadata: %s 14:28:48.531 I (REDACTED) #weatherTwiddler: Weather removes SubtitleSupplementalItem in surface: %s 14:28:48.532 I (REDACTED) Predicting[%d]: %s 14:28:48.532 I (REDACTED) EchoTargets: %s Filtered by AiAi flag check: %s 14:28:48.533 I (REDACTED) Ranked targets strategy: %s, count: %d, ranking metadata: %s 14:28:48.533 I (REDACTED) Predicting[%d]: %s 14:28:48.534 I (REDACTED) EchoTargets: %s Filtered by AiAi flag check: %s 14:28:48.534 I (REDACTED) Ranked targets strategy: %s, count: %d, ranking metadata: %s 14:28:48.535 I (REDACTED) #weatherTwiddler: Weather removes SubtitleSupplementalItem in surface: %s 14:28:48.535 I (REDACTED) Predicting[%d]: %s 14:28:48.536 I (REDACTED) EchoTargets: %s Filtered by AiAi flag check: %s 14:28:48.536 I (REDACTED) Ranked targets strategy: %s, count: %d, ranking metadata: %s 14:28:48.536 I (REDACTED) #weatherTwiddler: Weather removes SubtitleSupplementalItem in surface: %s 14:28:48.537 I (REDACTED) Predicting[%d]: %s 14:28:48.538 I (REDACTED) EchoTargets: %s Filtered by AiAi flag check: %s 14:28:48.538 I (REDACTED) Ranked targets strategy: %s, count: %d, ranking metadata: %s 14:28:48.538 I (REDACTED) Predicting[%d]: %s 14:28:48.539 I (REDACTED) EchoTargets: %s Filtered by AiAi flag check: %s 14:28:48.539 I (REDACTED) Ranked targets strategy: %s, count: %d, ranking metadata: %s 14:28:48.540 I (REDACTED) #weatherTwiddler: Weather removes SubtitleSupplementalItem in surface: %s 14:28:48.542 I (REDACTED) #postPredictionTargets: Sending updates to UISurface %s with targets# %s (types=[%s]) 14:28:48.543 I (REDACTED) #postPredictionTargets: Sending updates to UISurface %s with targets# %s (types=[%s]) 14:28:48.546 I (REDACTED) #postPredictionTargets: Sending updates to UISurface %s with targets# %s (types=[%s]) 14:28:48.547 D No text view can be set up 14:28:48.547 D Passed-in item info is null 14:28:48.547 D Passed-in item info is null 14:28:48.547 I Secondary card pane is null 14:28:48.548 I (REDACTED) #postPredictionTargets: Sending updates to UISurface %s with targets# %s (types=[%s]) 14:28:48.549 I (REDACTED) #postPredictionTargets: Sending updates to UISurface %s with targets# %s (types=[%s]) 14:28:48.551 I (REDACTED) #postPredictionTargets: Sending updates to UISurface %s with targets# %s (types=[%s]) 14:28:48.552 D No text view can be set up 14:28:48.553 D No text view can be set up 14:28:48.553 D Passed-in item info is null 14:28:48.553 D Passed-in item info is null 14:28:48.553 I Secondary card pane is null 14:28:48.553 D Passed-in item info is null 14:28:48.553 D Passed-in item info is null 14:28:48.553 I Secondary card pane is null 14:28:48.553 D Forwarding Smartspace updates [] 14:28:48.553 D No text view can be set up 14:28:48.553 D Passed-in item info is null 14:28:48.553 D Passed-in item info is null 14:28:48.553 I Secondary card pane is null 14:28:48.554 D Forwarding Smartspace updates [] 14:28:48.580 D [6886]< UPDATE_DEVICE_STATE 14:28:48.606 D DEBUG [utils.re-frame:21] - Handling re-frame event: :syncing/input-connection-string-for-bootstrapping 14:28:48.608 D DEBUG [native-module.core:390] - [native-module] get-device-model-info 14:28:48.614 D DEBUG [native-module.core:38] - [native-module] prepare-dir-and-update-config 14:28:48.614 D prepareDirAndUpdateConfig 14:28:48.615 D Starting Geth node in folder: /data/user/0/im.status.ethereum.pr/no_backup/ethereum/testnet 14:28:48.618 D Can write true 14:28:48.618 D /storage/emulated/0/Android/data/im.status.ethereum.pr/files/Download/geth.log 14:28:48.618 D log dir: /storage/emulated/0/Android/data/im.status.ethereum.pr/files/Download log name: geth.log 14:28:48.619 D startNode() with config (see below) 14:28:48.619 D ********************** NODE CONFIG **************************** 14:28:48.619 D Node config:{"ClusterConfig":{"Enabled":true,"Fleet":"status.prod","DiscV5BootstrapNodes":["enrtree:\/\/AL65EKLJAUXKKPG43HVTML5EFFWEZ7L4LOKTLZCLJASG4DSESQZEC@prod.status.nodes.status.im"],"BootNodes":[],"TrustedMailServers":[],"StaticNodes":[],"RendezvousNodes":[],"WakuNodes":["enrtree:\/\/AL65EKLJAUXKKPG43HVTML5EFFWEZ7L4LOKTLZCLJASG4DSESQZEC@prod.status.nodes.status.im"]},"DataDir":"\/ethereum\/goerli_rpc","LogLevel":"DEBUG","WakuV2Config":{"Port":0,"AutoUpdate":true,"Nameserver":"8.8.8.8","PeerExchange":true,"UDPPort":0,"Enabled":true,"Host":"0.0.0.0","EnableDiscV5":true,"DiscoveryLimit":20},"Rendezvous":false,"LogEnabled":true,"BrowsersConfig":{"Enabled":true},"MailserversConfig":{"Enabled":true},"RequireTopics":{"whisper":{"Min":2,"Max":2}},"WakuConfig":{"Enabled":false,"BloomFilterMode":false,"LightClient":true,"MinimumPoW":1.0E-6},"UpstreamConfig":{"Enabled":true,"URL":"https:\/\/goerli-archival.gateway.pokt.network\/v1\/lb\/1fec70c0d1247dc818107ac7"},"PermissionsConfig":{"Enabled":true},"NetworkId":5,"KeycardPairingDataFile":"\/ethereum\/mainnet_rpc\/keycard\/pairings.json","MaxPeers":20,"Name":"StatusIM","EnableNTPSync":true,"MaxPendingPeers":20,"NoDiscovery":true,"LocalNotificationsConfig":{"Enabled":true},"ShhextConfig":{"VerifyENSURL":"https:\/\/goerli-archival.gateway.pokt.network\/v1\/lb\/1fec70c0d1247dc818107ac7","VerifyTransactionChainID":5,"BackupDisabledDataDir":"\/..\/no_backup","InstallationID":"afc07040-7d43-5573-93b7-057d76251b45","DataSyncEnabled":true,"PFSEnabled":true,"MailServerConfirmations":true,"MaxMessageDeliveryAttempts":6,"VerifyTransactionURL":"https:\/\/goerli-archival.gateway.pokt.network\/v1\/lb\/1fec70c0d1247dc818107ac7","VerifyENSContractAddress":"0x00000000000C2E074eC69A0dFb2997BA6C7d2e1e"},"WalletConfig":{"Enabled":true,"OpenseaAPIKey":"0eab37aa0b2d404b87e7dccc6ac70002"},"StatusAccountsConfig":{"Enabled":true},"KeyStoreDir":"\/keystore","LogDir":"\/storage\/emulated\/0\/Android\/data\/im.status.ethereum.pr\/files\/Download","LogFile":"geth.log"} 14:28:48.619 D ******************* ENDOF NODE CONFIG ************************* 14:28:48.622 D DEBUG [native-module.core:390] - [native-module] get-device-model-info 14:28:48.625 I INFO [native-module.core:286] - [native-module] Sending Connection String {:fn :input-connection-string-for-bootstrapping, :config-json "{\"receiverConfig\":{\"kdfIterations\":3200,\"nodeConfig\":{\"ClusterConfig\":{\"Enabled\":true,\"Fleet\":\"status.prod\",\"DiscV5BootstrapNodes\":[\"enrtree://AL65EKLJAUXKKPG43HVTML5EFFWEZ7L4LOKTLZCLJASG4DSESQZEC@prod.status.nodes.status.im\"],\"BootNodes\":[],\"TrustedMailServers\":[],\"StaticNodes\":[],\"RendezvousNodes\":[],\"WakuNodes\":[\"enrtree://AL65EKLJAUXKKPG43HVTML5EFFWEZ7L4LOKTLZCLJASG4DSESQZEC@prod.status.nodes.status.im\"]},\"DataDir\":\"/ethereum/goerli_rpc\",\"LogLevel\":\"DEBUG\",\"WakuV2Config\":{\"Port\":0,\"AutoUpdate\":true,\"Nameserver\":\"8.8.8.8\",\"PeerExchange\":true,\"UDPPort\":0,\"Enabled\":true,\"Host\":\"0.0.0.0\",\"EnableDiscV5\":true,\"DiscoveryLimit\":20},\"Rendezvous\":false,\"LogEnabled\":true,\"BrowsersConfig\":{\"Enabled\":true},\"MailserversConfig\":{\"Enabled\":true},\"KeyStoreDir\":\"/keystore\",\"RequireTopics\":{\"whisper\":{\"Min\":2,\"Max\":2}},\"WakuConfig\":{\"Enabled\":false,\"BloomFilterMode\":false,\"LightClient\":true,\"MinimumPoW\":0.000001},\"UpstreamConfig\":{\"Enabled\":true,\"URL\":\"https://goerli-archival.gateway.pokt.network/v1/lb/1fec70c0d1247dc818107ac7\"},\"PermissionsConfig\":{\"Enabled\":true},\"NetworkId\":5,\"KeycardPairingDataFile\":\"/ethereum/mainnet_rpc/keycard/pairings.json\",\"MaxPeers\":20,\"Name\":\"StatusIM\",\"EnableNTPSync\":true,\"LogDir\":\"/storage/emulated/0/Android/data/im.status.ethereum.pr/files/Download\",\"MaxPendingPeers\":20,\"NoDiscovery\":true,\"LocalNotificationsConfig\":{\"Enabled\":true},\"ShhextConfig\":{\"VerifyENSURL\":\"https://goerli-archival.gateway.pokt.network/v1/lb/1fec70c0d1247dc818107ac7\",\"VerifyTransactionChainID\":5,\"BackupDisabledDataDir\":\"/../no_backup\",\"InstallationID\":\"afc07040-7d43-5573-93b7-057d76251b45\",\"DataSyncEnabled\":true,\"PFSEnabled\":true,\"MailServerConfirmations\":true,\"MaxMessageDeliveryAttempts\":6,\"VerifyTransactionURL\":\"https://goerli-archival.gateway.pokt.network/v1/lb/1fec70c0d1247dc818107ac7\",\"VerifyENSContractAddress\":\"0x00000000000C2E074eC69A0dFb2997BA6C7d2e1e\"},\"LogFile\":\"geth.log\",\"WalletConfig\":{\"Enabled\":true,\"OpenseaAPIKey\":\"0eab37aa0b2d404b87e7dccc6ac70002\"},\"StatusAccountsConfig\":{\"Enabled\":true}},\"settingCurrentNetwork\":\"goerli_rpc\",\"deviceType\":\"android\",\"deviceName\":\"Pixel 7a lynx\"}}", :connection-string "cs3:scrCv79:Gbq:ragVcaKsBfFiShmyvH1Ez9GAXLCDqTH2LBLMim1qbZYD:BiUXTLQktxyzpeYhSSuFDAts3hvE5RCxccCnDaVJLJs6"} 14:28:48.627 D DEBUG [utils.re-frame:21] - Handling re-frame event: :syncing/update-role 14:28:49.261 D NetReassign [no changes] [c 2] [a 2] [i 5] 14:28:49.385 I I0000 00:00:1695986929.384464 1597 wakelock_manager.cc:46] GXP Interop Adapter released the TPU firmware wakelock. 14:28:49.455 I VIRTUAL-SKIN-CHARGE-WLC:36.5026 raw data: VIRTUAL-SKIN-CHARGE-WLC:36502.6 disp_therm:34822 skin_therm2:38183 usb_pwr_therm2:34688 14:28:49.468 I VIRTUAL-SKIN-CHARGE-WLC power_budget=4797.91 err=2.49739 s_power=2000 time_elapsed_ms=7029 p=1997.91 i=800 d=0 budget transient=0 control target=2 14:28:49.468 I VIRTUAL-SKIN-CHARGE-WLC power budget=4481.72 after (PARTIAL_SYSTEM_POWER: 632.39 mW, cdev_weight: 0.500000) is excluded 14:28:49.468 I VIRTUAL-SKIN-CPU:34.627 raw data: VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-1:34440.3 VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-4:34023.7 VIRTUAL-QUT-SKIN2-USB-GNSS-3:34627 VIRTUAL-QUT-SKIN2-USB-USB2-2:34219 VIRTUAL-SKIN-CPU:34627 14:28:49.468 I VIRTUAL-SKIN:34.627 raw data: VIRTUAL-SKIN:34627 14:28:49.468 I VIRTUAL-SKIN-CHARGE:34.627 raw data: VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-1:34440.3 VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-4:34023.7 VIRTUAL-QUT-SKIN2-USB-GNSS-3:34627 VIRTUAL-QUT-SKIN2-USB-USB2-2:34219 VIRTUAL-SKIN-CHARGE:34627 14:28:49.468 I VIRTUAL-SKIN-CHARGE power_budget=5061.13 err=4.37304 s_power=1200 time_elapsed_ms=7029 p=3061.13 i=800 d=0 budget transient=0 control target=2 14:28:49.469 I VIRTUAL-SKIN-CHARGE power budget=4744.93 after (PARTIAL_SYSTEM_POWER: 632.39 mW, cdev_weight: 0.500000) is excluded 14:28:49.469 I VIRTUAL-SKIN-CPU-GPU:34.627 raw data: VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-1:34440.3 VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-4:34023.7 VIRTUAL-QUT-SKIN2-USB-GNSS-3:34627 VIRTUAL-QUT-SKIN2-USB-USB2-2:34219 VIRTUAL-SKIN-CPU-GPU:34627 14:28:49.469 I VIRTUAL-SKIN-HINT:34.627 raw data: VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-1:34440.3 VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-4:34023.7 VIRTUAL-QUT-SKIN2-USB-GNSS-3:34627 VIRTUAL-QUT-SKIN2-USB-USB2-2:34219 VIRTUAL-SKIN-HINT:34627 14:28:49.469 I cellular-emergency:34.627 raw data: cellular-emergency:34627 14:28:49.469 I skin_therm1:35.957 raw data: skin_therm1:35957 14:28:50.308 I @ 534052.374: [ip] Peak detected: magnitude = 0.126472 hpa, duration = 80.000001 ms 14:28:50.309 I Barometric peak detected: magnitude = 0.126472 hPa (bucket: 3), duration = 80.000000 ms (bucket: 7). 14:28:50.310 I @ 534052.374: [ip] Peak detected: magnitude = 0.122296 hpa, duration = 80.000001 ms 14:28:50.310 I @ 534052.375: [ip] Peak detected: magnitude = 0.302158 hpa, duration = 159.999996 ms 14:28:50.311 I Vendor atom [id = 100047] reported. 14:28:50.311 I Barometric peak detected: magnitude = 0.122297 hPa (bucket: 3), duration = 80.000000 ms (bucket: 7). 14:28:50.312 D Got message from nanoapp: ID 0x476f6f676c001022 14:28:50.312 I Vendor atom [id = 100047] reported. 14:28:50.312 I Barometric peak detected: magnitude = 0.302158 hPa (bucket: 7), duration = 160.000000 ms (bucket: 14). 14:28:50.312 I handleNanoappMessage, Id 5147455389092024354, type 3, size 8 14:28:50.312 I handleNanoappMessage, Id 5147455389092024354, type 3, size 8 14:28:50.312 I handleNanoappMessage, Id 5147455389092024354, type 3, size 8 14:28:50.313 E Cannot send message to unregistered client (host endpoint ID = -28638) 14:28:50.313 I Vendor atom [id = 100047] reported. 14:28:50.313 D Got message from nanoapp: ID 0x476f6f676c001022 14:28:50.314 D Got message from nanoapp: ID 0x476f6f676c001022 14:28:50.315 E Cannot send message to unregistered client (host endpoint ID = -28638) 14:28:50.315 E Cannot send message to unregistered client (host endpoint ID = -28638) 14:28:51.237 D Layer: im.status.ethereum.pr/im.status.ethereum.MainActivity#51380 had an out of bounds transparent region 14:28:51.237 D Region transparentRegionHint (this=0xb4000072c34074c0, count=2) 14:28:51.237 D [-117, 0, 0, 1080] 14:28:51.237 D [1080, 0, 1803, 1080] 14:28:51.629 D Layer: im.status.ethereum.pr/im.status.ethereum.MainActivity#51380 had an out of bounds transparent region 14:28:51.630 D Region transparentRegionHint (this=0xb4000072c34074c0, count=2) 14:28:51.630 D [-117, 0, 0, 1080] 14:28:51.630 D [1080, 0, 1803, 1080] 14:28:51.648 I @ 534053.715: [AR] walking: 77.254903 14:28:52.286 D NetReassign [no changes] [c 1] [a 1] [i 4] 14:28:52.586 I 5095 [PROX] Received onSignalStrengthsChanged [CellSignalStrengthLte: rssi=-67 rsrp=-96 rsrq=-10 rssnr=11 cqiTableIndex=1 cqi=15 ta=4 level=4 parametersUseForLevel=0] [SLID:0 APM:false] (ImsConnectivityProxyListener$ImsPhoneStateListener%onSignalStrengthsChanged:511) 14:28:53.320 D Layer: im.status.ethereum.pr/im.status.ethereum.MainActivity#51380 had an out of bounds transparent region 14:28:53.320 D Region transparentRegionHint (this=0xb4000072c34074c0, count=2) 14:28:53.320 D [-117, 0, 0, 1080] 14:28:53.320 D [1080, 0, 1803, 1080] 14:28:53.794 D A3:MSG: lsm6dsv_device.cc, 734: USF: Lsm6dsvDevice: Sensor 4: Enabled 0, odr_index 0, period_ns 0. 14:28:53.794 D A3:MSG: lsm6dsv_fifo.cc, 752: USF: Lsm6dsvFifo: ODR: XL/G/T/TS: 60/60/15/7 WM: 2 Intr: 60. 14:28:53.796 D A3:MSG: lsm6dsv_device.cc, 643: USF: Lsm6dsvDevice: Fifo was disabled for 1148397ns. 14:28:53.796 D A3:MSG: usf_sensor.cc, 1375: USF: Received stop sampling request for LSM6DSV Stationary Detect. 14:28:53.797 D A3:ERR: usf_sensor.cc, 1835: USF: Sampling configuration 59837 not found. 14:28:53.797 D A3:ERR: usf_vs_helper.cc, 430: USF: Received stop sampling request error. (7) 14:28:53.797 D A3:MSG: usf_sensor.cc, 1368: USF: Received start sampling request for LSM6DSV Motion Detect. 14:28:53.797 D A3:MSG: lsm6dsv_device.cc, 734: USF: Lsm6dsvDevice: Sensor 3: Enabled 1, odr_index 1, period_ns 500000000. 14:28:53.797 D A3:MSG: lsm6dsv_fifo.cc, 752: USF: Lsm6dsvFifo: ODR: XL/G/T/TS: 60/60/15/7 WM: 2 Intr: 60. 14:28:53.799 D A3:MSG: lsm6dsv_device.cc, 643: USF: Lsm6dsvDevice: Fifo was disabled for 924479ns. 14:28:53.801 D A3:MSG: usf_sensor.cc, 1368: USF: Received start sampling request for LSM6DSV Motion Detect. 14:28:53.802 D A3:MSG: usf_sensor.cc, 1375: USF: Received stop sampling request for LSM6DSV Stationary Detect. 14:28:53.802 D A3:ERR: usf_sensor.cc, 1835: USF: Sampling configuration 59838 not found. 14:28:54.830 I @ 534056.896: [AR] inconsistent: 41.176471 14:28:54.924 V Transmission is done. 14:28:54.930 V Transmission is done. 14:28:54.931 V Transmission is done. 14:28:55.336 D NetReassign [no changes] [c 3] [a 2] [i 14] 14:28:56.480 I VIRTUAL-SKIN-CHARGE-WLC:36.9219 raw data: VIRTUAL-SKIN-CHARGE-WLC:36921.9 disp_therm:34844 skin_therm2:38984 usb_pwr_therm2:34710 14:28:56.490 I VIRTUAL-SKIN-CHARGE-WLC power_budget=4462.48 err=2.0781 s_power=2000 time_elapsed_ms=7025 p=1662.48 i=800 d=-0 budget transient=0 control target=2 14:28:56.490 I VIRTUAL-SKIN-CHARGE-WLC power budget=4146.28 after (PARTIAL_SYSTEM_POWER: 632.39 mW, cdev_weight: 0.500000) is excluded 14:28:56.493 I VIRTUAL-SKIN-CPU:35.0641 raw data: VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-1:34685.4 VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-4:34224.6 VIRTUAL-QUT-SKIN2-USB-GNSS-3:35064.1 VIRTUAL-QUT-SKIN2-USB-USB2-2:34331.1 VIRTUAL-SKIN-CPU:35064.1 gnss_tcxo_therm:37204 neutral_therm:37003 quiet_therm:34844 skin_therm1:36180 skin_therm2:38984 usb_pwr_therm:34443 usb_pwr_therm2:34710 14:28:56.493 I VIRTUAL-SKIN:35.0641 raw data: VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-1:34685.4 VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-4:34224.6 VIRTUAL-QUT-SKIN2-USB-GNSS-3:35064.1 VIRTUAL-QUT-SKIN2-USB-USB2-2:34331.1 VIRTUAL-SKIN:35064.1 14:28:56.493 I VIRTUAL-SKIN-CHARGE:35.0641 raw data: VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-1:34685.4 VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-4:34224.6 VIRTUAL-QUT-SKIN2-USB-GNSS-3:35064.1 VIRTUAL-QUT-SKIN2-USB-USB2-2:34331.1 VIRTUAL-SKIN-CHARGE:35064.1 14:28:56.494 I VIRTUAL-SKIN-CHARGE power_budget=4755.13 err=3.9359 s_power=1200 time_elapsed_ms=7025 p=2755.13 i=800 d=-0 budget transient=0 control target=2 14:28:56.494 I VIRTUAL-SKIN-CHARGE power budget=4438.93 after (PARTIAL_SYSTEM_POWER: 632.39 mW, cdev_weight: 0.500000) is excluded 14:28:56.494 I VIRTUAL-SKIN-CPU-GPU:35.0641 raw data: VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-1:34685.4 VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-4:34224.6 VIRTUAL-QUT-SKIN2-USB-GNSS-3:35064.1 VIRTUAL-QUT-SKIN2-USB-USB2-2:34331.1 VIRTUAL-SKIN-CPU-GPU:35064.1 14:28:56.494 I VIRTUAL-SKIN-HINT:35.0641 raw data: VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-1:34685.4 VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-4:34224.6 VIRTUAL-QUT-SKIN2-USB-GNSS-3:35064.1 VIRTUAL-QUT-SKIN2-USB-USB2-2:34331.1 VIRTUAL-SKIN-HINT:35064.1 14:28:56.494 I cellular-emergency:35.0641 raw data: VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-1:34685.4 VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-4:34224.6 VIRTUAL-QUT-SKIN2-USB-GNSS-3:35064.1 VIRTUAL-QUT-SKIN2-USB-USB2-2:34331.1 cellular-emergency:35064.1 14:28:56.495 I skin_therm1:36.18 raw data: skin_therm1:36180 14:28:56.496 I Successfully update cdev fcc sysfs to 1 14:28:56.548 D Signal event 14:28:56.549 E failed to connect to any of given addresses. failed to connect to any of given addresses. connecting to 'https://192.168.0.103:52480' failed: dial tcp 192.168.0.103:52480: connect: no route to host; 14:28:56.566 I INFO [status-im2.contexts.syncing.events:72] - Initiated local pairing {:response "{\"error\":\"failed to connect to any of given addresses. failed to connect to any of given addresses. connecting to 'https://192.168.0.103:52480' failed: dial tcp 192.168.0.103:52480: connect: no route to host; \"}", :event :syncing/input-connection-string-for-bootstrapping} 14:28:56.576 D DEBUG [utils.re-frame:21] - Handling re-frame event: :signals/signal-received 14:28:56.587 I INFO [status-im.signals.core:33] - local pairing signal received {:event {:type "connection-error", :error "failed to connect to any of given addresses. failed to connect to any of given addresses. connecting to 'https://192.168.0.103:52480' failed: dial tcp 192.168.0.103:52480: connect: no route to host; ", :action 1}} 14:28:56.609 D DEBUG [utils.re-frame:21] - Handling re-frame event: :navigate-to 14:28:56.684 D DEBUG [utils.re-frame:21] - Handling re-frame event: :hide-bottom-sheet 14:28:56.778 W initializeMessageQueueThreads() is called. 14:28:56.780 I Running "syncing-progress" with {"initialProps":{"componentId":"syncing-progress"},"rootTag":31} 14:28:56.864 I [GESTURE HANDLER] Initialize gesture handler for root view com.reactnativenavigation.react.ReactView{d743ad8 V.E...... ......ID 0,0-1080,2337 #1f} 14:28:56.864 E [857] omb.f: Failed to connect to server for server timestamp: java.net.UnknownHostException: Unable to resolve host "play.googleapis.com": No address associated with hostname 14:28:56.867 E [856] omb.f: Failed to connect to server for server timestamp: java.net.UnknownHostException: Unable to resolve host "play.googleapis.com": No address associated with hostname 14:28:56.876 E [856] omb.f: Failed to connect to server for log upload. 14:28:56.876 E [857] omb.f: Failed to connect to server for log upload. 14:28:56.955 D DEBUG [utils.re-frame:21] - Handling re-frame event: :set-view-id 14:28:56.963 D DEBUG [utils.re-frame:21] - Handling re-frame event: :screens/on-will-focus 14:28:56.964 D DEBUG [utils.re-frame:21] - Handling re-frame event: :onboarding/overlay-dismiss 14:28:56.972 I [GESTURE HANDLER] Tearing down gesture handler registered for root view com.reactnativenavigation.react.ReactView{f1cea50 V.E...... ......ID 0,0-1080,2337 #15} 14:28:57.083 D DEBUG [utils.re-frame:21] - Handling re-frame event: :set-view-id 14:28:57.085 D DEBUG [utils.re-frame:21] - Handling re-frame event: :screens/on-will-focus 14:28:58.359 D A3:MSG: lsm6dsv_device.cc, 734: USF: Lsm6dsvDevice: Sensor 3: Enabled 0, odr_index 0, period_ns 0. 14:28:58.359 D A3:MSG: lsm6dsv_fifo.cc, 752: USF: Lsm6dsvFifo: ODR: XL/G/T/TS: 60/60/15/7 WM: 2 Intr: 60. 14:28:58.360 D A3:MSG: lsm6dsv_device.cc, 643: USF: Lsm6dsvDevice: Fifo was disabled for 955485ns. 14:28:58.361 D A3:MSG: usf_sensor.cc, 1375: USF: Received stop sampling request for LSM6DSV Motion Detect. 14:28:58.362 D A3:ERR: usf_sensor.cc, 1835: USF: Sampling configuration 59851 not found. 14:28:58.362 D A3:ERR: usf_vs_helper.cc, 430: USF: Received stop sampling request error. (7) 14:28:58.362 D A3:MSG: usf_sensor.cc, 1368: USF: Received start sampling request for LSM6DSV Stationary Detect. 14:28:58.363 D A3:MSG: lsm6dsv_device.cc, 734: USF: Lsm6dsvDevice: Sensor 4: Enabled 1, odr_index 1, period_ns 500000000. 14:28:58.363 D A3:MSG: lsm6dsv_fifo.cc, 752: USF: Lsm6dsvFifo: ODR: XL/G/T/TS: 60/60/15/7 WM: 2 Intr: 60. 14:28:58.365 D A3:MSG: lsm6dsv_device.cc, 643: USF: Lsm6dsvDevice: Fifo was disabled for 951782ns. 14:28:58.367 D A3:MSG: usf_sensor.cc, 1368: USF: Received start sampling request for LSM6DSV Stationary Detect. 14:28:58.367 D A3:MSG: usf_sensor.cc, 1375: USF: Received stop sampling request for LSM6DSV Motion Detect. 14:28:58.367 D A3:ERR: usf_sensor.cc, 1835: USF: Sampling configuration 59852 not found. 14:28:58.447 D NetReassign [no changes] [c 4] [a 5] [i 18] 14:28:59.792 D onUnbind: Intent { act=com.google.android.gms.pay.service.BIND dat=chimera-action:/... cmp=com.google.android.gms/.chimera.GmsApiService } 14:29:00.018 I (REDACTED) Predicting[%d]: %s 14:29:00.019 I (REDACTED) EchoTargets: %s Filtered by AiAi flag check: %s 14:29:00.020 I (REDACTED) Ranked targets strategy: %s, count: %d, ranking metadata: %s 14:29:00.021 I (REDACTED) #weatherTwiddler: Weather removes SubtitleSupplementalItem in surface: %s 14:29:00.022 I (REDACTED) Predicting[%d]: %s 14:29:00.023 I (REDACTED) EchoTargets: %s Filtered by AiAi flag check: %s 14:29:00.028 I (REDACTED) Ranked targets strategy: %s, count: %d, ranking metadata: %s 14:29:00.029 I (REDACTED) #weatherTwiddler: Weather removes SubtitleSupplementalItem in surface: %s 14:29:00.033 I (REDACTED) #postPredictionTargets: Sending updates to UISurface %s with targets# %s (types=[%s]) 14:29:00.046 I (REDACTED) #postPredictionTargets: Sending updates to UISurface %s with targets# %s (types=[%s]) 14:29:00.051 D No text view can be set up 14:29:00.052 D Passed-in item info is null 14:29:00.053 D Passed-in item info is null 14:29:00.053 I Secondary card pane is null 14:29:00.062 D No text view can be set up 14:29:00.063 D Passed-in item info is null 14:29:00.063 D Passed-in item info is null 14:29:00.063 I Secondary card pane is null 14:29:00.296 I oneway function results for code 2 on binder at 0xb400006d96239c10 will be dropped but finished with status UNKNOWN_TRANSACTION 14:29:00.352 I handleNanoappMessage, Id 5147455389092024354, type 3, size 8 14:29:00.352 I Barometric peak detected: magnitude = 0.224878 hPa (bucket: 5), duration = 200.000000 ms (bucket: 18). 14:29:00.352 I @ 534062.418: [ip] Peak detected: magnitude = 0.224877 hpa, duration = 200.000002 ms 14:29:00.353 D Got message from nanoapp: ID 0x476f6f676c001022 14:29:00.353 E Cannot send message to unregistered client (host endpoint ID = -28638) 14:29:00.354 I Vendor atom [id = 100047] reported. 14:29:01.533 D NetReassign [no changes] [c 5] [a 7] [i 10] 14:29:02.373 I I0000 00:00:1695986942.373028 1613 tf_lite_classifier.cc:400] Event stream classified as kNone 14:29:02.409 D DEBUG [utils.re-frame:21] - Handling re-frame event: :syncing/clear-states 14:29:02.437 D DEBUG [utils.re-frame:21] - Handling re-frame event: :navigate-back-to 14:29:03.506 I VIRTUAL-SKIN-CHARGE-WLC:36.7913 raw data: VIRTUAL-SKIN-CHARGE-WLC:36791.3 disp_therm:34844 skin_therm2:38695 usb_pwr_therm2:34777 14:29:03.529 I VIRTUAL-SKIN-CHARGE-WLC power_budget=4566.99 err=2.20874 s_power=2000 time_elapsed_ms=7025 p=1766.99 i=800 d=0 budget transient=0 control target=2 14:29:03.529 I VIRTUAL-SKIN-CHARGE-WLC power budget=4288.03 after (PARTIAL_SYSTEM_POWER: 557.93 mW, cdev_weight: 0.500000) is excluded 14:29:03.533 I VIRTUAL-SKIN-CPU:34.9226 raw data: VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-1:34629.4 VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-4:34190.6 VIRTUAL-QUT-SKIN2-USB-GNSS-3:34922.6 VIRTUAL-QUT-SKIN2-USB-USB2-2:34341.3 VIRTUAL-SKIN-CPU:34922.6 gnss_tcxo_therm:37025 neutral_therm:36803 quiet_therm:34955 skin_therm1:36135 skin_therm2:38695 usb_pwr_therm:34443 usb_pwr_therm2:34777 14:29:03.534 I VIRTUAL-SKIN:34.9226 raw data: VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-1:34629.4 VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-4:34190.6 VIRTUAL-QUT-SKIN2-USB-GNSS-3:34922.6 VIRTUAL-QUT-SKIN2-USB-USB2-2:34341.3 VIRTUAL-SKIN:34922.6 14:29:03.534 I VIRTUAL-SKIN-CHARGE:34.9226 raw data: VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-1:34629.4 VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-4:34190.6 VIRTUAL-QUT-SKIN2-USB-GNSS-3:34922.6 VIRTUAL-QUT-SKIN2-USB-USB2-2:34341.3 VIRTUAL-SKIN-CHARGE:34922.6 14:29:03.534 I VIRTUAL-SKIN-CHARGE power_budget=4854.19 err=4.07741 s_power=1200 time_elapsed_ms=7025 p=2854.19 i=800 d=0 budget transient=0 control target=2 14:29:03.534 I VIRTUAL-SKIN-CHARGE power budget=4575.22 after (PARTIAL_SYSTEM_POWER: 557.93 mW, cdev_weight: 0.500000) is excluded 14:29:03.534 I VIRTUAL-SKIN-CPU-GPU:34.9226 raw data: VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-1:34629.4 VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-4:34190.6 VIRTUAL-QUT-SKIN2-USB-GNSS-3:34922.6 VIRTUAL-QUT-SKIN2-USB-USB2-2:34341.3 VIRTUAL-SKIN-CPU-GPU:34922.6 14:29:03.535 I VIRTUAL-SKIN-HINT:34.9226 raw data: VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-1:34629.4 VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-4:34190.6 VIRTUAL-QUT-SKIN2-USB-GNSS-3:34922.6 VIRTUAL-QUT-SKIN2-USB-USB2-2:34341.3 VIRTUAL-SKIN-HINT:34922.6 14:29:03.535 I cellular-emergency:34.9226 raw data: VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-1:34629.4 VIRTUAL-NEU-QUT-SKIN1-SKIN2-USB-USB2-GNSS-4:34190.6 VIRTUAL-QUT-SKIN2-USB-GNSS-3:34922.6 VIRTUAL-QUT-SKIN2-USB-USB2-2:34341.3 cellular-emergency:34922.6 14:29:03.535 I skin_therm1:36.135 raw data: skin_therm1:36135 14:29:04.029 D A3:MSG: lsm6dsv_device.cc, 734: USF: Lsm6dsvDevice: Sensor 4: Enabled 0, odr_index 0, period_ns 0. 14:29:04.030 D A3:MSG: lsm6dsv_fifo.cc, 752: USF: Lsm6dsvFifo: ODR: XL/G/T/TS: 60/60/15/7 WM: 2 Intr: 60. 14:29:04.032 D A3:MSG: lsm6dsv_device.cc, 643: USF: Lsm6dsvDevice: Fifo was disabled for 1235637ns. 14:29:04.033 D A3:MSG: usf_sensor.cc, 1375: USF: Received stop sampling request for LSM6DSV Stationary Detect. 14:29:04.033 D A3:ERR: usf_sensor.cc, 1835: USF: Sampling configuration 59853 not found. 14:29:04.033 D A3:ERR: usf_vs_helper.cc, 430: USF: Received stop sampling request error. (7) 14:29:04.033 D A3:MSG: usf_sensor.cc, 1368: USF: Received start sampling request for LSM6DSV Motion Detect. 14:29:04.034 D A3:MSG: lsm6dsv_device.cc, 734: USF: Lsm6dsvDevice: Sensor 3: Enabled 1, odr_index 1, period_ns 500000000. 14:29:04.034 D A3:MSG: lsm6dsv_fifo.cc, 752: USF: Lsm6dsvFifo: ODR: XL/G/T/TS: 60/60/15/7 WM: 2 Intr: 60. 14:29:04.034 D A3:MSG: lsm6dsv_device.cc, 643: USF: Lsm6dsvDevice: Fifo was disabled for 1117269ns. 14:29:04.038 D A3:MSG: usf_sensor.cc, 1368: USF: Received start sampling request for LSM6DSV Motion Detect. 14:29:04.038 D A3:MSG: usf_sensor.cc, 1375: USF: Received stop sampling request for LSM6DSV Stationary Detect. 14:29:04.038 D A3:ERR: usf_sensor.cc, 1835: USF: Sampling configuration 59854 not found. 14:29:04.364 I handleNanoappMessage, Id 5147455389092024352, type 200, size 2 14:29:04.364 D handleNanoappMessage, get position event from capo nanoapp, type 2 14:29:04.364 D handleNanoappMessage, sent position type 2 to callback function 14:29:04.364 D Vibrator capoEventCallback, From: 0x1 To: 0x2 14:29:04.365 I @ 534066.429: [AR] walking: 77.647054 14:29:04.365 I @ 534066.429: [Capo] MD nanoapp declared stationary at = 534066429805740 14:29:04.365 I @ 534066.429: [Capo] PositionDetector mode change from 0 --> 1 14:29:04.365 I @ 534066.430: [Capo] Update onTable position event: 2, z_axis=9.805142 (#sample 25) 14:29:04.365 I @ 534066.430: [Capo] Current position: 2, IsHostAwake: 1 14:29:04.365 I @ 534066.430: [Capo] Send position type 2 to the client 14:29:04.366 D Got message from nanoapp: ID 0x476f6f676c001020 14:29:04.368 E Cannot send message to unregistered client (host endpoint ID = -28640) 14:29:04.478 W Possible Unhandled Promise Rejection (id: 0): Error: Failed to execute stack command. Stack by sign-in not found. Error: Failed to execute stack command. Stack by sign-in not found. at promiseMethodWrapper (/build/android/app/build/generated/assets/react/pr/index.android.bundle:155078:45) at popTo (/build/android/app/build/generated/assets/react/pr/index.android.bundle:263677:47) at popTo (/build/android/app/build/generated/assets/react/pr/index.android.bundle:269561:53) at popTo (/build/android/app/build/generated/assets/react/pr/index.android.bundle:263962:35) at popTo (/build/android/app/build/generated/assets/react/pr/index.android.bundle:263561:45) at anonymous (/build/android/app/build/generated/assets/react/pr/index.android.bundle:106541:87) at anonymous (/build/android/app/build/generated/assets/react/pr/index.android.bundle:87013:197) at kja (/build/android/app/build/generated/assets/react/pr/index.android.bundle:9239:36) at nja (/build/android/app/build/generated/assets/react/pr/index.android.bundle:9255:14) at wja (/build/android/app/build/generated/assets/react/pr/index.android.bundle:9365:12) at xja (/build/android/app/build/generated/assets/react/pr/index.android.bundle:9306:12) at anonymous (/build/android/app/build/generated/assets/react/pr/index.android.bundle:9338:21) at Ak (/build/android/app/build/generated/assets/react/pr/index.android.bundle:9354:36) at b (/build/android/app/build/generated/assets/react/pr/index.android.bundle:9312:18) at apply (native) at anonymous (/build/android/app/build/generated/assets/react/pr/index.android.bundle:177973:26) at _callTimer (/build/android/app/build/generated/assets/react/pr/index.android.bundle:177912:17) at callTimers (/build/android/app/build/generated/assets/react/pr/index.android.bundle:178068:19) at apply (native) at __callFunction (/build/android/app/build/generated/assets/react/pr/index.android.bundle:155419:36) at anonymous (/build/android/app/build/generated/assets/react/pr/index.android.bundle:155188:31) at __guard (/build/android/app/build/generated/assets/react/pr/index.android.bundle:155378:15) at callFunctionReturnFlushedQueue (/build/android/app/build/generated/assets/react/pr/index.android.bundle:155187:21) 14:29:04.620 D NetReassign [no changes] [c 4] [a 3] [i 10]