Andy@admindeMBP my_first_flybirds % flybirds run -P ./features/test/android/base_en_click.feature 2022-07-11 15:05:25,584 - flybirds_log - INFO - flybirds cmd info: ./features/test/android/base_en_click.feature None --format=json report/1ae9c606-8943-48ec-b0cb-b3e02239fb13/report.json () True None True local 2022-07-11 15:05:25,585 - flybirds_log - INFO - report path: -o report/1ae9c606-8943-48ec-b0cb-b3e02239fb13/report.json 2022-07-11 15:05:25,585 - flybirds_log - INFO - screenshot path: report/1ae9c606-8943-48ec-b0cb-b3e02239fb13/screenshot 2022-07-11 15:05:25,585 - flybirds_log - INFO - the assembled behave execution command: behave ./features/test/android/base_en_click.feature --format=json -o report/1ae9c606-8943-48ec-b0cb-b3e02239fb13/report.json --define screenShotDir=cmVwb3J0LzFhZTljNjA2LTg5NDMtNDhlYy1iMGNiLWIzZTAyMjM5ZmIxMy9zY3JlZW5zaG90 --define run_at=bG9jYWw= --no-color --no-capture --no-capture-stderr 2022-07-11 15:05:25,585 - flybirds_log - INFO - ============last run_args: {'cmd_str': 'behave ./features/test/android/base_en_click.feature --format=json -o report/1ae9c606-8943-48ec-b0cb-b3e02239fb13/report.json --define screenShotDir=cmVwb3J0LzFhZTljNjA2LTg5NDMtNDhlYy1iMGNiLWIzZTAyMjM5ZmIxMy9zY3JlZW5zaG90 --define run_at=bG9jYWw= --no-color --no-capture --no-capture-stderr', 'need_rerun': True, 'report_dir_path': 'report/1ae9c606-8943-48ec-b0cb-b3e02239fb13', 'use_define': ['--define', 'screenShotDir=cmVwb3J0LzFhZTljNjA2LTg5NDMtNDhlYy1iMGNiLWIzZTAyMjM5ZmIxMy9zY3JlZW5zaG90', '--define', 'run_at=bG9jYWw='], 'env_config': None, 'report_format': '--format=json', 'html': True, 'run_at': 'local', 'processes': 4, 'feature_path': './features/test/android/base_en_click.feature', 'parsed_tags': []} 2022-07-11 15:05:25,585 - flybirds_log - INFO - received run_args: {'cmd_str': 'behave ./features/test/android/base_en_click.feature --format=json -o report/1ae9c606-8943-48ec-b0cb-b3e02239fb13/report.json --define screenShotDir=cmVwb3J0LzFhZTljNjA2LTg5NDMtNDhlYy1iMGNiLWIzZTAyMjM5ZmIxMy9zY3JlZW5zaG90 --define run_at=bG9jYWw= --no-color --no-capture --no-capture-stderr', 'need_rerun': True, 'report_dir_path': 'report/1ae9c606-8943-48ec-b0cb-b3e02239fb13', 'use_define': ['--define', 'screenShotDir=cmVwb3J0LzFhZTljNjA2LTg5NDMtNDhlYy1iMGNiLWIzZTAyMjM5ZmIxMy9zY3JlZW5zaG90', '--define', 'run_at=bG9jYWw='], 'env_config': None, 'report_format': '--format=json', 'html': True, 'run_at': 'local', 'processes': 4, 'feature_path': './features/test/android/base_en_click.feature', 'parsed_tags': []} 2022-07-11 15:05:25,587 - flybirds_log - INFO - find extend pkg 2022-07-11 15:05:25,601 - flybirds_log - INFO - use_define: ['--define', 'screenShotDir=cmVwb3J0LzFhZTljNjA2LTg5NDMtNDhlYy1iMGNiLWIzZTAyMjM5ZmIxMy9zY3JlZW5zaG90', '--define', 'run_at=bG9jYWw='] 2022-07-11 15:05:25,795 - flybirds_log - INFO - change behave json format feature to flybirds feature 2022-07-11 15:05:25,795 - flybirds_log - INFO - change behave add_step_definition to flybirds add_step_definition 2022-07-11 15:05:25,832 - flybirds_log - INFO - [before_all_hook] user_data:{'screenShotDir': 'cmVwb3J0LzFhZTljNjA2LTg5NDMtNDhlYy1iMGNiLWIzZTAyMjM5ZmIxMy9zY3JlZW5zaG90', 'run_at': 'bG9jYWw='} 2022-07-11 15:05:25,832 - flybirds_log - INFO - [loader] user_data: {'screenShotDir': 'report/1ae9c606-8943-48ec-b0cb-b3e02239fb13/screenshot', 'run_at': 'local'} 2022-07-11 15:05:25,832 - flybirds_log - INFO - [loader] run platform: android 2022-07-11 15:05:26,064 - flybirds_log - INFO - start load configuration file... 2022-07-11 15:05:26,064 - flybirds_log - INFO - [event config] user_data: {'screenShotDir': 'report/1ae9c606-8943-48ec-b0cb-b3e02239fb13/screenshot', 'run_at': 'local'} 2022-07-11 15:05:26,064 - flybirds_log - INFO - user data，count:2 2022-07-11 15:05:26,064 - flybirds_log - INFO - run_config 2022-07-11 15:05:26,064 - flybirds_log - INFO - {'is_rerun': False, 'run_at': 'local'} 2022-07-11 15:05:26,065 - flybirds_log - INFO - Logging configuration information 2022-07-11 15:05:26,065 - flybirds_log - INFO - {'level': 'info'} 2022-07-11 15:05:26,065 - flybirds_log - INFO - APP configuration information 2022-07-11 15:05:26,065 - flybirds_log - INFO - {'package_name': 'ctrip.android.view', 'unique_tag': '', 'default_user': None, 'default_password': None, 'user_group': 1, 'package_path': '/Users/Andy/Desktop/ctrip.apk', 'overwrite_installation': False, 'ocr_lang': 'ch', 'run_id': None, 'build_id': None, 'pkg_version': None} 2022-07-11 15:05:26,065 - flybirds_log - INFO - Device configuration information 2022-07-11 15:05:26,065 - flybirds_log - INFO - {'device_id': '11081JEC200268', 'platform': 'android', 'web_driver_agent': 'com.facebook.WebDriverAgentRunner', 'screen_size': None} 2022-07-11 15:05:26,065 - flybirds_log - INFO - Frame parameter configuration information 2022-07-11 15:05:26,065 - flybirds_log - INFO - {'wait_ele_timeout': 35, 'wait_ele_disappear': 10, 'click_verify_timeout': 15, 'use_swipe_duration': False, 'swipe_duration': 6, 'use_poco_input': True, 'after_input_wait': 1, 'use_search_swipe_duration': False, 'search_swipe_duration': 1, 'swipe_search_count': 5, 'swipe_search_distance': 0.3, 'page_render_timeout': 30, 'app_start_time': 6, 'swipe_ready_time': 3, 'verify_pos_not_change_count': 5, 'screen_record_time': 0, 'use_snap': True, 'use_airtest_record': False} 2022-07-11 15:05:26,066 - flybirds_log - INFO - Test report configuration information 2022-07-11 15:05:26,066 - flybirds_log - INFO - {'screen_shot_dir': 'report/1ae9c606-8943-48ec-b0cb-b3e02239fb13/screenshot'} 2022-07-11 15:05:26,066 - flybirds_log - INFO - Process control configuration information 2022-07-11 15:05:26,066 - flybirds_log - INFO - {'before_run_page': 'restartApp', 'scenario_fail_page': 'restartApp', 'scenario_success_page': 'None', 'before_run_login': False, 'fail_screen_record': False, 'scenario_screen_record_time': 0, 'fail_rerun': False, 'max_fail_rerun_count': 0, 'max_retry_count': 0} 2022-07-11 15:05:26,066 - flybirds_log - INFO - configuration file read completed 2022-07-11 15:05:26,067 - flybirds_log - INFO - Logging configuration information 2022-07-11 15:05:26,067 - flybirds_log - INFO - {'level': 'info'} 2022-07-11 15:05:26,068 - flybirds_log - INFO - the python script in the project is read 2022-07-11 15:05:26,068 - flybirds_log - INFO - [active_before_all] user_data:{'screenShotDir': 'report/1ae9c606-8943-48ec-b0cb-b3e02239fb13/screenshot', 'run_at': 'local'} 2022-07-11 15:05:26,068 - flybirds_log - INFO - default_active_tag_provider :android chromium 2022-07-11 15:05:26,068 - flybirds_log - INFO - [active_before_all] active_tag_provider:{'python2': 'false', 'python3': 'true', 'os': 'darwin', 'platform': 'android', 'deviceType': 'ivd', 'cur_browser': 'chromium'} 2022-07-11 15:05:26,068 - flybirds_log - INFO - device prepare 2022-07-11 15:05:26,068 - flybirds_log - INFO - device_id information:11081JEC200268 [15:05:26][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 wait-for-device [15:05:29][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell getprop ro.build.version.sdk 2022-07-11 15:05:29,251 - flybirds_log - INFO - device connect info: 2022-07-11 15:05:29,252 - flybirds_log - INFO - initialize the device complete:11081JEC200268 [15:05:29][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell pm list packages 2022-07-11 15:05:29,311 - flybirds_log - INFO - app ctrip.android.view has already be installed 2022-07-11 15:05:29,312 - flybirds_log - INFO - init device and screen config [15:05:29][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell dumpsys activity top [15:05:29][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell dumpsys package com.netease.open.pocoservice [15:05:29][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 forward --no-rebind tcp:12960 tcp:10080 [15:05:29][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 forward --no-rebind tcp:16503 tcp:10081 [15:05:29][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell am start -n com.netease.open.pocoservice/.TestActivity [15:05:29][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell am instrument -w -e debug false -e class com.netease.open.pocoservice.InstrumentedTestAsLauncher com.netease.open.pocoservice/androidx.test.runner.AndroidJUnitRunner 2022-07-11 15:05:31,761 - flybirds_log - INFO - poco object initialization completed 2022-07-11 15:05:31,793 - flybirds_log - INFO - device 11081JEC200268 get device screen size [1080, 2340] [2022/07/11 15:05:33] ppocr DEBUG: Namespace(help='==SUPPRESS==', use_gpu=False, ir_optim=True, use_tensorrt=False, min_subgraph_size=15, precision='fp32', gpu_mem=500, image_dir=None, det_algorithm='DB', det_model_dir='/Users/Andy/.paddleocr/whl/det/ch/ch_PP-OCRv3_det_infer', det_limit_side_len=960, det_limit_type='max', det_db_thresh=0.3, det_db_box_thresh=0.6, det_db_unclip_ratio=1.5, max_batch_size=10, use_dilation=False, det_db_score_mode='fast', det_east_score_thresh=0.8, det_east_cover_thresh=0.1, det_east_nms_thresh=0.2, det_sast_score_thresh=0.5, det_sast_nms_thresh=0.2, det_sast_polygon=False, det_pse_thresh=0, det_pse_box_thresh=0.85, det_pse_min_area=16, det_pse_box_type='quad', det_pse_scale=1, scales=[8, 16, 32], alpha=1.0, beta=1.0, fourier_degree=5, det_fce_box_type='poly', rec_algorithm='SVTR_LCNet', rec_model_dir='/Users/Andy/.paddleocr/whl/rec/ch/ch_PP-OCRv3_rec_infer', rec_image_shape='3, 48, 320', rec_batch_num=6, max_text_length=25, rec_char_dict_path='/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/paddleocr/ppocr/utils/ppocr_keys_v1.txt', use_space_char=True, vis_font_path='./doc/fonts/simfang.ttf', drop_score=0.5, e2e_algorithm='PGNet', e2e_model_dir=None, e2e_limit_side_len=768, e2e_limit_type='max', e2e_pgnet_score_thresh=0.5, e2e_char_dict_path='./ppocr/utils/ic15_dict.txt', e2e_pgnet_valid_set='totaltext', e2e_pgnet_mode='fast', use_angle_cls=True, cls_model_dir='/Users/Andy/.paddleocr/whl/cls/ch_ppocr_mobile_v2.0_cls_infer', cls_image_shape='3, 48, 192', label_list=['0', '180'], cls_batch_num=6, cls_thresh=0.9, enable_mkldnn=False, cpu_threads=10, use_pdserving=False, warmup=False, draw_img_save_dir='./inference_results', save_crop_res=False, crop_res_save_dir='./output', use_mp=False, total_process_num=1, process_id=0, benchmark=False, save_log_path='./log_output/', show_log=True, use_onnx=False, output='./output', table_max_len=488, table_model_dir=None, table_char_dict_path=None, layout_path_model='lp://PubLayNet/ppyolov2_r50vd_dcn_365e_publaynet/config', layout_label_map=None, mode='structure', layout=True, table=True, ocr=True, lang='ch', det=True, rec=True, type='ocr', ocr_version='PP-OCRv3', structure_version='PP-STRUCTURE') 2022-07-11 15:05:34,124 - flybirds_log - INFO - ocr object initialize complete 2022-07-11 15:05:34,389 - flybirds_log - INFO - record_support message: proc_code:0, support: True 2022-07-11 15:05:34,390 - flybirds_log - INFO - use_airtest_record: False 2022-07-11 15:05:34,391 - flybirds_log - INFO - screen recording context initialization completed 2022-07-11 15:05:34,391 - flybirds_log - INFO - before_run_login:False 2022-07-11 15:05:34,391 - flybirds_log - INFO - device_id:11081JEC200268, 2022-07-11 15:05:34,392 - flybirds_log - INFO - page_name:before_run_page, 2022-07-11 15:05:34,392 - flybirds_log - INFO - package_name:ctrip.android.view [15:05:34][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell am force-stop ctrip.android.view [15:05:34][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell monkey -p ctrip.android.view -c android.intent.category.LAUNCHER 1 2022-07-11 15:05:41,070 - flybirds_log - INFO - complete restartApp and sleep 6 2022-07-11 15:05:41,077 - flybirds_log - INFO - [before_feature] feature.tags:[] 2022-07-11 15:05:41,078 - flybirds_log - INFO - [before_scenario] scenario.effective_tags:[] 2022-07-11 15:05:41,078 - flybirds_log - INFO - [scenario_OnBefore] start! 2022-07-11 15:05:41,078 - flybirds_log - INFO - running feature:flybirds test feature-android click, scenario:test click--click position, location: features/test/android/base_en_click.feature:2 2022-07-11 15:05:41,078 - flybirds_log - INFO - [scenario_init] start! 2022-07-11 15:05:41,079 - flybirds_log - INFO - device_id:11081JEC200268, 2022-07-11 15:05:41,079 - flybirds_log - INFO - page_name:before_run_page, 2022-07-11 15:05:41,079 - flybirds_log - INFO - package_name:ctrip.android.view [15:05:41][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell am force-stop ctrip.android.view [15:05:41][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell monkey -p ctrip.android.view -c android.intent.category.LAUNCHER 1 2022-07-11 15:05:47,788 - flybirds_log - INFO - complete restartApp and sleep 6 2022-07-11 15:05:47,799 - flybirds_log - INFO - 2022-07-11 15:05:47,799 - flybirds_log - INFO - run step:start app[ctrip.android.view] 2022-07-11 15:05:47,800 - flybirds_log - INFO - the ele_locator.json has no element locator configuration for [ctrip.android.view] [15:05:47][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell monkey -p ctrip.android.view -c android.intent.category.LAUNCHER 1 2022-07-11 15:05:59,994 - flybirds_log - INFO - 2022-07-11 15:05:59,994 - flybirds_log - INFO - run step:page rendering complete appears element[text=机票] 2022-07-11 15:05:59,995 - flybirds_log - INFO - the ele_locator.json has no element locator configuration for [text=机票] 2022-07-11 15:05:59,996 - flybirds_log - INFO - result_dic: {'selector': 'text=机票'} 2022-07-11 15:06:00,120 - flybirds_log - INFO - wait_exists: text=机票, ele_exists: True, timeout: 30 2022-07-11 15:06:00,141 - flybirds_log - INFO - 2022-07-11 15:06:00,142 - flybirds_log - INFO - run step:click position[580,1200] [15:06:00][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell ls -l /data/local/tmp/rotationwatcher.jar [15:06:00][DEBUG] install_rotationwatcher skipped [15:06:00][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell app_process -Djava.class.path=/data/local/tmp/rotationwatcher.jar /data/local/tmp com.example.rotationwatcher.Main [15:06:00][DEBUG] [rotation_server]b'0' [15:06:01][INFO] update orientation None->0 [15:06:01][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell wm size [15:06:01][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell getprop ro.sf.lcd_density [15:06:01][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell dumpsys SurfaceFlinger [15:06:02][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell dumpsys input [15:06:02][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell getevent -p [15:06:02][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell ls -l /data/local/tmp/maxpresent.jar [15:06:02][DEBUG] install_maxtouch skipped [15:06:02][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 forward --no-rebind tcp:19275 localabstract:maxpresent_19275 [15:06:02][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell app_process -Djava.class.path=/data/local/tmp/maxpresent.jar /data/local/tmp com.netease.maxpresent.MaxPresent socket maxpresent_19275 [15:06:03][DEBUG] [airtouch_server]b'Metrics Message : 2340.0=====1080.0' [15:06:03][DEBUG] [airtouch_server]b'Start local socket (maxpresent_19275) server...' 2022-07-11 15:06:04,270 - flybirds_log - INFO - 2022-07-11 15:06:04,271 - flybirds_log - INFO - run step:wait[5]seconds 2022-07-11 15:06:09,278 - flybirds_log - INFO - 2022-07-11 15:06:09,278 - flybirds_log - INFO - run step:screenshot 2022-07-11 15:06:09,279 - flybirds_log - INFO - 2022-07-11 15:06:09,280 - flybirds_log - INFO - run step:close app [15:06:09][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 shell am force-stop ctrip.android.view 2022-07-11 15:06:09,539 - flybirds_log - INFO - [scenario_OnAfter] start! 2022-07-11 15:06:09,541 - flybirds_log - INFO - [scenario_success] start! 2022-07-11 15:06:09,542 - flybirds_log - INFO - device_id:11081JEC200268, 2022-07-11 15:06:09,542 - flybirds_log - INFO - page_name:scenario_success_page, 2022-07-11 15:06:09,542 - flybirds_log - INFO - package_name:ctrip.android.view 1 feature passed, 0 failed, 0 skipped 1 scenario passed, 0 failed, 0 skipped 6 steps passed, 0 failed, 0 skipped, 0 undefined Took 0m21.728s [15:06:09][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 forward --remove tcp:12960 [15:06:10][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 forward --remove tcp:16503 [15:06:10][DEBUG] /Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/airtest/core/android/static/adb/mac/adb -s 11081JEC200268 forward --remove tcp:19275 [15:06:10][DEBUG] [rotation_server]b'' [15:06:10][DEBUG] [airtouch_server]b'' 2022-07-11 15:06:10,675 - flybirds_log - INFO - rerun_feature_name: base_en_click 2022-07-11 15:06:10,675 - flybirds_log - INFO - Feature sum_count rerun after creation failure <= 0 or fail_count <= 0 2022-07-11 15:06:10,676 - flybirds_log - INFO - True 2022-07-11 15:06:10,677 - flybirds_log - INFO - Do you need to rerun the task: False, the failed retry task was not executed 2022-07-11 15:06:10,677 - flybirds_log - INFO - Start processing the json report. report_dir_path: [report/1ae9c606-8943-48ec-b0cb-b3e02239fb13],rerun_report_dir_path:[None] 2022-07-11 15:06:10,678 - flybirds_log - INFO - start generate report 2022-07-11 15:06:10,678 - flybirds_log - INFO - report cmd:node "/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/flybirds/report/node_report/report.js" report/1ae9c606-8943-48ec-b0cb-b3e02239fb13 report/1ae9c606-8943-48ec-b0cb-b3e02239fb13 android multiple-cucumber-html-reporter platform: android