builder: ash_win7-ix_test-dromaeojs-pgo slave: t-w732-ix-032 starttime: 1445593499.77 results: success (0) buildid: 20151022040420 builduid: 481232e75ba1437086db34585c78d66c revision: f2a99170e30af7fd4f395ca9a930c46b7acdbe48 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 02:44:59.769061) ========= master: http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 02:44:59.771596) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-23 02:44:59.771901) ========= '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-032 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-032 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-032 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.622000 basedir: 'C:\\slave\\test-pgo' ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-23 02:45:00.438870) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-23 02:45:00.439180) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-23 02:45:00.755963) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 02:45:00.756371) ========= '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-032 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-032 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-032 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.601000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 02:45:01.374668) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 02:45:01.375398) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 02:45:01.376186) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-23 02:45:01.376559) ========= '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-032 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-032 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-032 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 --02:45:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 9.09 MB/s 02:45:02 (9.09 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.190000 ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-23 02:45:02.584887) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 22 secs) (at 2015-10-23 02:45:02.585629) ========= '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-032 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-032 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-032 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=15.001000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 22 secs) (at 2015-10-23 02:45:25.014750) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-10-23 02:45:25.015099) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo projects/ash --rev f2a99170e30af7fd4f395ca9a930c46b7acdbe48 --destination scripts --debug' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/ash --rev f2a99170e30af7fd4f395ca9a930c46b7acdbe48 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-032 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-032 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-032 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-23 02:45:25,990 truncating revision to first 12 chars 2015-10-23 02:45:25,990 Setting DEBUG logging. 2015-10-23 02:45:25,990 attempt 1/10 2015-10-23 02:45:25,990 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/ash/f2a99170e30a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-23 02:45:27,431 unpacking tar archive at: ash-f2a99170e30a/testing/mozharness/ program finished with exit code 0 elapsedTime=2.398000 ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-10-23 02:45:27.490422) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-23 02:45:27.490776) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-23 02:45:27.506809) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 02:45:27.507177) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 02:45:27.507810) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 51 secs) (at 2015-10-23 02:45:27.508187) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Ash' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Ash' 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', 'Ash', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Ash'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-032 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-032 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-032 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 02:45:28 INFO - MultiFileLogger online at 20151023 02:45:28 in C:\slave\test-pgo 02:45:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Ash --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Ash 02:45:28 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 02:45:28 INFO - {'append_to_log': False, 02:45:28 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 02:45:28 INFO - 'blob_upload_branch': 'Ash', 02:45:28 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 02:45:28 INFO - 'branch': 'Ash', 02:45:28 INFO - 'buildbot_json_path': 'buildprops.json', 02:45:28 INFO - 'config_files': ('talos/windows_config.py',), 02:45:28 INFO - 'default_actions': ('clobber', 02:45:28 INFO - 'read-buildbot-config', 02:45:28 INFO - 'download-and-extract', 02:45:28 INFO - 'populate-webroot', 02:45:28 INFO - 'create-virtualenv', 02:45:28 INFO - 'install', 02:45:28 INFO - 'run-tests'), 02:45:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:45:28 INFO - 'download_minidump_stackwalk': True, 02:45:28 INFO - 'download_symbols': 'ondemand', 02:45:28 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 02:45:28 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 02:45:28 INFO - 'hg': 'c:/mozilla-build/hg/hg', 02:45:28 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 02:45:28 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 02:45:28 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 02:45:28 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 02:45:28 INFO - 'C:/mozilla-build/tooltool.py'), 02:45:28 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 02:45:28 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 02:45:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:45:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:45:28 INFO - 'installer_path': 'installer.exe', 02:45:28 INFO - 'log_level': 'info', 02:45:28 INFO - 'log_name': 'talos', 02:45:28 INFO - 'log_to_console': True, 02:45:28 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 02:45:28 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 02:45:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 02:45:28 INFO - 'opt_config_files': (), 02:45:28 INFO - 'pip_index': False, 02:45:28 INFO - 'sps_profile': False, 02:45:28 INFO - 'sps_profile_interval': 0, 02:45:28 INFO - 'suite': 'dromaeojs', 02:45:28 INFO - 'system_bits': '32', 02:45:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:45:28 INFO - 'title': 'T-W732-IX-032', 02:45:28 INFO - 'use_talos_json': True, 02:45:28 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 02:45:28 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 02:45:28 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 02:45:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:45:28 INFO - 'work_dir': 'build'} 02:45:28 INFO - ##### 02:45:28 INFO - ##### Running clobber step. 02:45:28 INFO - ##### 02:45:28 INFO - Running pre-action listener: _resource_record_pre_action 02:45:28 INFO - Running main action method: clobber 02:45:28 INFO - rmtree: C:\slave\test-pgo\build 02:45:28 INFO - Using _rmtree_windows ... 02:45:28 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 02:48:33 INFO - Running post-action listener: _resource_record_post_action 02:48:33 INFO - ##### 02:48:33 INFO - ##### Running read-buildbot-config step. 02:48:33 INFO - ##### 02:48:33 INFO - Running pre-action listener: _resource_record_pre_action 02:48:33 INFO - Running main action method: read_buildbot_config 02:48:33 INFO - Using buildbot properties: 02:48:33 INFO - { 02:48:33 INFO - "properties": { 02:48:33 INFO - "buildnumber": 0, 02:48:33 INFO - "product": "firefox", 02:48:33 INFO - "script_repo_revision": "production", 02:48:33 INFO - "builddir": "ash_win7-ix_test-dromaeojs", 02:48:33 INFO - "repository": "", 02:48:33 INFO - "buildername": "Windows 7 32-bit ash pgo talos dromaeojs", 02:48:33 INFO - "buildid": "20151022040420", 02:48:33 INFO - "slavename": "t-w732-ix-032", 02:48:33 INFO - "stage_platform": "win32-pgo", 02:48:33 INFO - "basedir": "C:\\slave\\test-pgo", 02:48:33 INFO - "nightly_build": "True", 02:48:33 INFO - "project": "", 02:48:33 INFO - "platform": "win7-ix", 02:48:33 INFO - "master": "http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/", 02:48:33 INFO - "slavebuilddir": "test", 02:48:33 INFO - "scheduler": "tests-ash-win32-pgo-talos", 02:48:33 INFO - "branch": "ash", 02:48:33 INFO - "repo_path": "projects/ash", 02:48:33 INFO - "pgo_build": "True", 02:48:33 INFO - "builduid": "481232e75ba1437086db34585c78d66c", 02:48:33 INFO - "revision": "f2a99170e30af7fd4f395ca9a930c46b7acdbe48" 02:48:33 INFO - }, 02:48:33 INFO - "sourcestamp": { 02:48:33 INFO - "repository": "", 02:48:33 INFO - "hasPatch": false, 02:48:33 INFO - "project": "", 02:48:33 INFO - "branch": "ash-win32-pgo-talos", 02:48:33 INFO - "changes": [ 02:48:33 INFO - { 02:48:33 INFO - "category": null, 02:48:33 INFO - "files": [ 02:48:33 INFO - { 02:48:33 INFO - "url": null, 02:48:33 INFO - "name": "https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 02:48:33 INFO - } 02:48:33 INFO - ], 02:48:33 INFO - "repository": "", 02:48:33 INFO - "rev": "f2a99170e30af7fd4f395ca9a930c46b7acdbe48", 02:48:33 INFO - "who": "sendchange", 02:48:33 INFO - "when": 1445537038, 02:48:33 INFO - "number": 6561111, 02:48:33 INFO - "comments": "", 02:48:33 INFO - "project": "", 02:48:33 INFO - "at": "Thu 22 Oct 2015 11:03:58", 02:48:33 INFO - "branch": "ash-win32-pgo-talos", 02:48:33 INFO - "revlink": "", 02:48:33 INFO - "properties": [ 02:48:33 INFO - [ 02:48:33 INFO - "buildid", 02:48:33 INFO - "20151022040420", 02:48:33 INFO - "Change" 02:48:33 INFO - ], 02:48:33 INFO - [ 02:48:33 INFO - "builduid", 02:48:33 INFO - "481232e75ba1437086db34585c78d66c", 02:48:33 INFO - "Change" 02:48:33 INFO - ], 02:48:33 INFO - [ 02:48:33 INFO - "nightly_build", 02:48:33 INFO - "True", 02:48:33 INFO - "Change" 02:48:33 INFO - ], 02:48:33 INFO - [ 02:48:33 INFO - "pgo_build", 02:48:33 INFO - "True", 02:48:33 INFO - "Change" 02:48:33 INFO - ] 02:48:33 INFO - ], 02:48:33 INFO - "revision": "f2a99170e30af7fd4f395ca9a930c46b7acdbe48" 02:48:33 INFO - } 02:48:33 INFO - ], 02:48:33 INFO - "revision": "f2a99170e30af7fd4f395ca9a930c46b7acdbe48" 02:48:33 INFO - } 02:48:33 INFO - } 02:48:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 02:48:33 INFO - Running post-action listener: _resource_record_post_action 02:48:33 INFO - ##### 02:48:33 INFO - ##### Running download-and-extract step. 02:48:33 INFO - ##### 02:48:33 INFO - Running pre-action listener: _resource_record_pre_action 02:48:33 INFO - Running main action method: download_and_extract 02:48:33 INFO - mkdir: C:\slave\test-pgo\build\tests 02:48:33 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')]} 02:48:33 INFO - https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 02:48:33 INFO - trying https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/test_packages.json 02:48:33 INFO - Downloading https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 02:48:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 02:48:34 INFO - Downloaded 1221 bytes. 02:48:34 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 02:48:34 INFO - Using the following test package requirements: 02:48:34 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 02:48:34 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:48:34 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 02:48:34 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:48:34 INFO - u'jsshell-win32.zip'], 02:48:34 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:48:34 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 02:48:34 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 02:48:34 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:48:34 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 02:48:34 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:48:34 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 02:48:34 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:48:34 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 02:48:34 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 02:48:34 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:48:34 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 02:48:34 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 02:48:34 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')]} 02:48:34 INFO - https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 02:48:34 INFO - trying https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 02:48:34 INFO - Downloading https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/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 02:48:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/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 02:48:35 INFO - Downloaded 18002623 bytes. 02:48:35 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 02:48:35 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 02:48:35 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 02:49:08 INFO - Return code: 0 02:49:08 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 02:49:08 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')]} 02:49:08 INFO - https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 02:49:08 INFO - trying https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 02:49:08 INFO - Downloading https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/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 02:49:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/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 02:49:10 INFO - Downloaded 18002623 bytes. 02:49:10 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 02:49:10 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 02:49:10 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 02:49:35 INFO - Return code: 0 02:49: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')]} 02:49:35 INFO - https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 02:49:35 INFO - trying https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 02:49:35 INFO - Downloading https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/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 02:49:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/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 02:49:36 INFO - Downloaded 11128541 bytes. 02:49:36 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 02:49:36 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip 02:49:36 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 02:49:38 INFO - Return code: 0 02:49:38 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')]} 02:49:38 INFO - https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 02:49:38 INFO - trying https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 02:49:38 INFO - Downloading https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip 02:49:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/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 02:49:41 INFO - Downloaded 53263276 bytes. 02:49:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 02:49:41 INFO - mkdir: C:\slave\test-pgo\properties 02:49:41 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 02:49:41 INFO - Writing to file C:\slave\test-pgo\properties\build_url 02:49:41 INFO - Contents: 02:49:41 INFO - build_url:https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 02:49:41 INFO - Running post-action listener: _resource_record_post_action 02:49:41 INFO - Running post-action listener: set_extra_try_arguments 02:49:41 INFO - ##### 02:49:41 INFO - ##### Running populate-webroot step. 02:49:41 INFO - ##### 02:49:41 INFO - Running pre-action listener: _resource_record_pre_action 02:49:41 INFO - Running main action method: populate_webroot 02:49:41 INFO - Guessing talos json url... 02:49:41 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')]} 02:49:41 INFO - https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 02:49:41 INFO - trying https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 02:49:41 INFO - Downloading https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 02:49:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/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 02:49:42 INFO - Downloaded 96 bytes. 02:49:42 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 02:49:42 INFO - Contents: 02:49:42 INFO - 20151022040420 02:49:42 INFO - https://hg.mozilla.org/projects/ash/rev/f2a99170e30af7fd4f395ca9a930c46b7acdbe48 02:49:42 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')]} 02:49:42 INFO - trying https://hg.mozilla.org/projects/ash/raw-file/f2a99170e3/testing/talos/talos.json 02:49:42 INFO - Downloading https://hg.mozilla.org/projects/ash/raw-file/f2a99170e3/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json 02:49:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/projects/ash/raw-file/f2a99170e3/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1 02:49:43 INFO - Downloaded 4264 bytes. 02:49:43 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 02:49:43 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 02:49:43 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 02:49:43 INFO - u'../fennec_ids.txt'], 02:49:43 INFO - u'tests': [u'tcheck2']}, 02:49:43 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 02:49:43 INFO - u'--tppagecycles', 02:49:43 INFO - u'7'], 02:49:43 INFO - u'tests': [u'tsvgm']}}, 02:49:43 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 02:49:43 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 02:49:43 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 02:49:43 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 02:49:43 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 02:49:43 INFO - u'tests': [u'dromaeo_css', 02:49:43 INFO - u'kraken', 02:49:43 INFO - u'v8_7']}, 02:49:43 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:43 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 02:49:43 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:43 INFO - u'talos_options': [u'--e10s'], 02:49:43 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 02:49:43 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:43 INFO - u'tests': [u'damp', u'tps']}, 02:49:43 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:43 INFO - u'talos_options': [u'--e10s'], 02:49:43 INFO - u'tests': [u'damp', u'tps']}, 02:49:43 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 02:49:43 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 02:49:43 INFO - u'other': {u'tests': [u'a11yr', 02:49:43 INFO - u'ts_paint', 02:49:43 INFO - u'tpaint', 02:49:43 INFO - u'sessionrestore', 02:49:43 INFO - u'sessionrestore_no_auto_restore']}, 02:49:43 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 02:49:43 INFO - u'tests': [u'a11yr', 02:49:43 INFO - u'ts_paint', 02:49:43 INFO - u'tpaint', 02:49:43 INFO - u'sessionrestore', 02:49:43 INFO - u'sessionrestore_no_auto_restore']}, 02:49:43 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 02:49:43 INFO - u'tests': [u'a11yr', 02:49:43 INFO - u'ts_paint', 02:49:43 INFO - u'tpaint', 02:49:43 INFO - u'sessionrestore', 02:49:43 INFO - u'sessionrestore_no_auto_restore']}, 02:49:43 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 02:49:43 INFO - u'tests': [u'a11yr', 02:49:43 INFO - u'ts_paint', 02:49:43 INFO - u'tpaint', 02:49:43 INFO - u'sessionrestore', 02:49:43 INFO - u'sessionrestore_no_auto_restore']}, 02:49:43 INFO - u'other_l64': {u'tests': [u'a11yr', 02:49:43 INFO - u'ts_paint', 02:49:43 INFO - u'tpaint', 02:49:43 INFO - u'sessionrestore', 02:49:43 INFO - u'sessionrestore_no_auto_restore']}, 02:49:43 INFO - u'other_nol64': {u'tests': [u'a11yr', 02:49:43 INFO - u'ts_paint', 02:49:43 INFO - u'tpaint', 02:49:43 INFO - u'sessionrestore', 02:49:43 INFO - u'sessionrestore_no_auto_restore']}, 02:49:43 INFO - u'svgr': {u'tests': [u'tsvgx', 02:49:43 INFO - u'tsvgr_opacity', 02:49:43 INFO - u'tart', 02:49:43 INFO - u'tscrollx', 02:49:43 INFO - u'cart']}, 02:49:43 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 02:49:43 INFO - u'tests': [u'tsvgx', 02:49:43 INFO - u'tsvgr_opacity', 02:49:43 INFO - u'tart', 02:49:43 INFO - u'tscrollx', 02:49:43 INFO - u'cart']}, 02:49:43 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:43 INFO - u'tests': [u'tp5o']}, 02:49:43 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:43 INFO - u'talos_options': [u'--e10s'], 02:49:43 INFO - u'tests': [u'tp5o']}, 02:49:43 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:43 INFO - u'talos_options': [u'--xperf_path', 02:49:43 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:49:43 INFO - u'tests': [u'tp5n']}, 02:49:43 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:43 INFO - u'talos_options': [u'--e10s', 02:49:43 INFO - u'--xperf_path', 02:49:43 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 02:49:43 INFO - u'C:/slave/talos-data/talos/xperf.config'], 02:49:43 INFO - u'tests': [u'tp5n']}}, 02:49:43 INFO - u'talos.zip': {u'path': u'', 02:49:43 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:49:43 INFO - Running post-action listener: _resource_record_post_action 02:49:43 INFO - ##### 02:49:43 INFO - ##### Running create-virtualenv step. 02:49:43 INFO - ##### 02:49:43 INFO - Running pre-action listener: _resource_record_pre_action 02:49:43 INFO - Running main action method: create_virtualenv 02:49:43 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 02:49:43 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 02:49:43 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 02:49:43 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'! 02:49:43 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 02:49:43 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 02:49:54 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 02:50:01 INFO - Installing distribute......................................................................................................................................................................................done. 02:50:03 INFO - Return code: 0 02:50:03 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 02:50:03 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')]} 02:50:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:03 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')]} 02:50:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:03 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 0x018D9B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0189CEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137C288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, '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 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01959660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-032', 'USERDOMAIN': 'T-W732-IX-032', '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-032', '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 02:50:03 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 02:50:03 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 02:50:03 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:50:03 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:50:03 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:50:03 INFO - 'COMPUTERNAME': 'T-W732-IX-032', 02:50:03 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:50:03 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:50:03 INFO - 'DCLOCATION': 'SCL3', 02:50:03 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:50:03 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:50:03 INFO - 'HOMEDRIVE': 'C:', 02:50:03 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:50:03 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:50:03 INFO - 'KTS_VERSION': '1.19c', 02:50:03 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:50:03 INFO - 'LOGONSERVER': '\\\\T-W732-IX-032', 02:50:03 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:50:03 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:50:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:03 INFO - 'MOZ_NO_REMOTE': '1', 02:50:03 INFO - 'NO_EM_RESTART': '1', 02:50:03 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:50:03 INFO - 'OS': 'Windows_NT', 02:50:03 INFO - 'OURDRIVE': 'C:', 02:50:03 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;', 02:50:03 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:50:03 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:50:03 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:50:03 INFO - 'PROCESSOR_LEVEL': '6', 02:50:03 INFO - 'PROCESSOR_REVISION': '1e05', 02:50:03 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:50:03 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:50:03 INFO - 'PROMPT': '$P$G', 02:50:03 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 02:50:03 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:50:03 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:50:03 INFO - 'PWD': 'C:\\slave\\test-pgo', 02:50:03 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:50:03 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:50:03 INFO - 'SYSTEMDRIVE': 'C:', 02:50:03 INFO - 'SYSTEMROOT': 'C:\\windows', 02:50:03 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:50:03 INFO - 'TEST1': 'testie', 02:50:03 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:50:03 INFO - 'USERDOMAIN': 'T-W732-IX-032', 02:50:03 INFO - 'USERNAME': 'cltbld', 02:50:03 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:50:03 INFO - 'WINDIR': 'C:\\windows', 02:50:03 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:50:03 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:50:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:50:05 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:50:05 INFO - Downloading/unpacking pip>=1.5 02:50:05 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache 02:50:11 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 02:50:11 INFO - Running setup.py egg_info for package pip 02:50:11 INFO - warning: no files found matching 'pip\cacert.pem' 02:50:11 INFO - warning: no files found matching '*.html' under directory 'docs' 02:50:11 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 02:50:11 INFO - no previously-included directories found matching 'docs\_build\_sources' 02:50:11 INFO - Installing collected packages: pip 02:50:11 INFO - Found existing installation: pip 0.8.2 02:50:11 INFO - Uninstalling pip: 02:50:11 INFO - Successfully uninstalled pip 02:50:11 INFO - Running setup.py install for pip 02:50:11 INFO - warning: no files found matching 'pip\cacert.pem' 02:50:11 INFO - warning: no files found matching '*.html' under directory 'docs' 02:50:11 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 02:50:11 INFO - no previously-included directories found matching 'docs\_build\_sources' 02:50:11 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:11 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:11 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:11 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:11 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:11 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:11 INFO - Successfully installed pip 02:50:11 INFO - Cleaning up... 02:50:11 INFO - Return code: 0 02:50:11 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 02:50:11 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')]} 02:50:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:11 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')]} 02:50:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:11 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 0x018D9B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0189CEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137C288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, '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 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01959660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-032', 'USERDOMAIN': 'T-W732-IX-032', '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-032', '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 02:50:11 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 02:50:11 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 02:50:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:50:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:50:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:50:11 INFO - 'COMPUTERNAME': 'T-W732-IX-032', 02:50:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:50:11 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:50:11 INFO - 'DCLOCATION': 'SCL3', 02:50:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:50:11 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:50:11 INFO - 'HOMEDRIVE': 'C:', 02:50:11 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:50:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:50:11 INFO - 'KTS_VERSION': '1.19c', 02:50:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:50:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-032', 02:50:11 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:50:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:50:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:11 INFO - 'MOZ_NO_REMOTE': '1', 02:50:11 INFO - 'NO_EM_RESTART': '1', 02:50:11 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:50:11 INFO - 'OS': 'Windows_NT', 02:50:11 INFO - 'OURDRIVE': 'C:', 02:50:11 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;', 02:50:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:50:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:50:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:50:11 INFO - 'PROCESSOR_LEVEL': '6', 02:50:11 INFO - 'PROCESSOR_REVISION': '1e05', 02:50:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:50:11 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:50:11 INFO - 'PROMPT': '$P$G', 02:50:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 02:50:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:50:11 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:50:11 INFO - 'PWD': 'C:\\slave\\test-pgo', 02:50:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:50:11 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:50:11 INFO - 'SYSTEMDRIVE': 'C:', 02:50:11 INFO - 'SYSTEMROOT': 'C:\\windows', 02:50:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:50:11 INFO - 'TEST1': 'testie', 02:50:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:50:11 INFO - 'USERDOMAIN': 'T-W732-IX-032', 02:50:11 INFO - 'USERNAME': 'cltbld', 02:50:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:50:11 INFO - 'WINDIR': 'C:\\windows', 02:50:11 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:50:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:50:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:50:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:14 INFO - Downloading/unpacking psutil>=0.7.1 02:50:14 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 02:50:14 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 02:50:15 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 02:50:15 INFO - Installing collected packages: psutil 02:50:15 INFO - Successfully installed psutil 02:50:15 INFO - Cleaning up... 02:50:15 INFO - Return code: 0 02:50:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 02:50:15 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')]} 02:50:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:15 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')]} 02:50:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:15 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 0x018D9B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0189CEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137C288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, '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 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01959660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-032', 'USERDOMAIN': 'T-W732-IX-032', '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-032', '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 02:50:15 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 02:50:15 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 02:50:15 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:50:15 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:50:15 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:50:15 INFO - 'COMPUTERNAME': 'T-W732-IX-032', 02:50:15 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:50:15 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:50:15 INFO - 'DCLOCATION': 'SCL3', 02:50:15 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:50:15 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:50:15 INFO - 'HOMEDRIVE': 'C:', 02:50:15 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:50:15 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:50:15 INFO - 'KTS_VERSION': '1.19c', 02:50:15 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:50:15 INFO - 'LOGONSERVER': '\\\\T-W732-IX-032', 02:50:15 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:50:15 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:50:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:15 INFO - 'MOZ_NO_REMOTE': '1', 02:50:15 INFO - 'NO_EM_RESTART': '1', 02:50:15 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:50:15 INFO - 'OS': 'Windows_NT', 02:50:15 INFO - 'OURDRIVE': 'C:', 02:50:15 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;', 02:50:15 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:50:15 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:50:15 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:50:15 INFO - 'PROCESSOR_LEVEL': '6', 02:50:15 INFO - 'PROCESSOR_REVISION': '1e05', 02:50:15 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:50:15 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:50:15 INFO - 'PROMPT': '$P$G', 02:50:15 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 02:50:15 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:50:15 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:50:15 INFO - 'PWD': 'C:\\slave\\test-pgo', 02:50:15 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:50:15 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:50:15 INFO - 'SYSTEMDRIVE': 'C:', 02:50:15 INFO - 'SYSTEMROOT': 'C:\\windows', 02:50:15 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:50:15 INFO - 'TEST1': 'testie', 02:50:15 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:50:15 INFO - 'USERDOMAIN': 'T-W732-IX-032', 02:50:15 INFO - 'USERNAME': 'cltbld', 02:50:15 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:50:15 INFO - 'WINDIR': 'C:\\windows', 02:50:15 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:50:15 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:50:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:50:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:16 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:50:16 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 02:50:16 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 02:50:17 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:50:17 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 02:50:17 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 02:50:17 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) 02:50:17 INFO - Installing collected packages: mozsystemmonitor 02:50:17 INFO - Running setup.py install for mozsystemmonitor 02:50:17 INFO - Successfully installed mozsystemmonitor 02:50:17 INFO - Cleaning up... 02:50:17 INFO - Return code: 0 02:50:17 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 02:50:17 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')]} 02:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:17 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')]} 02:50:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:17 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 0x018D9B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0189CEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137C288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, '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 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01959660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-032', 'USERDOMAIN': 'T-W732-IX-032', '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-032', '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 02:50:17 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 02:50:17 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 02:50:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:50:17 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:50:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:50:17 INFO - 'COMPUTERNAME': 'T-W732-IX-032', 02:50:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:50:17 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:50:17 INFO - 'DCLOCATION': 'SCL3', 02:50:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:50:17 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:50:17 INFO - 'HOMEDRIVE': 'C:', 02:50:17 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:50:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:50:17 INFO - 'KTS_VERSION': '1.19c', 02:50:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:50:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-032', 02:50:17 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:50:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:50:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:17 INFO - 'MOZ_NO_REMOTE': '1', 02:50:17 INFO - 'NO_EM_RESTART': '1', 02:50:17 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:50:17 INFO - 'OS': 'Windows_NT', 02:50:17 INFO - 'OURDRIVE': 'C:', 02:50:17 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;', 02:50:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:50:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:50:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:50:17 INFO - 'PROCESSOR_LEVEL': '6', 02:50:17 INFO - 'PROCESSOR_REVISION': '1e05', 02:50:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:50:17 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:50:17 INFO - 'PROMPT': '$P$G', 02:50:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 02:50:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:50:17 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:50:17 INFO - 'PWD': 'C:\\slave\\test-pgo', 02:50:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:50:17 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:50:17 INFO - 'SYSTEMDRIVE': 'C:', 02:50:17 INFO - 'SYSTEMROOT': 'C:\\windows', 02:50:17 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:50:17 INFO - 'TEST1': 'testie', 02:50:17 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:50:17 INFO - 'USERDOMAIN': 'T-W732-IX-032', 02:50:17 INFO - 'USERNAME': 'cltbld', 02:50:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:50:17 INFO - 'WINDIR': 'C:\\windows', 02:50:17 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:50:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:50:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:50:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:17 INFO - Downloading/unpacking blobuploader==1.2.4 02:50:17 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 02:50:17 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 02:50:18 INFO - Downloading blobuploader-1.2.4.tar.gz 02:50:18 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 02:50:18 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 02:50:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:50:18 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 02:50:18 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 02:50:19 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 02:50:19 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 02:50:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:50: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 02:50: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 02:50:19 INFO - Downloading docopt-0.6.1.tar.gz 02:50:19 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 02:50:19 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 02:50:19 INFO - Installing collected packages: blobuploader, requests, docopt 02:50:19 INFO - Running setup.py install for blobuploader 02:50:19 INFO - Running setup.py install for requests 02:50:20 INFO - Running setup.py install for docopt 02:50:21 INFO - Successfully installed blobuploader requests docopt 02:50:21 INFO - Cleaning up... 02:50:22 INFO - Return code: 0 02:50:22 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 02:50:22 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')]} 02:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:22 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')]} 02:50:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:22 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 0x018D9B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0189CEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137C288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, '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 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01959660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-032', 'USERDOMAIN': 'T-W732-IX-032', '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-032', '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 02:50:22 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 02:50:22 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 02:50:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:50:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:50:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:50:22 INFO - 'COMPUTERNAME': 'T-W732-IX-032', 02:50:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:50:22 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:50:22 INFO - 'DCLOCATION': 'SCL3', 02:50:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:50:22 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:50:22 INFO - 'HOMEDRIVE': 'C:', 02:50:22 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:50:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:50:22 INFO - 'KTS_VERSION': '1.19c', 02:50:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:50:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-032', 02:50:22 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:50:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:50:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:22 INFO - 'MOZ_NO_REMOTE': '1', 02:50:22 INFO - 'NO_EM_RESTART': '1', 02:50:22 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:50:22 INFO - 'OS': 'Windows_NT', 02:50:22 INFO - 'OURDRIVE': 'C:', 02:50:22 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;', 02:50:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:50:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:50:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:50:22 INFO - 'PROCESSOR_LEVEL': '6', 02:50:22 INFO - 'PROCESSOR_REVISION': '1e05', 02:50:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:50:22 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:50:22 INFO - 'PROMPT': '$P$G', 02:50:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 02:50:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:50:22 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:50:22 INFO - 'PWD': 'C:\\slave\\test-pgo', 02:50:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:50:22 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:50:22 INFO - 'SYSTEMDRIVE': 'C:', 02:50:22 INFO - 'SYSTEMROOT': 'C:\\windows', 02:50:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:50:22 INFO - 'TEST1': 'testie', 02:50:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:50:22 INFO - 'USERDOMAIN': 'T-W732-IX-032', 02:50:22 INFO - 'USERNAME': 'cltbld', 02:50:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:50:22 INFO - 'WINDIR': 'C:\\windows', 02:50:22 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:50:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:50:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:50:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:22 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 02:50:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-psxwey-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 02:50:23 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 02:50:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gc8izn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 02:50:23 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 02:50:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hucn84-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 02:50:23 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 02:50:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2kxyr2-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 02:50:23 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 02:50:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8h_opo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 02:50:23 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 02:50:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ys3uyb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 02:50:23 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 02:50:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jmrklm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 02:50:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 02:50:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fwllav-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 02:50:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 02:50:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ogorux-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 02:50:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 02:50:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zsvkc_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 02:50:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 02:50:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bldix7-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 02:50:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 02:50:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rdfhes-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 02:50:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 02:50:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ghha14-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 02:50:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 02:50:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wu2wdn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 02:50:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 02:50:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-twl89n-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 02:50:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 02:50:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-exkczz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 02:50:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 02:50:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ea5m6g-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 02:50:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:50:25 INFO - Running setup.py install for manifestparser 02:50:25 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:25 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:26 INFO - Running setup.py install for mozcrash 02:50:26 INFO - Running setup.py install for mozdebug 02:50:26 INFO - Running setup.py install for mozdevice 02:50:26 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:26 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:26 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:26 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:27 INFO - Running setup.py install for mozfile 02:50:27 INFO - Running setup.py install for mozhttpd 02:50:27 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:27 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:28 INFO - Running setup.py install for mozinfo 02:50:28 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:28 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:28 INFO - Running setup.py install for mozInstall 02:50:28 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:28 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:28 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:28 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:28 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:28 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:28 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:28 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:28 INFO - Running setup.py install for mozleak 02:50:29 INFO - Running setup.py install for mozlog 02:50:29 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:29 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:29 INFO - Running setup.py install for moznetwork 02:50:30 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:30 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:30 INFO - Running setup.py install for mozprocess 02:50:30 INFO - Running setup.py install for mozprofile 02:50:31 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:31 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:31 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:31 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:31 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:31 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:31 INFO - Running setup.py install for mozrunner 02:50:31 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:31 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:32 INFO - Running setup.py install for mozscreenshot 02:50:32 INFO - Running setup.py install for moztest 02:50:33 INFO - Running setup.py install for mozversion 02:50:33 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:50:33 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 02:50:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:50:33 INFO - Cleaning up... 02:50:33 INFO - Return code: 0 02:50:33 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 02:50:33 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')]} 02:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:33 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')]} 02:50:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:33 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 0x018D9B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0189CEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137C288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, '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 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01959660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-032', 'USERDOMAIN': 'T-W732-IX-032', '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-032', '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 02:50:33 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 02:50:33 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 02:50:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:50:33 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:50:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:50:33 INFO - 'COMPUTERNAME': 'T-W732-IX-032', 02:50:33 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:50:33 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:50:33 INFO - 'DCLOCATION': 'SCL3', 02:50:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:50:33 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:50:33 INFO - 'HOMEDRIVE': 'C:', 02:50:33 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:50:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:50:33 INFO - 'KTS_VERSION': '1.19c', 02:50:33 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:50:33 INFO - 'LOGONSERVER': '\\\\T-W732-IX-032', 02:50:33 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:50:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:50:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:33 INFO - 'MOZ_NO_REMOTE': '1', 02:50:33 INFO - 'NO_EM_RESTART': '1', 02:50:33 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:50:33 INFO - 'OS': 'Windows_NT', 02:50:33 INFO - 'OURDRIVE': 'C:', 02:50:33 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;', 02:50:33 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:50:33 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:50:33 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:50:33 INFO - 'PROCESSOR_LEVEL': '6', 02:50:33 INFO - 'PROCESSOR_REVISION': '1e05', 02:50:33 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:50:33 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:50:33 INFO - 'PROMPT': '$P$G', 02:50:33 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 02:50:33 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:50:33 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:50:33 INFO - 'PWD': 'C:\\slave\\test-pgo', 02:50:33 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:50:33 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:50:33 INFO - 'SYSTEMDRIVE': 'C:', 02:50:33 INFO - 'SYSTEMROOT': 'C:\\windows', 02:50:33 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:50:33 INFO - 'TEST1': 'testie', 02:50:33 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:50:33 INFO - 'USERDOMAIN': 'T-W732-IX-032', 02:50:33 INFO - 'USERNAME': 'cltbld', 02:50:33 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:50:33 INFO - 'WINDIR': 'C:\\windows', 02:50:33 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:50:33 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:50:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:50:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:34 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 02:50:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bohysm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 02:50:34 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)) 02:50:34 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 02:50:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eynghr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 02:50:34 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)) 02:50:34 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 02:50:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d4t46i-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 02:50:34 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)) 02:50:34 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 02:50:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8pazxd-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 02:50:34 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)) 02:50:34 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 02:50:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-npbm3a-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 02:50:34 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)) 02:50:34 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 02:50:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yoesed-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 02:50:34 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)) 02:50:34 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 02:50:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-huvpw4-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 02:50:35 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)) 02:50:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 02:50:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kv_wro-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 02:50:35 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)) 02:50:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 02:50:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zmqvzt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 02:50:35 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)) 02:50:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 02:50:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u1osig-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 02:50:35 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)) 02:50:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 02:50:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cek3jt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 02:50:35 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)) 02:50:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 02:50:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lojfnn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 02:50:35 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)) 02:50:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 02:50:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wrrofh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 02:50:35 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)) 02:50:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 02:50:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v9endn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 02:50:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 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)) 02:50:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 02:50:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-blxla_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 02:50:36 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)) 02:50:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 02:50:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yz9glv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 02:50:36 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)) 02:50:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 02:50:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7xjyyx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 02:50:36 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)) 02:50:36 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)) 02:50:36 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)) 02:50:36 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)) 02:50:36 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)) 02:50:36 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)) 02:50:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 02:50:36 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 02:50:36 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 02:50:37 INFO - Downloading blessings-1.5.1.tar.gz 02:50:37 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 02:50:37 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 02:50:37 INFO - Installing collected packages: blessings 02:50:37 INFO - Running setup.py install for blessings 02:50:37 INFO - Successfully installed blessings 02:50:37 INFO - Cleaning up... 02:50:40 INFO - Return code: 0 02:50:40 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 02:50:40 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 02:50:40 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 02:50:41 INFO - Reading from file tmpfile_stdout 02:50:41 INFO - Using _rmtree_windows ... 02:50:41 INFO - Using _rmtree_windows ... 02:50:41 INFO - Current package versions: 02:50:41 INFO - blessings == 1.5.1 02:50:41 INFO - blobuploader == 1.2.4 02:50:41 INFO - docopt == 0.6.1 02:50:41 INFO - manifestparser == 1.1 02:50:41 INFO - mozInstall == 1.12 02:50:41 INFO - mozcrash == 0.16 02:50:41 INFO - mozdebug == 0.1 02:50:41 INFO - mozdevice == 0.46 02:50:41 INFO - mozfile == 1.2 02:50:41 INFO - mozhttpd == 0.7 02:50:41 INFO - mozinfo == 0.8 02:50:41 INFO - mozleak == 0.1 02:50:41 INFO - mozlog == 3.0 02:50:41 INFO - moznetwork == 0.27 02:50:41 INFO - mozprocess == 0.22 02:50:41 INFO - mozprofile == 0.27 02:50:41 INFO - mozrunner == 6.10 02:50:41 INFO - mozscreenshot == 0.1 02:50:41 INFO - mozsystemmonitor == 0.0 02:50:41 INFO - moztest == 0.7 02:50:41 INFO - mozversion == 1.4 02:50:41 INFO - psutil == 3.1.1 02:50:41 INFO - requests == 1.2.3 02:50:41 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 02:50:41 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')]} 02:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:41 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')]} 02:50:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:41 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 0x018D9B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0189CEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137C288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, '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 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01959660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-032', 'USERDOMAIN': 'T-W732-IX-032', '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-032', '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 02:50:41 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 02:50:41 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 02:50:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:50:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:50:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:50:41 INFO - 'COMPUTERNAME': 'T-W732-IX-032', 02:50:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:50:41 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:50:41 INFO - 'DCLOCATION': 'SCL3', 02:50:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:50:41 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:50:41 INFO - 'HOMEDRIVE': 'C:', 02:50:41 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:50:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:50:41 INFO - 'KTS_VERSION': '1.19c', 02:50:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:50:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-032', 02:50:41 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:50:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:50:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:41 INFO - 'MOZ_NO_REMOTE': '1', 02:50:41 INFO - 'NO_EM_RESTART': '1', 02:50:41 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:50:41 INFO - 'OS': 'Windows_NT', 02:50:41 INFO - 'OURDRIVE': 'C:', 02:50:41 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;', 02:50:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:50:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:50:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:50:41 INFO - 'PROCESSOR_LEVEL': '6', 02:50:41 INFO - 'PROCESSOR_REVISION': '1e05', 02:50:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:50:41 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:50:41 INFO - 'PROMPT': '$P$G', 02:50:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 02:50:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:50:41 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:50:41 INFO - 'PWD': 'C:\\slave\\test-pgo', 02:50:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:50:41 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:50:41 INFO - 'SYSTEMDRIVE': 'C:', 02:50:41 INFO - 'SYSTEMROOT': 'C:\\windows', 02:50:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:50:41 INFO - 'TEST1': 'testie', 02:50:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:50:41 INFO - 'USERDOMAIN': 'T-W732-IX-032', 02:50:41 INFO - 'USERNAME': 'cltbld', 02:50:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:50:41 INFO - 'WINDIR': 'C:\\windows', 02:50:41 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:50:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:50:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:50:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:41 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)) 02:50:41 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)) 02:50:41 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)) 02:50:41 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)) 02:50:41 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)) 02:50:41 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)) 02:50:41 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)) 02:50:41 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)) 02:50:41 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)) 02:50:41 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)) 02:50:41 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)) 02:50:41 INFO - Cleaning up... 02:50:41 INFO - Return code: 0 02:50:41 INFO - Running post-action listener: _resource_record_post_action 02:50:41 INFO - Running post-action listener: _start_resource_monitoring 02:50:41 INFO - Starting resource monitoring. 02:50:41 INFO - ##### 02:50:41 INFO - ##### Running install step. 02:50:41 INFO - ##### 02:50:41 INFO - Running pre-action listener: _resource_record_pre_action 02:50:41 INFO - Running main action method: install 02:50:41 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 02:50:41 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 02:50:42 INFO - Reading from file tmpfile_stdout 02:50:42 INFO - Using _rmtree_windows ... 02:50:42 INFO - Using _rmtree_windows ... 02:50:42 INFO - Detecting whether we're running mozinstall >=1.0... 02:50:42 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 02:50:42 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 02:50:42 INFO - Reading from file tmpfile_stdout 02:50:42 INFO - Output received: 02:50:42 INFO - Usage: mozinstall-script.py [options] installer 02:50:42 INFO - Options: 02:50:42 INFO - -h, --help show this help message and exit 02:50:42 INFO - -d DEST, --destination=DEST 02:50:42 INFO - Directory to install application into. [default: 02:50:42 INFO - "C:\slave\test-pgo"] 02:50:42 INFO - --app=APP Application being installed. [default: firefox] 02:50:42 INFO - Using _rmtree_windows ... 02:50:42 INFO - Using _rmtree_windows ... 02:50:42 INFO - mkdir: C:\slave\test-pgo\build\application 02:50:42 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'] 02:50:42 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 02:50:44 INFO - Reading from file tmpfile_stdout 02:50:44 INFO - Output received: 02:50:44 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 02:50:44 INFO - Using _rmtree_windows ... 02:50:44 INFO - Using _rmtree_windows ... 02:50:44 INFO - Running post-action listener: _resource_record_post_action 02:50:44 INFO - ##### 02:50:44 INFO - ##### Running run-tests step. 02:50:44 INFO - ##### 02:50:44 INFO - Running pre-action listener: _resource_record_pre_action 02:50:44 INFO - Running main action method: run_tests 02:50:44 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 02:50:44 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 02:50:44 INFO - Python 2.7.3 02:50:44 INFO - Return code: 0 02:50:44 INFO - grabbing minidump binary from tooltool 02:50:44 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')]} 02:50:44 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 0x01298B48>, '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 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01959660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 02:50:44 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 02:50:44 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 02:50:44 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 02:50:46 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmphu1lht 02:50:46 INFO - INFO - File integrity verified, renaming tmphu1lht to win32-minidump_stackwalk.exe 02:50:46 INFO - Return code: 0 02:50:46 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 02:50:46 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 02:50:46 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 02:50:46 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 02:50:46 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 02:50:46 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Ash', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-032', '--webServer', 'localhost'] in C:\slave\test-pgo\build 02:50:46 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName Ash --suite dromaeojs --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-032 --webServer localhost 02:50:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:50:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:50:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:50:46 INFO - 'COMPUTERNAME': 'T-W732-IX-032', 02:50:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:50:46 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:50:46 INFO - 'DCLOCATION': 'SCL3', 02:50:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:50:46 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:50:46 INFO - 'HOMEDRIVE': 'C:', 02:50:46 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:50:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:50:46 INFO - 'KTS_VERSION': '1.19c', 02:50:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:50:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-032', 02:50:46 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 02:50:46 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 02:50:46 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:50:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:50:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:46 INFO - 'MOZ_NO_REMOTE': '1', 02:50:46 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 02:50:46 INFO - 'NO_EM_RESTART': '1', 02:50:46 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:50:46 INFO - 'OS': 'Windows_NT', 02:50:46 INFO - 'OURDRIVE': 'C:', 02:50:46 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;', 02:50:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:50:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:50:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:50:46 INFO - 'PROCESSOR_LEVEL': '6', 02:50:46 INFO - 'PROCESSOR_REVISION': '1e05', 02:50:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:50:46 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:50:46 INFO - 'PROMPT': '$P$G', 02:50:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 02:50:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:50:46 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:50:46 INFO - 'PWD': 'C:\\slave\\test-pgo', 02:50:46 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 02:50:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:50:46 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:50:46 INFO - 'SYSTEMDRIVE': 'C:', 02:50:46 INFO - 'SYSTEMROOT': 'C:\\windows', 02:50:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:50:46 INFO - 'TEST1': 'testie', 02:50:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:50:46 INFO - 'USERDOMAIN': 'T-W732-IX-032', 02:50:46 INFO - 'USERNAME': 'cltbld', 02:50:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:50:46 INFO - 'WINDIR': 'C:\\windows', 02:50:46 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:50:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:50:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:50:46 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Ash', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-032', '--webServer', 'localhost'] with output_timeout 3600 02:50:47 INFO - mozversion INFO | application_buildid: 20151022040420 02:50:47 INFO - mozversion INFO | application_changeset: f2a99170e30af7fd4f395ca9a930c46b7acdbe48 02:50:47 INFO - mozversion INFO | application_display_name: Nightly 02:50:47 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:50:47 INFO - mozversion INFO | application_name: Firefox 02:50:47 INFO - mozversion INFO | application_remotingname: firefox 02:50:47 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/projects/ash 02:50:47 INFO - mozversion INFO | application_vendor: Mozilla 02:50:47 INFO - mozversion INFO | application_version: 44.0a1 02:50:47 INFO - mozversion INFO | platform_buildid: 20151022040420 02:50:47 INFO - mozversion INFO | platform_changeset: f2a99170e30af7fd4f395ca9a930c46b7acdbe48 02:50:47 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/projects/ash 02:50:47 INFO - mozversion INFO | platform_version: 44.0a1 02:50:47 INFO - 2015-10-23 02:50:47,869 DEBUG : using testdate: 1445593847 02:50:47 INFO - 2015-10-23 02:50:47,869 DEBUG : actual date: 1445593847 02:50:47 INFO - 2015-10-23 02:50:47,878 INFO : starting webserver on 'localhost:49696' 02:50:47 INFO - 2015-10-23 02:50:47,880 INFO : Starting test suite T-W732-IX-032 02:50:47 INFO - 2015-10-23 02:50:47,881 INFO : Starting test dromaeo_css 02:50:47 INFO - 2015-10-23 02:50:47,881 DEBUG : operating with platform_type : w7_ 02:50:47 INFO - 2015-10-23 02:50:47,881 INFO : Initialising browser for dromaeo_css test... 02:50:47 INFO - 2015-10-23 02:50:47,974 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpx7f4hw\profile http://localhost:49696/getInfo.html 02:50:56 INFO - 2015-10-23 02:50:56,417 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:50:56 INFO - 2015-10-23 02:50:56,417 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:50:56 INFO - 2015-10-23 02:50:56,417 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 02:50:56 INFO - 2015-10-23 02:50:56,417 DEBUG : BROWSER_OUTPUT: __metrics 02:50:56 INFO - 2015-10-23 02:50:56,516 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:50:58 INFO - 2015-10-23 02:50:58,168 INFO : Browser initialized. 02:50:58 INFO - 2015-10-23 02:50:58,168 INFO : Running cycle 1/1 for dromaeo_css test... 02:50:58 INFO - 2015-10-23 02:50:58,168 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpx7f4hw\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 02:51:09 INFO - 2015-10-23 02:51:09,539 DEBUG : BROWSER_OUTPUT: RSS: Main: 137576448 02:51:09 INFO - 2015-10-23 02:51:09,539 DEBUG : BROWSER_OUTPUT: 02:52:00 INFO - 2015-10-23 02:52:00,782 DEBUG : BROWSER_OUTPUT: 1445593920805 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:54:11 INFO - 2015-10-23 02:54:11,569 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49696/tests/dromaeo/cssquery-ext.html) 02:54:12 INFO - 2015-10-23 02:54:12,631 DEBUG : BROWSER_OUTPUT: RSS: Main: 207527936 02:54:12 INFO - 2015-10-23 02:54:12,631 DEBUG : BROWSER_OUTPUT: 02:57:15 INFO - 2015-10-23 02:57:15,065 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/dromaeo/cssquery-ext.html (next: http://localhost:49696/tests/dromaeo/cssquery-jquery.html) 02:57:15 INFO - 2015-10-23 02:57:15,621 DEBUG : BROWSER_OUTPUT: RSS: Main: 198610944 02:57:15 INFO - 2015-10-23 02:57:15,621 DEBUG : BROWSER_OUTPUT: 03:00:17 INFO - 2015-10-23 03:00:17,716 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49696/tests/dromaeo/cssquery-mootools.html) 03:00:18 INFO - 2015-10-23 03:00:18,282 DEBUG : BROWSER_OUTPUT: RSS: Main: 161570816 03:00:18 INFO - 2015-10-23 03:00:18,282 DEBUG : BROWSER_OUTPUT: 03:03:20 INFO - 2015-10-23 03:03:20,309 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49696/tests/dromaeo/cssquery-prototype.html) 03:03:20 INFO - 2015-10-23 03:03:20,904 DEBUG : BROWSER_OUTPUT: RSS: Main: 133332992 03:03:20 INFO - 2015-10-23 03:03:20,904 DEBUG : BROWSER_OUTPUT: 03:06:23 INFO - 2015-10-23 03:06:23,499 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49696/tests/dromaeo/cssquery-yui.html) 03:06:24 INFO - 2015-10-23 03:06:24,082 DEBUG : BROWSER_OUTPUT: RSS: Main: 131420160 03:06:24 INFO - 2015-10-23 03:06:24,082 DEBUG : BROWSER_OUTPUT: 03:09:26 INFO - 2015-10-23 03:09:26,569 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/dromaeo/cssquery-yui.html (next: http://localhost:49696/tests/dromaeo/cssquery-dojo.html) 03:09:26 INFO - 2015-10-23 03:09:26,823 DEBUG : BROWSER_OUTPUT: RSS: Main: 190070784 03:09:26 INFO - 2015-10-23 03:09:26,823 DEBUG : BROWSER_OUTPUT: 03:09:26 INFO - 2015-10-23 03:09:26,823 DEBUG : BROWSER_OUTPUT: __start_tp_report 03:09:26 INFO - 2015-10-23 03:09:26,823 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 03:09:26 INFO - 2015-10-23 03:09:26,823 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 03:09:26 INFO - 2015-10-23 03:09:26,823 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 03:09:26 INFO - 2015-10-23 03:09:26,823 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2649.496595646827;2678.1563807400667;2721.496523143218;2792.008836862912;2834.858257087145;5101.234814777785;5178.456262092478;5247.606429517782;5273.1035723926925;5281.6830990140525;5035.32023176871;5125.410577783556;5253.290805741224;5260.368755749316;5361.946380536194;4374.650027997753;4532.592066713995;4589.151007063687;4601.562851529099;4620.607248383883;4416.734995900256;4427.424434823484;4480.52954439784;4536.364908912755;4565.543445655441;4314.417553630266;4343.261645520253;4503.392042074311;4569.223232050559;4586.564276393738;4293.162833247518;4357.346398040288;4380.934285985712;4467.7542735149555;4623.1678297906365;4219.43037689911;4265.936010959838;4381.145676593065;4463.308187230984;4585.816567337302;4827.672390092717;4880.072786170652;4883.804647814083;5146.356705411824;5167.354080739908;5274.235235890813;5396.10964190908;5528.889422211573;5590.440955904418;5620.775168993277;5057.367829021372;5201.375834899836;5279.260903473517;5323.787048518055;5326.041312563737;4784.186688263004;5071.619628527875;5109.8722531936255;5115.641905066647;5124.897502049976;5581.023320918823;5601;5646.971765141148;5717.771289148471;5742.397048309951;5275.57795376369;5285.048691235537;5374.811881584125;5411.540019098342;5468.589855760833;5463.836084917459;5491.341039075336;5512.834614961557;5541.418151094158;5641.12562552805;5451.236826844245;5472.2338872557875;5493.313335833021;5566.721663916788;5579.944200557942;5056.570191533688;5138.434772175057;5226.608004399609;5232.3459567554055;5236.214567814782;3262.1844538865284;3361.9831900840827;3500.1599616092462;3502.404591219499;3503.562054743157;2999.2351950252983;3144.402563512925;3240;3257.413665540178;3273.263515708994;4936.753162341868;5014.95671467342;5104.259882317043;5112.642115051911;5245.134552798745;5259.395169555473;5560.360558535739;5598.300212473441;5605.5515558755205;5700.315962084576;4932.753362331869;4944.826931057396;5020.849374518697;5031.6729412588065;5050.267711181858;4043.8989025274013;4048.7246517346944;4092.6725861930972;4169.499660040754;4184.765494179224;5384.353877534721;5424.783008679688;5766.625169363978;5798.971005144947;5897.9115313271195;5343.732813359395;5353;5495.642783219238;5684.3747187808485;5698.487136157766;5380.219868119179;5451.345838499246;5472.261244731911;5488.957098151339;5529.585281103853;5084.3644544431945;5175.948240517547;5185.196294574347;5200.557952574073;5224.52979231871;5232.241325007776;5257.158854583248;5372.785088596569;5658.745356459051;5689.061304884648;5058.898822023612;5107.361579802525;5169.4830516948;5219.19102539107;5471.206675032018;5083.161278389024;5106.157484015096;5168.276441298296;5227.89544209106;5262.342207224097;4988.650794444499;5076.746162691777;5079.415867175322;5231.424543300158;5303.7082960436;4999.775010124626;5124.410692770379;5141.53726164647;5230.8169214077325;5245.186996015624;5036.546710796046;5143.4085080216255;5184.351956005499;5333.626646134733;5434.755436005468 03:09:26 INFO - 2015-10-23 03:09:26,823 DEBUG : BROWSER_OUTPUT: |1;ext.html;484.8715090501018;496.4117520737925;507.5787096709731;520.8463503266536;524.3524247554268;31340.843295783518;35619.287614247696;35725.46411803822;35923.66496177747;36790.8160459198;37373;38391.232175356476;38392.42411363832;39591.208175836466;40412.19175616493;7687.730929417471;7805.219478052206;7993.720219792323;8066.959665201666;8137.837243255131;9909.702708918743;10037.548310326052;10111.342762720436;10122.038406351385;10145.188384929224;9415.905840941605;9427.575759090858;9441;9671.516424178799;10033.347832390908;7283.817904552376;7427.888581671279;9190.862137067948;9343.766405839908;9829.803403931954;8981.775455613597;9111.27109831212;9634.9518252409;9698.127168554793;9703.07820757027;35106.648933510594;35144.47283290727;35258.47112293317;35265.29469410597;36486.27027459462;3007.383486385286;3070.7082827131385;3079.9230019249476;3130.514770210621;3131.264152924061;28462.146135615963;29528.26179345512;29787.404251915057;30561.23596910073;30773.384532309454;2760.323720688424;2765.198092553157;2792.3158826087533;2821.514797023164;2842.8294302341924;299219.5039024813;299781;299866;300541;311161.88838111557;43517.04173312209;44049.55950440455;50012;50389.74805125951;54001.189982150296;54782;54920.17619735707;55073.88374557671;55098;55119.72440137774;12172.695682608006;13442.93278533601;13588.38852251651;13620.659483512793;13622;10154.898451015395;10223.897761022443;10226;10234.846477302846;10259.692209233735;16683.997920249647;17101.828981710023;17246.482605521855;17304.58733023071;17342.01133739247;7296.233895440902;7490.340928970137;7491.587962661997;7497.700091996368;7547.132079810892;824.3570015388007;876.4084243135858;935.8783358163531;952.2857856607544;983.9471765211156;27223.04719334814;27392.17823465299;27797.444051118866;28322.575161372595;28346.002021016386;940.3887473142512;983.5573991703619;992.389680346596;1051.495282264502;1065.105311538296;14361.425542978373;14444.277786110653;14630.414783408758;14751.557453276419;14754.188519631309;353631;358521;366817;366838;367108;43764.56235437605;44191.33712994308;44217.55782442199;44235;44359.55640443554;1099.312929419113;1167.4629670351565;1174.1898090317654;1180.4392913366082;1181.6809461445362;22122.77877221207;26449.33876653099;27290.45419091647;27947.441051178455;28223.57664635073;36955.260894781415;38758.44966201433;38905.2218955625;39326.41010384789;39459.81620551388;242217;320188;320553;321063;341814;24068.638970415806;24121.75878241195;26235.4752904937;26534.60198097069;27546.449071018065;37326.626733732315;37514.94976877634;37794.62205377911;38387.232255355295;38516;30947.381052378376;32627.673723262462;33050.504242436866;33339.83330083334;33606.83196584099;32296.677033229367;32468.83765581157;32953.34093318072;33066.66933330732;33299.334013319116;30913.227169320046;31411.685883140875;32350.19124521906;34670.30659386849;34806;28858.855705722177;30791.076267712004;31003.689963100984;31570.684293156773;31787.20531986719;23509.294721158392;24308.149214777397;24715.87642061778;24737.257882263562;25010 03:09:26 INFO - 2015-10-23 03:09:26,832 DEBUG : BROWSER_OUTPUT: |2;jquery.html;313.57823727087066;318.2568702080641;320.87646256191357;324.9967500324996;335.60398729499184;12684.936575317133;15476.76784848228;15816.525504234862;16094.275757590907;16128.27422765977;10264.74338141545;10403.531841067152;15744.763828542553;15972.920135399307;16001.599960001005;4070.8778736637946;4155.937660935088;4160.355144952538;4161.79191040449;4201.810918508674;3501.422265326231;3546.45030020346;4141.068259641572;4613.538646135376;4788.97605511972;4379.583939525756;4737.5499327564;4738.3603213566075;4757.048590281957;4760.666753327268;4408.53710360414;4720.103180395714;4723.889885876816;4731.1720448921305;4739.95720941394;3748.981255093707;3751.362268414376;3761.8495260189834;3805.7906815125157;3880.3209438348176;11707.53169873204;12044.337561434118;12232.266064036186;12537.122401431903;12585.685357866036;1841.1383392912323;1868.2208674308479;1889.395393474089;1949.3177387914259;1981.4749091490767;9275.211607013413;9292.953535232347;9361.672341468016;9394.248460123174;9651.758706032335;1527.4051929796788;1533.432629926923;1603.3426295219022;1616.0707593133877;1624.1716724470486;69705.75056011949;71823.64088179579;72307.63846180787;72417;75019;5827.213326200994;5927.525797936155;6033.125196846433;6163.537734669918;6245.906311405332;6704.631245281459;6998.020277161201;7006.194287656883;7076.433885289164;7077.646117694095;4967.850964471072;5061.924071138936;5193.974030129825;5209.47905209476;5244.370675518962;5131;5183.222516622537;5289.68261904287;5391.865203369947;5467.6719396836315;2600.1289567994554;2620.9082682106036;2628.250948479674;2654.9070782522517;2783.930401739932;982.4449186209769;988.4662282367441;1009.7728011197422;1026.296986564206;1032.2052019944751;388.3708392404267;400.24353970994844;401.34380288228374;412.46379706381583;418.23254328307894;10971.451427428756;11066.114710823114;11074.833877491845;11247.943760281147;11818.94090529559;423.2656341247932;424.07763115224265;430.7787422656803;443.9943527909288;453.28381157770644;5833.912491312633;5966.194563733927;6096.542759293071;6305.117283580303;6604.207495100619;448322;450378;451824;452869;455698;66628;67492;69050;75086;79653.60173199097;471.52376100138423;473.3893277671834;491.33424210195386;500.43450900482225;502.0461123864686;9920.503974801375;10368.009909495657;10501;10808.405537695187;10903.727406814576;31356.902508412095;38446.23107537778;39104;41681.79159104185;45167.548324516334;55062.724686376314;55966.440335596126;57471;57687.71156144192;58339.12491312718;5522.2544956430365;5802.187693722798;5802.796902108406;5817.0983497557945;5842.269716285465;11229.697911928199;11231.157663175458;11698.239614425034;12013.45939432745;12211.633650990485;8477.576121193792;8740.73777786642;9344.112309330603;9799.11807937289;9821.950890245504;9400.623975041195;9633.90366096358;9819.754506137404;10039.949800250952;10285.9485702574;9713.514324283617;9752.756181095529;9879.50602469888;10006.749831254278;10367.429791361548;5678;5714.085746280575;5717.285339332583;5728.79949201776;5732;9428.764280893032;9630.518474076129;9854.704358869245;10357.274990750575;10500.369977801356 03:09:26 INFO - 2015-10-23 03:09:26,834 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1189.3399163464285;1192.6064398748415;1212.9393530323487;1382.668159641686;1573.5358069369536;8054.55699936504;8667;8861.601227944742;8878.86681699775;8885.422447540905;8179.141190175035;8595.871061934076;8627.007894092181;8661.69684061058;8684.34867384947;1881.7459642948163;1900.1544312780818;2048.252387878421;2083.1354987680093;2181.7054697615777;2096.6016456873144;2099.454141923096;2106.7577228618675;2228.4094714900566;2233.1179184222224;2105.620988222119;2165.577712346093;2167.7398712154563;2189.1243502598927;2199.52710167314;1901.1254822781536;1963.813437723414;2070.927517536894;2157.3635777445534;2246.932592022242;2196.2203417786613;2204.7574766775642;2233.2741858895924;2246.6854640350366;2254.7519772824976;8855.203031727175;8920.197182253629;8937.776555586097;8951.19439250464;8958.23854972328;5099.87250318745;5128.230765385185;5148;5156.355455568054;5159.58723302135;3377.392069427502;3378.3243351329834;3433.2729185289086;3463.4977928200524;3472.5138480612736;1112.9499172537255;1115.3140818396628;1154.5266440759333;1169.2925779903205;1171.1041053594008;12762.936185319108;12920.935395322964;12977.286249256287;12978.351082445934;12989.87010129896;4546.590806827401;4576.794044268016;4600.125976064537;4668.766561671903;4675.298705194248;10123.493825308853;10574.206934479791;10576.36541807494;10581.682549523526;10585.259031867849;5096.974515127401;5177.792888284502;5179.067767801832;5181.067407866545;5186.455422180692;4437.534058923831;4486.977565112154;4552.795124219352;4708;4721.645876559203;3464.6362131976284;3685.1892583631557;3692.593814680383;3710.5547334320054;3712;1111.4387234446715;1165.959191428296;1172.6306213542707;1178.452019810792;1180.0264781555252;773.1263672050659;1038.7091614347994;1040.1782591752583;1041.6041904076553;1054.504382940017;6637.668116594247;7979.122296547375;8162.265396114378;8173.264406203471;8188.2630563249595;593.9881202376015;612.3509080374816;612.5161122713096;621.6922623301494;631.6936285901335;5570.693611851306;5616.66300022;5639.069553523705;5640.238567793295;5641.887162256732;10175.43516908585;10434.37393756377;10582.788344233073;10661.893381066246;10804.13566914645;10580.994805493467;10597.788044239072;10637.095846853104;11328.150388720878;11337.716557086138;7560.886586701204;7640.579768112807;7641.2740789624895;7644.617769111522;7685.692572297157;7053.0830991970715;7526.586037767756;7561.697532098655;7586;7607.505512141693;9100.863487047567;9185.586648600696;9189.448633082036;9206.723798286062;9493.905060949302;9633.9036609633;10168.525563793357;10222.693319200436;10225.642102526375;10230.897691023292;7737.3423259021365;7920.287174154354;7970.800729981797;7994.960025199837;8018;8126.431149819456;8175.67297308101;8231.917680823115;8341.582920854054;8711.956440217758;7674.117476490275;7824;7997.560134192675;8041.833934079643;8157.3474122071275;7410.543512910235;7519.135299440415;7689.461737678309;7806.453548251795;8014.0383154019555;6769.559978601375;6822.897656535057;7475.579639868408;7487.513311634726;7516.962415187889;6514.469099761647;6872;6908.723651053901;6909.516333856582;6918.827029324307;6421.935780642134;6428.4857211423905;6444.194475690539;6444.7744328948265;6570 03:09:26 INFO - 2015-10-23 03:09:26,878 DEBUG : BROWSER_OUTPUT: |4;prototype.html;305.97858149929505;307.99286333688855;309.88689128468116;317.9570757947677;319.37083944629086;5499.615026948115;5501.284832971718;5508.91736623951;5512.366077901038;5687.516561092301;5472.835814925548;5753.395893431192;5760.7695692172365;5762.855928601787;5764.855878603037;4092.6930480213955;4272.743635381871;4275.722078064923;4279.74321540707;4281.529031806499;4014.759114453142;4029.6373326400617;4031.8307690769643;4039.838406463738;4117.75293482392;3929.8014105697753;3986.621270979252;3996.1008773025983;3997.780122093284;4031.6169963853386;3954.584768599313;3993.560708322082;3993.7004724645767;3998.2603218404543;4160.771157586331;3492.022233774547;3620.6198349173483;3667.2848794485085;3706.5366829146355;3716.1638631307737;5305.920411193797;5306.336707911511;5309.920351194735;5325.707086110262;5343.251944727741;4729.69256998294;4740.454847692525;4810.374651295309;4936.358273424432;4985.90028199434;5680.886382272372;5681.914771278434;5693.146028095777;5875.089361149029;6044.486218671418;3919.4904662393706;3961.8217180226957;4152.190322887038;4152.480939882515;4161.9375709364385;5890.617109887844;5939.2575928009;5950.4644581987395;6022.789202377896;6300.621962682254;4032.737872038308;4046.4537287466405;4049.3318602430268;4245.363195520635;4289.463817022872;4093.0790572121036;4239.342901850218;4280.614744672995;4342.218400687843;4348.543402942709;4675.76621168947;4723.7874295656775;4724.196886529298;4731.905361892743;4733.337332773345;5693.259876216065;5724.198612194296;5726;5735.283089613798;5740.397258287903;1463.4292625875917;1480.5188313798058;1483.9538125621411;1486.947956821506;1490.1952945409573;970.684527528556;982.2780256511459;996.4121168510469;1056.1128651932413;1062.984055239172;5947.256592925884;5988.071848863433;5988.9101663475085;5995.160677505153;6007.429294217042;6307.463865571386;6308.684565771693;6314.968425157845;6316.968415157895;6320.778772742932;5936.5844390892225;5972.522198224218;5982.641041537435;5986.2517185351835;5996.430339117776;3231.3860366530284;3289.227031647561;3407.216340241758;3456.1532424555644;3485.7734247273847;6546;6575.8027259181345;6662.933370666231;6670.332966703291;6672.9332706673285;6372.394622510854;6409.230892292955;6411.96794016027;6424.1327420798525;6426.036094585849;6270.372962703785;6272.278687950944;6655.267920528738;6657.201135863727;6659.4006539411685;5839.35767065614;5998.820035398945;6275.6548389839;6340.778072767431;6351.555391122577;5892.499137573354;6129.662868542273;6138.508919286357;6146.446819786241;6171.1668924694595;6261.467775239155;6261.499080073674;6262.185915831095;6292.2134733158355;6315.179026726496;4062.674986001172;4067.4102255173425;4067.7152599317765;4091.529474110396;4165.937510937399;6085.1785009023215;6115.449609535163;6424.164858568356;6450.774222902176;6452.419282264619;5860.501857342174;5876.6180198286975;5910.852228694317;6233.2208473940755;6239.78160764371;5819.330776960704;5825.359210486928;5832.562557808096;5833.708314584339;6184.288806787277;5982.102684597346;6022.307434645072;6023.066424704178;6024.969875150771;6031.457168854824;6099.634021958696;6158.230221222348;6172.8765424690355;6180.165677633462;6184.226971628546;5655.293088363955;5704.3725190228215;5725.770969161354;5812.360640329476;5913.231279933581 03:09:26 INFO - 2015-10-23 03:09:26,880 DEBUG : BROWSER_OUTPUT: |5;yui.html;656.3993945539831;681.635325101071;737.7786664000802;752.1500704204249;772.7836205862359;12260.386980650976;12804.295763733002;12817.679558011054;12880.16278941868;13280.46878124875;12252.26486410816;12471.875281247207;12472.688182795435;12473.31396773177;12534.373281335942;485.958693511052;520.5497244883168;584.436019241432;584.8128598848349;591.8757061017192;549.5878091431426;554.1576803259044;559.4070285497365;565.3357305166434;588.5379976718277;619.1734035063173;620.8603064310516;623.1120653069381;623.2676604989142;623.2956758862501;540.8053100883678;545.0570513012491;611.4160976267698;618.7122987810644;621.4065567383138;419.381412416686;435.6863058597805;444.22704494180215;447.7067520773898;447.74031061984294;11104.16781231096;11199.384033878134;11208.607698730586;11529.192956493049;11978.460969256404;11024.889751102466;11640.883591164149;12073.335966521838;12214.389280536026;12270.956968657674;9754.414735115844;9813.558389872473;9954;10163.33938294016;10824.917508249191;3046.2079859236537;3153.13288845567;3159.510275907238;3182.204448887778;3207.615086189649;43382.349264761055;44994.77502612466;45483.545164548595;45574.08851822978;45593.77203113996;7167.928320716726;7221.555688862249;7224.602646854369;7237.493375463772;7469.215732348134;10319.019693129141;10325.48372581368;10711.25021248505;10721.142308615292;10737.141028717684;6438.3883531064475;6446.8388290293115;6766.966165169143;6835.863282734317;6921.065656136458;3971.4241434991764;4003.5195776507007;4020.7587544746825;4055.371417430303;4120.299549076664;2476.2818782553218;2494.5759220932487;2651.0853755454336;2651.8541480218773;2655.774259187952;432.58687953004807;434.35932000299306;435.9106383191438;439.83286351186985;462.0942951814481;641.355437785023;641.7144370755062;682.2801943949082;697.4978015828596;705.1467724053953;9033.683821066232;9191.1251222091;9193.55558530814;9209.309301802392;9372.343935924557;594.7947957954499;616.3220457496718;663.9800805975829;665.9334066593302;675.9256481787098;4144.668426525871;4550.47669518003;4575.405197324393;4580.8038725548095;4614.630829533629;54695;55865.44134558603;56547.717261414255;56735;57221.427785722444;48643.5135648646;49101.754491227315;50338;50913.745431273346;51016.74491627518;40842.38736418956;41013;41961.79019104946;41962.79018604887;43628.781856090514;8766.474011559327;9146.725598232064;9175.724728258163;9320.116851015011;9441.52792360393;28574.57138142888;30271.243218919702;30707.692923070485;30719.692803071684;30751.692483074883;39485.01287467836;41811.37282940819;42636.360454592585;42730.78634606807;42848.35727464028;7979.840403191787;8189.79525511867;8533.27467165273;8557.828843423222;8574.485530868167;12933;13304.534341298007;13541.729165416833;13633.454661813417;13644.740044605065;12207;12740.74518509606;12946.287954162235;12972;13129.540466084023;11538.250013749415;11804.350760708243;12093.69765755828;12234.265944043385;12726.618201453972;11987.760244794881;12448.502059917864;12546.937265313614;12623.495060197489;12625.810612840998;9764.658236961672;9820.901790982;10012.499375031364;10262.332948358333;11164.162687798611;9571.234301256021;10036.849447258443;10195.541200645841;10601.455650643637;10732.785344292914 03:09:26 INFO - 2015-10-23 03:09:26,881 DEBUG : BROWSER_OUTPUT: __end_tp_report 03:09:26 INFO - 2015-10-23 03:09:26,881 DEBUG : BROWSER_OUTPUT: __start_cc_report 03:09:26 INFO - 2015-10-23 03:09:26,881 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,855 03:09:26 INFO - 2015-10-23 03:09:26,881 DEBUG : BROWSER_OUTPUT: __end_cc_report 03:09:26 INFO - 2015-10-23 03:09:26,881 DEBUG : BROWSER_OUTPUT: __startTimestamp1445594966839__endTimestamp 03:09:26 INFO - 2015-10-23 03:09:26,881 DEBUG : BROWSER_OUTPUT: 03:09:26 INFO - 2015-10-23 03:09:26,881 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 03:09:26 INFO - 2015-10-23 03:09:26,881 DEBUG : BROWSER_OUTPUT: Number of tests: 6 03:09:26 INFO - 2015-10-23 03:09:26,881 DEBUG : BROWSER_OUTPUT: 03:09:26 INFO - 2015-10-23 03:09:26,881 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4936.94 Median:5167.35 stddev:710.70 (13.8%) stddev-sans-first:689.85 03:09:26 INFO - 2015-10-23 03:09:26,881 DEBUG : BROWSER_OUTPUT: Values: 2649.5 2678.2 2721.5 2792.0 2834.9 5101.2 5178.5 5247.6 5273.1 5281.7 5035.3 5125.4 5253.3 5260.4 5361.9 4374.7 4532.6 4589.2 4601.6 4620.6 4416.7 4427.4 4480.5 4536.4 4565.5 4314.4 4343.3 4503.4 4569.2 4586.6 4293.2 4357.3 4380.9 4467.8 4623.2 4219.4 4265.9 4381.1 4463.3 4585.8 4827.7 4880.1 4883.8 5146.4 5167.4 5274.2 5396.1 5528.9 5590.4 5620.8 5057.4 5201.4 5279.3 5323.8 5326.0 4784.2 5071.6 5109.9 5115.6 5124.9 5581.0 5601.0 5647.0 5717.8 5742.4 5275.6 5285.0 5374.8 5411.5 5468.6 5463.8 5491.3 5512.8 5541.4 5641.1 5451.2 5472.2 5493.3 5566.7 5579.9 5056.6 5138.4 5226.6 5232.3 5236.2 3262.2 3362.0 3500.2 3502.4 3503.6 2999.2 3144.4 3240.0 3257.4 3273.3 4936.8 5015.0 5104.3 5112.6 5245.1 5259.4 5560.4 5598.3 5605.6 5700.3 4932.8 4944.8 5020.8 5031.7 5050.3 4043.9 4048.7 4092.7 4169.5 4184.8 5384.4 5424.8 5766.6 5799.0 5897.9 5343.7 5353.0 5495.6 5684.4 5698.5 5380.2 5451.3 5472.3 5489.0 5529.6 5084.4 5175.9 5185.2 5200.6 5224.5 5232.2 5257.2 5372.8 5658.7 5689.1 5058.9 5107.4 5169.5 5219.2 5471.2 5083.2 5106.2 5168.3 5227.9 5262.3 4988.7 5076.7 5079.4 5231.4 5303.7 4999.8 5124.4 5141.5 5230.8 5245.2 5036.5 5143.4 5184.4 5333.6 5434.8 03:09:26 INFO - 2015-10-23 03:09:26,881 DEBUG : BROWSER_OUTPUT: 03:09:26 INFO - 2015-10-23 03:09:26,881 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:46903.88 Median:33058.59 stddev:85828.53 (259.6%) stddev-sans-first:85998.54 03:09:26 INFO - 2015-10-23 03:09:26,881 DEBUG : BROWSER_OUTPUT: Values: 484.9 496.4 507.6 520.8 524.4 31340.8 35619.3 35725.5 35923.7 36790.8 37373.0 38391.2 38392.4 39591.2 40412.2 7687.7 7805.2 7993.7 8067.0 8137.8 9909.7 10037.5 10111.3 10122.0 10145.2 9415.9 9427.6 9441.0 9671.5 10033.3 7283.8 7427.9 9190.9 9343.8 9829.8 8981.8 9111.3 9635.0 9698.1 9703.1 35106.6 35144.5 35258.5 35265.3 36486.3 3007.4 3070.7 3079.9 3130.5 3131.3 28462.1 29528.3 29787.4 30561.2 30773.4 2760.3 2765.2 2792.3 2821.5 2842.8 299219.5 299781.0 299866.0 300541.0 311161.9 43517.0 44049.6 50012.0 50389.7 54001.2 54782.0 54920.2 55073.9 55098.0 55119.7 12172.7 13442.9 13588.4 13620.7 13622.0 10154.9 10223.9 10226.0 10234.8 10259.7 16684.0 17101.8 17246.5 17304.6 17342.0 7296.2 7490.3 7491.6 7497.7 7547.1 824.4 876.4 935.9 952.3 983.9 27223.0 27392.2 27797.4 28322.6 28346.0 940.4 983.6 992.4 1051.5 1065.1 14361.4 14444.3 14630.4 14751.6 14754.2 353631.0 358521.0 366817.0 366838.0 367108.0 43764.6 44191.3 44217.6 44235.0 44359.6 1099.3 1167.5 1174.2 1180.4 1181.7 22122.8 26449.3 27290.5 27947.4 28223.6 36955.3 38758.4 38905.2 39326.4 39459.8 242217.0 320188.0 320553.0 321063.0 341814.0 24068.6 24121.8 26235.5 26534.6 27546.4 37326.6 37514.9 37794.6 38387.2 38516.0 30947.4 32627.7 33050.5 33339.8 33606.8 32296.7 32468.8 32953.3 33066.7 33299.3 30913.2 31411.7 32350.2 34670.3 34806.0 28858.9 30791.1 31003.7 31570.7 31787.2 23509.3 24308.1 24715.9 24737.3 25010.0 03:09:26 INFO - 2015-10-23 03:09:26,882 DEBUG : BROWSER_OUTPUT: 03:09:26 INFO - 2015-10-23 03:09:26,882 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:24608.39 Median:226661.14 stddev:74609.21 (32.9%) stddev-sans-first:74796.21 03:09:26 INFO - 2015-10-23 03:09:26,882 DEBUG : BROWSER_OUTPUT: Values: 313.6 318.3 320.9 325.0 335.6 12684.9 15476.8 15816.5 16094.3 16128.3 10264.7 10403.5 15744.8 15972.9 16001.6 4070.9 4155.9 4160.4 4161.8 4201.8 3501.4 3546.5 4141.1 4613.5 4789.0 4379.6 4737.5 4738.4 4757.0 4760.7 4408.5 4720.1 4723.9 4731.2 4740.0 3749.0 3751.4 3761.8 3805.8 3880.3 11707.5 12044.3 12232.3 12537.1 12585.7 1841.1 1868.2 1889.4 1949.3 1981.5 9275.2 9293.0 9361.7 9394.2 9651.8 1527.4 1533.4 1603.3 1616.1 1624.2 69705.8 71823.6 72307.6 72417.0 75019.0 5827.2 5927.5 6033.1 6163.5 6245.9 6704.6 6998.0 7006.2 7076.4 7077.6 4967.9 5061.9 5194.0 5209.5 5244.4 5131.0 5183.2 5289.7 5391.9 5467.7 2600.1 2620.9 2628.3 2654.9 2783.9 982.4 988.5 1009.8 1026.3 1032.2 388.4 400.2 401.3 412.5 418.2 10971.5 11066.1 11074.8 11247.9 11818.9 423.3 424.1 430.8 444.0 453.3 5833.9 5966.2 6096.5 6305.1 6604.2 448322.0 450378.0 451824.0 452869.0 455698.0 66628.0 67492.0 69050.0 75086.0 79653.6 471.5 473.4 491.3 500.4 502.0 9920.5 10368.0 10501.0 10808.4 10903.7 31356.9 38446.2 39104.0 41681.8 45167.5 55062.7 55966.4 57471.0 57687.7 58339.1 5522.3 5802.2 5802.8 5817.1 5842.3 11229.7 11231.2 11698.2 12013.5 12211.6 8477.6 8740.7 9344.1 9799.1 9822.0 9400.6 9633.9 9819.8 10039.9 10285.9 9713.5 9752.8 9879.5 10006.7 10367.4 5678.0 5714.1 5717.3 5728.8 5732.0 9428.8 9630.5 9854.7 10357.3 10500.4 03:09:26 INFO - 2015-10-23 03:09:26,884 DEBUG : BROWSER_OUTPUT: 03:09:26 INFO - 2015-10-23 03:09:26,885 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5935.32 Median:5138.12 stddev:3363.96 (65.5%) stddev-sans-first:3354.49 03:09:26 INFO - 2015-10-23 03:09:26,885 DEBUG : BROWSER_OUTPUT: Values: 1189.3 1192.6 1212.9 1382.7 1573.5 8054.6 8667.0 8861.6 8878.9 8885.4 8179.1 8595.9 8627.0 8661.7 8684.3 1881.7 1900.2 2048.3 2083.1 2181.7 2096.6 2099.5 2106.8 2228.4 2233.1 2105.6 2165.6 2167.7 2189.1 2199.5 1901.1 1963.8 2070.9 2157.4 2246.9 2196.2 2204.8 2233.3 2246.7 2254.8 8855.2 8920.2 8937.8 8951.2 8958.2 5099.9 5128.2 5148.0 5156.4 5159.6 3377.4 3378.3 3433.3 3463.5 3472.5 1112.9 1115.3 1154.5 1169.3 1171.1 12762.9 12920.9 12977.3 12978.4 12989.9 4546.6 4576.8 4600.1 4668.8 4675.3 10123.5 10574.2 10576.4 10581.7 10585.3 5097.0 5177.8 5179.1 5181.1 5186.5 4437.5 4487.0 4552.8 4708.0 4721.6 3464.6 3685.2 3692.6 3710.6 3712.0 1111.4 1166.0 1172.6 1178.5 1180.0 773.1 1038.7 1040.2 1041.6 1054.5 6637.7 7979.1 8162.3 8173.3 8188.3 594.0 612.4 612.5 621.7 631.7 5570.7 5616.7 5639.1 5640.2 5641.9 10175.4 10434.4 10582.8 10661.9 10804.1 10581.0 10597.8 10637.1 11328.2 11337.7 7560.9 7640.6 7641.3 7644.6 7685.7 7053.1 7526.6 7561.7 7586.0 7607.5 9100.9 9185.6 9189.4 9206.7 9493.9 9633.9 10168.5 10222.7 10225.6 10230.9 7737.3 7920.3 7970.8 7995.0 8018.0 8126.4 8175.7 8231.9 8341.6 8712.0 7674.1 7824.0 7997.6 8041.8 8157.3 7410.5 7519.1 7689.5 7806.5 8014.0 6769.6 6822.9 7475.6 7487.5 7517.0 6514.5 6872.0 6908.7 6909.5 6918.8 6421.9 6428.5 6444.2 6444.8 6570.0 03:09:26 INFO - 2015-10-23 03:09:26,887 DEBUG : BROWSER_OUTPUT: 03:09:26 INFO - 2015-10-23 03:09:26,887 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4967.94 Median:5725.89 stddev:1549.12 (27.1%) stddev-sans-first:1513.43 03:09:26 INFO - 2015-10-23 03:09:26,887 DEBUG : BROWSER_OUTPUT: Values: 306.0 308.0 309.9 318.0 319.4 5499.6 5501.3 5508.9 5512.4 5687.5 5472.8 5753.4 5760.8 5762.9 5764.9 4092.7 4272.7 4275.7 4279.7 4281.5 4014.8 4029.6 4031.8 4039.8 4117.8 3929.8 3986.6 3996.1 3997.8 4031.6 3954.6 3993.6 3993.7 3998.3 4160.8 3492.0 3620.6 3667.3 3706.5 3716.2 5305.9 5306.3 5309.9 5325.7 5343.3 4729.7 4740.5 4810.4 4936.4 4985.9 5680.9 5681.9 5693.1 5875.1 6044.5 3919.5 3961.8 4152.2 4152.5 4161.9 5890.6 5939.3 5950.5 6022.8 6300.6 4032.7 4046.5 4049.3 4245.4 4289.5 4093.1 4239.3 4280.6 4342.2 4348.5 4675.8 4723.8 4724.2 4731.9 4733.3 5693.3 5724.2 5726.0 5735.3 5740.4 1463.4 1480.5 1484.0 1486.9 1490.2 970.7 982.3 996.4 1056.1 1063.0 5947.3 5988.1 5988.9 5995.2 6007.4 6307.5 6308.7 6315.0 6317.0 6320.8 5936.6 5972.5 5982.6 5986.3 5996.4 3231.4 3289.2 3407.2 3456.2 3485.8 6546.0 6575.8 6662.9 6670.3 6672.9 6372.4 6409.2 6412.0 6424.1 6426.0 6270.4 6272.3 6655.3 6657.2 6659.4 5839.4 5998.8 6275.7 6340.8 6351.6 5892.5 6129.7 6138.5 6146.4 6171.2 6261.5 6261.5 6262.2 6292.2 6315.2 4062.7 4067.4 4067.7 4091.5 4165.9 6085.2 6115.4 6424.2 6450.8 6452.4 5860.5 5876.6 5910.9 6233.2 6239.8 5819.3 5825.4 5832.6 5833.7 6184.3 5982.1 6022.3 6023.1 6025.0 6031.5 6099.6 6158.2 6172.9 6180.2 6184.2 5655.3 5704.4 5725.8 5812.4 5913.2 03:09:26 INFO - 2015-10-23 03:09:26,888 DEBUG : BROWSER_OUTPUT: 03:09:26 INFO - 2015-10-23 03:09:26,888 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12937.01 Median:22034.31 stddev:15077.54 (68.4%) stddev-sans-first:15091.63 03:09:26 INFO - 2015-10-23 03:09:26,888 DEBUG : BROWSER_OUTPUT: Values: 656.4 681.6 737.8 752.2 772.8 12260.4 12804.3 12817.7 12880.2 13280.5 12252.3 12471.9 12472.7 12473.3 12534.4 486.0 520.5 584.4 584.8 591.9 549.6 554.2 559.4 565.3 588.5 619.2 620.9 623.1 623.3 623.3 540.8 545.1 611.4 618.7 621.4 419.4 435.7 444.2 447.7 447.7 11104.2 11199.4 11208.6 11529.2 11978.5 11024.9 11640.9 12073.3 12214.4 12271.0 9754.4 9813.6 9954.0 10163.3 10824.9 3046.2 3153.1 3159.5 3182.2 3207.6 43382.3 44994.8 45483.5 45574.1 45593.8 7167.9 7221.6 7224.6 7237.5 7469.2 10319.0 10325.5 10711.3 10721.1 10737.1 6438.4 6446.8 6767.0 6835.9 6921.1 3971.4 4003.5 4020.8 4055.4 4120.3 2476.3 2494.6 2651.1 2651.9 2655.8 432.6 434.4 435.9 439.8 462.1 641.4 641.7 682.3 697.5 705.1 9033.7 9191.1 9193.6 9209.3 9372.3 594.8 616.3 664.0 665.9 675.9 4144.7 4550.5 4575.4 4580.8 4614.6 54695.0 55865.4 56547.7 56735.0 57221.4 48643.5 49101.8 50338.0 50913.7 51016.7 40842.4 41013.0 41961.8 41962.8 43628.8 8766.5 9146.7 9175.7 9320.1 9441.5 28574.6 30271.2 30707.7 30719.7 30751.7 39485.0 41811.4 42636.4 42730.8 42848.4 7979.8 8189.8 8533.3 8557.8 8574.5 12933.0 13304.5 13541.7 13633.5 13644.7 12207.0 12740.7 12946.3 12972.0 13129.5 11538.3 11804.4 12093.7 12234.3 12726.6 11987.8 12448.5 12546.9 12623.5 12625.8 9764.7 9820.9 10012.5 10262.3 11164.2 9571.2 10036.8 10195.5 10601.5 10732.8 03:09:26 INFO - 2015-10-23 03:09:26,888 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 03:09:26 INFO - 2015-10-23 03:09:26,888 DEBUG : BROWSER_OUTPUT: 03:09:27 INFO - 2015-10-23 03:09:27,311 INFO : Browser exited with error code: 0 03:09:28 INFO - 2015-10-23 03:09:28,045 INFO : Completed test dromaeo_css (00:18:40) 03:09:28 INFO - 2015-10-23 03:09:28,045 INFO : Starting test kraken 03:09:28 INFO - 2015-10-23 03:09:28,045 DEBUG : operating with platform_type : w7_ 03:09:28 INFO - 2015-10-23 03:09:28,045 INFO : Initialising browser for kraken test... 03:09:28 INFO - 2015-10-23 03:09:28,071 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpzcomkb\profile http://localhost:49696/getInfo.html 03:09:32 INFO - 2015-10-23 03:09:32,713 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:09:32 INFO - 2015-10-23 03:09:32,713 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:09:32 INFO - 2015-10-23 03:09:32,713 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 03:09:32 INFO - 2015-10-23 03:09:32,713 DEBUG : BROWSER_OUTPUT: __metrics 03:09:32 INFO - 2015-10-23 03:09:32,812 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:09:33 INFO - 2015-10-23 03:09:33,792 INFO : Browser initialized. 03:09:33 INFO - 2015-10-23 03:09:33,792 INFO : Running cycle 1/1 for kraken test... 03:09:33 INFO - 2015-10-23 03:09:33,792 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpzcomkb\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:09:45 INFO - 2015-10-23 03:09:45,161 DEBUG : BROWSER_OUTPUT: RSS: Main: 137347072 03:09:45 INFO - 2015-10-23 03:09:45,161 DEBUG : BROWSER_OUTPUT: 03:09:47 INFO - 2015-10-23 03:09:47,500 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49696/tests/kraken/driver.html?testName=audio-beat-detection) 03:09:48 INFO - 2015-10-23 03:09:48,075 DEBUG : BROWSER_OUTPUT: RSS: Main: 171810816 03:09:48 INFO - 2015-10-23 03:09:48,075 DEBUG : BROWSER_OUTPUT: 03:09:50 INFO - 2015-10-23 03:09:50,671 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49696/tests/kraken/driver.html?testName=audio-dft) 03:09:51 INFO - 2015-10-23 03:09:51,230 DEBUG : BROWSER_OUTPUT: RSS: Main: 176427008 03:09:51 INFO - 2015-10-23 03:09:51,230 DEBUG : BROWSER_OUTPUT: 03:09:53 INFO - 2015-10-23 03:09:53,933 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49696/tests/kraken/driver.html?testName=audio-fft) 03:09:54 INFO - 2015-10-23 03:09:54,507 DEBUG : BROWSER_OUTPUT: RSS: Main: 165457920 03:09:54 INFO - 2015-10-23 03:09:54,507 DEBUG : BROWSER_OUTPUT: 03:09:56 INFO - 2015-10-23 03:09:56,568 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49696/tests/kraken/driver.html?testName=audio-oscillator) 03:09:57 INFO - 2015-10-23 03:09:57,128 DEBUG : BROWSER_OUTPUT: RSS: Main: 165482496 03:09:57 INFO - 2015-10-23 03:09:57,128 DEBUG : BROWSER_OUTPUT: 03:09:59 INFO - 2015-10-23 03:09:59,269 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49696/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:09:59 INFO - 2015-10-23 03:09:59,823 DEBUG : BROWSER_OUTPUT: RSS: Main: 164528128 03:09:59 INFO - 2015-10-23 03:09:59,823 DEBUG : BROWSER_OUTPUT: 03:10:02 INFO - 2015-10-23 03:10:02,918 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49696/tests/kraken/driver.html?testName=imaging-darkroom) 03:10:03 INFO - 2015-10-23 03:10:03,482 DEBUG : BROWSER_OUTPUT: RSS: Main: 158064640 03:10:03 INFO - 2015-10-23 03:10:03,482 DEBUG : BROWSER_OUTPUT: 03:10:06 INFO - 2015-10-23 03:10:06,836 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49696/tests/kraken/driver.html?testName=imaging-desaturate) 03:10:07 INFO - 2015-10-23 03:10:07,397 DEBUG : BROWSER_OUTPUT: RSS: Main: 160509952 03:10:07 INFO - 2015-10-23 03:10:07,397 DEBUG : BROWSER_OUTPUT: 03:10:10 INFO - 2015-10-23 03:10:10,180 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49696/tests/kraken/driver.html?testName=json-parse-financial) 03:10:10 INFO - 2015-10-23 03:10:10,755 DEBUG : BROWSER_OUTPUT: RSS: Main: 160227328 03:10:10 INFO - 2015-10-23 03:10:10,755 DEBUG : BROWSER_OUTPUT: 03:10:12 INFO - 2015-10-23 03:10:12,558 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49696/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:10:13 INFO - 2015-10-23 03:10:13,112 DEBUG : BROWSER_OUTPUT: RSS: Main: 190402560 03:10:13 INFO - 2015-10-23 03:10:13,112 DEBUG : BROWSER_OUTPUT: 03:10:14 INFO - 2015-10-23 03:10:14,967 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49696/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:10:15 INFO - 2015-10-23 03:10:15,535 DEBUG : BROWSER_OUTPUT: RSS: Main: 195923968 03:10:15 INFO - 2015-10-23 03:10:15,535 DEBUG : BROWSER_OUTPUT: 03:10:17 INFO - 2015-10-23 03:10:17,795 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49696/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:10:18 INFO - 2015-10-23 03:10:18,355 DEBUG : BROWSER_OUTPUT: RSS: Main: 202539008 03:10:18 INFO - 2015-10-23 03:10:18,355 DEBUG : BROWSER_OUTPUT: 03:10:20 INFO - 2015-10-23 03:10:20,769 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49696/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:10:21 INFO - 2015-10-23 03:10:21,328 DEBUG : BROWSER_OUTPUT: RSS: Main: 203952128 03:10:21 INFO - 2015-10-23 03:10:21,328 DEBUG : BROWSER_OUTPUT: 03:10:24 INFO - 2015-10-23 03:10:24,384 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49696/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:10:24 INFO - 2015-10-23 03:10:24,944 DEBUG : BROWSER_OUTPUT: RSS: Main: 202727424 03:10:24 INFO - 2015-10-23 03:10:24,944 DEBUG : BROWSER_OUTPUT: 03:10:26 INFO - 2015-10-23 03:10:26,790 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49696/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49696/tests/kraken/driver.html?testName=ai-astar) 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: RSS: Main: 248004608 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: __start_tp_report 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: |0;ai-astar;92;96;104;87;95;95;100;96;96;95 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;132;133;154;152;135;135;140;134;153;144 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: |2;audio-dft;152;153;151;151;151;151;152;151;150;151 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: |3;audio-fft;110;91;78;98;82;81;77;80;104;80 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;96;111;96;100;95;117;96;96;121 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;116;117;117;131;116;117;116;117;118;117 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;140;141;141;141;140;140;141;141;141;140 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;89;89;89;89;89;90;92;92;92;92 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;81;82;81;81;81;81;81;82;81;95 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;74;77;74;74;74;74;75;74;74;74 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;100;95;97;98;98;95;95;97;97;96 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;121;123;120;119;126;117;119;117;127;121 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;188;197;194;196;202;193;195;196;195;187 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;85;85;84;90;85;85;86;84;86;84 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: __end_tp_report 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: __start_cc_report 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,816 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: __end_cc_report 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: __startTimestamp1445595027046__endTimestamp 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 03:10:27 INFO - 2015-10-23 03:10:27,046 DEBUG : BROWSER_OUTPUT: Number of tests: 14 03:10:27 INFO - 2015-10-23 03:10:27,048 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,048 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:95.60 Median:95.00 stddev:4.45 (4.7%) stddev-sans-first:4.53 03:10:27 INFO - 2015-10-23 03:10:27,048 DEBUG : BROWSER_OUTPUT: Values: 92.0 96.0 104.0 87.0 95.0 95.0 100.0 96.0 96.0 95.0 03:10:27 INFO - 2015-10-23 03:10:27,048 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,048 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.20 Median:142.00 stddev:8.88 (6.3%) stddev-sans-first:8.77 03:10:27 INFO - 2015-10-23 03:10:27,048 DEBUG : BROWSER_OUTPUT: Values: 132.0 133.0 154.0 152.0 135.0 135.0 140.0 134.0 153.0 144.0 03:10:27 INFO - 2015-10-23 03:10:27,048 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,048 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:151.30 Median:151.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 03:10:27 INFO - 2015-10-23 03:10:27,048 DEBUG : BROWSER_OUTPUT: Values: 152.0 153.0 151.0 151.0 151.0 151.0 152.0 151.0 150.0 151.0 03:10:27 INFO - 2015-10-23 03:10:27,048 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,048 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.10 Median:80.50 stddev:11.94 (14.8%) stddev-sans-first:9.68 03:10:27 INFO - 2015-10-23 03:10:27,048 DEBUG : BROWSER_OUTPUT: Values: 110.0 91.0 78.0 98.0 82.0 81.0 77.0 80.0 104.0 80.0 03:10:27 INFO - 2015-10-23 03:10:27,048 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,048 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.20 Median:95.50 stddev:10.15 (10.6%) stddev-sans-first:10.33 03:10:27 INFO - 2015-10-23 03:10:27,049 DEBUG : BROWSER_OUTPUT: Values: 94.0 96.0 111.0 96.0 100.0 95.0 117.0 96.0 96.0 121.0 03:10:27 INFO - 2015-10-23 03:10:27,049 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,052 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:118.20 Median:117.00 stddev:4.54 (3.9%) stddev-sans-first:4.75 03:10:27 INFO - 2015-10-23 03:10:27,052 DEBUG : BROWSER_OUTPUT: Values: 116.0 117.0 117.0 131.0 116.0 117.0 116.0 117.0 118.0 117.0 03:10:27 INFO - 2015-10-23 03:10:27,053 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,053 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:140.60 Median:141.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 03:10:27 INFO - 2015-10-23 03:10:27,053 DEBUG : BROWSER_OUTPUT: Values: 140.0 141.0 141.0 141.0 140.0 140.0 141.0 141.0 141.0 140.0 03:10:27 INFO - 2015-10-23 03:10:27,053 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,053 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:90.30 Median:91.00 stddev:1.49 (1.6%) stddev-sans-first:1.51 03:10:27 INFO - 2015-10-23 03:10:27,055 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 89.0 89.0 89.0 90.0 92.0 92.0 92.0 92.0 03:10:27 INFO - 2015-10-23 03:10:27,055 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,055 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:82.60 Median:81.00 stddev:4.38 (5.4%) stddev-sans-first:4.60 03:10:27 INFO - 2015-10-23 03:10:27,055 DEBUG : BROWSER_OUTPUT: Values: 81.0 82.0 81.0 81.0 81.0 81.0 81.0 82.0 81.0 95.0 03:10:27 INFO - 2015-10-23 03:10:27,055 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,055 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:74.40 Median:74.00 stddev:0.97 (1.3%) stddev-sans-first:1.01 03:10:27 INFO - 2015-10-23 03:10:27,055 DEBUG : BROWSER_OUTPUT: Values: 74.0 77.0 74.0 74.0 74.0 74.0 75.0 74.0 74.0 74.0 03:10:27 INFO - 2015-10-23 03:10:27,056 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,056 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:96.80 Median:97.00 stddev:1.62 (1.7%) stddev-sans-first:1.24 03:10:27 INFO - 2015-10-23 03:10:27,056 DEBUG : BROWSER_OUTPUT: Values: 100.0 95.0 97.0 98.0 98.0 95.0 95.0 97.0 97.0 96.0 03:10:27 INFO - 2015-10-23 03:10:27,056 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,058 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:121.00 Median:121.00 stddev:3.43 (2.8%) stddev-sans-first:3.64 03:10:27 INFO - 2015-10-23 03:10:27,058 DEBUG : BROWSER_OUTPUT: Values: 121.0 123.0 120.0 119.0 126.0 117.0 119.0 117.0 127.0 121.0 03:10:27 INFO - 2015-10-23 03:10:27,059 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,059 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.30 Median:195.50 stddev:4.32 (2.2%) stddev-sans-first:3.94 03:10:27 INFO - 2015-10-23 03:10:27,059 DEBUG : BROWSER_OUTPUT: Values: 188.0 197.0 194.0 196.0 202.0 193.0 195.0 196.0 195.0 187.0 03:10:27 INFO - 2015-10-23 03:10:27,059 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,059 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:85.40 Median:85.00 stddev:1.78 (2.1%) stddev-sans-first:1.88 03:10:27 INFO - 2015-10-23 03:10:27,059 DEBUG : BROWSER_OUTPUT: Values: 85.0 85.0 84.0 90.0 85.0 85.0 86.0 84.0 86.0 84.0 03:10:27 INFO - 2015-10-23 03:10:27,059 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 03:10:27 INFO - 2015-10-23 03:10:27,061 DEBUG : BROWSER_OUTPUT: 03:10:27 INFO - 2015-10-23 03:10:27,628 INFO : Browser exited with error code: 0 03:10:28 INFO - 2015-10-23 03:10:28,515 INFO : Completed test kraken (00:01:00) 03:10:28 INFO - 2015-10-23 03:10:28,515 INFO : Starting test v8_7 03:10:28 INFO - 2015-10-23 03:10:28,515 DEBUG : operating with platform_type : w7_ 03:10:28 INFO - 2015-10-23 03:10:28,515 INFO : Initialising browser for v8_7 test... 03:10:28 INFO - 2015-10-23 03:10:28,542 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpghzoer\profile http://localhost:49696/getInfo.html 03:10:33 INFO - 2015-10-23 03:10:33,045 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:10:33 INFO - 2015-10-23 03:10:33,045 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:10:33 INFO - 2015-10-23 03:10:33,045 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 03:10:33 INFO - 2015-10-23 03:10:33,045 DEBUG : BROWSER_OUTPUT: __metrics 03:10:33 INFO - 2015-10-23 03:10:33,072 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:10:33 INFO - 2015-10-23 03:10:33,766 DEBUG : BROWSER_OUTPUT: console.error: 03:10:33 INFO - 2015-10-23 03:10:33,766 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] 03:10:33 INFO - 2015-10-23 03:10:33,766 DEBUG : BROWSER_OUTPUT: Stack: 03:10:33 INFO - 2015-10-23 03:10:33,766 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 03:10:33 INFO - 2015-10-23 03:10:33,766 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 03:10:33 INFO - 2015-10-23 03:10:33,766 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 03:10:33 INFO - 2015-10-23 03:10:33,766 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 03:10:33 INFO - 2015-10-23 03:10:33,766 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 03:10:33 INFO - 2015-10-23 03:10:33,766 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 03:10:33 INFO - 2015-10-23 03:10:33,766 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 03:10:33 INFO - 2015-10-23 03:10:33,766 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 03:10:33 INFO - 2015-10-23 03:10:33,766 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 03:10:33 INFO - 2015-10-23 03:10:33,767 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 03:10:33 INFO - 2015-10-23 03:10:33,767 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 03:10:33 INFO - 2015-10-23 03:10:33,767 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 03:10:33 INFO - 2015-10-23 03:10:33,767 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6315.52 03:11:09 INFO - RETURN: kraken: 1582.00 03:11:09 INFO - RETURN: v8_7: 20658.56 03:11:09 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,140,25]]", "result": "20658.56"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,140,25]]", "result": "1582.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,140,25]]", "result": "6315.52"}}} 03:11:09 INFO - Return code: 0 03:11:09 INFO - # TBPL SUCCESS # 03:11:09 INFO - Running post-action listener: _resource_record_post_action 03:11:09 INFO - Running post-run listener: _resource_record_post_run 03:11:10 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 93151744; Write bytes: 506549760; Read time: 1525440; Write time: 5300510 03:11:10 INFO - install - Wall time: 3s; CPU: 9.0%; Read bytes: 0; Write bytes: 37600256; Read time: 0; Write time: 128880 03:11:10 INFO - run-tests - Wall time: 1225s; CPU: 13.0%; Read bytes: 93151744; Write bytes: 441540096; Read time: 1525440; Write time: 4969120 03:11:10 INFO - Running post-run listener: _upload_blobber_files 03:11:10 INFO - Blob upload gear active. 03:11:10 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 03:11:10 INFO - Copying logs to upload dir... 03:11:10 INFO - mkdir: C:\slave\test-pgo\build\upload\logs program finished with exit code 0 elapsedTime=1542.537000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 51 secs) (at 2015-10-23 03:11:19.061137) ========= ========= Started set props: build_url (results: 0, elapsed: 15 secs) (at 2015-10-23 03:11:19.061829) ========= '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-032 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-032 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-032 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/dh-l-0i_RdSY7eRbhyUERA/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/dh-l-0i_RdSY7eRbhyUERA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 15 secs) (at 2015-10-23 03:11:34.584594) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 10 secs) (at 2015-10-23 03:11:34.584931) ========= '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-032 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-032 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-032 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: 10 secs) (at 2015-10-23 03:11:44.808607) ========= ========= Started reboot slave lost (results: 0, elapsed: 2 secs) (at 2015-10-23 03:11:44.814548) ========= ========= Finished reboot slave lost (results: 0, elapsed: 2 secs) (at 2015-10-23 03:11:47.319418) =========