builder: mozilla-inbound_win7-ix_test-dromaeojs-pgo slave: t-w732-ix-052 starttime: 1445818254.68 results: success (0) buildid: 20151025133003 builduid: aa0a500302a04a3287f2a38fdea396f8 revision: 61d1f8675fc39e0fd1abb9828bbd30e324eb3234 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-25 17:10:54.683219) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-25 17:10:54.685059) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-25 17:10:54.685445) ========= 'cd' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['cd'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-052 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-052 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-052 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False C:\slave\test-pgo program finished with exit code 0 elapsedTime=0.583000 basedir: 'C:\\slave\\test-pgo' ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-25 17:10:55.393057) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-25 17:10:55.393463) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-25 17:10:55.845097) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-25 17:10:55.845424) ========= 'rm' '-rf' 'properties' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-052 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-052 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-052 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.501000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-25 17:10:56.692199) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-25 17:10:56.692591) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-25 17:10:56.693367) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-25 17:10:56.693704) ========= '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-pgo\. (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 COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-052 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-052 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-052 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False --17:10:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 2.27 GB/s 17:10:57 (2.27 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.744000 ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-25 17:10:57.572371) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2015-10-25 17:10:57.572736) ========= 'rm' '-rf' 'scripts' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-052 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-052 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-052 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=9.301000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2015-10-25 17:11:06.907525) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-25 17:11:06.908459) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 61d1f8675fc39e0fd1abb9828bbd30e324eb3234 --destination scripts --debug' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 61d1f8675fc39e0fd1abb9828bbd30e324eb3234 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-052 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-052 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-052 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2015-10-25 17:11:07,250 truncating revision to first 12 chars 2015-10-25 17:11:07,250 Setting DEBUG logging. 2015-10-25 17:11:07,250 attempt 1/10 2015-10-25 17:11:07,250 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/61d1f8675fc3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-25 17:11:07,688 unpacking tar archive at: mozilla-inbound-61d1f8675fc3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.174000 ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-25 17:11:08.118126) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-25 17:11:08.118598) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-25 17:11:08.133056) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-25 17:11:08.133411) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-25 17:11:08.133882) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 57 secs) (at 2015-10-25 17:11:08.134254) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Mozilla-Inbound' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Mozilla-Inbound' in dir C:\slave\test-pgo\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-052 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-052 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PROPERTIES_FILE=C:\slave\test-pgo/buildprops.json PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-052 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 17:11:08 INFO - MultiFileLogger online at 20151025 17:11:08 in C:\slave\test-pgo 17:11:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 17:11:08 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 17:11:08 INFO - {'append_to_log': False, 17:11:08 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 17:11:08 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 17:11:08 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 17:11:08 INFO - 'branch': 'Mozilla-Inbound', 17:11:08 INFO - 'buildbot_json_path': 'buildprops.json', 17:11:08 INFO - 'config_files': ('talos/windows_config.py',), 17:11:08 INFO - 'default_actions': ('clobber', 17:11:08 INFO - 'read-buildbot-config', 17:11:08 INFO - 'download-and-extract', 17:11:08 INFO - 'populate-webroot', 17:11:08 INFO - 'create-virtualenv', 17:11:08 INFO - 'install', 17:11:08 INFO - 'run-tests'), 17:11:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:11:08 INFO - 'download_minidump_stackwalk': True, 17:11:08 INFO - 'download_symbols': 'ondemand', 17:11:08 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 17:11:08 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 17:11:08 INFO - 'hg': 'c:/mozilla-build/hg/hg', 17:11:08 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 17:11:08 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 17:11:08 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 17:11:08 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 17:11:08 INFO - 'C:/mozilla-build/tooltool.py'), 17:11:08 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 17:11:08 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 17:11:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:11:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:11:08 INFO - 'installer_path': 'installer.exe', 17:11:08 INFO - 'log_level': 'info', 17:11:08 INFO - 'log_name': 'talos', 17:11:08 INFO - 'log_to_console': True, 17:11:08 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 17:11:08 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 17:11:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 17:11:08 INFO - 'opt_config_files': (), 17:11:08 INFO - 'pip_index': False, 17:11:08 INFO - 'sps_profile': False, 17:11:08 INFO - 'sps_profile_interval': 0, 17:11:08 INFO - 'suite': 'dromaeojs', 17:11:08 INFO - 'system_bits': '32', 17:11:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:11:08 INFO - 'title': 'T-W732-IX-052', 17:11:08 INFO - 'use_talos_json': True, 17:11:08 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 17:11:08 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 17:11:08 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 17:11:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:11:08 INFO - 'work_dir': 'build'} 17:11:08 INFO - ##### 17:11:08 INFO - ##### Running clobber step. 17:11:08 INFO - ##### 17:11:08 INFO - Running pre-action listener: _resource_record_pre_action 17:11:08 INFO - Running main action method: clobber 17:11:08 INFO - rmtree: C:\slave\test-pgo\build 17:11:08 INFO - Using _rmtree_windows ... 17:11:08 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 17:13:31 INFO - Running post-action listener: _resource_record_post_action 17:13:31 INFO - ##### 17:13:31 INFO - ##### Running read-buildbot-config step. 17:13:31 INFO - ##### 17:13:31 INFO - Running pre-action listener: _resource_record_pre_action 17:13:31 INFO - Running main action method: read_buildbot_config 17:13:31 INFO - Using buildbot properties: 17:13:31 INFO - { 17:13:31 INFO - "properties": { 17:13:31 INFO - "buildnumber": 251, 17:13:31 INFO - "product": "firefox", 17:13:31 INFO - "script_repo_revision": "production", 17:13:31 INFO - "builddir": "mozilla-inbound_win7-ix_test-dromaeojs", 17:13:31 INFO - "repository": "", 17:13:31 INFO - "buildername": "Windows 7 32-bit mozilla-inbound pgo talos dromaeojs", 17:13:31 INFO - "buildid": "20151025133003", 17:13:31 INFO - "slavename": "t-w732-ix-052", 17:13:31 INFO - "pgo_build": "True", 17:13:31 INFO - "basedir": "C:\\slave\\test-pgo", 17:13:31 INFO - "project": "", 17:13:31 INFO - "platform": "win7-ix", 17:13:31 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 17:13:31 INFO - "slavebuilddir": "test", 17:13:31 INFO - "scheduler": "tests-mozilla-inbound-win32-pgo-talos", 17:13:31 INFO - "branch": "mozilla-inbound", 17:13:31 INFO - "repo_path": "integration/mozilla-inbound", 17:13:31 INFO - "stage_platform": "win32-pgo", 17:13:31 INFO - "builduid": "aa0a500302a04a3287f2a38fdea396f8", 17:13:31 INFO - "revision": "61d1f8675fc39e0fd1abb9828bbd30e324eb3234" 17:13:31 INFO - }, 17:13:31 INFO - "sourcestamp": { 17:13:31 INFO - "repository": "", 17:13:31 INFO - "hasPatch": false, 17:13:31 INFO - "project": "", 17:13:31 INFO - "branch": "mozilla-inbound-win32-pgo-talos", 17:13:31 INFO - "changes": [ 17:13:31 INFO - { 17:13:31 INFO - "category": null, 17:13:31 INFO - "files": [ 17:13:31 INFO - { 17:13:31 INFO - "url": null, 17:13:31 INFO - "name": "https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 17:13:31 INFO - } 17:13:31 INFO - ], 17:13:31 INFO - "repository": "", 17:13:31 INFO - "rev": "61d1f8675fc39e0fd1abb9828bbd30e324eb3234", 17:13:31 INFO - "who": "sendchange", 17:13:31 INFO - "when": 1445818234, 17:13:31 INFO - "number": 6571820, 17:13:31 INFO - "comments": "", 17:13:31 INFO - "project": "", 17:13:31 INFO - "at": "Sun 25 Oct 2015 17:10:34", 17:13:31 INFO - "branch": "mozilla-inbound-win32-pgo-talos", 17:13:31 INFO - "revlink": "", 17:13:31 INFO - "properties": [ 17:13:31 INFO - [ 17:13:31 INFO - "buildid", 17:13:31 INFO - "20151025133003", 17:13:31 INFO - "Change" 17:13:31 INFO - ], 17:13:31 INFO - [ 17:13:31 INFO - "builduid", 17:13:31 INFO - "aa0a500302a04a3287f2a38fdea396f8", 17:13:31 INFO - "Change" 17:13:31 INFO - ], 17:13:31 INFO - [ 17:13:31 INFO - "pgo_build", 17:13:31 INFO - "True", 17:13:31 INFO - "Change" 17:13:31 INFO - ] 17:13:31 INFO - ], 17:13:31 INFO - "revision": "61d1f8675fc39e0fd1abb9828bbd30e324eb3234" 17:13:31 INFO - } 17:13:31 INFO - ], 17:13:31 INFO - "revision": "61d1f8675fc39e0fd1abb9828bbd30e324eb3234" 17:13:31 INFO - } 17:13:31 INFO - } 17:13:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 17:13:31 INFO - Running post-action listener: _resource_record_post_action 17:13:31 INFO - ##### 17:13:31 INFO - ##### Running download-and-extract step. 17:13:31 INFO - ##### 17:13:31 INFO - Running pre-action listener: _resource_record_pre_action 17:13:31 INFO - Running main action method: download_and_extract 17:13:31 INFO - mkdir: C:\slave\test-pgo\build\tests 17:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:31 INFO - https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 17:13:31 INFO - trying https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/test_packages.json 17:13:31 INFO - Downloading https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 17:13:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 17:13:32 INFO - Downloaded 1221 bytes. 17:13:32 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 17:13:32 INFO - Using the following test package requirements: 17:13:32 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 17:13:32 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 17:13:32 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 17:13:32 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 17:13:32 INFO - u'jsshell-win32.zip'], 17:13:32 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 17:13:32 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 17:13:32 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 17:13:32 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 17:13:32 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 17:13:32 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 17:13:32 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 17:13:32 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 17:13:32 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 17:13:32 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 17:13:32 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 17:13:32 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 17:13:32 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 17:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:32 INFO - https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 17:13:32 INFO - trying https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 17:13:32 INFO - Downloading https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 17:13:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'}, attempt #1 17:13:34 INFO - Downloaded 18016548 bytes. 17:13:34 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] in C:\slave\test-pgo\build\tests 17:13:34 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 17:13:34 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 17:14:05 INFO - Return code: 0 17:14:05 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip', u'firefox-44.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 17:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:05 INFO - https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 17:14:05 INFO - trying https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 17:14:05 INFO - Downloading https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 17:14:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'}, attempt #1 17:14:06 INFO - Downloaded 18016548 bytes. 17:14:06 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] in C:\slave\test-pgo\build\tests 17:14:06 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 17:14:06 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 17:14:29 INFO - Return code: 0 17:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:29 INFO - https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 17:14:29 INFO - trying https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 17:14:29 INFO - Downloading https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip 17:14:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'}, attempt #1 17:14:30 INFO - Downloaded 11131307 bytes. 17:14:30 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] in C:\slave\test-pgo\build\tests 17:14:30 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip 17:14:30 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 17:14:35 INFO - Return code: 0 17:14:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:35 INFO - https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 17:14:35 INFO - trying https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 17:14:35 INFO - Downloading https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip 17:14:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.zip'}, attempt #1 17:14:39 INFO - Downloaded 53185635 bytes. 17:14:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 17:14:39 INFO - mkdir: C:\slave\test-pgo\properties 17:14:39 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 17:14:39 INFO - Writing to file C:\slave\test-pgo\properties\build_url 17:14:39 INFO - Contents: 17:14:39 INFO - build_url:https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 17:14:39 INFO - Running post-action listener: _resource_record_post_action 17:14:39 INFO - Running post-action listener: set_extra_try_arguments 17:14:39 INFO - ##### 17:14:39 INFO - ##### Running populate-webroot step. 17:14:39 INFO - ##### 17:14:39 INFO - Running pre-action listener: _resource_record_pre_action 17:14:39 INFO - Running main action method: populate_webroot 17:14:39 INFO - Guessing talos json url... 17:14:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:39 INFO - https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 17:14:39 INFO - trying https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 17:14:39 INFO - Downloading https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 17:14:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt', 'file_name': 'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.txt'}, attempt #1 17:14:39 INFO - Downloaded 111 bytes. 17:14:39 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 17:14:39 INFO - Contents: 17:14:39 INFO - 20151025133003 17:14:39 INFO - https://hg.mozilla.org/integration/mozilla-inbound/rev/61d1f8675fc39e0fd1abb9828bbd30e324eb3234 17:14:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:39 INFO - trying https://hg.mozilla.org/integration/mozilla-inbound/raw-file/61d1f8675f/testing/talos/talos.json 17:14:39 INFO - Downloading https://hg.mozilla.org/integration/mozilla-inbound/raw-file/61d1f8675f/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json 17:14:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/mozilla-inbound/raw-file/61d1f8675f/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1 17:14:40 INFO - Downloaded 4264 bytes. 17:14:40 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 17:14:40 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 17:14:40 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 17:14:40 INFO - u'../fennec_ids.txt'], 17:14:40 INFO - u'tests': [u'tcheck2']}, 17:14:40 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 17:14:40 INFO - u'--tppagecycles', 17:14:40 INFO - u'7'], 17:14:40 INFO - u'tests': [u'tsvgm']}}, 17:14:40 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 17:14:40 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 17:14:40 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 17:14:40 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 17:14:40 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 17:14:40 INFO - u'tests': [u'dromaeo_css', 17:14:40 INFO - u'kraken', 17:14:40 INFO - u'v8_7']}, 17:14:40 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:40 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 17:14:40 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:40 INFO - u'talos_options': [u'--e10s'], 17:14:40 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 17:14:40 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:40 INFO - u'tests': [u'damp', u'tps']}, 17:14:40 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:40 INFO - u'talos_options': [u'--e10s'], 17:14:40 INFO - u'tests': [u'damp', u'tps']}, 17:14:40 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 17:14:40 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 17:14:40 INFO - u'other': {u'tests': [u'a11yr', 17:14:40 INFO - u'ts_paint', 17:14:40 INFO - u'tpaint', 17:14:40 INFO - u'sessionrestore', 17:14:40 INFO - u'sessionrestore_no_auto_restore']}, 17:14:40 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 17:14:40 INFO - u'tests': [u'a11yr', 17:14:40 INFO - u'ts_paint', 17:14:40 INFO - u'tpaint', 17:14:40 INFO - u'sessionrestore', 17:14:40 INFO - u'sessionrestore_no_auto_restore']}, 17:14:40 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 17:14:40 INFO - u'tests': [u'a11yr', 17:14:40 INFO - u'ts_paint', 17:14:40 INFO - u'tpaint', 17:14:40 INFO - u'sessionrestore', 17:14:40 INFO - u'sessionrestore_no_auto_restore']}, 17:14:40 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 17:14:40 INFO - u'tests': [u'a11yr', 17:14:40 INFO - u'ts_paint', 17:14:40 INFO - u'tpaint', 17:14:40 INFO - u'sessionrestore', 17:14:40 INFO - u'sessionrestore_no_auto_restore']}, 17:14:40 INFO - u'other_l64': {u'tests': [u'a11yr', 17:14:40 INFO - u'ts_paint', 17:14:40 INFO - u'tpaint', 17:14:40 INFO - u'sessionrestore', 17:14:40 INFO - u'sessionrestore_no_auto_restore']}, 17:14:40 INFO - u'other_nol64': {u'tests': [u'a11yr', 17:14:40 INFO - u'ts_paint', 17:14:40 INFO - u'tpaint', 17:14:40 INFO - u'sessionrestore', 17:14:40 INFO - u'sessionrestore_no_auto_restore']}, 17:14:40 INFO - u'svgr': {u'tests': [u'tsvgx', 17:14:40 INFO - u'tsvgr_opacity', 17:14:40 INFO - u'tart', 17:14:40 INFO - u'tscrollx', 17:14:40 INFO - u'cart']}, 17:14:40 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 17:14:40 INFO - u'tests': [u'tsvgx', 17:14:40 INFO - u'tsvgr_opacity', 17:14:40 INFO - u'tart', 17:14:40 INFO - u'tscrollx', 17:14:40 INFO - u'cart']}, 17:14:40 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:40 INFO - u'tests': [u'tp5o']}, 17:14:40 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:40 INFO - u'talos_options': [u'--e10s'], 17:14:40 INFO - u'tests': [u'tp5o']}, 17:14:40 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:40 INFO - u'talos_options': [u'--xperf_path', 17:14:40 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:14:40 INFO - u'tests': [u'tp5n']}, 17:14:40 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:40 INFO - u'talos_options': [u'--e10s', 17:14:40 INFO - u'--xperf_path', 17:14:40 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 17:14:40 INFO - u'C:/slave/talos-data/talos/xperf.config'], 17:14:40 INFO - u'tests': [u'tp5n']}}, 17:14:40 INFO - u'talos.zip': {u'path': u'', 17:14:40 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:14:40 INFO - Running post-action listener: _resource_record_post_action 17:14:40 INFO - ##### 17:14:40 INFO - ##### Running create-virtualenv step. 17:14:40 INFO - ##### 17:14:40 INFO - Running pre-action listener: _resource_record_pre_action 17:14:40 INFO - Running main action method: create_virtualenv 17:14:40 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 17:14:40 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 17:14:40 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 17:14:40 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'! 17:14:40 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test-pgo\\build/venv'] in C:\slave\test-pgo\build 17:14:40 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test-pgo\build/venv 17:14:45 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 17:14:48 INFO - Installing distribute......................................................................................................................................................................................done. 17:14:49 INFO - Return code: 0 17:14:49 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 17:14:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:50 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01918B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0138E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012A8B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0199D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-052', 'USERDOMAIN': 'T-W732-IX-052', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-052', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 17:14:50 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test-pgo\build 17:14:50 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:14:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:14:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:14:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:14:50 INFO - 'COMPUTERNAME': 'T-W732-IX-052', 17:14:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:14:50 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:14:50 INFO - 'DCLOCATION': 'SCL3', 17:14:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:14:50 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:14:50 INFO - 'HOMEDRIVE': 'C:', 17:14:50 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:14:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:14:50 INFO - 'KTS_VERSION': '1.19c', 17:14:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:14:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-052', 17:14:50 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:14:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:14:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:50 INFO - 'MOZ_NO_REMOTE': '1', 17:14:50 INFO - 'NO_EM_RESTART': '1', 17:14:50 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:14:50 INFO - 'OS': 'Windows_NT', 17:14:50 INFO - 'OURDRIVE': 'C:', 17:14:50 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 17:14:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:14:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:14:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:14:50 INFO - 'PROCESSOR_LEVEL': '6', 17:14:50 INFO - 'PROCESSOR_REVISION': '1e05', 17:14:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:14:50 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:14:50 INFO - 'PROMPT': '$P$G', 17:14:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 17:14:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:14:50 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:14:50 INFO - 'PWD': 'C:\\slave\\test-pgo', 17:14:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:14:50 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:14:50 INFO - 'SYSTEMDRIVE': 'C:', 17:14:50 INFO - 'SYSTEMROOT': 'C:\\windows', 17:14:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:14:50 INFO - 'TEST1': 'testie', 17:14:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:14:50 INFO - 'USERDOMAIN': 'T-W732-IX-052', 17:14:50 INFO - 'USERNAME': 'cltbld', 17:14:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:14:50 INFO - 'WINDIR': 'C:\\windows', 17:14:50 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:14:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:14:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:14:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:14:50 INFO - Downloading/unpacking pip>=1.5 17:14:50 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache 17:14:55 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpip-1.5.5.tar.gz 17:14:55 INFO - Running setup.py egg_info for package pip 17:14:55 INFO - warning: no files found matching 'pip\cacert.pem' 17:14:55 INFO - warning: no files found matching '*.html' under directory 'docs' 17:14:55 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 17:14:55 INFO - no previously-included directories found matching 'docs\_build\_sources' 17:14:55 INFO - Installing collected packages: pip 17:14:55 INFO - Found existing installation: pip 0.8.2 17:14:55 INFO - Uninstalling pip: 17:14:55 INFO - Successfully uninstalled pip 17:14:55 INFO - Running setup.py install for pip 17:14:55 INFO - warning: no files found matching 'pip\cacert.pem' 17:14:55 INFO - warning: no files found matching '*.html' under directory 'docs' 17:14:55 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 17:14:55 INFO - no previously-included directories found matching 'docs\_build\_sources' 17:14:55 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:14:55 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 17:14:55 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:14:55 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 17:14:55 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:14:55 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 17:14:55 INFO - Successfully installed pip 17:14:55 INFO - Cleaning up... 17:14:55 INFO - Return code: 0 17:14:55 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 17:14:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:55 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01918B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0138E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012A8B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0199D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-052', 'USERDOMAIN': 'T-W732-IX-052', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-052', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 17:14:55 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in C:\slave\test-pgo\build 17:14:55 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 17:14:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:14:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:14:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:14:55 INFO - 'COMPUTERNAME': 'T-W732-IX-052', 17:14:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:14:55 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:14:55 INFO - 'DCLOCATION': 'SCL3', 17:14:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:14:55 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:14:55 INFO - 'HOMEDRIVE': 'C:', 17:14:55 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:14:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:14:55 INFO - 'KTS_VERSION': '1.19c', 17:14:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:14:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-052', 17:14:55 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:14:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:14:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:55 INFO - 'MOZ_NO_REMOTE': '1', 17:14:55 INFO - 'NO_EM_RESTART': '1', 17:14:55 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:14:55 INFO - 'OS': 'Windows_NT', 17:14:55 INFO - 'OURDRIVE': 'C:', 17:14:55 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 17:14:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:14:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:14:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:14:55 INFO - 'PROCESSOR_LEVEL': '6', 17:14:55 INFO - 'PROCESSOR_REVISION': '1e05', 17:14:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:14:55 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:14:55 INFO - 'PROMPT': '$P$G', 17:14:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 17:14:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:14:55 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:14:55 INFO - 'PWD': 'C:\\slave\\test-pgo', 17:14:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:14:55 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:14:55 INFO - 'SYSTEMDRIVE': 'C:', 17:14:55 INFO - 'SYSTEMROOT': 'C:\\windows', 17:14:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:14:55 INFO - 'TEST1': 'testie', 17:14:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:14:55 INFO - 'USERDOMAIN': 'T-W732-IX-052', 17:14:55 INFO - 'USERNAME': 'cltbld', 17:14:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:14:55 INFO - 'WINDIR': 'C:\\windows', 17:14:55 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:14:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:14:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:14:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:56 INFO - Downloading/unpacking psutil>=0.7.1 17:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:57 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpsutil-3.1.1-cp27-none-win32.whl 17:14:57 INFO - Installing collected packages: psutil 17:14:57 INFO - Successfully installed psutil 17:14:57 INFO - Cleaning up... 17:14:58 INFO - Return code: 0 17:14:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 17:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:58 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01918B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0138E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012A8B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0199D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-052', 'USERDOMAIN': 'T-W732-IX-052', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-052', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 17:14:58 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in C:\slave\test-pgo\build 17:14:58 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 17:14:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:14:58 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:14:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:14:58 INFO - 'COMPUTERNAME': 'T-W732-IX-052', 17:14:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:14:58 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:14:58 INFO - 'DCLOCATION': 'SCL3', 17:14:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:14:58 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:14:58 INFO - 'HOMEDRIVE': 'C:', 17:14:58 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:14:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:14:58 INFO - 'KTS_VERSION': '1.19c', 17:14:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:14:58 INFO - 'LOGONSERVER': '\\\\T-W732-IX-052', 17:14:58 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:14:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:58 INFO - 'MOZ_NO_REMOTE': '1', 17:14:58 INFO - 'NO_EM_RESTART': '1', 17:14:58 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:14:58 INFO - 'OS': 'Windows_NT', 17:14:58 INFO - 'OURDRIVE': 'C:', 17:14:58 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 17:14:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:14:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:14:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:14:58 INFO - 'PROCESSOR_LEVEL': '6', 17:14:58 INFO - 'PROCESSOR_REVISION': '1e05', 17:14:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:14:58 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:14:58 INFO - 'PROMPT': '$P$G', 17:14:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 17:14:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:14:58 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:14:58 INFO - 'PWD': 'C:\\slave\\test-pgo', 17:14:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:14:58 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:14:58 INFO - 'SYSTEMDRIVE': 'C:', 17:14:58 INFO - 'SYSTEMROOT': 'C:\\windows', 17:14:58 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:14:58 INFO - 'TEST1': 'testie', 17:14:58 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:14:58 INFO - 'USERDOMAIN': 'T-W732-IX-052', 17:14:58 INFO - 'USERNAME': 'cltbld', 17:14:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:14:58 INFO - 'WINDIR': 'C:\\windows', 17:14:58 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:14:58 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:14:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:14:59 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozsystemmonitor-0.0.tar.gz 17:14:59 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 17:14:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0) 17:14:59 INFO - Installing collected packages: mozsystemmonitor 17:14:59 INFO - Running setup.py install for mozsystemmonitor 17:14:59 INFO - Successfully installed mozsystemmonitor 17:14:59 INFO - Cleaning up... 17:14:59 INFO - Return code: 0 17:14:59 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 17:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:59 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01918B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0138E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012A8B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0199D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-052', 'USERDOMAIN': 'T-W732-IX-052', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-052', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 17:14:59 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test-pgo\build 17:14:59 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:14:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:14:59 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:14:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:14:59 INFO - 'COMPUTERNAME': 'T-W732-IX-052', 17:14:59 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:14:59 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:14:59 INFO - 'DCLOCATION': 'SCL3', 17:14:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:14:59 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:14:59 INFO - 'HOMEDRIVE': 'C:', 17:14:59 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:14:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:14:59 INFO - 'KTS_VERSION': '1.19c', 17:14:59 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:14:59 INFO - 'LOGONSERVER': '\\\\T-W732-IX-052', 17:14:59 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:14:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:14:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:59 INFO - 'MOZ_NO_REMOTE': '1', 17:14:59 INFO - 'NO_EM_RESTART': '1', 17:14:59 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:14:59 INFO - 'OS': 'Windows_NT', 17:14:59 INFO - 'OURDRIVE': 'C:', 17:14:59 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 17:14:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:14:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:14:59 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:14:59 INFO - 'PROCESSOR_LEVEL': '6', 17:14:59 INFO - 'PROCESSOR_REVISION': '1e05', 17:14:59 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:14:59 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:14:59 INFO - 'PROMPT': '$P$G', 17:14:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 17:14:59 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:14:59 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:14:59 INFO - 'PWD': 'C:\\slave\\test-pgo', 17:14:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:14:59 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:14:59 INFO - 'SYSTEMDRIVE': 'C:', 17:14:59 INFO - 'SYSTEMROOT': 'C:\\windows', 17:14:59 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:14:59 INFO - 'TEST1': 'testie', 17:14:59 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:14:59 INFO - 'USERDOMAIN': 'T-W732-IX-052', 17:14:59 INFO - 'USERNAME': 'cltbld', 17:14:59 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:14:59 INFO - 'WINDIR': 'C:\\windows', 17:14:59 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:14:59 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:14:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:15:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:15:00 INFO - Downloading/unpacking blobuploader==1.2.4 17:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:15:01 INFO - Downloading blobuploader-1.2.4.tar.gz 17:15:01 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblobuploader-1.2.4.tar.gz 17:15:01 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 17:15:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:15:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:15:01 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2frequests-1.2.3.tar.gz 17:15:01 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 17:15:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:15:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:15:01 INFO - Downloading docopt-0.6.1.tar.gz 17:15:01 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fdocopt-0.6.1.tar.gz 17:15:01 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 17:15:01 INFO - Installing collected packages: blobuploader, requests, docopt 17:15:01 INFO - Running setup.py install for blobuploader 17:15:02 INFO - Running setup.py install for requests 17:15:02 INFO - Running setup.py install for docopt 17:15:02 INFO - Successfully installed blobuploader requests docopt 17:15:02 INFO - Cleaning up... 17:15:04 INFO - Return code: 0 17:15:04 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 17:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:15:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:15:04 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01918B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0138E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012A8B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0199D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-052', 'USERDOMAIN': 'T-W732-IX-052', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-052', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 17:15:04 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config 17:15:04 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --no-deps --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:15:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:15:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:15:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:15:04 INFO - 'COMPUTERNAME': 'T-W732-IX-052', 17:15:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:15:04 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:15:04 INFO - 'DCLOCATION': 'SCL3', 17:15:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:15:04 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:15:04 INFO - 'HOMEDRIVE': 'C:', 17:15:04 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:15:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:15:04 INFO - 'KTS_VERSION': '1.19c', 17:15:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:15:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-052', 17:15:04 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:15:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:15:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:04 INFO - 'MOZ_NO_REMOTE': '1', 17:15:04 INFO - 'NO_EM_RESTART': '1', 17:15:04 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:15:04 INFO - 'OS': 'Windows_NT', 17:15:04 INFO - 'OURDRIVE': 'C:', 17:15:04 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 17:15:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:15:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:15:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:15:04 INFO - 'PROCESSOR_LEVEL': '6', 17:15:04 INFO - 'PROCESSOR_REVISION': '1e05', 17:15:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:15:04 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:15:04 INFO - 'PROMPT': '$P$G', 17:15:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 17:15:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:15:04 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:15:04 INFO - 'PWD': 'C:\\slave\\test-pgo', 17:15:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:15:04 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:15:04 INFO - 'SYSTEMDRIVE': 'C:', 17:15:04 INFO - 'SYSTEMROOT': 'C:\\windows', 17:15:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:15:04 INFO - 'TEST1': 'testie', 17:15:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:15:04 INFO - 'USERDOMAIN': 'T-W732-IX-052', 17:15:04 INFO - 'USERNAME': 'cltbld', 17:15:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:15:04 INFO - 'WINDIR': 'C:\\windows', 17:15:04 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:15:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:15:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:15:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:15:04 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 17:15:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3sksy9-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 17:15:05 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 17:15:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tjzq8n-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 17:15:05 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 17:15:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9pr7e9-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 17:15:05 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 17:15:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iml2tt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 17:15:05 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 17:15:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x7phve-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 17:15:06 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 17:15:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-secacl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 17:15:06 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 17:15:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_zbhx8-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 17:15:06 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 17:15:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f3btdy-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 17:15:06 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 17:15:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qeqbww-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 17:15:06 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 17:15:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w2xkuc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 17:15:06 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 17:15:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dg8lec-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 17:15:07 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 17:15:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jdca8m-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 17:15:07 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 17:15:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rby8xm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 17:15:07 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 17:15:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yywkyv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 17:15:07 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 17:15:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-occ7ss-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:15:07 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 17:15:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pbnyet-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 17:15:07 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 17:15:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h0nm08-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 17:15:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:15:07 INFO - Running setup.py install for manifestparser 17:15:08 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:15:08 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 17:15:08 INFO - Running setup.py install for mozcrash 17:15:08 INFO - Running setup.py install for mozdebug 17:15:09 INFO - Running setup.py install for mozdevice 17:15:09 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:15:09 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 17:15:09 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:15:09 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 17:15:10 INFO - Running setup.py install for mozfile 17:15:10 INFO - Running setup.py install for mozhttpd 17:15:10 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:15:10 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 17:15:11 INFO - Running setup.py install for mozinfo 17:15:11 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:15:11 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 17:15:11 INFO - Running setup.py install for mozInstall 17:15:11 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:15:11 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 17:15:11 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:15:11 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 17:15:11 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:15:11 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 17:15:11 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:15:11 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 17:15:11 INFO - Running setup.py install for mozleak 17:15:12 INFO - Running setup.py install for mozlog 17:15:12 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:15:12 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 17:15:13 INFO - Running setup.py install for moznetwork 17:15:13 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:15:13 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 17:15:13 INFO - Running setup.py install for mozprocess 17:15:13 INFO - Running setup.py install for mozprofile 17:15:14 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:15:14 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 17:15:14 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:15:14 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 17:15:14 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:15:14 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 17:15:14 INFO - Running setup.py install for mozrunner 17:15:14 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:15:14 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 17:15:15 INFO - Running setup.py install for mozscreenshot 17:15:15 INFO - Running setup.py install for moztest 17:15:15 INFO - Running setup.py install for mozversion 17:15:16 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 17:15:16 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 17:15:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:15:16 INFO - Cleaning up... 17:15:16 INFO - Return code: 0 17:15:16 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 17:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:15:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:15:16 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01918B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0138E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012A8B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0199D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-052', 'USERDOMAIN': 'T-W732-IX-052', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-052', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 17:15:16 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config 17:15:16 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:15:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:15:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:15:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:15:16 INFO - 'COMPUTERNAME': 'T-W732-IX-052', 17:15:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:15:16 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:15:16 INFO - 'DCLOCATION': 'SCL3', 17:15:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:15:16 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:15:16 INFO - 'HOMEDRIVE': 'C:', 17:15:16 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:15:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:15:16 INFO - 'KTS_VERSION': '1.19c', 17:15:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:15:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-052', 17:15:16 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:15:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:15:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:16 INFO - 'MOZ_NO_REMOTE': '1', 17:15:16 INFO - 'NO_EM_RESTART': '1', 17:15:16 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:15:16 INFO - 'OS': 'Windows_NT', 17:15:16 INFO - 'OURDRIVE': 'C:', 17:15:16 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 17:15:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:15:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:15:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:15:16 INFO - 'PROCESSOR_LEVEL': '6', 17:15:16 INFO - 'PROCESSOR_REVISION': '1e05', 17:15:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:15:16 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:15:16 INFO - 'PROMPT': '$P$G', 17:15:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 17:15:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:15:16 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:15:16 INFO - 'PWD': 'C:\\slave\\test-pgo', 17:15:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:15:16 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:15:16 INFO - 'SYSTEMDRIVE': 'C:', 17:15:16 INFO - 'SYSTEMROOT': 'C:\\windows', 17:15:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:15:16 INFO - 'TEST1': 'testie', 17:15:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:15:16 INFO - 'USERDOMAIN': 'T-W732-IX-052', 17:15:16 INFO - 'USERNAME': 'cltbld', 17:15:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:15:16 INFO - 'WINDIR': 'C:\\windows', 17:15:16 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:15:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:15:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:15:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:15:16 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 17:15:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a5u6on-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 17:15:16 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 1)) 17:15:16 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 17:15:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n5bocd-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 17:15:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 17:15:16 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 17:15:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-r6agwk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 17:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 17:15:17 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 17:15:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-can6t3-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 17:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 17:15:17 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 17:15:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bkigec-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 17:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 5)) 17:15:17 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 17:15:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mlypvb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 17:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 6)) 17:15:17 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 17:15:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1pmqso-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 17:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 7)) 17:15:17 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 17:15:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f3bkbb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 17:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 8)) 17:15:17 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 17:15:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-abraam-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 17:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 9)) 17:15:17 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 17:15:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k5wwvr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 17:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 17:15:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 17:15:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zryqh1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 17:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 11)) 17:15:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 17:15:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-px3xdo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 17:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 12)) 17:15:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 17:15:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o_ypti-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 17:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 13)) 17:15:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 17:15:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ynw3uw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 17:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14)) 17:15:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 17:15:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-c_bf8a-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 15)) 17:15:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 17:15:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pn1rwf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 17:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/moztest in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 16)) 17:15:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 17:15:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3qrnpq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 17:15:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 17)) 17:15:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 17:15:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 17:15:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 17:15:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 17:15:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 17:15:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 17:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:15:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:15:20 INFO - Downloading blessings-1.5.1.tar.gz 17:15:20 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblessings-1.5.1.tar.gz 17:15:20 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 17:15:20 INFO - Installing collected packages: blessings 17:15:20 INFO - Running setup.py install for blessings 17:15:20 INFO - Successfully installed blessings 17:15:20 INFO - Cleaning up... 17:15:23 INFO - Return code: 0 17:15:23 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 17:15:23 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 17:15:23 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 17:15:23 INFO - Reading from file tmpfile_stdout 17:15:23 INFO - Using _rmtree_windows ... 17:15:23 INFO - Using _rmtree_windows ... 17:15:23 INFO - Current package versions: 17:15:23 INFO - blessings == 1.5.1 17:15:23 INFO - blobuploader == 1.2.4 17:15:23 INFO - docopt == 0.6.1 17:15:23 INFO - manifestparser == 1.1 17:15:23 INFO - mozInstall == 1.12 17:15:23 INFO - mozcrash == 0.16 17:15:23 INFO - mozdebug == 0.1 17:15:23 INFO - mozdevice == 0.46 17:15:23 INFO - mozfile == 1.2 17:15:23 INFO - mozhttpd == 0.7 17:15:23 INFO - mozinfo == 0.8 17:15:23 INFO - mozleak == 0.1 17:15:23 INFO - mozlog == 3.0 17:15:23 INFO - moznetwork == 0.27 17:15:23 INFO - mozprocess == 0.22 17:15:23 INFO - mozprofile == 0.27 17:15:23 INFO - mozrunner == 6.11 17:15:23 INFO - mozscreenshot == 0.1 17:15:23 INFO - mozsystemmonitor == 0.0 17:15:23 INFO - moztest == 0.7 17:15:23 INFO - mozversion == 1.4 17:15:23 INFO - psutil == 3.1.1 17:15:23 INFO - requests == 1.2.3 17:15:23 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 17:15:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:15:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:15:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:15:23 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01918B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0138E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012A8B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0199D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-052', 'USERDOMAIN': 'T-W732-IX-052', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-052', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 17:15:23 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\talos 17:15:23 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:15:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:15:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:15:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:15:23 INFO - 'COMPUTERNAME': 'T-W732-IX-052', 17:15:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:15:23 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:15:23 INFO - 'DCLOCATION': 'SCL3', 17:15:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:15:23 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:15:23 INFO - 'HOMEDRIVE': 'C:', 17:15:23 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:15:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:15:23 INFO - 'KTS_VERSION': '1.19c', 17:15:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:15:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-052', 17:15:23 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:15:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:15:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:23 INFO - 'MOZ_NO_REMOTE': '1', 17:15:23 INFO - 'NO_EM_RESTART': '1', 17:15:23 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:15:23 INFO - 'OS': 'Windows_NT', 17:15:23 INFO - 'OURDRIVE': 'C:', 17:15:23 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 17:15:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:15:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:15:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:15:23 INFO - 'PROCESSOR_LEVEL': '6', 17:15:23 INFO - 'PROCESSOR_REVISION': '1e05', 17:15:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:15:23 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:15:23 INFO - 'PROMPT': '$P$G', 17:15:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 17:15:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:15:23 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:15:23 INFO - 'PWD': 'C:\\slave\\test-pgo', 17:15:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:15:23 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:15:23 INFO - 'SYSTEMDRIVE': 'C:', 17:15:23 INFO - 'SYSTEMROOT': 'C:\\windows', 17:15:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:15:23 INFO - 'TEST1': 'testie', 17:15:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:15:23 INFO - 'USERDOMAIN': 'T-W732-IX-052', 17:15:23 INFO - 'USERNAME': 'cltbld', 17:15:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:15:23 INFO - 'WINDIR': 'C:\\windows', 17:15:23 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:15:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:15:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:15:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 17:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 2)) 17:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3)) 17:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 4)) 17:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 5)) 17:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 6)) 17:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 7)) 17:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 8)) 17:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash>=0.15->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 17:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3)) 17:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozlog>=3.0->mozcrash>=0.15->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 17:15:24 INFO - Cleaning up... 17:15:24 INFO - Return code: 0 17:15:24 INFO - Running post-action listener: _resource_record_post_action 17:15:24 INFO - Running post-action listener: _start_resource_monitoring 17:15:24 INFO - Starting resource monitoring. 17:15:24 INFO - ##### 17:15:24 INFO - ##### Running install step. 17:15:24 INFO - ##### 17:15:24 INFO - Running pre-action listener: _resource_record_pre_action 17:15:24 INFO - Running main action method: install 17:15:24 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 17:15:24 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 17:15:24 INFO - Reading from file tmpfile_stdout 17:15:24 INFO - Using _rmtree_windows ... 17:15:24 INFO - Using _rmtree_windows ... 17:15:24 INFO - Detecting whether we're running mozinstall >=1.0... 17:15:24 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 17:15:24 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 17:15:24 INFO - Reading from file tmpfile_stdout 17:15:24 INFO - Output received: 17:15:24 INFO - Usage: mozinstall-script.py [options] installer 17:15:24 INFO - Options: 17:15:24 INFO - -h, --help show this help message and exit 17:15:24 INFO - -d DEST, --destination=DEST 17:15:24 INFO - Directory to install application into. [default: 17:15:24 INFO - "C:\slave\test-pgo"] 17:15:24 INFO - --app=APP Application being installed. [default: firefox] 17:15:24 INFO - Using _rmtree_windows ... 17:15:24 INFO - Using _rmtree_windows ... 17:15:24 INFO - mkdir: C:\slave\test-pgo\build\application 17:15:24 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test-pgo\\build\\application'] 17:15:24 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip --destination C:\slave\test-pgo\build\application 17:15:26 INFO - Reading from file tmpfile_stdout 17:15:26 INFO - Output received: 17:15:26 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 17:15:26 INFO - Using _rmtree_windows ... 17:15:26 INFO - Using _rmtree_windows ... 17:15:26 INFO - Running post-action listener: _resource_record_post_action 17:15:26 INFO - ##### 17:15:26 INFO - ##### Running run-tests step. 17:15:26 INFO - ##### 17:15:26 INFO - Running pre-action listener: _resource_record_pre_action 17:15:26 INFO - Running main action method: run_tests 17:15:26 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 17:15:26 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 17:15:26 INFO - Python 2.7.3 17:15:26 INFO - Return code: 0 17:15:26 INFO - grabbing minidump binary from tooltool 17:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:15:26 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012A8B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0199D660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 17:15:26 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test-pgo\build 17:15:26 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test-pgo\build\tests\config/tooltool-manifests/win32/releng.manifest -o 17:15:26 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 17:15:27 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmp5wevlz 17:15:27 INFO - INFO - File integrity verified, renaming tmp5wevlz to win32-minidump_stackwalk.exe 17:15:27 INFO - Return code: 0 17:15:27 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 17:15:27 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 17:15:27 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 17:15:27 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 17:15:27 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 17:15:27 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-052', '--webServer', 'localhost'] in C:\slave\test-pgo\build 17:15:27 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName Mozilla-Inbound --suite dromaeojs --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-052 --webServer localhost 17:15:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:15:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:15:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:15:27 INFO - 'COMPUTERNAME': 'T-W732-IX-052', 17:15:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:15:27 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:15:27 INFO - 'DCLOCATION': 'SCL3', 17:15:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:15:27 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:15:27 INFO - 'HOMEDRIVE': 'C:', 17:15:27 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:15:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:15:27 INFO - 'KTS_VERSION': '1.19c', 17:15:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:15:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-052', 17:15:27 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 17:15:27 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 17:15:27 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:15:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:15:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:27 INFO - 'MOZ_NO_REMOTE': '1', 17:15:27 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 17:15:27 INFO - 'NO_EM_RESTART': '1', 17:15:27 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:15:27 INFO - 'OS': 'Windows_NT', 17:15:27 INFO - 'OURDRIVE': 'C:', 17:15:27 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 17:15:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:15:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:15:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:15:27 INFO - 'PROCESSOR_LEVEL': '6', 17:15:27 INFO - 'PROCESSOR_REVISION': '1e05', 17:15:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:15:27 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:15:27 INFO - 'PROMPT': '$P$G', 17:15:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 17:15:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:15:27 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:15:27 INFO - 'PWD': 'C:\\slave\\test-pgo', 17:15:27 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 17:15:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:15:27 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:15:27 INFO - 'SYSTEMDRIVE': 'C:', 17:15:27 INFO - 'SYSTEMROOT': 'C:\\windows', 17:15:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:15:27 INFO - 'TEST1': 'testie', 17:15:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:15:27 INFO - 'USERDOMAIN': 'T-W732-IX-052', 17:15:27 INFO - 'USERNAME': 'cltbld', 17:15:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:15:27 INFO - 'WINDIR': 'C:\\windows', 17:15:27 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:15:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:15:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:15:27 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-052', '--webServer', 'localhost'] with output_timeout 3600 17:15:28 INFO - mozversion INFO | application_buildid: 20151025133003 17:15:28 INFO - mozversion INFO | application_changeset: 61d1f8675fc39e0fd1abb9828bbd30e324eb3234 17:15:28 INFO - mozversion INFO | application_display_name: Nightly 17:15:28 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:15:28 INFO - mozversion INFO | application_name: Firefox 17:15:28 INFO - mozversion INFO | application_remotingname: firefox 17:15:28 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound 17:15:28 INFO - mozversion INFO | application_vendor: Mozilla 17:15:28 INFO - mozversion INFO | application_version: 44.0a1 17:15:28 INFO - mozversion INFO | platform_buildid: 20151025133003 17:15:28 INFO - mozversion INFO | platform_changeset: 61d1f8675fc39e0fd1abb9828bbd30e324eb3234 17:15:28 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 17:15:28 INFO - mozversion INFO | platform_version: 44.0a1 17:15:28 INFO - 2015-10-25 17:15:28,397 DEBUG : using testdate: 1445818528 17:15:28 INFO - 2015-10-25 17:15:28,398 DEBUG : actual date: 1445818528 17:15:28 INFO - 2015-10-25 17:15:28,483 INFO : starting webserver on 'localhost:49896' 17:15:28 INFO - 2015-10-25 17:15:28,484 INFO : Starting test suite T-W732-IX-052 17:15:28 INFO - 2015-10-25 17:15:28,486 INFO : Starting test dromaeo_css 17:15:28 INFO - 2015-10-25 17:15:28,486 DEBUG : operating with platform_type : w7_ 17:15:28 INFO - 2015-10-25 17:15:28,486 INFO : Initialising browser for dromaeo_css test... 17:15:28 INFO - 2015-10-25 17:15:28,582 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp1wrxia\profile http://localhost:49896/getInfo.html 17:15:36 INFO - 2015-10-25 17:15:36,335 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:15:36 INFO - 2015-10-25 17:15:36,335 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:15:36 INFO - 2015-10-25 17:15:36,335 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 17:15:36 INFO - 2015-10-25 17:15:36,335 DEBUG : BROWSER_OUTPUT: __metrics 17:15:36 INFO - 2015-10-25 17:15:36,410 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:15:37 INFO - 2015-10-25 17:15:37,413 DEBUG : BROWSER_OUTPUT: console.error: 17:15:37 INFO - 2015-10-25 17:15:37,414 DEBUG : BROWSER_OUTPUT: Message: [Exception... "Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [mozIStorageAsyncStatement.executeAsync]" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "JS frame :: resource://gre/modules/Sqlite.jsm :: ConnectionData.prototype<._executeStatement :: line 758" data: no] 17:15:37 INFO - 2015-10-25 17:15:37,414 DEBUG : BROWSER_OUTPUT: Stack: 17:15:37 INFO - 2015-10-25 17:15:37,414 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 17:15:37 INFO - 2015-10-25 17:15:37,414 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 17:15:37 INFO - 2015-10-25 17:15:37,414 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 17:15:37 INFO - 2015-10-25 17:15:37,414 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 17:15:37 INFO - 2015-10-25 17:15:37,414 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 17:15:37 INFO - 2015-10-25 17:15:37,414 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 17:15:37 INFO - 2015-10-25 17:15:37,414 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 17:15:37 INFO - 2015-10-25 17:15:37,414 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 17:15:37 INFO - 2015-10-25 17:15:37,414 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 17:15:37 INFO - 2015-10-25 17:15:37,414 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 17:15:37 INFO - 2015-10-25 17:15:37,414 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 17:15:37 INFO - 2015-10-25 17:15:37,414 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 17:15:37 INFO - 2015-10-25 17:15:37,414 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23 17:34:11 INFO - 2015-10-25 17:34:11,565 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 17:34:11 INFO - 2015-10-25 17:34:11,565 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 17:34:11 INFO - 2015-10-25 17:34:11,565 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 17:34:11 INFO - 2015-10-25 17:34:11,565 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 17:34:11 INFO - 2015-10-25 17:34:11,565 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 17:34:11 INFO - 2015-10-25 17:34:11,565 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 17:34:11 INFO - 2015-10-25 17:34:11,565 DEBUG : BROWSER_OUTPUT: 17:34:12 INFO - 2015-10-25 17:34:12,174 INFO : Browser initialized. 17:34:12 INFO - 2015-10-25 17:34:12,174 INFO : Running cycle 1/1 for kraken test... 17:34:12 INFO - 2015-10-25 17:34:12,174 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmprzrwe_\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:34:23 INFO - 2015-10-25 17:34:23,578 DEBUG : BROWSER_OUTPUT: RSS: Main: 147083264 17:34:23 INFO - 2015-10-25 17:34:23,578 DEBUG : BROWSER_OUTPUT: 17:34:25 INFO - 2015-10-25 17:34:25,913 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49896/tests/kraken/driver.html?testName=audio-beat-detection) 17:34:26 INFO - 2015-10-25 17:34:26,487 DEBUG : BROWSER_OUTPUT: RSS: Main: 181583872 17:34:26 INFO - 2015-10-25 17:34:26,487 DEBUG : BROWSER_OUTPUT: 17:34:29 INFO - 2015-10-25 17:34:29,088 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49896/tests/kraken/driver.html?testName=audio-dft) 17:34:29 INFO - 2015-10-25 17:34:29,654 DEBUG : BROWSER_OUTPUT: RSS: Main: 185393152 17:34:29 INFO - 2015-10-25 17:34:29,654 DEBUG : BROWSER_OUTPUT: 17:34:32 INFO - 2015-10-25 17:34:32,415 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49896/tests/kraken/driver.html?testName=audio-fft) 17:34:32 INFO - 2015-10-25 17:34:32,990 DEBUG : BROWSER_OUTPUT: RSS: Main: 176435200 17:34:32 INFO - 2015-10-25 17:34:32,990 DEBUG : BROWSER_OUTPUT: 17:34:35 INFO - 2015-10-25 17:34:35,017 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49896/tests/kraken/driver.html?testName=audio-oscillator) 17:34:35 INFO - 2015-10-25 17:34:35,585 DEBUG : BROWSER_OUTPUT: RSS: Main: 178384896 17:34:35 INFO - 2015-10-25 17:34:35,585 DEBUG : BROWSER_OUTPUT: 17:34:37 INFO - 2015-10-25 17:34:37,740 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49896/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:34:38 INFO - 2015-10-25 17:34:38,296 DEBUG : BROWSER_OUTPUT: RSS: Main: 175493120 17:34:38 INFO - 2015-10-25 17:34:38,296 DEBUG : BROWSER_OUTPUT: 17:34:41 INFO - 2015-10-25 17:34:41,371 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49896/tests/kraken/driver.html?testName=imaging-darkroom) 17:34:41 INFO - 2015-10-25 17:34:41,938 DEBUG : BROWSER_OUTPUT: RSS: Main: 172232704 17:34:41 INFO - 2015-10-25 17:34:41,940 DEBUG : BROWSER_OUTPUT: 17:34:45 INFO - 2015-10-25 17:34:45,295 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49896/tests/kraken/driver.html?testName=imaging-desaturate) 17:34:45 INFO - 2015-10-25 17:34:45,858 DEBUG : BROWSER_OUTPUT: RSS: Main: 172646400 17:34:45 INFO - 2015-10-25 17:34:45,858 DEBUG : BROWSER_OUTPUT: 17:34:48 INFO - 2015-10-25 17:34:48,673 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49896/tests/kraken/driver.html?testName=json-parse-financial) 17:34:49 INFO - 2015-10-25 17:34:49,240 DEBUG : BROWSER_OUTPUT: RSS: Main: 172445696 17:34:49 INFO - 2015-10-25 17:34:49,240 DEBUG : BROWSER_OUTPUT: 17:34:51 INFO - 2015-10-25 17:34:51,026 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49896/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:34:51 INFO - 2015-10-25 17:34:51,582 DEBUG : BROWSER_OUTPUT: RSS: Main: 202756096 17:34:51 INFO - 2015-10-25 17:34:51,582 DEBUG : BROWSER_OUTPUT: 17:34:53 INFO - 2015-10-25 17:34:53,509 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49896/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:34:54 INFO - 2015-10-25 17:34:54,076 DEBUG : BROWSER_OUTPUT: RSS: Main: 208203776 17:34:54 INFO - 2015-10-25 17:34:54,076 DEBUG : BROWSER_OUTPUT: 17:34:56 INFO - 2015-10-25 17:34:56,312 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49896/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:34:56 INFO - 2015-10-25 17:34:56,878 DEBUG : BROWSER_OUTPUT: RSS: Main: 193286144 17:34:56 INFO - 2015-10-25 17:34:56,878 DEBUG : BROWSER_OUTPUT: 17:34:59 INFO - 2015-10-25 17:34:59,286 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49896/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:34:59 INFO - 2015-10-25 17:34:59,845 DEBUG : BROWSER_OUTPUT: RSS: Main: 195039232 17:34:59 INFO - 2015-10-25 17:34:59,845 DEBUG : BROWSER_OUTPUT: 17:35:02 INFO - 2015-10-25 17:35:02,859 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49896/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:35:03 INFO - 2015-10-25 17:35:03,423 DEBUG : BROWSER_OUTPUT: RSS: Main: 192987136 17:35:03 INFO - 2015-10-25 17:35:03,423 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,266 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49896/tests/kraken/driver.html?testName=ai-astar) 17:35:05 INFO - 2015-10-25 17:35:05,520 DEBUG : BROWSER_OUTPUT: RSS: Main: 240128000 17:35:05 INFO - 2015-10-25 17:35:05,520 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,520 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:35:05 INFO - 2015-10-25 17:35:05,520 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:35:05 INFO - 2015-10-25 17:35:05,520 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:35:05 INFO - 2015-10-25 17:35:05,520 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:35:05 INFO - 2015-10-25 17:35:05,520 DEBUG : BROWSER_OUTPUT: |0;ai-astar;95;87;96;94;92;95;95;96;95;102 17:35:05 INFO - 2015-10-25 17:35:05,520 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;131;131;151;168;131;134;136;135;153;145 17:35:05 INFO - 2015-10-25 17:35:05,520 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;157;153;154;159;154;155;158;157 17:35:05 INFO - 2015-10-25 17:35:05,520 DEBUG : BROWSER_OUTPUT: |3;audio-fft;104;87;76;98;76;76;78;80;104;78 17:35:05 INFO - 2015-10-25 17:35:05,520 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;95;112;95;95;94;124;96;96;119 17:35:05 INFO - 2015-10-25 17:35:05,520 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;117;116;116;116;116;117;118;116;117;117 17:35:05 INFO - 2015-10-25 17:35:05,520 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;139;140;139;139;139;139;148;139;140 17:35:05 INFO - 2015-10-25 17:35:05,520 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;91;92;92;91;93;92;93;98;92;93 17:35:05 INFO - 2015-10-25 17:35:05,522 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;82;80;80;80;79;78;79;83;79;89 17:35:05 INFO - 2015-10-25 17:35:05,522 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;82;82;83;81;81;82;82;82;82;82 17:35:05 INFO - 2015-10-25 17:35:05,522 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;96;95;96;97;97;97;97;94;96;96 17:35:05 INFO - 2015-10-25 17:35:05,522 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;121;126;119;116;126;119;120;123;118;122 17:35:05 INFO - 2015-10-25 17:35:05,522 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;184;192;192;193;193;185;190;199;195;188 17:35:05 INFO - 2015-10-25 17:35:05,522 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;86;85;83;86;85;86;84;90;85;84 17:35:05 INFO - 2015-10-25 17:35:05,522 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:35:05 INFO - 2015-10-25 17:35:05,522 DEBUG : BROWSER_OUTPUT: __start_cc_report 17:35:05 INFO - 2015-10-25 17:35:05,522 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,841 17:35:05 INFO - 2015-10-25 17:35:05,522 DEBUG : BROWSER_OUTPUT: __end_cc_report 17:35:05 INFO - 2015-10-25 17:35:05,522 DEBUG : BROWSER_OUTPUT: __startTimestamp1445819705516__endTimestamp 17:35:05 INFO - 2015-10-25 17:35:05,522 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,522 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:35:05 INFO - 2015-10-25 17:35:05,522 DEBUG : BROWSER_OUTPUT: Number of tests: 14 17:35:05 INFO - 2015-10-25 17:35:05,523 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,523 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:94.70 Median:95.00 stddev:3.71 (3.9%) stddev-sans-first:3.94 17:35:05 INFO - 2015-10-25 17:35:05,523 DEBUG : BROWSER_OUTPUT: Values: 95.0 87.0 96.0 94.0 92.0 95.0 95.0 96.0 95.0 102.0 17:35:05 INFO - 2015-10-25 17:35:05,523 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,523 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.50 Median:140.50 stddev:12.46 (8.9%) stddev-sans-first:12.62 17:35:05 INFO - 2015-10-25 17:35:05,523 DEBUG : BROWSER_OUTPUT: Values: 131.0 131.0 151.0 168.0 131.0 134.0 136.0 135.0 153.0 145.0 17:35:05 INFO - 2015-10-25 17:35:05,523 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,523 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:156.70 Median:157.50 stddev:2.58 (1.6%) stddev-sans-first:2.45 17:35:05 INFO - 2015-10-25 17:35:05,523 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 157.0 153.0 154.0 159.0 154.0 155.0 158.0 157.0 17:35:05 INFO - 2015-10-25 17:35:05,523 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,523 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:85.70 Median:78.00 stddev:11.81 (15.1%) stddev-sans-first:10.51 17:35:05 INFO - 2015-10-25 17:35:05,523 DEBUG : BROWSER_OUTPUT: Values: 104.0 87.0 76.0 98.0 76.0 76.0 78.0 80.0 104.0 78.0 17:35:05 INFO - 2015-10-25 17:35:05,523 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,523 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.10 Median:95.00 stddev:11.57 (12.2%) stddev-sans-first:11.98 17:35:05 INFO - 2015-10-25 17:35:05,523 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 112.0 95.0 95.0 94.0 124.0 96.0 96.0 119.0 17:35:05 INFO - 2015-10-25 17:35:05,523 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,529 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 17:35:05 INFO - 2015-10-25 17:35:05,529 DEBUG : BROWSER_OUTPUT: Values: 117.0 116.0 116.0 116.0 116.0 117.0 118.0 116.0 117.0 117.0 17:35:05 INFO - 2015-10-25 17:35:05,529 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,529 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:141.00 Median:139.50 stddev:3.71 (2.7%) stddev-sans-first:2.95 17:35:05 INFO - 2015-10-25 17:35:05,529 DEBUG : BROWSER_OUTPUT: Values: 148.0 139.0 140.0 139.0 139.0 139.0 139.0 148.0 139.0 140.0 17:35:05 INFO - 2015-10-25 17:35:05,529 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,529 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:92.70 Median:92.50 stddev:2.00 (2.2%) stddev-sans-first:2.03 17:35:05 INFO - 2015-10-25 17:35:05,530 DEBUG : BROWSER_OUTPUT: Values: 91.0 92.0 92.0 91.0 93.0 92.0 93.0 98.0 92.0 93.0 17:35:05 INFO - 2015-10-25 17:35:05,530 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,530 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:80.90 Median:80.00 stddev:3.21 (4.0%) stddev-sans-first:3.38 17:35:05 INFO - 2015-10-25 17:35:05,532 DEBUG : BROWSER_OUTPUT: Values: 82.0 80.0 80.0 80.0 79.0 78.0 79.0 83.0 79.0 89.0 17:35:05 INFO - 2015-10-25 17:35:05,532 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,532 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:81.90 Median:82.00 stddev:0.57 (0.7%) stddev-sans-first:0.60 17:35:05 INFO - 2015-10-25 17:35:05,532 DEBUG : BROWSER_OUTPUT: Values: 82.0 82.0 83.0 81.0 81.0 82.0 82.0 82.0 82.0 82.0 17:35:05 INFO - 2015-10-25 17:35:05,532 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,532 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:96.10 Median:96.50 stddev:0.99 (1.0%) stddev-sans-first:1.05 17:35:05 INFO - 2015-10-25 17:35:05,532 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 96.0 97.0 97.0 97.0 97.0 94.0 96.0 96.0 17:35:05 INFO - 2015-10-25 17:35:05,533 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,533 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:121.00 Median:121.50 stddev:3.30 (2.7%) stddev-sans-first:3.50 17:35:05 INFO - 2015-10-25 17:35:05,535 DEBUG : BROWSER_OUTPUT: Values: 121.0 126.0 119.0 116.0 126.0 119.0 120.0 123.0 118.0 122.0 17:35:05 INFO - 2015-10-25 17:35:05,535 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,535 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.10 Median:192.50 stddev:4.53 (2.4%) stddev-sans-first:4.01 17:35:05 INFO - 2015-10-25 17:35:05,535 DEBUG : BROWSER_OUTPUT: Values: 184.0 192.0 192.0 193.0 193.0 185.0 190.0 199.0 195.0 188.0 17:35:05 INFO - 2015-10-25 17:35:05,535 DEBUG : BROWSER_OUTPUT: 17:35:05 INFO - 2015-10-25 17:35:05,535 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:85.40 Median:85.50 stddev:1.90 (2.2%) stddev-sans-first:2.00 17:35:05 INFO - 2015-10-25 17:35:05,536 DEBUG : BROWSER_OUTPUT: Values: 86.0 85.0 83.0 86.0 85.0 86.0 84.0 90.0 85.0 84.0 17:35:05 INFO - 2015-10-25 17:35:05,536 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:35:05 INFO - 2015-10-25 17:35:05,536 DEBUG : BROWSER_OUTPUT: 17:35:06 INFO - 2015-10-25 17:35:06,114 INFO : Browser exited with error code: 0 17:35:06 INFO - 2015-10-25 17:35:06,903 INFO : Completed test kraken (00:01:00) 17:35:06 INFO - 2015-10-25 17:35:06,903 INFO : Starting test v8_7 17:35:06 INFO - 2015-10-25 17:35:06,903 DEBUG : operating with platform_type : w7_ 17:35:06 INFO - 2015-10-25 17:35:06,903 INFO : Initialising browser for v8_7 test... 17:35:06 INFO - 2015-10-25 17:35:06,928 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpq7hw1i\profile http://localhost:49896/getInfo.html 17:35:11 INFO - 2015-10-25 17:35:11,513 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:35:11 INFO - 2015-10-25 17:35:11,513 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:35:11 INFO - 2015-10-25 17:35:11,513 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 17:35:11 INFO - 2015-10-25 17:35:11,513 DEBUG : BROWSER_OUTPUT: __metrics 17:35:11 INFO - 2015-10-25 17:35:11,588 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:35:11 INFO - 2015-10-25 17:35:11,766 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 121: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 17:35:11 INFO - 2015-10-25 17:35:11,901 DEBUG : BROWSER_OUTPUT: console.error: 17:35:11 INFO - 2015-10-25 17:35:11,904 DEBUG : BROWSER_OUTPUT: Message: [Exception... "Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [mozIStorageAsyncStatement.executeAsync]" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "JS frame :: resource://gre/modules/Sqlite.jsm :: ConnectionData.prototype<._executeStatement :: line 758" data: no] 17:35:11 INFO - 2015-10-25 17:35:11,904 DEBUG : BROWSER_OUTPUT: Stack: 17:35:11 INFO - 2015-10-25 17:35:11,904 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 17:35:11 INFO - 2015-10-25 17:35:11,904 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 17:35:11 INFO - 2015-10-25 17:35:11,904 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 17:35:11 INFO - 2015-10-25 17:35:11,904 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 17:35:11 INFO - 2015-10-25 17:35:11,904 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 17:35:11 INFO - 2015-10-25 17:35:11,904 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 17:35:11 INFO - 2015-10-25 17:35:11,904 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 17:35:11 INFO - 2015-10-25 17:35:11,904 DEBUG : BROWSER_OUTPUT: setAncestorsLastModified<@resource://gre/modules/Bookmarks.jsm:1347:9 17:35:11 INFO - 2015-10-25 17:35:11,904 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 17:35:11 INFO - 2015-10-25 17:35:11,904 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 17:35:11 INFO - 2015-10-25 17:35:11,904 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 17:35:11 INFO - 2015-10-25 17:35:11,905 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:808:13 17:35:11 INFO - 2015-10-25 17:35:11,905 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 17:35:11 INFO - 2015-10-25 17:35:11,905 DEBUG : BROWSER_OUTPUT: Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:934:23 17:35:11 INFO - 2015-10-25 17:35:11,905 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 17:35:11 INFO - 2015-10-25 17:35:11,905 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 17:35:11 INFO - 2015-10-25 17:35:11,905 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 17:35:11 INFO - 2015-10-25 17:35:11,905 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 17:35:11 INFO - 2015-10-25 17:35:11,905 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 17:35:11 INFO - 2015-10-25 17:35:11,905 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 17:35:11 INFO - 2015-10-25 17:35:11,905 DEBUG : BROWSER_OUTPUT: 17:35:12 INFO - 2015-10-25 17:35:12,500 INFO : Browser initialized. 17:35:12 INFO - 2015-10-25 17:35:12,500 INFO : Running cycle 1/1 for v8_7 test... 17:35:12 INFO - 2015-10-25 17:35:12,500 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpq7hw1i\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\v8_7\v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:35:23 INFO - 2015-10-25 17:35:23,904 DEBUG : BROWSER_OUTPUT: RSS: Main: 127193088 17:35:23 INFO - 2015-10-25 17:35:23,904 DEBUG : BROWSER_OUTPUT: 17:35:45 INFO - 2015-10-25 17:35:45,438 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/v8_7/run.html (next: http://localhost:49896/tests/v8_7/run.html) 17:35:45 INFO - 2015-10-25 17:35:45,686 DEBUG : BROWSER_OUTPUT: RSS: Main: 539115520 17:35:45 INFO - 2015-10-25 17:35:45,686 DEBUG : BROWSER_OUTPUT: 17:35:45 INFO - 2015-10-25 17:35:45,686 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: |0;Richards;151.30882130428205 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;201.28824476650564 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: |2;Encrypt;293.2551319648094 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5640.449438202248 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1398.04469273743 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: |5;Earley;660.9385327164574 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: |6;Boyer;10447.916666666666 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: |7;RegExp;29085.714285714286 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: |8;Splay;565.9309564233164 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;5091.370558375635 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: __startTimestamp1445819745686__endTimestamp 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: Number of tests: 10 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: 17:35:45 INFO - 2015-10-25 17:35:45,687 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:151.31 Median:151.31 stddev:0.00 (0.0%) 17:35:45 INFO - 2015-10-25 17:35:45,688 DEBUG : BROWSER_OUTPUT: Values: 151.3 17:35:45 INFO - 2015-10-25 17:35:45,688 DEBUG : BROWSER_OUTPUT: 17:35:45 INFO - 2015-10-25 17:35:45,688 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:201.29 Median:201.29 stddev:0.00 (0.0%) 17:35:45 INFO - 2015-10-25 17:35:45,688 DEBUG : BROWSER_OUTPUT: Values: 201.3 17:35:45 INFO - 2015-10-25 17:35:45,688 DEBUG : BROWSER_OUTPUT: 17:35:45 INFO - 2015-10-25 17:35:45,688 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:293.26 Median:293.26 stddev:0.00 (0.0%) 17:35:45 INFO - 2015-10-25 17:35:45,688 DEBUG : BROWSER_OUTPUT: Values: 293.3 17:35:45 INFO - 2015-10-25 17:35:45,688 DEBUG : BROWSER_OUTPUT: 17:35:45 INFO - 2015-10-25 17:35:45,688 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5640.45 Median:5640.45 stddev:0.00 (0.0%) 17:35:45 INFO - 2015-10-25 17:35:45,688 DEBUG : BROWSER_OUTPUT: Values: 5640.4 17:35:45 INFO - 2015-10-25 17:35:45,688 DEBUG : BROWSER_OUTPUT: 17:35:45 INFO - 2015-10-25 17:35:45,688 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1398.04 Median:1398.04 stddev:0.00 (0.0%) 17:35:45 INFO - 2015-10-25 17:35:45,688 DEBUG : BROWSER_OUTPUT: Values: 1398.0 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:660.94 Median:660.94 stddev:0.00 (0.0%) 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: Values: 660.9 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10447.92 Median:10447.92 stddev:0.00 (0.0%) 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: Values: 10447.9 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:29085.71 Median:29085.71 stddev:0.00 (0.0%) 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: Values: 29085.7 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:565.93 Median:565.93 stddev:0.00 (0.0%) 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: Values: 565.9 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:5091.37 Median:5091.37 stddev:0.00 (0.0%) 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: Values: 5091.4 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:35:45 INFO - 2015-10-25 17:35:45,690 DEBUG : BROWSER_OUTPUT: 17:35:46 INFO - 2015-10-25 17:35:46,288 INFO : Browser exited with error code: 0 17:35:46 INFO - 2015-10-25 17:35:46,950 INFO : Completed test v8_7 (00:00:40) 17:35:47 INFO - 2015-10-25 17:35:47,062 INFO : Completed test suite (00:20:18) 17:35:47 INFO - 2015-10-25 17:35:47,062 DEBUG : Working with test: dromaeo_css 17:35:47 INFO - 2015-10-25 17:35:47,062 DEBUG : Generating results file: dromaeo_css 17:35:47 INFO - 2015-10-25 17:35:47,062 DEBUG : Working with test: kraken 17:35:47 INFO - 2015-10-25 17:35:47,062 DEBUG : Generating results file: kraken 17:35:47 INFO - 2015-10-25 17:35:47,062 INFO : javascript benchmark 17:35:47 INFO - 2015-10-25 17:35:47,062 DEBUG : Working with test: v8_7 17:35:47 INFO - 2015-10-25 17:35:47,062 DEBUG : Generating results file: v8_7 17:35:47 INFO - 2015-10-25 17:35:47,062 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:35:47 INFO - 2015-10-25 17:35:47,128 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,63,25]] 17:35:47 INFO - 2015-10-25 17:35:47,128 DEBUG : process_Request line: dromaeo_css 6416.09 graph.html#tests=[[72,63,25]] 17:35:47 INFO - 2015-10-25 17:35:47,128 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:35:47 INFO - 2015-10-25 17:35:47,236 DEBUG : process_Request line: kraken 1587.40 graph.html#tests=[[232,63,25]] 17:35:47 INFO - 2015-10-25 17:35:47,236 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:35:47 INFO - 2015-10-25 17:35:47,335 DEBUG : process_Request line: v8_7 20821.77 graph.html#tests=[[230,63,25]] 17:35:47 INFO - 2015-10-25 17:35:47,336 INFO : javascript benchmark 17:35:47 INFO - 2015-10-25 17:35:47,338 INFO : TALOSDATA: [{"talos_counters": {}, "results": {"jquery.html": [305.6790370111385, 316.9984150079248, 322.36654972978107, 326.2691570881226, 329.8680527788885, 10574.841377379325, 12368.567100151497, 16189.83810161898, 16607.833921660796, 16642.334306627734, 16449.67100657986, 16650.50048498544, 16827.495175144733, 17108.486745397626, 17705.91147044263, 5495.6153069285165, 5497.917531237024, 5598.1602759585985, 5603.047481928062, 5611.270534830466, 4148.357004664282, 4343.847965321214, 5542.2517960075475, 5837.678927658977, 6234.470070044052, 6051.636901785907, 6144.586745048641, 6168.4448399644025, 6169.691515424233, 6189.566730328879, 5427.592930530206, 5429.67421954684, 5430.429804870471, 5744.16709577111, 6004.099385092228, 4991.675541089854, 5065.974670126662, 5093.643444958854, 5093.872653183663, 5104.285400043997, 12765.936170319183, 13116.6720831979, 13123.475060997549, 13133.802992955114, 22977.77022229773, 1922.7115932610081, 1959.4415591556337, 1965.7346258255095, 2024.9190032398687, 2101.569178318441, 9551.235901127962, 9554.665586704501, 9598.616055357777, 9630.374025688308, 9838.852417213748, 1448.6740483391259, 1452.0271418149864, 1486.4723023326671, 1520.1942970225798, 1556.9455069072528, 69679.65160174167, 70662.2933770666, 71034.64482677553, 71349.28650713478, 73891.26108738898, 6022.608530445507, 6136.294326152461, 6376.959373000595, 6404.903926441107, 6438.098666186737, 7120.608366539786, 7174.282571742889, 7204.9279507204255, 7424.925750742532, 7429.962850185822, 5080.212567052113, 5193.68837869729, 5231.712255825966, 5255.5795536357, 5265.52610265078, 5237.973810130925, 5336.786528538815, 5478.643888147258, 5543.667379957215, 5705.486506214461, 2686.87151396414, 2698.9460210795673, 2708.3364575679084, 2814.4793213255202, 2876.137158852336, 939.3800091939287, 942.3356533643764, 966.1160038564775, 990.4404011733347, 1036.486938965202, 369.86499927526796, 412.6636791015313, 435.39697518936504, 449.70319589071056, 455.35339817458623, 11633.360165190996, 11645.708857278467, 12156.331401772988, 12175.756484870253, 12257.203281786655, 450.5201959912748, 454.9249373853343, 455.44435788338177, 472.0417552368697, 481.066730542746, 6174.197354343996, 6238.469730072995, 6254.405831446096, 6433.324500927429, 6649.534532582769, 421304.0, 448945.0, 455776.0, 456616.0, 457049.0, 84611.0, 87229.0, 88089.0, 88259.0, 91875.0, 470.8822794301425, 478.72473327836633, 480.7668280883769, 480.9903801924012, 481.70134516599234, 10872.456377181268, 10924.0, 11234.662960111209, 11395.886041139482, 11400.543978241109, 37274.813625931696, 37632.623673762915, 37954.43068353922, 39102.413463798635, 41534.58465415307, 53984.460155399516, 54762.0, 55433.7557171781, 56873.43126568847, 57891.71054144702, 5888.673973780247, 5910.734016969161, 5913.793017244548, 6232.189815323979, 6239.594426362272, 12061.698457538632, 12457.813132802834, 12487.375631218221, 12505.624831255076, 12563.87436125627, 9234.861477077984, 9454.33819632619, 10231.693049208536, 10340.379577225089, 10635.627753028606, 9507.809843803223, 9733.853992190265, 9769.02913101555, 10053.69838904834, 10628.0, 9482.905170948477, 9890.159336456481, 10292.742681433025, 10946.233763636468, 10985.505652245827, 5828.912566311594, 5909.91135132982, 6177.752889884354, 6195.690215489298, 6198.256209254918, 10203.0, 10562.31344962575, 10622.468876556295, 10658.786824263627, 10659.573617055541], "dojo.html": [2480.67751192345, 2502.18678929348, 2508.0845491395644, 2595.3511622094475, 2610.660614120164, 4910.779014944323, 4961.875953101175, 4964.70211787293, 5167.199084141959, 5254.6847189168675, 4653.604443622287, 5024.84925452237, 5158.123119069758, 5248.291480650111, 5344.0, 4429.756363400012, 4516.435445569304, 4551.5448455154465, 4611.561901619358, 4614.261718125101, 4346.434963454746, 4543.863684089482, 4553.658475614326, 4578.908421831561, 4584.4957054723955, 4338.1540599583095, 4350.6301964332915, 4485.977570112145, 4522.706024108439, 4583.014651849851, 4379.839342574221, 4504.617107545862, 4536.296873984537, 4564.0, 4613.953860461386, 4419.823207071713, 4477.955220447787, 4481.731096134243, 4513.578222859789, 4535.750533720644, 4995.700257984533, 5099.33708617881, 5136.4606716295, 5150.922736158961, 5241.161414173714, 5300.893982120375, 5470.17947307907, 5560.555155587544, 5586.273784408041, 5587.469190426903, 5081.491850814926, 5153.6392452528335, 5202.97398513005, 5293.894122117536, 5300.761465734051, 4819.759012049383, 4834.347363106006, 4879.829205977774, 4918.893249018978, 4939.3084968104495, 5259.869128137469, 5394.217838413487, 5399.622026458189, 5403.162509810985, 5695.686737229492, 4950.826721064817, 5063.822766203239, 5068.41713202979, 5116.283720279164, 5129.538341549279, 5113.181890897438, 5202.843914682566, 5210.114280572312, 5376.569874410038, 5430.402655707869, 5227.529522343008, 5440.483154100354, 5453.727313634382, 5455.0, 5553.083741182741, 5021.046001259822, 5114.181730923109, 5147.6911385317, 5192.8961420771375, 5252.868678283074, 3138.4978403455793, 3149.4488464518618, 3200.831696430786, 3226.951595726066, 3259.7775834062227, 2922.9707702922697, 2939.4855900217804, 2941.9411611768073, 2945.6170697809575, 3002.0693584988285, 4969.850904472872, 5153.0466863630345, 5242.97378513105, 5260.736963151827, 5271.630985830955, 5295.920561191585, 5463.754131064112, 5499.625093726569, 5517.972410137923, 5638.013347664142, 4992.675476094042, 5038.798448062036, 5081.644284900021, 5286.577073834084, 5409.78360865561, 4254.85108021224, 4259.3185090386005, 4340.544242854518, 4347.478302603708, 4387.100644367897, 5569.637973531789, 5606.775728970795, 5616.887662246732, 5630.521405680399, 5834.095715164156, 5209.791608335776, 5236.345456817898, 5328.920066199088, 5333.7066461345, 5333.9466605335, 5490.533304669148, 5507.449255074461, 5666.688332141772, 5846.064629659234, 5856.209411729362, 5387.730613469389, 5405.621606487508, 5491.258680078298, 5496.093144631251, 5500.147477141049, 5468.8359349218, 5544.057510223352, 5564.888702226014, 5681.0910254359105, 5690.630109042899, 5339.733013349394, 5431.755570999236, 5443.782248710166, 5560.4161563035295, 5562.082256427644, 5090.363704536933, 5354.277172581652, 5385.27298814655, 5467.043267428264, 5496.835094947158, 4997.675615961845, 5075.923861142012, 5093.159628661229, 5192.922106168335, 5247.2129180623215, 5015.2978582997675, 5112.821051263188, 5114.360704911886, 5231.032259031939, 5391.865203369947, 4985.277134815504, 5077.0, 5097.311862898609, 5157.5358217759085, 5293.920591191212], "mootools.html": [1095.2661716649843, 1160.048760016786, 1207.372166473434, 1378.9795551292043, 1417.4897037066655, 7640.159582445934, 8666.263367613761, 8755.124487551242, 8940.59767310471, 9067.63729450822, 8338.0, 8480.533570653612, 8811.471311721285, 8819.0, 8832.116788321164, 1993.1827950540282, 2004.4086994336706, 2238.809701175402, 2271.8750468724215, 2286.542691461706, 2256.6050941085327, 2298.8160947124275, 2313.479467119893, 2318.13070098713, 2319.9188028419007, 2269.6482045282924, 2305.1125316753005, 2308.595995700754, 2309.9653505197434, 2312.352541288442, 1991.7809041005478, 2031.969520457187, 2187.8359123065834, 2262.6266666000092, 2266.4787098967163, 2139.5720855828895, 2148.3125399872047, 2150.6881502182096, 2155.4395857077116, 2290.141197051106, 8944.955275223647, 8972.955135224347, 8984.910150898539, 9003.414778039407, 9055.320850936207, 5093.770780314895, 5101.948980510185, 5154.38147422308, 5156.484351564851, 5159.58723302135, 3216.517522371663, 3293.6541663125504, 3404.8127352995575, 3406.3527929693278, 3505.1587618971516, 995.5271246158089, 1001.864748258983, 1020.3623184788456, 1028.275066078413, 1059.2638116509029, 12181.39093045353, 12608.936955315256, 12862.807057894139, 12889.226646401152, 12905.741885162244, 4508.098380323882, 4550.271956486946, 4605.792739326738, 4625.468071171791, 4637.07258548285, 10025.448600326907, 10042.397456152654, 10596.735081623021, 10639.148868090535, 10647.094996925192, 5131.1277082895995, 5140.691558506502, 5151.4333423323405, 5154.92267615986, 5176.223566464985, 4653.348531205634, 4684.250519916865, 4691.390119284471, 4700.106979673919, 4713.646476514206, 3672.926541469156, 3702.648248416396, 3705.8332375043187, 3743.47591337213, 3775.7734535927384, 1099.763550836574, 1135.3017893995298, 1143.891330323618, 1155.6821873984554, 1162.9302241865514, 787.6455594982281, 1011.858339832424, 1025.5590096258532, 1028.136365453023, 1041.9687409377732, 6869.0383346331555, 7645.814898690712, 7737.264959828807, 8072.4349295548745, 8227.42408031432, 593.6289818863211, 614.2812908896602, 616.3528295290017, 627.3632263252802, 632.1118828046602, 5674.319081710221, 5691.857703557362, 5790.0, 5973.880522389528, 6018.578699491081, 10814.29707069038, 11057.0, 11228.101751859831, 11393.259438136494, 11462.4268786562, 11290.830637540443, 11303.095752339794, 11309.71725706864, 11311.717207069889, 11319.773604527863, 8173.386995975206, 8197.549134797524, 8209.261166495044, 8213.178682131738, 8214.8357032859, 6978.47661425385, 7170.96414517924, 7326.1941186468375, 7355.963220183865, 7380.926190738024, 9210.309226807882, 9242.815143697222, 9251.352405331561, 9392.295577831554, 9474.85787713171, 10021.699349019542, 10048.246381521269, 10083.798324033332, 10089.747756305858, 10099.242556808416, 7660.923390766021, 7688.154303026775, 7715.691372345268, 7766.417518686008, 7959.164287749702, 8529.829403411773, 8568.485890846569, 8570.700025499327, 8679.609417576097, 8710.695125670825, 7515.962420188082, 7596.354309883722, 7607.505512141693, 7797.103333116765, 8058.717944871902, 7410.96294518524, 7518.548887066793, 7640.426967977535, 7654.540727556365, 7702.345300649507, 6780.423663988616, 6832.965835170792, 7710.421718371257, 7762.7671169865, 7787.338076263581, 6365.172527571386, 6472.546921715435, 6484.610923344426, 6892.448604111559, 6899.344562266577, 6518.934810651833, 6667.466602671678, 6702.530822842355, 6739.157605299338, 6942.236354000955], "ext.html": [422.5077784381196, 488.8166937398478, 495.180476311704, 501.0380070265091, 520.9374875014998, 30017.699823001763, 30289.54565681514, 30323.545146822788, 36843.447348289796, 38131.23737525248, 39118.804405978, 40085.59914400855, 40272.0, 40889.59110408895, 41075.79462102693, 7992.440529162961, 8141.104478507375, 8307.83384332313, 8457.57712114395, 8491.108433614474, 7334.66993985272, 7494.0, 10356.482175891213, 10581.365118092901, 10626.521806518725, 9811.215102791795, 10107.646232381869, 10530.947345263301, 10566.52450639723, 10585.470726463685, 7456.739014134533, 7643.961780191064, 10321.896781032168, 10448.320859144169, 10509.684709458728, 9560.521973901346, 9802.36284641496, 10227.590896364136, 10389.37663740178, 10423.739406514822, 37869.81065094657, 37911.0, 37934.43098353527, 37975.24049519022, 38205.80897095497, 3034.5142494118477, 3060.586820779211, 3068.9539656905163, 3115.43922094023, 3125.7186853183093, 28823.56764648532, 29684.70315296841, 30088.24779380533, 31048.602812873476, 31057.37885242305, 2538.5176816404824, 2588.9223323300143, 2592.9870350648316, 2629.0929629277866, 2666.919992400231, 307793.46103269566, 310999.0, 313604.0, 313638.0, 317246.0, 44338.556614434085, 48650.75674621675, 48695.756521217874, 48745.56272186377, 51553.48446515487, 45964.5403545967, 55479.44520554823, 55563.0, 58002.70998645064, 58237.41762582405, 13488.39302231402, 13660.93169534146, 13831.10097843657, 13848.169109853441, 13873.791893121612, 10388.792224155475, 10396.272260941812, 10401.53193106312, 10414.687559373231, 10416.322939009091, 16739.832601674072, 16997.320107195574, 17189.05460199701, 17261.91369043147, 17294.481165565052, 7321.633918304064, 7465.962670186615, 7577.696892124253, 7618.0, 7654.846903061907, 826.995865020683, 829.2660995019452, 870.4124715816798, 902.5261737587688, 964.1081999150898, 27808.86095569509, 28052.158435246976, 28631.57052644212, 29234.38485961191, 29424.705752942624, 977.8337682593834, 978.2858513285341, 983.2478154212033, 987.6592575561564, 1111.4776055254179, 14718.187795110864, 14826.777598336033, 15110.320035598425, 15119.16844573537, 15278.923605381902, 411418.0, 417474.0, 437826.0, 439935.0, 442478.0, 47572.52427475681, 49005.0, 49349.75325123423, 49373.25940110901, 49448.75275623599, 1150.2408410449063, 1175.1010476985114, 1176.1707995862896, 1188.0, 1196.0790191552455, 28119.0, 28349.858250708614, 28486.57270140991, 28714.0, 28833.85583072155, 38108.0, 40293.0, 40372.0, 40618.39072413857, 42502.0, 271818.0, 382797.0, 387091.0, 389245.0, 389679.27056047623, 26966.86516567483, 27157.72842271552, 27166.864165679046, 27802.582961256, 27860.0, 33291.66708332886, 37518.812405937795, 41415.37876931909, 41778.16443671049, 42015.15969680528, 32095.35809283848, 33413.33173336568, 36495.81752091223, 36915.0, 37184.62815371811, 33466.330673385906, 34249.48625770665, 35891.64108358987, 36347.45478817767, 37736.77343238445, 34981.47527787136, 35483.112922177155, 36905.81547092248, 37045.81477092597, 37146.44280335743, 33464.33071338511, 33892.35647197232, 34380.96857094291, 36852.63147368492, 37550.24899502049, 28248.435031298846, 28777.71222287834, 29950.0, 30997.535036974914, 31032.68967310298], "prototype.html": [303.86933618544026, 316.34200862206603, 317.42704418524556, 317.49359771164995, 321.4984623986581, 5645.096784514479, 5671.631343962639, 5683.801066962657, 5685.8578535536635, 5686.573506986972, 5531.0873705838485, 5631.60578759487, 5633.8309850704445, 5638.097904335307, 5639.68981706006, 3931.8230679619487, 3935.1736135411456, 4098.97950510247, 4133.834646614132, 4188.706790524657, 3941.9802900985596, 4128.071183983609, 4142.7514349139, 4186.03721144137, 4220.493540775111, 4031.7984100794843, 4132.648724858391, 4134.3178375567995, 4141.62725354718, 4154.169166166764, 4045.1505183911117, 4073.8370465181356, 4097.856575019875, 4146.4402305688645, 4153.667706583481, 3445.8621655133766, 3531.434970404723, 3615.8553657853654, 3632.037510059836, 3650.5071815305123, 5445.591580631469, 5503.807366742185, 5510.614257002011, 5516.7517461714315, 5526.944730552724, 4465.307877278994, 4625.861224163247, 4826.758662066883, 4871.196252618313, 4925.285833554115, 5517.586181036439, 5520.420355862657, 5527.447255274481, 5530.419305972855, 5559.861003474945, 3904.843806247775, 3942.64516193544, 4047.5750046245016, 4085.468889044435, 4088.1414902870433, 5804.535637149018, 5861.41385861419, 6065.60573562717, 6144.446999770043, 6150.415710507539, 3911.0808959894403, 3923.372260438316, 3924.0, 3931.4102884566973, 3975.3241948868763, 3996.7002474814503, 4214.1150358425075, 4256.553061928515, 4260.510041345223, 4268.893277668083, 4637.118947399983, 4639.234526303189, 4654.162250794821, 4819.445763737215, 4916.360873086547, 5550.278463799761, 5603.6077474577205, 5663.263775709132, 5783.219265399117, 5927.2590926134235, 1437.6621493948915, 1461.8976671632884, 1481.5185064853965, 1494.7757836324638, 1532.256855425118, 1020.3265844542566, 1030.8299130643509, 1043.3739756146404, 1045.994770026145, 1047.7118792332017, 5454.209139674805, 5460.126379779295, 5724.055530837449, 5777.826665200051, 5783.88432231353, 6137.140800287963, 6153.2000839891425, 6154.1999540059505, 6157.599756015845, 6175.96912015446, 5446.373667028263, 5472.534834539029, 5482.2873026506295, 5527.53015993637, 5649.322081350182, 3182.0, 3198.72810811079, 3324.900252992414, 3376.7636265461183, 3380.7126394256766, 6043.939560604425, 6143.109249158936, 6144.17053697754, 6284.811455656338, 6376.266729326094, 5770.88458230833, 5893.469587737124, 5907.409259074058, 5908.468237858613, 5919.852003699942, 6185.938140618626, 6509.414152726277, 6518.739250430024, 6520.347965203442, 6520.739170433129, 6038.788642397671, 6077.604955677867, 6097.146399503984, 6147.538934580014, 6181.474574661204, 5672.631278966854, 5679.318481782213, 5769.336526299361, 5819.679917604403, 5950.583459157818, 6149.139120523041, 6150.169727086786, 6157.445829875153, 6215.409536094064, 6376.107344971801, 4114.259433302034, 4215.59951804578, 4217.873463796091, 4225.852095176654, 4241.91516169681, 5833.416658334302, 5876.707124432618, 5929.584929054925, 6214.627122372672, 6224.1597384352535, 5801.158831969256, 5858.7070646468355, 6069.787557435645, 6094.329623741473, 6098.29869564988, 5866.325372582208, 6016.458518733335, 6025.578209525291, 6031.577789554689, 6040.728167232573, 5674.290713660793, 5905.291365036223, 5908.734106965283, 5910.468057874812, 5913.142594323713, 5744.281964754406, 5749.540036796962, 5840.561957853263, 5855.443732845373, 6056.06131049688, 5611.80358687444, 5689.975804355256, 5888.969430349586, 5968.582199246011, 5970.343262241063], "yui.html": [699.1470406104557, 702.0732632924542, 714.9749758758444, 716.6739133694169, 717.6196615793627, 10889.83665245022, 11140.108791296698, 11154.609588664389, 11212.27120237185, 11381.26021808582, 10629.73425664357, 10693.625723099693, 10696.9303069693, 10751.462426878665, 10842.783144337109, 495.5094456488087, 499.37079280107145, 549.4725063938623, 555.9499645031937, 590.5069267161925, 560.2772423573585, 563.8139413993372, 565.9094544872821, 565.9179418984245, 566.8157848699169, 583.9532837373, 588.1706793421276, 589.9026660600996, 604.4348534120611, 618.1685632823845, 505.2194359714232, 524.8058218459175, 592.0290723213933, 605.4520658803812, 606.2785285510229, 430.0560270206897, 430.3587654394929, 430.51782004155234, 431.9654427645781, 446.2191165460444, 9864.852027219598, 9943.105120539114, 10034.59861605535, 10225.335353202092, 10538.473076346152, 10126.44304563256, 10753.51609177589, 10935.617253396173, 11735.119866010084, 12055.035597152208, 9955.55200016001, 10004.54979525923, 10031.49842507879, 10083.243756718202, 10710.464476776207, 3301.290222602152, 3397.405454045532, 3402.9659703402895, 3445.534852794866, 3448.3965306071336, 46119.76940115311, 46980.76509617464, 47129.0, 47328.526714732754, 47570.048599028174, 9115.134062264075, 9549.0, 9592.472414017297, 9777.706668799805, 9860.654877079267, 16589.751153732705, 16620.0, 16632.916835415745, 16639.334426622798, 16684.916575417046, 6846.0, 6871.278515755874, 7001.124859392576, 7024.929750702529, 7129.857402851914, 3919.8824035278985, 4065.5324637666454, 4144.0, 4184.895377615584, 4304.80628371724, 2763.77889768818, 2796.2310364649884, 2805.635267415223, 2817.4646817104685, 2821.7319354661277, 473.3633263260909, 482.8117034356604, 482.9082474329866, 490.2694984473181, 490.5781028315646, 585.654463866321, 628.3245511075612, 642.7975187815823, 664.1001443044605, 705.2665228162756, 8183.386246031571, 8887.200151986352, 9261.212796912203, 9429.454047407162, 9549.809003819884, 515.5257163409672, 643.5655932245716, 688.5386790850142, 697.6546609428366, 726.8001299642641, 3897.9610203898164, 4131.9173616527505, 4188.706790524695, 4268.743875367426, 4355.455568053993, 56203.71898140483, 56341.0, 59211.11183332253, 59590.404095959355, 59673.0, 49762.25356619654, 49993.500064999615, 50642.493575064516, 50813.49186508162, 50939.74530127326, 39890.80054599708, 40132.398014029815, 40487.797561012005, 40653.796731016155, 42207.577924221594, 8666.610002550027, 8714.433561818461, 9057.90942090597, 9132.406393584395, 9285.396449230779, 29384.26539336534, 30316.23778534035, 30530.18372703412, 30836.383272334875, 30916.381672366875, 39559.80220098881, 39648.80175599104, 39837.83324418545, 40145.59854401419, 42399.0, 8315.501069935823, 8468.872966905377, 8564.721362460583, 8592.183742544448, 8614.44006139599, 11300.7739845201, 11794.705132371759, 12142.0, 12146.514139434677, 12216.378886521745, 11239.887601123884, 11483.540658373571, 11507.884921150682, 11584.884151158718, 11813.586524471602, 10811.297265677706, 10928.781424371628, 11137.164712646745, 11546.307221566733, 11939.164258501822, 11216.775664486828, 11480.311181329147, 11691.298522088702, 11760.11799115087, 12154.574589889311, 9493.905060949302, 9918.603255869974, 9988.80917647522, 10090.596376144871, 10120.190384769348, 10003.849942251018, 10041.899581004096, 10128.189744820544, 10148.83288421841, 10170.8474372883]}, "summary": {"suite": 6416.091369087857, "subtests": {"jquery.html": {"filtered": 6881.95622929035, "value": 6881.95622929035}, "dojo.html": {"filtered": 4851.542820949914, "value": 4851.542820949914}, "mootools.html": {"filtered": 4665.817582352379, "value": 4665.817582352379}, "ext.html": {"filtered": 17937.13284087834, "value": 17937.13284087834}, "prototype.html": {"filtered": 4397.6075110141555, "value": 4397.6075110141555}, "yui.html": {"filtered": 5676.690058193704, "value": 5676.690058193704}}}, "test_machine": {"platform": "x86", "osversion": "6.1.7601", "os": "win", "name": "T-W732-IX-052"}, "testrun": {"date": 1445818528, "suite": "dromaeo_css", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "44.0a1", "id": "20151025133003", "branch": "Mozilla-Inbound", "revision": "61d1f8675fc39e0fd1abb9828bbd30e324eb3234"}}, {"talos_counters": {}, "results": {"audio-dft": [160.0, 160.0, 157.0, 153.0, 154.0, 159.0, 154.0, 155.0, 158.0, 157.0], "ai-astar": [95.0, 87.0, 96.0, 94.0, 92.0, 95.0, 95.0, 96.0, 95.0, 102.0], "imaging-gaussian-blur": [117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 118.0, 116.0, 117.0, 117.0], "audio-beat-detection": [131.0, 131.0, 151.0, 168.0, 131.0, 134.0, 136.0, 135.0, 153.0, 145.0], "json-stringify-tinderbox": [82.0, 82.0, 83.0, 81.0, 81.0, 82.0, 82.0, 82.0, 82.0, 82.0], "stanford-crypto-pbkdf2": [184.0, 192.0, 192.0, 193.0, 193.0, 185.0, 190.0, 199.0, 195.0, 188.0], "json-parse-financial": [82.0, 80.0, 80.0, 80.0, 79.0, 78.0, 79.0, 83.0, 79.0, 89.0], "stanford-crypto-sha256-iterative": [86.0, 85.0, 83.0, 86.0, 85.0, 86.0, 84.0, 90.0, 85.0, 84.0], "audio-fft": [104.0, 87.0, 76.0, 98.0, 76.0, 76.0, 78.0, 80.0, 104.0, 78.0], "imaging-darkroom": [148.0, 139.0, 140.0, 139.0, 139.0, 139.0, 139.0, 148.0, 139.0, 140.0], "stanford-crypto-ccm": [121.0, 126.0, 119.0, 116.0, 126.0, 119.0, 120.0, 123.0, 118.0, 122.0], "audio-oscillator": [95.0, 95.0, 112.0, 95.0, 95.0, 94.0, 124.0, 96.0, 96.0, 119.0], "stanford-crypto-aes": [96.0, 95.0, 96.0, 97.0, 97.0, 97.0, 97.0, 94.0, 96.0, 96.0], "imaging-desaturate": [91.0, 92.0, 92.0, 91.0, 93.0, 92.0, 93.0, 98.0, 92.0, 93.0]}, "summary": {"suite": 1587.3999999999999, "subtests": {"audio-fft": {"filtered": 85.7, "value": 85.7}, "stanford-crypto-pbkdf2": {"filtered": 191.1, "value": 191.1}, "audio-beat-detection": {"filtered": 141.5, "value": 141.5}, "stanford-crypto-ccm": {"filtered": 121.0, "value": 121.0}, "imaging-darkroom": {"filtered": 141.0, "value": 141.0}, "json-parse-financial": {"filtered": 80.9, "value": 80.9}, "audio-oscillator": {"filtered": 102.1, "value": 102.1}, "ai-astar": {"filtered": 94.7, "value": 94.7}, "audio-dft": {"filtered": 156.7, "value": 156.7}, "stanford-crypto-sha256-iterative": {"filtered": 85.4, "value": 85.4}, "json-stringify-tinderbox": {"filtered": 81.9, "value": 81.9}, "imaging-gaussian-blur": {"filtered": 116.6, "value": 116.6}, "stanford-crypto-aes": {"filtered": 96.1, "value": 96.1}, "imaging-desaturate": {"filtered": 92.7, "value": 92.7}}}, "test_machine": {"platform": "x86", "osversion": "6.1.7601", "os": "win", "name": "T-W732-IX-052"}, "testrun": {"date": 1445818528, "suite": "kraken", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "44.0a1", "id": "20151025133003", "branch": "Mozilla-Inbound", "revision": "61d1f8675fc39e0fd1abb9828bbd30e324eb3234"}}, {"talos_counters": {}, "results": {"DeltaBlue": [201.28824476650564], "Encrypt": [293.2551319648094], "NavierStokes": [5091.370558375635], "Decrypt": [5640.449438202248], "Splay": [565.9309564233164], "Richards": [151.30882130428205], "RegExp": [29085.714285714286], "Earley": [660.9385327164574], "Boyer": [10447.916666666666], "RayTrace": [1398.04469273743]}, "summary": {"suite": 20821.769237716133, "subtests": {"DeltaBlue": {"filtered": 328.4742239999999, "value": 328.4742239999999}, "Encrypt": {"filtered": 907.6772099999997, "value": 907.6772099999997}, "NavierStokes": {"filtered": 291.4735792622133, "value": 291.4735792622133}, "Decrypt": {"filtered": 47.19145219123505, "value": 47.19145219123505}, "Splay": {"filtered": 143.99459700000003, "value": 143.99459700000003}, "RegExp": {"filtered": 31.320702357563874, "value": 31.320702357563874}, "Richards": {"filtered": 233.31091800000004, "value": 233.31091800000004}, "Earley": {"filtered": 1008.3585190000002, "value": 1008.3585190000002}, "Boyer": {"filtered": 63.78908075772688, "value": 63.78908075772688}, "RayTrace": {"filtered": 529.3028211788212, "value": 529.3028211788212}}}, "test_machine": {"platform": "x86", "osversion": "6.1.7601", "os": "win", "name": "T-W732-IX-052"}, "testrun": {"date": 1445818528, "suite": "v8_7", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "44.0a1", "id": "20151025133003", "branch": "Mozilla-Inbound", "revision": "61d1f8675fc39e0fd1abb9828bbd30e324eb3234"}}] 17:35:47 INFO - RETURN: dromaeo_css: 6416.09 17:35:47 INFO - RETURN: kraken: 1587.40 17:35:47 INFO - RETURN: v8_7: 20821.77 17:35:47 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,25]]", "result": "20821.77"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,25]]", "result": "1587.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,25]]", "result": "6416.09"}}} 17:35:47 INFO - Return code: 0 17:35:47 INFO - # TBPL SUCCESS # 17:35:47 INFO - Running post-action listener: _resource_record_post_action 17:35:47 INFO - Running post-run listener: _resource_record_post_run 17:35:48 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 80309248; Write bytes: 433884672; Read time: 1038580; Write time: 5255860 17:35:48 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 16384; Read time: 0; Write time: 60 17:35:48 INFO - run-tests - Wall time: 1221s; CPU: 13.0%; Read bytes: 80309248; Write bytes: 409184256; Read time: 1038580; Write time: 5021230 17:35:48 INFO - Running post-run listener: _upload_blobber_files 17:35:48 INFO - Blob upload gear active. 17:35:48 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 17:35:48 INFO - Copying logs to upload dir... 17:35:48 INFO - mkdir: C:\slave\test-pgo\build\upload\logs program finished with exit code 0 elapsedTime=1480.427000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 57 secs) (at 2015-10-25 17:36:05.293126) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-25 17:36:05.303275) ========= 'cmd' '/C' 'for' '%f' 'in' '(*)' 'do' '@type' '%f' in dir C:\slave\test-pgo\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 COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-052 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-052 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo\properties RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-052 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False build_url:https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/5QaG_5rjRlqYZffJGPaYbA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-25 17:36:05.605426) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 29 secs) (at 2015-10-25 17:36:05.605777) ========= 'rm' '-f' 'oauth.txt' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-052 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-052 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-052 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= Finished 'rm -f ...' (results: 0, elapsed: 29 secs) (at 2015-10-25 17:36:35.266940) ========= ========= Started reboot slave lost (results: 0, elapsed: 19 secs) (at 2015-10-25 17:36:35.267239) ========= ========= Finished reboot slave lost (results: 0, elapsed: 19 secs) (at 2015-10-25 17:36:54.420799) =========