builder: mozilla-inbound_win7_vm_gfx-debug_test-reftest-no-accel-e10s-2
slave: g-w732-spot-156
starttime: 1497308093.9
results: warnings (1)
buildid: 20170612143334
builduid: 7ed5a56d7cfd46378ece6c6791fc44c7
revision: 3d1ce85e6348307a1e98284e6d13da828729bf91

========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 15:54:53.900963) =========
master: http://buildbot-master129.bb.releng.usw2.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 15:54:53.901379) =========

========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 15:54:53.901666) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 15:54:53.973411) =========

========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 15:54:53.973698) =========
'bash' '-c' 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'
 in dir C:\slave\test\. (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld\AppData\Roaming
  BUILDBOT_PATH=C:\mozilla-build\
  CHOCOLATEYINSTALL=C:\ProgramData\chocolatey
  COMMONPROGRAMFILES=C:\Program Files\Common Files
  COMPUTERNAME=G-W732-SPOT-156
  COMSPEC=C:\windows\system32\cmd.exe
  DCLOCATION=SCL3
  DNSSUFFIX=wintest.releng.scl3.mozilla.com
  FP_NO_HOST_CHECK=NO
  GIT_SHARE_BASE_DIR=/builds/git-shared
  HG_SHARE_BASE_DIR=/builds/hg-shared
  HOMEDRIVE=C:
  HOMEPATH=\Users\cltbld
  IDLEIZER_HALT_ON_IDLE=true
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LOCALAPPDATA=C:\Users\cltbld\AppData\Local
  LOG="c:\runner\buildbot-startup.log"
  LOGONSERVER=\\G-W732-SPOT-156
  MAKE_MODE=unix
  MOZBUILDDIR=C:\mozilla-build
  MOZILLABUILD=C:\mozilla-build
  MOZ_TOOLS=C:\mozilla-build\moztools
  MSYSTEM=MINGW32
  NUMBER_OF_PROCESSORS=8
  OS=Windows_NT
  PATH=C:\Program Files\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;c:\Program Files\Microsoft Windows Performance Toolkit\;c:\mozilla-build\hg\;C:\Program Files (x86)\Microsoft SDKs\TypeScript\1.0\;C:\Program Files (x86)\Puppet Labs\Puppet\bin;C:\Program Files (x86)\Puppet Labs\Puppet\facter\bin;C:\Program Files (x86)\Puppet Labs\Puppet\hiera\bin;C:\Program Files (x86)\Puppet Labs\Puppet\puppet\bin;C:\Program Files (x86)\Puppet Labs\Puppet\sys\ruby\bin;C:\Program Files (x86)\Puppet Labs\Puppet\sys\tools\bin;C:\Program Files (x86)\Windows Kits\8.0\Windows Performance Toolkit\;C:\Program Files (x86)\Windows Kits\8.1\Windows Performance Toolkit\;C:\Program Files\Amazon\cfn-bootstrap\;C:\Program Files\Microsoft SQL Server\110\Tools\Binn\;C:\mozilla-build\buildbotve;C:\mozilla-build\msys\bin;C:\mozilla-build\msys\local\bin;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\python27;C:\opt\runner;c:\Program Files (x86)\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\DTS\Binn\;c:\Program Files\Microsoft SQL Server\100\Tools\Binn;C:\Users\cltbld\AppData\Local\Programs\Common\Microsoft\Visual C++ for Python\9.0;;C:\mozilla-build\moztools\bin
  PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
  PROCESSOR_ARCHITECTURE=x86
  PROCESSOR_IDENTIFIER=x86 Family 6 Model 62 Stepping 4, GenuineIntel
  PROCESSOR_LEVEL=6
  PROCESSOR_REVISION=3e04
  PROGRAMDATA=C:\ProgramData
  PROGRAMFILES=C:\Program Files
  PROMPT=$P$G
  PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\;C:\Program Files\AWS Tools\PowerShell\
  PUBLIC=C:\Users\Public
  PWD=C:\slave\test
  RUNNER_CONFIG_CMD=C:\opt\runner\Scripts\python2.7.exe C:\opt\runner\Scripts\runner-script.py -c c:\opt\runner\runner.cfg
  SYSTEMDRIVE=C:
  SYSTEMROOT=C:\windows
  TEMP=C:\Users\cltbld\AppData\Local\Temp
  TEST1=testie
  TMP=C:\Users\cltbld\AppData\Local\Temp
  USERDOMAIN=G-W732-SPOT-156
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld
  WINDIR=C:\windows
  WINDOWS_TRACING_FLAGS=3
  WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
 using PTY: False
--15:54:52--  https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
           => `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,179 (12K) [text/x-python]

    0K .......... .                                          100%   11.22 MB/s

15:54:52 (11.22 MB/s) - `archiver_client.py' saved [12179/12179]

program finished with exit code 0
elapsedTime=0.776000
========= master_lag: 0.01 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 15:54:54.764556) =========

========= Started 'rm -rf ...' (results: 0, elapsed: 4 secs) (at 2017-06-12 15:54:54.765358) =========
15:54:48     INFO - REFTEST INFO | REFTEST fuzzy test (0, 0) <= (1, 1) <= (1, 2)
15:54:48     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-circle.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html | image comparison
15:54:48     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-circle.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:48     INFO - ++DOMWINDOW == 40 (0CD2E400) [pid = 5040] [serial = 6207] [outer = 091EA000]
15:54:48     INFO - REFTEST None
15:54:48     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-downdiagonalstrike.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:48     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-downdiagonalstrike.html | 2147 / 7795 (27%)
15:54:48     INFO - ++DOMWINDOW == 41 (091D5000) [pid = 5040] [serial = 6208] [outer = 091EA000]
15:54:48     INFO - --DOMWINDOW == 40 (0CF76800) [pid = 5040] [serial = 6169] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-longdiv.html]
15:54:48     INFO - --DOMWINDOW == 39 (0BA17000) [pid = 5040] [serial = 6174] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:48     INFO - --DOMWINDOW == 38 (091D3400) [pid = 5040] [serial = 6175] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-radical.html]
15:54:48     INFO - --DOMWINDOW == 37 (0A8F7800) [pid = 5040] [serial = 6180] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:48     INFO - --DOMWINDOW == 36 (015DEC00) [pid = 5040] [serial = 6176] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-radical-ref.html]
15:54:48     INFO - --DOMWINDOW == 35 (0CF70000) [pid = 5040] [serial = 6170] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-longdiv-ref.html]
15:54:48     INFO - --DOMWINDOW == 34 (0CADF800) [pid = 5040] [serial = 6171] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:48     INFO - --DOMWINDOW == 33 (015DD800) [pid = 5040] [serial = 6173] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-madruwb-ref.html]
15:54:48     INFO - --DOMWINDOW == 32 (0CF71800) [pid = 5040] [serial = 6172] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-madruwb.html]
15:54:48     INFO - --DOMWINDOW == 31 (08FBB800) [pid = 5040] [serial = 6178] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-right.html]
15:54:48     INFO - --DOMWINDOW == 30 (0BCAF800) [pid = 5040] [serial = 6183] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:48     INFO - --DOMWINDOW == 29 (08FC3400) [pid = 5040] [serial = 6182] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-roundedbox-ref.html]
15:54:48     INFO - --DOMWINDOW == 28 (0CADB400) [pid = 5040] [serial = 6181] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-roundedbox.html]
15:54:48     INFO - --DOMWINDOW == 27 (08FBDC00) [pid = 5040] [serial = 6179] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-right-ref.html]
15:54:48     INFO - --DOMWINDOW == 26 (08FCA800) [pid = 5040] [serial = 6177] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:48     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-downdiagonalstrike.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html | image comparison
15:54:48     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-downdiagonalstrike.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:48     INFO - ++DOMWINDOW == 27 (015DEC00) [pid = 5040] [serial = 6209] [outer = 091EA000]
15:54:48     INFO - REFTEST None
15:54:48     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-horizontalstrike.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:48     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-horizontalstrike.html | 2148 / 7795 (27%)
15:54:48     INFO - ++DOMWINDOW == 28 (08FC8C00) [pid = 5040] [serial = 6210] [outer = 091EA000]
15:54:48     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-horizontalstrike.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html | image comparison
15:54:48     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-horizontalstrike.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:49     INFO - ++DOMWINDOW == 29 (08FC3400) [pid = 5040] [serial = 6211] [outer = 091EA000]
15:54:49     INFO - REFTEST None
15:54:49     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-left.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:49     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-left.html | 2149 / 7795 (27%)
15:54:49     INFO - ++DOMWINDOW == 30 (091D3400) [pid = 5040] [serial = 6212] [outer = 091EA000]
15:54:49     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-left.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html | image comparison
15:54:49     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-left.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:49     INFO - ++DOMWINDOW == 31 (091CC400) [pid = 5040] [serial = 6213] [outer = 091EA000]
15:54:49     INFO - REFTEST None
15:54:49     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-longdiv.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:49     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-longdiv.html | 2150 / 7795 (27%)
15:54:49     INFO - ++DOMWINDOW == 32 (0CAE1800) [pid = 5040] [serial = 6214] [outer = 091EA000]
15:54:49     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-longdiv.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html | image comparison
15:54:49     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-longdiv.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:49     INFO - ++DOMWINDOW == 33 (08FC2000) [pid = 5040] [serial = 6215] [outer = 091EA000]
15:54:49     INFO - REFTEST None
15:54:49     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-madruwb.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:49     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-madruwb.html | 2151 / 7795 (27%)
15:54:49     INFO - ++DOMWINDOW == 34 (0BCA8000) [pid = 5040] [serial = 6216] [outer = 091EA000]
15:54:49     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-madruwb.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html | image comparison
15:54:49     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-madruwb.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:49     INFO - ++DOMWINDOW == 35 (0BA18000) [pid = 5040] [serial = 6217] [outer = 091EA000]
15:54:49     INFO - REFTEST None
15:54:49     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-radical.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:49     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-radical.html | 2152 / 7795 (27%)
15:54:49     INFO - ++DOMWINDOW == 36 (0C3B4400) [pid = 5040] [serial = 6218] [outer = 091EA000]
15:54:49     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-radical.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html | image comparison
15:54:49     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-radical.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:49     INFO - ++DOMWINDOW == 37 (0BABAC00) [pid = 5040] [serial = 6219] [outer = 091EA000]
15:54:49     INFO - REFTEST None
15:54:49     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-right.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:49     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-right.html | 2153 / 7795 (27%)
15:54:49     INFO - ++DOMWINDOW == 38 (0CF76800) [pid = 5040] [serial = 6220] [outer = 091EA000]
15:54:49     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-right.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html | image comparison
15:54:49     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-right.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:49     INFO - ++DOMWINDOW == 39 (0BF8F800) [pid = 5040] [serial = 6221] [outer = 091EA000]
15:54:49     INFO - REFTEST None
15:54:49     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-roundedbox.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:49     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-roundedbox.html | 2154 / 7795 (27%)
15:54:49     INFO - ++DOMWINDOW == 40 (0D11A800) [pid = 5040] [serial = 6222] [outer = 091EA000]
15:54:49     INFO - REFTEST INFO | REFTEST fuzzy test (0, 0) <= (0, 0) <= (2, 3)
15:54:49     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-roundedbox.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html | image comparison
15:54:49     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-roundedbox.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:49     INFO - ++DOMWINDOW == 41 (0BF8B400) [pid = 5040] [serial = 6223] [outer = 091EA000]
15:54:49     INFO - REFTEST None
15:54:49     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-top.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:49     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-top.html | 2155 / 7795 (27%)
15:54:49     INFO - ++DOMWINDOW == 42 (0D179000) [pid = 5040] [serial = 6224] [outer = 091EA000]
15:54:49     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-top.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html | image comparison
15:54:49     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-top.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:49     INFO - ++DOMWINDOW == 43 (0BAB7000) [pid = 5040] [serial = 6225] [outer = 091EA000]
15:54:49     INFO - REFTEST None
15:54:49     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-updiagonalarrow.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:49     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-updiagonalarrow.html | 2156 / 7795 (27%)
15:54:49     INFO - ++DOMWINDOW == 44 (08FCA800) [pid = 5040] [serial = 6226] [outer = 091EA000]
15:54:50     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-updiagonalarrow.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html | image comparison
15:54:50     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-updiagonalarrow.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:50     INFO - ++DOMWINDOW == 45 (08FC9400) [pid = 5040] [serial = 6227] [outer = 091EA000]
15:54:50     INFO - REFTEST None
15:54:50     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-updiagonalstrike.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:50     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-updiagonalstrike.html | 2157 / 7795 (27%)
15:54:50     INFO - ++DOMWINDOW == 46 (0C254400) [pid = 5040] [serial = 6228] [outer = 091EA000]
15:54:50     INFO - --DOMWINDOW == 45 (015DDC00) [pid = 5040] [serial = 6191] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-updiagonalstrike-ref.html]
15:54:50     INFO - --DOMWINDOW == 44 (091D0800) [pid = 5040] [serial = 6185] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-top-ref.html]
15:54:50     INFO - --DOMWINDOW == 43 (0CD88C00) [pid = 5040] [serial = 6186] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:50     INFO - --DOMWINDOW == 42 (01509C00) [pid = 5040] [serial = 6188] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-updiagonalarrow-ref.html]
15:54:50     INFO - --DOMWINDOW == 41 (0CD27400) [pid = 5040] [serial = 6192] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:50     INFO - --DOMWINDOW == 40 (0B635000) [pid = 5040] [serial = 6190] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-updiagonalstrike.html]
15:54:50     INFO - --DOMWINDOW == 39 (0C008C00) [pid = 5040] [serial = 6187] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-updiagonalarrow.html]
15:54:50     INFO - --DOMWINDOW == 38 (0C25D000) [pid = 5040] [serial = 6189] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:50     INFO - --DOMWINDOW == 37 (0D069000) [pid = 5040] [serial = 6198] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:50     INFO - --DOMWINDOW == 36 (0CD2D800) [pid = 5040] [serial = 6196] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-phasorangle.html]
15:54:50     INFO - --DOMWINDOW == 35 (0BA17800) [pid = 5040] [serial = 6184] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-top.html]
15:54:50     INFO - --DOMWINDOW == 34 (0BF8D800) [pid = 5040] [serial = 6193] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-verticalstrike.html]
15:54:50     INFO - --DOMWINDOW == 33 (0C682400) [pid = 5040] [serial = 6199] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-actuarial.html]
15:54:50     INFO - --DOMWINDOW == 32 (091F8C00) [pid = 5040] [serial = 6194] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-verticalstrike-ref.html]
15:54:50     INFO - --DOMWINDOW == 31 (0BA10800) [pid = 5040] [serial = 6195] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:50     INFO - --DOMWINDOW == 30 (0CF70800) [pid = 5040] [serial = 6201] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:50     INFO - --DOMWINDOW == 29 (0C675000) [pid = 5040] [serial = 6197] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-5-phasorangle-ref.html]
15:54:50     INFO - --DOMWINDOW == 28 (0C3AF000) [pid = 5040] [serial = 6200] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html]
15:54:50     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-updiagonalstrike.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html | image comparison
15:54:50     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-updiagonalstrike.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:50     INFO - ++DOMWINDOW == 29 (01509C00) [pid = 5040] [serial = 6229] [outer = 091EA000]
15:54:50     INFO - REFTEST None
15:54:50     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-verticalstrike.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:50     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-verticalstrike.html | 2158 / 7795 (27%)
15:54:50     INFO - ++DOMWINDOW == 30 (0BCA9C00) [pid = 5040] [serial = 6230] [outer = 091EA000]
15:54:50     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-verticalstrike.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html | image comparison
15:54:50     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-verticalstrike.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:50     INFO - ++DOMWINDOW == 31 (091F8C00) [pid = 5040] [serial = 6231] [outer = 091EA000]
15:54:50     INFO - REFTEST None
15:54:50     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-phasorangle.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:50     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-phasorangle.html | 2159 / 7795 (27%)
15:54:50     INFO - ++DOMWINDOW == 32 (0C260800) [pid = 5040] [serial = 6232] [outer = 091EA000]
15:54:50     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-phasorangle.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html | image comparison
15:54:50     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-phasorangle.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-ref.html
15:54:50     INFO - ++DOMWINDOW == 33 (08FBCC00) [pid = 5040] [serial = 6233] [outer = 091EA000]
15:54:50     INFO - REFTEST None
15:54:50     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mmultiscript-align.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mmultiscript-align-ref.html
15:54:50     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mmultiscript-align.html | 2160 / 7795 (27%)
15:54:50     INFO - ++DOMWINDOW == 34 (0C008C00) [pid = 5040] [serial = 6234] [outer = 091EA000]
15:54:50     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mmultiscript-align-ref.html | 2160 / 7795 (27%)
15:54:50     INFO - ++DOMWINDOW == 35 (0CD2D800) [pid = 5040] [serial = 6235] [outer = 091EA000]
15:54:50     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mmultiscript-align.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mmultiscript-align-ref.html | image comparison
15:54:50     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mmultiscript-align.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mmultiscript-align-ref.html
15:54:50     INFO - ++DOMWINDOW == 36 (0C682400) [pid = 5040] [serial = 6236] [outer = 091EA000]
15:54:50     INFO - REFTEST None
15:54:50     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/subscript-italic-correction.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/subscript-italic-correction-ref.html
15:54:50     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/subscript-italic-correction.html | 2161 / 7795 (27%)
15:54:50     INFO - ++DOMWINDOW == 37 (0CDD8800) [pid = 5040] [serial = 6237] [outer = 091EA000]
15:54:50  WARNING - REFTEST WARNING | [CONTENT] MozInvalidateEvent didn't invalidate
15:54:50     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/subscript-italic-correction-ref.html | 2161 / 7795 (27%)
15:54:50     INFO - ++DOMWINDOW == 38 (0CF6F400) [pid = 5040] [serial = 6238] [outer = 091EA000]
15:54:50     INFO - REFTEST TEST-KNOWN-FAIL | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/subscript-italic-correction.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/subscript-italic-correction-ref.html | image comparison
15:54:50     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/subscript-italic-correction.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/subscript-italic-correction-ref.html
15:54:50     INFO - ++DOMWINDOW == 39 (08FC0C00) [pid = 5040] [serial = 6239] [outer = 091EA000]
15:54:50     INFO - REFTEST None
15:54:50     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1a.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1a-ref.html
15:54:50     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1a.html | 2162 / 7795 (27%)
15:54:50     INFO - ++DOMWINDOW == 40 (0D172400) [pid = 5040] [serial = 6240] [outer = 091EA000]
15:54:51     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1a-ref.html | 2162 / 7795 (27%)
15:54:51     INFO - ++DOMWINDOW == 41 (08FBE000) [pid = 5040] [serial = 6241] [outer = 091EA000]
15:54:51     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1a.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1a-ref.html | image comparison
15:54:51     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1a.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1a-ref.html
15:54:51     INFO - ++DOMWINDOW == 42 (091CBC00) [pid = 5040] [serial = 6242] [outer = 091EA000]
15:54:51     INFO - REFTEST None
15:54:51     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1b.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1b-ref.html
15:54:51     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1b.html | 2163 / 7795 (27%)
15:54:51     INFO - ++DOMWINDOW == 43 (015DF000) [pid = 5040] [serial = 6243] [outer = 091EA000]
15:54:51     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1b-ref.html | 2163 / 7795 (27%)
15:54:51     INFO - ++DOMWINDOW == 44 (0BF90800) [pid = 5040] [serial = 6244] [outer = 091EA000]
15:54:51     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1b.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1b-ref.html | image comparison
15:54:51     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1b.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1b-ref.html
15:54:51     INFO - ++DOMWINDOW == 45 (0BA16800) [pid = 5040] [serial = 6245] [outer = 091EA000]
15:54:51     INFO - REFTEST None
15:54:51     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1c.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1c-ref.html
15:54:51     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1c.html | 2164 / 7795 (27%)
15:54:51     INFO - ++DOMWINDOW == 46 (0BA17000) [pid = 5040] [serial = 6246] [outer = 091EA000]
15:54:52     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1c-ref.html | 2164 / 7795 (27%)
15:54:52     INFO - ++DOMWINDOW == 47 (0CAD5400) [pid = 5040] [serial = 6247] [outer = 091EA000]
15:54:52     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1c.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1c-ref.html | image comparison
15:54:52     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1c.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1c-ref.html
15:54:52     INFO - ++DOMWINDOW == 48 (0CADC400) [pid = 5040] [serial = 6248] [outer = 091EA000]
15:54:52     INFO - REFTEST None
15:54:52     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1d.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1d-ref.html
15:54:52     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1d.html | 2165 / 7795 (27%)
15:54:52     INFO - --DOMWINDOW == 47 (08FC3400) [pid = 5040] [serial = 6211] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:52     INFO - --DOMWINDOW == 46 (0BCA8000) [pid = 5040] [serial = 6216] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-madruwb.html]
15:54:52     INFO - --DOMWINDOW == 45 (091D5000) [pid = 5040] [serial = 6208] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-downdiagonalstrike.html]
15:54:52     INFO - --DOMWINDOW == 44 (015DEC00) [pid = 5040] [serial = 6209] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:52     INFO - --DOMWINDOW == 43 (091D3400) [pid = 5040] [serial = 6212] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-left.html]
15:54:52     INFO - --DOMWINDOW == 42 (0CAE1800) [pid = 5040] [serial = 6214] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-longdiv.html]
15:54:52     INFO - --DOMWINDOW == 41 (08FC8C00) [pid = 5040] [serial = 6210] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-horizontalstrike.html]
15:54:52     INFO - --DOMWINDOW == 40 (091CC400) [pid = 5040] [serial = 6213] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:52     INFO - --DOMWINDOW == 39 (0C3B4400) [pid = 5040] [serial = 6218] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-radical.html]
15:54:52     INFO - --DOMWINDOW == 38 (08FC2000) [pid = 5040] [serial = 6215] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:52     INFO - --DOMWINDOW == 37 (0BA18000) [pid = 5040] [serial = 6217] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:52     INFO - --DOMWINDOW == 36 (0BABAC00) [pid = 5040] [serial = 6219] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:52     INFO - --DOMWINDOW == 35 (0D078000) [pid = 5040] [serial = 6206] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-circle.html]
15:54:52     INFO - --DOMWINDOW == 34 (0C679800) [pid = 5040] [serial = 6202] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-bottom.html]
15:54:52     INFO - --DOMWINDOW == 33 (0D116800) [pid = 5040] [serial = 6204] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-box.html]
15:54:52     INFO - --DOMWINDOW == 32 (091F5400) [pid = 5040] [serial = 6205] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:52     INFO - --DOMWINDOW == 31 (0CD2E400) [pid = 5040] [serial = 6207] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:52     INFO - --DOMWINDOW == 30 (0CF6F800) [pid = 5040] [serial = 6203] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:52     INFO - ++DOMWINDOW == 31 (015DEC00) [pid = 5040] [serial = 6249] [outer = 091EA000]
15:54:52     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1d-ref.html | 2165 / 7795 (27%)
15:54:52     INFO - ++DOMWINDOW == 32 (0C004800) [pid = 5040] [serial = 6250] [outer = 091EA000]
15:54:52     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1d.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1d-ref.html | image comparison
15:54:52     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1d.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1d-ref.html
15:54:52     INFO - ++DOMWINDOW == 33 (0BABAC00) [pid = 5040] [serial = 6251] [outer = 091EA000]
15:54:52     INFO - REFTEST None
15:54:52     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-2.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-2-ref.html
15:54:52     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-2.html | 2166 / 7795 (27%)
15:54:52     INFO - ++DOMWINDOW == 34 (0CD2C400) [pid = 5040] [serial = 6252] [outer = 091EA000]
15:54:52     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-2-ref.html | 2166 / 7795 (27%)
15:54:52     INFO - ++DOMWINDOW == 35 (0CAE1800) [pid = 5040] [serial = 6253] [outer = 091EA000]
15:54:52     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-2.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-2-ref.html | image comparison
15:54:52     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-2.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-2-ref.html
15:54:52     INFO - ++DOMWINDOW == 36 (0CF71800) [pid = 5040] [serial = 6254] [outer = 091EA000]
15:54:52     INFO - REFTEST None
15:54:52     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-3.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-3-ref.html
15:54:52     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-3.html | 2167 / 7795 (27%)
15:54:52     INFO - ++DOMWINDOW == 37 (08FC8400) [pid = 5040] [serial = 6255] [outer = 091EA000]
15:54:52     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-3-ref.html | 2167 / 7795 (27%)
15:54:52     INFO - ++DOMWINDOW == 38 (091CC400) [pid = 5040] [serial = 6256] [outer = 091EA000]
15:54:52     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-3.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-3-ref.html | image comparison
15:54:52     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-3.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-3-ref.html
15:54:52     INFO - ++DOMWINDOW == 39 (0D119400) [pid = 5040] [serial = 6257] [outer = 091EA000]
15:54:52     INFO - REFTEST None
15:54:52     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-4.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-4-ref.html
15:54:52     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-4.html | 2168 / 7795 (27%)
15:54:52     INFO - ++DOMWINDOW == 40 (0D1C7C00) [pid = 5040] [serial = 6258] [outer = 091EA000]
15:54:52     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-4-ref.html | 2168 / 7795 (27%)
15:54:52     INFO - ++DOMWINDOW == 41 (0C256C00) [pid = 5040] [serial = 6259] [outer = 091EA000]
15:54:52     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-4.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-4-ref.html | image comparison
15:54:52     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-4.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-4-ref.html
15:54:52     INFO - ++DOMWINDOW == 42 (0D251000) [pid = 5040] [serial = 6260] [outer = 091EA000]
15:54:52     INFO - REFTEST None
15:54:52     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-5.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-5-ref.html
15:54:52     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-5.html | 2169 / 7795 (27%)
15:54:52     INFO - ++DOMWINDOW == 43 (0D1C4000) [pid = 5040] [serial = 6261] [outer = 091EA000]
15:54:53     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-5-ref.html | 2169 / 7795 (27%)
15:54:53     INFO - ++DOMWINDOW == 44 (0CD27C00) [pid = 5040] [serial = 6262] [outer = 091EA000]
15:54:53     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-5.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-5-ref.html | image comparison
15:54:53     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-5.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-5-ref.html
15:54:53     INFO - ++DOMWINDOW == 45 (0D298000) [pid = 5040] [serial = 6263] [outer = 091EA000]
15:54:53     INFO - REFTEST None
15:54:53     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-1.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-1-ref.html
15:54:53     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-1.html | 2170 / 7795 (27%)
15:54:53     INFO - ++DOMWINDOW == 46 (091CAC00) [pid = 5040] [serial = 6264] [outer = 091EA000]
15:54:53     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-1-ref.html | 2170 / 7795 (27%)
15:54:53     INFO - ++DOMWINDOW == 47 (0D299400) [pid = 5040] [serial = 6265] [outer = 091EA000]
15:54:53     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-1.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-1-ref.html | image comparison
15:54:53     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-1.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-1-ref.html
15:54:53     INFO - ++DOMWINDOW == 48 (08FC2400) [pid = 5040] [serial = 6266] [outer = 091EA000]
15:54:53     INFO - REFTEST None
15:54:53     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-2.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-2-ref.html
15:54:53     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-2.html | 2171 / 7795 (27%)
15:54:53     INFO - ++DOMWINDOW == 49 (015D1000) [pid = 5040] [serial = 6267] [outer = 091EA000]
'rm' '-rf' 'scripts' 'properties'
 in dir C:\slave\test\. (timeout 1200 secs)
 watching logfiles {}
 argv: ['rm', '-rf', 'scripts', 'properties']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld\AppData\Roaming
  BUILDBOT_PATH=C:\mozilla-build\
  CHOCOLATEYINSTALL=C:\ProgramData\chocolatey
  COMMONPROGRAMFILES=C:\Program Files\Common Files
  COMPUTERNAME=G-W732-SPOT-156
  COMSPEC=C:\windows\system32\cmd.exe
  DCLOCATION=SCL3
  DNSSUFFIX=wintest.releng.scl3.mozilla.com
  FP_NO_HOST_CHECK=NO
  GIT_SHARE_BASE_DIR=/builds/git-shared
  HG_SHARE_BASE_DIR=/builds/hg-shared
  HOMEDRIVE=C:
  HOMEPATH=\Users\cltbld
  IDLEIZER_HALT_ON_IDLE=true
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LOCALAPPDATA=C:\Users\cltbld\AppData\Local
  LOG="c:\runner\buildbot-startup.log"
  LOGONSERVER=\\G-W732-SPOT-156
  MAKE_MODE=unix
  MOZBUILDDIR=C:\mozilla-build
  MOZILLABUILD=C:\mozilla-build
  MOZ_TOOLS=C:\mozilla-build\moztools
  MSYSTEM=MINGW32
  NUMBER_OF_PROCESSORS=8
  OS=Windows_NT
  PATH=C:\Program Files\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;c:\Program Files\Microsoft Windows Performance Toolkit\;c:\mozilla-build\hg\;C:\Program Files (x86)\Microsoft SDKs\TypeScript\1.0\;C:\Program Files (x86)\Puppet Labs\Puppet\bin;C:\Program Files (x86)\Puppet Labs\Puppet\facter\bin;C:\Program Files (x86)\Puppet Labs\Puppet\hiera\bin;C:\Program Files (x86)\Puppet Labs\Puppet\puppet\bin;C:\Program Files (x86)\Puppet Labs\Puppet\sys\ruby\bin;C:\Program Files (x86)\Puppet Labs\Puppet\sys\tools\bin;C:\Program Files (x86)\Windows Kits\8.0\Windows Performance Toolkit\;C:\Program Files (x86)\Windows Kits\8.1\Windows Performance Toolkit\;C:\Program Files\Amazon\cfn-bootstrap\;C:\Program Files\Microsoft SQL Server\110\Tools\Binn\;C:\mozilla-build\buildbotve;C:\mozilla-build\msys\bin;C:\mozilla-build\msys\local\bin;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\python27;C:\opt\runner;c:\Program Files (x86)\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\DTS\Binn\;c:\Program Files\Microsoft SQL Server\100\Tools\Binn;C:\Users\cltbld\AppData\Local\Programs\Common\Microsoft\Visual C++ for Python\9.0;;C:\mozilla-build\moztools\bin
  PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
  PROCESSOR_ARCHITECTURE=x86
  PROCESSOR_IDENTIFIER=x86 Family 6 Model 62 Stepping 4, GenuineIntel
  PROCESSOR_LEVEL=6
  PROCESSOR_REVISION=3e04
  PROGRAMDATA=C:\ProgramData
  PROGRAMFILES=C:\Program Files
  PROMPT=$P$G
  PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\;C:\Program Files\AWS Tools\PowerShell\
  PUBLIC=C:\Users\Public
  PWD=C:\slave\test
  RUNNER_CONFIG_CMD=C:\opt\runner\Scripts\python2.7.exe C:\opt\runner\Scripts\runner-script.py -c c:\opt\runner\runner.cfg
  SYSTEMDRIVE=C:
  SYSTEMROOT=C:\windows
  TEMP=C:\Users\cltbld\AppData\Local\Temp
  TEST1=testie
  TMP=C:\Users\cltbld\AppData\Local\Temp
  USERDOMAIN=G-W732-SPOT-156
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld
  WINDIR=C:\windows
  WINDOWS_TRACING_FLAGS=3
  WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
 using PTY: False
program finished with exit code 0
elapsedTime=2.303000
========= master_lag: 2.06 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 4 secs) (at 2017-06-12 15:54:59.124517) =========

========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-06-12 15:54:59.125483) =========
15:54:53     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-2-ref.html | 2171 / 7795 (27%)
15:54:53     INFO - --DOMWINDOW == 48 (08FC9400) [pid = 5040] [serial = 6227] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:53     INFO - --DOMWINDOW == 47 (0BF8B400) [pid = 5040] [serial = 6223] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:53     INFO - --DOMWINDOW == 46 (0BAB7000) [pid = 5040] [serial = 6225] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:53     INFO - --DOMWINDOW == 45 (0BF8F800) [pid = 5040] [serial = 6221] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:53     INFO - --DOMWINDOW == 44 (08FCA800) [pid = 5040] [serial = 6226] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-updiagonalarrow.html]
15:54:53     INFO - --DOMWINDOW == 43 (0CF76800) [pid = 5040] [serial = 6220] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-right.html]
15:54:53     INFO - --DOMWINDOW == 42 (0D11A800) [pid = 5040] [serial = 6222] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-roundedbox.html]
15:54:53     INFO - --DOMWINDOW == 41 (0D179000) [pid = 5040] [serial = 6224] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-top.html]
15:54:53     INFO - --DOMWINDOW == 40 (0BCA9C00) [pid = 5040] [serial = 6230] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-verticalstrike.html]
15:54:53     INFO - --DOMWINDOW == 39 (091F8C00) [pid = 5040] [serial = 6231] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:53     INFO - --DOMWINDOW == 38 (0C260800) [pid = 5040] [serial = 6232] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-phasorangle.html]
15:54:53     INFO - --DOMWINDOW == 37 (08FBCC00) [pid = 5040] [serial = 6233] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:53     INFO - --DOMWINDOW == 36 (01509C00) [pid = 5040] [serial = 6229] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:53     INFO - --DOMWINDOW == 35 (0C254400) [pid = 5040] [serial = 6228] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/menclose-6-updiagonalstrike.html]
15:54:53     INFO - --DOMWINDOW == 34 (0C008C00) [pid = 5040] [serial = 6234] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mmultiscript-align.html]
15:54:53     INFO - ++DOMWINDOW == 35 (08F1D800) [pid = 5040] [serial = 6268] [outer = 091EA000]
15:54:53     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-2.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-2-ref.html | image comparison
15:54:53     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-2.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-2-ref.html
15:54:53     INFO - ++DOMWINDOW == 36 (0C005000) [pid = 5040] [serial = 6269] [outer = 091EA000]
15:54:53     INFO - REFTEST None
15:54:53     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-3.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-3-ref.html
15:54:53     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-3.html | 2172 / 7795 (27%)
15:54:53     INFO - ++DOMWINDOW == 37 (0BA13800) [pid = 5040] [serial = 6270] [outer = 091EA000]
15:54:53     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-3-ref.html | 2172 / 7795 (27%)
15:54:54     INFO - ++DOMWINDOW == 38 (08FC5800) [pid = 5040] [serial = 6271] [outer = 091EA000]
15:54:54     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-3.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-3-ref.html | image comparison
15:54:54     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-3.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-3-ref.html
15:54:54     INFO - ++DOMWINDOW == 39 (0D174400) [pid = 5040] [serial = 6272] [outer = 091EA000]
15:54:54     INFO - REFTEST None
15:54:54     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-1.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-1-ref.html
15:54:54     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-1.html | 2173 / 7795 (27%)
15:54:54     INFO - ++DOMWINDOW == 40 (091EA400) [pid = 5040] [serial = 6273] [outer = 091EA000]
15:54:54     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-1-ref.html | 2173 / 7795 (27%)
15:54:54     INFO - ++DOMWINDOW == 41 (0C3B6000) [pid = 5040] [serial = 6274] [outer = 091EA000]
15:54:54     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-1.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-1-ref.html | image comparison
15:54:54     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-1.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-1-ref.html
15:54:54     INFO - ++DOMWINDOW == 42 (0CADA000) [pid = 5040] [serial = 6275] [outer = 091EA000]
15:54:54     INFO - REFTEST None
15:54:54     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-2.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-2-ref.html
15:54:54     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-2.html | 2174 / 7795 (27%)
15:54:54     INFO - ++DOMWINDOW == 43 (0D32C400) [pid = 5040] [serial = 6276] [outer = 091EA000]
15:54:54     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-2-ref.html | 2174 / 7795 (27%)
15:54:54     INFO - ++DOMWINDOW == 44 (0C3AD000) [pid = 5040] [serial = 6277] [outer = 091EA000]
15:54:55     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-2.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-2-ref.html | image comparison
15:54:55     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-2.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-2-ref.html
15:54:55     INFO - ++DOMWINDOW == 45 (0D58A800) [pid = 5040] [serial = 6278] [outer = 091EA000]
15:54:55     INFO - REFTEST None
15:54:55     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-3.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-3-ref.html
15:54:55     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-3.html | 2175 / 7795 (27%)
15:54:55     INFO - ++DOMWINDOW == 46 (0BF8F000) [pid = 5040] [serial = 6279] [outer = 091EA000]
15:54:55     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-3-ref.html | 2175 / 7795 (27%)
15:54:55     INFO - ++DOMWINDOW == 47 (0C3AF000) [pid = 5040] [serial = 6280] [outer = 091EA000]
15:54:55     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-3.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-3-ref.html | image comparison
15:54:55     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-3.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-3-ref.html
15:54:55     INFO - ++DOMWINDOW == 48 (015DD800) [pid = 5040] [serial = 6281] [outer = 091EA000]
15:54:55     INFO - REFTEST None
15:54:55     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-4.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-4-ref.html
15:54:55     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-4.html | 2176 / 7795 (27%)
15:54:55     INFO - ++DOMWINDOW == 49 (091D3400) [pid = 5040] [serial = 6282] [outer = 091EA000]
15:54:55     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-4-ref.html | 2176 / 7795 (27%)
15:54:55     INFO - ++DOMWINDOW == 50 (08F19C00) [pid = 5040] [serial = 6283] [outer = 091EA000]
15:54:55     INFO - --DOMWINDOW == 49 (08FC0C00) [pid = 5040] [serial = 6239] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:55     INFO - --DOMWINDOW == 48 (0CD2D800) [pid = 5040] [serial = 6235] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mmultiscript-align-ref.html]
15:54:55     INFO - --DOMWINDOW == 47 (08FBE000) [pid = 5040] [serial = 6241] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1a-ref.html]
15:54:55     INFO - --DOMWINDOW == 46 (0CF6F400) [pid = 5040] [serial = 6238] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/subscript-italic-correction-ref.html]
15:54:55     INFO - --DOMWINDOW == 45 (0C682400) [pid = 5040] [serial = 6236] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:55     INFO - --DOMWINDOW == 44 (091CBC00) [pid = 5040] [serial = 6242] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:55     INFO - --DOMWINDOW == 43 (0D172400) [pid = 5040] [serial = 6240] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1a.html]
15:54:55     INFO - --DOMWINDOW == 42 (091CC400) [pid = 5040] [serial = 6256] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-3-ref.html]
15:54:55     INFO - --DOMWINDOW == 41 (0D119400) [pid = 5040] [serial = 6257] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:55     INFO - --DOMWINDOW == 40 (08FC8400) [pid = 5040] [serial = 6255] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-3.html]
15:54:55     INFO - --DOMWINDOW == 39 (0CDD8800) [pid = 5040] [serial = 6237] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/subscript-italic-correction.html]
15:54:55     INFO - --DOMWINDOW == 38 (0CADC400) [pid = 5040] [serial = 6248] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:55     INFO - --DOMWINDOW == 37 (015DEC00) [pid = 5040] [serial = 6249] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1d.html]
15:54:55     INFO - --DOMWINDOW == 36 (0C004800) [pid = 5040] [serial = 6250] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1d-ref.html]
15:54:55     INFO - --DOMWINDOW == 35 (0BABAC00) [pid = 5040] [serial = 6251] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:55     INFO - --DOMWINDOW == 34 (0CD2C400) [pid = 5040] [serial = 6252] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-2.html]
15:54:55     INFO - --DOMWINDOW == 33 (0CAE1800) [pid = 5040] [serial = 6253] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-2-ref.html]
15:54:55     INFO - --DOMWINDOW == 32 (0CF71800) [pid = 5040] [serial = 6254] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:55     INFO - --DOMWINDOW == 31 (0C256C00) [pid = 5040] [serial = 6259] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-4-ref.html]
15:54:55     INFO - --DOMWINDOW == 30 (015DF000) [pid = 5040] [serial = 6243] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1b.html]
15:54:55     INFO - --DOMWINDOW == 29 (0BF90800) [pid = 5040] [serial = 6244] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1b-ref.html]
15:54:55     INFO - --DOMWINDOW == 28 (0BA16800) [pid = 5040] [serial = 6245] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:55     INFO - --DOMWINDOW == 27 (0BA17000) [pid = 5040] [serial = 6246] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1c.html]
15:54:55     INFO - --DOMWINDOW == 26 (0D1C7C00) [pid = 5040] [serial = 6258] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-4.html]
15:54:55     INFO - --DOMWINDOW == 25 (0CAD5400) [pid = 5040] [serial = 6247] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-1c-ref.html]
15:54:55     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-4.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-4-ref.html | image comparison
15:54:55     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-4.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-4-ref.html
15:54:55     INFO - ++DOMWINDOW == 26 (091D5000) [pid = 5040] [serial = 6284] [outer = 091EA000]
15:54:55     INFO - REFTEST None
15:54:55     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-1.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-1-ref.html
15:54:55     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-1.html | 2177 / 7795 (27%)
15:54:55     INFO - ++DOMWINDOW == 27 (08FCA800) [pid = 5040] [serial = 6285] [outer = 091EA000]
15:54:55     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-1-ref.html | 2177 / 7795 (27%)
15:54:55     INFO - ++DOMWINDOW == 28 (015DF000) [pid = 5040] [serial = 6286] [outer = 091EA000]
15:54:55     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-1.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-1-ref.html | image comparison
15:54:55     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-1.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-1-ref.html
15:54:55     INFO - ++DOMWINDOW == 29 (0BCA8400) [pid = 5040] [serial = 6287] [outer = 091EA000]
15:54:55     INFO - REFTEST None
15:54:55     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-2.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-2-ref.html
15:54:55     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-2.html | 2178 / 7795 (27%)
15:54:55     INFO - ++DOMWINDOW == 30 (0D1C8800) [pid = 5040] [serial = 6288] [outer = 091EA000]
15:54:55     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-2-ref.html | 2178 / 7795 (27%)
15:54:56     INFO - ++DOMWINDOW == 31 (015DEC00) [pid = 5040] [serial = 6289] [outer = 091EA000]
15:54:56     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-2.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-2-ref.html | image comparison
15:54:56     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-2.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-2-ref.html
15:54:56     INFO - ++DOMWINDOW == 32 (0C25BC00) [pid = 5040] [serial = 6290] [outer = 091EA000]
15:54:56     INFO - REFTEST None
15:54:56     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mo-accent-dynamic.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mo-accent-dynamic-ref.html
15:54:56     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mo-accent-dynamic.html | 2179 / 7795 (27%)
15:54:56     INFO - ++DOMWINDOW == 33 (0CADB400) [pid = 5040] [serial = 6291] [outer = 091EA000]
15:54:56     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mo-accent-dynamic-ref.html | 2179 / 7795 (27%)
15:54:56     INFO - ++DOMWINDOW == 34 (091CEC00) [pid = 5040] [serial = 6292] [outer = 091EA000]
15:54:56     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mo-accent-dynamic.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mo-accent-dynamic-ref.html | image comparison
15:54:56     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mo-accent-dynamic.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mo-accent-dynamic-ref.html
15:54:56     INFO - ++DOMWINDOW == 35 (0BABAC00) [pid = 5040] [serial = 6293] [outer = 091EA000]
15:54:56     INFO - REFTEST None
15:54:56     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mo-movablelimits-dynamic.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mo-movablelimits-dynamic-ref.html
15:54:56     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mo-movablelimits-dynamic.html | 2180 / 7795 (27%)
15:54:56     INFO - ++DOMWINDOW == 36 (0C004800) [pid = 5040] [serial = 6294] [outer = 091EA000]
15:54:56     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mo-movablelimits-dynamic-ref.html | 2180 / 7795 (27%)
15:54:56     INFO - ++DOMWINDOW == 37 (091CDC00) [pid = 5040] [serial = 6295] [outer = 091EA000]
15:54:56     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mo-movablelimits-dynamic.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mo-movablelimits-dynamic-ref.html | image comparison
15:54:56     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mo-movablelimits-dynamic.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mo-movablelimits-dynamic-ref.html
15:54:56     INFO - ++DOMWINDOW == 38 (0BCA8000) [pid = 5040] [serial = 6296] [outer = 091EA000]
15:54:56     INFO - REFTEST None
15:54:56     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/munderover-accent-dynamic.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/munderover-accent-dynamic-ref.html
15:54:56     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/munderover-accent-dynamic.html | 2181 / 7795 (27%)
15:54:56     INFO - ++DOMWINDOW == 39 (0D173C00) [pid = 5040] [serial = 6297] [outer = 091EA000]
15:54:56     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/munderover-accent-dynamic-ref.html | 2181 / 7795 (27%)
15:54:56     INFO - ++DOMWINDOW == 40 (01509C00) [pid = 5040] [serial = 6298] [outer = 091EA000]
15:54:56     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/munderover-accent-dynamic.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/munderover-accent-dynamic-ref.html | image comparison
15:54:56     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/munderover-accent-dynamic.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/munderover-accent-dynamic-ref.html
15:54:56     INFO - ++DOMWINDOW == 41 (0C3B2400) [pid = 5040] [serial = 6299] [outer = 091EA000]
15:54:56     INFO - REFTEST None
15:54:56     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/munderover-accentunder-dynamic.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/munderover-accentunder-dynamic-ref.html
15:54:56     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/munderover-accentunder-dynamic.html | 2182 / 7795 (27%)
15:54:56     INFO - ++DOMWINDOW == 42 (015DDC00) [pid = 5040] [serial = 6300] [outer = 091EA000]
15:54:56     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/munderover-accentunder-dynamic-ref.html | 2182 / 7795 (27%)
15:54:56     INFO - ++DOMWINDOW == 43 (08FBB800) [pid = 5040] [serial = 6301] [outer = 091EA000]
15:54:56     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/munderover-accentunder-dynamic.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/munderover-accentunder-dynamic-ref.html | image comparison
15:54:56     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/munderover-accentunder-dynamic.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/munderover-accentunder-dynamic-ref.html
15:54:56     INFO - --DOMWINDOW == 42 (091CAC00) [pid = 5040] [serial = 6264] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-1.html]
15:54:56     INFO - --DOMWINDOW == 41 (08FC2400) [pid = 5040] [serial = 6266] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:56     INFO - --DOMWINDOW == 40 (0D299400) [pid = 5040] [serial = 6265] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-1-ref.html]
15:54:56     INFO - --DOMWINDOW == 39 (0CD27C00) [pid = 5040] [serial = 6262] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-5-ref.html]
15:54:56     INFO - --DOMWINDOW == 38 (0D298000) [pid = 5040] [serial = 6263] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:56     INFO - --DOMWINDOW == 37 (0D251000) [pid = 5040] [serial = 6260] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:56     INFO - --DOMWINDOW == 36 (0C005000) [pid = 5040] [serial = 6269] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:56     INFO - --DOMWINDOW == 35 (08F1D800) [pid = 5040] [serial = 6268] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-2-ref.html]
15:54:56     INFO - --DOMWINDOW == 34 (015D1000) [pid = 5040] [serial = 6267] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-2.html]
15:54:56     INFO - --DOMWINDOW == 33 (0BA13800) [pid = 5040] [serial = 6270] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-3.html]
15:54:56     INFO - --DOMWINDOW == 32 (08FC5800) [pid = 5040] [serial = 6271] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/dtls-3-ref.html]
15:54:56     INFO - --DOMWINDOW == 31 (0D174400) [pid = 5040] [serial = 6272] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:56     INFO - --DOMWINDOW == 30 (0C3B6000) [pid = 5040] [serial = 6274] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-1-ref.html]
15:54:56     INFO - --DOMWINDOW == 29 (091EA400) [pid = 5040] [serial = 6273] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-1.html]
15:54:56     INFO - --DOMWINDOW == 28 (0D1C4000) [pid = 5040] [serial = 6261] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathvariant-5.html]
15:54:56     INFO - ++DOMWINDOW == 29 (015D1000) [pid = 5040] [serial = 6302] [outer = 091EA000]
15:54:56     INFO - REFTEST None
15:54:56     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1a.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1-ref.html
15:54:56     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1a.html | 2183 / 7795 (28%)
15:54:56     INFO - ++DOMWINDOW == 30 (091F8800) [pid = 5040] [serial = 6303] [outer = 091EA000]
15:54:56     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1-ref.html | 2183 / 7795 (28%)
15:54:56     INFO - ++DOMWINDOW == 31 (08FC5800) [pid = 5040] [serial = 6304] [outer = 091EA000]
15:54:57     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1a.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1-ref.html | image comparison
15:54:57     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1a.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1-ref.html
15:54:57     INFO - ++DOMWINDOW == 32 (0D119C00) [pid = 5040] [serial = 6305] [outer = 091EA000]
15:54:57     INFO - REFTEST None
15:54:57     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1b.html != file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1-ref.html
15:54:57     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1b.html | 2184 / 7795 (28%)
15:54:57     INFO - ++DOMWINDOW == 33 (0C254800) [pid = 5040] [serial = 6306] [outer = 091EA000]
15:54:57     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1b.html != file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1-ref.html | image comparison
15:54:57     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1b.html != file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1-ref.html
15:54:57     INFO - ++DOMWINDOW == 34 (08FC7000) [pid = 5040] [serial = 6307] [outer = 091EA000]
15:54:57     INFO - REFTEST None
15:54:57     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1c.html != file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1-ref.html
15:54:57     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1c.html | 2185 / 7795 (28%)
15:54:57     INFO - ++DOMWINDOW == 35 (0CF6EC00) [pid = 5040] [serial = 6308] [outer = 091EA000]
15:54:57     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1c.html != file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1-ref.html | image comparison
15:54:57     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1c.html != file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-1-ref.html
15:54:57     INFO - ++DOMWINDOW == 36 (0D18CC00) [pid = 5040] [serial = 6309] [outer = 091EA000]
15:54:57     INFO - REFTEST None
15:54:57     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-2a.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-2-ref.html
15:54:57     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-2a.html | 2186 / 7795 (28%)
15:54:57     INFO - ++DOMWINDOW == 37 (0BCA9C00) [pid = 5040] [serial = 6310] [outer = 091EA000]
15:54:57     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-2-ref.html | 2186 / 7795 (28%)
15:54:57     INFO - ++DOMWINDOW == 38 (0D293400) [pid = 5040] [serial = 6311] [outer = 091EA000]
15:54:57     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-2a.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-2-ref.html | image comparison
15:54:57     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-2a.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-2-ref.html
15:54:57     INFO - ++DOMWINDOW == 39 (0C675000) [pid = 5040] [serial = 6312] [outer = 091EA000]
15:54:57     INFO - REFTEST None
15:54:57     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-2b.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-2-ref.html
15:54:57     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-2b.html | 2187 / 7795 (28%)
15:54:57     INFO - ++DOMWINDOW == 40 (0C25C800) [pid = 5040] [serial = 6313] [outer = 091EA000]
15:54:57     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-2b.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-2-ref.html | image comparison
15:54:57     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-2b.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-2-ref.html
15:54:57     INFO - ++DOMWINDOW == 41 (0E135800) [pid = 5040] [serial = 6314] [outer = 091EA000]
15:54:57     INFO - REFTEST None
15:54:57     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-3-1.html != file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-3-1-ref.html
15:54:57     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-3-1.html | 2188 / 7795 (28%)
15:54:57     INFO - ++DOMWINDOW == 42 (015D9800) [pid = 5040] [serial = 6315] [outer = 091EA000]
15:54:57     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-3-1-ref.html | 2188 / 7795 (28%)
15:54:57     INFO - ++DOMWINDOW == 43 (08FC8400) [pid = 5040] [serial = 6316] [outer = 091EA000]
15:54:57     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-3-1.html != file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-3-1-ref.html | image comparison
15:54:57     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-3-1.html != file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-3-1-ref.html
15:54:57     INFO - ++DOMWINDOW == 44 (08FBDC00) [pid = 5040] [serial = 6317] [outer = 091EA000]
15:54:57     INFO - REFTEST None
15:54:57     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-3-2.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-3-2-ref.html
15:54:57     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-3-2.html | 2189 / 7795 (28%)
15:54:57     INFO - --DOMWINDOW == 43 (0C3AD000) [pid = 5040] [serial = 6277] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-2-ref.html]
15:54:57     INFO - --DOMWINDOW == 42 (0BF8F000) [pid = 5040] [serial = 6279] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-3.html]
15:54:57     INFO - --DOMWINDOW == 41 (0D58A800) [pid = 5040] [serial = 6278] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:57     INFO - --DOMWINDOW == 40 (0CADA000) [pid = 5040] [serial = 6275] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:57     INFO - --DOMWINDOW == 39 (015DD800) [pid = 5040] [serial = 6281] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:57     INFO - --DOMWINDOW == 38 (0C3AF000) [pid = 5040] [serial = 6280] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-3-ref.html]
15:54:57     INFO - --DOMWINDOW == 37 (0D32C400) [pid = 5040] [serial = 6276] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-2.html]
15:54:57     INFO - --DOMWINDOW == 36 (015DEC00) [pid = 5040] [serial = 6289] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-2-ref.html]
15:54:57     INFO - --DOMWINDOW == 35 (0D1C8800) [pid = 5040] [serial = 6288] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-2.html]
15:54:57     INFO - --DOMWINDOW == 34 (0BCA8400) [pid = 5040] [serial = 6287] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:57     INFO - --DOMWINDOW == 33 (015DF000) [pid = 5040] [serial = 6286] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-1-ref.html]
15:54:57     INFO - --DOMWINDOW == 32 (08FCA800) [pid = 5040] [serial = 6285] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/mathscript-1.html]
15:54:57     INFO - --DOMWINDOW == 31 (091D5000) [pid = 5040] [serial = 6284] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:57     INFO - --DOMWINDOW == 30 (08F19C00) [pid = 5040] [serial = 6283] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-4-ref.html]
15:54:57     INFO - --DOMWINDOW == 29 (091D3400) [pid = 5040] [serial = 6282] [outer = 00000000] [url = file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/ssty-4.html]
15:54:57     INFO - --DOMWINDOW == 28 (0C25BC00) [pid = 5040] [serial = 6290] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
15:54:58     INFO - ++DOMWINDOW == 29 (015DEC00) [pid = 5040] [serial = 6318] [outer = 091EA000]
15:54:58     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-3-2-ref.html | 2189 / 7795 (28%)
15:54:58     INFO - ++DOMWINDOW == 30 (091CC400) [pid = 5040] [serial = 6319] [outer = 091EA000]
15:54:58     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-3-2.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-3-2-ref.html | image comparison
15:54:58     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-3-2.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/columnlines-3-2-ref.html
15:54:58     INFO - ++DOMWINDOW == 31 (0BA18000) [pid = 5040] [serial = 6320] [outer = 091EA000]
15:54:58     INFO - REFTEST None
15:54:58     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1a.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1-ref.html
15:54:58     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1a.html | 2190 / 7795 (28%)
15:54:58     INFO - ++DOMWINDOW == 32 (0C256C00) [pid = 5040] [serial = 6321] [outer = 091EA000]
15:54:58     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1-ref.html | 2190 / 7795 (28%)
15:54:58     INFO - ++DOMWINDOW == 33 (0C260800) [pid = 5040] [serial = 6322] [outer = 091EA000]
15:54:58     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1a.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1-ref.html | image comparison
15:54:58     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1a.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1-ref.html
15:54:58     INFO - ++DOMWINDOW == 34 (0D3A3000) [pid = 5040] [serial = 6323] [outer = 091EA000]
15:54:58     INFO - REFTEST None
15:54:58     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1b.html != file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1-ref.html
15:54:58     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1b.html | 2191 / 7795 (28%)
15:54:58     INFO - ++DOMWINDOW == 35 (08FC3400) [pid = 5040] [serial = 6324] [outer = 091EA000]
15:54:58     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1b.html != file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1-ref.html | image comparison
15:54:58     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1b.html != file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1-ref.html
15:54:58     INFO - ++DOMWINDOW == 36 (0C00E000) [pid = 5040] [serial = 6325] [outer = 091EA000]
15:54:58     INFO - REFTEST None
15:54:58     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1c.html != file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1-ref.html
15:54:58     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1c.html | 2192 / 7795 (28%)
15:54:58     INFO - ++DOMWINDOW == 37 (0C25E800) [pid = 5040] [serial = 6326] [outer = 091EA000]
15:54:58     INFO - REFTEST TEST-PASS | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1c.html != file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1-ref.html | image comparison
15:54:58     INFO - REFTEST TEST-END | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1c.html != file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-1-ref.html
15:54:58     INFO - ++DOMWINDOW == 38 (0D10E000) [pid = 5040] [serial = 6327] [outer = 091EA000]
15:54:58     INFO - REFTEST None
15:54:58     INFO - REFTEST TEST-START | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-2a.html == file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-2-ref.html
15:54:58     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-2a.html | 2193 / 7795 (28%)
15:54:58     INFO - ++DOMWINDOW == 39 (0D18B000) [pid = 5040] [serial = 6328] [outer = 091EA000]
15:54:58     INFO - REFTEST TEST-LOAD | file:///C:/slave/test/build/tests/reftest/tests/layout/reftests/mathml/rowlines-2-ref.html | 2193 / 7795 (28%)
'bash' '-c' u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 3d1ce85e6348307a1e98284e6d13da828729bf91 --destination scripts --debug'
 in dir C:\slave\test\. (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 3d1ce85e6348307a1e98284e6d13da828729bf91 --destination scripts --debug']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld\AppData\Roaming
  BUILDBOT_PATH=C:\mozilla-build\
  CHOCOLATEYINSTALL=C:\ProgramData\chocolatey
  COMMONPROGRAMFILES=C:\Program Files\Common Files
  COMPUTERNAME=G-W732-SPOT-156
  COMSPEC=C:\windows\system32\cmd.exe
  DCLOCATION=SCL3
  DNSSUFFIX=wintest.releng.scl3.mozilla.com
  FP_NO_HOST_CHECK=NO
  GIT_SHARE_BASE_DIR=/builds/git-shared
  HG_SHARE_BASE_DIR=/builds/hg-shared
  HOMEDRIVE=C:
  HOMEPATH=\Users\cltbld
  IDLEIZER_HALT_ON_IDLE=true
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LOCALAPPDATA=C:\Users\cltbld\AppData\Local
  LOG="c:\runner\buildbot-startup.log"
  LOGONSERVER=\\G-W732-SPOT-156
  MAKE_MODE=unix
  MOZBUILDDIR=C:\mozilla-build
  MOZILLABUILD=C:\mozilla-build
  MOZ_TOOLS=C:\mozilla-build\moztools
  MSYSTEM=MINGW32
  NUMBER_OF_PROCESSORS=8
  OS=Windows_NT
  PATH=C:\Program Files\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;c:\Program Files\Microsoft Windows Performance Toolkit\;c:\mozilla-build\hg\;C:\Program Files (x86)\Microsoft SDKs\TypeScript\1.0\;C:\Program Files (x86)\Puppet Labs\Puppet\bin;C:\Program Files (x86)\Puppet Labs\Puppet\facter\bin;C:\Program Files (x86)\Puppet Labs\Puppet\hiera\bin;C:\Program Files (x86)\Puppet Labs\Puppet\puppet\bin;C:\Program Files (x86)\Puppet Labs\Puppet\sys\ruby\bin;C:\Program Files (x86)\Puppet Labs\Puppet\sys\tools\bin;C:\Program Files (x86)\Windows Kits\8.0\Windows Performance Toolkit\;C:\Program Files (x86)\Windows Kits\8.1\Windows Performance Toolkit\;C:\Program Files\Amazon\cfn-bootstrap\;C:\Program Files\Microsoft SQL Server\110\Tools\Binn\;C:\mozilla-build\buildbotve;C:\mozilla-build\msys\bin;C:\mozilla-build\msys\local\bin;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\python27;C:\opt\runner;c:\Program Files (x86)\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\DTS\Binn\;c:\Program Files\Microsoft SQL Server\100\Tools\Binn;C:\Users\cltbld\AppData\Local\Programs\Common\Microsoft\Visual C++ for Python\9.0;;C:\mozilla-build\moztools\bin
  PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
  PROCESSOR_ARCHITECTURE=x86
  PROCESSOR_IDENTIFIER=x86 Family 6 Model 62 Stepping 4, GenuineIntel
  PROCESSOR_LEVEL=6
  PROCESSOR_REVISION=3e04
  PROGRAMDATA=C:\ProgramData
  PROGRAMFILES=C:\Program Files
  PROMPT=$P$G
  PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\;C:\Program Files\AWS Tools\PowerShell\
  PUBLIC=C:\Users\Public
  PWD=C:\slave\test
  RUNNER_CONFIG_CMD=C:\opt\runner\Scripts\python2.7.exe C:\opt\runner\Scripts\runner-script.py -c c:\opt\runner\runner.cfg
  SYSTEMDRIVE=C:
  SYSTEMROOT=C:\windows
  TEMP=C:\Users\cltbld\AppData\Local\Temp
  TEST1=testie
  TMP=C:\Users\cltbld\AppData\Local\Temp
  USERDOMAIN=G-W732-SPOT-156
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld
  WINDIR=C:\windows
  WINDOWS_TRACING_FLAGS=3
  WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
 using PTY: False
2017-06-12 15:54:59,013 truncating revision to first 12 chars
2017-06-12 15:54:59,013 Setting DEBUG logging.
2017-06-12 15:54:59,013 attempt 1/10
2017-06-12 15:54:59,013 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/3d1ce85e6348?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-06-12 15:55:00,441 unpacking tar archive at: mozilla-inbound-3d1ce85e6348/testing/mozharness/
2017-06-12 15:55:00,690 skipping symlink on windows: configs/single_locale/linux32.py
2017-06-12 15:55:00,690 skipping symlink on windows: configs/single_locale/linux32_devedition.py
program finished with exit code 0
elapsedTime=3.716000
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-06-12 15:55:02.866373) =========

========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 15:55:02.867302) =========
script_repo_revision: 3d1ce85e6348307a1e98284e6d13da828729bf91
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 15:55:02.868316) =========

========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 15:55:02.868734) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 15:55:02.888762) =========

========= Started 'c:/mozilla-build/python27/python -u ...' warnings (results: 1, elapsed: 0 secs) (at 2017-06-12 15:55:02.889045) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/desktop_unittest.py' '--cfg' 'unittests/win_unittest.py' '--reftest-suite' 'reftest-no-accel' '--e10s' '--total-chunks' '2' '--this-chunk' '2' '--blob-upload-branch' 'mozilla-inbound' '--download-symbols' 'true'
 in dir C:\slave\test\. (timeout 1800 secs) (maxTime 7200 secs)
 watching logfiles {}
 argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/desktop_unittest.py', '--cfg', 'unittests/win_unittest.py', '--reftest-suite', 'reftest-no-accel', '--e10s', '--total-chunks', '2', '--this-chunk', '2', '--blob-upload-branch', 'mozilla-inbound', '--download-symbols', 'true']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld\AppData\Roaming
  BUILDBOT_PATH=C:\mozilla-build\
  CHOCOLATEYINSTALL=C:\ProgramData\chocolatey
  COMMONPROGRAMFILES=C:\Program Files\Common Files
  COMPUTERNAME=G-W732-SPOT-156
  COMSPEC=C:\windows\system32\cmd.exe
  DCLOCATION=SCL3
  DNSSUFFIX=wintest.releng.scl3.mozilla.com
  DevEnvDir=D:\msvs8\Common7\IDE
  FP_NO_HOST_CHECK=NO
  FrameworkDir=C:\WINDOWS\Microsoft.NET\Framework
  FrameworkSDKDir=D:\msvs8\SDK\v2.0
  FrameworkVersion=v2.0.50727
  GIT_SHARE_BASE_DIR=/builds/git-shared
  HG_SHARE_BASE_DIR=/builds/hg-shared
  HOMEDRIVE=C:
  HOMEPATH=\Users\cltbld
  IDLEIZER_HALT_ON_IDLE=true
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LIBPATH=C:\WINDOWS\Microsoft.NET\Framework\v2.0.50727;D:\msvs8\VC\ATLMFC\LIB
  LOCALAPPDATA=C:\Users\cltbld\AppData\Local
  LOG="c:\runner\buildbot-startup.log"
  LOGONSERVER=\\G-W732-SPOT-156
  MAKE_MODE=unix
  MOZBUILDDIR=C:\mozilla-build
  MOZILLABUILD=D:\mozilla-build
  MOZILLABUILDDRIVE=C:
  MOZILLABUILDPATH=\mozilla-build\
  MOZ_AIRBAG=1
  MOZ_CRASHREPORTER_NO_REPORT=1
  MOZ_HIDE_RESULTS_TABLE=1
  MOZ_MSVCVERSION=8
  MOZ_NO_REMOTE=1
  MOZ_TOOLS=D:\mozilla-build\moztools
  MSVCDir=D:\msvs8\VC
  MSYSTEM=MINGW32
  NO_EM_RESTART=1
  NO_FAIL_ON_TEST_ERRORS=1
  NUMBER_OF_PROCESSORS=8
  OS=Windows_NT
  PATH=C:\Program Files\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;c:\Program Files\Microsoft Windows Performance Toolkit\;c:\mozilla-build\hg\;C:\Program Files (x86)\Microsoft SDKs\TypeScript\1.0\;C:\Program Files (x86)\Puppet Labs\Puppet\bin;C:\Program Files (x86)\Puppet Labs\Puppet\facter\bin;C:\Program Files (x86)\Puppet Labs\Puppet\hiera\bin;C:\Program Files (x86)\Puppet Labs\Puppet\puppet\bin;C:\Program Files (x86)\Puppet Labs\Puppet\sys\ruby\bin;C:\Program Files (x86)\Puppet Labs\Puppet\sys\tools\bin;C:\Program Files (x86)\Windows Kits\8.0\Windows Performance Toolkit\;C:\Program Files (x86)\Windows Kits\8.1\Windows Performance Toolkit\;C:\Program Files\Amazon\cfn-bootstrap\;C:\Program Files\Microsoft SQL Server\110\Tools\Binn\;C:\mozilla-build\buildbotve;C:\mozilla-build\msys\bin;C:\mozilla-build\msys\local\bin;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\python27;C:\opt\runner;c:\Program Files (x86)\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\DTS\Binn\;c:\Program Files\Microsoft SQL Server\100\Tools\Binn;C:\Users\cltbld\AppData\Local\Programs\Common\Microsoft\Visual C++ for Python\9.0;;C:\mozilla-build\moztools\bin
  PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
  PROCESSOR_ARCHITECTURE=x86
  PROCESSOR_IDENTIFIER=x86 Family 6 Model 62 Stepping 4, GenuineIntel
  PROCESSOR_LEVEL=6
  PROCESSOR_REVISION=3e04
  PROGRAMDATA=C:\ProgramData
  PROGRAMFILES=C:\Program Files
  PROMPT=$P$G
  PROPERTIES_FILE=C:\slave\test/buildprops.json
  PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\;C:\Program Files\AWS Tools\PowerShell\
  PUBLIC=C:\Users\Public
  PWD=C:\slave\test
  RUNNER_CONFIG_CMD=C:\opt\runner\Scripts\python2.7.exe C:\opt\runner\Scripts\runner-script.py -c c:\opt\runner\runner.cfg
  SYSTEMDRIVE=C:
  SYSTEMROOT=C:\windows
  TEMP=C:\Users\cltbld\AppData\Local\Temp
  TEST1=testie
  TMP=C:\Users\cltbld\AppData\Local\Temp
  USERDOMAIN=G-W732-SPOT-156
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld
  VCINSTALLDIR=D:\msvs8\VC
  VCVARS=D:\msvs8\VC\bin\vcvars32.bat
  VSINSTALLDIR=D:\msvs8
  WINDIR=C:\windows
  WINDOWS_TRACING_FLAGS=3
  WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
  XPCOM_DEBUG_BREAK=warn
 using PTY: False
Traceback (most recent call last):
  File "scripts/scripts/desktop_unittest.py", line 759, in <module>
    desktop_unittest = DesktopUnittest()
  File "scripts/scripts/desktop_unittest.py", line 184, in __init__
    config={'require_test_zip': True})
  File "C:\slave\test\scripts\mozharness\base\vcs\vcsbase.py", line 125, in __init__
    super(VCSScript, self).__init__(**kwargs)
  File "C:\slave\test\scripts\mozharness\base\script.py", line 1834, in __init__
    self.new_log_obj(default_log_level=default_log_level)
  File "C:\slave\test\scripts\mozharness\base\script.py", line 2164, in new_log_obj
    self.log_obj = MultiFileLogger(**log_config)
  File "C:\slave\test\scripts\mozharness\base\log.py", line 663, in __init__
    self.new_logger()
  File "C:\slave\test\scripts\mozharness\base\log.py", line 671, in new_logger
    BaseLogger.new_logger(self)
  File "C:\slave\test\scripts\mozharness\base\log.py", line 502, in new_logger
    log_format='%(message)s')
  File "C:\slave\test\scripts\mozharness\base\log.py", line 557, in add_file_handler
    os.remove(log_path)
WindowsError: [Error 32] The process cannot access the file because it is being used by another process: 'C:\\slave\\test\\logs\\log_raw.log'
program finished with exit code 1
elapsedTime=0.901000
========= master_lag: 0.03 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' warnings (results: 1, elapsed: 0 secs) (at 2017-06-12 15:55:03.824431) =========

========= Started no change (results: 0, elapsed: 0 secs) (at 2017-06-12 15:55:03.825289) =========
'cmd' '/C' 'for' '%f' 'in' '(*)' 'do' '@type' '%f'
 in dir C:\slave\test\properties (timeout 1200 secs)
 watching logfiles {}
 argv: ['cmd', '/C', 'for', '%f', 'in', '(*)', 'do', '@type', '%f']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld\AppData\Roaming
  BUILDBOT_PATH=C:\mozilla-build\
  CHOCOLATEYINSTALL=C:\ProgramData\chocolatey
  COMMONPROGRAMFILES=C:\Program Files\Common Files
  COMPUTERNAME=G-W732-SPOT-156
  COMSPEC=C:\windows\system32\cmd.exe
  DCLOCATION=SCL3
  DNSSUFFIX=wintest.releng.scl3.mozilla.com
  FP_NO_HOST_CHECK=NO
  GIT_SHARE_BASE_DIR=/builds/git-shared
  HG_SHARE_BASE_DIR=/builds/hg-shared
  HOMEDRIVE=C:
  HOMEPATH=\Users\cltbld
  IDLEIZER_HALT_ON_IDLE=true
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LOCALAPPDATA=C:\Users\cltbld\AppData\Local
  LOG="c:\runner\buildbot-startup.log"
  LOGONSERVER=\\G-W732-SPOT-156
  MAKE_MODE=unix
  MOZBUILDDIR=C:\mozilla-build
  MOZILLABUILD=C:\mozilla-build
  MOZ_TOOLS=C:\mozilla-build\moztools
  MSYSTEM=MINGW32
  NUMBER_OF_PROCESSORS=8
  OS=Windows_NT
  PATH=C:\Program Files\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;c:\Program Files\Microsoft Windows Performance Toolkit\;c:\mozilla-build\hg\;C:\Program Files (x86)\Microsoft SDKs\TypeScript\1.0\;C:\Program Files (x86)\Puppet Labs\Puppet\bin;C:\Program Files (x86)\Puppet Labs\Puppet\facter\bin;C:\Program Files (x86)\Puppet Labs\Puppet\hiera\bin;C:\Program Files (x86)\Puppet Labs\Puppet\puppet\bin;C:\Program Files (x86)\Puppet Labs\Puppet\sys\ruby\bin;C:\Program Files (x86)\Puppet Labs\Puppet\sys\tools\bin;C:\Program Files (x86)\Windows Kits\8.0\Windows Performance Toolkit\;C:\Program Files (x86)\Windows Kits\8.1\Windows Performance Toolkit\;C:\Program Files\Amazon\cfn-bootstrap\;C:\Program Files\Microsoft SQL Server\110\Tools\Binn\;C:\mozilla-build\buildbotve;C:\mozilla-build\msys\bin;C:\mozilla-build\msys\local\bin;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\python27;C:\opt\runner;c:\Program Files (x86)\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\DTS\Binn\;c:\Program Files\Microsoft SQL Server\100\Tools\Binn;C:\Users\cltbld\AppData\Local\Programs\Common\Microsoft\Visual C++ for Python\9.0;;C:\mozilla-build\moztools\bin
  PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
  PROCESSOR_ARCHITECTURE=x86
  PROCESSOR_IDENTIFIER=x86 Family 6 Model 62 Stepping 4, GenuineIntel
  PROCESSOR_LEVEL=6
  PROCESSOR_REVISION=3e04
  PROGRAMDATA=C:\ProgramData
  PROGRAMFILES=C:\Program Files
  PROMPT=$P$G
  PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\;C:\Program Files\AWS Tools\PowerShell\
  PUBLIC=C:\Users\Public
  PWD=C:\slave\test\properties
  RUNNER_CONFIG_CMD=C:\opt\runner\Scripts\python2.7.exe C:\opt\runner\Scripts\runner-script.py -c c:\opt\runner\runner.cfg
  SYSTEMDRIVE=C:
  SYSTEMROOT=C:\windows
  TEMP=C:\Users\cltbld\AppData\Local\Temp
  TEST1=testie
  TMP=C:\Users\cltbld\AppData\Local\Temp
  USERDOMAIN=G-W732-SPOT-156
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld
  WINDIR=C:\windows
  WINDOWS_TRACING_FLAGS=3
  WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
 using PTY: False
program finished with exit code 0
elapsedTime=0.102000

========= master_lag: 0.01 =========
========= Finished no change (results: 0, elapsed: 0 secs) (at 2017-06-12 15:55:03.939806) =========

========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 15:55:03.940139) =========
'rm' '-f' 'oauth.txt'
 in dir C:\slave\test\. (timeout 1200 secs)
 watching logfiles {}
 argv: ['rm', '-f', 'oauth.txt']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld\AppData\Roaming
  BUILDBOT_PATH=C:\mozilla-build\
  CHOCOLATEYINSTALL=C:\ProgramData\chocolatey
  COMMONPROGRAMFILES=C:\Program Files\Common Files
  COMPUTERNAME=G-W732-SPOT-156
  COMSPEC=C:\windows\system32\cmd.exe
  DCLOCATION=SCL3
  DNSSUFFIX=wintest.releng.scl3.mozilla.com
  FP_NO_HOST_CHECK=NO
  GIT_SHARE_BASE_DIR=/builds/git-shared
  HG_SHARE_BASE_DIR=/builds/hg-shared
  HOMEDRIVE=C:
  HOMEPATH=\Users\cltbld
  IDLEIZER_HALT_ON_IDLE=true
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LOCALAPPDATA=C:\Users\cltbld\AppData\Local
  LOG="c:\runner\buildbot-startup.log"
  LOGONSERVER=\\G-W732-SPOT-156
  MAKE_MODE=unix
  MOZBUILDDIR=C:\mozilla-build
  MOZILLABUILD=C:\mozilla-build
  MOZ_TOOLS=C:\mozilla-build\moztools
  MSYSTEM=MINGW32
  NUMBER_OF_PROCESSORS=8
  OS=Windows_NT
  PATH=C:\Program Files\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;c:\Program Files\Microsoft Windows Performance Toolkit\;c:\mozilla-build\hg\;C:\Program Files (x86)\Microsoft SDKs\TypeScript\1.0\;C:\Program Files (x86)\Puppet Labs\Puppet\bin;C:\Program Files (x86)\Puppet Labs\Puppet\facter\bin;C:\Program Files (x86)\Puppet Labs\Puppet\hiera\bin;C:\Program Files (x86)\Puppet Labs\Puppet\puppet\bin;C:\Program Files (x86)\Puppet Labs\Puppet\sys\ruby\bin;C:\Program Files (x86)\Puppet Labs\Puppet\sys\tools\bin;C:\Program Files (x86)\Windows Kits\8.0\Windows Performance Toolkit\;C:\Program Files (x86)\Windows Kits\8.1\Windows Performance Toolkit\;C:\Program Files\Amazon\cfn-bootstrap\;C:\Program Files\Microsoft SQL Server\110\Tools\Binn\;C:\mozilla-build\buildbotve;C:\mozilla-build\msys\bin;C:\mozilla-build\msys\local\bin;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\python27;C:\opt\runner;c:\Program Files (x86)\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\DTS\Binn\;c:\Program Files\Microsoft SQL Server\100\Tools\Binn;C:\Users\cltbld\AppData\Local\Programs\Common\Microsoft\Visual C++ for Python\9.0;;C:\mozilla-build\moztools\bin
  PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
  PROCESSOR_ARCHITECTURE=x86
  PROCESSOR_IDENTIFIER=x86 Family 6 Model 62 Stepping 4, GenuineIntel
  PROCESSOR_LEVEL=6
  PROCESSOR_REVISION=3e04
  PROGRAMDATA=C:\ProgramData
  PROGRAMFILES=C:\Program Files
  PROMPT=$P$G
  PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\;C:\Program Files\AWS Tools\PowerShell\
  PUBLIC=C:\Users\Public
  PWD=C:\slave\test
  RUNNER_CONFIG_CMD=C:\opt\runner\Scripts\python2.7.exe C:\opt\runner\Scripts\runner-script.py -c c:\opt\runner\runner.cfg
  SYSTEMDRIVE=C:
  SYSTEMROOT=C:\windows
  TEMP=C:\Users\cltbld\AppData\Local\Temp
  TEST1=testie
  TMP=C:\Users\cltbld\AppData\Local\Temp
  USERDOMAIN=G-W732-SPOT-156
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld
  WINDIR=C:\windows
  WINDOWS_TRACING_FLAGS=3
  WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
 using PTY: False
program finished with exit code 0
elapsedTime=0.101000
========= master_lag: 0.01 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 15:55:04.050035) =========

========= Started reboot slave lost (results: 2, elapsed: 0 secs) (at 2017-06-12 15:55:04.050335) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/external_tools/count_and_reboot.py' '-f' '../reboot_count.txt' '-n' '1' '-z'
 in dir C:\slave\test\. (timeout 1200 secs)
 watching logfiles {}
 argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/external_tools/count_and_reboot.py', '-f', '../reboot_count.txt', '-n', '1', '-z']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld\AppData\Roaming
  BUILDBOT_PATH=C:\mozilla-build\
  CHOCOLATEYINSTALL=C:\ProgramData\chocolatey
  COMMONPROGRAMFILES=C:\Program Files\Common Files
  COMPUTERNAME=G-W732-SPOT-156
  COMSPEC=C:\windows\system32\cmd.exe
  DCLOCATION=SCL3
  DNSSUFFIX=wintest.releng.scl3.mozilla.com
  DevEnvDir=D:\msvs8\Common7\IDE
  FP_NO_HOST_CHECK=NO
  FrameworkDir=C:\WINDOWS\Microsoft.NET\Framework
  FrameworkSDKDir=D:\msvs8\SDK\v2.0
  FrameworkVersion=v2.0.50727
  GIT_SHARE_BASE_DIR=/builds/git-shared
  HG_SHARE_BASE_DIR=/builds/hg-shared
  HOMEDRIVE=C:
  HOMEPATH=\Users\cltbld
  IDLEIZER_HALT_ON_IDLE=true
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LIBPATH=C:\WINDOWS\Microsoft.NET\Framework\v2.0.50727;D:\msvs8\VC\ATLMFC\LIB
  LOCALAPPDATA=C:\Users\cltbld\AppData\Local
  LOG="c:\runner\buildbot-startup.log"
  LOGONSERVER=\\G-W732-SPOT-156
  MAKE_MODE=unix
  MOZBUILDDIR=C:\mozilla-build
  MOZILLABUILD=D:\mozilla-build
  MOZILLABUILDDRIVE=C:
  MOZILLABUILDPATH=\mozilla-build\
  MOZ_AIRBAG=1
  MOZ_CRASHREPORTER_NO_REPORT=1
  MOZ_HIDE_RESULTS_TABLE=1
  MOZ_MSVCVERSION=8
  MOZ_NO_REMOTE=1
  MOZ_TOOLS=D:\mozilla-build\moztools
  MSVCDir=D:\msvs8\VC
  MSYSTEM=MINGW32
  NO_EM_RESTART=1
  NO_FAIL_ON_TEST_ERRORS=1
  NUMBER_OF_PROCESSORS=8
  OS=Windows_NT
  PATH=C:\Program Files\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;c:\Program Files\Microsoft Windows Performance Toolkit\;c:\mozilla-build\hg\;C:\Program Files (x86)\Microsoft SDKs\TypeScript\1.0\;C:\Program Files (x86)\Puppet Labs\Puppet\bin;C:\Program Files (x86)\Puppet Labs\Puppet\facter\bin;C:\Program Files (x86)\Puppet Labs\Puppet\hiera\bin;C:\Program Files (x86)\Puppet Labs\Puppet\puppet\bin;C:\Program Files (x86)\Puppet Labs\Puppet\sys\ruby\bin;C:\Program Files (x86)\Puppet Labs\Puppet\sys\tools\bin;C:\Program Files (x86)\Windows Kits\8.0\Windows Performance Toolkit\;C:\Program Files (x86)\Windows Kits\8.1\Windows Performance Toolkit\;C:\Program Files\Amazon\cfn-bootstrap\;C:\Program Files\Microsoft SQL Server\110\Tools\Binn\;C:\mozilla-build\buildbotve;C:\mozilla-build\msys\bin;C:\mozilla-build\msys\local\bin;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\python27;C:\opt\runner;c:\Program Files (x86)\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\DTS\Binn\;c:\Program Files\Microsoft SQL Server\100\Tools\Binn;C:\Users\cltbld\AppData\Local\Programs\Common\Microsoft\Visual C++ for Python\9.0;;C:\mozilla-build\moztools\bin
  PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
  PROCESSOR_ARCHITECTURE=x86
  PROCESSOR_IDENTIFIER=x86 Family 6 Model 62 Stepping 4, GenuineIntel
  PROCESSOR_LEVEL=6
  PROCESSOR_REVISION=3e04
  PROGRAMDATA=C:\ProgramData
  PROGRAMFILES=C:\Program Files
  PROMPT=$P$G
  PROPERTIES_FILE=C:\slave\test/buildprops.json
  PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\;C:\Program Files\AWS Tools\PowerShell\
  PUBLIC=C:\Users\Public
  PWD=C:\slave\test
  RUNNER_CONFIG_CMD=C:\opt\runner\Scripts\python2.7.exe C:\opt\runner\Scripts\runner-script.py -c c:\opt\runner\runner.cfg
  SYSTEMDRIVE=C:
  SYSTEMROOT=C:\windows
  TEMP=C:\Users\cltbld\AppData\Local\Temp
  TEST1=testie
  TMP=C:\Users\cltbld\AppData\Local\Temp
  USERDOMAIN=G-W732-SPOT-156
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld
  VCINSTALLDIR=D:\msvs8\VC
  VCVARS=D:\msvs8\VC\bin\vcvars32.bat
  VSINSTALLDIR=D:\msvs8
  WINDIR=C:\windows
  WINDOWS_TRACING_FLAGS=3
  WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
  XPCOM_DEBUG_BREAK=warn
 using PTY: False
************************************************************************************************
*********** END OF RUN - NOW DOING SCHEDULED REBOOT; FOLLOWING ERROR MESSAGE EXPECTED **********
************************************************************************************************
program finished with exit code 1073807364
elapsedTime=0.279000
========= master_lag: 0.12 =========
========= Finished reboot slave lost (results: 2, elapsed: 0 secs) (at 2017-06-12 15:55:04.453587) =========

========= Total master_lag: 2.28 =========