═══════════════════════════════════════════════════════════════════════════════════════════════════════════════════════ MAIN ═══════════════════════════════════════════════════════════════════════════════════════════════════════════════════════ ════════════════════════════════════════════════════ CAMPAIGN_13_1 ════════════════════════════════════════════════════ 2026-05-18 15:06:03.504 | INFO | CAMPAIGN_13_1 2026-05-18 15:06:03.508 | INFO | Count: 0 2026-05-18 15:06:03.529 | INFO | <<< UI ENSURE >>> 2026-05-18 15:06:03.534 | INFO | UI get current page 2026-05-18 15:06:04.064 | INFO | Unknown ui page 2026-05-18 15:06:04.522 | INFO | UI additional: IDLE -> REWARD_GOTO_MAIN 2026-05-18 15:06:04.526 | INFO | Click ( 855, 616) @ REWARD_GOTO_MAIN 2026-05-18 15:06:04.695 | INFO | Unknown ui page 2026-05-18 15:06:04.839 | INFO | [Package_name] com.bilibili.azurlane 2026-05-18 15:06:04.858 | INFO | [Device Orientation] 0 (Normal) 2026-05-18 15:06:05.000 | INFO | Unknown ui page 2026-05-18 15:06:05.304 | INFO | Unknown ui page 2026-05-18 15:06:05.524 | INFO | [UI] page_main 2026-05-18 15:06:05.528 | INFO | Goto page_campaign 2026-05-18 15:06:05.533 | INFO | <<< UI GOTO PAGE_CAMPAIGN >>> 2026-05-18 15:06:05.552 | INFO | Page switch: page_main_white -> page_campaign_menu 2026-05-18 15:06:05.556 | INFO | Click (1183, 498) @ MAIN_GOTO_CAMPAIGN_WHITE 2026-05-18 15:06:06.134 | INFO | Page switch: page_campaign_menu -> page_campaign 2026-05-18 15:06:06.137 | INFO | Click ( 280, 336) @ CAMPAIGN_MENU_GOTO_CAMPAIGN 2026-05-18 15:06:06.424 | INFO | Page arrive: page_campaign 2026-05-18 15:06:06.449 | INFO | Mode_switch_1 set to hard 2026-05-18 15:06:06.476 | INFO | [Mode_switch_1] hard 2026-05-18 15:06:06.480 | INFO | <<< UI ENSURE INDEX >>> 2026-05-18 15:06:06.561 | INFO | No stage found. 2026-05-18 15:06:06.787 | INFO | No stage found. 2026-05-18 15:06:07.129 | INFO | No stage found. 2026-05-18 15:06:07.412 | INFO | Loading OCR model: ./bin/cnocr_models/azur_lane 2026-05-18 15:06:07.528 | INFO | [campaign 0.136s] ['2--2', '2--3', '2--1', '2--4'] 2026-05-18 15:06:07.531 | INFO | [Chapter] 2 2026-05-18 15:06:07.534 | INFO | [Stage] 2-2, 2-3, 2-1, 2-4 2026-05-18 15:06:07.538 | INFO | [Index] 2 2026-05-18 15:06:07.783 | INFO | Click (1228, 381) @ CHAPTER_NEXT 2026-05-18 15:06:08.034 | INFO | Click (1223, 374) @ CHAPTER_NEXT 2026-05-18 15:06:08.271 | INFO | Click (1221, 371) @ CHAPTER_NEXT 2026-05-18 15:06:08.512 | INFO | Click (1224, 366) @ CHAPTER_NEXT 2026-05-18 15:06:08.768 | INFO | Click (1220, 384) @ CHAPTER_NEXT 2026-05-18 15:06:09.000 | INFO | Click (1229, 364) @ CHAPTER_NEXT 2026-05-18 15:06:09.248 | INFO | Click (1225, 372) @ CHAPTER_NEXT 2026-05-18 15:06:09.509 | INFO | Click (1221, 370) @ CHAPTER_NEXT 2026-05-18 15:06:09.743 | INFO | Click (1218, 379) @ CHAPTER_NEXT 2026-05-18 15:06:09.970 | INFO | Click (1224, 374) @ CHAPTER_NEXT 2026-05-18 15:06:10.204 | INFO | Click (1225, 380) @ CHAPTER_NEXT 2026-05-18 15:06:10.386 | INFO | No stage found. 2026-05-18 15:06:10.665 | INFO | No stage found. 2026-05-18 15:06:11.000 | INFO | [campaign 0.032s] ['', '', '', ''] 2026-05-18 15:06:11.315 | INFO | [campaign 0.049s] ['13--1', '13--3', '13--2', '13--4'] 2026-05-18 15:06:11.320 | INFO | [Chapter] 13 2026-05-18 15:06:11.323 | INFO | [Stage] 13-1, 13-3, 13-2, 13-4 2026-05-18 15:06:11.332 | INFO | [Index] 13 2026-05-18 15:06:11.428 | INFO | [OCR_OIL 0.064s] 23828 ──────────────────────────────────────────────────────── 13-1 ───────────────────────────────────────────────────────── 2026-05-18 15:06:11.434 | INFO | 13-1 2026-05-18 15:06:11.437 | INFO | Expect emotion reduce: (12, 2) 2026-05-18 15:06:11.449 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-05-18 15:06:11.454 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=53, Main.Emotion.Fleet1Record=datetime.datetime(2026, 5, 18, 15, 6, 11), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 5, 18, 15, 6, 11) 2026-05-18 15:06:11.472 | INFO | [Emotion fleet_1] 53 2026-05-18 15:06:11.478 | INFO | [Emotion fleet_2] 119 2026-05-18 15:06:11.481 | INFO | <<< ENTER MAP >>> 2026-05-18 15:06:11.560 | INFO | Click ( 185, 270) @ 13-1 2026-05-18 15:06:11.966 | INFO | [Map_clear_percentage] 5% 2026-05-18 15:06:12.274 | INFO | [Map_clear_percentage] 65% 2026-05-18 15:06:12.572 | INFO | [Map_clear_percentage] 99% 2026-05-18 15:06:12.598 | INFO | [MAP_CLEAR_ALL_THIS_TIME] False 2026-05-18 15:06:12.603 | INFO | [Map_info] 99%, star_1, star_2, star_3, 100_percent_clear, 3_stars, threat_safe, clear_mode 2026-05-18 15:06:12.607 | INFO | [StopCondition_MapAchievement] non_stop 2026-05-18 15:06:12.611 | INFO | Fast_Forward set to on 2026-05-18 15:06:12.613 | INFO | [Fast_Forward] on 2026-05-18 15:06:12.626 | INFO | Auto_Search set to on 2026-05-18 15:06:12.632 | INFO | [Auto_Search] on 2026-05-18 15:06:12.636 | INFO | Click (1013, 520) @ MAP_PREPARATION 2026-05-18 15:06:12.873 | INFO | Handling 2x book setting, mode=prep. 2026-05-18 15:06:13.168 | INFO | Using fleet: [4, 3, 1] 2026-05-18 15:06:13.245 | INFO | Hard satisfied: Fleet_1: None, Fleet_2: None, Submarine: None 2026-05-18 15:06:13.253 | INFO | [map_allow_submarine] True 2026-05-18 15:06:13.259 | INFO | Click (1135, 330) @ FLEET_2_CLEAR 2026-05-18 15:06:13.314 | INFO | Click (1057, 473) @ SUBMARINE_CHOOSE 2026-05-18 15:06:13.465 | INFO | Current selected: [1] 2026-05-18 15:06:13.470 | INFO | Click (1055, 459) @ SUBMARINE_CHOOSE 2026-05-18 15:06:13.770 | INFO | Click (1068, 225) @ FLEET_1_CHOOSE 2026-05-18 15:06:14.062 | INFO | Current selected: [5] 2026-05-18 15:06:14.066 | INFO | Click (1093, 406) @ FLEET_1_BAR_INDEX_4 2026-05-18 15:06:14.361 | INFO | Click (1056, 355) @ FLEET_2_CHOOSE 2026-05-18 15:06:14.660 | INFO | Current selected: [4] 2026-05-18 15:06:14.664 | INFO | Click (1066, 489) @ FLEET_2_BAR_INDEX_3 2026-05-18 15:06:14.961 | INFO | Auto search setting 2026-05-18 15:06:14.979 | INFO | [_fleet_sidebar_offset] 0 2026-05-18 15:06:14.987 | INFO | [_fleet_sidebar_offset] 0 2026-05-18 15:06:14.992 | INFO | [Fleet_sidebar] 1/3 2026-05-18 15:06:14.997 | INFO | Click (1206, 434) @ FLEET_SIDEBAR_0_2 2026-05-18 15:06:15.262 | INFO | [_fleet_sidebar_offset] 0 2026-05-18 15:06:15.266 | INFO | [Fleet_sidebar] 3/3 2026-05-18 15:06:15.269 | INFO | [Auto_Search_Setting] fleet1_all_fleet2_standby, sub_standby 2026-05-18 15:06:15.275 | INFO | Click ( 504, 219) @ AUTO_SEARCH_SET_MOB 2026-05-18 15:06:15.747 | INFO | [Auto_Search_Setting] fleet1_mob_fleet2_boss, sub_standby 2026-05-18 15:06:15.754 | INFO | Selected to the correct auto search setting 2026-05-18 15:06:15.758 | INFO | [Auto_Search_Setting] fleet1_mob_fleet2_boss, sub_standby 2026-05-18 15:06:15.763 | INFO | Selected to the correct auto search setting 2026-05-18 15:06:15.767 | INFO | Click (1066, 576) @ FLEET_PREPARATION 2026-05-18 15:06:16.657 | INFO | is_auto_search_running appeared ────────────────────────────────────────────────────── BATTLE_0 ─────────────────────────────────────────────────────── 2026-05-18 15:06:16.664 | INFO | BATTLE_0 2026-05-18 15:06:16.667 | INFO | Auto search moving 2026-05-18 15:06:16.680 | INFO | Fleet: 1, fleet_current_index: 1 2026-05-18 15:06:16.759 | INFO | [OCR_OIL 0.075s] 23818 2026-05-18 15:06:16.850 | INFO | [OCR_COIN 0.087s] 397533 2026-05-18 15:06:21.092 | INFO | [Loading] 7% 2026-05-18 15:06:21.096 | INFO | Auto search combat loading 2026-05-18 15:06:21.098 | INFO | Screenshot interval set to 1.0s 2026-05-18 15:06:27.141 | INFO | [BattleUI] PAUSE 2026-05-18 15:06:27.146 | INFO | Auto Search combat execute 2026-05-18 15:06:27.150 | INFO | <<< EMOTION REDUCE >>> 2026-05-18 15:06:27.163 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-05-18 15:06:27.169 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=51, Main.Emotion.Fleet1Record=datetime.datetime(2026, 5, 18, 15, 6, 27), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 5, 18, 15, 6, 27) 2026-05-18 15:06:27.185 | INFO | [Emotion fleet_1] 51 2026-05-18 15:06:27.189 | INFO | [Emotion fleet_2] 119 2026-05-18 15:06:33.085 | INFO | Combat auto check timer reached 2026-05-18 15:07:06.131 | INFO | Screenshot interval set to 0.3s 2026-05-18 15:07:06.134 | INFO | Auto Search combat status 2026-05-18 15:07:07.051 | INFO | Click (1019, 663) @ GET_SHIP 2026-05-18 15:07:08.219 | INFO | Click (1026, 657) @ GET_SHIP 2026-05-18 15:07:09.420 | INFO | Click (1034, 667) @ GET_SHIP 2026-05-18 15:07:10.624 | INFO | Click (1043, 665) @ GET_SHIP 2026-05-18 15:07:12.715 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_1 ─────────────────────────────────────────────────────── 2026-05-18 15:07:12.722 | INFO | BATTLE_1 2026-05-18 15:07:12.724 | INFO | Auto search moving 2026-05-18 15:07:12.728 | INFO | Fleet: 1, fleet_current_index: 1 2026-05-18 15:07:12.829 | INFO | [OCR_OIL 0.094s] 23799 2026-05-18 15:07:12.930 | INFO | [OCR_COIN 0.089s] 397554 2026-05-18 15:07:17.838 | INFO | [Loading] 7% 2026-05-18 15:07:17.841 | INFO | Auto search combat loading 2026-05-18 15:07:17.843 | INFO | Screenshot interval set to 1.0s 2026-05-18 15:07:23.843 | INFO | [BattleUI] PAUSE 2026-05-18 15:07:23.847 | INFO | Auto Search combat execute 2026-05-18 15:07:23.851 | INFO | <<< EMOTION REDUCE >>> 2026-05-18 15:07:23.864 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-05-18 15:07:23.868 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=49, Main.Emotion.Fleet1Record=datetime.datetime(2026, 5, 18, 15, 7, 23), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 5, 18, 15, 7, 23) 2026-05-18 15:07:23.883 | INFO | [Emotion fleet_1] 49 2026-05-18 15:07:23.888 | INFO | [Emotion fleet_2] 119 2026-05-18 15:07:29.836 | INFO | Combat auto check timer reached 2026-05-18 15:08:06.879 | INFO | Click (1025, 657) @ GET_SHIP 2026-05-18 15:08:07.879 | INFO | Click (1022, 666) @ GET_SHIP 2026-05-18 15:08:08.901 | INFO | Click (1022, 660) @ GET_SHIP 2026-05-18 15:08:10.889 | INFO | Click (1037, 653) @ GET_SHIP 2026-05-18 15:08:12.899 | INFO | Screenshot interval set to 0.3s 2026-05-18 15:08:12.903 | INFO | Auto Search combat status 2026-05-18 15:08:12.907 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_2 ─────────────────────────────────────────────────────── 2026-05-18 15:08:12.911 | INFO | BATTLE_2 2026-05-18 15:08:12.914 | INFO | Auto search moving 2026-05-18 15:08:12.917 | INFO | Fleet: 1, fleet_current_index: 1 2026-05-18 15:08:12.991 | INFO | [OCR_OIL 0.069s] 23780 2026-05-18 15:08:13.086 | INFO | [OCR_COIN 0.090s] 397581 2026-05-18 15:08:17.390 | INFO | [Loading] 13% 2026-05-18 15:08:17.394 | INFO | Auto search combat loading 2026-05-18 15:08:17.397 | INFO | Screenshot interval set to 1.0s 2026-05-18 15:08:23.385 | INFO | [BattleUI] PAUSE 2026-05-18 15:08:23.389 | INFO | Auto Search combat execute 2026-05-18 15:08:23.391 | INFO | <<< EMOTION REDUCE >>> 2026-05-18 15:08:23.402 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-05-18 15:08:23.407 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=47, Main.Emotion.Fleet1Record=datetime.datetime(2026, 5, 18, 15, 8, 23), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 5, 18, 15, 8, 23) 2026-05-18 15:08:23.427 | INFO | [Emotion fleet_1] 47 2026-05-18 15:08:23.433 | INFO | [Emotion fleet_2] 119 2026-05-18 15:08:29.380 | INFO | Combat auto check timer reached 2026-05-18 15:08:48.425 | INFO | Click (1033, 664) @ GET_SHIP 2026-05-18 15:08:50.418 | INFO | Click (1010, 658) @ GET_SHIP 2026-05-18 15:08:52.423 | INFO | Click (1033, 659) @ GET_SHIP 2026-05-18 15:08:53.418 | INFO | Screenshot interval set to 0.3s 2026-05-18 15:08:53.422 | INFO | Auto Search combat status 2026-05-18 15:08:53.437 | INFO | Click (1031, 645) @ GET_SHIP 2026-05-18 15:08:54.900 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_3 ─────────────────────────────────────────────────────── 2026-05-18 15:08:54.905 | INFO | BATTLE_3 2026-05-18 15:08:54.908 | INFO | Auto search moving 2026-05-18 15:08:54.913 | INFO | Fleet: 1, fleet_current_index: 1 2026-05-18 15:08:55.014 | INFO | [OCR_OIL 0.097s] 23761 2026-05-18 15:08:55.109 | INFO | [OCR_COIN 0.090s] 397609 2026-05-18 15:08:59.416 | INFO | [Loading] 7% 2026-05-18 15:08:59.419 | INFO | Auto search combat loading 2026-05-18 15:08:59.421 | INFO | Screenshot interval set to 1.0s 2026-05-18 15:09:05.443 | INFO | [BattleUI] PAUSE 2026-05-18 15:09:05.448 | INFO | Auto Search combat execute 2026-05-18 15:09:05.451 | INFO | <<< EMOTION REDUCE >>> 2026-05-18 15:09:05.466 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-05-18 15:09:05.476 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=45, Main.Emotion.Fleet1Record=datetime.datetime(2026, 5, 18, 15, 9, 5), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 5, 18, 15, 9, 5) 2026-05-18 15:09:05.498 | INFO | [Emotion fleet_1] 45 2026-05-18 15:09:05.506 | INFO | [Emotion fleet_2] 119 2026-05-18 15:09:11.430 | INFO | Combat auto check timer reached 2026-05-18 15:09:45.457 | INFO | Click (1040, 656) @ GET_SHIP 2026-05-18 15:09:46.460 | INFO | Click (1032, 653) @ GET_SHIP 2026-05-18 15:09:47.465 | INFO | Click (1025, 652) @ GET_SHIP 2026-05-18 15:09:48.476 | INFO | Click (1038, 671) @ GET_SHIP 2026-05-18 15:09:49.466 | INFO | Screenshot interval set to 0.3s 2026-05-18 15:09:49.470 | INFO | Auto Search combat status 2026-05-18 15:09:49.482 | INFO | Click (1036, 665) @ GET_SHIP 2026-05-18 15:09:50.946 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_4 ─────────────────────────────────────────────────────── 2026-05-18 15:09:50.953 | INFO | BATTLE_4 2026-05-18 15:09:50.957 | INFO | Auto search moving 2026-05-18 15:09:50.963 | INFO | Fleet: 1, fleet_current_index: 1 2026-05-18 15:09:51.067 | INFO | [OCR_OIL 0.097s] 23742 2026-05-18 15:09:51.164 | INFO | [OCR_COIN 0.092s] 397632 2026-05-18 15:09:55.158 | INFO | [Loading] 15% 2026-05-18 15:09:55.161 | INFO | Auto search combat loading 2026-05-18 15:09:55.164 | INFO | Screenshot interval set to 1.0s 2026-05-18 15:10:00.173 | INFO | [BattleUI] PAUSE 2026-05-18 15:10:00.177 | INFO | Auto Search combat execute 2026-05-18 15:10:00.179 | INFO | <<< EMOTION REDUCE >>> 2026-05-18 15:10:00.193 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-05-18 15:10:00.198 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=43, Main.Emotion.Fleet1Record=datetime.datetime(2026, 5, 18, 15, 10), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 5, 18, 15, 10) 2026-05-18 15:10:00.217 | INFO | [Emotion fleet_1] 43 2026-05-18 15:10:00.221 | INFO | [Emotion fleet_2] 119 2026-05-18 15:10:06.167 | INFO | Combat auto check timer reached 2026-05-18 15:10:44.211 | INFO | Click (1030, 643) @ GET_SHIP 2026-05-18 15:10:46.209 | INFO | Click (1010, 661) @ GET_SHIP 2026-05-18 15:10:47.277 | INFO | Click (1030, 658) @ GET_SHIP 2026-05-18 15:10:49.222 | INFO | Click (1016, 645) @ GET_SHIP 2026-05-18 15:10:51.218 | INFO | Screenshot interval set to 0.3s 2026-05-18 15:10:51.221 | INFO | Auto Search combat status 2026-05-18 15:10:51.226 | INFO | Combat end. ────────────────────────────────────────────────────── BATTLE_5 ─────────────────────────────────────────────────────── 2026-05-18 15:10:51.230 | INFO | BATTLE_5 2026-05-18 15:10:51.236 | INFO | Auto search moving 2026-05-18 15:10:51.242 | INFO | Fleet: 1, fleet_current_index: 1 2026-05-18 15:10:51.338 | INFO | [OCR_OIL 0.091s] 23723 2026-05-18 15:10:51.419 | INFO | [OCR_COIN 0.075s] 397644 2026-05-18 15:10:53.021 | INFO | [Loading] 4% 2026-05-18 15:10:53.026 | INFO | Auto search combat loading 2026-05-18 15:10:53.030 | INFO | Screenshot interval set to 1.0s 2026-05-18 15:10:59.027 | INFO | [BattleUI] PAUSE 2026-05-18 15:10:59.031 | INFO | Auto Search combat execute 2026-05-18 15:10:59.034 | INFO | <<< EMOTION REDUCE >>> 2026-05-18 15:10:59.051 | INFO | Bind task ['General', 'Alas', 'Main'] 2026-05-18 15:10:59.057 | INFO | Save config ./config/alas-pc.json, Main.Emotion.Fleet1Value=41, Main.Emotion.Fleet1Record=datetime.datetime(2026, 5, 18, 15, 10, 59), Main.Emotion.Fleet2Value=119, Main.Emotion.Fleet2Record=datetime.datetime(2026, 5, 18, 15, 10, 59) 2026-05-18 15:10:59.076 | INFO | [Emotion fleet_1] 41 2026-05-18 15:10:59.080 | INFO | [Emotion fleet_2] 119 2026-05-18 15:11:05.015 | INFO | Combat auto check timer reached 2026-05-18 15:12:49.110 | INFO | Click (1015, 657) @ GET_SHIP 2026-05-18 15:15:49.272 | 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 L457 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 L570 loop() alas.py L70 run() alas.py L367 main() run.py L431 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-05-18 15:15:49.287 | WARNING | Wait too long 2026-05-18 15:15:49.292 | WARNING | Waiting for {'NEW_SHIP', 'CAMPAIGN_CHECK', 'POPUP_CANCEL', 'GUILD_POPUP_CONFIRM', 'EXP_INFO_S', 'SP_CHECK', 'MISSION_POPUP_GO', 'GET_SHIP', 'BATTLE_STATUS_S', 'POPUP_CONFIRM_WHITE', 'PAUSE', 'BATTLE_STATUS_A', 'AUTO_SEARCH_MAP_OPTION_ON', 'BATTLE_STATUS_B', 'EXP_INFO_B', 'GET_MISSION', 'EXP_INFO_A', 'EVENT_CHECK'} 2026-05-18 15:15:49.356 | INFO | [Package_name] com.bilibili.azurlane 2026-05-18 15:15:49.360 | ERROR | GameStuckError: Wait too long 2026-05-18 15:15:49.362 | WARNING | Saving error: ./log/error/1779088549362