--------- beginning of /dev/log_main 08-26 23:23:08.589 I/DumpServer( 1258): Stack trace server start 08-26 23:23:08.790 I/ ( 1307): [OTA_INFO][veeprom_flag.c:89] veeprom crc, oricrc[637d5378], newcrc[637d5378], offset[1352] 08-26 23:23:08.790 I/ ( 1307): [OTA_INFO][veeprom_flag.c:410] update part num: [0] 08-26 23:23:08.912 I/ ( 1307): [OTA_INFO][ota_boot_control.c:260] This slot has been marked success before, no need to mark it repeatedly 08-26 23:23:08.912 I/ ( 1307): [OTA_INFO][ota_boot_control.c:756] set slot:0 boot success 08-26 23:23:08.944 I/ ( 1307): [OTA_INFO][veeprom_flag.c:89] veeprom crc, oricrc[637d5378], newcrc[637d5378], offset[1352] 08-26 23:23:08.944 I/ ( 1307): [OTA_INFO][veeprom_flag.c:324] veeprom: flag [0], value [3] 08-26 23:24:31.595 I/ ( 3315): [89.841042][camera_diag]:[cam_diag_create][643] cam_diag_create done 08-26 23:24:31.596 I/ ( 3315): [89.842659][camera_if]:[hbn_camera_create][234] create 0: camera kp612w as fd 0x4331 08-26 23:24:31.597 I/ ( 3315): [89.843315][hbn_vpf_interface.c]:[vnode_alloc_magic_fd][293]cur_index 0 08-26 23:24:31.597 I/ ( 3315): [89.843330][hbn_vpf_interface.c]:[hbn_vnode_open][389]vnode create done. 08-26 23:24:31.597 I/ ( 3315): [89.843559][hbn_vpf_interface.c]:[hbn_vnode_open][398]vin4 ctx 0 vnode open 08-26 23:24:31.597 I/ ( 3315): [89.843613][hbn_vpf_interface.c]:[hbn_vnode_set_attr][550]vin4 ctx0 vnode done. 08-26 23:24:31.597 I/ ( 3315): [89.843643][hbn_vpf_interface.c]:[hbn_vnode_set_ichn_attr][1298]vin4 ctx0 vnode done. 08-26 23:24:31.597 I/ ( 3315): [89.843681][hbn_vpf_interface.c]:[hbn_vnode_set_ochn_attr][925]vin4 ctx0 vnode done. 08-26 23:24:31.598 W/ ( 3315): [89.84384][3315:3315][MEM_MANAGER] Memory module has been opened. 08-26 23:24:31.598 I/ ( 3315): [89.843864][vpf_buf_mgr.c]:[vnode_buf_mgr_create][488]buffer_num 6 08-26 23:24:31.598 I/ ( 3315): [89.843872][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 11 0x4032d0000 0x50000 08-26 23:24:31.598 I/ ( 3315): [89.843887][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x403320000 0x28000 08-26 23:24:31.598 I/ ( 3315): [89.843994][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 08-26 23:24:31.598 I/ ( 3315): [89.844055][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 13 0x403360000 0x50000 08-26 23:24:31.598 I/ ( 3315): [89.844063][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x4033b0000 0x28000 08-26 23:24:31.598 I/ ( 3315): [89.844109][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 08-26 23:24:31.598 I/ ( 3315): [89.844131][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 15 0x4033f0000 0x50000 08-26 23:24:31.598 I/ ( 3315): [89.844131][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x403440000 0x28000 08-26 23:24:31.598 I/ ( 3315): [89.844162][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 08-26 23:24:31.598 I/ ( 3315): [89.844170][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 17 0x403480000 0x50000 08-26 23:24:31.598 I/ ( 3315): [89.844177][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x4034d0000 0x28000 08-26 23:24:31.598 I/ ( 3315): [89.844200][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 08-26 23:24:31.598 I/ ( 3315): [89.844208][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 19 0x403510000 0x50000 08-26 23:24:31.598 I/ ( 3315): [89.844208][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x403560000 0x28000 08-26 23:24:31.598 I/ ( 3315): [89.844231][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 08-26 23:24:31.598 I/ ( 3315): [89.844238][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 21 0x4035a0000 0x50000 08-26 23:24:31.598 I/ ( 3315): [89.844238][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x4035f0000 0x28000 08-26 23:24:31.598 I/ ( 3315): [89.844269][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 08-26 23:24:31.598 I/ ( 3315): [89.844269][hbn_vpf_interface.c]:[hbn_vnode_set_ochn_buf_attr][852]vin4 ctx0 vnode done. 08-26 23:24:31.598 I/ ( 3315): [89.844307][hbn_vpf_interface.c]:[hbn_vflow_create][3378]flowid 0 done 08-26 23:24:31.598 I/ ( 3315): [89.844322][hbn_vpf_interface.c]:[hbn_vflow_add_vnode][3622][S0] vflow[0][0] vin4 done 08-26 23:24:31.599 I/ ( 3315): [89.845245][deserial_if]:[hbn_deserial_create][283] create 1: deserial max96712 as fd 0x14332 08-26 23:24:31.599 I/ ( 3315): [89.845284][camera_if]:[hbn_camera_attach_to_deserial][460] camera kp612w attached to max96712 link 0 as deserial done 08-26 23:24:31.599 I/ ( 3315): [89.845314][camera_vpf]:[camera_vpf_vin_attr_show][1035] flow0 vcon 0x5cd: rx4 i2c3 08-26 23:24:31.599 I/ ( 3315): [89.845329][deserial_lib]:[camera_deserial_config_parse][371] deserial4 max96712 auto power set gpio_enable = 2 08-26 23:24:31.599 I/ ( 3315): [89.845367][deserial_lib]:[camera_deserial_config_parse][413] deserial max96712 link[0]: kp612w:XH05A_M24F217D4P0A2_S2R0T8E5@256 08-26 23:24:31.599 I/ ( 3315): [89.845398][deserial_lib]:[camera_deserial_config_parse][413] deserial max96712 link[1]: kp612w:XH05A_M24F217D4P0A2_S2R0T8E5@256 08-26 23:24:31.599 I/ ( 3315): [89.845421][deserial_lib]:[camera_deserial_config_parse][413] deserial max96712 link[2]: kp612w:XH05A_M24F217D4P0A2_S2R0T8E5@256 08-26 23:24:31.599 I/ ( 3315): [89.845451][deserial_lib]:[camera_deserial_config_parse][413] deserial max96712 link[3]: kp612w:XH05A_M24F217D4P0A2_S2R0T8E5@256 08-26 23:24:31.599 I/ ( 3315): [89.845474][camera_i2c]:[is_timeout_i2c_init][162] cam default i2c timeout 130ms 08-26 23:24:31.599 I/ ( 3315): [89.845490][camera_i2c]:[camera_i2c_init][231] i2c3 init as fd 27 08-26 23:24:31.699 I/ ( 3315): [89.945580][deserial_lib]:[camera_deserial_reset][813] deserial4 max96712 reset PWDN gpio452=0-20ms-1 08-26 23:24:31.732 I/ ( 3315): [89.978363][poc_lib]:[camera_poc_power][484] poc4 power on EN gpio433=1 08-26 23:24:31.732 I/ ( 3315): [89.978462][max96712]:[deserializer_init][1219]deserial4 max96712 init begin 08-26 23:24:31.732 I/ ( 3315): [89.978477][max96712]:[max96712_init][1109]deserial4 max96712 init begin 08-26 23:24:31.732 I/ ( 3315): [89.978485][max96712]:[max96712_get_deserial_link_info][131]deserial4 max96712 config link num is 4 08-26 23:24:31.936 I/ ( 3315): [90.182411][max96712]:[max96712_phy_lane_mode_cfg][713]deserial4 max96712 4x2 cphy 2lane 08-26 23:24:31.938 I/ ( 3315): [90.184074][max96712]:[max96712_phy_speed_cfg][790]deserial4 max96712 4 link 500Mbps 08-26 23:24:31.991 I/ ( 3315): [90.236839][max96712]:[data_intf_init][843]deserial4 max96712 pipe num = 4, pipe_config_index = 15, intf_init = 0 08-26 23:24:31.995 I/ ( 3315): [90.241806][deserial_lib]:[camera_deserial_devop_func][928] thread des4:max96712 work 08-26 23:24:31.998 I/ ( 3315): [90.243858][deserial_lib]:[camera_deserial_init][1254] deserial4 max96712 init real done 08-26 23:24:31.998 I/ ( 3315): [90.243965][camera_diag_report]:[report_thread][163] enter report_thread... diag_report 0x0 run 1 08-26 23:24:31.998 I/ ( 3315): [90.244049][camera_diag_reg]:[reg_polling_thread][262] enter bus 0 thread_id ffff9a28f120 08-26 23:24:31.998 I/ ( 3315): [90.244118][camera_diag_reg]:[reg_polling_thread][262] enter bus 1 thread_id ffff99a7f120 08-26 23:24:31.998 I/ ( 3315): [90.244179][camera_diag_reg]:[reg_polling_thread][262] enter bus 2 thread_id ffff9926f120 08-26 23:24:31.998 I/ ( 3315): [90.244225][camera_diag_reg]:[reg_polling_thread][262] enter bus 3 thread_id ffff98a5f120 08-26 23:24:31.998 I/ ( 3315): [90.244263][camera_diag_reg]:[reg_polling_thread][262] enter bus 4 thread_id ffff9824f120 08-26 23:24:31.998 I/ ( 3315): [90.244286][camera_diag_reg]:[reg_polling_thread][262] enter bus 5 thread_id ffff97a3f120 08-26 23:24:31.998 I/ ( 3315): [90.244339][camera_diag_reg]:[reg_polling_thread][262] enter bus 6 thread_id ffff9722f120 08-26 23:24:31.998 I/ ( 3315): [90.244370][camera_diag_reg]:[reg_polling_thread][262] enter bus 7 thread_id ffff96a1f120 08-26 23:24:31.998 I/ ( 3315): [90.244392][camera_diag_reg]:[reg_polling_thread][262] enter bus 8 thread_id ffff9620f120 08-26 23:24:31.998 I/ ( 3315): [90.244431][camera_diag_reg]:[reg_polling_thread][262] enter bus 9 thread_id ffff959ff120 08-26 23:24:31.998 I/ ( 3315): [90.244469][camera_diag_gpio]:[gpio_polling_thread][94] gpio polling thread starting! 08-26 23:24:31.998 I/ ( 3315): [90.244507][camera_diag_mon]:[mon_sub_list_thread][526] Local mon_type1 sub thread enter... 08-26 23:24:31.998 I/ ( 3315): [90.244545][camera_diag_gpioe]:[gpioe_epoll_thread][203] gpioe polling thread starting! 08-26 23:24:31.998 I/ ( 3315): [90.244560][camera_diag_mon]:[mon_sub_list_thread][526] Local mon_type2 sub thread enter... 08-26 23:24:31.998 I/ ( 3315): [90.244560][camera_diag_gpioe]:[diag_gpioe_init][354] sub_thid 0xffff939bf120, thread_id 0xffff941cf120 08-26 23:24:31.998 I/ ( 3315): [90.244568][camera_diag_gpioe]:[diag_gpioe_init][355] done 08-26 23:24:31.998 I/ ( 3315): [90.244637][camera_diag_gpio]:[gpio_polling_thread][94] gpio polling thread starting! 08-26 23:24:31.998 I/ ( 3315): [90.244675][camera_diag_mon]:[mon_sub_list_thread][526] Remote mon_type1 sub thread enter... 08-26 23:24:31.998 I/ ( 3315): [90.244713][camera_diag_gpioe]:[gpioe_epoll_thread][203] gpioe polling thread starting! 08-26 23:24:31.998 I/ ( 3315): [90.244736][camera_diag_gpioe]:[diag_gpioe_init][354] sub_thid 0xffff9197f120, thread_id 0xffff9218f120 08-26 23:24:31.998 I/ ( 3315): [90.244743][camera_diag_mon]:[mon_sub_list_thread][526] Remote mon_type2 sub thread enter... 08-26 23:24:31.998 I/ ( 3315): [90.244751][camera_diag_gpioe]:[diag_gpioe_init][355] done 08-26 23:24:31.998 I/ ( 3315): [90.244759][camera_diag]:[cam_diag_init][716] cam_diag_init done 08-26 23:24:31.998 I/ ( 3315): [90.244820][max_serial]:max_serial_init sensor0 kp612w serial_init begain 08-26 23:24:31.998 I/ ( 3315): [90.244827][max_serial]:max_serial_init deserial4 max96712 poc_map = 0x1320, poc_mask = 0x1 08-26 23:24:31.998 I/ ( 3315): [90.244835][max_serial]:poc_reset deserial4 max96712 poc_reset 0x1 begain 08-26 23:24:31.999 I/ ( 3315): [90.244835][max96712]:[max96712_get_deserial_link_info][131]deserial4 max96712 config link num is 4 08-26 23:24:31.999 I/ ( 3315): [90.245087][max_serial]:poc_reset desreial4 max96712 poc power down 0x1, set val: 0x1e 08-26 23:24:32.099 I/ ( 3315): [90.345406][max_serial]:poc_reset desreial4 max96712 poc power up 0x1, set val: 0x1f 08-26 23:24:32.199 I/ ( 3315): [90.445442][max_serial]:max_serial_init sensor0 kp612w poc reset 0x1 done 08-26 23:24:32.199 I/ ( 3315): [90.445465][max_serial]:i2c_addr_map sensor0 kp612w i2c map begain 08-26 23:24:32.199 I/ ( 3315): [90.445465][max_serial]:quad_serial_i2c_addr_map sensor0 kp612w serial i2c map 0x42 begin 08-26 23:24:32.199 I/ ( 3315): [90.445473][max_serial]:quad_serial_i2c_addr_map serial_addr=42, sensor_addr=3e, eeprom_addr=52, 08-26 23:24:32.199 I/ ( 3315): [90.445480][max96712]:[max96712_get_deserial_link_info][131]deserial4 max96712 config link num is 4 08-26 23:24:32.199 I/ ( 3315): [90.445648][max96712]:[max96712_link_enable][365]deserial4 max96712 link reg 0x6 = 0xff mask 0x1 val = 0xff 08-26 23:24:32.199 I/ ( 3315): [90.445663][max96712]:[max96712_link_enable][367]fangli add test 08-26 23:24:32.371 I/ ( 3315): [90.617249][max96712]:[max96712_link_lock_check][335]deserial4 max96712 link 0x1 locked, lock time is 0ms 08-26 23:24:32.371 I/ ( 3315): [90.617752][max_serial]:serial_pipeline_init max9295_pipeline_init datatype = 4 ser_model = 2 08-26 23:24:32.383 I/ ( 3315): [90.628891][max_serial]:max_serial_init sensor0 kp612w rst_mfp is 0 08-26 23:24:32.484 I/ ( 3315): [90.730255][sensor_lib]:[camera_sensor_devop_func][1510] thread sen0:kp612w work 08-26 23:24:32.486 I/ ( 3315): [90.732315][sensor_lib]:[camera_sensor_init][1838] sensor0 kp612w init real done 08-26 23:24:32.486 I/ ( 3315): [90.732338][sensor_lib]:[camera_sensor_get_iparam][2504] sensor0 kp612w 640x512@30.0ps state 1-SUCCESS 08-26 23:24:32.486 I/ ( 3315): [90.732368][deserial_lib]:[camera_deserial_csi_attr_parse][617] deserial4 max96712 csi attr rx parse: dphy 4lane 8000Mbps 0x1e 08-26 23:24:32.587 I/ ( 3315): [90.833641][hbn_vpf_interface.c]:[hbn_vnode_set_inter_attr][1532]vin4 ctx0 vnode done. 08-26 23:24:32.587 I/ ( 3315): [90.833656][camera_vpf]:[camera_vpf_vin_attach_deserial][1536] attach deserial4:0 camera0 to vin 0x8765 done 08-26 23:24:32.587 I/ ( 3315): [90.833672][deserial_if]:[hbn_deserial_attach_to_vin][362] deserial4 max96712 link 0 attached to vin 0x8765 as vin done 08-26 23:24:32.588 I/ ( 3315): [90.834274][camera_reg]:[camera_reg_i2c_bit_write8][230] write8 3@0x29: 0x08a0=0x81 08-26 23:24:32.588 I/ ( 3315): [90.834290][deserial_lib]:[camera_deserial_stream_on][1460] deserial4 max96712 lib stream on done 0.550ms 08-26 23:24:32.588 I/ ( 3315): [90.834343][sensor_lib]:[camera_sensor_start][2017] sensor0 kp612w start done 0.000ms 08-26 23:24:32.588 I/ ( 3315): [90.834396][hbn_vpf_interface.c]:[hbn_vnode_start][1762]vin4 ctx0 vnode done. cost_time = 992 ms 08-26 23:24:32.588 I/ ( 3315): [90.834412][hbn_vpf_interface.c]:[hbn_vflow_start][3751]flow 0 done, cost_time = 991 ms 08-26 23:24:39.302 E/ ( 3315): [97.548355][vpf_ioctl.c]:[vio_dev_node_dqbuf_poll][1010]dev poll Timeout(4000): 0, Success 08-26 23:24:39.302 I/ ( 3315): [97.548386][vpf_ioctl.c]:[vio_dbg_log_show][962] 08-26 23:24:39.302 I/ ( 3315): [S0] vin4_C0* 08-26 23:24:39.302 I/ ( 3315): gtask-vin4: res 1 rcnt 0 [vin4:0] 08-26 23:24:39.302 I/ ( 3315): [97.548401][vpf_ioctl.c]:[vio_dbg_log_show][962] 08-26 23:24:39.302 I/ ( 3315): ---------------------------------------------------------- 08-26 23:24:39.302 I/ ( 3315): flowid module cid chn FREE REQ PRO COM USED 08-26 23:24:39.302 I/ ( 3315): ---------------------------------------------------------- 08-26 23:24:39.302 I/ ( 3315): 0 vin4 0 0 16 0 0 0 0 08-26 23:24:39.302 I/ ( 3315): 0 vin4 0 8 0 5 1 0 0 08-26 23:24:39.302 I/ ( 3315): 08-26 23:24:39.302 E/ ( 3315): [97.548409][hbn_vpf_interface.c]:[hbn_vnode_getframe][1958]vin4 ctx 0 dqbuf failed COMMON dequeue buffer error