logcat test start !!! --------- beginning of /dev/log_main I/ (2040852): [257505.171875][camera_diag]:[cam_diag_create][652] cam_diag_create done I/ (2040852): [257505.171875][camera_if]:[hbn_camera_create][234] create 0: camera imx728std as fd 0x4331 I/ (2040852): [257505.171875][hbn_vpf_interface.c]:[vnode_alloc_magic_fd][293]cur_index 0 I/ (2040852): [257505.171875][hbn_vpf_interface.c]:[hbn_vnode_open][389]vnode create done. I/ (2040852): [257505.171875][hbn_vpf_interface.c]:[hbn_vnode_open][398]vin0 ctx 0 vnode open I/ (2040852): [257505.171875][hbn_vpf_interface.c]:[hbn_vnode_set_attr_s][554]vin0 ctx0 vnode done. I/ (2040852): [257505.171875][hbn_vpf_interface.c]:[hbn_vnode_set_ichn_attr_s][1302]vin0 ctx0 vnode done. I/ (2040852): [257505.171875][hbn_vpf_interface.c]:[hbn_vnode_set_ochn_attr_s][929]vin0 ctx0 vnode done. I/ (2040852): [257505.171875][vpf_buf_mgr.c]:[vnode_buf_mgr_create][488]buffer_num 6 I/ (2040852): [257505.171875][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 11 0x4032d0000 0xc05000 I/ (2040852): [257505.171875][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done I/ (2040852): [257505.171875][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 13 0x403ef0000 0xc05000 I/ (2040852): [257505.171875][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done I/ (2040852): [257505.171875][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 15 0x404b10000 0xc05000 I/ (2040852): [257505.171875][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done I/ (2040852): [257505.171875][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 17 0x405730000 0xc05000 I/ (2040852): [257505.171875][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done I/ (2040852): [257505.171875][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 19 0x406350000 0xc05000 I/ (2040852): [257505.171875][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done I/ (2040852): [257505.171875][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 21 0x406f70000 0xc05000 I/ (2040852): [257505.171875][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done I/ (2040852): [257505.171875][hbn_vpf_interface.c]:[hbn_vnode_set_ochn_buf_attr][856]vin0 ctx0 vnode done. I/ (2040852): [257505.171875][chip_j6_interface.c]:[vpf_init_ip_version][277]get sys info success I/ (2040852): [257505.171875][chip_j6_interface.c]:[vpf_init_ip_version][280]SOC chip name: S100 I/ (2040852): [257505.171875][hbn_vpf_interface.c]:[hbn_vflow_create][3596]flowid 0 done I/ (2040852): [257505.171875][hbn_vpf_interface.c]:[hbn_vflow_add_vnode][3840][S0] vflow[0][0] vin0 done I/ (2040852): [257505.171875][deserial_if]:[hbn_deserial_create][283] create 1: deserial max96712 as fd 0x14332 I/ (2040852): [257505.171875][camera_if]:[hbn_camera_attach_to_deserial][460] camera imx728std attached to max96712 link 0 as deserial done I/ (2040852): [257505.171875][camera_vpf]:[camera_vpf_vin_attr_show][1077] flow0 vcon 0xdcd: rx0 i2c2 I/ (2040852): [257505.171875][deserial_lib]:[camera_deserial_config_parse][373] deserial0 max96712 auto power set gpio_enable = 2 I/ (2040852): [257505.171875][deserial_lib]:[camera_deserial_config_parse][415] deserial max96712 link[0]: imx728std:PINYOU_M24B0F160D12_S1R0T8E6@512 I/ (2040852): [257505.171875][deserial_lib]:[camera_deserial_config_parse][415] deserial max96712 link[1]: sc1336_gmsl:ONE_GM24F100D10_S2N2R0T8E3@256 I/ (2040852): [257505.171875][deserial_lib]:[camera_deserial_config_parse][415] deserial max96712 link[2]: sc1336_gmsl:ONE_GM24F100D10_S2N2R0T8E3@256 I/ (2040852): [257505.171875][deserial_lib]:[camera_deserial_config_parse][415] deserial max96712 link[3]: sc1336_gmsl:ONE_GM24F100D10_S2N2R0T8E3@256 I/ (2040852): [257505.171875][camera_i2c]:[is_timeout_i2c_init][159] env default i2c timeout 130ms I/ (2040852): [257505.171875][camera_i2c]:[camera_i2c_init][225] i2c2 init as fd 27 I/ (2040852): [257505.265625][deserial_lib]:[camera_deserial_reset][854] deserial0 max96712 reset PWDN gpio451=0-20ms-1 I/ (2040852): [257505.296875][poc_lib]:[camera_poc_power][491] poc0 power on EN gpio502=1 I/ (2040852): [257505.296875][max96712]:[deserializer_init][1258]deserial0 max96712 init begin I/ (2040852): [257505.296875][max96712]:[max96712_init][1144]deserial0 max96712 init begin I/ (2040852): [257505.296875][max96712]:[max96712_get_deserial_link_info][149]deserial0 max96712 config link num is 4 I/ (2040852): [257505.296875][max96712]:[max2008x_init][88]deserial0 max2008x_init begin I/ (2040852): [257505.406250][max96712]:[max96712_phy_lane_mode_cfg][725]deserial0 max96712 2x4 dphy 4lane I/ (2040852): [257505.406250][max96712]:[max96712_phy_speed_cfg][801]deserial0 max96712 4 link 2000Mbps deskew I/ (2040852): [257505.406250][deserial_lib]:[camera_deserial_devop_func][972] thread des0:max96712 work I/ (2040852): [257505.406250][deserial_lib]:[camera_deserial_init][1316] deserial0 max96712 init real done I/ (2040852): [257505.406250][camera_diag]:[cam_diag_init][700] cam_diag_init run 1 I/ (2040852): [257505.406250][camera_diag_report]:[report_thread][169] enter report_thread... diag_report 0x0 run 1 I/ (2040852): [257505.406250][camera_diag_reg]:[reg_polling_thread][273] enter bus 0 thread_id ffff8f85f120 I/ (2040852): [257505.406250][camera_diag_reg]:[reg_polling_thread][273] enter bus 1 thread_id ffff8f04f120 I/ (2040852): [257505.406250][camera_diag_reg]:[reg_polling_thread][273] enter bus 2 thread_id ffff8e83f120 I/ (2040852): [257505.406250][camera_diag_reg]:[reg_polling_thread][273] enter bus 3 thread_id ffff8e02f120 I/ (2040852): [257505.406250][camera_diag_reg]:[reg_polling_thread][273] enter bus 4 thread_id ffff8d81f120 I/ (2040852): [257505.406250][camera_diag_reg]:[reg_polling_thread][273] enter bus 5 thread_id ffff8d00f120 I/ (2040852): [257505.406250][camera_diag_reg]:[reg_polling_thread][273] enter bus 6 thread_id ffff8c7ff120 I/ (2040852): [257505.406250][camera_diag_reg]:[reg_polling_thread][273] enter bus 7 thread_id ffff8bfef120 I/ (2040852): [257505.406250][camera_diag_reg]:[reg_polling_thread][273] enter bus 8 thread_id ffff8b7df120 I/ (2040852): [257505.406250][camera_diag_reg]:[reg_polling_thread][273] enter bus 9 thread_id ffff8afcf120 I/ (2040852): [257505.406250][camera_diag_gpio]:[gpio_polling_thread][95] gpio polling thread starting! I/ (2040852): [257505.406250][camera_diag_mon]:[mon_sub_list_thread][547] Local mon_type1 sub thread enter... I/ (2040852): [257505.406250][camera_diag_gpioe]:[gpioe_epoll_thread][307] gpioe polling thread starting! I/ (2040852): [257505.406250][camera_diag_mon]:[mon_sub_list_thread][547] Local mon_type2 sub thread enter... I/ (2040852): [257505.406250][camera_diag_gpioe]:[diag_gpioe_init][480] sub_thid 0xffff88f8f120, thread_id 0xffff8979f120 I/ (2040852): [257505.406250][camera_diag_gpioe]:[diag_gpioe_init][481] done I/ (2040852): [257505.406250][camera_diag_gpio]:[gpio_polling_thread][95] gpio polling thread starting! I/ (2040852): [257505.406250][camera_diag_mon]:[mon_sub_list_thread][547] Remote mon_type1 sub thread enter... I/ (2040852): [257505.406250][camera_diag_gpioe]:[gpioe_epoll_thread][307] gpioe polling thread starting! I/ (2040852): [257505.406250][camera_diag_gpioe]:[diag_gpioe_init][480] sub_thid 0xffff86f4f120, thread_id 0xffff8775f120 I/ (2040852): [257505.406250][camera_diag_mon]:[mon_sub_list_thread][547] Remote mon_type2 sub thread enter... I/ (2040852): [257505.406250][camera_diag_gpioe]:[diag_gpioe_init][481] done I/ (2040852): [257505.406250][camera_diag]:[cam_diag_init][726] cam_diag_init done I/ (2040852): [257505.406250][max_serial]:max_serial_init sensor0 imx728std serial_init begain I/ (2040852): [257505.406250][max_serial]:max_serial_init deserial0 max96712 poc_map = 0x1320, poc_mask = 0x1 I/ (2040852): [257505.406250][max_serial]:poc_reset deserial0 max96712 poc_reset 0x1 begain I/ (2040852): [257505.406250][max96712]:[max96712_get_deserial_link_info][149]deserial0 max96712 config link num is 4 I/ (2040852): [257505.406250][max_serial]:poc_reset desreial0 max96712 poc power down 0x1, set val: 0x10 I/ (2040852): [257505.515625][max_serial]:poc_reset desreial0 max96712 poc power up 0x1, set val: 0x11 I/ (2040852): [257505.609375][max_serial]:max_serial_init sensor0 imx728std poc reset 0x1 done I/ (2040852): [257506.625000][max_serial]:i2c_addr_map sensor0 imx728std i2c map begain I/ (2040852): [257506.625000][max_serial]:quad_serial_i2c_addr_map sensor0 imx728std serial i2c map 0x41 begin I/ (2040852): [257506.625000][max96712]:[max96712_get_deserial_link_info][149]deserial0 max96712 config link num is 4 I/ (2040852): [257506.625000][max96712]:[max96712_link_enable][383]deserial0 max96712 link reg 0x6 = 0xf0 mask 0x1 val = 0xf1 I/ (2040852): [257506.671875][max96712]:[max96712_link_lock_check][353]deserial0 max96712 link 0x1 locked, lock time is 0ms I/ (2040852): [257506.671875][max_serial]:serial_rclk_output_config sensor0 imx728std serial mfp4 out 24M rclk config I/ (2040852): [257506.671875][max_serial]:max_serial_init sensor0 imx728std rst_mfp is 0 I/ (2040852): [257506.765625][imx728std]:port:0 imx728_hdr_4exp_30fps_init_setting = 5140 I/ (2040852): [257507.671875][imx728std]:port:0 config sensor_mode: 5 I/ (2040852): [257507.671875][imx728std]:port:0 imx728std bayer_pattern = 0 I/ (2040852): [257507.671875][imx728std]:sensor data bits fill pwl 20bit I/ (2040852): [257507.671875][imx728std]:sensor snr mode defaulut as 0 I/ (2040852): [257507.671875][imx728std]:port:0 imx728_emb_disable = 21 I/ (2040852): [257507.687500][imx728std]:sensor_mode_config_init() write imx728 emb disable setting success... I/ (2040852): [257507.687500][max96712]:[max96712_trig_mode_config][580]deserial0 max96712 TX des_addr:0x29, des_link:0 mfp:5, reg_addr:0x0310, val:0xeb I/ (2040852): [257507.687500][max96712]:[max96712_trig_mode_config][595]deserial0 max96712 TX des_addr:0x29, des_link:0 mfp:5, reg_addr:0x0311, val:0xa4 I/ (2040852): [257507.687500][max_serial]:max_serial_mfp_config Serial RX bus:2, mfp:8, ser_addr:0x41, reg_addr:0x02d8, val:0x44 I/ (2040852): [257507.687500][imx728std]:port:0 imx728 trigger shutter mode is Sync Exposure I/ (2040852): [257507.687500][imx728std]:port:0 enter userspace_control enable = 96 I/ (2040852): [257507.687500][imx728std]:port:0 enter userspace_control enable = 96 I/ (2040852): [257507.687500][sensor_lib]:[camera_sensor_ctrl_func][1415] thread sen0_ctrl:imx728std work en 0x60 I/ (2040852): [257507.687500][sensor_lib]:[camera_sensor_devop_func][1673] thread sen0:imx728std work I/ (2040852): [257507.687500][sensor_lib]:[camera_sensor_init][2019] sensor0 imx728std init real done I/ (2040852): [257507.687500][sensor_lib]:[camera_sensor_get_iparam][3166] sensor0 imx728std 3840x2160@30.0ps state 1-SUCCESS I/ (2040852): [257507.687500][deserial_lib]:[camera_deserial_csi_attr_parse][658] deserial0 max96712 csi attr rx parse: dphy 4lane 8000Mbps 0x2c I/ (2040852): [257507.781250][hbn_vpf_interface.c]:[hbn_vnode_set_inter_attr_s][1536]vin0 ctx0 vnode done. I/ (2040852): [257507.781250][camera_vpf]:[camera_vpf_vin_attach_deserial][1578] attach deserial0:0 camera0 to vin 0x8765 done I/ (2040852): [257507.781250][deserial_if]:[hbn_deserial_attach_to_vin][362] deserial0 max96712 link 0 attached to vin 0x8765 as vin done I/ (2040852): [257507.781250][camera_reg]:[camera_reg_i2c_bit_write8][230] write8 2@0x29: 0x08a0=0x84 I/ (2040852): [257507.781250][deserial_lib]:[camera_deserial_stream_on][1532] deserial0 max96712 lib stream on done 0.636ms I/ (2040852): [257507.781250][imx728std]:port:0 config sensor_mode: 5 I/ (2040852): [257507.781250][imx728std]:port:0 imx728_SF_Stream_on_Add_Parameter = 16 I/ (2040852): [257507.828125][imx728std]:port:0 is streaming on I/ (2040852): [257507.828125][sensor_lib]:[camera_sensor_start][2198] sensor0 imx728std start done 44.138ms I/ (2040852): [257507.828125][hbn_vpf_interface.c]:[hbn_vnode_start][1880]vin0 ctx0 vnode done. cost_time = 2667 ms I/ (2040852): [257507.828125][hbn_vpf_interface.c]:[hbn_vflow_start][3969]flow 0 done, cost_time = 2666 ms E/ (2040852): [257508.828125][vpf_ioctl.c]:[vio_dev_node_dqbuf_poll][1236]dev poll Timeout(1000): 0, Success I/ (2040852): [257508.828125][vpf_ioctl.c]:[vio_dbg_log_show][1178] I/ (2040852): [S0] vin0_C0* I/ (2040852): gtask-vin0: res 1 rcnt 0 [vin0:0] I/ (2040852): [257508.828125][vpf_ioctl.c]:[vio_dbg_log_show][1178] I/ (2040852): ---------------------------------------------------------- I/ (2040852): flowid module cid chn FREE REQ PRO COM USED I/ (2040852): ---------------------------------------------------------- I/ (2040852): 0 vin0 0 0 16 0 0 0 0 I/ (2040852): 0 vin0 0 8 0 5 1 0 0 I/ (2040852): E/ (2040852): [257508.828125][hbn_vpf_interface.c]:[hbn_vnode_getframe][2081]vin0 ctx 0 dqbuf failed COMMON dequeue buffer error E/ (2040852): [257508.828125][vpf_ioctl.c]:[vpf_ioc_qbuf][1087]failed to ioctl: VIO_IOC_QBUF (22 - Invalid argument) E/ (2040852): [257508.828125][hbn_vpf_interface.c]:[hbn_vnode_releaseframe][3154]vin0 ctx 0 vpf_ioc_qbuf failed COMMON queue buffer error E/ (2040852): [257509.828125][vpf_ioctl.c]:[vio_dev_node_dqbuf_poll][1236]dev poll Timeout(1000): 22, Invalid argument I/ (2040852): [257509.828125][vpf_ioctl.c]:[vio_dbg_log_show][1178]