+ 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=22786
+ 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
+ echo '=== hunt at ~34s; settle 40s for the sphere cycle ==='
=== hunt at ~34s; settle 40s for the sphere cycle ===
+ sleep 40
+ kill 22786
+ taskkill //F //IM btl4.exe
SUCCESS: The process "btl4.exe" with PID 10304 has been terminated.
SUCCESS: The process "btl4.exe" with PID 6828 has been terminated.
+ taskkill //F //IM python.exe
SUCCESS: The process "python.exe" with PID 3572 has been terminated.
+ echo '=== DONE ==='
=== DONE ===
+ echo '=== A [tloc] ==='
=== A [tloc] ===
+ grep '\[tloc\]' mp_a.log
+ head -20
[tloc] warp start at (1725.8,-0.35,1336.02) scale=30/40
[tloc] tsphere.bgf load OK
[tloc] warp phase=1 scale=31 at=(1725.8,-0.35,1336.02)
[tloc] warp phase=2 scale=7.12 at=(1725.8,-0.35,1336.02)
[tloc] warp phase=2 scale=17.32 at=(1725.8,-0.35,1336.02)
[tloc] warp phase=2 scale=27.52 at=(1725.8,-0.35,1336.02)
[tloc] warp phase=2 scale=37.72 at=(1725.8,-0.35,1336.02)
+ echo '=== B [tloc] ==='
=== B [tloc] ===
+ grep '\[tloc\]' mp_b.log
+ head -20
[tloc] warp start at (1115.23,-0.35,159.693) scale=30/40
[tloc] tsphere.bgf load OK
[tloc] warp phase=1 scale=31 at=(1115.23,-0.35,159.693)
[tloc] warp phase=1 scale=4.85385 at=(1115.23,-0.35,159.693)
[tloc] warp phase=2 scale=4.4 at=(1115.23,-0.35,159.693)
[tloc] warp phase=2 scale=14.6 at=(1115.23,-0.35,159.693)
[tloc] warp phase=2 scale=24.8 at=(1115.23,-0.35,159.693)
[tloc] warp phase=2 scale=35 at=(1115.23,-0.35,159.693)
[tloc] warp start at (1156.83,-0.35,227.76) scale=30/40
[tloc] warp phase=1 scale=27.9538 at=(1156.83,-0.35,227.76)
[tloc] warp phase=1 scale=15.3538 at=(1156.83,-0.35,227.76)
[tloc] warp phase=1 scale=8.70769 at=(1156.83,-0.35,227.76)
[tloc] warp phase=1 scale=3.30769 at=(1156.83,-0.35,227.76)
[tloc] warp phase=2 scale=13.6 at=(1156.83,-0.35,227.76)
[tloc] warp phase=2 scale=22.96 at=(1156.83,-0.35,227.76)
[tloc] warp phase=2 scale=33.6 at=(1156.83,-0.35,227.76)