--------- beginning of /dev/log_main 08-26 23:42:14.687 I/ ( 4678): [1152.822632][camera_diag]:[cam_diag_create][643] cam_diag_create done 08-26 23:42:14.687 I/ ( 4678): [1152.822876][camera_if]:[hbn_camera_create][234] create 0: camera kp612w as fd 0x4331 08-26 23:42:14.688 I/ ( 4678): [1152.822876][hbn_vpf_interface.c]:[vnode_alloc_magic_fd][293]cur_index 0 08-26 23:42:14.688 I/ ( 4678): [1152.822876][hbn_vpf_interface.c]:[hbn_vnode_open][389]vnode create done. 08-26 23:42:14.688 I/ ( 4678): [1152.823120][hbn_vpf_interface.c]:[hbn_vnode_open][398]vin4 ctx 0 vnode open 08-26 23:42:14.688 I/ ( 4678): [1152.823120][hbn_vpf_interface.c]:[hbn_vnode_set_attr][550]vin4 ctx0 vnode done. 08-26 23:42:14.688 I/ ( 4678): [1152.823242][hbn_vpf_interface.c]:[hbn_vnode_set_ichn_attr][1298]vin4 ctx0 vnode done. 08-26 23:42:14.688 I/ ( 4678): [1152.823242][hbn_vpf_interface.c]:[hbn_vnode_set_ochn_attr][925]vin4 ctx0 vnode done. 08-26 23:42:14.688 W/ ( 4678): [1152.82342][4678:4678][MEM_MANAGER] Memory module has been opened. 08-26 23:42:14.688 I/ ( 4678): [1152.823364][vpf_buf_mgr.c]:[vnode_buf_mgr_create][488]buffer_num 6 08-26 23:42:14.688 I/ ( 4678): [1152.823364][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 11 0x4032d0000 0x50000 08-26 23:42:14.688 I/ ( 4678): [1152.823364][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x403320000 0x28000 08-26 23:42:14.688 I/ ( 4678): [1152.823608][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 08-26 23:42:14.688 I/ ( 4678): [1152.823608][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 13 0x403360000 0x50000 08-26 23:42:14.688 I/ ( 4678): [1152.823608][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x4033b0000 0x28000 08-26 23:42:14.688 I/ ( 4678): [1152.823608][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 08-26 23:42:14.688 I/ ( 4678): [1152.823730][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 15 0x4033f0000 0x50000 08-26 23:42:14.688 I/ ( 4678): [1152.823730][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x403440000 0x28000 08-26 23:42:14.688 I/ ( 4678): [1152.823730][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 08-26 23:42:14.688 I/ ( 4678): [1152.823730][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 17 0x403480000 0x50000 08-26 23:42:14.688 I/ ( 4678): [1152.823730][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x4034d0000 0x28000 08-26 23:42:14.688 I/ ( 4678): [1152.823730][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 08-26 23:42:14.688 I/ ( 4678): [1152.823730][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 19 0x403510000 0x50000 08-26 23:42:14.688 I/ ( 4678): [1152.823730][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x403560000 0x28000 08-26 23:42:14.688 I/ ( 4678): [1152.823730][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 08-26 23:42:14.688 I/ ( 4678): [1152.823853][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 21 0x4035a0000 0x50000 08-26 23:42:14.688 I/ ( 4678): [1152.823853][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x4035f0000 0x28000 08-26 23:42:14.688 I/ ( 4678): [1152.823853][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 08-26 23:42:14.688 I/ ( 4678): [1152.823853][hbn_vpf_interface.c]:[hbn_vnode_set_ochn_buf_attr][852]vin4 ctx0 vnode done. 08-26 23:42:14.688 I/ ( 4678): [1152.823853][hbn_vpf_interface.c]:[hbn_vflow_create][3378]flowid 0 done 08-26 23:42:14.688 I/ ( 4678): [1152.823853][hbn_vpf_interface.c]:[hbn_vflow_add_vnode][3622][S0] vflow[0][0] vin4 done 08-26 23:42:14.689 I/ ( 4678): [1152.824097][deserial_if]:[hbn_deserial_create][283] create 1: deserial max96712 as fd 0x14332 08-26 23:42:14.689 I/ ( 4678): [1152.824097][camera_if]:[hbn_camera_attach_to_deserial][460] camera kp612w attached to max96712 link 0 as deserial done 08-26 23:42:14.689 I/ ( 4678): [1152.824219][camera_vpf]:[camera_vpf_vin_attr_show][1035] flow0 vcon 0x5cd: rx4 i2c3 08-26 23:42:14.689 I/ ( 4678): [1152.824219][deserial_lib]:[camera_deserial_config_parse][371] deserial4 max96712 auto power set gpio_enable = 2 08-26 23:42:14.689 I/ ( 4678): [1152.824219][deserial_lib]:[camera_deserial_config_parse][413] deserial max96712 link[0]: kp612w:XH05A_M24F217D4P0A2_S2R0T8E5@256 08-26 23:42:14.689 I/ ( 4678): [1152.824219][deserial_lib]:[camera_deserial_config_parse][413] deserial max96712 link[1]: kp612w:XH05A_M24F217D4P0A2_S2R0T8E5@256 08-26 23:42:14.689 I/ ( 4678): [1152.824219][deserial_lib]:[camera_deserial_config_parse][413] deserial max96712 link[2]: kp612w:XH05A_M24F217D4P0A2_S2R0T8E5@256 08-26 23:42:14.689 I/ ( 4678): [1152.824341][deserial_lib]:[camera_deserial_config_parse][413] deserial max96712 link[3]: kp612w:XH05A_M24F217D4P0A2_S2R0T8E5@256 08-26 23:42:14.689 I/ ( 4678): [1152.824341][camera_i2c]:[is_timeout_i2c_init][162] cam default i2c timeout 130ms 08-26 23:42:14.689 I/ ( 4678): [1152.824341][camera_i2c]:[camera_i2c_init][231] i2c3 init as fd 27 08-26 23:42:14.789 I/ ( 4678): [1152.924438][deserial_lib]:[camera_deserial_reset][813] deserial4 max96712 reset PWDN gpio452=0-20ms-1 08-26 23:42:14.821 I/ ( 4678): [1152.956421][poc_lib]:[camera_poc_power][484] poc4 power on EN gpio433=1 08-26 23:42:14.821 I/ ( 4678): [1152.956543][max96712]:[deserializer_init][1216]deserial4 max96712 init begin 08-26 23:42:14.821 I/ ( 4678): [1152.956543][max96712]:[max96712_init][1106]deserial4 max96712 init begin 08-26 23:42:14.821 I/ ( 4678): [1152.956543][max96712]:[max96712_get_deserial_link_info][131]deserial4 max96712 config link num is 4 08-26 23:42:15.024 I/ ( 4678): [1153.159790][max96712]:[max96712_phy_lane_mode_cfg][710]deserial4 max96712 2x4 dphy 4lane 08-26 23:42:15.026 I/ ( 4678): [1153.161377][max96712]:[max96712_phy_speed_cfg][787]deserial4 max96712 4 link 500Mbps 08-26 23:42:15.079 I/ ( 4678): [1153.214233][max96712]:[data_intf_init][840]deserial4 max96712 pipe num = 4, pipe_config_index = 15, intf_init = 0 08-26 23:42:15.085 I/ ( 4678): [1153.220459][deserial_lib]:[camera_deserial_devop_func][928] thread des4:max96712 work 08-26 23:42:15.087 I/ ( 4678): [1153.222534][deserial_lib]:[camera_deserial_init][1254] deserial4 max96712 init real done 08-26 23:42:15.087 I/ ( 4678): [1153.222656][camera_diag_report]:[report_thread][163] enter report_thread... diag_report 0x0 run 1 08-26 23:42:15.087 I/ ( 4678): [1153.222778][camera_diag_reg]:[reg_polling_thread][262] enter bus 0 thread_id ffffa2f1f120 08-26 23:42:15.087 I/ ( 4678): [1153.222778][camera_diag_reg]:[reg_polling_thread][262] enter bus 1 thread_id ffffa270f120 08-26 23:42:15.087 I/ ( 4678): [1153.222778][camera_diag_reg]:[reg_polling_thread][262] enter bus 2 thread_id ffffa1eff120 08-26 23:42:15.087 I/ ( 4678): [1153.222900][camera_diag_reg]:[reg_polling_thread][262] enter bus 3 thread_id ffffa16ef120 08-26 23:42:15.088 I/ ( 4678): [1153.222900][camera_diag_reg]:[reg_polling_thread][262] enter bus 4 thread_id ffffa0edf120 08-26 23:42:15.088 I/ ( 4678): [1153.222900][camera_diag_reg]:[reg_polling_thread][262] enter bus 5 thread_id ffffa06cf120 08-26 23:42:15.088 I/ ( 4678): [1153.223022][camera_diag_reg]:[reg_polling_thread][262] enter bus 6 thread_id ffff9febf120 08-26 23:42:15.088 I/ ( 4678): [1153.223022][camera_diag_reg]:[reg_polling_thread][262] enter bus 7 thread_id ffff9f6af120 08-26 23:42:15.088 I/ ( 4678): [1153.223022][camera_diag_reg]:[reg_polling_thread][262] enter bus 8 thread_id ffff9ee9f120 08-26 23:42:15.088 I/ ( 4678): [1153.223022][camera_diag_reg]:[reg_polling_thread][262] enter bus 9 thread_id ffff9e68f120 08-26 23:42:15.088 I/ ( 4678): [1153.223145][camera_diag_gpio]:[gpio_polling_thread][94] gpio polling thread starting! 08-26 23:42:15.088 I/ ( 4678): [1153.223145][camera_diag_mon]:[mon_sub_list_thread][526] Local mon_type1 sub thread enter... 08-26 23:42:15.088 I/ ( 4678): [1153.223145][camera_diag_gpioe]:[gpioe_epoll_thread][203] gpioe polling thread starting! 08-26 23:42:15.088 I/ ( 4678): [1153.223267][camera_diag_gpioe]:[diag_gpioe_init][354] sub_thid 0xffff9c64f120, thread_id 0xffff9ce5f120 08-26 23:42:15.088 I/ ( 4678): [1153.223267][camera_diag_mon]:[mon_sub_list_thread][526] Local mon_type2 sub thread enter... 08-26 23:42:15.088 I/ ( 4678): [1153.223267][camera_diag_gpioe]:[diag_gpioe_init][355] done 08-26 23:42:15.088 I/ ( 4678): [1153.223267][camera_diag_gpio]:[gpio_polling_thread][94] gpio polling thread starting! 08-26 23:42:15.088 I/ ( 4678): [1153.223267][camera_diag_mon]:[mon_sub_list_thread][526] Remote mon_type1 sub thread enter... 08-26 23:42:15.088 I/ ( 4678): [1153.223389][camera_diag_gpioe]:[gpioe_epoll_thread][203] gpioe polling thread starting! 08-26 23:42:15.088 I/ ( 4678): [1153.223389][camera_diag_gpioe]:[diag_gpioe_init][354] sub_thid 0xffff9a60f120, thread_id 0xffff9ae1f120 08-26 23:42:15.088 I/ ( 4678): [1153.223389][camera_diag_mon]:[mon_sub_list_thread][526] Remote mon_type2 sub thread enter... 08-26 23:42:15.088 I/ ( 4678): [1153.223389][camera_diag_gpioe]:[diag_gpioe_init][355] done 08-26 23:42:15.088 I/ ( 4678): [1153.223389][camera_diag]:[cam_diag_init][716] cam_diag_init done 08-26 23:42:15.088 I/ ( 4678): [1153.223389][max_serial]:max_serial_init sensor0 kp612w serial_init begain 08-26 23:42:15.088 I/ ( 4678): [1153.223389][max_serial]:max_serial_init deserial4 max96712 poc_map = 0x1320, poc_mask = 0x1 08-26 23:42:15.088 I/ ( 4678): [1153.223511][max_serial]:poc_reset deserial4 max96712 poc_reset 0x1 begain 08-26 23:42:15.088 I/ ( 4678): [1153.223511][max96712]:[max96712_get_deserial_link_info][131]deserial4 max96712 config link num is 4 08-26 23:42:15.088 I/ ( 4678): [1153.223755][max_serial]:poc_reset desreial4 max96712 poc power down 0x1, set val: 0x1e 08-26 23:42:15.189 I/ ( 4678): [1153.323975][max_serial]:poc_reset desreial4 max96712 poc power up 0x1, set val: 0x1f 08-26 23:42:15.289 I/ ( 4678): [1153.424072][max_serial]:max_serial_init sensor0 kp612w poc reset 0x1 done 08-26 23:42:15.289 I/ ( 4678): [1153.424072][max_serial]:i2c_addr_map sensor0 kp612w i2c map begain 08-26 23:42:15.289 I/ ( 4678): [1153.424072][max_serial]:quad_serial_i2c_addr_map sensor0 kp612w serial i2c map 0x42 begin 08-26 23:42:15.289 I/ ( 4678): [1153.424072][max_serial]:quad_serial_i2c_addr_map serial_addr=42, sensor_addr=3e, eeprom_addr=52, 08-26 23:42:15.289 I/ ( 4678): [1153.424072][max96712]:[max96712_get_deserial_link_info][131]deserial4 max96712 config link num is 4 08-26 23:42:15.289 I/ ( 4678): [1153.424438][max96712]:[max96712_link_enable][365]deserial4 max96712 link reg 0x6 = 0xff mask 0x1 val = 0xff 08-26 23:42:15.289 I/ ( 4678): [1153.424438][max96712]:[max96712_link_enable][367]fangli add test 08-26 23:42:15.461 I/ ( 4678): [1153.596069][max96712]:[max96712_link_lock_check][335]deserial4 max96712 link 0x1 locked, lock time is 0ms 08-26 23:42:15.461 I/ ( 4678): [1153.596558][max_serial]:serial_pipeline_init max9295_pipeline_init datatype = 4 ser_model = 2 08-26 23:42:15.472 I/ ( 4678): [1153.607666][max_serial]:max_serial_init sensor0 kp612w rst_mfp is 0 08-26 23:42:15.574 I/ ( 4678): [1153.708984][sensor_lib]:[camera_sensor_devop_func][1510] thread sen0:kp612w work 08-26 23:42:15.576 I/ ( 4678): [1153.710938][sensor_lib]:[camera_sensor_init][1838] sensor0 kp612w init real done 08-26 23:42:15.576 I/ ( 4678): [1153.710938][sensor_lib]:[camera_sensor_get_iparam][2504] sensor0 kp612w 640x512@30.0ps state 1-SUCCESS 08-26 23:42:15.576 I/ ( 4678): [1153.710938][deserial_lib]:[camera_deserial_csi_attr_parse][617] deserial4 max96712 csi attr rx parse: dphy 4lane 8000Mbps 0x1e 08-26 23:42:15.677 I/ ( 4678): [1153.812256][hbn_vpf_interface.c]:[hbn_vnode_set_inter_attr][1532]vin4 ctx0 vnode done. 08-26 23:42:15.677 I/ ( 4678): [1153.812256][camera_vpf]:[camera_vpf_vin_attach_deserial][1536] attach deserial4:0 camera0 to vin 0x8765 done 08-26 23:42:15.677 I/ ( 4678): [1153.812378][deserial_if]:[hbn_deserial_attach_to_vin][362] deserial4 max96712 link 0 attached to vin 0x8765 as vin done 08-26 23:42:15.677 I/ ( 4678): [1153.812866][camera_reg]:[camera_reg_i2c_bit_write8][230] write8 3@0x29: 0x08a0=0x84 08-26 23:42:15.677 I/ ( 4678): [1153.812866][deserial_lib]:[camera_deserial_stream_on][1460] deserial4 max96712 lib stream on done 0.537ms 08-26 23:42:15.678 I/ ( 4678): [1153.812988][sensor_lib]:[camera_sensor_start][2017] sensor0 kp612w start done 0.000ms 08-26 23:42:15.678 I/ ( 4678): [1153.812988][hbn_vpf_interface.c]:[hbn_vnode_start][1762]vin4 ctx0 vnode done. cost_time = 991 ms 08-26 23:42:15.678 I/ ( 4678): [1153.812988][hbn_vpf_interface.c]:[hbn_vflow_start][3751]flow 0 done, cost_time = 990 ms 08-26 23:42:22.728 E/ ( 4678): [1160.862915][vpf_ioctl.c]:[vio_dev_node_dqbuf_poll][1010]dev poll Timeout(4000): 0, Success 08-26 23:42:22.728 I/ ( 4678): [1160.862915][vpf_ioctl.c]:[vio_dbg_log_show][962] 08-26 23:42:22.728 I/ ( 4678): [S0] vin4_C0* 08-26 23:42:22.728 I/ ( 4678): gtask-vin4: res 1 rcnt 0 [vin4:0] 08-26 23:42:22.728 I/ ( 4678): [1160.863159][vpf_ioctl.c]:[vio_dbg_log_show][962] 08-26 23:42:22.728 I/ ( 4678): ---------------------------------------------------------- 08-26 23:42:22.728 I/ ( 4678): flowid module cid chn FREE REQ PRO COM USED 08-26 23:42:22.728 I/ ( 4678): ---------------------------------------------------------- 08-26 23:42:22.728 I/ ( 4678): 0 vin4 0 0 16 0 0 0 0 08-26 23:42:22.728 I/ ( 4678): 0 vin4 0 8 0 5 1 0 0 08-26 23:42:22.728 I/ ( 4678): 08-26 23:42:22.728 E/ ( 4678): [1160.863159][hbn_vpf_interface.c]:[hbn_vnode_getframe][1958]vin4 ctx 0 dqbuf failed COMMON dequeue buffer error