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 Roadkill at (4)Roadkill.scm
0 | 0:00 | DEBUG | Strategery.Selection.StrategySelectionRecommended@325c68 has finished recommended strategy phase in game 149 of 5; will fall back to StrategySelectionGreedy
0 | 0:00 | DEBUG | Game start time:  Sat Oct 17 21:17:55 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:  752 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	WorkersKill	WorkersSpread	WorkersUnite	PvEStormYes	WorkersRaze	PvP2Gate1012Goon	PvPRobo	PvPProxy2Gate	PvP2Gate1012DT	PvP4GateGoon	PvP3GateGoon	PvP1ZealotExpand	PvP2GateDTExpand	PvP3rdBaseSlow	PvP3rdBaseFast
0 | 0:00 | DEBUG | #Games	0.0	0.0	0.0	0.0	0.0	0.0	12.0	63.0	8.0	11.0	16.0	9.0	20.0	10.0	131.0	18.0
0 | 0:00 | DEBUG | #Wtd	0.0	0.0	0.0	0.0	0.0	0.0	6.5	29.6	2.9	4.0	4.9	3.1	8.2	3.3	56.9	5.6
0 | 0:00 | DEBUG | #WtdWins	0.0	0.0	0.0	0.0	0.0	0.0	1.8	7.6	0.0	0.3	0.5	0.0	1.3	0.0	11.5	0.0
0 | 0:00 | DEBUG | WinPct	0%%	0%%	0%%	0%%	0%%	0%%	28%%	25%%	0%%	6%%	10%%	0%%	15%%	0%%	20%%	0%%
0 | 0:00 | DEBUG | WinEst	80%%	80%%	80%%	80%%	80%%	80%%	38%%	28%%	27%%	26%%	26%%	26%%	25%%	25%%	21%%	16%%
0 | 0:00 | DEBUG | Strategy interest
0 | 0:00 | DEBUG | 28%% PvP2Gate1012Goon + PvP3rdBaseSlow
0 | 0:00 | DEBUG | 25%% PvP2Gate1012Goon + PvP3rdBaseFast
0 | 0:00 | DEBUG | 24%% PvP3rdBaseSlow + PvPRobo
0 | 0:00 | DEBUG | 24%% PvP2Gate1012DT + PvP3rdBaseSlow
0 | 0:00 | DEBUG | 23%% PvP3rdBaseSlow + PvP4GateGoon
0 | 0:00 | DEBUG | 23%% PvP3GateGoon + PvP3rdBaseSlow
0 | 0:00 | DEBUG | 23%% PvP1ZealotExpand + PvP3rdBaseSlow
0 | 0:00 | DEBUG | 23%% PvP2GateDTExpand + PvP3rdBaseSlow
0 | 0:00 | DEBUG | 21%% PvP3rdBaseFast + PvPRobo
0 | 0:00 | DEBUG | 21%% PvP2Gate1012DT + PvP3rdBaseFast
0 | 0:00 | DEBUG | 21%% PvP3rdBaseFast + PvP4GateGoon
0 | 0:00 | DEBUG | 21%% PvP3GateGoon + PvP3rdBaseFast
0 | 0:00 | DEBUG | 20%% PvP1ZealotExpand + PvP3rdBaseFast
0 | 0:00 | DEBUG | 20%% PvP2GateDTExpand + PvP3rdBaseFast
0 | 0:00 | DEBUG | Opponents is Stardust
0 | 0:00 | DEBUG | Rush distances is 5088.0, 6528.0, 6496.0, 6368.0, 5216.0, 5280.0, 5312.0
0 | 0:00 | DEBUG | Playbook is Strategery.TournamentPlaybook$@11bdd3b
0 | 0:00 | DEBUG | Policy is Strategery.Selection.StrategySelectionTournament$@1ce375
0 | 0:00 | DEBUG | Enemy race is Protoss
0 | 0:00 | DEBUG | Strategy is PvP2Gate1012Goon PvP3rdBaseSlow
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 PvP2Gate1012GoonOrDT
0 | 0:00 | DEBUG | Performance danger is false
1 | 0:00 | DEBUG | Our bases changed from 0 to 1
327 | 0:13 | DEBUG | Task queue took @ 243ms, crossing the 55ms threshold. Task durations: 
TaskMicro: 242ms
TaskBattles: 0ms
TaskSquads: 1ms
TaskPlanning: 5ms
TaskGrids: 1ms
TaskAccounting: 0ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
714 | 0:29 | WARN  | TaskPlanning crossed the 55ms threshold, taking 169ms for 172ms total this frame. 
714 | 0:29 | DEBUG | Task queue took @ 172ms, crossing the 55ms threshold. Task durations: 
TaskMicro: 1ms
TaskBattles: 1ms
TaskSquads: 0ms
TaskPlanning: 169ms
TaskGrids: 2ms
TaskFingerprinting: 3ms
TaskGeography: 1ms
TaskAccounting: 0ms
TaskGathering: 1ms
TaskArchitecture: 4ms
TaskVisualizations: 0ms
1152 | 0:48 | WARN  | Microing Probe crossed the 55ms threshold by taking 881ms considering 
1152 | 0:48 | DEBUG | Task queue took @ 884ms, crossing the 55ms threshold. Task durations: 
TaskMicro: 884ms
TaskBattles: 1ms
TaskSquads: 0ms
TaskPlanning: 7ms
TaskGrids: 1ms
TaskFingerprinting: 3ms
TaskGeography: 1ms
TaskAccounting: 0ms
TaskGathering: 0ms
TaskArchitecture: 3ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
1897 | 1:19 | DEBUG | Our Gateways changed from 0 to 1
2495 | 1:43 | DEBUG | Discovered novel enemy unit: Probe
2495 | 1:43 | DEBUG | Probe #146 20/20 (20/20) [43, 86] [1406, 2762] projects to arrive in 729 frames at 2:14
2497 | 1:44 | WARN  | TaskGridEnemyRangeAir crossed the 55ms threshold, taking 1896ms for 1910ms total this frame. 
2497 | 1:44 | DEBUG | Task queue took @ 1910ms, crossing the 55ms threshold. Task durations: 
TaskGridUnits: 0ms
TaskGridEnemyDetection: 0ms
TaskGridEnemyRangeAir: 1896ms
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
2497 | 1:44 | WARN  | TaskGrids crossed the 55ms threshold, taking 1896ms for 1910ms total this frame. 
2497 | 1:44 | DEBUG | Task queue took @ 1911ms, crossing the 55ms threshold. Task durations: 
TaskBattles: 7ms
TaskMicro: 1ms
TaskSquads: 0ms
TaskPlanning: 6ms
TaskGrids: 1896ms
TaskGeography: 2ms
TaskAccounting: 0ms
TaskGathering: 1ms
TaskArchitecture: 2ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 1ms
2525 | 1:45 | DEBUG | Our Gateways changed from 1 to 2
2874 | 1:59 | DEBUG | Should attack changed from false to true
3040 | 2:06 | DEBUG | Discovered novel enemy unit: Pylon
3040 | 2:06 | DEBUG | Pylon #170 38/300 (39/300) [119, 55] [3808, 1760] projects to complete in 436 frames at 2:24
3106 | 2:09 | DEBUG | Fingerprints is 1GateCore GatewayFirst
3106 | 2:09 | DEBUG | Discovered novel enemy unit: Assimilator
3106 | 2:09 | DEBUG | Assimilator #24 450/450 (450/450) [119, 62] [3808, 1984] projects to complete in -76604 frames at -51:-2
3257 | 2:15 | DEBUG | Enemy Gateways changed from 0 to 1
3257 | 2:15 | DEBUG | Discovered novel enemy unit: Gateway
3257 | 2:15 | DEBUG | Gateway #162 500/500 (500/500) [115, 70] [3680, 2256] is already complete
3496 | 2:25 | DEBUG | Discovered novel enemy unit: Nexus
3496 | 2:25 | DEBUG | Nexus #5 750/750 (750/750) [119, 67] [3808, 2160] is already complete
3497 | 2:25 | DEBUG | Enemy bases changed from 0 to 1
3531 | 2:27 | WARN  | Microing Zealot crossed the 55ms threshold by taking 60ms considering 
3531 | 2:27 | DEBUG | Task queue took @ 62ms, crossing the 55ms threshold. Task durations: 
TaskMicro: 61ms
TaskBattles: 4ms
TaskSquads: 2ms
TaskPlanning: 6ms
TaskGrids: 2ms
TaskGeography: 1ms
TaskVisualizations: 0ms
3731 | 2:35 | DEBUG | Enemy Gateways changed from 1 to 2
4354 | 3:01 | DEBUG | Discovered novel enemy unit: Zealot
4354 | 3:01 | DEBUG | Zealot #175 100/100 (60/60) [117, 56] [3756, 1792] projects to arrive in 1216 frames at 3:52
5184 | 3:36 | DEBUG | Our Gateways changed from 2 to 3
7231 | 5:01 | DEBUG | Discovered novel enemy unit: Photon Cannon
7231 | 5:01 | DEBUG | Photon Cannon #223 11/100 (11/100) [119, 53] [3808, 1696] projects to complete in 740 frames at 5:32
7517 | 5:13 | DEBUG | Our Gateways changed from 3 to 4
7772 | 5:23 | DEBUG | Fingerprints changed from 1GateCore GatewayFirst to 1GateCore GatewayFirst EarlyForge
8036 | 5:34 | WARN  | Microing Dragoon crossed the 55ms threshold by taking 52ms considering 
8036 | 5:34 | DEBUG | Task queue took @ 57ms, crossing the 55ms threshold. Task durations: 
TaskMicro: 55ms
TaskSquads: 5ms
TaskGrids: 4ms
TaskGeography: 1ms
TaskAccounting: 0ms
TaskGathering: 1ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
8517 | 5:54 | DEBUG | Our upgrades is Singularity Charge = 1
8581 | 5:57 | DEBUG | Enemy Gateways changed from 2 to 3
8587 | 5:57 | DEBUG | Discovered novel enemy unit: Dragoon
8587 | 5:57 | DEBUG | Dragoon #229 100/100 (80/80) [114, 54] [3677, 1729] projects to arrive in 980 frames at 6:38
9013 | 6:15 | DEBUG | Our bases changed from 1 to 2
9020 | 6:15 | DEBUG | Should attack changed from true to false
9020 | 6:15 | DEBUG | Status changed from PvP2Gate1012GoonOrDT to PvPLateGame
9025 | 6:16 | DEBUG | Task queue took @ 84ms, crossing the 55ms threshold. Task durations: 
TaskMicro: 82ms
TaskSquads: 2ms
TaskGrids: 4ms
TaskFingerprinting: 2ms
TaskGeography: 2ms
TaskAccounting: 0ms
TaskGathering: 1ms
TaskArchitecture: 6ms
TaskVisualizations: 0ms
10115 | 7:01 | DEBUG | Our Gateways changed from 4 to 5
10659 | 7:24 | WARN  | Microing Dragoon crossed the 55ms threshold by taking 44ms considering 
10659 | 7:24 | DEBUG | Task queue took @ 60ms, crossing the 55ms threshold. Task durations: 
TaskMicro: 58ms
TaskBattles: 18ms
TaskVisualizations: 0ms
11344 | 7:52 | DEBUG | Should attack changed from false to true
11399 | 7:54 | DEBUG | Enemy upgrades is Singularity Charge = 1
11400 | 7:55 | DEBUG | Fingerprints changed from 1GateCore GatewayFirst EarlyForge to 1GateCore GatewayFirst EarlyForge DragoonRange
11783 | 8:10 | DEBUG | Safe to move out changed from true to false
11790 | 8:11 | DEBUG | Should attack changed from true to false
11808 | 8:12 | DEBUG | Safe to move out changed from false to true
11814 | 8:12 | DEBUG | Should attack changed from false to true
13715 | 9:31 | DEBUG | Our Gateways changed from 5 to 6
14440 | 10:01 | DEBUG | Safe to move out changed from true to false
14447 | 10:01 | DEBUG | Should attack changed from true to false
14756 | 10:14 | WARN  | TaskPlanning crossed the 55ms threshold, taking 65ms for 67ms total this frame. 
14756 | 10:14 | DEBUG | Task queue took @ 68ms, crossing the 55ms threshold. Task durations: 
TaskPlanning: 65ms
TaskMicro: 17ms
TaskVisualizations: 0ms
15036 | 10:26 | DEBUG | Our Gateways changed from 6 to 7
15102 | 10:29 | WARN  | TaskPlanning crossed the 55ms threshold, taking 55ms for 57ms total this frame. The task was overdue to run this frame.
15102 | 10:29 | DEBUG | Task queue took @ 57ms, crossing the 55ms threshold. Task durations: 
TaskPlanning: 55ms
TaskMicro: 16ms
TaskFingerprinting: 0ms
TaskGeography: 1ms
TaskVisualizations: 0ms
15378 | 10:40 | WARN  | Microing Dragoon crossed the 55ms threshold by taking 56ms considering 
15378 | 10:40 | DEBUG | Task queue took @ 70ms, crossing the 55ms threshold. Task durations: 
TaskMicro: 65ms
TaskBattles: 16ms
TaskVisualizations: 3ms
15682 | 10:53 | DEBUG | Should attack changed from false to true
16341 | 11:20 | DEBUG | Safe at home changed from true to false
16432 | 11:24 | DEBUG | Safe at home changed from false to true
16725 | 11:36 | DEBUG | Our upgrades changed from Singularity Charge = 1 to Singularity Charge = 1, Leg Enhancements = 1
17142 | 11:54 | DEBUG | Enemy bases changed from 1 to 2
17246 | 11:58 | DEBUG | Safe to move out changed from false to true
17800 | 12:21 | DEBUG | Safe to move out changed from true to false
17817 | 12:22 | DEBUG | Our Gateways changed from 7 to 8
17821 | 12:22 | DEBUG | Safe to move out changed from false to true
17841 | 12:23 | DEBUG | Enemy Gateways changed from 3 to 5
17855 | 12:23 | DEBUG | Enemy Gateways changed from 5 to 6
18252 | 12:40 | DEBUG | Our bases changed from 2 to 3
18339 | 12:44 | DEBUG | Enemy bases changed from 2 to 1
18789 | 13:02 | WARN  | TaskPlanning crossed the 55ms threshold, taking 52ms for 55ms total this frame. The task was overdue to run this frame.
18789 | 13:02 | DEBUG | Task queue took @ 56ms, crossing the 55ms threshold. Task durations: 
TaskPlanning: 52ms
TaskMicro: 15ms
TaskFingerprinting: 0ms
TaskGeography: 1ms
TaskAccounting: 0ms
TaskGathering: 1ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
19009 | 13:12 | DEBUG | Our techs is Psionic Storm
19010 | 13:12 | WARN  | Microing High Templar crossed the 55ms threshold by taking 43ms considering 
19010 | 13:12 | DEBUG | Task queue took @ 56ms, crossing the 55ms threshold. Task durations: 
TaskMicro: 54ms
TaskBattles: 18ms
TaskVisualizations: 0ms
19121 | 13:16 | DEBUG | Our upgrades changed from Singularity Charge = 1, Leg Enhancements = 1 to Singularity Charge = 1, Protoss Ground Weapons = 1, Leg Enhancements = 1
19980 | 13:52 | DEBUG | Discovered novel enemy unit: Observer
19980 | 13:52 | DEBUG | Observer #420 40/40 (20/20) [69, 46] [2237, 1485] projects to arrive in 853 frames at 14:28
20909 | 14:31 | WARN  | TaskPlanning crossed the 55ms threshold, taking 55ms for 58ms total this frame. The task was overdue to run this frame.
20909 | 14:31 | DEBUG | Task queue took @ 59ms, crossing the 55ms threshold. Task durations: 
TaskPlanning: 55ms
TaskMicro: 1ms
TaskSquads: 1ms
TaskGrids: 7ms
TaskFingerprinting: 0ms
TaskGeography: 1ms
TaskAccounting: 0ms
TaskGathering: 2ms
TaskArchitecture: 2ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
21303 | 14:47 | WARN  | TaskPlanning crossed the 55ms threshold, taking 65ms for 68ms total this frame. 
21303 | 14:47 | DEBUG | Task queue took @ 69ms, crossing the 55ms threshold. Task durations: 
TaskPlanning: 65ms
TaskMicro: 2ms
TaskSquads: 1ms
TaskGrids: 5ms
TaskFingerprinting: 0ms
TaskGeography: 2ms
TaskAccounting: 0ms
TaskGathering: 2ms
TaskArchitecture: 3ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
21314 | 14:48 | WARN  | TaskPlanning crossed the 55ms threshold, taking 56ms for 62ms total this frame. 
21314 | 14:48 | DEBUG | Task queue took @ 63ms, crossing the 55ms threshold. Task durations: 
TaskPlanning: 56ms
TaskMicro: 4ms
TaskSquads: 2ms
TaskGrids: 4ms
TaskFingerprinting: 0ms
TaskGeography: 2ms
TaskAccounting: 0ms
TaskGathering: 2ms
TaskArchitecture: 2ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
21540 | 14:57 | WARN  | TaskPlanning crossed the 55ms threshold, taking 59ms for 62ms total this frame. 
21540 | 14:57 | DEBUG | Task queue took @ 62ms, crossing the 55ms threshold. Task durations: 
TaskPlanning: 59ms
TaskMicro: 4ms
TaskSquads: 1ms
TaskGrids: 4ms
TaskFingerprinting: 0ms
TaskGeography: 1ms
TaskAccounting: 0ms
TaskGathering: 2ms
TaskArchitecture: 2ms
TaskManners: 0ms
TaskCamera: 0ms
TaskVisualizations: 0ms
22383 | 15:32 | WARN  | TaskPlanning crossed the 55ms threshold, taking 57ms for 60ms total this frame. The task was overdue to run this frame.
22383 | 15:32 | DEBUG | Task queue took @ 60ms, crossing the 55ms threshold. Task durations: 
TaskPlanning: 57ms
TaskMicro: 17ms
TaskVisualizations: 0ms
22526 | 15:38 | DEBUG | Discovered novel enemy unit: Robotics Facility
22526 | 15:38 | DEBUG | Robotics Facility #380 500/500 (500/500) [113, 66] [3632, 2112] is already complete
22592 | 15:41 | DEBUG | Discovered novel enemy unit: Observatory
22592 | 15:41 | DEBUG | Observatory #405 250/250 (250/250) [110, 66] [3536, 2112] is already complete
22654 | 15:43 | DEBUG | Discovered novel enemy unit: Cybernetics Core
22654 | 15:43 | DEBUG | Cybernetics Core #216 500/500 (500/500) [110, 68] [3536, 2176] is already complete
22654 | 15:43 | DEBUG | Discovered novel enemy unit: Forge
22654 | 15:43 | DEBUG | Forge #212 550/550 (550/550) [113, 68] [3632, 2176] is already complete
22756 | 15:48 | WARN  | TaskPlanning crossed the 55ms threshold, taking 53ms for 66ms total this frame. The task was overdue to run this frame.
22756 | 15:48 | DEBUG | Task queue took @ 67ms, crossing the 55ms threshold. Task durations: 
TaskPlanning: 53ms
TaskMicro: 17ms
TaskVisualizations: 0ms
23314 | 16:11 | DEBUG | Our Gateways changed from 8 to 9
23492 | 16:18 | WARN  | TaskPlanning crossed the 55ms threshold, taking 63ms for 66ms total this frame. 
23492 | 16:18 | DEBUG | Task queue took @ 67ms, crossing the 55ms threshold. Task durations: 
TaskPlanning: 63ms
TaskMicro: 18ms
TaskVisualizations: 0ms
23797 | 16:31 | DEBUG | Enemy bases changed from 1 to 0
24012 | 16:40 | DEBUG | Enemy Gateways changed from 6 to 5
24261 | 16:50 | DEBUG | Enemy Gateways changed from 5 to 4
24405 | 16:56 | DEBUG | Enemy Gateways changed from 4 to 3
24476 | 16:59 | DEBUG | Enemy Gateways changed from 3 to 2
24618 | 17:05 | DEBUG | Our Gateways changed from 9 to 10
25098 | 17:25 | DEBUG | Enemy Gateways changed from 2 to 1
25186 | 17:29 | DEBUG | Enemy Gateways changed from 1 to 0
25236 | 17:31 | DEBUG | Game ended in victory
25236 | 17:31 | DEBUG | Cutoff: 	55ms
Task	Last run	 Run %	Seconds	Avg ms	Max (Recent)	Max (All time)	Extended	Disqualifying
Accounting          		 49%%	1	0	1	5	1	0
Architecture        		 31%%	18	2	4	29	37	0
Battles             		 44%%	91	1	2	35	83	0
Camera              		 42%%	0	0	0	1	0	0
Fingerprinting      		 48%%	26	0	0	17	41	0
Gathering           		 43%%	10	2	3	16	332	0
Geography           		 48%%	18	1	2	15	549	0
Grids               		 45%%	32	1	2	1896	42	1
Manners             		 42%%	0	0	0	1	0	0
Micro               		 68%%	131	7	13	884	1422	8
Planning            	XXXXX	 26%%	74	33	39	169	1342	11
Squads              	X	 55%%	24	6	16	21	42	0
Visualizations      		 100%%	0	0	0	4	68	0
25236 | 17:31 | DEBUG | Cutoff: 	55ms
Task	Last run	 Run %	Seconds	Avg ms	Max (Recent)	Max (All time)	Extended	Disqualifying
Accounting          		 49%%	1	0	1	5	1	0
Architecture        		 31%%	18	2	4	29	37	0
Battles             		 44%%	91	1	2	35	83	0
Camera              		 42%%	0	0	0	1	0	0
Fingerprinting      		 48%%	26	0	0	17	41	0
Gathering           		 43%%	10	2	3	16	332	0
Geography           		 48%%	18	1	2	15	549	0
Grids               		 45%%	32	1	2	1896	42	1
Manners             		 42%%	0	0	0	1	0	0
Micro               		 68%%	131	7	13	884	1422	8
Planning            	XXXXX	 26%%	74	33	39	169	1342	11
Squads              	X	 55%%	24	6	16	21	42	0
Visualizations      		 100%%	0	0	0	4	68	0
25236 | 17:31 | DEBUG | Game duration (fastest):    17m 31s
25236 | 17:31 | DEBUG | Game duration (wall clock): 10m 24s
25236 | 17:31 | DEBUG | 
Bot frames over 55ms: 24
Bot frames over 1000ms: 2
Bot frames over 10000ms: 1
25236 | 17:31 | DEBUG | The bot believes its performance, if running synchronously, would be good
25236 | 17:31 | DEBUG | Performance metrics:
Frame duration: After receiving 'frame ready' -> before sending 'frame done':
25,236 samples averaging 21.2 [6.2 - 1,449.1]
>= 5: 25236 samples averaging 21.2
>= 10: 25172 samples averaging 21.3
>= 15: 24331 samples averaging 21.6
>= 20: 17443 samples averaging 22.8
>= 25: 2231 samples averaging 30.9
>= 30: 813 samples averaging 37.9
>= 35: 299 samples averaging 47.8
>= 40: 103 samples averaging 68.2
>= 45: 20 samples averaging 176.6
>= 50: 8 samples averaging 371.4
>= 55: 8 samples averaging 371.4
>= 85: 4 samples averaging 680.3
Frame duration: After receiving 'frame ready' -> after sending 'frame done':
25,236 samples averaging 21.4 [6.3 - 1,449.2]
>= 5: 25236 samples averaging 21.4
>= 10: 25177 samples averaging 21.5
>= 15: 24372 samples averaging 21.7
>= 20: 17784 samples averaging 23
>= 25: 2518 samples averaging 30.8
>= 30: 893 samples averaging 37.8
>= 35: 343 samples averaging 46.7
>= 40: 116 samples averaging 65.7
>= 45: 27 samples averaging 143.4
>= 50: 8 samples averaging 372.4
>= 55: 8 samples averaging 372.4
>= 85: 4 samples averaging 682
Frame duration: After receiving 'frame ready' -> after sending 'frame done' (Using GetTickCount()):
25,236 samples averaging 21.4 [0 - 1,438]
>= 5: 25101 samples averaging 21.5
>= 10: 25101 samples averaging 21.5
>= 15: 25101 samples averaging 21.5
>= 20: 9037 samples averaging 32
>= 25: 9037 samples averaging 32
>= 30: 9037 samples averaging 32
>= 35: 264 samples averaging 56.5
>= 40: 264 samples averaging 56.5
>= 45: 264 samples averaging 56.5
>= 50: 6 samples averaging 471.2
>= 55: 6 samples averaging 471.2
>= 85: 4 samples averaging 675.8
Frame duration: After receiving 'frame ready' -> receiving next 'frame ready':
25,236 samples averaging 24.1 [8.4 - 1,451.6]
>= 5: 25236 samples averaging 24.1
>= 10: 25226 samples averaging 24.1
>= 15: 24928 samples averaging 24.3
>= 20: 22345 samples averaging 24.9
>= 25: 7656 samples averaging 28.7
>= 30: 1619 samples averaging 36.5
>= 35: 619 samples averaging 43.7
>= 40: 212 samples averaging 56.6
>= 45: 76 samples averaging 82.4
>= 50: 15 samples averaging 227.1
>= 55: 11 samples averaging 290.9
>= 85: 4 samples averaging 683.3
Frame duration: After receiving 'frame ready' -> receiving next 'frame ready' (Using GetTickCount()):
25,236 samples averaging 24.1 [0 - 1,454]
>= 5: 25192 samples averaging 24.2
>= 10: 25192 samples averaging 24.2
>= 15: 25192 samples averaging 24.2
>= 20: 13178 samples averaging 31.9
>= 25: 13178 samples averaging 31.9
>= 30: 13178 samples averaging 31.9
>= 35: 417 samples averaging 53.3
>= 40: 417 samples averaging 53.3
>= 45: 417 samples averaging 53.3
>= 50: 11 samples averaging 289.7
>= 55: 11 samples averaging 289.7
>= 85: 4 samples averaging 679.8
BWAPI duration: Before sending 'frame done' -> After receiving 'frame ready':
25,236 samples averaging 2.8 [0.1 - 42.6]
>= 1: 25235 samples averaging 2.8
>= 3: 7590 samples averaging 3.9
>= 5: 835 samples averaging 6.7
>= 10: 44 samples averaging 15.1
>= 15: 13 samples averaging 22.9
>= 20: 5 samples averaging 33
>= 30: 2 samples averaging 42.6
BWAPI duration: Before sending 'frame done' -> After sending 'frame done':
25,236 samples averaging 0.1 [0 - 10.5]
>= 1: 1025 samples averaging 2.5
>= 3: 242 samples averaging 3.7
>= 5: 20 samples averaging 6.1
>= 10: 1 samples averaging 10.5
BWAPI duration: Before listening for 'frame ready' -> After receiving 'frame ready':
25,236 samples averaging 2.7 [0 - 42.5]
>= 1: 24370 samples averaging 2.8
>= 3: 6537 samples averaging 3.9
>= 5: 744 samples averaging 6.6
>= 10: 33 samples averaging 15.9
>= 15: 11 samples averaging 24.1
>= 20: 5 samples averaging 32.9
>= 30: 2 samples averaging 42.5
Copying frame to buffer:
25,236 samples averaging 1.8 [0.9 - 21.9]
>= 5: 130 samples averaging 9.3
>= 10: 45 samples averaging 13.6
>= 15: 9 samples averaging 18.2
>= 20: 3 samples averaging 21.1
Time holding frame until buffer frees capacity:
1 samples averaging 1,447 [1,447 - 1,447]
>= 0: 1 samples averaging 1,447
Frames already buffered when enqueuing a new frame:
25,237 samples averaging 0 [0 - 9]
>= 0: 25237 samples averaging 0
>= 1: 185 samples averaging 1.3
Frames behind real-time when handling events:
25,237 samples averaging 0 [0 - 8]
>= 0: 25237 samples averaging 0
>= 1: 11 samples averaging 4.7
Time flushing side effects:
25,237 samples averaging 0 [0 - 13.3]
>= 1: 4 samples averaging 8.9
>= 3: 3 samples averaging 11.2
>= 5: 3 samples averaging 11.2
Duration of bot event handlers:
25,235 samples averaging 20.8 [1 - 1,912.2]
>= 5: 25223 samples averaging 20.8
>= 10: 25127 samples averaging 20.8
>= 15: 24165 samples averaging 21.1
>= 20: 15919 samples averaging 22.5
>= 25: 1461 samples averaging 33.1
>= 30: 549 samples averaging 43.6
>= 35: 278 samples averaging 54.6
>= 40: 166 samples averaging 66.4
>= 45: 106 samples averaging 80.3
>= 50: 53 samples averaging 113.5
>= 55: 23 samples averaging 193.7
>= 85: 5 samples averaging 661.7
Time bot spent idle:
25,237 samples averaging 3.3 [0 - 45.9]
Time client spent waiting for bot:
25,236 samples averaging 19.2 [0 - 860.1]
>= 30: 512 samples averaging 36.8
Excess duration of client sleep:
26,991 samples averaging 0.2 [0 - 831]
>= 1: 653 samples averaging 6.8
>= 5: 285 samples averaging 12.7
>= 10: 98 samples averaging 24.6
>= 15: 18 samples averaging 82.6
>= 20: 7 samples averaging 186.6
>= 25: 7 samples averaging 186.6
>= 30: 5 samples averaging 251
>= 35: 5 samples averaging 251
>= 40: 4 samples averaging 305
>= 45: 3 samples averaging 393
>= 50: 3 samples averaging 393
>= 55: 3 samples averaging 393
>= 85: 3 samples averaging 393
Positive timer discrepancy compared to BWAPI:
14,874 samples averaging 4.7 [1 - 28]
>= 1: 14874 samples averaging 4.7
>= 2: 13806 samples averaging 4.9
>= 3: 12546 samples averaging 5.2
>= 4: 10759 samples averaging 5.6
>= 5: 7734 samples averaging 6.2
>= 10: 409 samples averaging 11.4
>= 15: 23 samples averaging 17.6
>= 20: 5 samples averaging 23.4
>= 25: 2 samples averaging 27.5
Negative timer discrepancy compared to BWAPI:
9,548 samples averaging 8.6 [1 - 23]
>= 1: 9548 samples averaging 8.6
>= 2: 8963 samples averaging 9.1
>= 3: 8550 samples averaging 9.5
>= 4: 8224 samples averaging 9.7
>= 5: 7961 samples averaging 9.9
>= 10: 5123 samples averaging 11.2
>= 15: 93 samples averaging 15.3
>= 20: 1 samples averaging 23
Number of events received from BWAPI:
25,236 samples averaging 1.3 [0 - 47]
>= 1: 24198 samples averaging 1.3
>= 2: 2319 samples averaging 4.4
>= 3: 2141 samples averaging 4.6
>= 4: 968 samples averaging 6.6
>= 5: 509 samples averaging 8.9
>= 6: 279 samples averaging 12.1
>= 8: 153 samples averaging 16.5
>= 10: 118 samples averaging 18.8
>= 15: 72 samples averaging 23.5
>= 20: 37 samples averaging 30.1
Number of events received from BWAPI, multiplied by the receive-to-sent duration of that frame:
25,236 samples averaging 27.4 [0 - 1,449.2]
>= 5: 24198 samples averaging 28.6
>= 10: 24141 samples averaging 28.6
>= 15: 23385 samples averaging 29.1
>= 20: 17362 samples averaging 32.9
>= 25: 4301 samples averaging 66.9
>= 30: 3014 samples averaging 84
>= 35: 2569 samples averaging 93
>= 40: 2360 samples averaging 98
>= 45: 2197 samples averaging 102.1
>= 50: 2125 samples averaging 104
>= 55: 2049 samples averaging 105.9
>= 85: 836 samples averaging 161.5
Number of events received from BWAPI, multiplied by the receive-to-sent duration of that frame (Using GetTickCount()):
25,236 samples averaging 27.3 [0 - 1,438]
>= 5: 24070 samples averaging 28.6
>= 10: 24070 samples averaging 28.6
>= 15: 24070 samples averaging 28.6
>= 20: 9919 samples averaging 47.2
>= 25: 9919 samples averaging 47.2
>= 30: 9919 samples averaging 47.2
>= 35: 2405 samples averaging 97
>= 40: 2405 samples averaging 97
>= 45: 2405 samples averaging 97
>= 50: 1481 samples averaging 128.2
>= 55: 1481 samples averaging 128.2
>= 85: 1016 samples averaging 156.2