═══════════════════════════════════════════════════════════════════════════════════════════════════════════════════════ MAIN ═══════════════════════════════════════════════════════════════════════════════════════════════════════════════════════ 2026-01-27 20:33:54.992 | INFO | Reuse forward: ForwardItem(serial='192.168.240.112:5555', local='tcp:20905', remote='tcp:53516') 2026-01-27 20:33:55.030 | INFO | [Device Orientation] 0 (Normal) 2026-01-27 20:33:55.057 | INFO | Reuse forward: ForwardItem(serial='192.168.240.112:5555', local='tcp:20958', remote='localabstract:minitouch') 2026-01-27 20:33:55.121 | INFO | 2026-01-27 20:33:55.127 | INFO | 2026-01-27 20:33:55.193 | INFO | [Screen_size] 1280x720 2026-01-27 20:33:55.768 | INFO | early_ocr_import call 2026-01-27 20:33:55.773 | INFO | early_ocr_import start 2026-01-27 20:33:55.808 | INFO | Loading OCR dependencies ════════════════════════════════════════════════════ CAMPAIGN_13_1 ════════════════════════════════════════════════════ 2026-01-27 20:33:55.822 | INFO | CAMPAIGN_13_1 2026-01-27 20:33:55.827 | INFO | Count: 0 2026-01-27 20:33:56.053 | INFO | <<< UI ENSURE >>> 2026-01-27 20:33:56.058 | INFO | UI get current page 2026-01-27 20:33:56.083 | INFO | [UI] page_main 2026-01-27 20:33:56.088 | INFO | Goto page_campaign 2026-01-27 20:33:56.095 | INFO | <<< UI GOTO PAGE_CAMPAIGN >>> 2026-01-27 20:33:56.182 | INFO | v 1 2026-01-27 20:33:56.210 | INFO | ^ 10 1280 720 0 2026-01-27 20:33:56.231 | INFO | Page switch: page_main_white -> page_campaign_menu 2026-01-27 20:33:56.249 | INFO | $ 3183 2026-01-27 20:33:56.309 | INFO | Click (1189, 500) @ MAIN_GOTO_CAMPAIGN_WHITE 2026-01-27 20:33:56.313 | INFO | minitouch running on port: 20958, pid: 3183 2026-01-27 20:33:56.325 | INFO | max_contact: 10; max_x: 1280; max_y: 720; max_pressure: 0 2026-01-27 20:33:58.240 | INFO | early_ocr_import finish 2026-01-27 20:33:58.826 | INFO | Page switch: page_campaign_menu -> page_campaign 2026-01-27 20:33:58.831 | INFO | Click ( 358, 345) @ CAMPAIGN_MENU_GOTO_CAMPAIGN 2026-01-27 20:33:59.086 | INFO | Page arrive: page_campaign 2026-01-27 20:33:59.115 | INFO | Mode_switch_1 set to hard 2026-01-27 20:33:59.146 | INFO | [Mode_switch_1] hard 2026-01-27 20:33:59.154 | INFO | <<< UI ENSURE INDEX >>> 2026-01-27 20:33:59.242 | INFO | No stage found. 2026-01-27 20:33:59.470 | INFO | No stage found. 2026-01-27 20:33:59.775 | INFO | No stage found. 2026-01-27 20:34:00.248 | INFO | Loading OCR model: ./bin/cnocr_models/azur_lane 2026-01-27 20:34:00.606 | INFO | [campaign 0.445s] ['13--1', '13--3', '13--2', '13--4'] 2026-01-27 20:34:00.612 | INFO | [Chapter] 13 2026-01-27 20:34:00.619 | INFO | [Stage] 13-1, 13-3, 13-2, 13-4 2026-01-27 20:34:00.628 | INFO | [Index] 13 2026-01-27 20:34:00.778 | INFO | [OCR_OIL 0.117s] 3519 ──────────────────────────────────────────────────────── 13-1 ───────────────────────────────────────────────────────── 2026-01-27 20:34:00.786 | INFO | 13-1 2026-01-27 20:34:00.788 | INFO | Expect emotion reduce: (12, 2) 2026-01-27 20:34:00.799 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-01-27 20:34:00.806 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=77, Main.Emotion.Fleet1Record=datetime.datetime(2026, 1, 27, 20, 34), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 1, 27, 20, 34) 2026-01-27 20:34:00.823 | INFO | [Emotion fleet_1] 77 2026-01-27 20:34:00.827 | INFO | [Emotion fleet_2] 119 2026-01-27 20:34:00.830 | INFO | <<< ENTER MAP >>> 2026-01-27 20:34:00.928 | INFO | Click ( 182, 292) @ 13-1 2026-01-27 20:34:01.362 | INFO | [Map_clear_percentage] 12% 2026-01-27 20:34:01.718 | INFO | [Map_clear_percentage] 73% 2026-01-27 20:34:01.975 | INFO | [Map_clear_percentage] 99% 2026-01-27 20:34:02.011 | INFO | [MAP_CLEAR_ALL_THIS_TIME] False 2026-01-27 20:34:02.018 | INFO | [Map_info] 99%, star_1, star_2, star_3, 100_percent_clear, 3_stars, threat_safe, clear_mode 2026-01-27 20:34:02.023 | INFO | [StopCondition_MapAchievement] non_stop 2026-01-27 20:34:02.029 | INFO | Fast_Forward set to on 2026-01-27 20:34:02.032 | INFO | [Fast_Forward] on 2026-01-27 20:34:02.048 | INFO | Auto_Search set to on 2026-01-27 20:34:02.055 | INFO | [Auto_Search] on 2026-01-27 20:34:02.062 | INFO | Click (1024, 500) @ MAP_PREPARATION 2026-01-27 20:34:02.549 | INFO | Handling 2x book setting, mode=prep. 2026-01-27 20:34:02.576 | INFO | Using fleet: [4, 3, 1] 2026-01-27 20:34:02.659 | INFO | Hard satisfied: Fleet_1: None, Fleet_2: None, Submarine: None 2026-01-27 20:34:02.672 | INFO | [map_allow_submarine] True 2026-01-27 20:34:02.680 | INFO | Click (1154, 333) @ FLEET_2_CLEAR 2026-01-27 20:34:02.738 | INFO | Click (1063, 464) @ SUBMARINE_CHOOSE 2026-01-27 20:34:03.148 | INFO | Current selected: [1] 2026-01-27 20:34:03.154 | INFO | Click (1048, 461) @ SUBMARINE_CHOOSE 2026-01-27 20:34:03.456 | INFO | Click (1037, 232) @ FLEET_1_CHOOSE 2026-01-27 20:34:03.784 | INFO | Current selected: [4] 2026-01-27 20:34:03.795 | INFO | Click (1050, 206) @ FLEET_1_CHOOSE 2026-01-27 20:34:04.079 | INFO | Click (1066, 330) @ FLEET_2_CHOOSE 2026-01-27 20:34:04.352 | INFO | Current selected: [4] 2026-01-27 20:34:04.373 | INFO | Click (1100, 477) @ FLEET_2_BAR_INDEX_3 2026-01-27 20:34:04.648 | INFO | Auto search setting 2026-01-27 20:34:04.664 | INFO | [_fleet_sidebar_offset] 0 2026-01-27 20:34:04.668 | INFO | [Fleet_sidebar] 1/3 2026-01-27 20:34:04.675 | INFO | Click (1207, 433) @ FLEET_SIDEBAR_0_2 2026-01-27 20:34:05.229 | INFO | [_fleet_sidebar_offset] 0 2026-01-27 20:34:05.234 | INFO | [Fleet_sidebar] 3/3 2026-01-27 20:34:05.240 | INFO | [Auto_Search_Setting] fleet1_all_fleet2_standby, sub_standby 2026-01-27 20:34:05.245 | INFO | Click ( 510, 217) @ AUTO_SEARCH_SET_MOB 2026-01-27 20:34:05.811 | INFO | [Auto_Search_Setting] fleet1_mob_fleet2_boss, sub_standby 2026-01-27 20:34:05.817 | INFO | Selected to the correct auto search setting 2026-01-27 20:34:05.824 | INFO | [Auto_Search_Setting] fleet1_mob_fleet2_boss, sub_standby 2026-01-27 20:34:05.829 | INFO | Selected to the correct auto search setting 2026-01-27 20:34:05.833 | INFO | Click (1107, 599) @ FLEET_PREPARATION 2026-01-27 20:34:07.404 | INFO | is_auto_search_running appeared ────────────────────────────────────────────────────── BATTLE_0 ─────────────────────────────────────────────────────── 2026-01-27 20:34:07.414 | INFO | BATTLE_0 2026-01-27 20:34:07.420 | INFO | Auto search moving 2026-01-27 20:34:07.440 | INFO | Fleet: 1, fleet_current_index: 1 2026-01-27 20:34:07.616 | INFO | [OCR_OIL 0.166s] 3509 2026-01-27 20:34:07.836 | INFO | [OCR_COIN 0.208s] 109975 2026-01-27 20:34:12.331 | INFO | [Loading] 16% 2026-01-27 20:34:12.336 | INFO | Auto search combat loading 2026-01-27 20:34:12.339 | INFO | Screenshot interval set to 1.0s 2026-01-27 20:34:18.343 | INFO | [BattleUI] PAUSE 2026-01-27 20:34:18.347 | INFO | Auto Search combat execute 2026-01-27 20:34:18.351 | INFO | <<< EMOTION REDUCE >>> 2026-01-27 20:34:18.366 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-01-27 20:34:18.369 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=75, Main.Emotion.Fleet1Record=datetime.datetime(2026, 1, 27, 20, 34, 18), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 1, 27, 20, 34, 18) 2026-01-27 20:34:18.420 | INFO | [Emotion fleet_1] 75 2026-01-27 20:34:18.425 | INFO | [Emotion fleet_2] 119 2026-01-27 20:34:24.328 | INFO | Combat auto check timer reached 2026-01-27 20:35:00.389 | INFO | Screenshot interval set to 0.3s 2026-01-27 20:35:00.392 | INFO | Auto Search combat status 2026-01-27 20:35:01.344 | INFO | Click (1010, 646) @ GET_SHIP 2026-01-27 20:35:02.503 | INFO | Click (1031, 676) @ GET_SHIP 2026-01-27 20:35:03.687 | INFO | Click (1014, 650) @ GET_SHIP 2026-01-27 20:35:04.912 | INFO | Click (1032, 658) @ GET_SHIP 2026-01-27 20:35:06.979 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_1 ─────────────────────────────────────────────────────── 2026-01-27 20:35:06.988 | INFO | BATTLE_1 2026-01-27 20:35:06.993 | INFO | Auto search moving 2026-01-27 20:35:07.003 | INFO | Fleet: 1, fleet_current_index: 1 2026-01-27 20:35:07.156 | INFO | [OCR_OIL 0.146s] 3490 2026-01-27 20:35:07.388 | INFO | [OCR_COIN 0.222s] 109985 2026-01-27 20:35:11.700 | INFO | [Loading] 4% 2026-01-27 20:35:11.704 | INFO | Auto search combat loading 2026-01-27 20:35:11.710 | INFO | Screenshot interval set to 1.0s 2026-01-27 20:35:17.720 | INFO | [BattleUI] PAUSE 2026-01-27 20:35:17.725 | INFO | Auto Search combat execute 2026-01-27 20:35:17.732 | INFO | <<< EMOTION REDUCE >>> 2026-01-27 20:35:17.745 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-01-27 20:35:17.750 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=73, Main.Emotion.Fleet1Record=datetime.datetime(2026, 1, 27, 20, 35, 17), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 1, 27, 20, 35, 17) 2026-01-27 20:35:17.773 | INFO | [Emotion fleet_1] 73 2026-01-27 20:35:17.777 | INFO | [Emotion fleet_2] 119 2026-01-27 20:35:22.747 | INFO | Combat auto check timer reached 2026-01-27 20:35:44.741 | INFO | Click (1017, 650) @ GET_SHIP 2026-01-27 20:35:45.748 | INFO | Click (1023, 665) @ GET_SHIP 2026-01-27 20:35:46.763 | INFO | Click (1022, 655) @ GET_SHIP 2026-01-27 20:35:47.747 | INFO | Screenshot interval set to 0.3s 2026-01-27 20:35:47.751 | INFO | Auto Search combat status 2026-01-27 20:35:47.755 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_2 ─────────────────────────────────────────────────────── 2026-01-27 20:35:47.765 | INFO | BATTLE_2 2026-01-27 20:35:47.770 | INFO | Auto search moving 2026-01-27 20:35:47.776 | INFO | Fleet: 1, fleet_current_index: 1 2026-01-27 20:35:47.976 | INFO | [OCR_OIL 0.186s] 3471 2026-01-27 20:35:48.177 | INFO | [OCR_COIN 0.186s] 109998 2026-01-27 20:35:51.908 | INFO | [Loading] 7% 2026-01-27 20:35:51.915 | INFO | Auto search combat loading 2026-01-27 20:35:51.920 | INFO | Screenshot interval set to 1.0s 2026-01-27 20:35:58.948 | INFO | [BattleUI] PAUSE 2026-01-27 20:35:58.952 | INFO | Auto Search combat execute 2026-01-27 20:35:58.955 | INFO | <<< EMOTION REDUCE >>> 2026-01-27 20:35:58.980 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-01-27 20:35:58.985 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=71, Main.Emotion.Fleet1Record=datetime.datetime(2026, 1, 27, 20, 35, 58), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 1, 27, 20, 35, 58) 2026-01-27 20:35:59.008 | INFO | [Emotion fleet_1] 71 2026-01-27 20:35:59.017 | INFO | [Emotion fleet_2] 119 2026-01-27 20:36:03.961 | INFO | Combat auto check timer reached 2026-01-27 20:36:59.969 | INFO | Click (1042, 658) @ GET_SHIP 2026-01-27 20:37:02.033 | INFO | Click (1033, 644) @ GET_SHIP 2026-01-27 20:37:04.026 | INFO | Screenshot interval set to 0.3s 2026-01-27 20:37:04.034 | INFO | Auto Search combat status 2026-01-27 20:37:04.047 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_3 ─────────────────────────────────────────────────────── 2026-01-27 20:37:04.064 | INFO | BATTLE_3 2026-01-27 20:37:04.073 | INFO | Auto search moving 2026-01-27 20:37:04.078 | INFO | Fleet: 1, fleet_current_index: 1 2026-01-27 20:37:04.375 | INFO | [OCR_OIL 0.293s] 3452 2026-01-27 20:37:04.609 | INFO | [OCR_COIN 0.227s] 110014 2026-01-27 20:37:08.313 | INFO | [Loading] 18% 2026-01-27 20:37:08.316 | INFO | Auto search combat loading 2026-01-27 20:37:08.318 | INFO | Screenshot interval set to 1.0s 2026-01-27 20:37:13.374 | INFO | [BattleUI] PAUSE 2026-01-27 20:37:13.383 | INFO | Auto Search combat execute 2026-01-27 20:37:13.388 | INFO | <<< EMOTION REDUCE >>> 2026-01-27 20:37:13.406 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-01-27 20:37:13.411 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=71, Main.Emotion.Fleet1Record=datetime.datetime(2026, 1, 27, 20, 37, 13), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 1, 27, 20, 37, 13) 2026-01-27 20:37:13.432 | INFO | [Emotion fleet_1] 71 2026-01-27 20:37:13.450 | INFO | [Emotion fleet_2] 119 2026-01-27 20:37:19.309 | INFO | Combat auto check timer reached 2026-01-27 20:37:58.362 | INFO | Click (1017, 652) @ GET_SHIP 2026-01-27 20:37:59.366 | INFO | Click (1030, 657) @ GET_SHIP 2026-01-27 20:38:01.379 | INFO | Click (1020, 666) @ GET_SHIP 2026-01-27 20:38:02.403 | INFO | Click (1026, 673) @ GET_SHIP 2026-01-27 20:38:04.374 | INFO | Screenshot interval set to 0.3s 2026-01-27 20:38:04.380 | INFO | Auto Search combat status 2026-01-27 20:38:04.386 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_4 ─────────────────────────────────────────────────────── 2026-01-27 20:38:04.394 | INFO | BATTLE_4 2026-01-27 20:38:04.398 | INFO | Auto search moving 2026-01-27 20:38:04.403 | INFO | Fleet: 1, fleet_current_index: 1 2026-01-27 20:38:04.508 | INFO | [OCR_OIL 0.099s] 3433 2026-01-27 20:38:04.576 | INFO | [OCR_COIN 0.064s] 110026 2026-01-27 20:38:08.266 | INFO | [Loading] 14% 2026-01-27 20:38:08.269 | INFO | Auto search combat loading 2026-01-27 20:38:08.272 | INFO | Screenshot interval set to 1.0s 2026-01-27 20:38:13.269 | INFO | [BattleUI] PAUSE 2026-01-27 20:38:13.273 | INFO | Auto Search combat execute 2026-01-27 20:38:13.277 | INFO | <<< EMOTION REDUCE >>> 2026-01-27 20:38:13.291 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-01-27 20:38:13.296 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=69, Main.Emotion.Fleet1Record=datetime.datetime(2026, 1, 27, 20, 38, 13), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 1, 27, 20, 38, 13) 2026-01-27 20:38:13.311 | INFO | [Emotion fleet_1] 69 2026-01-27 20:38:13.316 | INFO | [Emotion fleet_2] 119 2026-01-27 20:38:19.272 | INFO | Combat auto check timer reached 2026-01-27 20:38:40.296 | INFO | Click (1033, 676) @ GET_SHIP 2026-01-27 20:38:41.316 | INFO | Click (1015, 653) @ GET_SHIP 2026-01-27 20:38:43.315 | INFO | Screenshot interval set to 0.3s 2026-01-27 20:38:43.319 | INFO | Auto Search combat status 2026-01-27 20:38:43.323 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_5 ─────────────────────────────────────────────────────── 2026-01-27 20:38:43.330 | INFO | BATTLE_5 2026-01-27 20:38:43.334 | INFO | Auto search moving 2026-01-27 20:38:43.340 | INFO | Fleet: 1, fleet_current_index: 1 2026-01-27 20:38:43.475 | INFO | [OCR_OIL 0.129s] 3414 2026-01-27 20:38:43.713 | INFO | [OCR_COIN 0.234s] 110040 2026-01-27 20:38:45.921 | INFO | [Loading] 4% 2026-01-27 20:38:45.926 | INFO | Auto search combat loading 2026-01-27 20:38:45.929 | INFO | Screenshot interval set to 1.0s 2026-01-27 20:38:51.929 | INFO | [BattleUI] PAUSE 2026-01-27 20:38:51.934 | INFO | Auto Search combat execute 2026-01-27 20:38:51.938 | INFO | <<< EMOTION REDUCE >>> 2026-01-27 20:38:51.961 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-01-27 20:38:51.976 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=67, Main.Emotion.Fleet1Record=datetime.datetime(2026, 1, 27, 20, 38, 51), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 1, 27, 20, 38, 51) 2026-01-27 20:38:51.993 | INFO | [Emotion fleet_1] 67 2026-01-27 20:38:52.001 | INFO | [Emotion fleet_2] 119 2026-01-27 20:38:57.929 | INFO | Combat auto check timer reached 2026-01-27 20:40:22.009 | INFO | Click (1028, 649) @ GET_SHIP 2026-01-27 20:43:22.179 | INFO | Function calls: threading.py L890 _bootstrap() threading.py L926 _bootstrap_inner() threading.py L870 run() threadbased.py L254 run() output.py L801 click_callback() app.py L437 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-01-27 20:43:22.196 | WARNING | Wait too long 2026-01-27 20:43:22.200 | WARNING | Waiting for {'SP_CHECK', 'EXP_INFO_S', 'GET_SHIP', 'EVENT_CHECK', 'EXP_INFO_A', 'BATTLE_STATUS_B', 'CAMPAIGN_CHECK', 'GET_MISSION', 'BATTLE_STATUS_A', 'EXP_INFO_B', 'MISSION_POPUP_GO', 'POPUP_CONFIRM_WHITE', 'PAUSE', 'AUTO_SEARCH_MAP_OPTION_ON', 'NEW_SHIP', 'GUILD_POPUP_CONFIRM', 'BATTLE_STATUS_S', 'POPUP_CANCEL'} 2026-01-27 20:43:22.228 | INFO | newCommandTimeout updated to 168h0m0s 2026-01-27 20:43:22.233 | INFO | [u2.Device] Device(atx_agent_url=http://127.0.0.1:60399) 2026-01-27 20:43:22.304 | INFO | [Package_name] com.bilibili.azurlane 2026-01-27 20:43:22.309 | ERROR | GameStuckError: Wait too long 2026-01-27 20:43:22.313 | WARNING | Saving error: ./log/error/1769517802313