═══════════════════════════════════════════════════════════════════════════════════════════════════════════════════════ MAIN ═══════════════════════════════════════════════════════════════════════════════════════════════════════════════════════ ════════════════════════════════════════════════════ CAMPAIGN_13_1 ════════════════════════════════════════════════════ 2025-10-01 22:00:02.812 | INFO | CAMPAIGN_13_1 2025-10-01 22:00:02.816 | INFO | Count: 0 2025-10-01 22:00:02.832 | INFO | <<< UI ENSURE >>> 2025-10-01 22:00:02.837 | INFO | UI get current page 2025-10-01 22:00:02.855 | INFO | [UI] page_main 2025-10-01 22:00:02.859 | INFO | Goto page_campaign 2025-10-01 22:00:02.863 | INFO | <<< UI GOTO PAGE_CAMPAIGN >>> 2025-10-01 22:00:02.920 | INFO | Page switch: page_main_white -> page_campaign_menu 2025-10-01 22:00:02.925 | INFO | Click (1198, 522) @ MAIN_GOTO_CAMPAIGN_WHITE 2025-10-01 22:00:04.041 | INFO | Page switch: page_campaign_menu -> page_campaign 2025-10-01 22:00:04.047 | INFO | Click ( 271, 324) @ CAMPAIGN_MENU_GOTO_CAMPAIGN 2025-10-01 22:00:04.338 | INFO | Page arrive: page_campaign 2025-10-01 22:00:04.363 | INFO | Mode_switch_1 set to hard 2025-10-01 22:00:04.394 | INFO | [Mode_switch_1] hard 2025-10-01 22:00:04.398 | INFO | <<< UI ENSURE INDEX >>> 2025-10-01 22:00:04.473 | INFO | No stage found. 2025-10-01 22:00:04.705 | INFO | No stage found. 2025-10-01 22:00:05.028 | INFO | Loading OCR model: ./bin/cnocr_models/azur_lane 2025-10-01 22:00:05.138 | INFO | [campaign 0.130s] ['I3--1', 'I3--3', 'I3--2', 'I3--4'] 2025-10-01 22:00:05.141 | INFO | [Chapter] i3 2025-10-01 22:00:05.146 | INFO | [Stage] i3-1, i3-3, i3-2, i3-4 2025-10-01 22:00:05.240 | INFO | <<< UI ENSURE >>> 2025-10-01 22:00:05.245 | INFO | UI get current page 2025-10-01 22:00:05.256 | INFO | [UI] page_campaign 2025-10-01 22:00:05.260 | INFO | Already at page_campaign 2025-10-01 22:00:05.267 | INFO | Mode_switch_1 set to hard 2025-10-01 22:00:05.274 | INFO | [Mode_switch_1] hard 2025-10-01 22:00:05.279 | INFO | <<< UI ENSURE INDEX >>> 2025-10-01 22:00:05.403 | INFO | [campaign 0.045s] ['13--1', '13--3', '13--2', '13--4'] 2025-10-01 22:00:05.406 | INFO | [Chapter] 13 2025-10-01 22:00:05.411 | INFO | [Stage] 13-1, 13-3, 13-2, 13-4 2025-10-01 22:00:05.415 | INFO | [Index] 13 2025-10-01 22:00:05.486 | INFO | [OCR_OIL 0.045s] 8286 ──────────────────────────────────────────────────────── 13-1 ───────────────────────────────────────────────────────── 2025-10-01 22:00:05.491 | INFO | 13-1 2025-10-01 22:00:05.494 | INFO | Expect emotion reduce: (12, 2) 2025-10-01 22:00:05.506 | INFO | Bind task ['General', 'Alas', 'Main'] 2025-10-01 22:00:05.511 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=53, Main.Emotion.Fleet1Record=datetime.datetime(2025, 10, 1, 22, 0, 5), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2025, 10, 1, 22, 0, 5) 2025-10-01 22:00:05.528 | INFO | [Emotion fleet_1] 53 2025-10-01 22:00:05.534 | INFO | [Emotion fleet_2] 119 2025-10-01 22:00:05.538 | INFO | <<< ENTER MAP >>> 2025-10-01 22:00:05.615 | INFO | Click ( 194, 282) @ 13-1 2025-10-01 22:00:06.321 | INFO | [Map_clear_percentage] 54% 2025-10-01 22:00:06.619 | INFO | [Map_clear_percentage] 99% 2025-10-01 22:00:06.645 | INFO | [MAP_CLEAR_ALL_THIS_TIME] False 2025-10-01 22:00:06.653 | INFO | [Map_info] 99%, star_1, star_2, star_3, 100_percent_clear, 3_stars, threat_safe, clear_mode 2025-10-01 22:00:06.658 | INFO | [StopCondition_MapAchievement] non_stop 2025-10-01 22:00:06.661 | INFO | Fast_Forward set to on 2025-10-01 22:00:06.666 | INFO | [Fast_Forward] on 2025-10-01 22:00:06.682 | INFO | Auto_Search set to on 2025-10-01 22:00:06.690 | INFO | [Auto_Search] on 2025-10-01 22:00:06.695 | INFO | Click ( 881, 512) @ MAP_PREPARATION 2025-10-01 22:00:07.220 | INFO | Handling 2x book setting, mode=prep. 2025-10-01 22:00:07.244 | INFO | Using fleet: [4, 3, 1] 2025-10-01 22:00:07.329 | INFO | Hard satisfied: Fleet_1: None, Fleet_2: None, Submarine: None 2025-10-01 22:00:07.341 | INFO | [map_allow_submarine] True 2025-10-01 22:00:07.346 | INFO | Click (1035, 456) @ SUBMARINE_CHOOSE 2025-10-01 22:00:07.523 | INFO | Current selected: [1] 2025-10-01 22:00:07.527 | INFO | Click (1038, 477) @ SUBMARINE_CHOOSE 2025-10-01 22:00:07.823 | INFO | Click (1151, 333) @ FLEET_2_CLEAR 2025-10-01 22:00:08.418 | INFO | Click (1041, 208) @ FLEET_1_CHOOSE 2025-10-01 22:00:08.719 | INFO | Current selected: [4] 2025-10-01 22:00:08.723 | INFO | Click (1067, 240) @ FLEET_1_CHOOSE 2025-10-01 22:00:09.019 | INFO | Click (1056, 331) @ FLEET_2_CHOOSE 2025-10-01 22:00:09.316 | INFO | Current selected: [4] 2025-10-01 22:00:09.320 | INFO | Click (1071, 477) @ FLEET_2_BAR_INDEX_3 2025-10-01 22:00:09.619 | INFO | Auto search setting 2025-10-01 22:00:09.634 | INFO | [_fleet_sidebar_offset] 0 2025-10-01 22:00:09.639 | INFO | [Fleet_sidebar] 1/3 2025-10-01 22:00:09.642 | INFO | Click (1201, 411) @ FLEET_SIDEBAR_0_2 2025-10-01 22:00:10.153 | INFO | [_fleet_sidebar_offset] 0 2025-10-01 22:00:10.158 | INFO | [Fleet_sidebar] 3/3 2025-10-01 22:00:10.162 | INFO | [Auto_Search_Setting] fleet1_all_fleet2_standby, sub_auto_call 2025-10-01 22:00:10.165 | INFO | Click ( 500, 214) @ AUTO_SEARCH_SET_MOB 2025-10-01 22:00:10.636 | INFO | [Auto_Search_Setting] fleet1_mob_fleet2_boss, sub_auto_call 2025-10-01 22:00:10.640 | INFO | Selected to the correct auto search setting 2025-10-01 22:00:10.642 | INFO | [Auto_Search_Setting] fleet1_mob_fleet2_boss, sub_auto_call 2025-10-01 22:00:10.645 | INFO | Selected to the correct auto search setting 2025-10-01 22:00:10.648 | INFO | Click (1102, 585) @ FLEET_PREPARATION 2025-10-01 22:00:11.555 | INFO | is_auto_search_running appeared ────────────────────────────────────────────────────── BATTLE_0 ─────────────────────────────────────────────────────── 2025-10-01 22:00:11.560 | INFO | BATTLE_0 2025-10-01 22:00:11.562 | INFO | Auto search moving 2025-10-01 22:00:11.575 | INFO | Fleet: 1, fleet_current_index: 1 2025-10-01 22:00:11.670 | INFO | [OCR_OIL 0.091s] 8276 2025-10-01 22:00:11.792 | INFO | [OCR_COIN 0.119s] 99570 2025-10-01 22:00:18.624 | INFO | Skip map cat attack 2025-10-01 22:00:18.628 | INFO | Click (1232, 667) @ MAP_CAT_ATTACK 2025-10-01 22:00:20.424 | INFO | [Loading] 9% 2025-10-01 22:00:20.428 | INFO | Auto search combat loading 2025-10-01 22:00:20.431 | INFO | Screenshot interval set to 1.0s 2025-10-01 22:00:26.443 | INFO | [BattleUI] PAUSE 2025-10-01 22:00:26.448 | INFO | Auto Search combat execute 2025-10-01 22:00:26.451 | INFO | <<< EMOTION REDUCE >>> 2025-10-01 22:00:26.461 | INFO | Bind task ['General', 'Alas', 'Main'] 2025-10-01 22:00:26.467 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=51, Main.Emotion.Fleet1Record=datetime.datetime(2025, 10, 1, 22, 0, 26), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2025, 10, 1, 22, 0, 26) 2025-10-01 22:00:26.485 | INFO | [Emotion fleet_1] 51 2025-10-01 22:00:26.490 | INFO | [Emotion fleet_2] 119 2025-10-01 22:00:32.431 | INFO | Combat auto check timer reached 2025-10-01 22:00:56.465 | INFO | Screenshot interval set to 0.3s 2025-10-01 22:00:56.471 | INFO | Auto Search combat status 2025-10-01 22:00:57.954 | INFO | Click (1018, 651) @ GET_SHIP 2025-10-01 22:00:59.155 | INFO | Click (1018, 665) @ GET_SHIP 2025-10-01 22:01:01.557 | INFO | Click (1014, 673) @ GET_SHIP 2025-10-01 22:01:03.651 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_1 ─────────────────────────────────────────────────────── 2025-10-01 22:01:03.657 | INFO | BATTLE_1 2025-10-01 22:01:03.660 | INFO | Auto search moving 2025-10-01 22:01:03.664 | INFO | Fleet: 1, fleet_current_index: 1 2025-10-01 22:01:03.756 | INFO | [OCR_OIL 0.089s] 8235 2025-10-01 22:01:03.856 | INFO | [OCR_COIN 0.095s] 99591 2025-10-01 22:01:10.573 | INFO | [Loading] 9% 2025-10-01 22:01:10.577 | INFO | Auto search combat loading 2025-10-01 22:01:10.579 | INFO | Screenshot interval set to 1.0s 2025-10-01 22:01:16.576 | INFO | [BattleUI] PAUSE 2025-10-01 22:01:16.580 | INFO | Auto Search combat execute 2025-10-01 22:01:16.584 | INFO | <<< EMOTION REDUCE >>> 2025-10-01 22:01:16.596 | INFO | Bind task ['General', 'Alas', 'Main'] 2025-10-01 22:01:16.599 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=49, Main.Emotion.Fleet1Record=datetime.datetime(2025, 10, 1, 22, 1, 16), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2025, 10, 1, 22, 1, 16) 2025-10-01 22:01:16.619 | INFO | [Emotion fleet_1] 49 2025-10-01 22:01:16.624 | INFO | [Emotion fleet_2] 119 2025-10-01 22:01:22.582 | INFO | Combat auto check timer reached 2025-10-01 22:01:40.601 | INFO | Click (1028, 650) @ GET_SHIP 2025-10-01 22:01:41.602 | INFO | Click (1030, 671) @ GET_SHIP 2025-10-01 22:01:44.615 | INFO | Click (1022, 667) @ GET_SHIP 2025-10-01 22:01:46.607 | INFO | Screenshot interval set to 0.3s 2025-10-01 22:01:46.611 | INFO | Auto Search combat status 2025-10-01 22:01:46.616 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_2 ─────────────────────────────────────────────────────── 2025-10-01 22:01:46.625 | INFO | BATTLE_2 2025-10-01 22:01:46.631 | INFO | Auto search moving 2025-10-01 22:01:46.641 | INFO | Fleet: 1, fleet_current_index: 1 2025-10-01 22:01:46.731 | INFO | [OCR_OIL 0.083s] 8194 2025-10-01 22:01:46.815 | INFO | [OCR_COIN 0.079s] 99607 2025-10-01 22:01:50.207 | INFO | Skip map cat attack 2025-10-01 22:01:50.211 | INFO | Click (1235, 689) @ MAP_CAT_ATTACK 2025-10-01 22:01:52.015 | INFO | [Loading] 10% 2025-10-01 22:01:52.020 | INFO | Auto search combat loading 2025-10-01 22:01:52.024 | INFO | Screenshot interval set to 1.0s 2025-10-01 22:01:58.015 | INFO | [BattleUI] PAUSE 2025-10-01 22:01:58.019 | INFO | Auto Search combat execute 2025-10-01 22:01:58.021 | INFO | <<< EMOTION REDUCE >>> 2025-10-01 22:01:58.031 | INFO | Bind task ['General', 'Alas', 'Main'] 2025-10-01 22:01:58.039 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=47, Main.Emotion.Fleet1Record=datetime.datetime(2025, 10, 1, 22, 1, 58), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2025, 10, 1, 22, 1, 58) 2025-10-01 22:01:58.063 | INFO | [Emotion fleet_1] 47 2025-10-01 22:01:58.069 | INFO | [Emotion fleet_2] 119 2025-10-01 22:02:04.024 | INFO | Combat auto check timer reached 2025-10-01 22:02:27.057 | INFO | Click (1044, 643) @ GET_SHIP 2025-10-01 22:02:28.057 | INFO | Screenshot interval set to 0.3s 2025-10-01 22:02:28.061 | INFO | Auto Search combat status 2025-10-01 22:02:28.077 | INFO | Click (1046, 659) @ GET_SHIP 2025-10-01 22:02:29.236 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_3 ─────────────────────────────────────────────────────── 2025-10-01 22:02:29.241 | INFO | BATTLE_3 2025-10-01 22:02:29.246 | INFO | Auto search moving 2025-10-01 22:02:29.250 | INFO | Fleet: 1, fleet_current_index: 1 2025-10-01 22:02:29.339 | INFO | [OCR_OIL 0.084s] 8153 2025-10-01 22:02:29.435 | INFO | [OCR_COIN 0.088s] 99632 2025-10-01 22:02:34.050 | INFO | [Loading] 5% 2025-10-01 22:02:34.054 | INFO | Auto search combat loading 2025-10-01 22:02:34.057 | INFO | Screenshot interval set to 1.0s 2025-10-01 22:02:40.070 | INFO | [BattleUI] PAUSE 2025-10-01 22:02:40.074 | INFO | Auto Search combat execute 2025-10-01 22:02:40.076 | INFO | <<< EMOTION REDUCE >>> 2025-10-01 22:02:40.087 | INFO | Bind task ['General', 'Alas', 'Main'] 2025-10-01 22:02:40.093 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=45, Main.Emotion.Fleet1Record=datetime.datetime(2025, 10, 1, 22, 2, 40), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2025, 10, 1, 22, 2, 40) 2025-10-01 22:02:40.110 | INFO | [Emotion fleet_1] 45 2025-10-01 22:02:40.115 | INFO | [Emotion fleet_2] 119 2025-10-01 22:02:46.054 | INFO | Combat auto check timer reached 2025-10-01 22:03:26.099 | INFO | Click (1021, 681) @ GET_SHIP 2025-10-01 22:03:27.100 | INFO | Click (1023, 669) @ GET_SHIP 2025-10-01 22:03:29.121 | INFO | Click (1020, 659) @ GET_SHIP 2025-10-01 22:03:30.118 | INFO | Screenshot interval set to 0.3s 2025-10-01 22:03:30.121 | INFO | Auto Search combat status 2025-10-01 22:03:30.133 | INFO | Click (1016, 648) @ GET_SHIP 2025-10-01 22:03:31.593 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_4 ─────────────────────────────────────────────────────── 2025-10-01 22:03:31.603 | INFO | BATTLE_4 2025-10-01 22:03:31.606 | INFO | Auto search moving 2025-10-01 22:03:31.612 | INFO | Fleet: 1, fleet_current_index: 1 2025-10-01 22:03:31.722 | INFO | [OCR_OIL 0.103s] 8130 2025-10-01 22:03:31.827 | INFO | [OCR_COIN 0.100s] 99653 2025-10-01 22:03:38.521 | INFO | Skip map cat attack 2025-10-01 22:03:38.524 | INFO | Click (1203, 661) @ MAP_CAT_ATTACK 2025-10-01 22:03:40.033 | INFO | [Loading] 4% 2025-10-01 22:03:40.037 | INFO | Auto search combat loading 2025-10-01 22:03:40.042 | INFO | Screenshot interval set to 1.0s 2025-10-01 22:03:46.028 | INFO | [BattleUI] PAUSE 2025-10-01 22:03:46.031 | INFO | Auto Search combat execute 2025-10-01 22:03:46.034 | INFO | <<< EMOTION REDUCE >>> 2025-10-01 22:03:46.047 | INFO | Bind task ['General', 'Alas', 'Main'] 2025-10-01 22:03:46.052 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=43, Main.Emotion.Fleet1Record=datetime.datetime(2025, 10, 1, 22, 3, 46), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2025, 10, 1, 22, 3, 46) 2025-10-01 22:03:46.065 | INFO | [Emotion fleet_1] 43 2025-10-01 22:03:46.070 | INFO | [Emotion fleet_2] 119 2025-10-01 22:03:52.022 | INFO | Combat auto check timer reached 2025-10-01 22:04:29.066 | INFO | Screenshot interval set to 0.3s 2025-10-01 22:04:29.070 | INFO | Auto Search combat status 2025-10-01 22:04:29.958 | INFO | Click (1022, 672) @ GET_SHIP 2025-10-01 22:04:31.462 | INFO | Click (1034, 669) @ GET_SHIP 2025-10-01 22:04:32.669 | INFO | Click (1019, 664) @ GET_SHIP 2025-10-01 22:04:33.554 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_5 ─────────────────────────────────────────────────────── 2025-10-01 22:04:33.560 | INFO | BATTLE_5 2025-10-01 22:04:33.562 | INFO | Auto search moving 2025-10-01 22:04:33.566 | INFO | Fleet: 1, fleet_current_index: 1 2025-10-01 22:04:33.634 | INFO | [OCR_OIL 0.064s] 8107 2025-10-01 22:04:33.697 | INFO | [OCR_COIN 0.059s] 99673 2025-10-01 22:07:33.736 | 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 L1521 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 L422 run() campaign_base.py L143 run() campaign_base.py L177 auto_search_execute_a_battle() auto_search_combat.py L182 auto_search_moving() device.py L188 screenshot() device.py L250 stuck_record_check() device.py L43 show_function_call() 2025-10-01 22:07:33.761 | WARNING | Wait too long 2025-10-01 22:07:33.765 | WARNING | Waiting for {'CAMPAIGN_CHECK', 'SP_CHECK', 'PAUSE', 'EVENT_CHECK', 'GAME_TIPS3', 'AUTO_SEARCH_MAP_OPTION_OFF', 'GAME_TIPS4', 'OCR_OIL_CHECK', 'RETIRE_APPEAR_3', 'DOCK_CHECK', 'GAME_TIPS', 'FLEET_NUM_1', 'AUTO_SEARCH_MAP_OPTION_ON'} 2025-10-01 22:07:33.831 | INFO | [Package_name] com.bilibili.azurlane 2025-10-01 22:07:33.835 | ERROR | GameStuckError: Wait too long 2025-10-01 22:07:33.839 | WARNING | Saving error: ./log/error/1759327653839