═══════════════════════════════════════════════════════════════════════════════════════════════════════════════════════ MAIN ═══════════════════════════════════════════════════════════════════════════════════════════════════════════════════════ ════════════════════════════════════════════════════ CAMPAIGN_13_1 ════════════════════════════════════════════════════ 2026-02-16 02:06:04.241 | INFO | CAMPAIGN_13_1 2026-02-16 02:06:04.244 | INFO | Count: 0 2026-02-16 02:06:04.262 | INFO | <<< UI ENSURE >>> 2026-02-16 02:06:04.267 | INFO | UI get current page 2026-02-16 02:06:04.803 | INFO | Unknown ui page 2026-02-16 02:06:05.284 | INFO | UI additional: IDLE -> REWARD_GOTO_MAIN 2026-02-16 02:06:05.288 | INFO | Click ( 804, 629) @ REWARD_GOTO_MAIN 2026-02-16 02:06:05.458 | INFO | Unknown ui page 2026-02-16 02:06:05.600 | INFO | [Package_name] com.bilibili.azurlane 2026-02-16 02:06:05.620 | INFO | [Device Orientation] 0 (Normal) 2026-02-16 02:06:05.786 | INFO | Unknown ui page 2026-02-16 02:06:06.059 | INFO | Unknown ui page 2026-02-16 02:06:06.287 | INFO | [UI] page_main 2026-02-16 02:06:06.290 | INFO | Goto page_campaign 2026-02-16 02:06:06.294 | INFO | <<< UI GOTO PAGE_CAMPAIGN >>> 2026-02-16 02:06:06.313 | INFO | Page switch: page_main_white -> page_campaign_menu 2026-02-16 02:06:06.319 | INFO | Click (1199, 472) @ MAIN_GOTO_CAMPAIGN_WHITE 2026-02-16 02:06:06.886 | INFO | Page switch: page_campaign_menu -> page_campaign 2026-02-16 02:06:06.891 | INFO | Click ( 308, 377) @ CAMPAIGN_MENU_GOTO_CAMPAIGN 2026-02-16 02:06:07.184 | INFO | Page arrive: page_campaign 2026-02-16 02:06:07.209 | INFO | Mode_switch_1 set to hard 2026-02-16 02:06:07.236 | INFO | [Mode_switch_1] hard 2026-02-16 02:06:07.239 | INFO | <<< UI ENSURE INDEX >>> 2026-02-16 02:06:07.312 | INFO | No stage found. 2026-02-16 02:06:07.547 | INFO | No stage found. 2026-02-16 02:06:07.866 | INFO | Loading OCR model: ./bin/cnocr_models/azur_lane 2026-02-16 02:06:07.997 | INFO | [campaign 0.149s] ['I3--1', 'I3--3', 'I3--2', 'I3--4'] 2026-02-16 02:06:08.002 | INFO | [Chapter] i3 2026-02-16 02:06:08.005 | INFO | [Stage] i3-1, i3-3, i3-2, i3-4 2026-02-16 02:06:08.085 | INFO | <<< UI ENSURE >>> 2026-02-16 02:06:08.088 | INFO | UI get current page 2026-02-16 02:06:08.098 | INFO | [UI] page_campaign 2026-02-16 02:06:08.102 | INFO | Already at page_campaign 2026-02-16 02:06:08.107 | INFO | Mode_switch_1 set to hard 2026-02-16 02:06:08.115 | INFO | [Mode_switch_1] hard 2026-02-16 02:06:08.120 | INFO | <<< UI ENSURE INDEX >>> 2026-02-16 02:06:08.256 | INFO | [campaign 0.059s] ['13--1', '13--3', '13--2', '13--4'] 2026-02-16 02:06:08.261 | INFO | [Chapter] 13 2026-02-16 02:06:08.266 | INFO | [Stage] 13-1, 13-3, 13-2, 13-4 2026-02-16 02:06:08.268 | INFO | [Index] 13 2026-02-16 02:06:08.338 | INFO | [OCR_OIL 0.042s] 22233 ──────────────────────────────────────────────────────── 13-1 ───────────────────────────────────────────────────────── 2026-02-16 02:06:08.343 | INFO | 13-1 2026-02-16 02:06:08.346 | INFO | Expect emotion reduce: (12, 2) 2026-02-16 02:06:08.357 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-02-16 02:06:08.363 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=53, Main.Emotion.Fleet1Record=datetime.datetime(2026, 2, 16, 2, 6, 8), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 2, 16, 2, 6, 8) 2026-02-16 02:06:08.378 | INFO | [Emotion fleet_1] 53 2026-02-16 02:06:08.382 | INFO | [Emotion fleet_2] 119 2026-02-16 02:06:08.387 | INFO | <<< ENTER MAP >>> 2026-02-16 02:06:08.463 | INFO | Click ( 185, 277) @ 13-1 2026-02-16 02:06:08.866 | INFO | [Map_clear_percentage] 5% 2026-02-16 02:06:09.170 | INFO | [Map_clear_percentage] 69% 2026-02-16 02:06:09.468 | INFO | [Map_clear_percentage] 99% 2026-02-16 02:06:09.495 | INFO | [MAP_CLEAR_ALL_THIS_TIME] False 2026-02-16 02:06:09.500 | INFO | [Map_info] 99%, star_1, star_2, star_3, 100_percent_clear, 3_stars, threat_safe, clear_mode 2026-02-16 02:06:09.504 | INFO | [StopCondition_MapAchievement] non_stop 2026-02-16 02:06:09.507 | INFO | Fast_Forward set to on 2026-02-16 02:06:09.510 | INFO | [Fast_Forward] on 2026-02-16 02:06:09.526 | INFO | Auto_Search set to on 2026-02-16 02:06:09.531 | INFO | [Auto_Search] on 2026-02-16 02:06:09.535 | INFO | Click ( 918, 523) @ MAP_PREPARATION 2026-02-16 02:06:10.065 | INFO | Handling 2x book setting, mode=prep. 2026-02-16 02:06:10.088 | INFO | Using fleet: [4, 3, 1] 2026-02-16 02:06:10.165 | INFO | Hard satisfied: Fleet_1: None, Fleet_2: None, Submarine: None 2026-02-16 02:06:10.174 | INFO | [map_allow_submarine] True 2026-02-16 02:06:10.179 | INFO | Click (1157, 327) @ FLEET_2_CLEAR 2026-02-16 02:06:10.233 | INFO | Click (1031, 476) @ SUBMARINE_CHOOSE 2026-02-16 02:06:10.365 | INFO | Current selected: [1] 2026-02-16 02:06:10.370 | INFO | Click (1050, 471) @ SUBMARINE_CHOOSE 2026-02-16 02:06:10.701 | INFO | Click (1045, 210) @ FLEET_1_CHOOSE 2026-02-16 02:06:10.969 | INFO | Current selected: [1] 2026-02-16 02:06:10.974 | INFO | Click (1127, 415) @ FLEET_1_BAR_INDEX_4 2026-02-16 02:06:11.262 | INFO | Click (1063, 336) @ FLEET_2_CHOOSE 2026-02-16 02:06:11.562 | INFO | Current selected: [4] 2026-02-16 02:06:11.568 | INFO | Click (1146, 491) @ FLEET_2_BAR_INDEX_3 2026-02-16 02:06:11.861 | INFO | Auto search setting 2026-02-16 02:06:11.878 | INFO | [_fleet_sidebar_offset] 0 2026-02-16 02:06:11.884 | INFO | [Fleet_sidebar] 1/3 2026-02-16 02:06:11.888 | INFO | Click (1212, 434) @ FLEET_SIDEBAR_0_2 2026-02-16 02:06:12.361 | INFO | [_fleet_sidebar_offset] 0 2026-02-16 02:06:12.369 | INFO | [Fleet_sidebar] 3/3 2026-02-16 02:06:12.372 | INFO | [Auto_Search_Setting] fleet1_all_fleet2_standby, sub_standby 2026-02-16 02:06:12.375 | INFO | Click ( 501, 222) @ AUTO_SEARCH_SET_MOB 2026-02-16 02:06:12.913 | INFO | [Auto_Search_Setting] fleet1_mob_fleet2_boss, sub_standby 2026-02-16 02:06:12.917 | INFO | Selected to the correct auto search setting 2026-02-16 02:06:12.920 | INFO | [Auto_Search_Setting] fleet1_mob_fleet2_boss, sub_standby 2026-02-16 02:06:12.923 | INFO | Selected to the correct auto search setting 2026-02-16 02:06:12.926 | INFO | Click (1095, 587) @ FLEET_PREPARATION 2026-02-16 02:06:13.841 | INFO | is_auto_search_running appeared ────────────────────────────────────────────────────── BATTLE_0 ─────────────────────────────────────────────────────── 2026-02-16 02:06:13.847 | INFO | BATTLE_0 2026-02-16 02:06:13.850 | INFO | Auto search moving 2026-02-16 02:06:13.864 | INFO | Fleet: 1, fleet_current_index: 1 2026-02-16 02:06:13.932 | INFO | [OCR_OIL 0.063s] 22223 2026-02-16 02:06:14.027 | INFO | [OCR_COIN 0.090s] 109474 2026-02-16 02:06:19.097 | INFO | [Loading] 19% 2026-02-16 02:06:19.101 | INFO | Auto search combat loading 2026-02-16 02:06:19.104 | INFO | Screenshot interval set to 1.0s 2026-02-16 02:06:24.105 | INFO | [BattleUI] PAUSE 2026-02-16 02:06:24.109 | INFO | Auto Search combat execute 2026-02-16 02:06:24.115 | INFO | <<< EMOTION REDUCE >>> 2026-02-16 02:06:24.128 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-02-16 02:06:24.132 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=51, Main.Emotion.Fleet1Record=datetime.datetime(2026, 2, 16, 2, 6, 24), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 2, 16, 2, 6, 24) 2026-02-16 02:06:24.151 | INFO | [Emotion fleet_1] 51 2026-02-16 02:06:24.156 | INFO | [Emotion fleet_2] 119 2026-02-16 02:06:30.113 | INFO | Combat auto check timer reached 2026-02-16 02:06:51.137 | INFO | Click (1036, 648) @ GET_SHIP 2026-02-16 02:06:52.151 | INFO | Click (1012, 646) @ GET_SHIP 2026-02-16 02:06:53.157 | INFO | Click (1001, 652) @ GET_SHIP 2026-02-16 02:06:54.143 | INFO | Screenshot interval set to 0.3s 2026-02-16 02:06:54.146 | INFO | Auto Search combat status 2026-02-16 02:06:54.150 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_1 ─────────────────────────────────────────────────────── 2026-02-16 02:06:54.153 | INFO | BATTLE_1 2026-02-16 02:06:54.155 | INFO | Auto search moving 2026-02-16 02:06:54.158 | INFO | Fleet: 1, fleet_current_index: 1 2026-02-16 02:06:54.236 | INFO | [OCR_OIL 0.074s] 22204 2026-02-16 02:06:54.328 | INFO | [OCR_COIN 0.088s] 109497 2026-02-16 02:06:58.941 | INFO | [Loading] 13% 2026-02-16 02:06:58.945 | INFO | Auto search combat loading 2026-02-16 02:06:58.949 | INFO | Screenshot interval set to 1.0s 2026-02-16 02:07:03.943 | INFO | [BattleUI] PAUSE 2026-02-16 02:07:03.948 | INFO | Auto Search combat execute 2026-02-16 02:07:03.951 | INFO | <<< EMOTION REDUCE >>> 2026-02-16 02:07:03.964 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-02-16 02:07:03.969 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=49, Main.Emotion.Fleet1Record=datetime.datetime(2026, 2, 16, 2, 7, 3), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 2, 16, 2, 7, 3) 2026-02-16 02:07:03.981 | INFO | [Emotion fleet_1] 49 2026-02-16 02:07:03.985 | INFO | [Emotion fleet_2] 119 2026-02-16 02:07:08.954 | INFO | Combat auto check timer reached 2026-02-16 02:07:35.977 | INFO | Click (1030, 678) @ GET_SHIP 2026-02-16 02:07:36.978 | INFO | Click (1025, 654) @ GET_SHIP 2026-02-16 02:07:37.978 | INFO | Click (1037, 649) @ GET_SHIP 2026-02-16 02:07:38.997 | INFO | Click (1021, 661) @ GET_SHIP 2026-02-16 02:07:39.991 | INFO | Screenshot interval set to 0.3s 2026-02-16 02:07:39.995 | INFO | Auto Search combat status 2026-02-16 02:07:40.008 | INFO | Click (1021, 670) @ GET_SHIP 2026-02-16 02:07:41.167 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_2 ─────────────────────────────────────────────────────── 2026-02-16 02:07:41.173 | INFO | BATTLE_2 2026-02-16 02:07:41.176 | INFO | Auto search moving 2026-02-16 02:07:41.180 | INFO | Fleet: 1, fleet_current_index: 1 2026-02-16 02:07:41.295 | INFO | [OCR_OIL 0.110s] 22185 2026-02-16 02:07:41.393 | INFO | [OCR_COIN 0.095s] 109515 2026-02-16 02:07:45.081 | INFO | [Loading] 8% 2026-02-16 02:07:45.084 | INFO | Auto search combat loading 2026-02-16 02:07:45.087 | INFO | Screenshot interval set to 1.0s 2026-02-16 02:07:51.090 | INFO | [BattleUI] PAUSE 2026-02-16 02:07:51.095 | INFO | Auto Search combat execute 2026-02-16 02:07:51.100 | INFO | <<< EMOTION REDUCE >>> 2026-02-16 02:07:51.113 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-02-16 02:07:51.120 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=47, Main.Emotion.Fleet1Record=datetime.datetime(2026, 2, 16, 2, 7, 51), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 2, 16, 2, 7, 51) 2026-02-16 02:07:51.139 | INFO | [Emotion fleet_1] 47 2026-02-16 02:07:51.142 | INFO | [Emotion fleet_2] 119 2026-02-16 02:07:57.082 | INFO | Combat auto check timer reached 2026-02-16 02:08:37.137 | INFO | Click (1034, 677) @ GET_SHIP 2026-02-16 02:08:39.157 | INFO | Click (1026, 649) @ GET_SHIP 2026-02-16 02:08:41.146 | INFO | Click (1022, 659) @ GET_SHIP 2026-02-16 02:08:42.156 | INFO | Click (1018, 664) @ GET_SHIP 2026-02-16 02:08:43.140 | INFO | Screenshot interval set to 0.3s 2026-02-16 02:08:43.144 | INFO | Auto Search combat status 2026-02-16 02:08:43.148 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_3 ─────────────────────────────────────────────────────── 2026-02-16 02:08:43.152 | INFO | BATTLE_3 2026-02-16 02:08:43.156 | INFO | Auto search moving 2026-02-16 02:08:43.160 | INFO | Fleet: 1, fleet_current_index: 1 2026-02-16 02:08:43.230 | INFO | [OCR_OIL 0.068s] 22166 2026-02-16 02:08:43.320 | INFO | [OCR_COIN 0.084s] 109531 2026-02-16 02:08:47.334 | INFO | [Loading] 13% 2026-02-16 02:08:47.337 | INFO | Auto search combat loading 2026-02-16 02:08:47.341 | INFO | Screenshot interval set to 1.0s 2026-02-16 02:08:53.349 | INFO | [BattleUI] PAUSE 2026-02-16 02:08:53.353 | INFO | Auto Search combat execute 2026-02-16 02:08:53.356 | INFO | <<< EMOTION REDUCE >>> 2026-02-16 02:08:53.371 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-02-16 02:08:53.377 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=45, Main.Emotion.Fleet1Record=datetime.datetime(2026, 2, 16, 2, 8, 53), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 2, 16, 2, 8, 53) 2026-02-16 02:08:53.400 | INFO | [Emotion fleet_1] 45 2026-02-16 02:08:53.403 | INFO | [Emotion fleet_2] 119 2026-02-16 02:08:59.342 | INFO | Combat auto check timer reached 2026-02-16 02:09:34.382 | INFO | Click (1037, 641) @ GET_SHIP 2026-02-16 02:09:36.396 | INFO | Click (1013, 651) @ GET_SHIP 2026-02-16 02:09:37.404 | INFO | Click (1033, 638) @ GET_SHIP 2026-02-16 02:09:39.390 | INFO | Click (1028, 654) @ GET_SHIP 2026-02-16 02:09:40.388 | INFO | Screenshot interval set to 0.3s 2026-02-16 02:09:40.392 | INFO | Auto Search combat status 2026-02-16 02:09:40.397 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_4 ─────────────────────────────────────────────────────── 2026-02-16 02:09:40.403 | INFO | BATTLE_4 2026-02-16 02:09:40.407 | INFO | Auto search moving 2026-02-16 02:09:40.411 | INFO | Fleet: 1, fleet_current_index: 1 2026-02-16 02:09:40.481 | INFO | [OCR_OIL 0.065s] 22147 2026-02-16 02:09:40.565 | INFO | [OCR_COIN 0.079s] 109554 2026-02-16 02:09:44.581 | INFO | [Loading] 4% 2026-02-16 02:09:44.586 | INFO | Auto search combat loading 2026-02-16 02:09:44.589 | INFO | Screenshot interval set to 1.0s 2026-02-16 02:09:50.589 | INFO | [BattleUI] PAUSE 2026-02-16 02:09:50.595 | INFO | Auto Search combat execute 2026-02-16 02:09:50.602 | INFO | <<< EMOTION REDUCE >>> 2026-02-16 02:09:50.618 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-02-16 02:09:50.625 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=43, Main.Emotion.Fleet1Record=datetime.datetime(2026, 2, 16, 2, 9, 50), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 2, 16, 2, 9, 50) 2026-02-16 02:09:50.641 | INFO | [Emotion fleet_1] 43 2026-02-16 02:09:50.646 | INFO | [Emotion fleet_2] 119 2026-02-16 02:09:56.580 | INFO | Combat auto check timer reached 2026-02-16 02:10:44.636 | INFO | Click (1040, 659) @ GET_SHIP 2026-02-16 02:13:44.785 | INFO | Function calls: gui.py L94 process.py L112 start() context.py L223 _Popen() context.py L277 _Popen() popen_fork.py L20 __init__() popen_fork.py L74 _launch() process.py L297 _bootstrap() process.py L99 run() gui.py L85 func() main.py L463 run() server.py L60 run() runners.py L43 run() on.py L84 main() proxy_headers.py L75 __call__() applications.py L112 __call__() errors.py L146 __call__() base.py L21 __call__() exceptions.py L58 __call__() routing.py L569 __call__() routing.py L540 lifespan() routing.py L481 default_lifespan() routing.py L518 startup() app.py L1523 process_manager.py L227 restart_processes() process_manager.py L51 start() process.py L112 start() context.py L223 _Popen() context.py L277 _Popen() popen_fork.py L20 __init__() popen_fork.py L74 _launch() process.py L297 _bootstrap() process.py L99 run() process_manager.py L166 run_process() alas.py L555 loop() alas.py L69 run() alas.py L352 main() run.py L426 run() campaign_base.py L143 run() campaign_base.py L178 auto_search_execute_a_battle() auto_search_combat.py L349 auto_search_combat() auto_search_combat.py L246 auto_search_combat_execute() base.py L169 loop() device.py L188 screenshot() device.py L250 stuck_record_check() device.py L43 show_function_call() 2026-02-16 02:13:44.807 | WARNING | Wait too long 2026-02-16 02:13:44.810 | WARNING | Waiting for {'SP_CHECK', 'EXP_INFO_B', 'EVENT_CHECK', 'POPUP_CONFIRM_WHITE', 'GUILD_POPUP_CONFIRM', 'GET_MISSION', 'GET_SHIP', 'PAUSE', 'EXP_INFO_S', 'AUTO_SEARCH_MAP_OPTION_ON', 'NEW_SHIP', 'BATTLE_STATUS_S', 'CAMPAIGN_CHECK', 'EXP_INFO_A', 'POPUP_CANCEL', 'BATTLE_STATUS_A', 'BATTLE_STATUS_B', 'MISSION_POPUP_GO'} 2026-02-16 02:13:44.867 | INFO | [Package_name] com.bilibili.azurlane 2026-02-16 02:13:44.873 | ERROR | GameStuckError: Wait too long 2026-02-16 02:13:44.877 | WARNING | Saving error: ./log/error/1771179224877