--------- beginning of /dev/log_main 11-25 16:17:34.483 I/DumpServer( 1188): Stack trace server start 11-25 16:17:34.669 I/ ( 1252): [OTA_INFO][veeprom_flag.c:89] veeprom crc, oricrc[637d5378], newcrc[637d5378], offset[1352] 11-25 16:17:34.669 I/ ( 1252): [OTA_INFO][veeprom_flag.c:410] update part num: [0] 11-25 16:17:34.821 I/ ( 1252): [OTA_INFO][ota_boot_control.c:260] This slot has been marked success before, no need to mark it repeatedly 11-25 16:17:34.821 I/ ( 1252): [OTA_INFO][ota_boot_control.c:756] set slot:0 boot success 11-25 16:17:34.821 I/ ( 1252): [OTA_INFO][veeprom_flag.c:89] veeprom crc, oricrc[637d5378], newcrc[637d5378], offset[1352] 11-25 16:17:34.821 I/ ( 1252): [OTA_INFO][veeprom_flag.c:324] veeprom: flag [0], value [3] 11-25 16:18:55.970 I/ ( 3055): [62.832279][camera_diag]:[cam_diag_create][643] cam_diag_create done 11-25 16:18:55.971 I/ ( 3055): [62.833557][camera_if]:[hbn_camera_create][234] create 0: camera kp612w as fd 0x4331 11-25 16:18:55.971 I/ ( 3055): [62.834217][hbn_vpf_interface.c]:[vnode_alloc_magic_fd][293]cur_index 0 11-25 16:18:55.971 I/ ( 3055): [62.834229][hbn_vpf_interface.c]:[hbn_vnode_open][389]vnode create done. 11-25 16:18:55.972 I/ ( 3055): [62.834457][hbn_vpf_interface.c]:[hbn_vnode_open][398]vin4 ctx 0 vnode open 11-25 16:18:55.972 I/ ( 3055): [62.834503][hbn_vpf_interface.c]:[hbn_vnode_set_attr][550]vin4 ctx0 vnode done. 11-25 16:18:55.972 I/ ( 3055): [62.834538][hbn_vpf_interface.c]:[hbn_vnode_set_ichn_attr][1298]vin4 ctx0 vnode done. 11-25 16:18:55.972 I/ ( 3055): [62.834572][hbn_vpf_interface.c]:[hbn_vnode_set_ochn_attr][925]vin4 ctx0 vnode done. 11-25 16:18:55.972 W/ ( 3055): [62.83472][3055:3055][MEM_MANAGER] Memory module has been opened. 11-25 16:18:55.972 I/ ( 3055): [62.834736][vpf_buf_mgr.c]:[vnode_buf_mgr_create][488]buffer_num 6 11-25 16:18:55.972 I/ ( 3055): [62.834743][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 11 0x4032d0000 0x50a00 11-25 16:18:55.972 I/ ( 3055): [62.834751][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x403320a00 0x28500 11-25 16:18:55.972 I/ ( 3055): [62.834831][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 11-25 16:18:55.972 I/ ( 3055): [62.834839][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 13 0x403360000 0x50a00 11-25 16:18:55.972 I/ ( 3055): [62.834843][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x4033b0a00 0x28500 11-25 16:18:55.972 I/ ( 3055): [62.834873][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 11-25 16:18:55.972 I/ ( 3055): [62.834885][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 15 0x4033f0000 0x50a00 11-25 16:18:55.972 I/ ( 3055): [62.834900][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x403440a00 0x28500 11-25 16:18:55.972 I/ ( 3055): [62.834927][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 11-25 16:18:55.972 I/ ( 3055): [62.834934][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 17 0x403480000 0x50a00 11-25 16:18:55.972 I/ ( 3055): [62.834942][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x4034d0a00 0x28500 11-25 16:18:55.972 I/ ( 3055): [62.834961][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 11-25 16:18:55.972 I/ ( 3055): [62.834969][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 19 0x403510000 0x50a00 11-25 16:18:55.972 I/ ( 3055): [62.834972][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x403560a00 0x28500 11-25 16:18:55.972 I/ ( 3055): [62.834995][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 11-25 16:18:55.972 I/ ( 3055): [62.835003][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane0 21 0x4035a0000 0x50a00 11-25 16:18:55.972 I/ ( 3055): [62.835007][vpf_buf_mgr.c]:[buffer_mgr_image_map][321]plane1 0 0x4035f0a00 0x28500 11-25 16:18:55.972 I/ ( 3055): [62.835049][vpf_buf_mgr.c]:[buffer_mgr_image_map][357]done 11-25 16:18:55.972 I/ ( 3055): [62.835056][hbn_vpf_interface.c]:[hbn_vnode_set_ochn_buf_attr][852]vin4 ctx0 vnode done. 11-25 16:18:55.972 I/ ( 3055): [62.835087][hbn_vpf_interface.c]:[hbn_vflow_create][3378]flowid 0 done 11-25 16:18:55.972 I/ ( 3055): [62.835094][hbn_vpf_interface.c]:[hbn_vflow_add_vnode][3622][S0] vflow[0][0] vin4 done 11-25 16:18:55.973 I/ ( 3055): [62.835949][deserial_if]:[hbn_deserial_create][283] create 1: deserial max96712 as fd 0x14332 11-25 16:18:55.973 I/ ( 3055): [62.835983][camera_if]:[hbn_camera_attach_to_deserial][460] camera kp612w attached to max96712 link 0 as deserial done 11-25 16:18:55.973 I/ ( 3055): [62.836033][camera_vpf]:[camera_vpf_vin_attr_show][1035] flow0 vcon 0x5cd: rx4 i2c3 11-25 16:18:55.973 I/ ( 3055): [62.836056][deserial_lib]:[camera_deserial_config_parse][371] deserial4 max96712 auto power set gpio_enable = 2 11-25 16:18:55.973 I/ ( 3055): [62.836098][deserial_lib]:[camera_deserial_config_parse][413] deserial max96712 link[0]: kp612w:XH05A_M24F217D4P0A2_S2R0T8E5@512 11-25 16:18:55.973 I/ ( 3055): [62.836121][deserial_lib]:[camera_deserial_config_parse][413] deserial max96712 link[1]: kp612w:XH05A_M24F217D4P0A2_S2R0T8E5@512 11-25 16:18:55.973 I/ ( 3055): [62.836143][deserial_lib]:[camera_deserial_config_parse][413] deserial max96712 link[2]: kp612w:XH05A_M24F217D4P0A2_S2R0T8E5@512 11-25 16:18:55.973 I/ ( 3055): [62.836166][deserial_lib]:[camera_deserial_config_parse][413] deserial max96712 link[3]: kp612w:XH05A_M24F217D4P0A2_S2R0T8E5@512 11-25 16:18:55.973 I/ ( 3055): [62.836193][camera_i2c]:[is_timeout_i2c_init][162] cam default i2c timeout 130ms 11-25 16:18:55.973 I/ ( 3055): [62.836208][camera_i2c]:[camera_i2c_init][231] i2c3 init as fd 27 11-25 16:18:56.073 I/ ( 3055): [62.936291][deserial_lib]:[camera_deserial_reset][813] deserial4 max96712 reset PWDN gpio452=0-20ms-1 11-25 16:18:56.106 I/ ( 3055): [62.969090][poc_lib]:[camera_poc_power][484] poc4 power on EN gpio433=1 11-25 16:18:56.106 I/ ( 3055): [62.969189][max96712]:[deserializer_init][1219]deserial4 max96712 init begin 11-25 16:18:56.106 I/ ( 3055): [62.969208][max96712]:[max96712_init][1109]deserial4 max96712 init begin 11-25 16:18:56.106 I/ ( 3055): [62.969215][max96712]:[max96712_get_deserial_link_info][131]deserial4 max96712 config link num is 4 11-25 16:18:56.310 I/ ( 3055): [63.172935][max96712]:[max96712_phy_lane_mode_cfg][713]deserial4 max96712 4x2 dphy 1lane 11-25 16:18:56.312 I/ ( 3055): [63.174362][max96712]:[max96712_phy_speed_cfg][790]deserial4 max96712 4 link 500Mbps 11-25 16:18:56.364 I/ ( 3055): [63.227268][max96712]:[data_intf_init][843]deserial4 max96712 pipe num = 4, pipe_config_index = 15, intf_init = 0 11-25 16:18:56.369 I/ ( 3055): [63.232117][deserial_lib]:[camera_deserial_devop_func][928] thread des4:max96712 work 11-25 16:18:56.371 I/ ( 3055): [63.234165][deserial_lib]:[camera_deserial_init][1254] deserial4 max96712 init real done 11-25 16:18:56.371 I/ ( 3055): [63.234280][camera_diag_report]:[report_thread][163] enter report_thread... diag_report 0x0 run 1 11-25 16:18:56.372 I/ ( 3055): [63.234337][camera_diag_reg]:[reg_polling_thread][262] enter bus 0 thread_id ffff9ad4f120 11-25 16:18:56.372 I/ ( 3055): [63.234375][camera_diag_reg]:[reg_polling_thread][262] enter bus 1 thread_id ffff9a53f120 11-25 16:18:56.372 I/ ( 3055): [63.234417][camera_diag_reg]:[reg_polling_thread][262] enter bus 2 thread_id ffff99d2f120 11-25 16:18:56.372 I/ ( 3055): [63.234451][camera_diag_reg]:[reg_polling_thread][262] enter bus 3 thread_id ffff9951f120 11-25 16:18:56.372 I/ ( 3055): [63.234482][camera_diag_reg]:[reg_polling_thread][262] enter bus 4 thread_id ffff98d0f120 11-25 16:18:56.372 I/ ( 3055): [63.234505][camera_diag_reg]:[reg_polling_thread][262] enter bus 5 thread_id ffff984ff120 11-25 16:18:56.372 I/ ( 3055): [63.234547][camera_diag_reg]:[reg_polling_thread][262] enter bus 6 thread_id ffff97cef120 11-25 16:18:56.372 I/ ( 3055): [63.234577][camera_diag_reg]:[reg_polling_thread][262] enter bus 7 thread_id ffff974df120 11-25 16:18:56.372 I/ ( 3055): [63.234600][camera_diag_reg]:[reg_polling_thread][262] enter bus 8 thread_id ffff96ccf120 11-25 16:18:56.372 I/ ( 3055): [63.234631][camera_diag_reg]:[reg_polling_thread][262] enter bus 9 thread_id ffff964bf120 11-25 16:18:56.372 I/ ( 3055): [63.234665][camera_diag_gpio]:[gpio_polling_thread][94] gpio polling thread starting! 11-25 16:18:56.372 I/ ( 3055): [63.234692][camera_diag_mon]:[mon_sub_list_thread][526] Local mon_type1 sub thread enter... 11-25 16:18:56.372 I/ ( 3055): [63.234745][camera_diag_gpioe]:[gpioe_epoll_thread][203] gpioe polling thread starting! 11-25 16:18:56.372 I/ ( 3055): [63.234760][camera_diag_mon]:[mon_sub_list_thread][526] Local mon_type2 sub thread enter... 11-25 16:18:56.372 I/ ( 3055): [63.234756][camera_diag_gpioe]:[diag_gpioe_init][354] sub_thid 0xffff9447f120, thread_id 0xffff94c8f120 11-25 16:18:56.372 I/ ( 3055): [63.234768][camera_diag_gpioe]:[diag_gpioe_init][355] done 11-25 16:18:56.372 I/ ( 3055): [63.234818][camera_diag_gpio]:[gpio_polling_thread][94] gpio polling thread starting! 11-25 16:18:56.372 I/ ( 3055): [63.234844][camera_diag_mon]:[mon_sub_list_thread][526] Remote mon_type1 sub thread enter... 11-25 16:18:56.372 I/ ( 3055): [63.234882][camera_diag_gpioe]:[gpioe_epoll_thread][203] gpioe polling thread starting! 11-25 16:18:56.372 I/ ( 3055): [63.234898][camera_diag_gpioe]:[diag_gpioe_init][354] sub_thid 0xffff9243f120, thread_id 0xffff92c4f120 11-25 16:18:56.372 I/ ( 3055): [63.234898][camera_diag_mon]:[mon_sub_list_thread][526] Remote mon_type2 sub thread enter... 11-25 16:18:56.372 I/ ( 3055): [63.234909][camera_diag_gpioe]:[diag_gpioe_init][355] done 11-25 16:18:56.372 I/ ( 3055): [63.234928][camera_diag]:[cam_diag_init][716] cam_diag_init done 11-25 16:18:56.372 I/ ( 3055): [63.234997][max_serial]:max_serial_init sensor0 kp612w serial_init begain 11-25 16:18:56.372 I/ ( 3055): [63.235004][max_serial]:max_serial_init deserial4 max96712 poc_map = 0x1320, poc_mask = 0x1 11-25 16:18:56.372 I/ ( 3055): [63.235008][max_serial]:poc_reset deserial4 max96712 poc_reset 0x1 begain 11-25 16:18:56.372 I/ ( 3055): [63.235020][max96712]:[max96712_get_deserial_link_info][131]deserial4 max96712 config link num is 4 11-25 16:18:56.372 I/ ( 3055): [63.235268][max_serial]:poc_reset desreial4 max96712 poc power down 0x1, set val: 0x1e 11-25 16:18:56.473 I/ ( 3055): [63.335690][max_serial]:poc_reset desreial4 max96712 poc power up 0x1, set val: 0x1f 11-25 16:18:56.573 I/ ( 3055): [63.435776][max_serial]:max_serial_init sensor0 kp612w poc reset 0x1 done 11-25 16:18:56.573 I/ ( 3055): [63.435799][max_serial]:i2c_addr_map sensor0 kp612w i2c map begain 11-25 16:18:56.573 I/ ( 3055): [63.435806][max_serial]:quad_serial_i2c_addr_map sensor0 kp612w serial i2c map 0x42 begin 11-25 16:18:56.573 I/ ( 3055): [63.435818][max_serial]:quad_serial_i2c_addr_map serial_addr=42, sensor_addr=3e, eeprom_addr=52, 11-25 16:18:56.573 I/ ( 3055): [63.435825][max96712]:[max96712_get_deserial_link_info][131]deserial4 max96712 config link num is 4 11-25 16:18:56.573 I/ ( 3055): [63.436028][max96712]:[max96712_link_enable][365]deserial4 max96712 link reg 0x6 = 0xff mask 0x1 val = 0xff 11-25 16:18:56.573 I/ ( 3055): [63.436043][max96712]:[max96712_link_enable][367]fangli add test 11-25 16:18:56.745 I/ ( 3055): [63.607658][max96712]:[max96712_link_lock_check][335]deserial4 max96712 link 0x1 locked, lock time is 0ms 11-25 16:18:56.745 I/ ( 3055): [63.608181][max_serial]:serial_pipeline_init max9295_pipeline_init datatype = 4 ser_model = 2 11-25 16:18:56.757 I/ ( 3055): [63.619328][max_serial]:max_serial_init sensor0 kp612w rst_mfp is 0 11-25 16:18:56.858 I/ ( 3055): [63.720699][sensor_lib]:[camera_sensor_devop_func][1510] thread sen0:kp612w work 11-25 16:18:56.860 I/ ( 3055): [63.722752][sensor_lib]:[camera_sensor_init][1838] sensor0 kp612w init real done 11-25 16:18:56.860 I/ ( 3055): [63.722775][sensor_lib]:[camera_sensor_get_iparam][2504] sensor0 kp612w 640x516@30.0ps state 1-SUCCESS 11-25 16:18:56.860 I/ ( 3055): [63.722809][deserial_lib]:[camera_deserial_csi_attr_parse][617] deserial4 max96712 csi attr rx parse: dphy 4lane 8000Mbps 0x1e 11-25 16:18:59.963 E/ ( 3055): [66.826141][vpf_ioctl.c]:[vpf_ioc_set_inter_attr][628]failed to ioctl: VIO_IOC_SET_INTER_ATTR (1 - Operation not permitted) 11-25 16:18:5[ 66.827594] [E|MIPI|hobot_mipi_host_ops.c+3880]:[RX4]: lane state of host phy is error: 0x10005 [ 66.827601] [E|MIPI|hobot_mipi_host_ops.c+4265]:[RX4]: wait phy stop state error [ 66.827604] [E|MIPI|hobot_mipi_host_ops.c+4426]:[RX4]: init error: -1 [ 66.827607] [E|VIN|hobot_vin_node_ops.c+755]: [S0] vin_node_set_internal_attr mipi_set_attr fail 9.963 E/ [ 66.827628] [E|VIN|hobot_dev_vin_node.c+488]: flow_id 0 vin_node_video_set_internal_attr error ( 3055): [66.826164][hbn_vpf_interface.c]:[vnode_set_inter_attr][1473]vin4 ctx 0 vpf_ioc_set_inter_attr failed COMMON illegal attribute 11-25 16:18:59.963 E/ ( 3055): [66.826172][camera_vpf]:[camera_vpf_vin_attach_deserial][1533] attach deserial4:0 camera0 to vin 0x8765 error -10 11-25 16:18:59.963 E/ ( 3055): [66.826180][camera_run]:[camera_attach_deserial_to_vin][2928] deserial4 max96712 link 0 camera0 kp612w attach vin 0x8765 error -10 11-25 16:18:59.963 I/ ( 3055): [66.826233][sensor_lib]:[camera_sensor_devop_func][1533] thread sen0:kp612w exit 11-25 16:18:59.963 I/ ( 3055): [66.826294][sensor_lib]:[camera_sensor_deinit][1941] sensor0 kp612w deinit real done 11-25 16:18:59.964 I/ ( 3055): [66.826340][deserial_lib]:[camera_deserial_devop_func][951] thread des4:max96712 exit 11-25 16:18:59.970 I/ ( 3055): [66.832611][poc_lib]:[camera_poc_power][484] poc4 power off EN gpio433=0 11-25 16:18:59.975 I/ ( 3055): [66.838097][deserial_lib]:[camera_deserial_power][766] deserial4 max96712 power off PWDN gpio452=0 11-25 16:18:59.975 I/ ( 3055): [66.838127][camera_i2c]:[camera_i2c_deinit][282] i2c3 deinit as fd 27 11-25 16:18:59.975 I/ ( 3055): [66.838150][deserial_lib]:[camera_deserial_deinit][1395] deserial4 max96712 deinit real done 11-25 16:18:59.984 I/ ( 3055): [66.846497][camera_diag_reg]:[reg_polling_thread][378] leave bus 9 thread_id ffff964bf120 11-25 16:18:59.984 I/ ( 3055): [66.846497][camera_diag_reg]:[reg_polling_thread][378] leave bus 7 thread_id ffff974df120 11-25 16:18:59.984 I/ ( 3055): [66.846497][camera_diag_mon]:[mon_pre_read_gpio_sublist][261] gpio list is empty 11-25 16:18:59.984 I/ ( 3055): [66.846497][camera_diag_mon]:[mon_sub_list_thread][586] Local mon_type1 sub thread exit... 11-25 16:18:59.984 I/ ( 3055): [66.846497][camera_diag_reg]:[reg_polling_thread][378] leave bus 6 thread_id ffff97cef120 11-25 16:18:59.984 I/ ( 3055): [66.846519][camera_diag_mon]:[mon_sub_list_thread][586] Remote mon_type1 sub thread exit... 11-25 16:18:59.984 I/ ( 3055): [66.846519][camera_diag_reg]:[reg_polling_thread][378] leave bus 3 thread_id ffff9951f120 11-25 16:18:59.984 I/ ( 3055): [66.846519][camera_diag_reg]:[reg_polling_thread][378] leave bus 5 thread_id ffff984ff120 11-25 16:18:59.984 I/ ( 3055): [66.846527][camera_diag_mon]:[mon_pre_read_gpio_sublist][261] gpio list is empty 11-25 16:18:59.984 I/ ( 3055): [66.846527][camera_diag_mon]:[mon_sub_list_thread][586] Local mon_type2 sub thread exit... 11-25 16:18:59.984 I/ ( 3055): [66.846527][camera_diag_mon]:[mon_sub_list_thread][586] Remote mon_type2 sub thread exit... 11-25 16:18:59.984 I/ ( 3055): [66.846535][camera_diag_gpioe]:[gpioe_epoll_thread][325] gpioe polling exit 11-25 16:18:59.984 I/ ( 3055): [66.846535][camera_diag_reg]:[reg_polling_thread][378] leave bus 2 thread_id ffff99d2f120 11-25 16:18:59.984 I/ ( 3055): [66.846542][camera_diag_reg]:[reg_polling_thread][378] leave bus 4 thread_id ffff98d0f120 11-25 16:18:59.984 I/ ( 3055): [66.846542][camera_diag_mon]:[mon_pre_read_gpio_sublist][261] gpio list is empty 11-25 16:18:59.984 I/ ( 3055): [66.846558][camera_diag_mon]:[mon_pre_read_gpio_sublist][261] gpio list is empty 11-25 16:18:59.984 I/ ( 3055): [66.846558][camera_diag_reg]:[reg_polling_thread][378] leave bus 8 thread_id ffff96ccf120 11-25 16:18:59.984 I/ ( 3055): [66.846504][camera_diag_reg]:[reg_polling_thread][378] leave bus 0 thread_id ffff9ad4f120 11-25 16:18:59.984 I/ ( 3055): [66.846565][camera_diag_reg]:[reg_polling_thread][378] leave bus 1 thread_id ffff9a53f120 11-25 16:18:59.984 I/ ( 3055): [66.846573][camera_diag_gpio]:[gpio_polling_thread][181] gpio polling exit 11-25 16:18:59.984 I/ ( 3055): [66.846619][camera_diag_gpio]:[gpio_polling_thread][181] gpio polling exit 11-25 16:18:59.984 I/ ( 3055): [66.846626][camera_diag_gpioe]:[gpioe_epoll_thread][325] gpioe polling exit 11-25 16:18:59.985 I/ ( 3055): [66.847488][camera_diag_gpioe]:[diag_gpioe_deinit][362] sub_thid 0xffff9447f120, thread_id 0xffff94c8f120 11-25 16:18:59.985 I/ ( 3055): [66.847519][camera_diag_gpioe]:[diag_gpioe_deinit][367] terminate_epoll 11-25 16:18:59.985 I/ ( 3055): [66.847542][camera_diag_gpioe]:[diag_gpioe_deinit][387] done 11-25 16:18:59.985 I/ ( 3055): [66.847588][camera_diag_gpioe]:[diag_gpioe_deinit][362] sub_thid 0xffff9243f120, thread_id 0xffff92c4f120 11-25 16:18:59.985 I/ ( 3055): [66.847603][camera_diag_gpioe]:[diag_gpioe_deinit][367] terminate_epoll 11-25 16:18:59.985 I/ ( 3055): [66.847618][camera_diag_gpioe]:[diag_gpioe_deinit][387] done 11-25 16:18:59.985 I/ ( 3055): [66.847633][camera_diag_report]:[report_thread][208] leave report_thread 11-25 16:18:59.985 I/ ( 3055): [66.847672][camera_diag_report]:[diag_report_deinit][236] diag_report_deinit done 11-25 16:18:59.985 I/ ( 3055): [66.847679][camera_diag]:[cam_diag_deinit][765] cam_diag_deinit done 11-25 16:18:59.985 E/ ( 3055): [66.847694][deserial_if]:[hbn_deserial_attach_to_vin][359] deserial max96712 link 0 attach to vin 0x8765 error -10