search

RedApparat / Fotoapparat

Making Camera for Android more friendly. 📸
Apache License 2.0
3.83k stars 408 forks source link

With camera1, getting "IllegalStateException: Camera error code: 1" #26

Closed hagabaka closed 7 years ago

hagabaka commented 7 years ago

Since d8fad94, my app now crashes with this backtrace:

java.lang.IllegalStateException: Camera error code: 1
 at io.fotoapparat.hardware.v1.Camera1$1.onError(Camera1.java:73)
 at android.hardware.Camera$EventHandler.handleMessage(Camera.java:1146)
 at android.os.Handler.dispatchMessage(Handler.java:102)
 at android.os.Looper.loop(Looper.java:154)
 at android.app.ActivityThread.main(ActivityThread.java:6121)
 at java.lang.reflect.Method.invoke(Native Method)
 at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:889)
 at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:779)

When camera2 was the default, the app did not have this problem.

dmitry-zaitsev commented 7 years ago

Could you please also attach all logs with Fotoapparat tag? Or just all logs.

You also have an option to switch back to Camera2, if you like. That's how you can do it:

builder
    .cameraProvider(v2(context))
hagabaka commented 7 years ago

Thanks! Specifying v2 cameraProvider worked, but I hope to figure out how to fix it for v1 too.

Here's the full log in Android Studio if I select "Show only selected application". 

05-19 15:57:14.943 4343-4343/? I/art: Late-enabling -Xcheck:jni
05-19 15:57:15.083 4343-4343/? W/System: ClassLoader referenced unknown path: /data/app/com.my.app/lib/arm64
05-19 15:57:15.102 4343-4343/? I/InstantRun: starting instant run server: is main process
05-19 15:57:15.132 4343-4350/? I/art: Debugger is no longer active
05-19 15:57:15.132 4343-4350/? I/art: Starting a blocking GC Instrumentation
05-19 15:57:15.165 4343-4343/? W/art: Before Android 4.1, method android.graphics.PorterDuffColorFilter android.support.graphics.drawable.VectorDrawableCompat.updateTintFilter(android.graphics.PorterDuffColorFilter, android.content.res.ColorStateList, android.graphics.PorterDuff$Mode) would have incorrectly overridden the package-private method in android.graphics.drawable.Drawable
05-19 15:57:15.428 4343-4364/? I/Adreno: QUALCOMM build                   : 6818200, Idb2b4cb785
                                         Build Date                       : 11/17/16
                                         OpenGL ES Shader Compiler Version: XE031.09.00.04
                                         Local Branch                     : N25
                                         Remote Branch                    : 
                                         Remote Branch                    : 
                                         Reconstruct Branch               : 
05-19 15:57:15.431 4343-4364/? I/OpenGLRenderer: Initialized EGL, version 1.4
05-19 15:57:15.431 4343-4364/? D/OpenGLRenderer: Swap behavior 1
05-19 15:57:15.471 4343-4343/? W/art: Before Android 4.1, method int android.support.v7.widget.ListViewCompat.lookForSelectablePosition(int, boolean) would have incorrectly overridden the package-private method in android.widget.ListView
05-19 15:57:16.218 4343-4343/com.my.app E/Camera: Error 1
05-19 15:57:16.218 4343-4343/com.my.app W/System.err: java.lang.Exception
05-19 15:57:16.218 4343-4343/com.my.app W/System.err:     at io.fotoapparat.hardware.v1.Camera1.recordMethod(Camera1.java:304)
05-19 15:57:16.218 4343-4343/com.my.app W/System.err:     at io.fotoapparat.hardware.v1.Camera1.startPreview(Camera1.java:114)
05-19 15:57:16.218 4343-4343/com.my.app W/System.err:     at io.fotoapparat.routine.StartCameraRoutine.run(StartCameraRoutine.java:47)
05-19 15:57:16.218 4343-4343/com.my.app W/System.err:     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133)
05-19 15:57:16.218 4343-4343/com.my.app W/System.err:     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607)
05-19 15:57:16.218 4343-4343/com.my.app W/System.err:     at java.lang.Thread.run(Thread.java:761)
05-19 15:57:16.218 4343-4343/com.my.app D/AndroidRuntime: Shutting down VM
05-19 15:57:16.219 4343-4343/com.my.app E/AndroidRuntime: FATAL EXCEPTION: main
  Process: com.my.app, PID: 4343
  java.lang.IllegalStateException: Camera error code: 1
      at io.fotoapparat.hardware.v1.Camera1$1.onError(Camera1.java:73)
      at android.hardware.Camera$EventHandler.handleMessage(Camera.java:1146)
      at android.os.Handler.dispatchMessage(Handler.java:102)
      at android.os.Looper.loop(Looper.java:154)
      at android.app.ActivityThread.main(ActivityThread.java:6121)
      at java.lang.reflect.Method.invoke(Native Method)
      at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:889)
      at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:779)

If I turn off filters, I also see this before the above:

05-19 16:03:22.249 671-23189/? I/CameraService: CameraService::connect call (PID -1 "com.my.app", camera ID 0) for HAL version default and Camera API version 1
05-19 16:03:22.261 679-10137/? D/NuPlayerDriver: notifyListener_l(0xe3899c00), (1, 0, 0), loop setting(0, 0)
05-19 16:03:22.274 679-10139/? D/NuPlayerDriver: notifyListener_l(0xe3899ae0), (1, 0, 0), loop setting(0, 0)
05-19 16:03:22.285 679-10141/? D/NuPlayerDriver: notifyListener_l(0xe3899120), (1, 0, 0), loop setting(0, 0)
05-19 16:03:22.285 671-23189/? I/Camera2ClientBase: Camera 0: Opened. Client: com.my.app (PID 10093, UID 10193)
05-19 16:03:22.287 10093-10135/com.my.app I/Adreno: QUALCOMM build                   : 6818200, Idb2b4cb785
                                                                Build Date                       : 11/17/16
                                                                OpenGL ES Shader Compiler Version: XE031.09.00.04
                                                                Local Branch                     : N25
                                                                Remote Branch                    : 
                                                                Remote Branch                    : 
                                                                Reconstruct Branch               : 
05-19 16:03:22.287 671-23189/? I/CameraService: onTorchStatusChangedLocked: Torch status changed for cameraId=0, newStatus=0
05-19 16:03:22.289 1164-29056/? W/ActivityManager: Unable to start service Intent { cmp=com.google.android.apps.photos/.cameraassistant.CameraAssistantService (has extras) } U=0: not found
05-19 16:03:22.290 10093-10135/com.my.app I/OpenGLRenderer: Initialized EGL, version 1.4
05-19 16:03:22.290 10093-10135/com.my.app D/OpenGLRenderer: Swap behavior 1
05-19 16:03:22.291 671-23189/? I/QCamera: <HAL><INFO> int qcamera::QCamera2Factory::cameraDeviceOpen(int, struct hw_device_t **): 386: Open camera id 0 API version 768
05-19 16:03:22.303 671-23189/? I/QCamera: <HAL><INFO> int qcamera::QCamera3HardwareInterface::openCamera(struct hw_device_t **): 671: [KPI Perf]: E PROFILE_OPEN_CAMERA camera id 0
05-19 16:03:22.303 671-23189/? I/CameraService: onTorchStatusChangedLocked: Torch status changed for cameraId=0, newStatus=0
05-19 16:03:22.303 671-23189/? I/mm-camera: <MCT   >< INFO> 64: mct_controller_new: Creating new mct_controller with session-id 2
05-19 16:03:22.304 671-10144/? I/mm-camera: <MCT   >< INFO> 4158: mct_pipeline_start_session_thread: E sensor
05-19 16:03:22.304 671-10144/? I/mm-camera: <MCT   >< INFO> 4165: mct_pipeline_start_session_thread: Calling start_session on Module sensor
05-19 16:03:22.304 671-10145/? I/mm-camera: <MCT   >< INFO> 4158: mct_pipeline_start_session_thread: E iface
05-19 16:03:22.304 671-10145/? I/mm-camera: <MCT   >< INFO> 4165: mct_pipeline_start_session_thread: Calling start_session on Module iface
05-19 16:03:22.305 671-10147/? I/mm-camera: <MCT   >< INFO> 4158: mct_pipeline_start_session_thread: E isp
05-19 16:03:22.305 671-10145/? I/mm-camera: <MCT   >< INFO> 4168: mct_pipeline_start_session_thread: Module iface start_session rc = 1
05-19 16:03:22.305 671-10145/? I/mm-camera: <MCT   >< INFO> 4176: mct_pipeline_start_session_thread: started_num = 1, success = 1
05-19 16:03:22.305 671-10145/? I/mm-camera: <MCT   >< INFO> 4183: mct_pipeline_start_session_thread: X iface
05-19 16:03:22.307 671-10147/? I/mm-camera: <MCT   >< INFO> 4165: mct_pipeline_start_session_thread: Calling start_session on Module isp
05-19 16:03:22.307 671-10147/? I/mm-camera: <ISP   >< INFO> 190: isp_module_start_session: session id 2
05-19 16:03:22.308 671-10149/? I/mm-camera: <MCT   >< INFO> 4158: mct_pipeline_start_session_thread: E stats
05-19 16:03:22.308 671-10149/? I/mm-camera: <MCT   >< INFO> 4165: mct_pipeline_start_session_thread: Calling start_session on Module stats
05-19 16:03:22.309 671-10150/? I/mm-camera: <MCT   >< INFO> 4158: mct_pipeline_start_session_thread: E pproc
05-19 16:03:22.309 671-10150/? I/mm-camera: <MCT   >< INFO> 4165: mct_pipeline_start_session_thread: Calling start_session on Module pproc
05-19 16:03:22.310 671-10151/? I/mm-camera: <MCT   >< INFO> 4158: mct_pipeline_start_session_thread: E imglib
05-19 16:03:22.310 671-10151/? I/mm-camera: <MCT   >< INFO> 4165: mct_pipeline_start_session_thread: Calling start_session on Module imglib
05-19 16:03:22.310 671-10151/? I/mm-camera: <IMGLIB>< INFO> 1768: module_imglib_start_session: module_imglib_start_session:1768 ###Img_Loglevel 1, moduleMask 65535
05-19 16:03:22.310 671-10151/? I/mm-camera: <MCT   >< INFO> 4168: mct_pipeline_start_session_thread: Module imglib start_session rc = 1
05-19 16:03:22.310 671-10151/? I/mm-camera: <MCT   >< INFO> 4176: mct_pipeline_start_session_thread: started_num = 2, success = 2
05-19 16:03:22.310 671-10151/? I/mm-camera: <MCT   >< INFO> 4183: mct_pipeline_start_session_thread: X imglib
05-19 16:03:22.314 671-10150/? I/mm-camera: <C2D   >< INFO> 394: c2d_module_start_session: Warning: Cannot open ION fd
05-19 16:03:22.315 671-10144/? I/mm-camera: <MCT   >< INFO> 4168: mct_pipeline_start_session_thread: Module sensor start_session rc = 1
05-19 16:03:22.315 671-10144/? I/mm-camera: <MCT   >< INFO> 4176: mct_pipeline_start_session_thread: started_num = 3, success = 3
05-19 16:03:22.315 671-10144/? I/mm-camera: <MCT   >< INFO> 4183: mct_pipeline_start_session_thread: X sensor
05-19 16:03:22.324 10093-10093/com.my.app W/art: Before Android 4.1, method int android.support.v7.widget.ListViewCompat.lookForSelectablePosition(int, boolean) would have incorrectly overridden the package-private method in android.widget.ListView
05-19 16:03:22.324 671-10149/? I/mm-camera: <STATS_AIS >< INFO> 334: gyro_port_use_dsps_iface: Native sensor interface is default
05-19 16:03:22.325 671-10149/? I/mm-camera: <MCT   >< INFO> 4168: mct_pipeline_start_session_thread: Module stats start_session rc = 1
05-19 16:03:22.325 671-10149/? I/mm-camera: <MCT   >< INFO> 4176: mct_pipeline_start_session_thread: started_num = 4, success = 4
05-19 16:03:22.325 671-10149/? I/mm-camera: <MCT   >< INFO> 4183: mct_pipeline_start_session_thread: X stats
05-19 16:03:22.326 671-10157/? I/mm-camera: <STATS_AIS >< INFO> 689: sensor_event_callback: Available: GYRO_TYPE
05-19 16:03:22.326 671-10157/? I/mm-camera: <STATS_AIS >< INFO> 686: sensor_event_callback: Available: ACCELEROMETER_TYPE
05-19 16:03:22.326 671-10157/? I/mm-camera: <STATS_AIS >< INFO> 692: sensor_event_callback: Available: GRAVITY_TYPE
05-19 16:03:22.326 671-10157/? I/mm-camera: <STATS_AIS >< INFO> 695: sensor_event_callback: Available: LINEAR_ACCELERATION_TYPE
05-19 16:03:22.326 671-10147/? I/mm-camera: <MCT   >< INFO> 4168: mct_pipeline_start_session_thread: Module isp start_session rc = 1
05-19 16:03:22.326 671-10147/? I/mm-camera: <MCT   >< INFO> 4176: mct_pipeline_start_session_thread: started_num = 5, success = 5
05-19 16:03:22.326 671-10147/? I/mm-camera: <MCT   >< INFO> 4183: mct_pipeline_start_session_thread: X isp
05-19 16:03:22.335 671-10150/? I/Adreno: QUALCOMM build                   : 6818200, Idb2b4cb785
                                         Build Date                       : 11/17/16
                                         OpenGL ES Shader Compiler Version: XE031.09.00.04
                                         Local Branch                     : N25
                                         Remote Branch                    : 
                                         Remote Branch                    : 
                                         Reconstruct Branch               : 
05-19 16:03:22.351 671-10150/? D/eglc2d: Create display/context:0x1/0x1077f280
05-19 16:03:22.352 671-10150/? D/eglc2d: EglC2DLoadProgram
05-19 16:03:22.352 671-10150/? I/eglc2d: Load program binary size 10316, format 34624
05-19 16:03:22.352 671-10150/? D/eglc2d: EglC2DLoadProgram
05-19 16:03:22.352 671-10150/? I/eglc2d: Load program binary size 9516, format 34624
05-19 16:03:22.353 671-10150/? I/eglc2d: Create triangle mesh for 12 x 12 grid
05-19 16:03:22.353 671-10150/? D/eglc2d: EglC2DLoadProgram
05-19 16:03:22.353 671-10150/? I/eglc2d: Load program binary size 10212, format 34624
05-19 16:03:22.353 671-10150/? I/eglc2d: Create triangle mesh for 12 x 12 grid
05-19 16:03:22.353 671-10150/? I/GoogGyro: tripod Reset
05-19 16:03:22.353 671-10150/? I/GoogGyro: enable tripod:1
05-19 16:03:22.353 671-10150/? I/GoogGyro: tripod mode, average threshold: 0.004000
05-19 16:03:22.353 671-10150/? I/GoogGyro: tripod mode, variance threshold: 0.001000
05-19 16:03:22.353 671-10150/? I/GoogGyro: tripod mode, adaptive step: 0.001000
05-19 16:03:22.353 671-10150/? I/GoogGyro: tripod mode, deque size: 100
05-19 16:03:22.353 671-10150/? I/GoogGyro: tripod mode, damping duration: 5.000000
05-19 16:03:22.354 671-10150/? I/mm-camera: <MCT   >< INFO> 4168: mct_pipeline_start_session_thread: Module pproc start_session rc = 1
05-19 16:03:22.354 671-10150/? I/mm-camera: <MCT   >< INFO> 4176: mct_pipeline_start_session_thread: started_num = 6, success = 6
05-19 16:03:22.357 671-10150/? I/mm-camera: <MCT   >< INFO> 4183: mct_pipeline_start_session_thread: X pproc
05-19 16:03:22.364 671-23189/? I/mm-camera: <MCT   >< INFO> 4079: mct_pipeline_start_stream_internal: Adding session stream streamid= 0xf for session=2
05-19 16:03:22.364 671-23189/? I/mm-camera: <MCT   >< INFO> 4127: mct_pipeline_start_stream_internal: Linking session stream for session 2
05-19 16:03:22.364 671-23189/? I/mm-camera: <MCT   >< INFO> 464: mct_stream_start_link: Start linking Session-stream 0x2000f
05-19 16:03:22.364 671-23189/? I/mm-camera: <ISP   >< INFO> 784: isp_port_check_caps_reserve: port 0xf3964340 ide 2000f type 10 dim 0 0
05-19 16:03:22.364 1164-1215/? I/ActivityManager: Displayed com.my.app/.MainActivity: +506ms
05-19 16:03:22.365 671-23189/? I/mm-camera: <PPROC >< INFO> 446: pproc_port_add_modules_to_stream: stream 10 int_link = 0xf36f0c80
05-19 16:03:22.365 671-23189/? I/mm-camera: <PPROC >< INFO> 458: pproc_port_add_modules_to_stream: :LINK linking mods tmod and paaf for identity 2000f
05-19 16:03:22.365 671-23189/? I/mm-camera: <PPROC >< INFO> 458: pproc_port_add_modules_to_stream: :LINK linking mods paaf and goog_eis for identity 2000f
05-19 16:03:22.365 671-23189/? I/mm-camera: <PPROC >< INFO> 458: pproc_port_add_modules_to_stream: :LINK linking mods goog_eis and llvd for identity 2000f
05-19 16:03:22.370 671-23189/? I/mm-camera: <PPROC >< INFO> 458: pproc_port_add_modules_to_stream: :LINK linking mods llvd and ezt for identity 2000f
05-19 16:03:22.370 671-23189/? I/mm-camera: <PPROC >< INFO> 458: pproc_port_add_modules_to_stream: :LINK linking mods ezt and c2d for identity 2000f
05-19 16:03:22.370 671-23189/? I/mm-camera: <C2D   >< INFO> 1510: c2d_module_notify_add_stream: width 0, height 0, stride 0, scanline 0, is_type 0
05-19 16:03:22.370 671-23189/? I/mm-camera: <PPROC >< INFO> 458: pproc_port_add_modules_to_stream: :LINK linking mods c2d and cpp for identity 2000f
05-19 16:03:22.370 671-23189/? I/mm-camera: <CPP   >< INFO> 2113: cpp_module_notify_add_stream: :width 0, height 0, stride 0, scanline 0, framelen 0
05-19 16:03:22.370 671-23189/? E/mm-camera: <CPP   ><ERROR> 2209: cpp_module_notify_add_stream: failed, CPP current version can not support rotation
05-19 16:03:22.370 671-23189/? I/mm-camera: <CPP   >< INFO> 2275: cpp_module_notify_add_stream: : stream 10, fmt 1, asf_mode 0, sharpness_level 0.000000,asf mask 0, denoise 0, denoise_mask 0, dsdn mask 0,dsdn enable 0, tnr mask 0, tnr enable 0, ds_mask 0
05-19 16:03:22.371 671-23189/? I/mm-camera: <MCT   >< INFO> 4136: mct_pipeline_start_stream_internal: Session stream linked successfully session 2
05-19 16:03:22.372 671-23189/? I/QCamera: <HAL><INFO> int qcamera::QCamera3HardwareInterface::openCamera(struct hw_device_t **): 681: [KPI Perf]: X PROFILE_OPEN_CAMERA camera id 0, rc: 0
05-19 16:03:22.372 671-23189/? I/QCamera: <HAL><INFO> int qcamera::QCamera3HardwareInterface::initialize(const struct camera3_callback_ops *): 882: E :mCameraId = 0 mState = 1
05-19 16:03:22.373 671-23189/? I/QCamera: <HAL><INFO> int qcamera::QCamera3HardwareInterface::initialize(const struct camera3_callback_ops *): 915: X
05-19 16:03:22.373 671-23189/? W/Camera2-Parameters: initialize: Camera 0: Unknown preview format: 24
05-19 16:03:22.373 671-23189/? W/Camera2-Parameters: initialize: Camera 0: Unknown preview format: 25
05-19 16:03:22.374 671-23189/? I/Camera2-Parameters: initialize: allowZslMode: 1 slowJpegMode 0
05-19 16:03:22.375 1164-29068/? D/QCOM PowerHAL: LAUNCH HINT: OFF
05-19 16:03:22.383 671-23189/? E/Camera2-Metadata: Mismatched tag type when updating entry enable (-2146893824) of type byte; got type int32 data instead 
05-19 16:03:22.383 671-23189/? E/Camera2-Metadata: Mismatched tag type when updating entry is_main (-2146893823) of type byte; got type int32 data instead 
05-19 16:03:22.383 671-23189/? E/Camera2-Metadata: Mismatched tag type when updating entry enable (-2146893824) of type byte; got type int32 data instead 
05-19 16:03:22.383 671-23189/? E/Camera2-Metadata: Mismatched tag type when updating entry is_main (-2146893823) of type byte; got type int32 data instead 
05-19 16:03:22.388 671-1469/? I/QCamera: <HAL><INFO> int qcamera::QCamera3HardwareInterface::configureStreamsPerfLocked(camera3_stream_configuration_t *): 1508: stream[0] type = 2, format = 34, width = 4048, height = 3036, rotation = 0, usage = 0x60000
05-19 16:03:22.388 671-1469/? I/QCamera: <HAL><INFO> int qcamera::QCamera3HardwareInterface::configureStreamsPerfLocked(camera3_stream_configuration_t *): 1508: stream[1] type = 0, format = 34, width = 1600, height = 1200, rotation = 0, usage = 0x100
05-19 16:03:22.388 671-1469/? I/QCamera: <HAL><INFO> int qcamera::QCamera3HardwareInterface::configureStreamsPerfLocked(camera3_stream_configuration_t *): 1508: stream[2] type = 0, format = 33, width = 4048, height = 3036, rotation = 0, usage = 0x3
05-19 16:03:22.389 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=800000a
05-19 16:03:22.389 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000018
05-19 16:03:22.389 671-10166/? I/mm-camera: <MCT   >< INFO> 720: mct_stream_start_link: Link Metadata stream 0x20001: do nothing
05-19 16:03:22.389 671-10166/? I/mm-camera: <MCT   >< INFO> 3363: mct_pipeline_process_set: Linking successful for stream 0x20001 stream type=7
05-19 16:03:22.389 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000004
05-19 16:03:22.396 671-1469/? I/qomx_image_core: OMX_Init:101] Complete 3
05-19 16:03:22.399 671-1469/? I/qomx_image_core: OMX_GetHandle:243] get instance pts is 0xef7f5c00
05-19 16:03:22.399 671-1469/? D/qomx_image_core: OMX_GetHandle:261] handle = 0xef7f5c04 Instanceindex = 0,comp_idx 2 g_ptr 0x1057c680
05-19 16:03:22.399 671-1469/? E/mm-still: virtual OMX_ERRORTYPE QOMXImageCodec::omx_component_set_callbacks(OMX_HANDLETYPE, OMX_CALLBACKTYPE *, OMX_PTR): Bad Parameter
05-19 16:03:22.399 671-1469/? I/qomx_image_core: OMX_GetHandle:267] Success
05-19 16:03:22.401 671-1469/? D/Camera3-Device: Set real time priority for request queue thread (tid 10172)
05-19 16:03:22.413 671-10172/? I/QCamera: <HAL><INFO> int qcamera::QCamera3HardwareInterface::processCaptureRequest(camera3_capture_request_t *): 3693: STREAM INFO : type 3, wxh: 4048 x 3036, pp_mask: 0x0 Format:0
05-19 16:03:22.413 671-10172/? I/QCamera: <HAL><INFO> int qcamera::QCamera3HardwareInterface::processCaptureRequest(camera3_capture_request_t *): 3693: STREAM INFO : type 1, wxh: 1600 x 1200, pp_mask: 0x100068e Format:1
05-19 16:03:22.413 671-10172/? I/QCamera: <HAL><INFO> int qcamera::QCamera3HardwareInterface::processCaptureRequest(camera3_capture_request_t *): 3693: STREAM INFO : type 3, wxh: 4048 x 3036, pp_mask: 0x0 Format:0
05-19 16:03:22.413 671-10172/? I/QCamera: <HAL><INFO> int qcamera::QCamera3HardwareInterface::processCaptureRequest(camera3_capture_request_t *): 3693: STREAM INFO : type 11, wxh: 640 x 480, pp_mask: 0x100068e Format:1
05-19 16:03:22.414 671-10166/? I/mm-camera: <C2D   >< INFO> 1748: c2d_module_handle_set_parm_event: META_STREAM_INFO type = 3 w x h : 4048 x 3036 pp_mask = 0x0
05-19 16:03:22.414 671-10166/? I/mm-camera: <C2D   >< INFO> 1748: c2d_module_handle_set_parm_event: META_STREAM_INFO type = 1 w x h : 1600 x 1200 pp_mask = 0x100068e
05-19 16:03:22.414 671-10166/? I/mm-camera: <C2D   >< INFO> 1748: c2d_module_handle_set_parm_event: META_STREAM_INFO type = 3 w x h : 4048 x 3036 pp_mask = 0x0
05-19 16:03:22.414 671-10166/? I/mm-camera: <C2D   >< INFO> 1748: c2d_module_handle_set_parm_event: META_STREAM_INFO type = 11 w x h : 640 x 480 pp_mask = 0x100068e
05-19 16:03:22.414 671-10166/? I/mm-camera: <ISP   >< INFO> 6231: isp_util_print_meta_stream_info: Stream type 3 Resolution: 4048x3036 pp_mask: 0x0
05-19 16:03:22.414 671-10166/? I/mm-camera: <ISP   >< INFO> 6231: isp_util_print_meta_stream_info: Stream type 1 Resolution: 1600x1200 pp_mask: 0x100068e
05-19 16:03:22.414 671-10166/? I/mm-camera: <ISP   >< INFO> 6231: isp_util_print_meta_stream_info: Stream type 3 Resolution: 4048x3036 pp_mask: 0x0
05-19 16:03:22.414 671-10166/? I/mm-camera: <ISP   >< INFO> 6231: isp_util_print_meta_stream_info: Stream type 11 Resolution: 640x480 pp_mask: 0x100068e
05-19 16:03:22.414 671-10166/? I/mm-camera: <ISP   >< INFO> 2309: isp_resource_allocate: INFO: ISP resource acquired: session 2 num_isp 1
05-19 16:03:22.414 671-10166/? I/mm-camera: <ISP   >< INFO> 2311: isp_resource_allocate: INFO: ISP resource acquired: hw_id 1
05-19 16:03:22.414 671-10166/? I/mm-camera: <ISP   >< INFO> 6136: isp_util_map_streams_to_hw_hal3: priority stream: 0 num: 1 mask: 0x800
05-19 16:03:22.414 671-10166/? I/mm-camera: <ISP   >< INFO> 6192: isp_util_decide_stream_mapping: stream_port_map num streams 4
05-19 16:03:22.414 671-10166/? I/mm-camera: <ISP   >< INFO> 6202: isp_util_decide_stream_mapping: INFO: type 3 resolution 4048x3036 hw_stream 0 need_native_buff 0 controllable_output 1 shared_output 1
05-19 16:03:22.414 671-10166/? I/mm-camera: <ISP   >< INFO> 6202: isp_util_decide_stream_mapping: INFO: type 3 resolution 4048x3036 hw_stream 0 need_native_buff 0 controllable_output 1 shared_output 1
05-19 16:03:22.415 671-10166/? I/mm-camera: <ISP   >< INFO> 6202: isp_util_decide_stream_mapping: INFO: type 1 resolution 1600x1200 hw_stream 1 need_native_buff 1 controllable_output 0 shared_output 0
05-19 16:03:22.415 671-10166/? I/mm-camera: <ISP   >< INFO> 6202: isp_util_decide_stream_mapping: INFO: type 11 resolution 640x480 hw_stream 2 need_native_buff 1 controllable_output 0 shared_output 0
05-19 16:03:22.418 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=800000a
05-19 16:03:22.420 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000018
05-19 16:03:22.420 671-10166/? I/mm-camera: <MCT   >< INFO> 525: mct_stream_start_link: Start linking snapshot stream 0x20002
05-19 16:03:22.420 671-10166/? I/mm-camera: <ISP   >< INFO> 784: isp_port_check_caps_reserve: port 0xf3964340 ide 20002 type 3 dim 4048 3036
05-19 16:03:22.421 671-10166/? I/mm-camera: <MCT   >< INFO> 3363: mct_pipeline_process_set: Linking successful for stream 0x20002 stream type=3
05-19 16:03:22.421 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000004
05-19 16:03:22.421 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=800000a
05-19 16:03:22.422 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000018
05-19 16:03:22.422 671-10166/? I/mm-camera: <MCT   >< INFO> 438: mct_stream_start_link: Start linking preview stream 0x20003
05-19 16:03:22.422 671-10166/? I/mm-camera: <MCT   >< INFO> 452: mct_stream_start_link: non secure stream linking
05-19 16:03:22.422 671-10166/? I/mm-camera: <ISP   >< INFO> 784: isp_port_check_caps_reserve: port 0xf3964340 ide 20003 type 1 dim 1600 1200
05-19 16:03:22.422 671-10166/? I/mm-camera: <PPROC >< INFO> 446: pproc_port_add_modules_to_stream: stream 1 int_link = 0xf36f0f00
05-19 16:03:22.423 671-10166/? I/mm-camera: <PPROC >< INFO> 458: pproc_port_add_modules_to_stream: :LINK linking mods tmod and paaf for identity 20003
05-19 16:03:22.423 671-10166/? I/mm-camera: <PPROC >< INFO> 458: pproc_port_add_modules_to_stream: :LINK linking mods paaf and cpp for identity 20003
05-19 16:03:22.423 671-10166/? I/mm-camera: <CPP   >< INFO> 2113: cpp_module_notify_add_stream: :width 1600, height 1200, stride 1664, scanline 1216, framelen 3137536
05-19 16:03:22.423 671-10166/? I/mm-camera: <CPP   >< INFO> 2137: cpp_module_notify_add_stream: offset_x 0, offset_y 0, offset 0, meta_len 12288,meta_scanline 160, meta_stride 64, plane_len 2035712
05-19 16:03:22.423 671-10166/? I/mm-camera: <CPP   >< INFO> 2137: cpp_module_notify_add_stream: offset_x 0, offset_y 0, offset 0, meta_len 8192,meta_scanline 80, meta_stride 64, plane_len 1101824
05-19 16:03:22.423 671-10166/? I/mm-camera: <CPP   >< INFO> 2275: cpp_module_notify_add_stream: : stream 1, fmt 65, asf_mode 0, sharpness_level 0.333333,asf mask 1, denoise 0, denoise_mask 1, dsdn mask 0,dsdn enable 0, tnr mask 0, tnr enable 0, ds_mask 1
05-19 16:03:22.428 671-10166/? I/mm-camera: <IMGLIB>< INFO> 4386: faceproc_hw_comp_create: Using FD HW Hybrid solution : Tracker v2 + SWFP v6
05-19 16:03:22.428 671-10166/? I/mm-camera: <MCT   >< INFO> 3363: mct_pipeline_process_set: Linking successful for stream 0x20003 stream type=1
05-19 16:03:22.428 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000004
05-19 16:03:22.429 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=800000a
05-19 16:03:22.429 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000018
05-19 16:03:22.429 671-10166/? I/mm-camera: <MCT   >< INFO> 525: mct_stream_start_link: Start linking snapshot stream 0x20004
05-19 16:03:22.429 671-10166/? I/mm-camera: <ISP   >< INFO> 784: isp_port_check_caps_reserve: port 0xf3964340 ide 20004 type 3 dim 4048 3036
05-19 16:03:22.430 671-10166/? I/mm-camera: <MCT   >< INFO> 3363: mct_pipeline_process_set: Linking successful for stream 0x20004 stream type=3
05-19 16:03:22.430 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000004
05-19 16:03:22.432 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=800000a
05-19 16:03:22.433 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000018
05-19 16:03:22.433 671-10166/? I/mm-camera: <MCT   >< INFO> 679: mct_stream_start_link: Starting Analysis stream linking
05-19 16:03:22.433 671-10166/? I/mm-camera: <ISP   >< INFO> 784: isp_port_check_caps_reserve: port 0xf3964340 ide 20005 type 11 dim 640 480
05-19 16:03:22.433 671-10166/? I/mm-camera: <PPROC >< INFO> 446: pproc_port_add_modules_to_stream: stream 11 int_link = 0xf36f12c0
05-19 16:03:22.433 671-10166/? I/mm-camera: <PPROC >< INFO> 458: pproc_port_add_modules_to_stream: :LINK linking mods tmod and paaf for identity 20005
05-19 16:03:22.433 671-10166/? I/mm-camera: <MCT   >< INFO> 3363: mct_pipeline_process_set: Linking successful for stream 0x20005 stream type=11
05-19 16:03:22.434 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000004
05-19 16:03:22.434 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000019
05-19 16:03:22.444 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000019
05-19 16:03:22.444 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000019
05-19 16:03:22.444 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000019
05-19 16:03:22.453 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000008
05-19 16:03:22.453 671-10166/? I/mm-camera: <MCT   >< INFO> 3403: mct_pipeline_process_set:  STREAM-ON on stream 0x20001 stream type=7
05-19 16:03:22.453 671-10166/? I/mm-camera: <MCT   >< INFO> 3418: mct_pipeline_process_set:  STREAM-ON success for stream 0x20001, stream type=7
05-19 16:03:22.453 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000008
05-19 16:03:22.453 671-10166/? I/mm-camera: <MCT   >< INFO> 3403: mct_pipeline_process_set:  STREAM-ON on stream 0x20002 stream type=3
05-19 16:03:22.453 671-10166/? I/mm-camera: <CPP   >< INFO> 1732: cpp_module_handle_stream_cfg_event: frame_offset=3, input_fps=30.00, identity=0x2000f
05-19 16:03:22.453 671-10166/? I/mm-camera: <CPP   >< INFO> 1732: cpp_module_handle_stream_cfg_event: frame_offset=3, input_fps=30.00, identity=0x20003
05-19 16:03:22.455 1164-2538/? I/sensors: batch
05-19 16:03:22.456 1164-2538/? I/nanohub: queueBatch: sensor=6, handle=2, period=160000000, latency=0
05-19 16:03:22.456 1164-2538/? I/sensors: activate
05-19 16:03:22.458 1164-2538/? I/nanohub: queueActivate: sensor=6, handle=2, enable=1
05-19 16:03:22.458 1164-2500/? I/sensors: setDelay
05-19 16:03:22.459 1164-2500/? I/nanohub: queueSetDelay: sensor=6, handle=2, period=33333000
05-19 16:03:22.459 671-10166/? I/mm-camera: <IFACE >< INFO> 404: iface_util_dump_sensor_cfg: iface_util_dump_sensor_cfg: sensor dim: width = 4048, heght = 3044, fmt = 31, is_bayer = 1, init_skip = 2
05-19 16:03:22.460 671-10166/? I/mm-camera: <ISP   >< INFO> 1143: isp_resource_get_stats_type_mask: enable_stats_mask 1588
05-19 16:03:22.460 671-10166/? I/mm-camera: <IFACE >< INFO> 3470: iface_util_dump_isp_resource_request:  =====DUMP RESOURCE: PIX STREAM=== 
05-19 16:03:22.460 671-10166/? I/mm-camera: <IFACE >< INFO> 3471: iface_util_dump_isp_resource_request: num pix stream = 3
05-19 16:03:22.460 671-10166/? I/mm-camera: <IFACE >< INFO> 3474: iface_util_dump_isp_resource_request: num_isp = 1, isp_mask = 2, request_op_pix_clk = 0
05-19 16:03:22.460 671-10166/? I/mm-camera: <IFACE >< INFO> 3478: iface_util_dump_isp_resource_request: ispif stripe info: split 0, overlap 0, stripe offset 0
05-19 16:03:22.460 671-10166/? I/mm-camera: <IFACE >< INFO> 3481: iface_util_dump_isp_resource_request: pix stream 0
05-19 16:03:22.460 671-10166/? I/mm-camera: <IFACE >< INFO> 3484: iface_util_dump_isp_resource_request: num mct stream mapped = 2(Mapped stream ID[0] = 2)
05-19 16:03:22.460 671-10166/? I/mm-camera: <IFACE >< INFO> 3487: iface_util_dump_isp_resource_request: axi path = 1(ENC-0/VIEW-1/CAMIF-2/IDEAL-3), cam_fmt 2
05-19 16:03:22.460 671-10166/? I/mm-camera: <IFACE >< INFO> 3481: iface_util_dump_isp_resource_request: pix stream 1
05-19 16:03:22.460 671-10166/? I/mm-camera: <IFACE >< INFO> 3484: iface_util_dump_isp_resource_request: num mct stream mapped = 1(Mapped stream ID[0] = 3)
05-19 16:03:22.460 671-10166/? I/mm-camera: <IFACE >< INFO> 3487: iface_util_dump_isp_resource_request: axi path = 0(ENC-0/VIEW-1/CAMIF-2/IDEAL-3), cam_fmt 101
05-19 16:03:22.460 671-10166/? I/mm-camera: <IFACE >< INFO> 3481: iface_util_dump_isp_resource_request: pix stream 2
05-19 16:03:22.460 671-10166/? I/mm-camera: <IFACE >< INFO> 3484: iface_util_dump_isp_resource_request: num mct stream mapped = 1(Mapped stream ID[0] = 5)
05-19 16:03:22.460 671-10166/? I/mm-camera: <IFACE >< INFO> 3487: iface_util_dump_isp_resource_request: axi path = 2(ENC-0/VIEW-1/CAMIF-2/IDEAL-3), cam_fmt 100
05-19 16:03:22.460 671-10166/? I/mm-camera: <IFACE >< INFO> 3509: iface_util_dump_isp_resource_request: num STATS stream = 5
05-19 16:03:22.460 1164-1501/? I/nanohub: osLog: [BMI160] gyrPower: on=1, state=3
05-19 16:03:22.460 671-10166/? I/mm-camera: <CPP   >< INFO> 865: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS]  stream type 1, width 1600, height 1200,stride 1600, scanline 1200, planes 2
05-19 16:03:22.460 671-10166/? I/mm-camera: <CPP   >< INFO> 884: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS] plane 0, stride 1600, scanline 1200, offset_x 0, offset_y 0, len 1920000, offset:0
05-19 16:03:22.460 671-10166/? I/mm-camera: <CPP   >< INFO> 884: cpp_module_handle_isp_out_dim_event: [STREAM_PARAMS] plane 1, stride 1600, scanline 1200, offset_x 0, offset_y 0, len 960000, offset:0
05-19 16:03:22.462 671-10166/? I/mm-camera: <IFACE >< INFO> 8303: iface_util_dump_camif_cfg: =====Camif DUMP cfg for PIX interface====
05-19 16:03:22.462 671-10166/? I/mm-camera: <IFACE >< INFO> 8305: iface_util_dump_camif_cfg: camif input type = 3(MIPI=3), op_pix_clk = 480000000
05-19 16:03:22.462 671-10166/? I/mm-camera: <IFACE >< INFO> 8307: iface_util_dump_camif_cfg: camif pix_pattern(RGRG-0/GRGR-1/BGBG-2/GBGB-3) = 2
05-19 16:03:22.462 671-10166/? I/mm-camera: <IFACE >< INFO> 8309: iface_util_dump_camif_cfg: camif first_pix = 0, last_pix = 4047
05-19 16:03:22.462 671-10166/? I/mm-camera: <IFACE >< INFO> 8311: iface_util_dump_camif_cfg: camif first_line = 8, last_line = 3043
05-19 16:03:22.462 671-10166/? I/mm-camera: <IFACE >< INFO> 8313: iface_util_dump_camif_cfg: camif pixels_per_line = 4048, lines_per_frame = 3044
05-19 16:03:22.462 671-10166/? I/mm-camera: <IFACE >< INFO> 8317: iface_util_dump_camif_cfg: camif irq subsample pattern = 0, period = 0 sof_step 1
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 534: iface_dump_axi_plane_config: === AXI DUMP: VFE session_id 2, hw_stream_id 2 ===
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 538: iface_dump_axi_plane_config:     AXI DUMP Axi_src: VIEWFINDER hw_stream width 4048, height 3036
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 540: iface_dump_axi_plane_config: need buf divert = 0, burst count = 0
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 543: iface_dump_axi_plane_config: hfr mode = 0, skip pattern = 0, init_frame_drop = 2
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 576: iface_dump_axi_plane_config: plane[0]: plane_fmt 0(Y-0/CB-1/Cr-2/CrCb-3/CbCr-4)
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 578: iface_dump_axi_plane_config: plane[0]: width = 4048, height = 3036
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 580: iface_dump_axi_plane_config: plane[0]: stride = 4096, scanlines = 3072
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 582: iface_dump_axi_plane_config: plane[0]: address_offset 0
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 576: iface_dump_axi_plane_config: plane[1]: plane_fmt 3(Y-0/CB-1/Cr-2/CrCb-3/CbCr-4)
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 578: iface_dump_axi_plane_config: plane[1]: width = 4048, height = 1518
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 580: iface_dump_axi_plane_config: plane[1]: stride = 4096, scanlines = 1536
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 582: iface_dump_axi_plane_config: plane[1]: address_offset 0
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 534: iface_dump_axi_plane_config: === AXI DUMP: VFE session_id 2, hw_stream_id 10003 ===
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 538: iface_dump_axi_plane_config:     AXI DUMP Axi_src: ENCODER hw_stream width 1600, height 1200
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 540: iface_dump_axi_plane_config: need buf divert = 1, burst count = 0
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 543: iface_dump_axi_plane_config: hfr mode = 0, skip pattern = 0, init_frame_drop = 2
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 576: iface_dump_axi_plane_config: plane[0]: plane_fmt 0(Y-0/CB-1/Cr-2/CrCb-3/CbCr-4)
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 578: iface_dump_axi_plane_config: plane[0]: width = 1600, height = 1200
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 580: iface_dump_axi_plane_config: plane[0]: stride = 1600, scanlines = 1200
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 582: iface_dump_axi_plane_config: plane[0]: address_offset 0
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 576: iface_dump_axi_plane_config: plane[1]: plane_fmt 4(Y-0/CB-1/Cr-2/CrCb-3/CbCr-4)
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 578: iface_dump_axi_plane_config: plane[1]: width = 1600, height = 600
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 580: iface_dump_axi_plane_config: plane[1]: stride = 1600, scanlines = 600
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 582: iface_dump_axi_plane_config: plane[1]: address_offset 0
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 534: iface_dump_axi_plane_config: === AXI DUMP: VFE session_id 2, hw_stream_id 10005 ===
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 538: iface_dump_axi_plane_config:     AXI DUMP Axi_src: VIDEO hw_stream width 640, height 480
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 540: iface_dump_axi_plane_config: need buf divert = 1, burst count = 0
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 543: iface_dump_axi_plane_config: hfr mode = 0, skip pattern = 0, init_frame_drop = 2
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 576: iface_dump_axi_plane_config: plane[0]: plane_fmt 0(Y-0/CB-1/Cr-2/CrCb-3/CbCr-4)
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 578: iface_dump_axi_plane_config: plane[0]: width = 640, height = 480
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 580: iface_dump_axi_plane_config: plane[0]: stride = 640, scanlines = 480
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 582: iface_dump_axi_plane_config: plane[0]: address_offset 0
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 534: iface_dump_axi_plane_config: === AXI DUMP: VFE session_id 2, hw_stream_id 90001 ===
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 538: iface_dump_axi_plane_config:     AXI DUMP Axi_src: RDI_0 hw_stream width 4056, height 2
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 540: iface_dump_axi_plane_config: need buf divert = 1, burst count = 0
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 543: iface_dump_axi_plane_config: hfr mode = 0, skip pattern = 0, init_frame_drop = 2
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 576: iface_dump_axi_plane_config: plane[0]: plane_fmt 0(Y-0/CB-1/Cr-2/CrCb-3/CbCr-4)
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 578: iface_dump_axi_plane_config: plane[0]: width = 4056, height = 2
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 580: iface_dump_axi_plane_config: plane[0]: stride = 4064, scanlines = 2
05-19 16:03:22.462 671-10219/? I/mm-camera: <IFACE >< INFO> 582: iface_dump_axi_plane_config: plane[0]: address_offset 0
05-19 16:03:22.464 671-10166/? I/mm-camera: <IFACE >< INFO> 1559: iface_streamon_to_thread: iface_streamon_to_thread: E, session id = 2, user stream id = 2
05-19 16:03:22.465 671-10148/? I/mm-camera: <IFACE >< INFO> 1902: iface_streamon: E, session_id 2 mct_stream_id = 2
05-19 16:03:22.465 671-10148/? I/mm-camera: <IFACE >< INFO> 10341: iface_util_get_user_streams_by_bundle: got No.1 bundled streamon, mct stream id 2total bundled stream num = 4
05-19 16:03:22.465 671-10166/? I/mm-camera: <ISP   >< INFO> 285: isp_resource_update_module_cfg: Warning: 0xf6b62000 hw_id 0 fd 0 0xec87b4d4
05-19 16:03:22.465 1164-29063/? W/SensorService: sensor 00000002 already enabled in connection 0x7aade88780 (ignoring)
05-19 16:03:22.465 1164-29063/? I/sensors: batch
05-19 16:03:22.467 1164-29063/? I/nanohub: queueBatch: sensor=6, handle=2, period=160000000, latency=0
05-19 16:03:22.467 1164-29063/? I/sensors: activate
05-19 16:03:22.468 1164-29063/? I/nanohub: queueActivate: sensor=6, handle=2, enable=1
05-19 16:03:22.470 1164-2529/? I/sensors: setDelay
05-19 16:03:22.471 1164-2529/? I/nanohub: queueSetDelay: sensor=6, handle=2, period=33333000
05-19 16:03:22.475 671-10220/? I/Laser: openInput
05-19 16:03:22.476 671-10166/? I/mm-camera: <IFACE >< INFO> 1588: iface_streamon_to_thread: iface_streamon_to_thread: X, session id = 2, user stream id = 2
05-19 16:03:22.488 671-10166/? I/mm-camera: <MCT   >< INFO> 149: start_sof_check_thread: Starting SOF timeout thread session id =2
05-19 16:03:22.488 671-10166/? I/mm-camera: <MCT   >< INFO> 3418: mct_pipeline_process_set:  STREAM-ON success for stream 0x20002, stream type=3
05-19 16:03:22.488 1164-2535/? I/sensors: batch
05-19 16:03:22.490 1164-2535/? I/nanohub: queueBatch: sensor=31, handle=27, period=200000000, latency=0
05-19 16:03:22.490 1164-2535/? I/sensors: activate
05-19 16:03:22.492 1164-2535/? I/nanohub: queueActivate: sensor=31, handle=27, enable=1
05-19 16:03:22.492 1164-2535/? I/sensors: setDelay
05-19 16:03:22.493 1164-1501/? I/nanohub: osLog: [VSYNC] power 1
05-19 16:03:22.494 1164-1501/? I/nanohub: osLog: [VSYNC] setRate
05-19 16:03:22.496 1164-2535/? I/nanohub: queueSetDelay: sensor=31, handle=27, period=5000000
05-19 16:03:22.496 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000008
05-19 16:03:22.496 671-10166/? I/mm-camera: <MCT   >< INFO> 3403: mct_pipeline_process_set:  STREAM-ON on stream 0x20003 stream type=1
05-19 16:03:22.496 671-10166/? I/mm-camera: <IFACE >< INFO> 1559: iface_streamon_to_thread: iface_streamon_to_thread: E, session id = 2, user stream id = 3
05-19 16:03:22.496 671-10166/? I/mm-camera: <ISP   >< INFO> 285: isp_resource_update_module_cfg: Warning: 0xf6b62000 hw_id 0 fd 0 0xec87b4e4
05-19 16:03:22.497 671-10148/? I/mm-camera: <IFACE >< INFO> 1902: iface_streamon: E, session_id 2 mct_stream_id = 3
05-19 16:03:22.497 671-10148/? I/mm-camera: <IFACE >< INFO> 10341: iface_util_get_user_streams_by_bundle: got No.2 bundled streamon, mct stream id 3total bundled stream num = 4
05-19 16:03:22.497 671-10166/? I/mm-camera: <CPP   >< INFO> 3831: cpp_module_handle_streamon_event: identity=0x20003, stream-on done
05-19 16:03:22.497 671-10166/? I/mm-camera: <IFACE >< INFO> 1588: iface_streamon_to_thread: iface_streamon_to_thread: X, session id = 2, user stream id = 3
05-19 16:03:22.497 671-10166/? I/mm-camera: <MCT   >< INFO> 3418: mct_pipeline_process_set:  STREAM-ON success for stream 0x20003, stream type=1
05-19 16:03:22.498 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000008
05-19 16:03:22.498 671-10166/? I/mm-camera: <MCT   >< INFO> 3403: mct_pipeline_process_set:  STREAM-ON on stream 0x20004 stream type=3
05-19 16:03:22.498 671-10166/? I/mm-camera: <IFACE >< INFO> 1559: iface_streamon_to_thread: iface_streamon_to_thread: E, session id = 2, user stream id = 4
05-19 16:03:22.498 671-10148/? I/mm-camera: <IFACE >< INFO> 1902: iface_streamon: E, session_id 2 mct_stream_id = 4
05-19 16:03:22.498 671-10148/? I/mm-camera: <IFACE >< INFO> 10341: iface_util_get_user_streams_by_bundle: got No.3 bundled streamon, mct stream id 4total bundled stream num = 4
05-19 16:03:22.498 671-10153/? I/mm-camera: <CPP   >< INFO> 362: cpp_hardware_set_clock: Set clock 200000000 BW avg 174555008 BW inst 174555008
05-19 16:03:22.498 671-10166/? I/mm-camera: <ISP   >< INFO> 285: isp_resource_update_module_cfg: Warning: 0xf6b62000 hw_id 0 fd 0 0xec87b4e4
05-19 16:03:22.498 671-10166/? I/mm-camera: <IFACE >< INFO> 1588: iface_streamon_to_thread: iface_streamon_to_thread: X, session id = 2, user stream id = 4
05-19 16:03:22.498 671-10166/? I/mm-camera: <MCT   >< INFO> 3418: mct_pipeline_process_set:  STREAM-ON success for stream 0x20004, stream type=3
05-19 16:03:22.499 671-10166/? I/mm-camera: <MCT   >< INFO> 3265: mct_pipeline_process_set: command=8000008
05-19 16:03:22.499 671-10166/? I/mm-camera: <MCT   >< INFO> 3403: mct_pipeline_process_set:  STREAM-ON on stream 0x20005 stream type=11
05-19 16:03:22.499 671-10166/? I/mm-camera: <IFACE >< INFO> 1559: iface_streamon_to_thread: iface_streamon_to_thread: E, session id = 2, user stream id = 5
05-19 16:03:22.499 671-10148/? I/mm-camera: <IFACE >< INFO> 1902: iface_streamon: E, session_id 2 mct_stream_id = 5
05-19 16:03:22.499 671-10148/? I/mm-camera: <IFACE >< INFO> 10335: iface_util_get_user_streams_by_bundle: streamon, got all bundled streamon!
05-19 16:03:22.499 671-10166/? I/mm-camera: <ISP   >< INFO> 285: isp_resource_update_module_cfg: Warning: 0xf6b62000 hw_id 0 fd 0 0xec87b4e4
05-19 16:03:22.500 671-10166/? I/mm-camera: <IMGLIB>< INFO> 1180: faceproc_hw_comp_send_msg: faceproc_hw_comp_send_msg 1180] FDHW_MSG_CHROMATIX_UPDATE 
05-19 16:03:22.510 671-10148/? I/mm-camera: <IFACE >< INFO> 2155: iface_streamon: X
05-19 16:03:22.510 671-10166/? I/mm-camera: <IFACE >< INFO> 1588: iface_streamon_to_thread: iface_streamon_to_thread: X, session id = 2, user stream id = 5
05-19 16:03:22.510 671-10166/? I/mm-camera: <MCT   >< INFO> 3418: mct_pipeline_process_set:  STREAM-ON success for stream 0x20005, stream type=11
05-19 16:03:22.512 671-10172/? D/QCOM PowerHAL: LAUNCH ENCODER-ON: 2000 MS
05-19 16:03:22.513 1164-1501/? I/nanohub: osLog: [BMI160] gyrSetRate: rate=51200, latency=33333248, state=12
05-19 16:03:22.514 1164-1501/? I/nanohub: osLog: [BMI160] gyrSetRate: rate=51200, latency=33333248, state=3
05-19 16:03:22.516 671-10172/? I/QCOM PowerHAL: Video Encode hint start
05-19 16:03:22.535 671-10215/? E/mm-camera: <ISP   ><ERROR> 1255: isp_handler_module_handle_reg_update:  Warning! Invalid reg_update state 0
05-19 16:03:22.537 671-10162/? E/mm-camera-CORE: int gcam_ae_handle_sensor_roi(gcam_ae_thread_data_t *, aec_interested_region_t *): Invalid input crop_width 0, crop_height 0
05-19 16:03:22.541 671-10220/? I/Laser: inputName: STM VL53L0 proximity sensor found
05-19 16:03:22.541 671-10220/? E/Laser: open_sensors gets called!
05-19 16:03:22.541 671-10220/? E/Laser: sensors_poll_context_t::activate gets called!
05-19 16:03:22.541 671-10220/? I/Laser: ProximitySensor::enable function called for stmvl53L0!
05-19 16:03:22.620 671-10220/? I/Laser: ProximitySensor::enable stmvl53L0 enabled sucessfully!
05-19 16:03:22.631 1164-1501/? I/nanohub: osLog: bridge: Got event 0x00000221 with 144 bytes of data
05-19 16:03:22.632 1164-1501/? I/nanohub: osLog: bridge: Issuing on-demand request for Cell Info
05-19 16:03:22.635 671-10153/? I/mm-camera: <CPP   >< INFO> 362: cpp_hardware_set_clock: Set clock 200000000 BW avg 174555008 BW inst 174555008
05-19 16:03:22.648 1164-1501/? I/nanohub: osLog: bridge: Got event 0x00000225 with 136 bytes of data
05-19 16:03:22.652 2425-2728/? D/BrcmNfcJni: RoutingManager::nfaEeCallback: NFA_EE_SET_TECH_CFG_EVT; status=0x0
05-19 16:03:22.652 2425-2728/? D/BrcmNfcJni: RoutingManager::nfaEeCallback: NFA_EE_SET_PROTO_CFG_EVT; status=0x0
05-19 16:03:22.653 2425-2839/? D/BrcmNfcJni: RoutingManager::commitRouting
05-19 16:03:22.653 2425-2728/? D/BrcmNfcJni: RoutingManager::nfaEeCallback: NFA_EE_UPDATED_EVT
05-19 16:03:22.655 1164-1501/? I/nanohub: osLog: [WifiLocator] incoming scan 0x20029aa0
05-19 16:03:22.656 1164-1501/? I/nanohub: osLog: [WifiLocator] wifi position success (20m)
05-19 16:03:22.657 1164-1501/? I/nanohub: osLog: [CellLocator] incoming scan 0x20029a78
05-19 16:03:22.658 1164-1501/? I/nanohub: osLog: [CellLocator] cell position success (2857m)
05-19 16:03:22.660 1164-1501/? I/nanohub: osLog: [LocEng] Sending Scan Request with interval ~60 seconds, ~5 meters
05-19 16:03:22.661 1164-1501/? I/nanohub: osLog: [GeofencingEngine] LocationCallback: provider 1, accuracy 20 meters, 0 transitions
05-19 16:03:22.668 501-501/? W/SurfaceFlinger: couldn't log to binary event log: overflow.
05-19 16:03:22.674 671-10158/? E/mm-camera: <ERROR> 831: dmlroc_init: dmlroc_init cfg: camif 3036x4048, elem 126x126, thres 0.002500, accur 8
05-19 16:03:22.674 671-10158/? E/mm-camera: <ERROR> 832: dmlroc_init: dmlroc_init cfg: offsetH 8, offsetV 6
05-19 16:03:22.674 671-10158/? E/mm-camera: <ERROR> 833: dmlroc_init: dmlroc_init mesh: rxc 13x17, subs 4, hxw 64x64, offset vert 18, horz 24
05-19 16:03:23.984 671-10172/? E/QCamera: <HAL><ERROR> virtual int32_t qcamera::QCamera3ProcessingChannel::registerBuffer(buffer_handle_t *, cam_is_type_t): 1130: Buffer 0xe9db8c04 couldn't be registered -12
05-19 16:03:23.984 671-10172/? E/QCamera: <HAL><ERROR> virtual int32_t qcamera::QCamera3RegularChannel::request(buffer_handle_t *, uint32_t, int &): 1967: On-the-fly buffer registration failed -12
05-19 16:03:23.984 671-10172/? E/QCamera: <HAL><ERROR> int qcamera::QCamera3HardwareInterface::processCaptureRequest(camera3_capture_request_t *): 4305: request failed
05-19 16:03:23.984 671-10172/? E/Camera3-Device: Camera 0: threadLoop: RequestThread: Unable to submit capture request 38 to HAL device: Out of memory (-12)
0
Diolor commented 7 years ago

You are getting a CAMERA_ERROR_UNKNOWN. If android doesn't know what's the issue, I can't either :)

This is not a bug related to FA but the device's compatibility with camera1 API.

Which device are you using and more interestingly what API level? Alternatively you can force v2 as @dmitry-zaitsev suggested.

hagabaka commented 7 years ago

I'm using Pixel XL, Android 7.1.2, and the app has minSdkVersion 16, targetSdkVersion 25. Forcing v2 worked for me, but will it be broken if the API level does not support v2?

hagabaka commented 7 years ago

Actually, I got an error dialog when using the Android built-in camera app. So I rebooted the phone, and the problem has gone away without needing to force cameraProvider. So I guess this was just a temporary problem with my phone. Thanks for taking time to investigate!