0 | 0:00 | DEBUG | Using configuration file PurpleWaveAIIDETournament.config.json
0 | 0:00 | DEBUG | Human: false
0 | 0:00 | DEBUG | Ladder: false
0 | 0:00 | DEBUG | Stream: false
0 | 0:00 | DEBUG | Tournament: true
0 | 0:00 | DEBUG | Round-robin: true
0 | 0:00 | DEBUG | Elimination: false
0 | 0:00 | DEBUG | Debugging: false
0 | 0:00 | DEBUG | Loading BWTA for Python 1.1 at (4)Python.scx
0 | 0:00 | DEBUG | Strategery.Selection.StrategySelectionRecommended@94675b has finished recommended strategy phase in game 147 of 5; will fall back to StrategySelectionGreedy
0 | 0:00 | DEBUG | Game start time:  Sat Oct 17 20:05:38 NDT 2020
0 | 0:00 | DEBUG | OS:               Windows 7 6.1 x86
0 | 0:00 | DEBUG | JRE:              Oracle Corporation - 1.8.0_211
0 | 0:00 | DEBUG | CPUs available:   2
0 | 0:00 | DEBUG | System memory:    2147 MB
0 | 0:00 | DEBUG | JVM Max memory:   1037 MB
0 | 0:00 | DEBUG | JVM Total memory: 1037 MB
0 | 0:00 | DEBUG | JVM Free memory:  699 MB
0 | 0:00 | DEBUG | This copy of PurpleWave was packaged for distribution on Mon, Sep 28, 2020 12:17:44 AM
0 | 0:00 | DEBUG | This copy of PurpleWave came from Git revision 82d99bd1f57d158ab68478e9b35c42d521127e1b
0 | 0:00 | DEBUG | JBWAPI autocontinue: true
0 | 0:00 | DEBUG | JBWAPI debugConnection: false
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 | Strategy	PvEStormNo	PvT3BaseGateway	PvT32Nexus	PvT2BaseReaver	PvT3rdFast	PvT2BaseGateway	PvT28Nexus	PvT3rdObs	WorkersUnite	PvEStormYes	PvT13Nexus	PvTDTExpand	WorkersKill	PvT3BaseArbiter	PvT2BaseArbiter	PvT2BaseCarrier	WorkersSpread	WorkersRaze	PvT3BaseCarrier	PvT1GateReaver	PvTProxy2Gate
0 | 0:00 | DEBUG | #Games	147.0	146.0	146.0	145.0	1.0	1.0	1.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0
0 | 0:00 | DEBUG | #Wtd	62.1	61.9	61.9	61.0	0.9	0.2	0.2	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0
0 | 0:00 | DEBUG | #WtdWins	62.1	61.9	61.9	61.0	0.9	0.2	0.2	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0	0.0
0 | 0:00 | DEBUG | WinPct	100%%	100%%	100%%	100%%	100%%	100%%	100%%	0%%	0%%	0%%	0%%	0%%	0%%	0%%	0%%	0%%	0%%	0%%	0%%	0%%	0%%
0 | 0:00 | DEBUG | WinEst	99%%	99%%	99%%	99%%	87%%	82%%	82%%	80%%	80%%	80%%	80%%	80%%	80%%	80%%	80%%	80%%	80%%	80%%	80%%	80%%	80%%
0 | 0:00 | DEBUG | Strategy interest
0 | 0:00 | DEBUG | 99%% PvEStormNo + PvT2BaseReaver + PvT32Nexus + PvT3BaseGateway
0 | 0:00 | DEBUG | 96%% PvEStormNo + PvT32Nexus + PvT3BaseGateway + PvT3rdFast
0 | 0:00 | DEBUG | 94%% PvEStormNo + PvT28Nexus + PvT2BaseReaver + PvT3BaseGateway
0 | 0:00 | DEBUG | 94%% PvEStormNo + PvT32Nexus + PvT3BaseGateway + PvT3rdObs
0 | 0:00 | DEBUG | 94%% PvEStormNo + PvT13Nexus + PvT2BaseReaver + PvT3BaseGateway
0 | 0:00 | DEBUG | 94%% PvEStormNo + PvT2BaseCarrier + PvT2BaseReaver + PvT32Nexus
0 | 0:00 | DEBUG | 94%% PvEStormNo + PvT2BaseReaver + PvT32Nexus + PvT3BaseCarrier
0 | 0:00 | DEBUG | 94%% PvEStormNo + PvT1GateReaver + PvT2BaseReaver + PvT3BaseGateway
0 | 0:00 | DEBUG | 94%% PvEStormNo + PvT2BaseReaver + PvT32Nexus + PvT3BaseArbiter
0 | 0:00 | DEBUG | 94%% PvEStormNo + PvT2BaseReaver + PvT3BaseGateway + PvTProxy2Gate
0 | 0:00 | DEBUG | 94%% PvEStormYes + PvT2BaseReaver + PvT32Nexus + PvT3BaseGateway
0 | 0:00 | DEBUG | 93%% PvEStormNo + PvT2BaseGateway + PvT32Nexus
0 | 0:00 | DEBUG | 92%% PvEStormNo + PvT2BaseArbiter + PvT32Nexus
0 | 0:00 | DEBUG | 92%% PvEStormNo + PvT2BaseCarrier + PvT32Nexus
0 | 0:00 | DEBUG | 91%% PvEStormNo + PvT28Nexus + PvT3BaseGateway + PvT3rdFast
0 | 0:00 | DEBUG | 91%% PvEStormNo + PvT3BaseGateway + PvT3rdFast + PvTProxy2Gate
0 | 0:00 | DEBUG | 91%% PvEStormNo + PvT3BaseGateway + PvT3rdFast + PvTDTExpand
0 | 0:00 | DEBUG | 91%% PvEStormNo + PvT32Nexus + PvT3BaseArbiter + PvT3rdFast
0 | 0:00 | DEBUG | 91%% PvEStormNo + PvT32Nexus + PvT3BaseCarrier + PvT3rdFast
0 | 0:00 | DEBUG | 91%% PvEStormNo + PvT13Nexus + PvT3BaseGateway + PvT3rdFast
0 | 0:00 | DEBUG | 91%% PvEStormYes + PvT32Nexus + PvT3BaseGateway + PvT3rdFast
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT28Nexus + PvT3BaseGateway + PvT3rdObs
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT28Nexus + PvT2BaseReaver + PvT3BaseCarrier
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT28Nexus + PvT2BaseReaver + PvT3BaseArbiter
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT28Nexus + PvT2BaseCarrier + PvT2BaseReaver
0 | 0:00 | DEBUG | 89%% PvEStormYes + PvT28Nexus + PvT2BaseReaver + PvT3BaseGateway
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT32Nexus + PvT3BaseCarrier + PvT3rdObs
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT32Nexus + PvT3BaseArbiter + PvT3rdObs
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT1GateReaver + PvT3BaseGateway + PvT3rdObs
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT13Nexus + PvT3BaseGateway + PvT3rdObs
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT3BaseGateway + PvT3rdObs + PvTProxy2Gate
0 | 0:00 | DEBUG | 89%% PvEStormYes + PvT32Nexus + PvT3BaseGateway + PvT3rdObs
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT2BaseReaver + PvT3BaseArbiter + PvTProxy2Gate
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT2BaseReaver + PvT3BaseCarrier + PvTProxy2Gate
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT1GateReaver + PvT2BaseReaver + PvT3BaseArbiter
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT13Nexus + PvT2BaseReaver + PvT3BaseCarrier
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT13Nexus + PvT2BaseReaver + PvT3BaseArbiter
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT1GateReaver + PvT2BaseReaver + PvT3BaseCarrier
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT13Nexus + PvT2BaseCarrier + PvT2BaseReaver
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT1GateReaver + PvT2BaseCarrier + PvT2BaseReaver
0 | 0:00 | DEBUG | 89%% PvEStormNo + PvT2BaseCarrier + PvT2BaseReaver + PvTProxy2Gate
0 | 0:00 | DEBUG | 89%% PvEStormYes + PvT2BaseCarrier + PvT2BaseReaver + PvT32Nexus
0 | 0:00 | DEBUG | 89%% PvEStormYes + PvT1GateReaver + PvT2BaseReaver + PvT3BaseGateway
0 | 0:00 | DEBUG | 89%% PvEStormYes + PvT2BaseReaver + PvT32Nexus + PvT3BaseCarrier
0 | 0:00 | DEBUG | 89%% PvEStormYes + PvT2BaseReaver + PvT3BaseGateway + PvTProxy2Gate
0 | 0:00 | DEBUG | 89%% PvEStormYes + PvT13Nexus + PvT2BaseReaver + PvT3BaseGateway
0 | 0:00 | DEBUG | 89%% PvEStormYes + PvT2BaseReaver + PvT32Nexus + PvT3BaseArbiter
0 | 0:00 | DEBUG | 87%% PvEStormNo + PvT28Nexus + PvT2BaseGateway
0 | 0:00 | DEBUG | 87%% PvEStormNo + PvT28Nexus + PvT3BaseCarrier + PvT3rdFast
0 | 0:00 | DEBUG | 87%% PvEStormNo + PvT28Nexus + PvT3BaseArbiter + PvT3rdFast
0 | 0:00 | DEBUG | 87%% PvEStormYes + PvT28Nexus + PvT3BaseGateway + PvT3rdFast
0 | 0:00 | DEBUG | 86%% PvEStormNo + PvT13Nexus + PvT2BaseGateway
0 | 0:00 | DEBUG | 86%% PvEStormNo + PvT2BaseGateway + PvTDTExpand
0 | 0:00 | DEBUG | 86%% PvEStormNo + PvT28Nexus + PvT2BaseArbiter
0 | 0:00 | DEBUG | 86%% PvEStormNo + PvT28Nexus + PvT2BaseCarrier
0 | 0:00 | DEBUG | 86%% PvEStormYes + PvT2BaseGateway + PvT32Nexus
0 | 0:00 | DEBUG | 86%% PvEStormNo + PvT13Nexus + PvT3BaseArbiter + PvT3rdFast
0 | 0:00 | DEBUG | 86%% PvEStormNo + PvT13Nexus + PvT3BaseCarrier + PvT3rdFast
0 | 0:00 | DEBUG | 86%% PvEStormNo + PvT3BaseArbiter + PvT3rdFast + PvTDTExpand
0 | 0:00 | DEBUG | 86%% PvEStormNo + PvT3BaseCarrier + PvT3rdFast + PvTDTExpand
0 | 0:00 | DEBUG | 86%% PvEStormNo + PvT3BaseArbiter + PvT3rdFast + PvTProxy2Gate
0 | 0:00 | DEBUG | 86%% PvEStormNo + PvT3BaseCarrier + PvT3rdFast + PvTProxy2Gate
0 | 0:00 | DEBUG | 86%% PvEStormYes + PvT32Nexus + PvT3BaseArbiter + PvT3rdFast
0 | 0:00 | DEBUG | 86%% PvEStormYes + PvT3BaseGateway + PvT3rdFast + PvTDTExpand
0 | 0:00 | DEBUG | 86%% PvEStormYes + PvT3BaseGateway + PvT3rdFast + PvTProxy2Gate
0 | 0:00 | DEBUG | 86%% PvEStormYes + PvT13Nexus + PvT3BaseGateway + PvT3rdFast
0 | 0:00 | DEBUG | 86%% PvEStormYes + PvT32Nexus + PvT3BaseCarrier + PvT3rdFast
0 | 0:00 | DEBUG | 86%% PvEStormNo + PvT13Nexus + PvT2BaseCarrier
0 | 0:00 | DEBUG | 86%% PvEStormNo + PvT2BaseCarrier + PvTProxy2Gate
0 | 0:00 | DEBUG | 86%% PvEStormNo + PvT2BaseCarrier + PvTDTExpand
0 | 0:00 | DEBUG | 86%% PvEStormNo + PvT13Nexus + PvT2BaseArbiter
0 | 0:00 | DEBUG | 86%% PvEStormNo + PvT2BaseArbiter + PvTDTExpand
0 | 0:00 | DEBUG | 86%% PvEStormNo + PvT2BaseArbiter + PvTProxy2Gate
0 | 0:00 | DEBUG | 86%% PvEStormYes + PvT2BaseArbiter + PvT32Nexus
0 | 0:00 | DEBUG | 86%% PvEStormYes + PvT2BaseCarrier + PvT32Nexus
0 | 0:00 | DEBUG | 85%% PvEStormNo + PvT28Nexus + PvT3BaseArbiter + PvT3rdObs
0 | 0:00 | DEBUG | 85%% PvEStormNo + PvT28Nexus + PvT3BaseCarrier + PvT3rdObs
0 | 0:00 | DEBUG | 85%% PvEStormYes + PvT28Nexus + PvT3BaseGateway + PvT3rdObs
0 | 0:00 | DEBUG | 85%% PvEStormYes + PvT28Nexus + PvT2BaseReaver + PvT3BaseArbiter
0 | 0:00 | DEBUG | 85%% PvEStormYes + PvT28Nexus + PvT2BaseReaver + PvT3BaseCarrier
0 | 0:00 | DEBUG | 85%% PvEStormYes + PvT28Nexus + PvT2BaseCarrier + PvT2BaseReaver
0 | 0:00 | DEBUG | 84%% PvEStormNo + PvT13Nexus + PvT3BaseArbiter + PvT3rdObs
0 | 0:00 | DEBUG | 84%% PvEStormNo + PvT3BaseCarrier + PvT3rdObs + PvTProxy2Gate
0 | 0:00 | DEBUG | 84%% PvEStormNo + PvT3BaseArbiter + PvT3rdObs + PvTProxy2Gate
0 | 0:00 | DEBUG | 84%% PvEStormNo + PvT13Nexus + PvT3BaseCarrier + PvT3rdObs
0 | 0:00 | DEBUG | 84%% PvEStormNo + PvT1GateReaver + PvT3BaseCarrier + PvT3rdObs
0 | 0:00 | DEBUG | 84%% PvEStormNo + PvT1GateReaver + PvT3BaseArbiter + PvT3rdObs
0 | 0:00 | DEBUG | 84%% PvEStormYes + PvT13Nexus + PvT3BaseGateway + PvT3rdObs
0 | 0:00 | DEBUG | 84%% PvEStormYes + PvT1GateReaver + PvT3BaseGateway + PvT3rdObs
0 | 0:00 | DEBUG | 84%% PvEStormYes + PvT32Nexus + PvT3BaseCarrier + PvT3rdObs
0 | 0:00 | DEBUG | 84%% PvEStormYes + PvT32Nexus + PvT3BaseArbiter + PvT3rdObs
0 | 0:00 | DEBUG | 84%% PvEStormYes + PvT3BaseGateway + PvT3rdObs + PvTProxy2Gate
0 | 0:00 | DEBUG | 84%% PvEStormYes + PvT1GateReaver + PvT2BaseCarrier + PvT2BaseReaver
0 | 0:00 | DEBUG | 84%% PvEStormYes + PvT2BaseCarrier + PvT2BaseReaver + PvTProxy2Gate
0 | 0:00 | DEBUG | 84%% PvEStormYes + PvT13Nexus + PvT2BaseCarrier + PvT2BaseReaver
0 | 0:00 | DEBUG | 84%% PvEStormYes + PvT2BaseReaver + PvT3BaseArbiter + PvTProxy2Gate
0 | 0:00 | DEBUG | 84%% PvEStormYes + PvT13Nexus + PvT2BaseReaver + PvT3BaseCarrier
0 | 0:00 | DEBUG | 84%% PvEStormYes + PvT2BaseReaver + PvT3BaseCarrier + PvTProxy2Gate
0 | 0:00 | DEBUG | 84%% PvEStormYes + PvT1GateReaver + PvT2BaseReaver + PvT3BaseCarrier
0 | 0:00 | DEBUG | 84%% PvEStormYes + PvT1GateReaver + PvT2BaseReaver + PvT3BaseArbiter
0 | 0:00 | DEBUG | 84%% PvEStormYes + PvT13Nexus + PvT2BaseReaver + PvT3BaseArbiter
0 | 0:00 | DEBUG | 82%% PvEStormYes + PvT28Nexus + PvT3BaseCarrier + PvT3rdFast
0 | 0:00 | DEBUG | 82%% PvEStormYes + PvT28Nexus + PvT3BaseArbiter + PvT3rdFast
0 | 0:00 | DEBUG | 81%% PvEStormYes + PvT3BaseArbiter + PvT3rdFast + PvTProxy2Gate
0 | 0:00 | DEBUG | 81%% PvEStormYes + PvT13Nexus + PvT3BaseArbiter + PvT3rdFast
0 | 0:00 | DEBUG | 81%% PvEStormYes + PvT3BaseCarrier + PvT3rdFast + PvTDTExpand
0 | 0:00 | DEBUG | 81%% PvEStormYes + PvT3BaseArbiter + PvT3rdFast + PvTDTExpand
0 | 0:00 | DEBUG | 81%% PvEStormYes + PvT3BaseCarrier + PvT3rdFast + PvTProxy2Gate
0 | 0:00 | DEBUG | 81%% PvEStormYes + PvT13Nexus + PvT3BaseCarrier + PvT3rdFast
0 | 0:00 | DEBUG | 81%% PvEStormYes + PvT28Nexus + PvT2BaseGateway
0 | 0:00 | DEBUG | 80%% PvEStormYes + PvT28Nexus + PvT2BaseCarrier
0 | 0:00 | DEBUG | 80%% PvEStormYes + PvT28Nexus + PvT2BaseArbiter
0 | 0:00 | DEBUG | 80%% PvEStormYes + PvT13Nexus + PvT2BaseGateway
0 | 0:00 | DEBUG | 80%% PvEStormYes + PvT2BaseGateway + PvTDTExpand
0 | 0:00 | DEBUG | 80%% PvEStormYes + PvT28Nexus + PvT3BaseCarrier + PvT3rdObs
0 | 0:00 | DEBUG | 80%% PvEStormYes + PvT28Nexus + PvT3BaseArbiter + PvT3rdObs
0 | 0:00 | DEBUG | 80%% PvEStormYes + PvT13Nexus + PvT2BaseArbiter
0 | 0:00 | DEBUG | 80%% PvEStormYes + PvT2BaseArbiter + PvTProxy2Gate
0 | 0:00 | DEBUG | 79%% PvEStormYes + PvT2BaseArbiter + PvTDTExpand
0 | 0:00 | DEBUG | 79%% PvEStormYes + PvT13Nexus + PvT3BaseArbiter + PvT3rdObs
0 | 0:00 | DEBUG | 79%% PvEStormYes + PvT13Nexus + PvT3BaseCarrier + PvT3rdObs
0 | 0:00 | DEBUG | 79%% PvEStormYes + PvT1GateReaver + PvT3BaseArbiter + PvT3rdObs
0 | 0:00 | DEBUG | 79%% PvEStormYes + PvT3BaseCarrier + PvT3rdObs + PvTProxy2Gate
0 | 0:00 | DEBUG | 79%% PvEStormYes + PvT3BaseArbiter + PvT3rdObs + PvTProxy2Gate
0 | 0:00 | DEBUG | 79%% PvEStormYes + PvT1GateReaver + PvT3BaseCarrier + PvT3rdObs
0 | 0:00 | DEBUG | 79%% PvEStormYes + PvT2BaseCarrier + PvTDTExpand
0 | 0:00 | DEBUG | 79%% PvEStormYes + PvT2BaseCarrier + PvTProxy2Gate
0 | 0:00 | DEBUG | 79%% PvEStormYes + PvT13Nexus + PvT2BaseCarrier
0 | 0:00 | DEBUG | Opponents is Ecgberht
0 | 0:00 | DEBUG | Rush distances is 5152.0, 5600.0, 5216.0, 5280.0
0 | 0:00 | DEBUG | Playbook is Strategery.TournamentPlaybook$@1670bdb
0 | 0:00 | DEBUG | Policy is Strategery.Selection.StrategySelectionTournament$@1584d47
0 | 0:00 | DEBUG | Enemy race is Terran
0 | 0:00 | DEBUG | Strategy is PvT32Nexus PvT2BaseReaver PvT3BaseGateway PvEStormNo
0 | 0:00 | DEBUG | Our bases is 0
0 | 0:00 | DEBUG | Enemy bases is 0
0 | 0:00 | DEBUG | Our Factories is 0
0 | 0:00 | DEBUG | Our Barracks is 0
0 | 0:00 | DEBUG | Our Gateways is 0
0 | 0:00 | DEBUG | Our Hatcheries is 0
0 | 0:00 | DEBUG | Enemy Factories is 0
0 | 0:00 | DEBUG | Enemy Barracks is 0
0 | 0:00 | DEBUG | Enemy Gateways is 0
0 | 0:00 | DEBUG | Enemy Hatcheries is 0
0 | 0:00 | DEBUG | Safe at home is true
0 | 0:00 | DEBUG | Safe to move out is true
0 | 0:00 | DEBUG | Should attack is false
0 | 0:00 | DEBUG | Status is PvTBasic, GoReaver, GoGateway
0 | 0:00 | DEBUG | Performance danger is false
1 | 0:00 | DEBUG | Our bases changed from 0 to 1
157 | 0:06 | WARN  | TaskPlanning crossed the 55ms threshold, taking 173ms for 177ms total this frame. 
157 | 0:06 | DEBUG | Task queue took @ 177ms, crossing the 55ms threshold. Task durations: 
TaskMicro: 2ms
TaskBattles: 0ms
TaskSquads: 1ms
TaskPlanning: 173ms
TaskAccounting: 0ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
620 | 0:25 | WARN  | TaskPlanning crossed the 55ms threshold, taking 164ms for 169ms total this frame. 
620 | 0:25 | DEBUG | Task queue took @ 170ms, crossing the 55ms threshold. Task durations: 
TaskMicro: 2ms
TaskBattles: 1ms
TaskSquads: 0ms
TaskPlanning: 164ms
TaskGrids: 1ms
TaskFingerprinting: 2ms
TaskGeography: 1ms
TaskAccounting: 0ms
TaskGathering: 1ms
TaskArchitecture: 10ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
1255 | 0:52 | WARN  | Microing Probe crossed the 55ms threshold by taking 655ms considering 
1255 | 0:52 | DEBUG | Task queue took @ 659ms, crossing the 55ms threshold. Task durations: 
TaskMicro: 659ms
TaskPlanning: 23ms
TaskVisualizations: 0ms
2017 | 1:24 | DEBUG | Our Gateways changed from 0 to 1
2058 | 1:25 | DEBUG | Discovered novel enemy unit: Supply Depot
2058 | 1:25 | DEBUG | Supply Depot #143 500/500 [80, 7] [2576, 224] is already complete
2086 | 1:26 | DEBUG | Discovered novel enemy unit: Command Center
2086 | 1:26 | DEBUG | Command Center #11 1500/1500 [85, 7] [2720, 240] is already complete
2087 | 1:26 | DEBUG | Enemy bases changed from 0 to 1
2090 | 1:27 | DEBUG | Discovered novel enemy unit: SCV
2090 | 1:27 | DEBUG | SCV #136 60/60 [81, 3] [2600, 108] projects to arrive in 1048 frames at 2:10
2094 | 1:27 | WARN  | TaskGridEnemyRangeAir crossed the 55ms threshold, taking 1818ms for 1825ms total this frame. 
2094 | 1:27 | DEBUG | Task queue took @ 1825ms, crossing the 55ms threshold. Task durations: 
TaskGridUnits: 0ms
TaskGridEnemyDetection: 0ms
TaskGridEnemyRangeAir: 1818ms
TaskGridEnemyRangeGround: 0ms
TaskGridEnemyRangeAirGround: 0ms
TaskGridEnemyVulnerabilityGround: 0ms
TaskGridPsionicStorm: 0ms
TaskGridBuildable: 0ms
TaskGridBuildableTerrain: 0ms
TaskGridEnemyVision: 0ms
TaskGridFriendlyDetection: 0ms
TaskGridFriendlyVision: 0ms
TaskGridPsi2x2and3x2: 0ms
TaskGridPsi4x3: 0ms
TaskGridAltitudeBonus: 0ms
TaskGridBuildableTownHall: 0ms
TaskGridCreepInitial: 0ms
TaskGridCreep: 0ms
TaskGridMobilityBorder: 0ms
TaskGridMobilityTerrain: 0ms
TaskGridScoutingPathBases: 0ms
TaskGridScoutingPathStartLocations: 0ms
TaskGridWalkable: 0ms
TaskGridWalkableTerrain: 0ms
TaskGridUnwalkableUnits: 0ms
2094 | 1:27 | WARN  | TaskGrids crossed the 55ms threshold, taking 1819ms for 1826ms total this frame. 
2094 | 1:27 | DEBUG | Task queue took @ 1826ms, crossing the 55ms threshold. Task durations: 
TaskBattles: 5ms
TaskMicro: 1ms
TaskSquads: 0ms
TaskGrids: 1819ms
TaskFingerprinting: 2ms
TaskGeography: 1ms
TaskAccounting: 0ms
TaskGathering: 0ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
2996 | 2:04 | DEBUG | Should attack changed from false to true
3079 | 2:08 | WARN  | Microing Probe crossed the 55ms threshold by taking 57ms considering 
3079 | 2:08 | DEBUG | Task queue took @ 63ms, crossing the 55ms threshold. Task durations: 
TaskMicro: 61ms
TaskBattles: 2ms
TaskSquads: 0ms
TaskPlanning: 11ms
TaskGrids: 1ms
TaskGeography: 1ms
TaskAccounting: 0ms
TaskGathering: 0ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
3469 | 2:24 | DEBUG | Fingerprints is 14CC
3470 | 2:24 | DEBUG | Enemy bases changed from 1 to 2
4135 | 2:52 | DEBUG | Enemy Barracks changed from 0 to 1
4138 | 2:52 | DEBUG | Discovered novel enemy unit: Barracks
4138 | 2:52 | DEBUG | Barracks #163 316/1000 [85, 11] [2720, 368] projects to complete in 913 frames at 3:30
4576 | 3:10 | DEBUG | Enemy Barracks changed from 1 to 2
4577 | 3:10 | DEBUG | Fingerprints changed from 14CC to 14CC Bio
4579 | 3:10 | DEBUG | Status changed from PvTBasic, GoReaver, GoGateway to PvTBasic, GoReaver, GoReaver, GoGateway
4730 | 3:17 | DEBUG | Our bases changed from 1 to 2
5342 | 3:42 | WARN  | TaskPlanning crossed the 55ms threshold, taking 78ms for 82ms total this frame. The task was overdue to run this frame.
5342 | 3:42 | DEBUG | Task queue took @ 83ms, crossing the 55ms threshold. Task durations: 
TaskPlanning: 78ms
TaskMicro: 3ms
TaskBattles: 4ms
TaskSquads: 0ms
TaskGrids: 2ms
TaskFingerprinting: 2ms
TaskGeography: 1ms
TaskAccounting: 0ms
TaskGathering: 0ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
5562 | 3:51 | DEBUG | Enemy bases changed from 2 to 1
5729 | 3:58 | DEBUG | Discovered novel enemy unit: Marine
5729 | 3:58 | DEBUG | Marine #175 40/40 [74, 9] [2394, 319] projects to arrive in 1048 frames at 4:42
7071 | 4:54 | DEBUG | Status changed from PvTBasic, GoReaver, GoReaver, GoGateway to PvTBasic, Instant3rd, GoReaver, GoReaver, GoReaver, GoGateway
7719 | 5:21 | DEBUG | Our bases changed from 2 to 3
8275 | 5:44 | DEBUG | Our upgrades is Singularity Charge = 1
8343 | 5:47 | DEBUG | Discovered novel enemy unit: Bunker
8343 | 5:47 | DEBUG | Bunker #219 39/350 [56, 20] [1808, 640] projects to complete in 445 frames at 6:06
9088 | 6:18 | DEBUG | Our Gateways changed from 1 to 2
9166 | 6:21 | DEBUG | Discovered novel enemy unit: Refinery
9166 | 6:21 | DEBUG | Refinery #120 750/750 [85, 2] [2720, 64] is already complete
9180 | 6:22 | DEBUG | Our Gateways changed from 2 to 3
9858 | 6:50 | DEBUG | Our Gateways changed from 3 to 4
10068 | 6:59 | WARN  | TaskPlanning crossed the 55ms threshold, taking 58ms for 60ms total this frame. The task was overdue to run this frame.
10068 | 6:59 | DEBUG | Task queue took @ 61ms, crossing the 55ms threshold. Task durations: 
TaskPlanning: 58ms
TaskMicro: 10ms
TaskSquads: 0ms
TaskGrids: 2ms
TaskFingerprinting: 0ms
TaskGeography: 2ms
TaskAccounting: 0ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
10082 | 7:00 | WARN  | TaskPlanning crossed the 55ms threshold, taking 56ms for 61ms total this frame. The task was overdue to run this frame.
10082 | 7:00 | DEBUG | Task queue took @ 61ms, crossing the 55ms threshold. Task durations: 
TaskPlanning: 56ms
TaskMicro: 10ms
TaskSquads: 0ms
TaskGrids: 2ms
TaskFingerprinting: 0ms
TaskGeography: 1ms
TaskAccounting: 1ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
10190 | 7:04 | DEBUG | Our Gateways changed from 4 to 5
10885 | 7:33 | DEBUG | Enemy bases changed from 1 to 2
11469 | 7:57 | DEBUG | Enemy bases changed from 2 to 1
11626 | 8:04 | WARN  | TaskPlanning crossed the 55ms threshold, taking 70ms for 71ms total this frame. The task was overdue to run this frame.
11626 | 8:04 | DEBUG | Task queue took @ 73ms, crossing the 55ms threshold. Task durations: 
TaskPlanning: 70ms
TaskMicro: 3ms
TaskBattles: 1ms
TaskSquads: 1ms
TaskGrids: 1ms
TaskFingerprinting: 0ms
TaskGeography: 2ms
TaskAccounting: 0ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
11882 | 8:15 | DEBUG | Enemy Barracks changed from 2 to 1
12111 | 8:24 | DEBUG | Our upgrades changed from Singularity Charge = 1 to Singularity Charge = 1, Gravitic Drive = 1
12235 | 8:29 | DEBUG | Enemy Barracks changed from 1 to 0
12354 | 8:34 | DEBUG | Enemy bases changed from 1 to 0
12670 | 8:47 | WARN  | TaskPlanning crossed the 55ms threshold, taking 60ms for 64ms total this frame. The task was overdue to run this frame.
12670 | 8:47 | DEBUG | Task queue took @ 65ms, crossing the 55ms threshold. Task durations: 
TaskPlanning: 60ms
TaskMicro: 4ms
TaskBattles: 1ms
TaskSquads: 0ms
TaskGrids: 2ms
TaskFingerprinting: 0ms
TaskGeography: 1ms
TaskAccounting: 1ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
12681 | 8:48 | DEBUG | Game ended in victory
12681 | 8:48 | DEBUG | Cutoff: 	55ms
Task	Last run	 Run %	Seconds	Avg ms	Max (Recent)	Max (All time)	Extended	Disqualifying
Accounting          		 70%%	0	0	1	13	4	0
Architecture        	XXXXXXXXXX	 5%%	8	13	17	32	65	0
Battles             		 64%%	34	0	1	24	16	0
Camera              		 67%%	0	0	1	1	0	0
Fingerprinting      		 73%%	16	0	1	12	6	0
Gathering           	XXXXXXXXXX	 58%%	6	4	16	20	84	0
Geography           		 70%%	10	1	4	15	101	0
Grids               		 74%%	18	1	3	1819	27	1
Manners             		 67%%	0	0	0	2	0	0
Micro               		 80%%	42	5	14	659	62	2
Planning            	XXX	 27%%	49	32	60	173	749	7
Squads              		 86%%	3	0	1	14	3	0
Visualizations      		 100%%	0	0	1	1	14	0
12681 | 8:48 | DEBUG | Cutoff: 	55ms
Task	Last run	 Run %	Seconds	Avg ms	Max (Recent)	Max (All time)	Extended	Disqualifying
Accounting          		 70%%	0	0	1	13	4	0
Architecture        	XXXXXXXXXX	 5%%	8	13	17	32	65	0
Battles             		 64%%	34	0	1	24	16	0
Camera              		 67%%	0	0	1	1	0	0
Fingerprinting      		 73%%	16	0	1	12	6	0
Gathering           	XXXXXXXXXX	 58%%	6	4	16	20	84	0
Geography           		 70%%	10	1	4	15	101	0
Grids               		 74%%	18	1	3	1819	27	1
Manners             		 67%%	0	0	0	2	0	0
Micro               		 80%%	42	5	14	659	62	2
Planning            	XXX	 27%%	49	32	60	173	749	7
Squads              		 86%%	3	0	1	14	3	0
Visualizations      		 100%%	0	0	1	1	14	0
12681 | 8:48 | DEBUG | Game duration (fastest):    8m 48s
12681 | 8:48 | DEBUG | Game duration (wall clock): 4m 28s
12681 | 8:48 | DEBUG | 
Bot frames over 55ms: 12
Bot frames over 1000ms: 2
Bot frames over 10000ms: 1
12681 | 8:48 | DEBUG | The bot believes its performance, if running synchronously, would be good
12681 | 8:48 | DEBUG | Performance metrics:
Frame duration: After receiving 'frame ready' -> before sending 'frame done':
12,681 samples averaging 17.9 [3.7 - 1,403.4]
>= 5: 12598 samples averaging 18
>= 10: 11952 samples averaging 18.5
>= 15: 8865 samples averaging 20.5
>= 20: 4074 samples averaging 23.6
>= 25: 807 samples averaging 33.2
>= 30: 350 samples averaging 40.9
>= 35: 108 samples averaging 60.5
>= 40: 40 samples averaging 100.1
>= 45: 11 samples averaging 251.4
>= 50: 8 samples averaging 328.2
>= 55: 7 samples averaging 367.7
>= 85: 4 samples averaging 593.5
Frame duration: After receiving 'frame ready' -> after sending 'frame done':
12,681 samples averaging 18 [3.7 - 1,404.9]
>= 5: 12600 samples averaging 18.1
>= 10: 11980 samples averaging 18.6
>= 15: 8947 samples averaging 20.6
>= 20: 4228 samples averaging 23.7
>= 25: 861 samples averaging 33.2
>= 30: 364 samples averaging 41.3
>= 35: 131 samples averaging 57.2
>= 40: 49 samples averaging 90.5
>= 45: 25 samples averaging 137.1
>= 50: 9 samples averaging 298.2
>= 55: 7 samples averaging 368.6
>= 85: 4 samples averaging 595
Frame duration: After receiving 'frame ready' -> after sending 'frame done' (Using GetTickCount()):
12,681 samples averaging 18 [0 - 1,391]
>= 5: 11751 samples averaging 19.4
>= 10: 11751 samples averaging 19.4
>= 15: 11751 samples averaging 19.4
>= 20: 2576 samples averaging 32.8
>= 25: 2576 samples averaging 32.8
>= 30: 2576 samples averaging 32.8
>= 35: 119 samples averaging 65.1
>= 40: 119 samples averaging 65.1
>= 45: 119 samples averaging 65.1
>= 50: 4 samples averaging 590.2
>= 55: 4 samples averaging 590.2
>= 85: 4 samples averaging 590.2
Frame duration: After receiving 'frame ready' -> receiving next 'frame ready':
12,681 samples averaging 20.1 [5 - 1,404.9]
>= 5: 12681 samples averaging 20.1
>= 10: 12279 samples averaging 20.5
>= 15: 10428 samples averaging 21.8
>= 20: 6532 samples averaging 24.3
>= 25: 1431 samples averaging 31.8
>= 30: 584 samples averaging 38.9
>= 35: 231 samples averaging 49.2
>= 40: 74 samples averaging 75.2
>= 45: 33 samples averaging 116.3
>= 50: 10 samples averaging 276.2
>= 55: 8 samples averaging 332
>= 85: 4 samples averaging 595
Frame duration: After receiving 'frame ready' -> receiving next 'frame ready' (Using GetTickCount()):
12,681 samples averaging 20.1 [0 - 1,391]
>= 5: 12183 samples averaging 20.9
>= 10: 12183 samples averaging 20.9
>= 15: 12183 samples averaging 20.9
>= 20: 3811 samples averaging 32.5
>= 25: 3811 samples averaging 32.5
>= 30: 3811 samples averaging 32.5
>= 35: 169 samples averaging 59.9
>= 40: 169 samples averaging 59.9
>= 45: 169 samples averaging 59.9
>= 50: 5 samples averaging 487.8
>= 55: 5 samples averaging 487.8
>= 85: 4 samples averaging 590.2
BWAPI duration: Before sending 'frame done' -> After receiving 'frame ready':
12,681 samples averaging 2.2 [0.1 - 44.9]
>= 1: 12680 samples averaging 2.2
>= 3: 1266 samples averaging 4.2
>= 5: 166 samples averaging 8.3
>= 10: 37 samples averaging 15.9
>= 15: 14 samples averaging 21.2
>= 20: 5 samples averaging 29.5
>= 30: 2 samples averaging 38.3
BWAPI duration: Before sending 'frame done' -> After sending 'frame done':
12,681 samples averaging 0.1 [0 - 12.4]
>= 1: 488 samples averaging 2
>= 3: 46 samples averaging 3.8
>= 5: 2 samples averaging 9.3
>= 10: 1 samples averaging 12.4
BWAPI duration: Before listening for 'frame ready' -> After receiving 'frame ready':
12,682 samples averaging 2.1 [0 - 44.8]
>= 1: 12195 samples averaging 2.1
>= 3: 1069 samples averaging 4.2
>= 5: 135 samples averaging 8.2
>= 10: 26 samples averaging 16.3
>= 15: 10 samples averaging 22.9
>= 20: 5 samples averaging 29.4
>= 30: 2 samples averaging 38.2
Copying frame to buffer:
12,681 samples averaging 1.9 [0.9 - 30.2]
>= 5: 96 samples averaging 9.6
>= 10: 33 samples averaging 14.7
>= 15: 11 samples averaging 18.9
>= 20: 2 samples averaging 28.5
>= 25: 2 samples averaging 28.5
>= 30: 1 samples averaging 30.2
Time holding frame until buffer frees capacity:
1 samples averaging 1,402.3 [1,402.3 - 1,402.3]
>= 0: 1 samples averaging 1,402.3
Frames already buffered when enqueuing a new frame:
12,682 samples averaging 0 [0 - 9]
>= 0: 12682 samples averaging 0
>= 1: 71 samples averaging 1.7
Frames behind real-time when handling events:
12,682 samples averaging 0 [0 - 8]
>= 0: 12682 samples averaging 0
>= 1: 11 samples averaging 4.6
Time flushing side effects:
12,682 samples averaging 0 [0 - 17.1]
>= 1: 3 samples averaging 10.7
>= 3: 2 samples averaging 15
>= 5: 2 samples averaging 15
Duration of bot event handlers:
12,680 samples averaging 17.4 [1 - 1,826.9]
>= 5: 12564 samples averaging 17.5
>= 10: 11844 samples averaging 18.1
>= 15: 8500 samples averaging 20.2
>= 20: 3277 samples averaging 23.8
>= 25: 618 samples averaging 35.5
>= 30: 253 samples averaging 47.2
>= 35: 112 samples averaging 66
>= 40: 49 samples averaging 103.1
>= 45: 33 samples averaging 132.4
>= 50: 16 samples averaging 223.2
>= 55: 11 samples averaging 300.9
>= 85: 5 samples averaging 584.8
Time bot spent idle:
12,682 samples averaging 2.6 [0 - 64.5]
Time client spent waiting for bot:
12,681 samples averaging 15.7 [0 - 632.4]
>= 30: 203 samples averaging 38.7
Excess duration of client sleep:
13,545 samples averaging 0.2 [0 - 603]
>= 1: 276 samples averaging 8
>= 5: 103 samples averaging 17.7
>= 10: 37 samples averaging 37.3
>= 15: 9 samples averaging 115.3
>= 20: 7 samples averaging 144
>= 25: 6 samples averaging 164.5
>= 30: 5 samples averaging 192
>= 35: 4 samples averaging 231.5
>= 40: 4 samples averaging 231.5
>= 45: 4 samples averaging 231.5
>= 50: 3 samples averaging 292.7
>= 55: 3 samples averaging 292.7
>= 85: 3 samples averaging 292.7
Positive timer discrepancy compared to BWAPI:
6,022 samples averaging 4.7 [1 - 31]
>= 1: 6022 samples averaging 4.7
>= 2: 5288 samples averaging 5.2
>= 3: 4559 samples averaging 5.7
>= 4: 3602 samples averaging 6.4
>= 5: 2329 samples averaging 7.7
>= 10: 680 samples averaging 11.7
>= 15: 23 samples averaging 16.1
>= 20: 1 samples averaging 31
>= 25: 1 samples averaging 31
>= 30: 1 samples averaging 31
Negative timer discrepancy compared to BWAPI:
5,787 samples averaging 6 [1 - 21]
>= 1: 5787 samples averaging 6
>= 2: 4914 samples averaging 6.9
>= 3: 4173 samples averaging 7.8
>= 4: 3533 samples averaging 8.6
>= 5: 3007 samples averaging 9.5
>= 10: 1652 samples averaging 11.8
>= 15: 87 samples averaging 15.3
>= 20: 2 samples averaging 20.5
Number of events received from BWAPI:
12,682 samples averaging 1.2 [0 - 114]
>= 1: 12164 samples averaging 1.2
>= 2: 763 samples averaging 4.3
>= 3: 664 samples averaging 4.6
>= 4: 351 samples averaging 6
>= 5: 138 samples averaging 9.2
>= 6: 73 samples averaging 12.8
>= 8: 43 samples averaging 17.2
>= 10: 35 samples averaging 19.1
>= 15: 19 samples averaging 25.9
>= 20: 10 samples averaging 34.5
Number of events received from BWAPI, multiplied by the receive-to-sent duration of that frame:
12,682 samples averaging 20.6 [0 - 1,404.9]
>= 5: 12084 samples averaging 21.6
>= 10: 11493 samples averaging 22.3
>= 15: 8695 samples averaging 25.4
>= 20: 4386 samples averaging 32.9
>= 25: 1453 samples averaging 56.5
>= 30: 1027 samples averaging 68.6
>= 35: 802 samples averaging 78.8
>= 40: 692 samples averaging 85.4
>= 45: 615 samples averaging 90.8
>= 50: 554 samples averaging 95.6
>= 55: 499 samples averaging 100.3
>= 85: 174 samples averaging 162.2
Number of events received from BWAPI, multiplied by the receive-to-sent duration of that frame (Using GetTickCount()):
12,682 samples averaging 20.6 [0 - 1,457]
>= 5: 11251 samples averaging 23.2
>= 10: 11251 samples averaging 23.2
>= 15: 11251 samples averaging 23.2
>= 20: 2926 samples averaging 44.8
>= 25: 2926 samples averaging 44.8
>= 30: 2926 samples averaging 44.8
>= 35: 736 samples averaging 85
>= 40: 736 samples averaging 85
>= 45: 736 samples averaging 85
>= 50: 424 samples averaging 113.1
>= 55: 424 samples averaging 113.1
>= 85: 205 samples averaging 164.1