═══════════════════════════════════════════════════════════════════════════════════════════════════════════════════════ EXERCISE ═══════════════════════════════════════════════════════════════════════════════════════════════════════════════════════ 2025-09-29 04:09:28.613 | INFO | <<< UI ENSURE >>> 2025-09-29 04:09:28.619 | INFO | UI get current page 2025-09-29 04:09:28.716 | INFO | [UI] page_reshmenu 2025-09-29 04:09:28.723 | INFO | Goto page_exercise 2025-09-29 04:09:28.728 | INFO | <<< UI GOTO PAGE_EXERCISE >>> 2025-09-29 04:09:28.795 | INFO | Page switch: page_reshmenu -> page_main 2025-09-29 04:09:28.801 | INFO | Click (1236, 37) @ GOTO_MAIN 2025-09-29 04:09:30.226 | INFO | Page switch: page_main_white -> page_campaign_menu 2025-09-29 04:09:30.240 | INFO | Click (1176, 550) @ MAIN_GOTO_CAMPAIGN_WHITE 2025-09-29 04:09:30.796 | INFO | Page switch: page_campaign_menu -> page_exercise 2025-09-29 04:09:30.803 | INFO | Click (1044, 614) @ CAMPAIGN_MENU_GOTO_EXERCISE 2025-09-29 04:09:31.106 | INFO | Page arrive: page_exercise 2025-09-29 04:09:31.122 | INFO | Bind task ['General', 'Alas', 'Exercise'] 2025-09-29 04:09:31.128 | INFO | Save config ./config/alas-pc.json, Exercise.Exercise.OpponentRefreshValue=0, Exercise.Exercise.OpponentRefreshRecord=datetime.datetime(2025, 9, 29, 4, 9, 31) 2025-09-29 04:09:31.146 | INFO | [Change_opponent_count] 0 2025-09-29 04:09:31.155 | INFO | [Exercise_ExerciseStrategy] fri18 2025-09-29 04:09:31.207 | INFO | Loading OCR model: ./bin/cnocr_models/cnocr 2025-09-29 04:09:31.530 | INFO | [OCR_PERIOD_REMAIN 0.367s] 6天19:50:39 2025-09-29 04:09:31.538 | INFO | Exercise period remain: 6 days, 19:50:39 2025-09-29 04:09:31.546 | INFO | Preserve 5 exercise 2025-09-29 04:09:31.683 | INFO | [OCR_EXERCISE_REMAIN 0.130s] 10 ═════════════════════════════════════════════════ EXERCISE REMAIN 10 ══════════════════════════════════════════════════ 2025-09-29 04:09:31.694 | INFO | EXERCISE REMAIN 10 ───────────────────────────────────────────────────── OPPONENT 0 ────────────────────────────────────────────────────── 2025-09-29 04:09:31.701 | INFO | OPPONENT 0 2025-09-29 04:09:31.710 | INFO | <<< OPPONENT: 0 >>> 2025-09-29 04:09:31.727 | INFO | Click ( 159, 262) @ OPPONENT_0_0 2025-09-29 04:09:32.797 | INFO | Click ( 624, 584) @ EXERCISE_PREPARATION 2025-09-29 04:09:33.399 | INFO | <<< TRY: 1 >>> 2025-09-29 04:09:33.410 | INFO | Combat preparation 2025-09-29 04:09:33.417 | INFO | Click (1196, 642) @ BATTLE_PREPARATION 2025-09-29 04:09:38.805 | INFO | [BattleUI] PAUSE 2025-09-29 04:09:38.811 | INFO | Combat execute 2025-09-29 04:09:39.240 | INFO | [99% - 99%] 2025-09-29 04:09:44.242 | INFO | [98% - 64%] 2025-09-29 04:09:49.378 | INFO | [98% - 43%] 2025-09-29 04:09:54.480 | INFO | [00% - 00%] 2025-09-29 04:09:59.538 | INFO | Click (1039, 645) @ BATTLE_STATUS_S 2025-09-29 04:10:00.157 | INFO | Click (1018, 659) @ GET_ITEMS_1 2025-09-29 04:10:02.540 | INFO | Click (1221, 665) @ CLICK_SAFE_AREA 2025-09-29 04:10:03.750 | INFO | Click (1233, 649) @ CLICK_SAFE_AREA 2025-09-29 04:10:04.943 | INFO | Click (1248, 656) @ CLICK_SAFE_AREA 2025-09-29 04:10:05.238 | INFO | <<< COMBAT END >>> 2025-09-29 04:10:05.290 | INFO | [OCR_EXERCISE_REMAIN 0.045s] 9 ══════════════════════════════════════════════════ EXERCISE REMAIN 9 ══════════════════════════════════════════════════ 2025-09-29 04:10:05.298 | INFO | EXERCISE REMAIN 9 ───────────────────────────────────────────────────── OPPONENT 0 ────────────────────────────────────────────────────── 2025-09-29 04:10:05.303 | INFO | OPPONENT 0 2025-09-29 04:10:05.309 | INFO | <<< OPPONENT: 0 >>> 2025-09-29 04:10:05.318 | INFO | Click ( 243, 186) @ OPPONENT_0_0 2025-09-29 04:10:06.147 | INFO | Click ( 644, 560) @ EXERCISE_PREPARATION 2025-09-29 04:10:06.740 | INFO | <<< TRY: 1 >>> 2025-09-29 04:10:06.752 | INFO | Combat preparation 2025-09-29 04:10:06.761 | INFO | Click (1158, 642) @ BATTLE_PREPARATION 2025-09-29 04:10:12.467 | INFO | [BattleUI] PAUSE 2025-09-29 04:10:12.473 | INFO | Combat execute 2025-09-29 04:10:12.811 | INFO | [99% - 99%] 2025-09-29 04:10:17.879 | INFO | [84% - 71%] 2025-09-29 04:10:22.978 | INFO | [78% - 58%] 2025-09-29 04:10:27.672 | ERROR | ConnectionError: HTTPConnectionPool(host='127.0.0.1', port=20416): Max retries exceeded with url: /screenshot (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 2025-09-29 04:10:27.689 | INFO | <<< DROIDCAST INIT >>> 2025-09-29 04:10:27.701 | INFO | Stopping DroidCast 2025-09-29 04:10:27.714 | WARNING | atx-agent has something wrong, auto recovering 2025-09-29 04:10:27.724 | INFO | device reconnecting, time left(3.0s) 2025-09-29 04:10:28.759 | INFO | device reconnecting, time left(2.0s) 2025-09-29 04:10:29.765 | INFO | adb reconnect error: adb read timeout 2025-09-29 04:10:30.775 | ERROR | RuntimeError: USB device 192.168.240.112:5555 is offline 2025-09-29 04:10:31.829 | INFO | failed to connect to '192.168.240.112:5555': No route to host 2025-09-29 04:10:33.886 | INFO | connected to 192.168.240.112:5555 2025-09-29 04:10:33.891 | INFO | <<< DETECT DEVICE >>> 2025-09-29 04:10:33.894 | INFO | Here are the available devices, copy to Alas.Emulator.Serial to use it or set Alas.Emulator.Serial="auto" 2025-09-29 04:10:33.898 | INFO | 192.168.240.112:5555 2025-09-29 04:10:33.940 | WARNING | atx-agent has something wrong, auto recovering 2025-09-29 04:10:33.950 | INFO | device 192.168.240.112:5555 is online 2025-09-29 04:10:34.133 | INFO | uiautomator2 version: 2.16.7 2025-09-29 04:10:34.198 | INFO | Real version: [0, 10, 0], Expect version: [0, 10, 0] 2025-09-29 04:10:34.257 | INFO | apk-debug package-info: {'package_name': 'com.github.uiautomator', 'version_name': '2.3.3', 'version_code': '2003003', 'flags': ['DEBUGGABLE', 'HAS_CODE', 'ALLOW_CLEAR_USER_DATA', 'ALLOW_BACKUP'], 'first_install_time': datetime.datetime(2024, 4, 4, 22, 58, 41), 'last_update_time': datetime.datetime(2024, 4, 4, 22, 58, 41), 'signature': 'ae17cd86], past signatures:['} 2025-09-29 04:10:34.266 | INFO | apk-debug-test package-info: {'package_name': 'com.github.uiautomator.test', 'version_name': '', 'version_code': '', 'flags': ['DEBUGGABLE', 'HAS_CODE', 'ALLOW_CLEAR_USER_DATA', 'ALLOW_BACKUP'], 'first_install_time': datetime.datetime(2024, 4, 4, 22, 58, 42), 'last_update_time': datetime.datetime(2024, 4, 4, 22, 58, 42), 'signature': 'ae17cd86], past signatures:['} 2025-09-29 04:10:34.276 | INFO | Shell: ('/data/local/tmp/atx-agent', 'server', '--nouia', '-d', '--addr', '127.0.0.1:7912') 2025-09-29 04:10:34.351 | INFO | Forward: local:tcp:59629 -> remote:tcp:7912 2025-09-29 04:10:34.868 | INFO | Forward: local:tcp:59629 -> remote:tcp:7912 2025-09-29 04:10:34.877 | INFO | atx-agent version 0.10.0 2025-09-29 04:10:34.886 | INFO | device wlan ip: route ip+net: no such network interface 2025-09-29 04:10:34.909 | INFO | Pushing DroidCast apk 2025-09-29 04:10:34.913 | INFO | Execute: ['/usr/bin/adb', '-s', '192.168.240.112:5555', 'push', './bin/DroidCast/DroidCast_raw-release-1.0.apk', '/data/local/tmp/DroidCast_raw.apk'] 2025-09-29 04:10:34.978 | INFO | Starting DroidCast apk 2025-09-29 04:10:34.996 | INFO | ShellBackgroundResponse(success=True, pid=870, description='Successfully started program: CLASSPATH=/data/local/tmp/DroidCast_raw.apk app_process / ink.mol.droidcast_raw.Main > /dev/null') 2025-09-29 04:10:35.013 | INFO | Create forward: ForwardItem(serial='192.168.240.112:5555', local='tcp:20913', remote='tcp:53516') 2025-09-29 04:10:35.024 | INFO | [DroidCast_raw] http://127.0.0.1:20913/screenshot 2025-09-29 04:10:35.312 | INFO | [DroidCast] online 2025-09-29 04:10:35.507 | INFO | [00% - 00%] 2025-09-29 04:10:40.698 | INFO | [00% - 00%] 2025-09-29 04:10:45.795 | INFO | [00% - 00%] 2025-09-29 04:10:50.899 | INFO | [00% - 00%] 2025-09-29 04:10:56.022 | INFO | [00% - 00%] 2025-09-29 04:10:58.943 | ERROR | ConnectionError: HTTPConnectionPool(host='127.0.0.1', port=20913): Max retries exceeded with url: /screenshot (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 2025-09-29 04:10:58.950 | INFO | <<< DROIDCAST INIT >>> 2025-09-29 04:10:58.955 | INFO | Stopping DroidCast 2025-09-29 04:10:58.980 | INFO | Kill pid=870 2025-09-29 04:10:58.991 | INFO | Kill pid=871 2025-09-29 04:10:59.003 | INFO | Pushing DroidCast apk 2025-09-29 04:10:59.009 | INFO | Execute: ['/usr/bin/adb', '-s', '192.168.240.112:5555', 'push', './bin/DroidCast/DroidCast_raw-release-1.0.apk', '/data/local/tmp/DroidCast_raw.apk'] 2025-09-29 04:10:59.076 | INFO | Starting DroidCast apk 2025-09-29 04:10:59.091 | INFO | ShellBackgroundResponse(success=True, pid=1950, description='Successfully started program: CLASSPATH=/data/local/tmp/DroidCast_raw.apk app_process / ink.mol.droidcast_raw.Main > /dev/null') 2025-09-29 04:10:59.110 | INFO | Create forward: ForwardItem(serial='192.168.240.112:5555', local='tcp:20240', remote='tcp:53516') 2025-09-29 04:10:59.121 | INFO | [DroidCast_raw] http://127.0.0.1:20240/screenshot 2025-09-29 04:10:59.446 | INFO | [DroidCast] online 2025-09-29 04:11:01.325 | INFO | [00% - 00%] 2025-09-29 04:11:06.435 | INFO | [00% - 00%] 2025-09-29 04:11:11.556 | INFO | [00% - 00%] 2025-09-29 04:11:16.651 | INFO | [00% - 00%] 2025-09-29 04:11:21.733 | INFO | [00% - 00%] 2025-09-29 04:11:26.837 | INFO | [00% - 00%] 2025-09-29 04:11:31.974 | INFO | [00% - 00%] 2025-09-29 04:11:37.049 | INFO | [00% - 00%] 2025-09-29 04:11:42.149 | INFO | [00% - 00%] 2025-09-29 04:11:47.259 | INFO | [00% - 00%] 2025-09-29 04:11:52.360 | INFO | [00% - 00%] 2025-09-29 04:11:57.478 | INFO | [00% - 00%] 2025-09-29 04:12:02.606 | INFO | [00% - 00%] 2025-09-29 04:12:07.688 | INFO | [00% - 00%] 2025-09-29 04:12:12.782 | INFO | [00% - 00%] 2025-09-29 04:12:17.886 | INFO | [00% - 00%] 2025-09-29 04:12:22.990 | INFO | [00% - 00%] 2025-09-29 04:12:28.092 | INFO | [00% - 00%] 2025-09-29 04:12:33.203 | INFO | [00% - 00%] 2025-09-29 04:12:38.306 | INFO | [00% - 00%] 2025-09-29 04:12:43.410 | INFO | [00% - 00%] 2025-09-29 04:12:48.540 | INFO | [00% - 00%] 2025-09-29 04:12:53.660 | INFO | [00% - 00%] 2025-09-29 04:12:58.730 | INFO | [00% - 00%] 2025-09-29 04:13:03.828 | INFO | [00% - 00%] 2025-09-29 04:13:08.935 | INFO | [00% - 00%] 2025-09-29 04:13:12.568 | 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 L435 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 L269 exercise() exercise.py L237 run() exercise.py L115 _exercise_once() combat.py L182 _combat() combat.py L54 _combat_execute() device.py L188 screenshot() device.py L250 stuck_record_check() device.py L43 show_function_call() 2025-09-29 04:13:12.585 | WARNING | Wait too long 2025-09-29 04:13:12.594 | WARNING | Waiting for {'EXP_INFO_D', 'GET_MISSION', 'QUIT_RECONFIRM', 'PAUSE', 'POPUP_CANCEL', 'POPUP_CONFIRM_WHITE', 'BATTLE_STATUS_D', 'BATTLE_STATUS_S', 'OPTS_INFO_D', 'GUILD_POPUP_CONFIRM', 'BATTLE_PREPARATION', 'EXERCISE_CHECK', 'EXP_INFO_S', 'MISSION_POPUP_GO'} 2025-09-29 04:13:12.732 | INFO | [Package_name] com.bilibili.azurlane 2025-09-29 04:13:12.739 | ERROR | GameStuckError: Wait too long 2025-09-29 04:13:12.742 | WARNING | Saving error: ./log/error/1759090392742