0 | 0:00 | DEBUG | Using configuration file PurpleWaveAIIDE.config.json
0 | 0:00 | DEBUG | Human: false
0 | 0:00 | DEBUG | Ladder: false
0 | 0:00 | DEBUG | Livestream: false
0 | 0:00 | DEBUG | Tournament: true
0 | 0:00 | DEBUG | Round-robin: true
0 | 0:00 | DEBUG | Elimination: false
0 | 0:00 | DEBUG | Pretraining: false
0 | 0:00 | DEBUG | Debugging: false
0 | 0:00 | DEBUG | DebuggingLive: false
0 | 0:00 | DEBUG | Log stdout: false
0 | 0:00 | DEBUG | Frame MS target: 35
0 | 0:00 | DEBUG | Frame MS limit: 35
0 | 0:00 | DEBUG | Fixed build: 
0 | 0:00 | DEBUG | Melee on MatchPoint 1.3 at (2)MatchPoint1.3.scx as PurpleWave (Us, always Protoss) with AutoPilot (Enemy, always Protoss)
0 | 0:00 | DEBUG | Detecting ownership of base from visible town hall: Our Nexus #14 750/750 (750/750) [3264, 496](102, 15)
0 | 0:00 | DEBUG | Found C:\TM\Starcraft\bwapi-data\AI\accelerants-matchpoint.json
0 | 0:00 | DEBUG | 74 minerals have cached steady zippers
0 | 0:00 | DEBUG | Inferred enemy main from process of elimination.
0 | 0:00 | WARN  | Didn't find opponent plan for AutoPilot
0 | 0:00 | DEBUG | Choosing tournament policy StrategySelectionGreedy
0 | 0:00 | DEBUG | Activating strategy PvPGateCore
0 | 0:00 | DEBUG | Activating strategy PvPDT
0 | 0:00 | DEBUG | Game start time:  Tue Oct 22 18:14:22 PDT 2024
0 | 0:00 | DEBUG | OS:               Windows 10 10.0 amd64
0 | 0:00 | DEBUG | JRE:              Oracle Corporation - 1.8.0_421
0 | 0:00 | DEBUG | CPUs available:   12
0 | 0:00 | DEBUG | System memory:    8263 MB
0 | 0:00 | DEBUG | JVM arguments     -XX:MaxGCPauseMillis=15 -Xms2048m -Xmx2048m
0 | 0:00 | DEBUG | Live debugging:   false
0 | 0:00 | DEBUG | Buffer capacity:  42
0 | 0:00 | DEBUG | Using buffer?     true
0 | 0:00 | DEBUG | JVM Max memory:   2058 MB
0 | 0:00 | DEBUG | JVM Total memory: 2058 MB
0 | 0:00 | DEBUG | JVM Free memory:  1887 MB
0 | 0:00 | DEBUG | JVM Used memory:  170 MB
0 | 0:00 | DEBUG | JVM Used, heap:   170 MB
0 | 0:00 | DEBUG | Heap memory PS Survivor Space, current:          0 MB used | 89 MB init | 89 MB max | 89 MB committed
0 | 0:00 | DEBUG | Heap memory PS Survivor Space, peak:             89 MB used | 89 MB init | 89 MB max | 89 MB committed
0 | 0:00 | DEBUG | Non-heap memory Compressed Class Space, current: 5 MB used | 0 MB init | 1073 MB max | 6 MB committed
0 | 0:00 | DEBUG | Non-heap memory Compressed Class Space, peak:    5 MB used | 0 MB init | 1073 MB max | 6 MB committed
0 | 0:00 | DEBUG | Non-heap memory Code Cache, current:             14 MB used | 2 MB init | 251 MB max | 14 MB committed
0 | 0:00 | DEBUG | Non-heap memory Code Cache, peak:                14 MB used | 2 MB init | 251 MB max | 14 MB committed
0 | 0:00 | DEBUG | Heap memory PS Eden Space, current:              33 MB used | 537 MB init | 539 MB max | 537 MB committed
0 | 0:00 | DEBUG | Heap memory PS Eden Space, peak:                 537 MB used | 537 MB init | 539 MB max | 537 MB committed
0 | 0:00 | DEBUG | Non-heap memory Metaspace, current:              37 MB used | 0 MB init | 0 MB max | 44 MB committed
0 | 0:00 | DEBUG | Non-heap memory Metaspace, peak:                 37 MB used | 0 MB init | 0 MB max | 44 MB committed
0 | 0:00 | DEBUG | Heap memory PS Old Gen, current:                 136 MB used | 1431 MB init | 1431 MB max | 1431 MB committed
0 | 0:00 | DEBUG | Heap memory PS Old Gen, peak:                    136 MB used | 1431 MB init | 1431 MB max | 1431 MB committed
0 | 0:00 | DEBUG | This copy of PurpleWave was packaged for distribution on Sun, Oct 13, 2024  5:25:22 AM
0 | 0:00 | DEBUG | This copy of PurpleWave came from Git revision 9ccf8df0341bf2eff257cd04a145bdf58dde1b5b
0 | 0:00 | DEBUG | JBWAPI autocontinue: false
0 | 0:00 | DEBUG | JBWAPI debugConnection: true
0 | 0:00 | DEBUG | JBWAPI async: true
0 | 0:00 | DEBUG | JBWAPI async unsafe: true
0 | 0:00 | DEBUG | JBWAPI async frame buffer size: 10
0 | 0:00 | DEBUG | JBWAPI unlimited frame zero: true
0 | 0:00 | DEBUG | JBWAPI max frame duration: 30ms
0 | 0:00 | DEBUG | Map file: (2)MatchPoint1.3.scx
0 | 0:00 | DEBUG | Map hash: 0a41f144c6134a2204f3d47d57cf2afcd8430841
0 | 0:00 | DEBUG | Map id: matchpoint
0 | 0:00 | DEBUG | Start: 12 o'clock
0 | 0:00 | DEBUG | Rush distance: 209
0 | 0:00 | DEBUG | PvPGateCore: Won 100 of 100 games (46.7 weighted). 100% raw, 100% weighted
0 | 0:00 | DEBUG | PvPDT: Won 100 of 100 games (46.7 weighted). 100% raw, 100% weighted
0 | 0:00 | DEBUG | PvPRobo: Won 0 of 0 games (0.0 weighted). 75% raw, 75% weighted
0 | 0:00 | DEBUG | PvPReaver: Won 0 of 0 games (0.0 weighted). 75% raw, 75% weighted
0 | 0:00 | DEBUG | PvP1012: Won 0 of 0 games (0.0 weighted). 75% raw, 75% weighted
0 | 0:00 | DEBUG | PvP5Zealot: Won 0 of 0 games (0.0 weighted). 75% raw, 75% weighted
0 | 0:00 | DEBUG | PvP3GateGoon: Won 0 of 0 games (0.0 weighted). 75% raw, 75% weighted
0 | 0:00 | DEBUG | PvP4GateGoon: Won 0 of 0 games (0.0 weighted). 75% raw, 75% weighted
0 | 0:00 | DEBUG | PvPCoreExpand: Won 0 of 0 games (0.0 weighted). 75% raw, 75% weighted
0 | 0:00 | DEBUG | Win probability by branch
0 | 0:00 | DEBUG | 99% PvPDT + PvPGateCore
0 | 0:00 | DEBUG | 86% PvP3GateGoon + PvPGateCore
0 | 0:00 | DEBUG | 86% PvP4GateGoon + PvPGateCore
0 | 0:00 | DEBUG | 86% PvPCoreExpand + PvPGateCore
0 | 0:00 | DEBUG | 82% PvPRobo + PvPGateCore + PvPReaver
0 | 0:00 | DEBUG | 82% PvPDT + PvP1012 + PvP5Zealot
0 | 0:00 | DEBUG | 74% PvP4GateGoon + PvP1012 + PvP5Zealot
0 | 0:00 | DEBUG | 74% PvP3GateGoon + PvP1012 + PvP5Zealot
0 | 0:00 | DEBUG | Frame 0 (secs): 1
0 | 0:00 | DEBUG | Opponents: AutoPilot
0 | 0:00 | DEBUG | Rush distances: 209, 209
0 | 0:00 | DEBUG | Mean rush distance: 209
0 | 0:00 | DEBUG | Playbook: TournamentPlaybook
0 | 0:00 | DEBUG | Policy: StrategySelectionTournament
0 | 0:00 | DEBUG | Enemy race: Protoss
0 | 0:00 | DEBUG | Strategy: PvPDT PvPGateCore
0 | 0:00 | DEBUG | Our bases: 1
0 | 0:00 | DEBUG | Enemy bases: 0
0 | 0:00 | DEBUG | Our mining bases: 1
0 | 0:00 | DEBUG | Enemy mining bases: 0
0 | 0:00 | DEBUG | Our max mining: 1
0 | 0:00 | DEBUG | Enemy max mining: 1
0 | 0:00 | DEBUG | Our techs: 
0 | 0:00 | DEBUG | Enemy techs: 
0 | 0:00 | DEBUG | Our upgrades: 
0 | 0:00 | DEBUG | Enemy upgrades: 
0 | 0:00 | DEBUG | Our Factories: 0
0 | 0:00 | DEBUG | Our Barracks: 0
0 | 0:00 | DEBUG | Our Gateways: 0
0 | 0:00 | DEBUG | Our Hatcheries: 0
0 | 0:00 | DEBUG | Enemy Factories: 0
0 | 0:00 | DEBUG | Enemy Barracks: 0
0 | 0:00 | DEBUG | Enemy Gateways: 0
0 | 0:00 | DEBUG | Enemy Hatcheries: 0
0 | 0:00 | DEBUG | Safe defending: true
0 | 0:00 | DEBUG | Safe pushing: true
0 | 0:00 | DEBUG | Safe skirmishing: true
0 | 0:00 | DEBUG | Skirmish > Defense: false
0 | 0:00 | DEBUG | Kill potential: true
0 | 0:00 | DEBUG | Aggression: 1.0
0 | 0:00 | DEBUG | Want to attack: false
0 | 0:00 | DEBUG | Want to harass: false
0 | 0:00 | DEBUG | Monitor bases: true
0 | 0:00 | DEBUG | Fingerprints: 
0 | 0:00 | DEBUG | Status: PvPOpening, CoreZ, GCRange, DTAfterCannon@5:09-5:15-14:45, DoForgeCannon
0 | 0:00 | DEBUG | Performance danger: false
0 | 0:00 | DEBUG | Sluggishness: 0
0 | 0:00 | DEBUG | Gas worker floor: 0
0 | 0:00 | DEBUG | Gas worker ceiling: 200
0 | 0:00 | DEBUG | Gas worker ratio: 0.3
0 | 0:00 | DEBUG | Gas limit floor: 0
0 | 0:00 | DEBUG | Gas limit ceiling: 350
0 | 0:00 | DEBUG | Active squads: 
0 | 0:00 | DEBUG | Attack squad: Push Nige
0 | 0:00 | DEBUG | Pulling workers: false
0 | 0:00 | DEBUG | Main fully scouted: false
1 | 0:00 | DEBUG | Frame 0 (secs) changed from 1 to 2
9 | 0:00 | DEBUG | Assuming AutoPilot owns ? Enemy main Lahore, Kenya, Westeros  [6, 113] as implicit starting location
9 | 0:00 | DEBUG | Enemy bases changed from 0 to 1
9 | 0:00 | DEBUG | Enemy mining bases changed from 0 to 1
1196 | 0:49 | DEBUG | Status
  Was: PvPOpening, CoreZ, GCRange, DTAfterCannon@5:09-5:15-14:45, DoForgeCannon
  Now: PvPOpening, CoreZ, GCRange, DTAfterCannon@5:05-5:12-14:45, DoForgeCannon
1847 | 1:16 | DEBUG | Our Gateways changed from 0 to 1
2339 | 1:37 | DEBUG | Discovered first Probe
2339 | 1:37 | DEBUG | Foe Probe #4 20/20 (20/20) [3392, 1119](106, 34) projects to arrive in 142 frames at 1:43
2628 | 1:49 | DEBUG | Active squads
  Was: 
  Now: Eject
2827 | 1:57 | DEBUG | Active squads
  Was: Eject
  Now: 
2833 | 1:58 | DEBUG | Status
  Was: PvPOpening, CoreZ, GCRange, DTAfterCannon@5:05-5:12-14:45, DoForgeCannon
  Now: PvPOpening, CoreZ, GCRange, DTAfterCannon@5:05-5:11-14:45, DoForgeCannon
2833 | 1:58 | DEBUG | Active squads
  Was: 
  Now: WorkerScout
2836 | 1:58 | DEBUG | Status
  Was: PvPOpening, CoreZ, GCRange, DTAfterCannon@5:05-5:11-14:45, DoForgeCannon
  Now: PvPOpening, CoreZ, GCRange, DTAfterCannon@5:04-5:11-14:45, DoForgeCannon
2930 | 2:02 | DEBUG | Active squads
  Was: WorkerScout
  Now: Eject, WorkerScout
2939 | 2:02 | DEBUG | Active squads
  Was: Eject, WorkerScout
  Now: WorkerScout
3453 | 2:23 | DEBUG | Status
  Was: PvPOpening, CoreZ, GCRange, DTAfterCannon@5:04-5:11-14:45, DoForgeCannon
  Now: PvPOpening, CoreZ, GCRange, DTAfterCannon@5:03-5:09-14:45, DoForgeCannon
3831 | 2:39 | DEBUG | Active squads
  Was: WorkerScout
  Now: Plug Guang, WorkerScout
3934 | 2:43 | DEBUG | Discovered first Nexus
3934 | 2:43 | DEBUG | Foe Nexus #9 750/750 (750/750) [320, 3632](10, 113) is already complete
4077 | 2:49 | DEBUG | Discovered first Zealot
4077 | 2:49 | DEBUG | Foe Zealot #111 100/100 (60/60) [388, 3590](12, 112) projects to arrive in 1712 frames at 4:01
4078 | 2:49 | DEBUG | Skirmish > Defense changed from false to true
4078 | 2:49 | DEBUG | Kill potential changed from true to false
4090 | 2:50 | DEBUG | Safe defending changed from true to false
4123 | 2:51 | DEBUG | Enemy Gateways changed from 0 to 1
4123 | 2:51 | DEBUG | Status
  Was: PvPOpening, CoreZ, GCRange, DTAfterCannon@5:03-5:09-14:45, DoForgeCannon
  Now: PvPOpening, CoreZ, GCRange, DTAfterCannon@5:04-5:11-14:45, DoForgeCannon
4123 | 2:51 | DEBUG | Discovered first Gateway
4123 | 2:51 | DEBUG | Foe Gateway #103 500/500 (500/500) [512, 3696](16, 115) is already complete
4268 | 2:57 | DEBUG | Discovered first Pylon
4268 | 2:57 | DEBUG | Foe Pylon #99 300/300 (300/300) [352, 3776](11, 118) is already complete
4270 | 2:57 | DEBUG | Main fully scouted changed from false to true
4980 | 3:27 | DEBUG | Active squads
  Was: Plug Guang, WorkerScout
  Now: Plug Guang, Eject, WorkerScout
5001 | 3:28 | DEBUG | Active squads
  Was: Plug Guang, Eject, WorkerScout
  Now: Plug Seoul, Eject, WorkerScout
5299 | 3:40 | DEBUG | Safe defending changed from false to true
5320 | 3:41 | DEBUG | Safe defending changed from true to false
5488 | 3:48 | DEBUG | Safe defending changed from false to true
5498 | 3:49 | DEBUG | Active squads
  Was: Scour Seoul, Eject, WorkerScout
  Now: Scour Seoul, WorkerScout
5832 | 4:03 | DEBUG | Our Gateways changed from 1 to 2
5905 | 4:06 | DEBUG | Active squads
  Was: Plug Seoul, WorkerScout
  Now: Plug Seoul, Eject, WorkerScout
5918 | 4:06 | DEBUG | Kill potential changed from false to true
5920 | 4:06 | DEBUG | Kill potential changed from true to false
6052 | 4:12 | DEBUG | Kill potential changed from false to true
6300 | 4:22 | DEBUG | Skirmish > Defense changed from true to false
6332 | 4:23 | DEBUG | Active squads
  Was: Guard Seoul, Eject, WorkerScout
  Now: Guard Seoul, WorkerScout
6444 | 4:28 | DEBUG | Active squads
  Was: Guard Seoul, WorkerScout
  Now: Guard Guang, WorkerScout
6771 | 4:42 | DEBUG | Our upgrades
  Was: 
  Now: Singularity Charge = 1
7152 | 4:58 | DEBUG | Skirmish > Defense changed from false to true
7180 | 4:59 | DEBUG | Status
  Was: PvPOpening, CoreZ, GCRange, DTAfterCannon@5:04-5:11-14:45, DoForgeCannon
  Now: PvPOpening, CoreZ, GCRange, DTAfterCannon@5:04-5:11-4:48, DoForgeCannon
7207 | 5:00 | DEBUG | Status
  Was: PvPOpening, CoreZ, GCRange, DTAfterCannon@5:04-5:11-4:48, DoForgeCannon
  Now: PvPOpening, CoreZ, GCRange, DTAfterCannon@5:04-5:11-4:48, NaturalPylon, DoForgeCannon
7216 | 5:00 | DEBUG | Active squads
  Was: Guard Guang, WorkerScout
  Now: Guard Seoul, WorkerScout
7517 | 5:13 | DEBUG | Status
  Was: PvPOpening, CoreZ, GCRange, DTAfterCannon@5:04-5:11-4:48, NaturalPylon, DoForgeCannon
  Now: PvPOpening, CoreZ, GCRange, DTAfterCannon@4:58-5:04-4:42, DoForgeCannon
7552 | 5:14 | DEBUG | Active squads
  Was: Guard Seoul, WorkerScout
  Now: Guard Seoul, Eject, WorkerScout
7612 | 5:17 | DEBUG | Skirmish > Defense changed from true to false
7618 | 5:17 | DEBUG | Skirmish > Defense changed from false to true
7641 | 5:18 | DEBUG | Skirmish > Defense changed from true to false
7661 | 5:19 | DEBUG | Skirmish > Defense changed from false to true
7677 | 5:19 | DEBUG | Skirmish > Defense changed from true to false
7691 | 5:20 | DEBUG | Skirmish > Defense changed from false to true
7705 | 5:21 | DEBUG | Skirmish > Defense changed from true to false
7800 | 5:25 | DEBUG | Skirmish > Defense changed from false to true
7898 | 5:29 | DEBUG | Active squads
  Was: Guard Seoul, Eject, WorkerScout
  Now: Guard Seoul, WorkerScout
8038 | 5:34 | DEBUG | Status
  Was: PvPOpening, CoreZ, GCRange, DTAfterCannon@4:58-5:04-4:42, DoForgeCannon
  Now: PvPOpening, CoreZ, GCRange, DTAfterCannon@4:58-5:04-4:42, NaturalPylon, DoForgeCannon
8279 | 5:44 | DEBUG | Skirmish > Defense changed from true to false
8364 | 5:48 | DEBUG | Status
  Was: PvPOpening, CoreZ, GCRange, DTAfterCannon@4:58-5:04-4:42, NaturalPylon, DoForgeCannon
  Now: PvPOpening, CoreZ, GCRange, DTAfterCannon@4:57-5:04-4:41, NaturalPylon, DoForgeCannon
8415 | 5:50 | DEBUG | Roll for DTGreedyExpand FAILED (Rolled 0.5360049385914836 into probability 0.5)
8457 | 5:52 | DEBUG | Active squads
  Was: Guard Seoul, WorkerScout
  Now: Guard Seoul, Eject, WorkerScout
8484 | 5:53 | DEBUG | Skirmish > Defense changed from false to true
8486 | 5:53 | DEBUG | Skirmish > Defense changed from true to false
8493 | 5:53 | DEBUG | Active squads
  Was: Guard Seoul, Eject, WorkerScout
  Now: Guard Seoul, WorkerScout
8589 | 5:57 | DEBUG | Active squads
  Was: Guard Seoul, WorkerScout
  Now: Guard Seoul, Eject, WorkerScout
8628 | 5:59 | DEBUG | Active squads
  Was: Guard Seoul, Eject, WorkerScout
  Now: Guard Seoul, WorkerScout
8787 | 6:06 | DEBUG | Status
  Was: PvPOpening, CoreZ, GCRange, DTAfterCannon@4:57-5:04-4:41, NaturalPylon, DoForgeCannon
  Now: PvPOpening, CoreZ, GCRange, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon
9308 | 6:27 | DEBUG | Status
  Was: PvPOpening, CoreZ, GCRange, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon
  Now: PvPOpening, CoreZ, GCRange, DTAfterCannon@4:57-5:04-4:41, NaturalPylon, DoForgeCannon
9371 | 6:30 | DEBUG | Active squads
  Was: Guard Seoul, WorkerScout
  Now: Guard Seoul, Eject, WorkerScout
9394 | 6:31 | DEBUG | Status
  Was: PvPOpening, CoreZ, GCRange, DTAfterCannon@4:57-5:04-4:41, NaturalPylon, DoForgeCannon
  Now: PvPOpening, CoreZ, GCRange, Expand, HoldNat, DTAfterCannon@4:57-5:04-4:41, NaturalPylon, DoForgeCannon
9421 | 6:32 | DEBUG | Active squads
  Was: Guard Seoul, Eject, WorkerScout
  Now: Guard Seoul, WorkerScout
9748 | 6:46 | DEBUG | Detecting ownership of base from visible town hall: Our Nexus 8:01 #172 76/750 (76/750) [3264, 1520](102, 47)
9749 | 6:46 | DEBUG | Our bases changed from 1 to 2
9749 | 6:46 | DEBUG | Our mining bases changed from 1 to 2
9751 | 6:46 | DEBUG | Our max mining changed from 1 to 2
9751 | 6:46 | DEBUG | Enemy max mining changed from 1 to 2
9751 | 6:46 | DEBUG | Status
  Was: PvPOpening, CoreZ, GCRange, Expand, HoldNat, DTAfterCannon@4:57-5:04-4:41, NaturalPylon, DoForgeCannon
  Now: PvPOpening, CoreZ, GCRange, Expand, DTAfterCannon@4:57-5:04-4:41, NaturalPylon, DoForgeCannon
9753 | 6:46 | DEBUG | Recording our first expansion frame
9789 | 6:47 | DEBUG | Status
  Was: PvPOpening, CoreZ, GCRange, Expand, DTAfterCannon@4:57-5:04-4:41, NaturalPylon, DoForgeCannon
  Now: PvPOpening, CoreZ, GCRange, Expand, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon
10143 | 7:02 | DEBUG | Active squads
  Was: Guard Seoul, WorkerScout
  Now: Guard Seoul, WorkerScout, DarkTemplar
10148 | 7:02 | DEBUG | Status
  Was: PvPOpening, CoreZ, GCRange, Expand, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon
  Now: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon, Pioneer, DTBraveHome, DTBraveAbroad, Templar
10148 | 7:02 | DEBUG | Gas limit ceiling changed from 350 to 500
10153 | 7:03 | DEBUG | Active squads
  Was: Guard Seoul, WorkerScout, DarkTemplar
  Now: Guard Seoul, DarkTemplar
10295 | 7:08 | DEBUG | Our Gateways changed from 2 to 3
10316 | 7:09 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon, Pioneer, DTBraveHome, DTBraveAbroad, Templar
  Now: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, NaturalPylon, DoForgeCannon, Pioneer, DTBraveHome, DTBraveAbroad, Templar
10456 | 7:15 | DEBUG | Our Gateways changed from 3 to 4
10479 | 7:16 | DEBUG | Active squads
  Was: Guard Seoul, DarkTemplar
  Now: Guard Seoul, Eject, DarkTemplar
10528 | 7:18 | DEBUG | Active squads
  Was: Guard Seoul, Eject, DarkTemplar
  Now: Guard Seoul, DarkTemplar
10630 | 7:22 | DEBUG | Our Gateways changed from 4 to 5
10735 | 7:27 | DEBUG | Wall clock: 1 minutes elapsed. Checkpoint game speed: 7.45x. Total game speed: 7.45x
11431 | 7:56 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, NaturalPylon, DoForgeCannon, Pioneer, DTBraveHome, DTBraveAbroad, Templar
  Now: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon, Pioneer, DTBraveHome, DTBraveAbroad, Templar
11950 | 8:17 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon, Pioneer, DTBraveHome, DTBraveAbroad, Templar
  Now: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, NaturalPylon, DoForgeCannon, Pioneer, DTBraveHome, DTBraveAbroad, Templar
12085 | 8:23 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, NaturalPylon, DoForgeCannon, Pioneer, DTBraveHome, DTBraveAbroad, Templar
  Now: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon, Pioneer, DTBraveHome, DTBraveAbroad, Templar
12466 | 8:39 | DEBUG | Detecting absent base: ? Neutral main Lahore, Kenya, Westeros  [6, 113]
12467 | 8:39 | DEBUG | Enemy bases changed from 1 to 0
12467 | 8:39 | DEBUG | Enemy mining bases changed from 1 to 0
12607 | 8:45 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon, Pioneer, DTBraveHome, DTBraveAbroad, Templar
  Now: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, NaturalPylon, DoForgeCannon, Pioneer, DTBraveHome, DTBraveAbroad, Templar
12715 | 8:49 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, NaturalPylon, DoForgeCannon, Pioneer, DTBraveHome, DTBraveAbroad, Templar
  Now: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon, Pioneer, DTBraveHome, DTBraveAbroad, Templar
12901 | 8:57 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon, Pioneer, DTBraveHome, DTBraveAbroad, Templar
  Now: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon, Pioneer, DTBraveHome, Templar
13883 | 9:38 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon, Pioneer, DTBraveHome, Templar
  Now: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon, Pioneer, Templar
14075 | 9:46 | DEBUG | Want to attack changed from false to true
14075 | 9:46 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon, Pioneer, Templar
  Now: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon, Attack, Templar
14075 | 9:46 | DEBUG | Active squads
  Was: Guard Seoul, DarkTemplar
  Now: Push Laho, DarkTemplar, ScoutExpansions
14075 | 9:46 | DEBUG | Attack squad changed from Push Nige to Push Laho
14202 | 9:51 | DEBUG | Our Gateways changed from 5 to 6
14400 | 10:00 | DEBUG | Storyteller moving to more intermittent updates
14424 | 10:01 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:57-5:04-4:41, DoForgeCannon, Attack, Templar
  Now: PvPLateGame, DTAfterCannon@4:59-5:05-4:42, DoForgeCannon, Attack, Templar
14472 | 10:03 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:59-5:05-4:42, DoForgeCannon, Attack, Templar
  Now: PvPLateGame, DTAfterCannon@4:59-5:05-4:42, NaturalPylon, DoForgeCannon, Attack, Templar
14496 | 10:04 | DEBUG | Our upgrades
  Was: Singularity Charge = 1
  Now: Leg Enhancements = 1, Singularity Charge = 1
14640 | 10:10 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:59-5:05-4:42, NaturalPylon, DoForgeCannon, Attack, Templar
  Now: PvPLateGame, DTAfterCannon@4:58-5:04-4:42, DoForgeCannon, Attack, Templar
14976 | 10:24 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:58-5:04-4:42, DoForgeCannon, Attack, Templar
  Now: PvPLateGame, DTAfterCannon@4:58-5:04-4:42, DoForgeCannon, Expand, Attack, Templar, 3base
15192 | 10:33 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:58-5:04-4:42, DoForgeCannon, Expand, Attack, Templar, 3base
  Now: PvPLateGame, DTAfterCannon@4:58-5:04-4:42, DoForgeCannon, Expand, Attack, Templar, 3base, Ready4Storm
15480 | 10:45 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:58-5:04-4:42, DoForgeCannon, Expand, Attack, Templar, 3base, Ready4Storm
  Now: PvPLateGame, DTAfterCannon@4:58-5:04-4:42, NaturalPylon, DoForgeCannon, Expand, Attack, Templar, 3base, Ready4Storm
15528 | 10:47 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:58-5:04-4:42, NaturalPylon, DoForgeCannon, Expand, Attack, Templar, 3base, Ready4Storm
  Now: PvPLateGame, DTAfterCannon@4:58-5:04-4:42, NaturalPylon, DoForgeCannon, 2Tech, Expand, Attack, Templar, 3base, Ready4Storm
15672 | 10:53 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:58-5:04-4:42, NaturalPylon, DoForgeCannon, 2Tech, Expand, Attack, Templar, 3base, Ready4Storm
  Now: PvPLateGame, DTAfterCannon@4:58-5:04-4:42, DoForgeCannon, 2Tech, Expand, Attack, Templar, 3base, Ready4Storm
15696 | 10:54 | DEBUG | Enemy Gateways changed from 1 to 0
15720 | 10:55 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:58-5:04-4:42, DoForgeCannon, 2Tech, Expand, Attack, Templar, 3base, Ready4Storm
  Now: PvPLateGame, DTAfterCannon@4:58-5:05-4:42, DoForgeCannon, 2Tech, Expand, Attack, Templar, 3base, Ready4Storm
15792 | 10:58 | DEBUG | Status
  Was: PvPLateGame, DTAfterCannon@4:58-5:05-4:42, DoForgeCannon, 2Tech, Expand, Attack, Templar, 3base, Ready4Storm
  Now: PvPLateGame, DTAfterCannon@4:45-4:52-4:29, DoForgeCannon, 2Tech, Expand, Attack, Templar, 3base, Ready4Storm
15792 | 10:58 | DEBUG | Attack squad changed from Push Laho to Push Shan
15843 | 11:00 | DEBUG | Game ended in victory
15843 | 11:00 | DEBUG | Found C:\TM\Starcraft\bwapi-data\AI\accelerants-matchpoint.json
15843 | 11:00 | DEBUG | Game duration (fastest):    11m 0s
15843 | 11:00 | DEBUG | Game duration (wall clock): 1m 31s
15843 | 11:00 | DEBUG | 
Bot frames over 35ms: 1
Bot frames over 1000ms: 0
Bot frames over 10000ms: 0
15843 | 11:00 | DEBUG | The bot believes its performance, if it were running synchronously, would have been good
15843 | 11:00 | DEBUG | Performance metrics:
Frame duration: After receiving 'frame ready' -> before sending 'frame done':
15,842 samples averaging 4.6 [1.2 - 345.3]
>= 5: 5700 samples averaging 6.5
>= 10: 199 samples averaging 14
>= 15: 19 samples averaging 38.6
>= 20: 10 samples averaging 58.5
>= 25: 8 samples averaging 67.1
>= 30: 2 samples averaging 187.7
>= 35: 1 samples averaging 345.3
>= 40: 1 samples averaging 345.3
>= 45: 1 samples averaging 345.3
>= 50: 1 samples averaging 345.3
>= 55: 1 samples averaging 345.3
>= 85: 1 samples averaging 345.3
Frame duration: After receiving 'frame ready' -> after sending 'frame done':
15,842 samples averaging 4.6 [1.2 - 345.3]
>= 5: 5736 samples averaging 6.5
>= 10: 199 samples averaging 14.1
>= 15: 19 samples averaging 38.6
>= 20: 10 samples averaging 58.5
>= 25: 8 samples averaging 67.1
>= 30: 2 samples averaging 187.7
>= 35: 1 samples averaging 345.3
>= 40: 1 samples averaging 345.3
>= 45: 1 samples averaging 345.3
>= 50: 1 samples averaging 345.3
>= 55: 1 samples averaging 345.3
>= 85: 1 samples averaging 345.3
Frame duration: After receiving 'frame ready' -> receiving next 'frame ready':
15,842 samples averaging 5.6 [1.9 - 346.9]
>= 5: 8909 samples averaging 6.9
>= 10: 337 samples averaging 13.5
>= 15: 40 samples averaging 27.4
>= 20: 13 samples averaging 51.2
>= 25: 10 samples averaging 60
>= 30: 3 samples averaging 136.2
>= 35: 1 samples averaging 346.9
>= 40: 1 samples averaging 346.9
>= 45: 1 samples averaging 346.9
>= 50: 1 samples averaging 346.9
>= 55: 1 samples averaging 346.9
>= 85: 1 samples averaging 346.9
BWAPI duration: Before sending 'frame done' -> After receiving 'frame ready':
15,842 samples averaging 1 [0.6 - 22.9]
>= 1: 7488 samples averaging 1.2
>= 3: 8 samples averaging 9.9
>= 5: 8 samples averaging 9.9
>= 10: 3 samples averaging 14.9
>= 15: 1 samples averaging 22.9
>= 20: 1 samples averaging 22.9
BWAPI duration: Before sending 'frame done' -> After sending 'frame done':
15,842 samples averaging 0 [0 - 0]
BWAPI duration: Before listening for 'frame ready' -> After receiving 'frame ready':
15,842 samples averaging 1 [0.6 - 22.9]
>= 1: 7111 samples averaging 1.2
>= 3: 8 samples averaging 9.9
>= 5: 8 samples averaging 9.9
>= 10: 3 samples averaging 14.9
>= 15: 1 samples averaging 22.9
>= 20: 1 samples averaging 22.9
Copying frame to buffer:
15,843 samples averaging 1 [0.7 - 10.2]
>= 5: 2 samples averaging 8.2
>= 10: 1 samples averaging 10.2
Time holding frame until buffer frees capacity: No samples.
Frames already buffered when enqueuing a new frame:
15,844 samples averaging 0 [0 - 1]
>= 0: 15844 samples averaging 0
>= 1: 1 samples averaging 1
Frames behind real-time when handling events:
15,844 samples averaging 0 [0 - 0]
>= 0: 15844 samples averaging 0
Time flushing side effects:
15,843 samples averaging 0 [0 - 6]
>= 1: 1 samples averaging 6
>= 3: 1 samples averaging 6
>= 5: 1 samples averaging 6
Duration of bot event handlers:
15,842 samples averaging 4.5 [1.2 - 349.2]
>= 5: 5484 samples averaging 6.5
>= 10: 193 samples averaging 14.1
>= 15: 19 samples averaging 38.7
>= 20: 10 samples averaging 58.8
>= 25: 8 samples averaging 67.5
>= 30: 2 samples averaging 189.9
>= 35: 1 samples averaging 349.2
>= 40: 1 samples averaging 349.2
>= 45: 1 samples averaging 349.2
>= 50: 1 samples averaging 349.2
>= 55: 1 samples averaging 349.2
>= 85: 1 samples averaging 349.2
Time bot spent idle:
15,844 samples averaging 1.1 [0 - 22.9]
Time client spent waiting for bot:
15,842 samples averaging 3.6 [0.1 - 344.1]
>= 30: 1 samples averaging 344.1
Excess duration of client sleep:
15,843 samples averaging 0 [0 - 315]
>= 1: 1 samples averaging 315
>= 5: 1 samples averaging 315
>= 10: 1 samples averaging 315
>= 15: 1 samples averaging 315
>= 20: 1 samples averaging 315
>= 25: 1 samples averaging 315
>= 30: 1 samples averaging 315
>= 35: 1 samples averaging 315
>= 40: 1 samples averaging 315
>= 45: 1 samples averaging 315
>= 50: 1 samples averaging 315
>= 55: 1 samples averaging 315
>= 85: 1 samples averaging 315
Number of events received from BWAPI:
15,842 samples averaging 1.1 [1 - 73]
>= 1: 15842 samples averaging 1.1
>= 2: 670 samples averaging 4.4
>= 3: 573 samples averaging 4.8
>= 4: 304 samples averaging 6.3
>= 5: 140 samples averaging 9
>= 6: 78 samples averaging 12.2
>= 8: 48 samples averaging 15.5
>= 10: 38 samples averaging 17.3
>= 15: 25 samples averaging 20.1
>= 20: 5 samples averaging 33.8
Number of events received from BWAPI, multiplied by the receive-to-sent duration of that frame:
15,842 samples averaging 5.4 [1.2 - 1,042.6]
>= 5: 6085 samples averaging 8.4
>= 10: 745 samples averaging 23.8
>= 15: 400 samples averaging 34
>= 20: 241 samples averaging 45.1
>= 25: 161 samples averaging 56.6
>= 30: 100 samples averaging 74.4
>= 35: 74 samples averaging 89.3
>= 40: 50 samples averaging 114.1
>= 45: 44 samples averaging 123.8
>= 50: 39 samples averaging 133.6
>= 55: 35 samples averaging 142.9
>= 85: 19 samples averaging 206.8
15843 | 11:00 | DEBUG | Target:                      35ms   Cutoff: 35ms 
Task                         Runs   Seconds %Time Budgt (Recnt) Avg ms Max (Rcnt) Max (All time) AcrossTarget AcrossLimit
Global                       15844  66      74%   34            4      7          349            1            1          
Micro                        15844  15      17%   33            1      2          347            1            1          
Agency                       15836  15      16%   33            1      2          346            1            1          
Planning                     15843  26      29%   2             1      4          28             0            0          
Battles                      15844  13      14%   34            1      1          14             0            0          
Gameplan                     8137   12      14%   5             3      4          15             0            0          
Tactician                    8136   11      13%   5             4      5          27             0            0          
BattleProcessPredict         60115  5       6%    33            0      1          14             0            0          
Geography                    4559   3       3%    1             0      1          8              0            0          
Squads                       8136   3       3%    5             2      3          25             0            0          
Grids                        15843  2       2%    0             0      1          10             0            0          
Intel                        15843  2       2%    0             0      1          2              0            0          
BattleProcessCluster         11284  2       2%    34            0      1          9              0            0          
Fingerprinting               15843  1       2%    2             0      0          21             0            0          
BattleProcessJudge           60115  1       2%    33            0      0          8              0            0          
BattleProcessDivisions       11284  1       1%    34            0      1          2              0            0          
GameSensor                   7537   1       1%    1             0      1          2              0            0          
Gathering                    8136   1       1%    2             0      1          9              0            0          
BattleProcessSwap            11284  1       1%    33            0      1          1              0            0          
Scouting                     7537   0       1%    1             0      0          1              0            0          
FingerGatewayFirst           10944  0       0%    2             0      0          6              0            0          
Yolo                         15755  0       0%    2             0      0          1              0            0          
FingerDTRush                 10944  0       0%    2             0      0          1              0            0          
FingerRobo                   10944  0       0%    2             0      0          1              0            0          
Accounting                   8137   0       0%    4             0      1          1              0            0          
UnitsShown                   8137   0       0%    3             0      0          1              0            0          
FingerWorkerRush             10944  0       0%    2             0      0          1              0            0          
Finger2GateGoon              10944  0       0%    2             0      0          1              0            0          
FingerForgeFE                10944  0       0%    2             0      0          1              0            0          
ProductionHistory            8137   0       0%    3             0      0          6              0            0          
FingerProxyGateway           10944  0       0%    2             0      0          1              0            0          
Finger2Gate99                10944  0       0%    2             0      0          2              0            0          
FingerGatewayFE              10944  0       0%    2             0      0          1              0            0          
Finger3GateGoon              10944  0       0%    2             0      0          1              0            0          
FingerGasSteal               10944  0       0%    2             0      0          1              0            0          
FingerEarlyForge             10944  0       0%    2             0      0          1              0            0          
BattleProcessMatchupAnalysis 183784 0       0%    33            0      0          1              0            0          
BattleProcessComplete        11283  0       0%    34            0      0          1              0            0          
Manners                      15627  0       0%    2             0      0          1              0            0          
FingerCannonRush             10944  0       0%    2             0      0          1              0            0          
FingerRampBlock              10944  0       0%    2             0      0          1              0            0          
FingerMannerPylon            10944  0       0%    2             0      0          1              0            0          
FingerCoreBeforeZealot       10944  0       0%    2             0      0          1              0            0          
BattleProcessInitial         11284  0       0%    34            0      0          1              0            0          
Finger2Gate                  10944  0       0%    2             0      0          1              0            0          
Finger1GateCore              10944  0       0%    2             0      0          1              0            0          
MatchupGraph                 1      0       0%    345678        2      2          0              0            0          
FingerNexusFirst             10944  0       0%    2             0      0          1              0            0          
Camera                       0      0       0%    0             0      0          0              0            0          
Finger4GateGoon              10944  0       0%    2             0      0          0              0            0          
FingerDragoonRange           10944  0       0%    2             0      0          0              0            0          
Visualization                0      0       0%    0             0      0          0              0            0          
15843 | 11:00 | DEBUG | Actions:
Action             Invocations Mean (ms) Total (secs)
Plug               649         0.417     0           
Search             3656        0.173     0           
Retreat            1331        0.118     0           
SearchWhenBored    3848        0.098     0           
PreserveScout      3656        0.031     0           
Idle               568050      0.019     10          
KnockKnock         3656        0.01      0           
BlockConstruction  3656        0.009     0           
Fight              568634      0.008     4           
SabotageProxy      3656        0.006     0           
FightOrFlee        568634      0.004     2           
Harass4PoolWorkers 3656        0.002     0           
Scout              568577      0.002     1           
AttackBuilder      3656        0.002     0           
Gather             568595      0.002     0           
Build              568577      0.001     0           
Brawl              42353       0.001     0           
Produce            568577      0.001     0           
Crack              568577      0         0           
Cancel             568577      0         0           
Potshot            169678      0         0           
Spot               88949       0         0           
Stealth            88949       0         0           
Root               88949       0         0           
Bust               88949       0         0           
OccupyBunker       88949       0         0           
Recharge           88949       0         0           
Follow             88949       0         0           
BeCarrier          88949       0         0           
Stim               88949       0         0           
BeFlier            88949       0         0           
Cast               88949       0         0           
Rally              568577      0         0           
BeArbiter          88949       0         0           
StrategicNuke      88949       0         0           
EmergencyBunk      88949       0         0           
BeVulture          88949       0         0           
Unstick            568577      0         0           
BeReaver           88949       0         0           
Unbunk             568577      0         0           
Attack             568577      0         0           
Liftoff            568577      0         0           
EmergencyRepair    568577      0         0           
Repair             568577      0         0           
ReloadScarabs      568577      0         0           
Addon              568577      0         0           
ReloadInterceptors 568577      0         0           
Meld               568577      0         0           
FinishConstruction 568577      0         0           
Travel             568577      0         0           
Scan               568577      0         0           
Transport          568577      0         0           
Bunk               568577      0         0           
15843 | 11:00 | DEBUG | Pathfinding:
A* pathfinds:         52241 
Exploration maxed:    148   
Over 1ms:             21    
Max ms:               6.2968
Mean ms:              0.016 
Path length, max:     97    
Path length, mean:    4.387 
Tiles explored, max:  960   
Tiles explored, mean: 18    
15843 | 11:00 | DEBUG | JVM Max memory:   2133 MB
15843 | 11:00 | DEBUG | JVM Total memory: 2133 MB
15843 | 11:00 | DEBUG | JVM Free memory:  1652 MB
15843 | 11:00 | DEBUG | JVM Used memory:  481 MB
15843 | 11:00 | DEBUG | JVM Used, heap:   481 MB
15843 | 11:00 | DEBUG | Non-heap memory Compressed Class Space, current: 7 MB used | 0 MB init | 1073 MB max | 8 MB committed
15843 | 11:00 | DEBUG | Non-heap memory Compressed Class Space, peak:    7 MB used | 0 MB init | 1073 MB max | 8 MB committed
15843 | 11:00 | DEBUG | Heap memory PS Survivor Space, current:          10 MB used | 89 MB init | 14 MB max | 14 MB committed
15843 | 11:00 | DEBUG | Heap memory PS Survivor Space, peak:             89 MB used | 89 MB init | 92 MB max | 92 MB committed
15843 | 11:00 | DEBUG | Non-heap memory Metaspace, current:              52 MB used | 0 MB init | 0 MB max | 64 MB committed
15843 | 11:00 | DEBUG | Non-heap memory Metaspace, peak:                 52 MB used | 0 MB init | 0 MB max | 64 MB committed
15843 | 11:00 | DEBUG | Non-heap memory Code Cache, current:             58 MB used | 2 MB init | 251 MB max | 59 MB committed
15843 | 11:00 | DEBUG | Non-heap memory Code Cache, peak:                59 MB used | 2 MB init | 251 MB max | 59 MB committed
15843 | 11:00 | DEBUG | Heap memory PS Old Gen, current:                 167 MB used | 1431 MB init | 1431 MB max | 1431 MB committed
15843 | 11:00 | DEBUG | Heap memory PS Old Gen, peak:                    167 MB used | 1431 MB init | 1431 MB max | 1431 MB committed
15843 | 11:00 | DEBUG | Heap memory PS Eden Space, current:              302 MB used | 537 MB init | 687 MB max | 687 MB committed
15843 | 11:00 | DEBUG | Heap memory PS Eden Space, peak:                 697 MB used | 537 MB init | 697 MB max | 697 MB committed5822 | 4:02 | SPEED | Our Dragoon #123 100/100 (80/80) [3497, 892](109, 27) crossed 35ms: 345ms on Idle, FightOrFlee, Idle, Fight, Reposition
5822 | 4:02 | SPEED | Agency crossed 35ms taking 346ms on a 34ms budget (33ms avg budget), reaching 348ms on the frame.
5822 | 4:02 | SPEED | Global crossed 35ms to 348ms. Task durations: (3ms: Planning) (1ms: Battles) (1ms: Micro) (1ms: Grids) (0ms: Yolo) (0ms: Manners) (0ms: Geography) (0ms: Intel) (0ms: Fingerprinting)