+ cd /c/git/bt411/content
+ rm -f mp_a.log mp_b.log
+ sleep 2
+ BT_LOG=mp_b.log
+ BT_AFFINITY=0x2
+ BT_MP_LOG=1
+ BT_MP_NET=1
+ BT_DEATH_LOG=1
+ BT_SCORE_LOG=1
+ BT_TLOC_LOG=1
+ ../build/Debug/btl4.exe -egg MP.EGG -net 1601
+ sleep 4
+ BT_LOG=mp_a.log
+ BT_AFFINITY=0x1
+ BT_MP_FORCE_DMG=1
+ BT_MP_LOG=1
+ BT_TLOC_LOG=1
+ ../build/Debug/btl4.exe -egg MP.EGG -net 1501
+ PC=19255
+ python ../tools/btconsole.py MP.EGG 127.0.0.1:1501 127.0.0.1:1601
+ n=0
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 0 -ge 150 ']'
+ sleep 2
+ n=2
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 2 -ge 150 ']'
+ sleep 2
+ n=4
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 4 -ge 150 ']'
+ sleep 2
+ n=6
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 6 -ge 150 ']'
+ sleep 2
+ n=8
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 8 -ge 150 ']'
+ sleep 2
+ n=10
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 10 -ge 150 ']'
+ sleep 2
+ n=12
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 12 -ge 150 ']'
+ sleep 2
+ n=14
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 14 -ge 150 ']'
+ sleep 2
+ n=16
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 16 -ge 150 ']'
+ sleep 2
+ n=18
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 18 -ge 150 ']'
+ sleep 2
+ n=20
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 20 -ge 150 ']'
+ sleep 2
+ n=22
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 22 -ge 150 ']'
+ sleep 2
+ n=24
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 24 -ge 150 ']'
+ sleep 2
+ n=26
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 26 -ge 150 ']'
+ sleep 2
+ n=28
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 28 -ge 150 ']'
+ sleep 2
+ n=30
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 30 -ge 150 ']'
+ sleep 2
+ n=32
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 32 -ge 150 ']'
+ sleep 2
+ n=34
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 34 -ge 150 ']'
+ sleep 2
+ n=36
+ grep -q 'drop-zone hunt' mp_b.log
+ '[' 36 -ge 150 ']'
+ sleep 2
+ n=38
+ grep -q 'drop-zone hunt' mp_b.log
+ echo '=== hunt at ~38s; settle 40s for the sphere cycle ==='
=== hunt at ~38s; settle 40s for the sphere cycle ===
+ sleep 40
+ kill 19255
+ taskkill //F //IM btl4.exe
SUCCESS: The process "btl4.exe" with PID 17296 has been terminated.
SUCCESS: The process "btl4.exe" with PID 24012 has been terminated.
+ taskkill //F //IM python.exe
SUCCESS: The process "python.exe" with PID 26040 has been terminated.
+ echo '=== DONE ==='
=== DONE ===
+ echo '=== A [tloc] ==='
=== A [tloc] ===
+ grep '\[tloc\]' mp_a.log
+ head -20
[tloc] renderable created for entity 2:1 control=1 (active=1)
[tloc] renderable created for entity 3:1 control=1 (active=2)
[tloc] tsphere.bgf load OK
[tloc] fx=0 trigger=3 ctrl=1 state=0 scale=1 vis=0 at=(1023.54,-0.35,1622.67)
[tloc] fx=1 trigger=2 ctrl=1 state=0 scale=1 vis=0 at=(-4.31602e+08,-4.31602e+08,-4.31602e+08)
[tloc] fx=0 trigger=3 ctrl=1 state=0 scale=1 vis=0 at=(1023.54,-0.35,1622.67)
[tloc] fx=1 trigger=2 ctrl=1 state=0 scale=1 vis=0 at=(-4.31602e+08,-4.31602e+08,-4.31602e+08)
[tloc] fx=0 trigger=3 ctrl=1 state=0 scale=1 vis=0 at=(1023.54,-0.35,1622.67)
[tloc] fx=1 trigger=2 ctrl=1 state=0 scale=1 vis=0 at=(-4.31602e+08,-4.31602e+08,-4.31602e+08)
[tloc] fx=0 trigger=3 ctrl=1 state=0 scale=1 vis=0 at=(1023.54,-0.35,1622.67)
[tloc] fx=1 trigger=2 ctrl=1 state=0 scale=1 vis=0 at=(-4.31602e+08,-4.31602e+08,-4.31602e+08)
[tloc] fx=0 trigger=3 ctrl=1 state=0 scale=1 vis=0 at=(1023.54,-0.35,1622.67)
[tloc] fx=1 trigger=2 ctrl=1 state=0 scale=1 vis=0 at=(-4.31602e+08,-4.31602e+08,-4.31602e+08)
[tloc] fx=0 trigger=2 ctrl=1 state=0 scale=1 vis=0 at=(1023.54,-0.35,1622.67)
[tloc] fx=1 trigger=2 ctrl=1 state=0 scale=1 vis=0 at=(-4.31602e+08,-4.31602e+08,-4.31602e+08)
[tloc] fx=0 trigger=2 ctrl=1 state=0 scale=1 vis=0 at=(1023.54,-0.35,1622.67)
[tloc] fx=1 trigger=2 ctrl=1 state=0 scale=1 vis=0 at=(-4.31602e+08,-4.31602e+08,-4.31602e+08)
[tloc] fx=0 trigger=2 ctrl=1 state=0 scale=1 vis=0 at=(1023.54,-0.35,1622.67)
[tloc] fx=1 trigger=2 ctrl=1 state=0 scale=1 vis=0 at=(-4.31602e+08,-4.31602e+08,-4.31602e+08)
[tloc] fx=0 trigger=2 ctrl=1 state=0 scale=1 vis=0 at=(1023.54,-0.35,1622.67)
+ echo '=== B [tloc] ==='
=== B [tloc] ===
+ grep '\[tloc\]' mp_b.log
+ head -20
[tloc] renderable created for entity 3:1 control=1 (active=1)
[tloc] renderable created for entity 2:1 control=1 (active=2)
[tloc] tsphere.bgf load OK
[tloc] fx=0 trigger=3 ctrl=1 state=0 scale=1 vis=0 at=(752.855,-0.35,317.239)
[tloc] fx=1 trigger=0 ctrl=1 state=0 scale=1 vis=0 at=(-4.31602e+08,-4.31602e+08,-4.31602e+08)
[tloc] fx=0 trigger=3 ctrl=1 state=0 scale=1 vis=0 at=(752.855,-0.35,317.239)
[tloc] fx=1 trigger=0 ctrl=1 state=0 scale=1 vis=0 at=(-4.31602e+08,-4.31602e+08,-4.31602e+08)
[tloc] fx=0 trigger=3 ctrl=1 state=0 scale=1 vis=0 at=(752.855,-0.35,317.239)
[tloc] fx=1 trigger=0 ctrl=1 state=0 scale=1 vis=0 at=(-4.31602e+08,-4.31602e+08,-4.31602e+08)
[tloc] fx=0 trigger=3 ctrl=1 state=0 scale=1 vis=0 at=(752.855,-0.35,317.239)
[tloc] fx=1 trigger=0 ctrl=1 state=0 scale=1 vis=0 at=(-4.31602e+08,-4.31602e+08,-4.31602e+08)
[tloc] fx=0 trigger=3 ctrl=1 state=0 scale=1 vis=0 at=(752.855,-0.35,317.239)
[tloc] fx=1 trigger=0 ctrl=1 state=0 scale=1 vis=0 at=(-4.31602e+08,-4.31602e+08,-4.31602e+08)
[tloc] fx=0 trigger=2 ctrl=1 state=0 scale=1 vis=0 at=(752.855,-0.35,317.239)
[tloc] fx=1 trigger=0 ctrl=1 state=0 scale=1 vis=0 at=(-4.31602e+08,-4.31602e+08,-4.31602e+08)
[tloc] fx=0 trigger=2 ctrl=1 state=0 scale=1 vis=0 at=(752.855,-0.35,317.239)
[tloc] fx=1 trigger=0 ctrl=1 state=0 scale=1 vis=0 at=(-4.31602e+08,-4.31602e+08,-4.31602e+08)
[tloc] fx=0 trigger=2 ctrl=1 state=0 scale=1 vis=0 at=(752.855,-0.35,317.239)
[tloc] fx=1 trigger=0 ctrl=1 state=0 scale=1 vis=0 at=(-4.31602e+08,-4.31602e+08,-4.31602e+08)
[tloc] fx=0 trigger=2 ctrl=1 state=0 scale=1 vis=0 at=(752.855,-0.35,317.239)