═══════════════════════════════════════════════════════════════════════════════════════════════════════════════════════ MAIN ═══════════════════════════════════════════════════════════════════════════════════════════════════════════════════════ ════════════════════════════════════════════════════ CAMPAIGN_13_1 ════════════════════════════════════════════════════ 2025-10-01 14:36:01.572 | INFO | CAMPAIGN_13_1 2025-10-01 14:36:01.575 | INFO | Count: 0 2025-10-01 14:36:01.590 | INFO | <<< UI ENSURE >>> 2025-10-01 14:36:01.597 | INFO | UI get current page 2025-10-01 14:36:02.116 | INFO | Unknown ui page 2025-10-01 14:36:02.586 | INFO | UI additional: IDLE -> REWARD_GOTO_MAIN 2025-10-01 14:36:02.589 | INFO | Click ( 855, 625) @ REWARD_GOTO_MAIN 2025-10-01 14:36:02.762 | INFO | Unknown ui page 2025-10-01 14:36:02.907 | INFO | [Package_name] com.bilibili.azurlane 2025-10-01 14:36:02.923 | INFO | [Device Orientation] 0 (Normal) 2025-10-01 14:36:03.085 | INFO | Unknown ui page 2025-10-01 14:36:03.291 | INFO | [UI] page_main 2025-10-01 14:36:03.295 | INFO | Goto page_campaign 2025-10-01 14:36:03.302 | INFO | <<< UI GOTO PAGE_CAMPAIGN >>> 2025-10-01 14:36:03.318 | INFO | Page switch: page_main_white -> page_campaign_menu 2025-10-01 14:36:03.322 | INFO | Click (1191, 502) @ MAIN_GOTO_CAMPAIGN_WHITE 2025-10-01 14:36:03.896 | INFO | Page switch: page_campaign_menu -> page_campaign 2025-10-01 14:36:03.900 | INFO | Click ( 361, 221) @ CAMPAIGN_MENU_GOTO_CAMPAIGN 2025-10-01 14:36:04.190 | INFO | Page arrive: page_campaign 2025-10-01 14:36:04.215 | INFO | Mode_switch_1 set to hard 2025-10-01 14:36:04.242 | INFO | [Mode_switch_1] hard 2025-10-01 14:36:04.247 | INFO | <<< UI ENSURE INDEX >>> 2025-10-01 14:36:04.324 | INFO | No stage found. 2025-10-01 14:36:04.557 | INFO | No stage found. 2025-10-01 14:36:04.872 | INFO | Loading OCR model: ./bin/cnocr_models/azur_lane 2025-10-01 14:36:04.989 | INFO | [campaign 0.134s] ['13--1', '13--3', '13--2', '13--4'] 2025-10-01 14:36:04.993 | INFO | [Chapter] 13 2025-10-01 14:36:04.995 | INFO | [Stage] 13-1, 13-3, 13-2, 13-4 2025-10-01 14:36:04.999 | INFO | [Index] 13 2025-10-01 14:36:05.068 | INFO | [OCR_OIL 0.043s] 13352 ──────────────────────────────────────────────────────── 13-1 ───────────────────────────────────────────────────────── 2025-10-01 14:36:05.072 | INFO | 13-1 2025-10-01 14:36:05.074 | INFO | Expect emotion reduce: (12, 2) 2025-10-01 14:36:05.087 | INFO | Bind task ['General', 'Alas', 'Main'] 2025-10-01 14:36:05.091 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=53, Main.Emotion.Fleet1Record=datetime.datetime(2025, 10, 1, 14, 36, 5), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2025, 10, 1, 14, 36, 5) 2025-10-01 14:36:05.107 | INFO | [Emotion fleet_1] 53 2025-10-01 14:36:05.110 | INFO | [Emotion fleet_2] 119 2025-10-01 14:36:05.116 | INFO | <<< ENTER MAP >>> 2025-10-01 14:36:05.192 | INFO | Click ( 188, 285) @ 13-1 2025-10-01 14:36:05.902 | INFO | [Map_clear_percentage] 59% 2025-10-01 14:36:06.197 | INFO | [Map_clear_percentage] 99% 2025-10-01 14:36:06.224 | INFO | [MAP_CLEAR_ALL_THIS_TIME] False 2025-10-01 14:36:06.228 | INFO | [Map_info] 99%, star_1, star_2, star_3, 100_percent_clear, 3_stars, threat_safe, clear_mode 2025-10-01 14:36:06.235 | INFO | [StopCondition_MapAchievement] non_stop 2025-10-01 14:36:06.239 | INFO | Fast_Forward set to on 2025-10-01 14:36:06.242 | INFO | [Fast_Forward] on 2025-10-01 14:36:06.257 | INFO | Auto_Search set to on 2025-10-01 14:36:06.266 | INFO | [Auto_Search] on 2025-10-01 14:36:06.271 | INFO | Click ( 938, 511) @ MAP_PREPARATION 2025-10-01 14:36:06.800 | INFO | Handling 2x book setting, mode=prep. 2025-10-01 14:36:06.823 | INFO | Using fleet: [4, 3, 1] 2025-10-01 14:36:06.905 | INFO | Hard satisfied: Fleet_1: None, Fleet_2: None, Submarine: None 2025-10-01 14:36:06.915 | INFO | [map_allow_submarine] True 2025-10-01 14:36:06.921 | INFO | Click (1039, 477) @ SUBMARINE_CHOOSE 2025-10-01 14:36:07.094 | INFO | Current selected: [1] 2025-10-01 14:36:07.099 | INFO | Click (1052, 456) @ SUBMARINE_CHOOSE 2025-10-01 14:36:07.401 | INFO | Click (1132, 318) @ FLEET_2_CLEAR 2025-10-01 14:36:08.000 | INFO | Click (1067, 212) @ FLEET_1_CHOOSE 2025-10-01 14:36:08.298 | INFO | Current selected: [4] 2025-10-01 14:36:08.303 | INFO | Click (1051, 229) @ FLEET_1_CHOOSE 2025-10-01 14:36:08.598 | INFO | Click (1052, 337) @ FLEET_2_CHOOSE 2025-10-01 14:36:08.896 | INFO | Current selected: [4] 2025-10-01 14:36:08.900 | INFO | Click (1103, 486) @ FLEET_2_BAR_INDEX_3 2025-10-01 14:36:09.205 | INFO | Auto search setting 2025-10-01 14:36:09.222 | INFO | [_fleet_sidebar_offset] 0 2025-10-01 14:36:09.227 | INFO | [Fleet_sidebar] 1/3 2025-10-01 14:36:09.231 | INFO | Click (1206, 424) @ FLEET_SIDEBAR_0_2 2025-10-01 14:36:09.703 | INFO | [_fleet_sidebar_offset] 0 2025-10-01 14:36:09.707 | INFO | [Fleet_sidebar] 3/3 2025-10-01 14:36:09.711 | INFO | [Auto_Search_Setting] fleet1_all_fleet2_standby, sub_auto_call 2025-10-01 14:36:09.713 | INFO | Click ( 505, 220) @ AUTO_SEARCH_SET_MOB 2025-10-01 14:36:10.151 | INFO | [Auto_Search_Setting] fleet1_mob_fleet2_boss, sub_auto_call 2025-10-01 14:36:10.157 | INFO | Selected to the correct auto search setting 2025-10-01 14:36:10.160 | INFO | [Auto_Search_Setting] fleet1_mob_fleet2_boss, sub_auto_call 2025-10-01 14:36:10.163 | INFO | Selected to the correct auto search setting 2025-10-01 14:36:10.167 | INFO | Click (1048, 581) @ FLEET_PREPARATION 2025-10-01 14:36:11.067 | INFO | is_auto_search_running appeared ────────────────────────────────────────────────────── BATTLE_0 ─────────────────────────────────────────────────────── 2025-10-01 14:36:11.072 | INFO | BATTLE_0 2025-10-01 14:36:11.075 | INFO | Auto search moving 2025-10-01 14:36:11.088 | INFO | Fleet: 1, fleet_current_index: 1 2025-10-01 14:36:11.145 | INFO | [OCR_OIL 0.052s] 13342 2025-10-01 14:36:11.239 | INFO | [OCR_COIN 0.091s] 85820 2025-10-01 14:36:17.179 | INFO | [Loading] 14% 2025-10-01 14:36:17.183 | INFO | Auto search combat loading 2025-10-01 14:36:17.185 | INFO | Screenshot interval set to 1.0s 2025-10-01 14:36:23.180 | INFO | [BattleUI] PAUSE 2025-10-01 14:36:23.184 | INFO | Auto Search combat execute 2025-10-01 14:36:23.187 | INFO | <<< EMOTION REDUCE >>> 2025-10-01 14:36:23.199 | INFO | Bind task ['General', 'Alas', 'Main'] 2025-10-01 14:36:23.205 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=51, Main.Emotion.Fleet1Record=datetime.datetime(2025, 10, 1, 14, 36, 23), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2025, 10, 1, 14, 36, 23) 2025-10-01 14:36:23.222 | INFO | [Emotion fleet_1] 51 2025-10-01 14:36:23.226 | INFO | [Emotion fleet_2] 119 2025-10-01 14:36:28.190 | INFO | Combat auto check timer reached 2025-10-01 14:36:46.216 | INFO | Screenshot interval set to 0.3s 2025-10-01 14:36:46.221 | INFO | Auto Search combat status 2025-10-01 14:36:48.601 | INFO | Click (1021, 670) @ GET_SHIP 2025-10-01 14:36:49.807 | INFO | Click (1038, 646) @ GET_SHIP 2025-10-01 14:36:50.989 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_1 ─────────────────────────────────────────────────────── 2025-10-01 14:36:50.995 | INFO | BATTLE_1 2025-10-01 14:36:50.998 | INFO | Auto search moving 2025-10-01 14:36:51.003 | INFO | Fleet: 1, fleet_current_index: 1 2025-10-01 14:36:51.099 | INFO | [OCR_OIL 0.089s] 13301 2025-10-01 14:36:51.208 | INFO | [OCR_COIN 0.103s] 85845 2025-10-01 14:36:56.710 | INFO | Skip map cat attack 2025-10-01 14:36:56.715 | INFO | Click (1181, 674) @ MAP_CAT_ATTACK 2025-10-01 14:36:58.513 | INFO | [Loading] 11% 2025-10-01 14:36:58.517 | INFO | Auto search combat loading 2025-10-01 14:36:58.522 | INFO | Screenshot interval set to 1.0s 2025-10-01 14:37:04.527 | INFO | [BattleUI] PAUSE 2025-10-01 14:37:04.532 | INFO | Auto Search combat execute 2025-10-01 14:37:04.534 | INFO | <<< EMOTION REDUCE >>> 2025-10-01 14:37:04.547 | INFO | Bind task ['General', 'Alas', 'Main'] 2025-10-01 14:37:04.552 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=49, Main.Emotion.Fleet1Record=datetime.datetime(2025, 10, 1, 14, 37, 4), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2025, 10, 1, 14, 37, 4) 2025-10-01 14:37:04.569 | INFO | [Emotion fleet_1] 49 2025-10-01 14:37:04.574 | INFO | [Emotion fleet_2] 119 2025-10-01 14:37:10.523 | INFO | Combat auto check timer reached 2025-10-01 14:37:47.550 | INFO | Screenshot interval set to 0.3s 2025-10-01 14:37:47.555 | INFO | Auto Search combat status 2025-10-01 14:37:49.045 | INFO | Click (1016, 663) @ GET_SHIP 2025-10-01 14:37:50.550 | INFO | Click (1020, 657) @ GET_SHIP 2025-10-01 14:37:51.756 | INFO | Click (1037, 669) @ GET_SHIP 2025-10-01 14:37:52.637 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_2 ─────────────────────────────────────────────────────── 2025-10-01 14:37:52.643 | INFO | BATTLE_2 2025-10-01 14:37:52.645 | INFO | Auto search moving 2025-10-01 14:37:52.648 | INFO | Fleet: 1, fleet_current_index: 1 2025-10-01 14:37:52.712 | INFO | [OCR_OIL 0.060s] 13278 2025-10-01 14:37:52.794 | INFO | [OCR_COIN 0.079s] 85868 2025-10-01 14:37:58.060 | INFO | Skip map cat attack 2025-10-01 14:37:58.065 | INFO | Click (1240, 688) @ MAP_CAT_ATTACK 2025-10-01 14:37:59.863 | INFO | [Loading] 9% 2025-10-01 14:37:59.866 | INFO | Auto search combat loading 2025-10-01 14:37:59.869 | INFO | Screenshot interval set to 1.0s 2025-10-01 14:38:05.864 | INFO | [BattleUI] PAUSE 2025-10-01 14:38:05.867 | INFO | Auto Search combat execute 2025-10-01 14:38:05.869 | INFO | <<< EMOTION REDUCE >>> 2025-10-01 14:38:05.880 | INFO | Bind task ['General', 'Alas', 'Main'] 2025-10-01 14:38:05.885 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=47, Main.Emotion.Fleet1Record=datetime.datetime(2025, 10, 1, 14, 38, 5), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2025, 10, 1, 14, 38, 5) 2025-10-01 14:38:05.904 | INFO | [Emotion fleet_1] 47 2025-10-01 14:38:05.907 | INFO | [Emotion fleet_2] 119 2025-10-01 14:38:11.869 | INFO | Combat auto check timer reached 2025-10-01 14:38:32.894 | INFO | Screenshot interval set to 0.3s 2025-10-01 14:38:32.897 | INFO | Auto Search combat status 2025-10-01 14:38:34.384 | INFO | Click (1026, 671) @ GET_SHIP 2025-10-01 14:38:35.587 | INFO | Click (1032, 663) @ GET_SHIP 2025-10-01 14:38:37.696 | INFO | Click (1029, 644) @ GET_SHIP 2025-10-01 14:38:38.890 | INFO | Click (1030, 647) @ GET_SHIP 2025-10-01 14:38:40.089 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_3 ─────────────────────────────────────────────────────── 2025-10-01 14:38:40.094 | INFO | BATTLE_3 2025-10-01 14:38:40.096 | INFO | Auto search moving 2025-10-01 14:38:40.099 | INFO | Fleet: 1, fleet_current_index: 1 2025-10-01 14:38:40.171 | INFO | [OCR_OIL 0.065s] 13237 2025-10-01 14:38:40.243 | INFO | [OCR_COIN 0.067s] 85880 2025-10-01 14:38:45.798 | INFO | [Loading] 10% 2025-10-01 14:38:45.802 | INFO | Auto search combat loading 2025-10-01 14:38:45.804 | INFO | Screenshot interval set to 1.0s 2025-10-01 14:38:51.802 | INFO | [BattleUI] PAUSE 2025-10-01 14:38:51.805 | INFO | Auto Search combat execute 2025-10-01 14:38:51.807 | INFO | <<< EMOTION REDUCE >>> 2025-10-01 14:38:51.820 | INFO | Bind task ['General', 'Alas', 'Main'] 2025-10-01 14:38:51.825 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=45, Main.Emotion.Fleet1Record=datetime.datetime(2025, 10, 1, 14, 38, 51), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2025, 10, 1, 14, 38, 51) 2025-10-01 14:38:51.842 | INFO | [Emotion fleet_1] 45 2025-10-01 14:38:51.848 | INFO | [Emotion fleet_2] 119 2025-10-01 14:38:56.819 | INFO | Combat auto check timer reached 2025-10-01 14:39:14.830 | INFO | Screenshot interval set to 0.3s 2025-10-01 14:39:14.833 | INFO | Auto Search combat status 2025-10-01 14:39:15.421 | INFO | Click (1027, 646) @ GET_SHIP 2025-10-01 14:39:16.619 | INFO | Click (1045, 666) @ GET_SHIP 2025-10-01 14:39:18.723 | INFO | Click (1045, 638) @ GET_SHIP 2025-10-01 14:39:19.939 | INFO | Click (1038, 643) @ GET_SHIP 2025-10-01 14:39:21.118 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_4 ─────────────────────────────────────────────────────── 2025-10-01 14:39:21.124 | INFO | BATTLE_4 2025-10-01 14:39:21.127 | INFO | Auto search moving 2025-10-01 14:39:21.130 | INFO | Fleet: 1, fleet_current_index: 1 2025-10-01 14:39:21.208 | INFO | [OCR_OIL 0.075s] 13196 2025-10-01 14:39:21.286 | INFO | [OCR_COIN 0.072s] 85905 2025-10-01 14:39:28.027 | INFO | Skip map cat attack 2025-10-01 14:39:28.030 | INFO | Click (1195, 699) @ MAP_CAT_ATTACK 2025-10-01 14:39:29.833 | INFO | [Loading] 11% 2025-10-01 14:39:29.837 | INFO | Auto search combat loading 2025-10-01 14:39:29.841 | INFO | Screenshot interval set to 1.0s 2025-10-01 14:39:35.847 | INFO | [BattleUI] PAUSE 2025-10-01 14:39:35.851 | INFO | Auto Search combat execute 2025-10-01 14:39:35.854 | INFO | <<< EMOTION REDUCE >>> 2025-10-01 14:39:35.869 | INFO | Bind task ['General', 'Alas', 'Main'] 2025-10-01 14:39:35.874 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=43, Main.Emotion.Fleet1Record=datetime.datetime(2025, 10, 1, 14, 39, 35), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2025, 10, 1, 14, 39, 35) 2025-10-01 14:39:35.897 | INFO | [Emotion fleet_1] 43 2025-10-01 14:39:35.903 | INFO | [Emotion fleet_2] 119 2025-10-01 14:39:41.840 | INFO | Combat auto check timer reached 2025-10-01 14:40:18.881 | INFO | Screenshot interval set to 0.3s 2025-10-01 14:40:18.884 | INFO | Auto Search combat status 2025-10-01 14:40:20.377 | INFO | Click (1034, 656) @ GET_SHIP 2025-10-01 14:40:21.883 | INFO | Click (1033, 644) @ GET_SHIP 2025-10-01 14:40:23.087 | INFO | Click (1028, 666) @ GET_SHIP 2025-10-01 14:40:24.267 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_5 ─────────────────────────────────────────────────────── 2025-10-01 14:40:24.272 | INFO | BATTLE_5 2025-10-01 14:40:24.275 | INFO | Auto search moving 2025-10-01 14:40:24.283 | INFO | Fleet: 1, fleet_current_index: 1 2025-10-01 14:40:24.386 | INFO | [OCR_OIL 0.098s] 13173 2025-10-01 14:40:24.477 | INFO | [OCR_COIN 0.086s] 85918 2025-10-01 14:43:24.469 | 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 14:43:24.491 | WARNING | Wait too long 2025-10-01 14:43:24.496 | 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 14:43:24.559 | INFO | [Package_name] com.bilibili.azurlane 2025-10-01 14:43:24.564 | ERROR | GameStuckError: Wait too long 2025-10-01 14:43:24.569 | WARNING | Saving error: ./log/error/1759301004569