═══════════════════════════════════════════════════════════════════════════════════════════════════════════════════════ EXERCISE ═══════════════════════════════════════════════════════════════════════════════════════════════════════════════════════ 2025-09-29 04:15:30.609 | INFO | <<< UI ENSURE >>> 2025-09-29 04:15:30.615 | INFO | UI get current page 2025-09-29 04:15:30.799 | INFO | Unknown ui page 2025-09-29 04:15:30.995 | INFO | [Package_name] com.bilibili.azurlane 2025-09-29 04:15:31.038 | INFO | [Device Orientation] 0 (Normal) 2025-09-29 04:15:31.225 | INFO | Unknown ui page 2025-09-29 04:15:31.252 | INFO | Click (1183, 96) @ LOGIN_ANNOUNCE_2 2025-09-29 04:15:31.546 | INFO | Unknown ui page 2025-09-29 04:15:31.902 | INFO | Unknown ui page 2025-09-29 04:15:32.211 | INFO | Unknown ui page 2025-09-29 04:15:32.538 | INFO | Unknown ui page 2025-09-29 04:15:32.826 | INFO | Unknown ui page 2025-09-29 04:15:33.172 | INFO | Unknown ui page 2025-09-29 04:15:33.551 | INFO | Unknown ui page 2025-09-29 04:15:33.881 | INFO | Unknown ui page 2025-09-29 04:15:34.108 | INFO | [UI] page_main 2025-09-29 04:15:34.113 | INFO | Goto page_exercise 2025-09-29 04:15:34.118 | INFO | <<< UI GOTO PAGE_EXERCISE >>> 2025-09-29 04:15:34.145 | INFO | Page switch: page_main_white -> page_campaign_menu 2025-09-29 04:15:34.152 | INFO | Click (1165, 506) @ MAIN_GOTO_CAMPAIGN_WHITE 2025-09-29 04:15:39.422 | INFO | Page switch: page_main_white -> page_campaign_menu 2025-09-29 04:15:39.430 | INFO | Click (1213, 499) @ MAIN_GOTO_CAMPAIGN_WHITE 2025-09-29 04:15:39.434 | ERROR | BrokenPipeError: [Errno 32] Broken pipe 2025-09-29 04:15:39.440 | INFO | <<< MINITOUCH INIT >>> 2025-09-29 04:15:39.486 | INFO | [Device Orientation] 0 (Normal) 2025-09-29 04:15:39.498 | INFO | Reuse forward: ForwardItem(serial='192.168.240.112:5555', local='tcp:20629', remote='localabstract:minitouch') 2025-09-29 04:15:40.512 | ERROR | MinitouchOccupiedError: Timeout when connecting to minitouch, probably because another connection has been established 2025-09-29 04:15:40.517 | INFO | Restart ATX 2025-09-29 04:15:41.123 | INFO | <<< MINITOUCH INIT >>> 2025-09-29 04:15:41.179 | INFO | [Device Orientation] 0 (Normal) 2025-09-29 04:15:41.188 | INFO | Create forward: ForwardItem(serial='192.168.240.112:5555', local='tcp:20360', remote='localabstract:minitouch') 2025-09-29 04:15:41.202 | INFO | 2025-09-29 04:15:41.213 | INFO | 2025-09-29 04:15:42.224 | INFO | v 1 2025-09-29 04:15:42.229 | INFO | ^ 10 1280 720 0 2025-09-29 04:15:42.235 | INFO | $ 2703 2025-09-29 04:15:42.238 | INFO | minitouch running on port: 20360, pid: 2703 2025-09-29 04:15:42.241 | INFO | max_contact: 10; max_x: 1280; max_y: 720; max_pressure: 0 2025-09-29 04:15:42.422 | INFO | Page switch: page_research -> page_main 2025-09-29 04:15:42.428 | INFO | Click (1241, 33) @ GOTO_MAIN 2025-09-29 04:15:44.548 | INFO | Page switch: page_main_white -> page_campaign_menu 2025-09-29 04:15:44.557 | INFO | Click (1206, 506) @ MAIN_GOTO_CAMPAIGN_WHITE 2025-09-29 04:15:45.113 | INFO | Page switch: page_campaign_menu -> page_exercise 2025-09-29 04:15:45.122 | INFO | Click (1056, 612) @ CAMPAIGN_MENU_GOTO_EXERCISE 2025-09-29 04:15:45.413 | INFO | Page arrive: page_exercise 2025-09-29 04:15:45.422 | INFO | [Change_opponent_count] 0 2025-09-29 04:15:45.430 | INFO | [Exercise_ExerciseStrategy] fri18 2025-09-29 04:15:45.491 | INFO | Loading OCR model: ./bin/cnocr_models/cnocr 2025-09-29 04:15:45.829 | INFO | [OCR_PERIOD_REMAIN 0.390s] 6天19:44:20 2025-09-29 04:15:45.837 | INFO | Exercise period remain: 6 days, 19:44:20 2025-09-29 04:15:45.847 | INFO | Preserve 5 exercise 2025-09-29 04:15:45.923 | INFO | [OCR_EXERCISE_REMAIN 0.069s] 9 ══════════════════════════════════════════════════ EXERCISE REMAIN 9 ══════════════════════════════════════════════════ 2025-09-29 04:15:45.937 | INFO | EXERCISE REMAIN 9 ───────────────────────────────────────────────────── OPPONENT 0 ────────────────────────────────────────────────────── 2025-09-29 04:15:45.944 | INFO | OPPONENT 0 2025-09-29 04:15:45.950 | INFO | <<< OPPONENT: 0 >>> 2025-09-29 04:15:45.959 | INFO | Click ( 182, 246) @ OPPONENT_0_0 2025-09-29 04:15:46.745 | INFO | Click ( 658, 570) @ EXERCISE_PREPARATION 2025-09-29 04:15:47.944 | INFO | <<< TRY: 1 >>> 2025-09-29 04:15:47.954 | INFO | Combat preparation 2025-09-29 04:15:47.969 | INFO | Click (1182, 624) @ BATTLE_PREPARATION 2025-09-29 04:15:53.640 | INFO | [BattleUI] PAUSE 2025-09-29 04:15:53.646 | INFO | Combat execute 2025-09-29 04:15:54.080 | INFO | [99% - 99%] 2025-09-29 04:15:59.085 | INFO | [86% - 89%] 2025-09-29 04:16:04.175 | INFO | [82% - 72%] 2025-09-29 04:16:09.315 | INFO | [00% - 00%] 2025-09-29 04:16:14.424 | INFO | [68% - 01%] 2025-09-29 04:16:19.496 | INFO | [64% - 00%] 2025-09-29 04:16:20.408 | INFO | Click (1034, 653) @ BATTLE_STATUS_S 2025-09-29 04:16:21.596 | INFO | Click (1043, 680) @ BATTLE_STATUS_S 2025-09-29 04:16:22.176 | INFO | Click (1007, 649) @ GET_ITEMS_1 2025-09-29 04:16:24.611 | INFO | Click (1249, 655) @ CLICK_SAFE_AREA 2025-09-29 04:16:25.800 | INFO | Click (1241, 650) @ CLICK_SAFE_AREA 2025-09-29 04:16:26.989 | INFO | Click (1241, 651) @ CLICK_SAFE_AREA 2025-09-29 04:16:27.296 | INFO | <<< COMBAT END >>> 2025-09-29 04:16:27.363 | INFO | [OCR_EXERCISE_REMAIN 0.059s] 8 ══════════════════════════════════════════════════ EXERCISE REMAIN 8 ══════════════════════════════════════════════════ 2025-09-29 04:16:27.373 | INFO | EXERCISE REMAIN 8 ───────────────────────────────────────────────────── OPPONENT 0 ────────────────────────────────────────────────────── 2025-09-29 04:16:27.383 | INFO | OPPONENT 0 2025-09-29 04:16:27.388 | INFO | <<< OPPONENT: 0 >>> 2025-09-29 04:16:27.398 | INFO | Click ( 241, 184) @ OPPONENT_0_0 2025-09-29 04:16:28.187 | INFO | Click ( 666, 561) @ EXERCISE_PREPARATION 2025-09-29 04:16:28.800 | INFO | <<< TRY: 1 >>> 2025-09-29 04:16:28.811 | INFO | Combat preparation 2025-09-29 04:16:28.830 | INFO | Click (1142, 653) @ BATTLE_PREPARATION 2025-09-29 04:16:33.605 | INFO | [BattleUI] PAUSE 2025-09-29 04:16:33.611 | INFO | Combat execute 2025-09-29 04:16:33.935 | INFO | [99% - 99%] 2025-09-29 04:16:39.023 | INFO | [99% - 00%] 2025-09-29 04:16:41.423 | INFO | Click (1013, 655) @ BATTLE_STATUS_S 2025-09-29 04:16:42.593 | INFO | Click (1023, 668) @ BATTLE_STATUS_S 2025-09-29 04:16:43.181 | INFO | Click (1028, 668) @ GET_ITEMS_1 2025-09-29 04:16:45.292 | INFO | Click (1230, 671) @ CLICK_SAFE_AREA 2025-09-29 04:16:45.590 | INFO | Click (1024, 650) @ BATTLE_STATUS_D 2025-09-29 04:16:45.646 | INFO | Exercise LOST 2025-09-29 04:16:46.497 | INFO | Click (1239, 650) @ CLICK_SAFE_AREA 2025-09-29 04:16:46.779 | INFO | Click (1032, 654) @ BATTLE_STATUS_D 2025-09-29 04:16:46.837 | INFO | Exercise LOST 2025-09-29 04:16:47.684 | INFO | <<< COMBAT END >>> 2025-09-29 04:16:47.770 | INFO | [OCR_EXERCISE_REMAIN 0.080s] 7 ══════════════════════════════════════════════════ EXERCISE REMAIN 7 ══════════════════════════════════════════════════ 2025-09-29 04:16:47.787 | INFO | EXERCISE REMAIN 7 ───────────────────────────────────────────────────── OPPONENT 0 ────────────────────────────────────────────────────── 2025-09-29 04:16:47.793 | INFO | OPPONENT 0 2025-09-29 04:16:47.801 | INFO | <<< OPPONENT: 0 >>> 2025-09-29 04:16:47.811 | INFO | Click ( 231, 238) @ OPPONENT_0_0 2025-09-29 04:16:48.582 | INFO | Click ( 624, 545) @ EXERCISE_PREPARATION 2025-09-29 04:16:49.186 | INFO | <<< TRY: 1 >>> 2025-09-29 04:16:49.193 | INFO | Combat preparation 2025-09-29 04:16:49.201 | INFO | Click (1125, 627) @ BATTLE_PREPARATION 2025-09-29 04:16:53.322 | ERROR | ConnectionError: HTTPConnectionPool(host='127.0.0.1', port=20353): Max retries exceeded with url: /screenshot (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 2025-09-29 04:16:53.333 | INFO | <<< DROIDCAST INIT >>> 2025-09-29 04:16:53.338 | INFO | Stopping DroidCast 2025-09-29 04:16:53.348 | WARNING | atx-agent has something wrong, auto recovering 2025-09-29 04:16:53.360 | INFO | device reconnecting, time left(3.0s) 2025-09-29 04:16:54.384 | INFO | device reconnecting, time left(2.0s) 2025-09-29 04:16:55.411 | INFO | adb reconnect error: adb read timeout 2025-09-29 04:16:56.422 | ERROR | RuntimeError: USB device 192.168.240.112:5555 is offline 2025-09-29 04:16:57.301 | INFO | failed to connect to '192.168.240.112:5555': No route to host 2025-09-29 04:16:59.350 | INFO | connected to 192.168.240.112:5555 2025-09-29 04:16:59.355 | INFO | <<< DETECT DEVICE >>> 2025-09-29 04:16:59.365 | INFO | Here are the available devices, copy to Alas.Emulator.Serial to use it or set Alas.Emulator.Serial="auto" 2025-09-29 04:16:59.386 | INFO | 192.168.240.112:5555 2025-09-29 04:16:59.410 | WARNING | atx-agent has something wrong, auto recovering 2025-09-29 04:16:59.424 | INFO | device 192.168.240.112:5555 is online 2025-09-29 04:16:59.636 | INFO | uiautomator2 version: 2.16.7 2025-09-29 04:16:59.692 | INFO | Real version: [0, 10, 0], Expect version: [0, 10, 0] 2025-09-29 04:16:59.744 | 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:16:59.755 | 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:16:59.763 | INFO | Shell: ('/data/local/tmp/atx-agent', 'server', '--nouia', '-d', '--addr', '127.0.0.1:7912') 2025-09-29 04:16:59.833 | INFO | Forward: local:tcp:58097 -> remote:tcp:7912 2025-09-29 04:17:00.348 | INFO | Forward: local:tcp:58097 -> remote:tcp:7912 2025-09-29 04:17:00.357 | INFO | atx-agent version 0.10.0 2025-09-29 04:17:00.368 | INFO | device wlan ip: route ip+net: no such network interface 2025-09-29 04:17:00.389 | INFO | Pushing DroidCast apk 2025-09-29 04:17:00.397 | 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:17:00.460 | INFO | Starting DroidCast apk 2025-09-29 04:17:00.473 | INFO | ShellBackgroundResponse(success=True, pid=869, description='Successfully started program: CLASSPATH=/data/local/tmp/DroidCast_raw.apk app_process / ink.mol.droidcast_raw.Main > /dev/null') 2025-09-29 04:17:00.485 | INFO | Create forward: ForwardItem(serial='192.168.240.112:5555', local='tcp:20850', remote='tcp:53516') 2025-09-29 04:17:00.493 | INFO | [DroidCast_raw] http://127.0.0.1:20850/screenshot 2025-09-29 04:17:00.780 | INFO | [DroidCast] online 2025-09-29 04:17:24.686 | ERROR | ConnectionError: HTTPConnectionPool(host='127.0.0.1', port=20850): Max retries exceeded with url: /screenshot (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 2025-09-29 04:17:24.705 | INFO | <<< DROIDCAST INIT >>> 2025-09-29 04:17:24.712 | INFO | Stopping DroidCast 2025-09-29 04:17:24.739 | INFO | Kill pid=869 2025-09-29 04:17:24.758 | INFO | Kill pid=870 2025-09-29 04:17:24.783 | INFO | Pushing DroidCast apk 2025-09-29 04:17:24.810 | 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:17:24.913 | INFO | Starting DroidCast apk 2025-09-29 04:17:24.940 | INFO | ShellBackgroundResponse(success=True, pid=1925, description='Successfully started program: CLASSPATH=/data/local/tmp/DroidCast_raw.apk app_process / ink.mol.droidcast_raw.Main > /dev/null') 2025-09-29 04:17:24.960 | INFO | Create forward: ForwardItem(serial='192.168.240.112:5555', local='tcp:20661', remote='tcp:53516') 2025-09-29 04:17:24.978 | INFO | [DroidCast_raw] http://127.0.0.1:20661/screenshot 2025-09-29 04:17:25.337 | INFO | [DroidCast] online 2025-09-29 04:19:49.265 | 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 L181 _combat() combat.py L22 _combat_preparation() device.py L188 screenshot() device.py L250 stuck_record_check() device.py L43 show_function_call() 2025-09-29 04:19:49.280 | WARNING | Wait too long 2025-09-29 04:19:49.284 | WARNING | Waiting for {'BATTLE_PREPARATION', 'PAUSE'} 2025-09-29 04:19:49.355 | INFO | [Package_name] com.bilibili.azurlane 2025-09-29 04:19:49.359 | ERROR | GameStuckError: Wait too long 2025-09-29 04:19:49.365 | WARNING | Saving error: ./log/error/1759090789365