builder: mozilla-central_win7-ix_test-dromaeojs-pgo slave: t-w732-ix-120 starttime: 1445877457.0 results: success (0) buildid: 20151026043008 builduid: 5bf84b0556cc4399aa1313ae308f511e revision: 5ca03a00d26823ce91ee0eaa2937bed605bd53c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-26 09:37:37.000568) ========= master: http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-26 09:37:37.002234) ========= ========= Started set props: basedir (results: 0, elapsed: 3 secs) (at 2015-10-26 09:37:37.002573) ========= '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-120 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-120 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-120 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.529000 basedir: 'C:\\slave\\test-pgo' ========= Finished set props: basedir (results: 0, elapsed: 3 secs) (at 2015-10-26 09:37:40.884382) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 15 secs) (at 2015-10-26 09:37:40.884656) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 15 secs) (at 2015-10-26 09:37:56.317416) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 09:37:56.317808) ========= '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-120 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-120 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-120 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.501000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 09:37:56.923566) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-26 09:37:56.923830) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-26 09:37:56.924168) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 9 secs) (at 2015-10-26 09:37:56.924786) ========= '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-120 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-120 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-120 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 --09:38:06-- 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% 2.45 GB/s 09:38:06 (2.45 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.830000 ========= Finished 'bash -c ...' (results: 0, elapsed: 9 secs) (at 2015-10-26 09:38:06.335500) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2015-10-26 09:38:06.335865) ========= '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-120 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-120 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-120 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=11.301000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2015-10-26 09:38:17.801501) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 9 secs) (at 2015-10-26 09:38:17.801835) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev 5ca03a00d26823ce91ee0eaa2937bed605bd53c1 --destination scripts --debug' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5ca03a00d26823ce91ee0eaa2937bed605bd53c1 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-120 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-120 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-120 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-26 09:38:18,578 truncating revision to first 12 chars 2015-10-26 09:38:18,578 Setting DEBUG logging. 2015-10-26 09:38:18,578 attempt 1/10 2015-10-26 09:38:18,578 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5ca03a00d268?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-26 09:38:19,693 unpacking tar archive at: mozilla-central-5ca03a00d268/testing/mozharness/ program finished with exit code 0 elapsedTime=1.880000 ========= Finished 'bash -c ...' (results: 0, elapsed: 9 secs) (at 2015-10-26 09:38:27.310509) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-26 09:38:27.310861) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-26 09:38:27.355589) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-26 09:38:27.355982) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-26 09:38:27.356574) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 27 mins, 50 secs) (at 2015-10-26 09:38:27.356944) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox' 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', 'Firefox', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-120 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-120 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-120 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 09:38:28 INFO - MultiFileLogger online at 20151026 09:38:28 in C:\slave\test-pgo 09:38:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:38:28 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 09:38:28 INFO - {'append_to_log': False, 09:38:28 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 09:38:28 INFO - 'blob_upload_branch': 'Firefox', 09:38:28 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 09:38:28 INFO - 'branch': 'Firefox', 09:38:28 INFO - 'buildbot_json_path': 'buildprops.json', 09:38:28 INFO - 'config_files': ('talos/windows_config.py',), 09:38:28 INFO - 'default_actions': ('clobber', 09:38:28 INFO - 'read-buildbot-config', 09:38:28 INFO - 'download-and-extract', 09:38:28 INFO - 'populate-webroot', 09:38:28 INFO - 'create-virtualenv', 09:38:28 INFO - 'install', 09:38:28 INFO - 'run-tests'), 09:38:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:38:28 INFO - 'download_minidump_stackwalk': True, 09:38:28 INFO - 'download_symbols': 'ondemand', 09:38:28 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 09:38:28 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 09:38:28 INFO - 'hg': 'c:/mozilla-build/hg/hg', 09:38:28 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 09:38:28 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 09:38:28 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 09:38:28 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 09:38:28 INFO - 'C:/mozilla-build/tooltool.py'), 09:38:28 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 09:38:28 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 09:38:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:38:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:38:28 INFO - 'installer_path': 'installer.exe', 09:38:28 INFO - 'log_level': 'info', 09:38:28 INFO - 'log_name': 'talos', 09:38:28 INFO - 'log_to_console': True, 09:38:28 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 09:38:28 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 09:38:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 09:38:28 INFO - 'opt_config_files': (), 09:38:28 INFO - 'pip_index': False, 09:38:28 INFO - 'sps_profile': False, 09:38:28 INFO - 'sps_profile_interval': 0, 09:38:28 INFO - 'suite': 'dromaeojs', 09:38:28 INFO - 'system_bits': '32', 09:38:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:38:28 INFO - 'title': 'T-W732-IX-120', 09:38:28 INFO - 'use_talos_json': True, 09:38:28 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 09:38:28 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 09:38:28 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 09:38:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:38:28 INFO - 'work_dir': 'build'} 09:38:28 INFO - ##### 09:38:28 INFO - ##### Running clobber step. 09:38:28 INFO - ##### 09:38:28 INFO - Running pre-action listener: _resource_record_pre_action 09:38:28 INFO - Running main action method: clobber 09:38:28 INFO - rmtree: C:\slave\test-pgo\build 09:38:28 INFO - Using _rmtree_windows ... 09:38:28 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 09:41:30 INFO - Running post-action listener: _resource_record_post_action 09:41:30 INFO - ##### 09:41:30 INFO - ##### Running read-buildbot-config step. 09:41:30 INFO - ##### 09:41:30 INFO - Running pre-action listener: _resource_record_pre_action 09:41:30 INFO - Running main action method: read_buildbot_config 09:41:30 INFO - Using buildbot properties: 09:41:30 INFO - { 09:41:30 INFO - "properties": { 09:41:30 INFO - "buildnumber": 12, 09:41:30 INFO - "product": "firefox", 09:41:30 INFO - "script_repo_revision": "production", 09:41:30 INFO - "builddir": "mozilla-central_win7-ix_test-dromaeojs", 09:41:30 INFO - "repository": "", 09:41:30 INFO - "buildername": "Windows 7 32-bit mozilla-central pgo talos dromaeojs", 09:41:30 INFO - "buildid": "20151026043008", 09:41:30 INFO - "slavename": "t-w732-ix-120", 09:41:30 INFO - "pgo_build": "True", 09:41:30 INFO - "basedir": "C:\\slave\\test-pgo", 09:41:30 INFO - "project": "", 09:41:30 INFO - "platform": "win7-ix", 09:41:30 INFO - "master": "http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/", 09:41:30 INFO - "slavebuilddir": "test", 09:41:30 INFO - "scheduler": "tests-mozilla-central-win32-pgo-talos", 09:41:30 INFO - "branch": "mozilla-central", 09:41:30 INFO - "repo_path": "mozilla-central", 09:41:30 INFO - "stage_platform": "win32-pgo", 09:41:30 INFO - "builduid": "5bf84b0556cc4399aa1313ae308f511e", 09:41:30 INFO - "revision": "5ca03a00d26823ce91ee0eaa2937bed605bd53c1" 09:41:30 INFO - }, 09:41:30 INFO - "sourcestamp": { 09:41:30 INFO - "repository": "", 09:41:30 INFO - "hasPatch": false, 09:41:30 INFO - "project": "", 09:41:30 INFO - "branch": "mozilla-central-win32-pgo-talos", 09:41:30 INFO - "changes": [ 09:41:30 INFO - { 09:41:30 INFO - "category": null, 09:41:30 INFO - "files": [ 09:41:30 INFO - { 09:41:30 INFO - "url": null, 09:41:30 INFO - "name": "https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 09:41:30 INFO - } 09:41:30 INFO - ], 09:41:30 INFO - "repository": "", 09:41:30 INFO - "rev": "5ca03a00d26823ce91ee0eaa2937bed605bd53c1", 09:41:30 INFO - "who": "sendchange", 09:41:30 INFO - "when": 1445877115, 09:41:30 INFO - "number": 6574374, 09:41:30 INFO - "comments": "", 09:41:30 INFO - "project": "", 09:41:30 INFO - "at": "Mon 26 Oct 2015 09:31:55", 09:41:30 INFO - "branch": "mozilla-central-win32-pgo-talos", 09:41:30 INFO - "revlink": "", 09:41:30 INFO - "properties": [ 09:41:30 INFO - [ 09:41:30 INFO - "buildid", 09:41:30 INFO - "20151026043008", 09:41:30 INFO - "Change" 09:41:30 INFO - ], 09:41:30 INFO - [ 09:41:30 INFO - "builduid", 09:41:30 INFO - "5bf84b0556cc4399aa1313ae308f511e", 09:41:30 INFO - "Change" 09:41:30 INFO - ], 09:41:30 INFO - [ 09:41:30 INFO - "pgo_build", 09:41:30 INFO - "True", 09:41:30 INFO - "Change" 09:41:30 INFO - ] 09:41:30 INFO - ], 09:41:30 INFO - "revision": "5ca03a00d26823ce91ee0eaa2937bed605bd53c1" 09:41:30 INFO - } 09:41:30 INFO - ], 09:41:30 INFO - "revision": "5ca03a00d26823ce91ee0eaa2937bed605bd53c1" 09:41:30 INFO - } 09:41:30 INFO - } 09:41:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 09:41:30 INFO - Running post-action listener: _resource_record_post_action 09:41:30 INFO - ##### 09:41:30 INFO - ##### Running download-and-extract step. 09:41:30 INFO - ##### 09:41:30 INFO - Running pre-action listener: _resource_record_pre_action 09:41:30 INFO - Running main action method: download_and_extract 09:41:30 INFO - mkdir: C:\slave\test-pgo\build\tests 09:41:30 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')]} 09:41:30 INFO - https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:41:30 INFO - trying https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/test_packages.json 09:41:30 INFO - Downloading https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 09:41:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 09:41:32 INFO - Downloaded 1221 bytes. 09:41:32 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 09:41:32 INFO - Using the following test package requirements: 09:41:32 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 09:41:32 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 09:41:32 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 09:41:32 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 09:41:32 INFO - u'jsshell-win32.zip'], 09:41:32 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 09:41:32 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 09:41:32 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 09:41:32 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 09:41:32 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 09:41:32 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 09:41:32 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 09:41:32 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 09:41:32 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 09:41:32 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 09:41:32 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 09:41:32 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 09:41:32 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 09:41:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:41:32 INFO - https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 09:41:32 INFO - trying https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 09:41:32 INFO - Downloading https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/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 09:41:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/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 09:41:52 INFO - Downloaded 18016571 bytes. 09:41:52 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 09:41:52 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 09:41:52 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 09:42:22 INFO - Return code: 0 09:42:22 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 09:42: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')]} 09:42:22 INFO - https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 09:42:22 INFO - trying https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 09:42:22 INFO - Downloading https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/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 09:42:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/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 09:42:43 INFO - Downloaded 18016571 bytes. 09:42:43 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 09:42:43 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 09:42:43 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 09:43:04 INFO - Return code: 0 09:43:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:04 INFO - https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 09:43:04 INFO - trying https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 09:43:04 INFO - Downloading https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/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 09:43:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/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 09:43:06 INFO - Downloaded 11131307 bytes. 09:43:06 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 09:43:06 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip 09:43:06 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 09:43:09 INFO - Return code: 0 09:43:09 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')]} 09:43:09 INFO - https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 09:43:09 INFO - trying https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 09:43:09 INFO - Downloading https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip 09:43:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/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 09:43:13 INFO - Downloaded 53212399 bytes. 09:43:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 09:43:13 INFO - mkdir: C:\slave\test-pgo\properties 09:43:13 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 09:43:13 INFO - Writing to file C:\slave\test-pgo\properties\build_url 09:43:13 INFO - Contents: 09:43:13 INFO - build_url:https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 09:43:13 INFO - Running post-action listener: _resource_record_post_action 09:43:13 INFO - Running post-action listener: set_extra_try_arguments 09:43:13 INFO - ##### 09:43:13 INFO - ##### Running populate-webroot step. 09:43:13 INFO - ##### 09:43:13 INFO - Running pre-action listener: _resource_record_pre_action 09:43:13 INFO - Running main action method: populate_webroot 09:43:13 INFO - Guessing talos json url... 09:43:13 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')]} 09:43:13 INFO - https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 09:43:13 INFO - trying https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 09:43:13 INFO - Downloading https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 09:43:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/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 09:43:14 INFO - Downloaded 99 bytes. 09:43:14 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 09:43:14 INFO - Contents: 09:43:14 INFO - 20151026043008 09:43:14 INFO - https://hg.mozilla.org/mozilla-central/rev/5ca03a00d26823ce91ee0eaa2937bed605bd53c1 09:43:14 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')]} 09:43:14 INFO - trying https://hg.mozilla.org/mozilla-central/raw-file/5ca03a00d2/testing/talos/talos.json 09:43:14 INFO - Downloading https://hg.mozilla.org/mozilla-central/raw-file/5ca03a00d2/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json 09:43:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/mozilla-central/raw-file/5ca03a00d2/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1 09:43:15 INFO - Downloaded 4264 bytes. 09:43:15 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 09:43:15 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 09:43:15 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 09:43:15 INFO - u'../fennec_ids.txt'], 09:43:15 INFO - u'tests': [u'tcheck2']}, 09:43:15 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 09:43:15 INFO - u'--tppagecycles', 09:43:15 INFO - u'7'], 09:43:15 INFO - u'tests': [u'tsvgm']}}, 09:43:15 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 09:43:15 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 09:43:15 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 09:43:15 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 09:43:15 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 09:43:15 INFO - u'tests': [u'dromaeo_css', 09:43:15 INFO - u'kraken', 09:43:15 INFO - u'v8_7']}, 09:43:15 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:15 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 09:43:15 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:15 INFO - u'talos_options': [u'--e10s'], 09:43:15 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 09:43:15 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:15 INFO - u'tests': [u'damp', u'tps']}, 09:43:15 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:15 INFO - u'talos_options': [u'--e10s'], 09:43:15 INFO - u'tests': [u'damp', u'tps']}, 09:43:15 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 09:43:15 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 09:43:15 INFO - u'other': {u'tests': [u'a11yr', 09:43:15 INFO - u'ts_paint', 09:43:15 INFO - u'tpaint', 09:43:15 INFO - u'sessionrestore', 09:43:15 INFO - u'sessionrestore_no_auto_restore']}, 09:43:15 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 09:43:15 INFO - u'tests': [u'a11yr', 09:43:15 INFO - u'ts_paint', 09:43:15 INFO - u'tpaint', 09:43:15 INFO - u'sessionrestore', 09:43:15 INFO - u'sessionrestore_no_auto_restore']}, 09:43:15 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 09:43:15 INFO - u'tests': [u'a11yr', 09:43:15 INFO - u'ts_paint', 09:43:15 INFO - u'tpaint', 09:43:15 INFO - u'sessionrestore', 09:43:15 INFO - u'sessionrestore_no_auto_restore']}, 09:43:15 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 09:43:15 INFO - u'tests': [u'a11yr', 09:43:15 INFO - u'ts_paint', 09:43:15 INFO - u'tpaint', 09:43:15 INFO - u'sessionrestore', 09:43:15 INFO - u'sessionrestore_no_auto_restore']}, 09:43:15 INFO - u'other_l64': {u'tests': [u'a11yr', 09:43:15 INFO - u'ts_paint', 09:43:15 INFO - u'tpaint', 09:43:15 INFO - u'sessionrestore', 09:43:15 INFO - u'sessionrestore_no_auto_restore']}, 09:43:15 INFO - u'other_nol64': {u'tests': [u'a11yr', 09:43:15 INFO - u'ts_paint', 09:43:15 INFO - u'tpaint', 09:43:15 INFO - u'sessionrestore', 09:43:15 INFO - u'sessionrestore_no_auto_restore']}, 09:43:15 INFO - u'svgr': {u'tests': [u'tsvgx', 09:43:15 INFO - u'tsvgr_opacity', 09:43:15 INFO - u'tart', 09:43:15 INFO - u'tscrollx', 09:43:15 INFO - u'cart']}, 09:43:15 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 09:43:15 INFO - u'tests': [u'tsvgx', 09:43:15 INFO - u'tsvgr_opacity', 09:43:15 INFO - u'tart', 09:43:15 INFO - u'tscrollx', 09:43:15 INFO - u'cart']}, 09:43:15 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:15 INFO - u'tests': [u'tp5o']}, 09:43:15 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:15 INFO - u'talos_options': [u'--e10s'], 09:43:15 INFO - u'tests': [u'tp5o']}, 09:43:15 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:15 INFO - u'talos_options': [u'--xperf_path', 09:43:15 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:43:15 INFO - u'tests': [u'tp5n']}, 09:43:15 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:15 INFO - u'talos_options': [u'--e10s', 09:43:15 INFO - u'--xperf_path', 09:43:15 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 09:43:15 INFO - u'C:/slave/talos-data/talos/xperf.config'], 09:43:15 INFO - u'tests': [u'tp5n']}}, 09:43:15 INFO - u'talos.zip': {u'path': u'', 09:43:15 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:43:15 INFO - Running post-action listener: _resource_record_post_action 09:43:15 INFO - ##### 09:43:15 INFO - ##### Running create-virtualenv step. 09:43:15 INFO - ##### 09:43:15 INFO - Running pre-action listener: _resource_record_pre_action 09:43:15 INFO - Running main action method: create_virtualenv 09:43:15 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 09:43:15 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 09:43:15 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 09:43:15 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'! 09:43:15 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 09:43:15 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 09:43:17 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 09:43:20 INFO - Installing distribute......................................................................................................................................................................................done. 09:43:21 INFO - Return code: 0 09:43:21 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 09:43:21 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')]} 09:43:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:21 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')]} 09:43:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:21 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 0x01998B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0134E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268B48>, '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 0x0128A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A19660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-120', 'USERDOMAIN': 'T-W732-IX-120', '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-120', '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 09:43:21 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 09:43:21 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 09:43:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 09:43:21 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 09:43:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 09:43:21 INFO - 'COMPUTERNAME': 'T-W732-IX-120', 09:43:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 09:43:21 INFO - 'CYGWINBASE': 'C:\\cygwin', 09:43:21 INFO - 'DCLOCATION': 'SCL3', 09:43:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 09:43:21 INFO - 'FP_NO_HOST_CHECK': 'NO', 09:43:21 INFO - 'HOMEDRIVE': 'C:', 09:43:21 INFO - 'HOMEPATH': '\\Users\\cltbld', 09:43:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 09:43:21 INFO - 'KTS_VERSION': '1.19c', 09:43:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 09:43:21 INFO - 'LOGONSERVER': '\\\\T-W732-IX-120', 09:43:21 INFO - 'MONDIR': 'C:\\Monitor_config\\', 09:43:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 09:43:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:21 INFO - 'MOZ_NO_REMOTE': '1', 09:43:21 INFO - 'NO_EM_RESTART': '1', 09:43:21 INFO - 'NUMBER_OF_PROCESSORS': '8', 09:43:21 INFO - 'OS': 'Windows_NT', 09:43:21 INFO - 'OURDRIVE': 'C:', 09:43:21 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;', 09:43:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 09:43:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 09:43:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 09:43:21 INFO - 'PROCESSOR_LEVEL': '6', 09:43:21 INFO - 'PROCESSOR_REVISION': '1e05', 09:43:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 09:43:21 INFO - 'PROGRAMFILES': 'C:\\Program Files', 09:43:21 INFO - 'PROMPT': '$P$G', 09:43:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 09:43:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 09:43:21 INFO - 'PUBLIC': 'C:\\Users\\Public', 09:43:21 INFO - 'PWD': 'C:\\slave\\test-pgo', 09:43:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 09:43:21 INFO - 'SLAVEDIR': 'C:\\slave\\', 09:43:21 INFO - 'SYSTEMDRIVE': 'C:', 09:43:21 INFO - 'SYSTEMROOT': 'C:\\windows', 09:43:21 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 09:43:21 INFO - 'TEST1': 'testie', 09:43:21 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 09:43:21 INFO - 'USERDOMAIN': 'T-W732-IX-120', 09:43:21 INFO - 'USERNAME': 'cltbld', 09:43:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 09:43:21 INFO - 'WINDIR': 'C:\\windows', 09:43:21 INFO - 'WINDOWS_TRACING_FLAGS': '3', 09:43:21 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 09:43:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 09:43:23 INFO - Ignoring indexes: http://pypi.python.org/simple/ 09:43:23 INFO - Downloading/unpacking pip>=1.5 09:43:23 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache 09:43:28 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 09:43:28 INFO - Running setup.py egg_info for package pip 09:43:28 INFO - warning: no files found matching 'pip\cacert.pem' 09:43:28 INFO - warning: no files found matching '*.html' under directory 'docs' 09:43:28 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 09:43:28 INFO - no previously-included directories found matching 'docs\_build\_sources' 09:43:28 INFO - Installing collected packages: pip 09:43:28 INFO - Found existing installation: pip 0.8.2 09:43:28 INFO - Uninstalling pip: 09:43:28 INFO - Successfully uninstalled pip 09:43:28 INFO - Running setup.py install for pip 09:43:28 INFO - warning: no files found matching 'pip\cacert.pem' 09:43:28 INFO - warning: no files found matching '*.html' under directory 'docs' 09:43:28 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 09:43:28 INFO - no previously-included directories found matching 'docs\_build\_sources' 09:43:28 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:28 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:28 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:28 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:28 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:28 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:28 INFO - Successfully installed pip 09:43:28 INFO - Cleaning up... 09:43:28 INFO - Return code: 0 09:43:28 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 09:43:28 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')]} 09:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:28 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')]} 09:43:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:28 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 0x01998B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0134E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268B48>, '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 0x0128A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A19660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-120', 'USERDOMAIN': 'T-W732-IX-120', '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-120', '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 09:43:28 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 09:43:28 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 09:43:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 09:43:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 09:43:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 09:43:28 INFO - 'COMPUTERNAME': 'T-W732-IX-120', 09:43:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 09:43:28 INFO - 'CYGWINBASE': 'C:\\cygwin', 09:43:28 INFO - 'DCLOCATION': 'SCL3', 09:43:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 09:43:28 INFO - 'FP_NO_HOST_CHECK': 'NO', 09:43:28 INFO - 'HOMEDRIVE': 'C:', 09:43:28 INFO - 'HOMEPATH': '\\Users\\cltbld', 09:43:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 09:43:28 INFO - 'KTS_VERSION': '1.19c', 09:43:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 09:43:28 INFO - 'LOGONSERVER': '\\\\T-W732-IX-120', 09:43:28 INFO - 'MONDIR': 'C:\\Monitor_config\\', 09:43:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 09:43:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:28 INFO - 'MOZ_NO_REMOTE': '1', 09:43:28 INFO - 'NO_EM_RESTART': '1', 09:43:28 INFO - 'NUMBER_OF_PROCESSORS': '8', 09:43:28 INFO - 'OS': 'Windows_NT', 09:43:28 INFO - 'OURDRIVE': 'C:', 09:43:28 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;', 09:43:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 09:43:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 09:43:28 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 09:43:28 INFO - 'PROCESSOR_LEVEL': '6', 09:43:28 INFO - 'PROCESSOR_REVISION': '1e05', 09:43:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 09:43:28 INFO - 'PROGRAMFILES': 'C:\\Program Files', 09:43:28 INFO - 'PROMPT': '$P$G', 09:43:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 09:43:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 09:43:28 INFO - 'PUBLIC': 'C:\\Users\\Public', 09:43:28 INFO - 'PWD': 'C:\\slave\\test-pgo', 09:43:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 09:43:28 INFO - 'SLAVEDIR': 'C:\\slave\\', 09:43:28 INFO - 'SYSTEMDRIVE': 'C:', 09:43:28 INFO - 'SYSTEMROOT': 'C:\\windows', 09:43:28 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 09:43:28 INFO - 'TEST1': 'testie', 09:43:28 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 09:43:28 INFO - 'USERDOMAIN': 'T-W732-IX-120', 09:43:28 INFO - 'USERNAME': 'cltbld', 09:43:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 09:43:28 INFO - 'WINDIR': 'C:\\windows', 09:43:28 INFO - 'WINDOWS_TRACING_FLAGS': '3', 09:43:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 09:43:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 09:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:29 INFO - Downloading/unpacking psutil>=0.7.1 09:43:29 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 09:43:29 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 09:43:33 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 09:43:33 INFO - Installing collected packages: psutil 09:43:33 INFO - Successfully installed psutil 09:43:33 INFO - Cleaning up... 09:43:33 INFO - Return code: 0 09:43:33 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 09:43: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')]} 09:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43: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')]} 09:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43: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', '--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 0x01998B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0134E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268B48>, '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 0x0128A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A19660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-120', 'USERDOMAIN': 'T-W732-IX-120', '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-120', '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 09:43:33 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 09:43:33 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 09:43:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 09:43:33 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 09:43:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 09:43:33 INFO - 'COMPUTERNAME': 'T-W732-IX-120', 09:43:33 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 09:43:33 INFO - 'CYGWINBASE': 'C:\\cygwin', 09:43:33 INFO - 'DCLOCATION': 'SCL3', 09:43:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 09:43:33 INFO - 'FP_NO_HOST_CHECK': 'NO', 09:43:33 INFO - 'HOMEDRIVE': 'C:', 09:43:33 INFO - 'HOMEPATH': '\\Users\\cltbld', 09:43:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 09:43:33 INFO - 'KTS_VERSION': '1.19c', 09:43:33 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 09:43:33 INFO - 'LOGONSERVER': '\\\\T-W732-IX-120', 09:43:33 INFO - 'MONDIR': 'C:\\Monitor_config\\', 09:43:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 09:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:33 INFO - 'MOZ_NO_REMOTE': '1', 09:43:33 INFO - 'NO_EM_RESTART': '1', 09:43:33 INFO - 'NUMBER_OF_PROCESSORS': '8', 09:43:33 INFO - 'OS': 'Windows_NT', 09:43:33 INFO - 'OURDRIVE': 'C:', 09:43: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;', 09:43:33 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 09:43:33 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 09:43:33 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 09:43:33 INFO - 'PROCESSOR_LEVEL': '6', 09:43:33 INFO - 'PROCESSOR_REVISION': '1e05', 09:43:33 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 09:43:33 INFO - 'PROGRAMFILES': 'C:\\Program Files', 09:43:33 INFO - 'PROMPT': '$P$G', 09:43:33 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 09:43:33 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 09:43:33 INFO - 'PUBLIC': 'C:\\Users\\Public', 09:43:33 INFO - 'PWD': 'C:\\slave\\test-pgo', 09:43:33 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 09:43:33 INFO - 'SLAVEDIR': 'C:\\slave\\', 09:43:33 INFO - 'SYSTEMDRIVE': 'C:', 09:43:33 INFO - 'SYSTEMROOT': 'C:\\windows', 09:43:33 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 09:43:33 INFO - 'TEST1': 'testie', 09:43:33 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 09:43:33 INFO - 'USERDOMAIN': 'T-W732-IX-120', 09:43:33 INFO - 'USERNAME': 'cltbld', 09:43:33 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 09:43:33 INFO - 'WINDIR': 'C:\\windows', 09:43:33 INFO - 'WINDOWS_TRACING_FLAGS': '3', 09:43:33 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 09:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 09:43:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:33 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:43:33 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 09:43:33 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 09:43:35 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:43:35 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 09:43:35 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 09:43:36 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) 09:43:36 INFO - Installing collected packages: mozsystemmonitor 09:43:36 INFO - Running setup.py install for mozsystemmonitor 09:43:36 INFO - Successfully installed mozsystemmonitor 09:43:36 INFO - Cleaning up... 09:43:36 INFO - Return code: 0 09:43:36 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 09:43:36 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')]} 09:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:36 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')]} 09:43:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:36 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 0x01998B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0134E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268B48>, '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 0x0128A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A19660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-120', 'USERDOMAIN': 'T-W732-IX-120', '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-120', '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 09:43:36 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 09:43:36 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 09:43:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 09:43:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 09:43:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 09:43:36 INFO - 'COMPUTERNAME': 'T-W732-IX-120', 09:43:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 09:43:36 INFO - 'CYGWINBASE': 'C:\\cygwin', 09:43:36 INFO - 'DCLOCATION': 'SCL3', 09:43:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 09:43:36 INFO - 'FP_NO_HOST_CHECK': 'NO', 09:43:36 INFO - 'HOMEDRIVE': 'C:', 09:43:36 INFO - 'HOMEPATH': '\\Users\\cltbld', 09:43:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 09:43:36 INFO - 'KTS_VERSION': '1.19c', 09:43:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 09:43:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-120', 09:43:36 INFO - 'MONDIR': 'C:\\Monitor_config\\', 09:43:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 09:43:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:36 INFO - 'MOZ_NO_REMOTE': '1', 09:43:36 INFO - 'NO_EM_RESTART': '1', 09:43:36 INFO - 'NUMBER_OF_PROCESSORS': '8', 09:43:36 INFO - 'OS': 'Windows_NT', 09:43:36 INFO - 'OURDRIVE': 'C:', 09:43:36 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;', 09:43:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 09:43:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 09:43:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 09:43:36 INFO - 'PROCESSOR_LEVEL': '6', 09:43:36 INFO - 'PROCESSOR_REVISION': '1e05', 09:43:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 09:43:36 INFO - 'PROGRAMFILES': 'C:\\Program Files', 09:43:36 INFO - 'PROMPT': '$P$G', 09:43:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 09:43:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 09:43:36 INFO - 'PUBLIC': 'C:\\Users\\Public', 09:43:36 INFO - 'PWD': 'C:\\slave\\test-pgo', 09:43:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 09:43:36 INFO - 'SLAVEDIR': 'C:\\slave\\', 09:43:36 INFO - 'SYSTEMDRIVE': 'C:', 09:43:36 INFO - 'SYSTEMROOT': 'C:\\windows', 09:43:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 09:43:36 INFO - 'TEST1': 'testie', 09:43:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 09:43:36 INFO - 'USERDOMAIN': 'T-W732-IX-120', 09:43:36 INFO - 'USERNAME': 'cltbld', 09:43:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 09:43:36 INFO - 'WINDIR': 'C:\\windows', 09:43:36 INFO - 'WINDOWS_TRACING_FLAGS': '3', 09:43:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 09:43:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 09:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:37 INFO - Downloading/unpacking blobuploader==1.2.4 09:43:37 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 09:43:37 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 09:43:40 INFO - Downloading blobuploader-1.2.4.tar.gz 09:43:40 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 09:43:40 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 09:43:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:43:40 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 09:43:40 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 09:43:43 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 09:43:43 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 09:43:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:43:43 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 09:43:43 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 09:43:43 INFO - Downloading docopt-0.6.1.tar.gz 09:43:43 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 09:43:43 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 09:43:43 INFO - Installing collected packages: blobuploader, requests, docopt 09:43:43 INFO - Running setup.py install for blobuploader 09:43:44 INFO - Running setup.py install for requests 09:43:44 INFO - Running setup.py install for docopt 09:43:45 INFO - Successfully installed blobuploader requests docopt 09:43:45 INFO - Cleaning up... 09:43:46 INFO - Return code: 0 09:43:46 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 09:43:46 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')]} 09:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:46 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')]} 09:43:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:46 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 0x01998B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0134E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268B48>, '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 0x0128A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A19660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-120', 'USERDOMAIN': 'T-W732-IX-120', '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-120', '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 09:43:46 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 09:43:46 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 09:43:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 09:43:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 09:43:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 09:43:46 INFO - 'COMPUTERNAME': 'T-W732-IX-120', 09:43:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 09:43:46 INFO - 'CYGWINBASE': 'C:\\cygwin', 09:43:46 INFO - 'DCLOCATION': 'SCL3', 09:43:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 09:43:46 INFO - 'FP_NO_HOST_CHECK': 'NO', 09:43:46 INFO - 'HOMEDRIVE': 'C:', 09:43:46 INFO - 'HOMEPATH': '\\Users\\cltbld', 09:43:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 09:43:46 INFO - 'KTS_VERSION': '1.19c', 09:43:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 09:43:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-120', 09:43:46 INFO - 'MONDIR': 'C:\\Monitor_config\\', 09:43:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 09:43:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:46 INFO - 'MOZ_NO_REMOTE': '1', 09:43:46 INFO - 'NO_EM_RESTART': '1', 09:43:46 INFO - 'NUMBER_OF_PROCESSORS': '8', 09:43:46 INFO - 'OS': 'Windows_NT', 09:43:46 INFO - 'OURDRIVE': 'C:', 09:43: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;', 09:43:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 09:43:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 09:43:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 09:43:46 INFO - 'PROCESSOR_LEVEL': '6', 09:43:46 INFO - 'PROCESSOR_REVISION': '1e05', 09:43:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 09:43:46 INFO - 'PROGRAMFILES': 'C:\\Program Files', 09:43:46 INFO - 'PROMPT': '$P$G', 09:43:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 09:43:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 09:43:46 INFO - 'PUBLIC': 'C:\\Users\\Public', 09:43:46 INFO - 'PWD': 'C:\\slave\\test-pgo', 09:43:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 09:43:46 INFO - 'SLAVEDIR': 'C:\\slave\\', 09:43:46 INFO - 'SYSTEMDRIVE': 'C:', 09:43:46 INFO - 'SYSTEMROOT': 'C:\\windows', 09:43:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 09:43:46 INFO - 'TEST1': 'testie', 09:43:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 09:43:46 INFO - 'USERDOMAIN': 'T-W732-IX-120', 09:43:46 INFO - 'USERNAME': 'cltbld', 09:43:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 09:43:46 INFO - 'WINDIR': 'C:\\windows', 09:43:46 INFO - 'WINDOWS_TRACING_FLAGS': '3', 09:43:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 09:43:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 09:43:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:46 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 09:43:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rqio3l-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 09:43:46 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 09:43:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v7p_so-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 09:43:46 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 09:43:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dhatgi-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 09:43:46 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 09:43:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ekhswi-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 09:43:47 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 09:43:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y6fpg8-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 09:43:47 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 09:43:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-reeaxh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 09:43:47 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 09:43:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fzx0yf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 09:43:47 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 09:43:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fpwgzs-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 09:43:47 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 09:43:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rw1kde-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 09:43:47 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 09:43:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ges1cz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 09:43:47 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 09:43:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ostiak-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 09:43:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 09:43:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uhx1hq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 09:43:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 09:43:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3l4jls-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 09:43:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 09:43:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vsgsd4-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 09:43:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 09:43:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3kqkvv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:43:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 09:43:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-980wwc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 09:43:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 09:43:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k6i4ju-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 09:43:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:43:48 INFO - Running setup.py install for manifestparser 09:43:49 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:49 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:49 INFO - Running setup.py install for mozcrash 09:43:49 INFO - Running setup.py install for mozdebug 09:43:50 INFO - Running setup.py install for mozdevice 09:43:50 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:50 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:50 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:50 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:50 INFO - Running setup.py install for mozfile 09:43:51 INFO - Running setup.py install for mozhttpd 09:43:51 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:51 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:51 INFO - Running setup.py install for mozinfo 09:43:51 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:51 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:51 INFO - Running setup.py install for mozInstall 09:43:51 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:51 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:51 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:51 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:51 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:51 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:51 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:51 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:52 INFO - Running setup.py install for mozleak 09:43:52 INFO - Running setup.py install for mozlog 09:43:52 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:52 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:53 INFO - Running setup.py install for moznetwork 09:43:53 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:53 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:53 INFO - Running setup.py install for mozprocess 09:43:54 INFO - Running setup.py install for mozprofile 09:43:54 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:54 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:54 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:54 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:54 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:54 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:54 INFO - Running setup.py install for mozrunner 09:43:55 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:55 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:55 INFO - Running setup.py install for mozscreenshot 09:43:55 INFO - Running setup.py install for moztest 09:43:56 INFO - Running setup.py install for mozversion 09:43:56 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 09:43:56 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 09:43:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:43:56 INFO - Cleaning up... 09:43:56 INFO - Return code: 0 09:43:56 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 09:43:56 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')]} 09:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:56 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')]} 09:43:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:56 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 0x01998B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0134E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268B48>, '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 0x0128A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A19660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-120', 'USERDOMAIN': 'T-W732-IX-120', '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-120', '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 09:43:56 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 09:43:56 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 09:43:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 09:43:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 09:43:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 09:43:56 INFO - 'COMPUTERNAME': 'T-W732-IX-120', 09:43:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 09:43:56 INFO - 'CYGWINBASE': 'C:\\cygwin', 09:43:56 INFO - 'DCLOCATION': 'SCL3', 09:43:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 09:43:56 INFO - 'FP_NO_HOST_CHECK': 'NO', 09:43:56 INFO - 'HOMEDRIVE': 'C:', 09:43:56 INFO - 'HOMEPATH': '\\Users\\cltbld', 09:43:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 09:43:56 INFO - 'KTS_VERSION': '1.19c', 09:43:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 09:43:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-120', 09:43:56 INFO - 'MONDIR': 'C:\\Monitor_config\\', 09:43:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 09:43:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:56 INFO - 'MOZ_NO_REMOTE': '1', 09:43:56 INFO - 'NO_EM_RESTART': '1', 09:43:56 INFO - 'NUMBER_OF_PROCESSORS': '8', 09:43:56 INFO - 'OS': 'Windows_NT', 09:43:56 INFO - 'OURDRIVE': 'C:', 09:43:56 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;', 09:43:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 09:43:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 09:43:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 09:43:56 INFO - 'PROCESSOR_LEVEL': '6', 09:43:56 INFO - 'PROCESSOR_REVISION': '1e05', 09:43:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 09:43:56 INFO - 'PROGRAMFILES': 'C:\\Program Files', 09:43:56 INFO - 'PROMPT': '$P$G', 09:43:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 09:43:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 09:43:56 INFO - 'PUBLIC': 'C:\\Users\\Public', 09:43:56 INFO - 'PWD': 'C:\\slave\\test-pgo', 09:43:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 09:43:56 INFO - 'SLAVEDIR': 'C:\\slave\\', 09:43:56 INFO - 'SYSTEMDRIVE': 'C:', 09:43:56 INFO - 'SYSTEMROOT': 'C:\\windows', 09:43:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 09:43:56 INFO - 'TEST1': 'testie', 09:43:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 09:43:56 INFO - 'USERDOMAIN': 'T-W732-IX-120', 09:43:56 INFO - 'USERNAME': 'cltbld', 09:43:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 09:43:56 INFO - 'WINDIR': 'C:\\windows', 09:43:56 INFO - 'WINDOWS_TRACING_FLAGS': '3', 09:43:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 09:43:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 09:43:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 09:43:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-frfnov-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 09:43:57 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)) 09:43:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 09:43:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ls5ivg-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 09:43:57 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)) 09:43:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 09:43:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sahpci-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 09:43:57 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)) 09:43:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 09:43:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1ozfm1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 09:43:57 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)) 09:43:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 09:43:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1qwdgk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 09:43:57 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)) 09:43:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 09:43:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ncpeo4-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 09:43:58 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)) 09:43:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 09:43:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xiitac-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 09:43:58 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)) 09:43:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 09:43:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_ju1fm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 09:43:58 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)) 09:43:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 09:43:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3dcyeg-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 09:43:58 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)) 09:43:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 09:43:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xv6u7s-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 09:43:58 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)) 09:43:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 09:43:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xnua5t-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 09:43:58 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)) 09:43:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 09:43:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hojuzl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 09:43:58 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)) 09:43:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 09:43:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3gdmen-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 09:43:59 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)) 09:43:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 09:43:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y4g9ae-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 09:43:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14)) 09:43:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 09:43:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n7qkqv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:43:59 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)) 09:43:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 09:43:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-p3edcn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 09:43:59 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)) 09:43:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 09:43:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hybeuz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 09:43:59 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)) 09:43:59 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)) 09:43:59 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)) 09:43:59 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)) 09:43:59 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)) 09:43:59 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)) 09:43:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 09:43:59 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 09:43:59 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 09:44:00 INFO - Downloading blessings-1.5.1.tar.gz 09:44:00 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 09:44:00 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 09:44:01 INFO - Installing collected packages: blessings 09:44:01 INFO - Running setup.py install for blessings 09:44:01 INFO - Successfully installed blessings 09:44:01 INFO - Cleaning up... 09:44:03 INFO - Return code: 0 09:44:03 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 09:44:03 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 09:44:03 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 09:44:04 INFO - Reading from file tmpfile_stdout 09:44:04 INFO - Using _rmtree_windows ... 09:44:04 INFO - Using _rmtree_windows ... 09:44:04 INFO - Current package versions: 09:44:04 INFO - blessings == 1.5.1 09:44:04 INFO - blobuploader == 1.2.4 09:44:04 INFO - docopt == 0.6.1 09:44:04 INFO - manifestparser == 1.1 09:44:04 INFO - mozInstall == 1.12 09:44:04 INFO - mozcrash == 0.16 09:44:04 INFO - mozdebug == 0.1 09:44:04 INFO - mozdevice == 0.46 09:44:04 INFO - mozfile == 1.2 09:44:04 INFO - mozhttpd == 0.7 09:44:04 INFO - mozinfo == 0.8 09:44:04 INFO - mozleak == 0.1 09:44:04 INFO - mozlog == 3.0 09:44:04 INFO - moznetwork == 0.27 09:44:04 INFO - mozprocess == 0.22 09:44:04 INFO - mozprofile == 0.27 09:44:04 INFO - mozrunner == 6.11 09:44:04 INFO - mozscreenshot == 0.1 09:44:04 INFO - mozsystemmonitor == 0.0 09:44:04 INFO - moztest == 0.7 09:44:04 INFO - mozversion == 1.4 09:44:04 INFO - psutil == 3.1.1 09:44:04 INFO - requests == 1.2.3 09:44:04 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 09:44:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:04 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 0x01998B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0134E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268B48>, '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 0x0128A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A19660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-120', 'USERDOMAIN': 'T-W732-IX-120', '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-120', '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 09:44:04 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 09:44:04 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 09:44:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 09:44:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 09:44:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 09:44:04 INFO - 'COMPUTERNAME': 'T-W732-IX-120', 09:44:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 09:44:04 INFO - 'CYGWINBASE': 'C:\\cygwin', 09:44:04 INFO - 'DCLOCATION': 'SCL3', 09:44:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 09:44:04 INFO - 'FP_NO_HOST_CHECK': 'NO', 09:44:04 INFO - 'HOMEDRIVE': 'C:', 09:44:04 INFO - 'HOMEPATH': '\\Users\\cltbld', 09:44:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 09:44:04 INFO - 'KTS_VERSION': '1.19c', 09:44:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 09:44:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-120', 09:44:04 INFO - 'MONDIR': 'C:\\Monitor_config\\', 09:44:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 09:44:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:04 INFO - 'MOZ_NO_REMOTE': '1', 09:44:04 INFO - 'NO_EM_RESTART': '1', 09:44:04 INFO - 'NUMBER_OF_PROCESSORS': '8', 09:44:04 INFO - 'OS': 'Windows_NT', 09:44:04 INFO - 'OURDRIVE': 'C:', 09:44:04 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 09:44:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 09:44:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 09:44:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 09:44:04 INFO - 'PROCESSOR_LEVEL': '6', 09:44:04 INFO - 'PROCESSOR_REVISION': '1e05', 09:44:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 09:44:04 INFO - 'PROGRAMFILES': 'C:\\Program Files', 09:44:04 INFO - 'PROMPT': '$P$G', 09:44:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 09:44:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 09:44:04 INFO - 'PUBLIC': 'C:\\Users\\Public', 09:44:04 INFO - 'PWD': 'C:\\slave\\test-pgo', 09:44:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 09:44:04 INFO - 'SLAVEDIR': 'C:\\slave\\', 09:44:04 INFO - 'SYSTEMDRIVE': 'C:', 09:44:04 INFO - 'SYSTEMROOT': 'C:\\windows', 09:44:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 09:44:04 INFO - 'TEST1': 'testie', 09:44:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 09:44:04 INFO - 'USERDOMAIN': 'T-W732-IX-120', 09:44:04 INFO - 'USERNAME': 'cltbld', 09:44:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 09:44:04 INFO - 'WINDIR': 'C:\\windows', 09:44:04 INFO - 'WINDOWS_TRACING_FLAGS': '3', 09:44:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 09:44:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 09:44:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:04 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)) 09:44:04 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)) 09:44:04 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)) 09:44:04 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)) 09:44:04 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)) 09:44:04 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)) 09:44:04 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)) 09:44:04 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)) 09:44:04 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)) 09:44:04 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)) 09:44:04 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)) 09:44:04 INFO - Cleaning up... 09:44:04 INFO - Return code: 0 09:44:04 INFO - Running post-action listener: _resource_record_post_action 09:44:04 INFO - Running post-action listener: _start_resource_monitoring 09:44:04 INFO - Starting resource monitoring. 09:44:04 INFO - ##### 09:44:04 INFO - ##### Running install step. 09:44:04 INFO - ##### 09:44:04 INFO - Running pre-action listener: _resource_record_pre_action 09:44:04 INFO - Running main action method: install 09:44:04 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 09:44:04 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 09:44:05 INFO - Reading from file tmpfile_stdout 09:44:05 INFO - Using _rmtree_windows ... 09:44:05 INFO - Using _rmtree_windows ... 09:44:05 INFO - Detecting whether we're running mozinstall >=1.0... 09:44:05 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 09:44:05 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 09:44:05 INFO - Reading from file tmpfile_stdout 09:44:05 INFO - Output received: 09:44:05 INFO - Usage: mozinstall-script.py [options] installer 09:44:05 INFO - Options: 09:44:05 INFO - -h, --help show this help message and exit 09:44:05 INFO - -d DEST, --destination=DEST 09:44:05 INFO - Directory to install application into. [default: 09:44:05 INFO - "C:\slave\test-pgo"] 09:44:05 INFO - --app=APP Application being installed. [default: firefox] 09:44:05 INFO - Using _rmtree_windows ... 09:44:05 INFO - Using _rmtree_windows ... 09:44:05 INFO - mkdir: C:\slave\test-pgo\build\application 09:44:05 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'] 09:44:05 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 09:44:07 INFO - Reading from file tmpfile_stdout 09:44:07 INFO - Output received: 09:44:07 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 09:44:07 INFO - Using _rmtree_windows ... 09:44:07 INFO - Using _rmtree_windows ... 09:44:07 INFO - Running post-action listener: _resource_record_post_action 09:44:07 INFO - ##### 09:44:07 INFO - ##### Running run-tests step. 09:44:07 INFO - ##### 09:44:07 INFO - Running pre-action listener: _resource_record_pre_action 09:44:07 INFO - Running main action method: run_tests 09:44:07 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 09:44:07 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 09:44:07 INFO - Python 2.7.3 09:44:07 INFO - Return code: 0 09:44:07 INFO - grabbing minidump binary from tooltool 09:44:07 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')]} 09:44:07 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 0x01268B48>, '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 0x0128A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A19660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 09:44:07 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 09:44:07 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 09:44:07 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 09:44:11 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpdozzrp 09:44:11 INFO - INFO - File integrity verified, renaming tmpdozzrp to win32-minidump_stackwalk.exe 09:44:11 INFO - Return code: 0 09:44:11 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 09:44:11 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 09:44:11 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 09:44:11 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 09:44:11 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 09:44:11 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-120', '--webServer', 'localhost'] in C:\slave\test-pgo\build 09:44:11 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName Firefox --suite dromaeojs --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-120 --webServer localhost 09:44:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 09:44:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 09:44:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 09:44:11 INFO - 'COMPUTERNAME': 'T-W732-IX-120', 09:44:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 09:44:11 INFO - 'CYGWINBASE': 'C:\\cygwin', 09:44:11 INFO - 'DCLOCATION': 'SCL3', 09:44:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 09:44:11 INFO - 'FP_NO_HOST_CHECK': 'NO', 09:44:11 INFO - 'HOMEDRIVE': 'C:', 09:44:11 INFO - 'HOMEPATH': '\\Users\\cltbld', 09:44:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 09:44:11 INFO - 'KTS_VERSION': '1.19c', 09:44:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 09:44:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-120', 09:44:11 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 09:44:11 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 09:44:11 INFO - 'MONDIR': 'C:\\Monitor_config\\', 09:44:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 09:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:11 INFO - 'MOZ_NO_REMOTE': '1', 09:44:11 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 09:44:11 INFO - 'NO_EM_RESTART': '1', 09:44:11 INFO - 'NUMBER_OF_PROCESSORS': '8', 09:44:11 INFO - 'OS': 'Windows_NT', 09:44:11 INFO - 'OURDRIVE': 'C:', 09:44: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;', 09:44:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 09:44:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 09:44:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 09:44:11 INFO - 'PROCESSOR_LEVEL': '6', 09:44:11 INFO - 'PROCESSOR_REVISION': '1e05', 09:44:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 09:44:11 INFO - 'PROGRAMFILES': 'C:\\Program Files', 09:44:11 INFO - 'PROMPT': '$P$G', 09:44:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 09:44:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 09:44:11 INFO - 'PUBLIC': 'C:\\Users\\Public', 09:44:11 INFO - 'PWD': 'C:\\slave\\test-pgo', 09:44:11 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 09:44:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 09:44:11 INFO - 'SLAVEDIR': 'C:\\slave\\', 09:44:11 INFO - 'SYSTEMDRIVE': 'C:', 09:44:11 INFO - 'SYSTEMROOT': 'C:\\windows', 09:44:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 09:44:11 INFO - 'TEST1': 'testie', 09:44:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 09:44:11 INFO - 'USERDOMAIN': 'T-W732-IX-120', 09:44:11 INFO - 'USERNAME': 'cltbld', 09:44:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 09:44:11 INFO - 'WINDIR': 'C:\\windows', 09:44:11 INFO - 'WINDOWS_TRACING_FLAGS': '3', 09:44:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 09:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 09:44:11 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-120', '--webServer', 'localhost'] with output_timeout 3600 09:44:12 INFO - mozversion INFO | application_buildid: 20151026043008 09:44:12 INFO - mozversion INFO | application_changeset: 5ca03a00d26823ce91ee0eaa2937bed605bd53c1 09:44:12 INFO - mozversion INFO | application_display_name: Nightly 09:44:12 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:44:12 INFO - mozversion INFO | application_name: Firefox 09:44:12 INFO - mozversion INFO | application_remotingname: firefox 09:44:12 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 09:44:12 INFO - mozversion INFO | application_vendor: Mozilla 09:44:12 INFO - mozversion INFO | application_version: 44.0a1 09:44:12 INFO - mozversion INFO | platform_buildid: 20151026043008 09:44:12 INFO - mozversion INFO | platform_changeset: 5ca03a00d26823ce91ee0eaa2937bed605bd53c1 09:44:12 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 09:44:12 INFO - mozversion INFO | platform_version: 44.0a1 09:44:12 INFO - 2015-10-26 09:44:12,947 DEBUG : using testdate: 1445877852 09:44:12 INFO - 2015-10-26 09:44:12,947 DEBUG : actual date: 1445877852 09:44:13 INFO - 2015-10-26 09:44:13,421 INFO : starting webserver on 'localhost:49849' 09:44:13 INFO - 2015-10-26 09:44:13,421 INFO : Starting test suite T-W732-IX-120 09:44:13 INFO - 2015-10-26 09:44:13,421 INFO : Starting test dromaeo_css 09:44:13 INFO - 2015-10-26 09:44:13,421 DEBUG : operating with platform_type : w7_ 09:44:13 INFO - 2015-10-26 09:44:13,423 INFO : Initialising browser for dromaeo_css test... 09:44:13 INFO - 2015-10-26 09:44:13,515 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpgy5f1g\profile http://localhost:49849/getInfo.html 09:44:21 INFO - 2015-10-26 09:44:21,296 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:44:21 INFO - 2015-10-26 09:44:21,296 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:44:21 INFO - 2015-10-26 09:44:21,296 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 09:44:21 INFO - 2015-10-26 09:44:21,296 DEBUG : BROWSER_OUTPUT: __metrics 09:44:21 INFO - 2015-10-26 09:44:21,371 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:44:22 INFO - 2015-10-26 09:44:22,766 DEBUG : BROWSER_OUTPUT: console.error: 09:44:22 INFO - 2015-10-26 09:44:22,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] 09:44:22 INFO - 2015-10-26 09:44:22,766 DEBUG : BROWSER_OUTPUT: Stack: 09:44:22 INFO - 2015-10-26 09:44:22,766 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 09:44:22 INFO - 2015-10-26 09:44:22,766 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 09:44:22 INFO - 2015-10-26 09:44:22,766 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 09:44:22 INFO - 2015-10-26 09:44:22,766 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 09:44:22 INFO - 2015-10-26 09:44:22,766 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 09:44:22 INFO - 2015-10-26 09:44:22,766 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 09:44:22 INFO - 2015-10-26 09:44:22,767 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 09:44:22 INFO - 2015-10-26 09:44:22,767 DEBUG : BROWSER_OUTPUT: setAncestorsLastModified<@resource://gre/modules/Bookmarks.jsm:1347:9 09:44:22 INFO - 2015-10-26 09:44:22,767 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 09:44:22 INFO - 2015-10-26 09:44:22,767 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 09:44:22 INFO - 2015-10-26 09:44:22,767 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 09:44:22 INFO - 2015-10-26 09:44:22,767 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:808:13 09:44:22 INFO - 2015-10-26 09:44:22,767 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 09:44:22 INFO - 2015-10-26 09:44:22,767 DEBUG : BROWSER_OUTPUT: Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:934:23 09:44:22 INFO - 2015-10-26 09:44:22,767 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 09:44:22 INFO - 2015-10-26 09:44:22,767 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 09:44:22 INFO - 2015-10-26 09:44:22,767 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 09:44:22 INFO - 2015-10-26 09:44:22,767 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 09:44:22 INFO - 2015-10-26 09:44:22,769 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 09:44:22 INFO - 2015-10-26 09:44:22,769 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 09:44:22 INFO - 2015-10-26 09:44:22,769 DEBUG : BROWSER_OUTPUT: 09:44:23 INFO - 2015-10-26 09:44:23,403 INFO : Browser initialized. 09:44:23 INFO - 2015-10-26 09:44:23,403 INFO : Running cycle 1/1 for dromaeo_css test... 09:44:23 INFO - 2015-10-26 09:44:23,403 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpgy5f1g\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:44:34 INFO - 2015-10-26 09:44:34,806 DEBUG : BROWSER_OUTPUT: RSS: Main: 127496192 09:44:34 INFO - 2015-10-26 09:44:34,806 DEBUG : BROWSER_OUTPUT: 09:45:26 INFO - 2015-10-26 09:45:26,246 DEBUG : BROWSER_OUTPUT: 1445877926253 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] 09:47:35 INFO - 2015-10-26 09:47:35,006 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49849/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49849/tests/dromaeo/cssquery-ext.html) 09:47:35 INFO - 2015-10-26 09:47:35,960 DEBUG : BROWSER_OUTPUT: RSS: Main: 174354432 09:47:35 INFO - 2015-10-26 09:47:35,960 DEBUG : BROWSER_OUTPUT: 09:50:38 INFO - 2015-10-26 09:50:38,407 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49849/tests/dromaeo/cssquery-ext.html (next: http://localhost:49849/tests/dromaeo/cssquery-jquery.html) 09:50:38 INFO - 2015-10-26 09:50:38,967 DEBUG : BROWSER_OUTPUT: RSS: Main: 182435840 09:50:38 INFO - 2015-10-26 09:50:38,967 DEBUG : BROWSER_OUTPUT: 09:53:40 INFO - 2015-10-26 09:53:40,723 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49849/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49849/tests/dromaeo/cssquery-mootools.html) 09:53:41 INFO - 2015-10-26 09:53:41,282 DEBUG : BROWSER_OUTPUT: RSS: Main: 155000832 09:53:41 INFO - 2015-10-26 09:53:41,282 DEBUG : BROWSER_OUTPUT: 09:56:43 INFO - 2015-10-26 09:56:43,164 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49849/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49849/tests/dromaeo/cssquery-prototype.html) 09:56:43 INFO - 2015-10-26 09:56:43,763 DEBUG : BROWSER_OUTPUT: RSS: Main: 140062720 09:56:43 INFO - 2015-10-26 09:56:43,763 DEBUG : BROWSER_OUTPUT: 09:59:46 INFO - 2015-10-26 09:59:46,441 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49849/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49849/tests/dromaeo/cssquery-yui.html) 09:59:47 INFO - 2015-10-26 09:59:47,022 DEBUG : BROWSER_OUTPUT: RSS: Main: 131846144 09:59:47 INFO - 2015-10-26 09:59:47,022 DEBUG : BROWSER_OUTPUT: 10:02:49 INFO - 2015-10-26 10:02:49,486 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49849/tests/dromaeo/cssquery-yui.html (next: http://localhost:49849/tests/dromaeo/cssquery-dojo.html) 10:02:49 INFO - 2015-10-26 10:02:49,740 DEBUG : BROWSER_OUTPUT: RSS: Main: 187154432 10:02:49 INFO - 2015-10-26 10:02:49,740 DEBUG : BROWSER_OUTPUT: 10:02:49 INFO - 2015-10-26 10:02:49,740 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:02:49 INFO - 2015-10-26 10:02:49,740 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:02:49 INFO - 2015-10-26 10:02:49,740 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:02:49 INFO - 2015-10-26 10:02:49,742 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:02:49 INFO - 2015-10-26 10:02:49,742 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2679.423923856371;2713.8507382093976;2730.4675588260284;2746.24478268476;2757.3658058646515;5107.974460127703;5112.8977420451565;5143.434222235551;5232.084385232588;5247.1866860636665;5208.453112423198;5237.10969135247;5318.787248510064;5321.52106310432;5329.946700532993;4375.16871794358;4482.484514280851;4570.611498022656;4606.378138951232;4613.354130421743;4203.201391735561;4500.8874778130485;4502.572255635726;4552.749598772066;4571.771411429432;4548;4557.361969324297;4606.884827879297;4635.675502714811;4647.60495357895;4519.231730605772;4558.407407037097;4613.446386433616;4621.399218101659;4630.374899388573;4386.254336762758;4405;4483.641308695296;4530.864074077783;4535.501094879561;5152.252923326134;5181.533661970403;5286.418493965661;5289.814856480043;5311.575073994071;5305.257263983045;5423.647462914898;5471.480209380103;5576.9163462548095;5577.637453565505;5263.710495922723;5518.265488665957;5528.834134975917;5548.195511650788;5586.24585680932;5110.616703747197;5119.488051194925;5194.0131375039;5196.038732834438;5223.451537588537;5477.67133971963;5650.3219613646625;5657;5758.596898217168;5759.827205183851;5191.351081114861;5289.523942845162;5324.5207931285595;5419.051665958442;5432.6468779529205;5457.781688732406;5467.781288748485;5481.862953426116;5486.451354864562;5597.8600534986135;5291.867703307448;5444.428335024844;5527.281453411031;5546.195801608744;5548;5465.371482279509;5467.45325467458;5498.80754173602;5518.172274158908;5605.7197140142825;3440.415129428003;3443.328550932544;3517.1383011161915;3518.7888726676483;3525.629808870083;3051.0999255219763;3154;3164.47786115293;3200.759943004284;3213.614366276015;5060.544550990428;5097.719625420564;5208.635395522353;5211.2443695663915;5236.214567814858;5506.476884695948;5533.6956467393875;5560.360558535739;5632.098864181793;5632.831015069554;5085.74571271435;5111.974440127776;5138.845834624967;5273.024490469275;5279.41926388097;4247.851325203665;4287.742735435884;4298.763568003727;4366.781660917005;4384.868453946387;5696.316442026983;5790.565707571949;5857.7656893724625;5879.147523609138;5910.674912879746;5386.272853164773;5408.432114627907;5489.58828087887;5603.8879222416135;5621.634593751393;5654.519365853948;5694.686792226467;5704.629199102045;5808.6224395414165;5826.0387036140255;5302.125149350314;5396.9730151348995;5442.700651464049;5507.972460137674;5530.364008139116;5396.541293989898;5444.96167164299;5478.397376288684;5529.529989950899;5676.801311954062;5045.7981680732355;5263.499967503081;5329.067413202752;5372.785088596569;5471.097268950738;5321.973390133025;5373.811916582901;5456.290682211287;5505.6696598204235;5509.0359187141285;5072.54347108762;5134.024535338273;5234.764435600483;5244.76398562058;5372.758225879923;5353.2505449237915;5381.300430943952;5418.647787893774;5455.399906010415;5478.178273258884;5309.150535914235;5319.2287118368395;5341.225522299323;5395.3525576931015;5407.675539467645 10:02:49 INFO - 2015-10-26 10:02:49,742 DEBUG : BROWSER_OUTPUT: |1;ext.html;494.591961631654;502.239107751756;515.0188890164237;515.9664621799585;516.6848222584225;29055.854720726395;29258.853705731468;29376.559351609743;38532;38909.41635875464;39524.802375988154;39948.40077398841;40158.35011971234;40210;40305.39541906866;7061.717531298742;8273.544955027455;8411.747647570583;8425.957870210641;8434.746957591251;6998.090248267718;10441.582336706522;10750.462476876164;10768.300060496043;10785.083267922197;9735.610575577004;10243.795124097514;10449.477526123701;10569.788604227911;10579.312344697608;7254.92745072553;7407.518511296748;10371.792564148713;10521.631742889;10559.63041293551;10204.897951020468;10323.58705651773;10325.225608079349;10333.896661033445;10351.896481035245;37199.25601487955;37399.25201495982;37641;37712;37751.622483775085;3141.1361875484195;3163.335699503084;3344.3645707315777;3576.356255873942;3608.566971963355;29403.558946615816;29670.554941675677;29788.404231915458;29828.254293642614;30742.23144421385;2776.9028084016963;2829.405824776779;2853.629028226317;2858.3425812063133;2860.785441091906;326337;326572;326674;327068;327070.9504551585;52528.212076818876;52882.47117528853;52956.47043529592;53022.93954120973;53072;49128.50871491311;49652.26912519774;57348;57555.42444575501;58432;14298.28508574567;14509.419623215164;14523.34644940959;14535.636609084857;14540.63648408777;10725.356478611307;10747.462626868626;11096.944515277533;11123.443827808576;11152.888471115348;16638.500844974427;17208.116753298782;17221.56946076333;17263.913680431768;17322.82677173212;7240.710371585077;7357.926420735831;7420.777376678709;7423.888641670379;7477.252274772479;883.779055236191;904.0055938467633;921.9032001639865;940.3981451870808;1033.4625994482976;28572.71427285742;28916.819149893494;29422.852885735436;29489;29620.851895740383;966.40566051879;1026.6509386808373;1070.710908054821;1097.2045267181388;1139.9259048161844;13845.723085538233;14158.433662653379;14173.929130354281;14176.362063707158;14271.857281427052;308098;313784;314576;314911;315189;44537.55462445334;45870.31194532085;45874.311885321746;45881;45902.770486147354;1215.9817602735789;1216.4890745886748;1226.1907141286708;1226.2397313665406;1232.2914324263584;23580.8820955901;26150.73849261559;27434.45131097327;27780.44439111247;27953;44920.77539612412;45333.320000200685;45389.546104539855;45491;45635;319299;334992;338357;339061;339271;26133.346666333495;26993.73006269912;27427.35435873854;28000.579991300554;28120.43759124765;40654.59345406508;40964;41520.792396037825;41615.583844162386;41700.79149604354;33395.66604333925;33777.49333760045;35215.47176792299;35287.29425411529;35625.46561801627;30994.535081973336;36067.63932360748;37080.629193708795;37180.814095929345;37376.065598360256;34932.30135397227;34952.12619684528;36718.63281367259;36767.81616091902;37099.814500928405;36182;36728.449073263386;36879.81560092182;37507.43738843974;38063.04842378962;28898.711012889602;30598.235044124074;30710.078697639106;31470.213244669067;31475.84262078675 10:02:49 INFO - 2015-10-26 10:02:49,750 DEBUG : BROWSER_OUTPUT: |2;jquery.html;317.94753865612176;320.0095703796748;327.5185477348298;329.8218961760651;332.0453695625078;12567;17139.74290385645;17455.21451534681;17558.912205438988;18302.359417420394;13254.602361929134;13505.18968861869;13650.249236292;16178.35286588535;16398.508044758648;5244.921326180111;5491.313585801775;5506.504414602685;5523.502884740373;5523.585731070166;5398.379186393556;5465.262189604392;5476.2333273341765;5497.532709719679;5505.174223866432;4120.752754834704;4214.4099826024385;5441.319835020622;5734.79928202513;5948.672822994734;5860.501857342131;5869.354371019184;5953.285605727318;5954.136650185699;5954.225950626413;5188.221766734982;5224.242484839715;5265.973670131625;5375.65058271215;5377.838664840061;21458.463538411666;21868.343949681377;21876.671849922262;21971.23100691484;22124.336269911928;2055.722477465553;2073.429806803126;2103.7265155529835;2141.4111119442123;2142.0896119149297;13754.174749515061;13800.654983625389;14234.572962811133;14362.497312594009;14363.784543231859;1709.2137616696334;1714.9142542872808;1729.290990693822;1809.11353436815;1820.2070960104286;75370;77308.61345693236;78356.60821695912;78815.21184788136;79148.60425697893;9805.215582753506;9867.259955503367;9959;10037.397756134655;10086.899131008742;12163.939180304042;12464.439100240512;12479.438425270884;12524.68688282782;12536.373181340896;7848.097468791162;7851.803704907423;7868.842623147505;7874.251946065115;7887.329576985906;7797.532148071134;7855.921440785633;7881.0936742274225;7906.723264685824;7917.722879699183;5515.834524964257;5581.860453488695;5589.133684278943;5594.888102237933;5612.130119831432;1676.6898123847127;1679.8908070975347;1733.6706025855046;1734.3322820714116;1735.6094878652202;371.26674817235744;373.12688309356236;384.78644352384697;411.07097958613747;416.13236402101353;10634.20243481742;10725.83911241332;10832.566697332177;11072.889271107348;11121;400.3594249201313;400.6934694958359;410.13461596032346;411.31105398457464;412.0317254452014;4686.554777296152;6086.6652334122045;6144.78493252734;6172.629642221481;6185.752569897244;424427;425863;426074;426190;426554;81516.59241703872;81619;83463.58268208742;84294;84978.5751071253;438.45850374787113;442.70560077548714;445.8305843779343;445.8862989937545;446.02988500012344;10532.157427405637;10554.577816887546;10634.468276585985;10706.197035222545;10770.192235582206;41085;41133.382999255635;41694.37458438187;41724;42558.57441425546;56271.15593266022;56478.43521564732;56676.433235667115;56849.43150568441;57478;6001.189839371804;6059.697015149313;6082.878342433038;6088;6110.327863935053;11280.830787538029;11389.772204555697;11524.711882203012;11702.35637039937;11750.647480575597;8223.506589604644;9714.902850971399;10206.23453240995;10276.486175691036;10282.126019288145;8139.83720325602;9084.591193396182;9247.167754902091;9268.58291376903;9324.020977797234;9308.255339572683;9316.394434361744;9522.95238523803;9591.808163836544;9599.088086631758;5767.509761670305;5768.865519757716;5775.162601422854;5775.595708300379;5793.478586927196;9318;9329;9574.856377154487;9608.231341492803;9648.951755241178 10:02:49 INFO - 2015-10-26 10:02:49,753 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1173.7241748189178;1215.5502464088286;1270.8983281337494;1454.112991075444;1574.976375354369;7784.532928024316;8432.536210508428;8496.49021058737;8553.95723021384;8562.229399354057;8489.74530764078;8733.30133589313;8752.037275899662;8763.561821908896;8766.912330876688;1927.15205309664;1960.3432849995197;2208.657658062999;2219.8002179803816;2239.585676649817;2256.909723611054;2262.151693115082;2268.2401395532434;2279.4757205842666;2281.954360912781;2208.0726095040122;2262.1969200933677;2265.2071774878827;2265.9320220393415;2270.3642979965634;1919.529715219766;1944.4263942137104;2168.7397512298626;2214.944626384337;2216.3794137641485;2173.0873033326043;2220.2673117871063;2220.378294077661;2242.159190303636;2267.5238199977857;8604.440711353805;8607.870881936777;8623.784405389853;8711.60797764102;8739.169778870997;4907.190313598252;4988.426330971948;5020.899582008339;5028.170351891933;5031.622628302892;3412.436947903593;3461.584609846809;3465.549478567795;3467.3412051710093;3512.297540491912;1021.4790456866978;1034.886162522122;1067.8291473364225;1069.1660504806264;1070.143884892091;12485.50057997679;12514.249145051235;12525;12527;12529;4371.540988196257;4432.955670443319;4436.134953684001;4465.397171381822;4465.508794032654;9706.9029309706;9995.950020249853;10373.58505659765;10393.324433911772;10413.479326033668;5009.173486374707;5020.3724534433195;5025.623078269145;5026.54761071498;5028.572571331478;4567.086582683477;4576.336431217454;4576.794044267949;4580.129775342741;4580.679352445363;3543.1496440854007;3630.8184590770884;3644.21649345392;3658.7804731716183;3659.3596120678776;1087.8368244763349;1158.3744777820052;1160.9419529023685;1167.2821214952744;1174.6652204121956;906.4334790755778;1072.0619457974271;1104.7735214281054;1105.6738262212666;1108.7948729485072;6884;7479.513831600929;7854.607269636495;7990.560519171385;8045.0748163960725;592.2064433658832;606.6117684681807;608.6682757896957;613.5582380685898;615.6121643364652;5504.6421982571;5538.16927460876;5540.806071787549;5545.3622833374675;5546.7781288748;10587.735306617396;10668.573257069786;10730.463476826128;10857.837132443019;10864.076553492883;10646.89353106459;10722.892771072346;10815.513301901434;10870.510827012804;10922.344659320308;7763.961180194063;7803.531788092732;7807.267456390218;7810.258025487569;7820.648070836826;6624.735010599522;6815.182178138655;7076.186238582629;7726.806829829075;7771.805704857424;9501.809963800548;9583.424994500352;9587;9605.37565058269;9619.374740641835;9448.952755236454;9864.654737084455;9888;9897.257705672248;9901.257405694458;7827.099883513469;7858.960705196438;7911.327537159406;7922.128565857866;7928.960355198187;8416.873746893923;8561.143885611638;8571.40000200005;8575.571221439028;8605.569721513773;7766.106897706836;7820.765377038698;7846.942329700905;7958.124606293188;7989.600519973862;6548;7700.768976930701;7775.144734079134;7827.25641064098;7874.84250314979;7191;7462.776116716507;7485.850282994418;7650.8087297818;7679.155292917663;6435.646039467874;6490.221173459215;6506.121673574007;6517.478601711757;6523.6738163092605;6771.153605799275;6791.01530278097;6800.795976120724;6841.144856892888;6842.760503382557 10:02:49 INFO - 2015-10-26 10:02:49,809 DEBUG : BROWSER_OUTPUT: |4;prototype.html;318.2251218283481;320.4856205789707;321.1729795775876;325.2405632847301;333.6346700363103;5639.125935479997;5844.561657875661;5848.12278158276;5858.794942177014;5877.9999999999945;5630.2117703521435;5751.137329400592;5814.069748840187;5824.446677565625;5829.504492118164;4194.66442684586;4242.53332133465;4273.807678654468;4276.935845962313;4281.957180428187;4190.497140343163;4275.486941567016;4308.763018034023;4313.094250207445;4317.740935543861;4270.594293542124;4274.572542745716;4276.123394704078;4280.229558679452;4284.97857510712;4121.711480196387;4171.624553790143;4192.11965487245;4281.207976524337;4287.464066991626;3639.3267245559655;3717.516722826015;3727.38498147808;3737.663610275088;3738.9439158412647;5555.8888822223735;5660.320761508604;5672.602917795756;5674.375818659944;5681.9431805681825;4822.324874517571;4866.562009419134;5009.774560144802;5051.722155281458;5056.84829455117;6029.608075475124;6032.638041717511;6051.364606716319;6056.3338032816355;6056.788012419588;4191.7694526801015;4200.878556329065;4206.936895946563;4211.094614657833;4216.262154123016;6318.74725010999;6320.873582528369;6322.936770632281;6324.588901721373;6331.8417039573915;4014.7992600369867;4070.674346052309;4085.6527195188146;4321.243782338078;4322.416473776063;4340.153670034313;4346.6088052075465;4379.4525684289465;4386.473623165177;4390.385346051556;4883.609311255091;4933.679310844784;5039.79840806371;5039.874003149877;5050.4697006814495;5956.195913551702;6058.636481811105;6076.574639775262;6093.847653808691;6097.603655762361;1523.6267114557004;1549.1634517360724;1568.9058656480647;1581.019767744006;1582.9366825326858;1021.1932573267036;1054.9367037977745;1075.2795626929976;1087.5486673030603;1088.755030118233;5878.911816322759;5884.382139875337;5885.087811389241;5899.085641725539;5900.940990590125;6299.842503937347;6316.2736285326855;6324.652144132025;6332.145160403286;6346.174997250328;5561.5272701820795;5865.178874957509;5876.118582212703;5887.234659494324;5889.676067816311;3370.25854312051;3464.185916309665;3480.460528618068;3487.1805125795418;3491.3366460372954;6664.333566643297;6667.366600172976;6668.199816022108;6668.633225172662;6682.83292917681;6458.967705161538;6459.386358295955;6459.998700201476;6464.159659244362;6469.159009328851;6612.603243805387;6614.338566143443;6621.536492445579;6640.302768209365;6653.9667301663185;6149.477294430024;6320.4311611955145;6337.9366206337345;6339.587926784744;6345.936540634534;6082.695865206811;6129.509639228761;6220.720067596879;6221.751129954751;6224.315325314121;6623.105880706043;6648;6651.800445986628;6658.5671931324305;6664.166979127609;4180.519240287406;4389.275769498125;4400.515943246304;4401.845935392246;4402.295632698753;5949.167116603766;6206.410391012847;6305.653189074645;6343.111964324906;6353.3011368748475;6106.542009349228;6171.506279497719;6174.44430001284;6178.969105154445;6194.194754681863;6125.724342404514;6132.080187971662;6136.048912418582;6137.079438084323;6145.477634401126;5850.678212698343;5896.528277737685;5995.400459953969;5996.100584912124;5997.970010149921;5971.253593300838;6066.423689749467;6172.69136543162;6184.845378865384;6210.099535567408;6065.939340606537;6081.27024757032;6085.878282434238;6086;6087 10:02:49 INFO - 2015-10-26 10:02:49,809 DEBUG : BROWSER_OUTPUT: |5;yui.html;697.5675081449502;705.4885208224039;713.6253466929865;715.9606221657807;742.9554226746392;12749.872501274984;13175.407106680199;13202.66993325165;13252.403641836116;13346.599602011944;12084.456199471022;12198.085143614242;12205.694857628563;12207.206531575464;12247.693807654812;497.8133200049981;501.33823353173824;586.8444862111534;593.8040446652594;594.8066878264559;566.4392251670837;583.9562032847554;584.4768931806032;588.7085892483227;590.1354515634595;620.4013127332123;622.5082185073786;622.953278504114;623.102732065826;623.4856243599027;521.5905514171376;527.3908635525974;613.6624856329001;623.1307326279859;623.7879121098825;451.41090876406275;452.61075475091394;452.86187712747596;453.14582012905583;453.25892166308324;12016.939915300454;12050.758984820342;12075.45660445282;12089.274643521328;12120.878791212152;11233.438328083645;11342.149338799623;11628.127890408254;11856.407179640983;11950.760984780343;9376;9823.263255255813;9826.852597211047;9850.556724947395;9854.852177217348;3393.915152121192;3451.0854623524788;3474.1488335358;3515.33208690348;3566.7681600696133;42200;46384.304235436495;46566.30150547711;46592.53407465916;49141.26288105646;6603.636799976048;6675;6697.832554186087;6737.663116844138;6916.585004899722;10051.949740251252;10100.393976361442;10324.638637647646;10348.068673819393;10377.84433233502;6134.754609815646;6291.6854157291955;6337.746490140343;6412.294647588761;6529.249136349285;4037.374206997861;4119.011437255037;4141.440905477722;4160.563140870226;4312.374705667723;2496.1513085551;2497;2529.950070720667;2568.4092658688605;2570.742925707452;415;424.5160517010549;429.289525834744;429.4159942478227;430.47912026448347;617.4813156948185;617.4874853871275;633.4615576759718;649.8342922554718;656.4223483334634;8531.616077276538;8729.956350218208;8851.778705532284;8922.776930576787;9140.54297285133;535.4030256264238;553.808935917108;601.7893737191948;607.6900780601925;651.3128649274965;4004.2191772604065;4034.23349563582;4079.755214687128;4111.568285330057;4112.897177570585;53232.73383633057;55696.44303556994;55985.720071400196;57508;60021;49239.75380123076;49333.50666493361;49869.50130498649;50332.496675033515;50709.74645126751;42119.57880421218;42615.36076958848;42953.35569966453;43007;43190;8446.070932197576;8587.699430519902;8672.826543469222;8767.912320876709;8769.956150219208;27690.723092769622;29088.98188563475;29171.562426563192;29505.409891801613;29714.554281686225;38939.61060389473;41739.58260417357;42326.576734233495;42490.78754606207;42752;7641.7707468776025;7749.65126569317;7867.09528404218;7967.601619918865;8075.3135983439715;12064.517419303129;12117;12294.631161065183;12436.502539898303;12574.811377829523;10957.06864916491;11401.258918170292;11764.88235117672;11997.76004479888;12086.879131208576;10401.53193106297;11435.656930292105;11480.655580332603;11712.70718232051;11791.410429478321;11025.669229923114;11233.831492527783;11665.125115616192;11735.061195104201;11948.76102477963;9045.909540904508;9127.954360228156;9450;9528.809423811346;9698.75753106178;9589.472579008221;9598.760030999281;10038.598456061676;10229.130523905254;10292.897071029194 10:02:49 INFO - 2015-10-26 10:02:49,811 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:02:49 INFO - 2015-10-26 10:02:49,811 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:02:49 INFO - 2015-10-26 10:02:49,811 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,731 10:02:49 INFO - 2015-10-26 10:02:49,811 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:02:49 INFO - 2015-10-26 10:02:49,811 DEBUG : BROWSER_OUTPUT: __startTimestamp1445878969755__endTimestamp 10:02:49 INFO - 2015-10-26 10:02:49,811 DEBUG : BROWSER_OUTPUT: 10:02:49 INFO - 2015-10-26 10:02:49,812 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:02:49 INFO - 2015-10-26 10:02:49,812 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:02:49 INFO - 2015-10-26 10:02:49,812 DEBUG : BROWSER_OUTPUT: 10:02:49 INFO - 2015-10-26 10:02:49,812 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5038.59 Median:5305.26 stddev:729.58 (13.8%) stddev-sans-first:707.96 10:02:49 INFO - 2015-10-26 10:02:49,812 DEBUG : BROWSER_OUTPUT: Values: 2679.4 2713.9 2730.5 2746.2 2757.4 5108.0 5112.9 5143.4 5232.1 5247.2 5208.5 5237.1 5318.8 5321.5 5329.9 4375.2 4482.5 4570.6 4606.4 4613.4 4203.2 4500.9 4502.6 4552.7 4571.8 4548.0 4557.4 4606.9 4635.7 4647.6 4519.2 4558.4 4613.4 4621.4 4630.4 4386.3 4405.0 4483.6 4530.9 4535.5 5152.3 5181.5 5286.4 5289.8 5311.6 5305.3 5423.6 5471.5 5576.9 5577.6 5263.7 5518.3 5528.8 5548.2 5586.2 5110.6 5119.5 5194.0 5196.0 5223.5 5477.7 5650.3 5657.0 5758.6 5759.8 5191.4 5289.5 5324.5 5419.1 5432.6 5457.8 5467.8 5481.9 5486.5 5597.9 5291.9 5444.4 5527.3 5546.2 5548.0 5465.4 5467.5 5498.8 5518.2 5605.7 3440.4 3443.3 3517.1 3518.8 3525.6 3051.1 3154.0 3164.5 3200.8 3213.6 5060.5 5097.7 5208.6 5211.2 5236.2 5506.5 5533.7 5560.4 5632.1 5632.8 5085.7 5112.0 5138.8 5273.0 5279.4 4247.9 4287.7 4298.8 4366.8 4384.9 5696.3 5790.6 5857.8 5879.1 5910.7 5386.3 5408.4 5489.6 5603.9 5621.6 5654.5 5694.7 5704.6 5808.6 5826.0 5302.1 5397.0 5442.7 5508.0 5530.4 5396.5 5445.0 5478.4 5529.5 5676.8 5045.8 5263.5 5329.1 5372.8 5471.1 5322.0 5373.8 5456.3 5505.7 5509.0 5072.5 5134.0 5234.8 5244.8 5372.8 5353.3 5381.3 5418.6 5455.4 5478.2 5309.2 5319.2 5341.2 5395.4 5407.7 10:02:49 INFO - 2015-10-26 10:02:49,812 DEBUG : BROWSER_OUTPUT: 10:02:49 INFO - 2015-10-26 10:02:49,812 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48154.59 Median:326871.00 stddev:85157.98 (26.1%) stddev-sans-first:85321.68 10:02:49 INFO - 2015-10-26 10:02:49,812 DEBUG : BROWSER_OUTPUT: Values: 494.6 502.2 515.0 516.0 516.7 29055.9 29258.9 29376.6 38532.0 38909.4 39524.8 39948.4 40158.4 40210.0 40305.4 7061.7 8273.5 8411.7 8426.0 8434.7 6998.1 10441.6 10750.5 10768.3 10785.1 9735.6 10243.8 10449.5 10569.8 10579.3 7254.9 7407.5 10371.8 10521.6 10559.6 10204.9 10323.6 10325.2 10333.9 10351.9 37199.3 37399.3 37641.0 37712.0 37751.6 3141.1 3163.3 3344.4 3576.4 3608.6 29403.6 29670.6 29788.4 29828.3 30742.2 2776.9 2829.4 2853.6 2858.3 2860.8 326337.0 326572.0 326674.0 327068.0 327071.0 52528.2 52882.5 52956.5 53022.9 53072.0 49128.5 49652.3 57348.0 57555.4 58432.0 14298.3 14509.4 14523.3 14535.6 14540.6 10725.4 10747.5 11096.9 11123.4 11152.9 16638.5 17208.1 17221.6 17263.9 17322.8 7240.7 7357.9 7420.8 7423.9 7477.3 883.8 904.0 921.9 940.4 1033.5 28572.7 28916.8 29422.9 29489.0 29620.9 966.4 1026.7 1070.7 1097.2 1139.9 13845.7 14158.4 14173.9 14176.4 14271.9 308098.0 313784.0 314576.0 314911.0 315189.0 44537.6 45870.3 45874.3 45881.0 45902.8 1216.0 1216.5 1226.2 1226.2 1232.3 23580.9 26150.7 27434.5 27780.4 27953.0 44920.8 45333.3 45389.5 45491.0 45635.0 319299.0 334992.0 338357.0 339061.0 339271.0 26133.3 26993.7 27427.4 28000.6 28120.4 40654.6 40964.0 41520.8 41615.6 41700.8 33395.7 33777.5 35215.5 35287.3 35625.5 30994.5 36067.6 37080.6 37180.8 37376.1 34932.3 34952.1 36718.6 36767.8 37099.8 36182.0 36728.4 36879.8 37507.4 38063.0 28898.7 30598.2 30710.1 31470.2 31475.8 10:02:49 INFO - 2015-10-26 10:02:49,812 DEBUG : BROWSER_OUTPUT: 10:02:49 INFO - 2015-10-26 10:02:49,812 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25524.38 Median:445.96 stddev:70585.56 (15827.8%) stddev-sans-first:70758.20 10:02:49 INFO - 2015-10-26 10:02:49,812 DEBUG : BROWSER_OUTPUT: Values: 317.9 320.0 327.5 329.8 332.0 12567.0 17139.7 17455.2 17558.9 18302.4 13254.6 13505.2 13650.2 16178.4 16398.5 5244.9 5491.3 5506.5 5523.5 5523.6 5398.4 5465.3 5476.2 5497.5 5505.2 4120.8 4214.4 5441.3 5734.8 5948.7 5860.5 5869.4 5953.3 5954.1 5954.2 5188.2 5224.2 5266.0 5375.7 5377.8 21458.5 21868.3 21876.7 21971.2 22124.3 2055.7 2073.4 2103.7 2141.4 2142.1 13754.2 13800.7 14234.6 14362.5 14363.8 1709.2 1714.9 1729.3 1809.1 1820.2 75370.0 77308.6 78356.6 78815.2 79148.6 9805.2 9867.3 9959.0 10037.4 10086.9 12163.9 12464.4 12479.4 12524.7 12536.4 7848.1 7851.8 7868.8 7874.3 7887.3 7797.5 7855.9 7881.1 7906.7 7917.7 5515.8 5581.9 5589.1 5594.9 5612.1 1676.7 1679.9 1733.7 1734.3 1735.6 371.3 373.1 384.8 411.1 416.1 10634.2 10725.8 10832.6 11072.9 11121.0 400.4 400.7 410.1 411.3 412.0 4686.6 6086.7 6144.8 6172.6 6185.8 424427.0 425863.0 426074.0 426190.0 426554.0 81516.6 81619.0 83463.6 84294.0 84978.6 438.5 442.7 445.8 445.9 446.0 10532.2 10554.6 10634.5 10706.2 10770.2 41085.0 41133.4 41694.4 41724.0 42558.6 56271.2 56478.4 56676.4 56849.4 57478.0 6001.2 6059.7 6082.9 6088.0 6110.3 11280.8 11389.8 11524.7 11702.4 11750.6 8223.5 9714.9 10206.2 10276.5 10282.1 8139.8 9084.6 9247.2 9268.6 9324.0 9308.3 9316.4 9523.0 9591.8 9599.1 5767.5 5768.9 5775.2 5775.6 5793.5 9318.0 9329.0 9574.9 9608.2 9649.0 10:02:49 INFO - 2015-10-26 10:02:49,815 DEBUG : BROWSER_OUTPUT: 10:02:49 INFO - 2015-10-26 10:02:49,815 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5901.61 Median:5030.10 stddev:3318.74 (66.0%) stddev-sans-first:3309.03 10:02:49 INFO - 2015-10-26 10:02:49,815 DEBUG : BROWSER_OUTPUT: Values: 1173.7 1215.6 1270.9 1454.1 1575.0 7784.5 8432.5 8496.5 8554.0 8562.2 8489.7 8733.3 8752.0 8763.6 8766.9 1927.2 1960.3 2208.7 2219.8 2239.6 2256.9 2262.2 2268.2 2279.5 2282.0 2208.1 2262.2 2265.2 2265.9 2270.4 1919.5 1944.4 2168.7 2214.9 2216.4 2173.1 2220.3 2220.4 2242.2 2267.5 8604.4 8607.9 8623.8 8711.6 8739.2 4907.2 4988.4 5020.9 5028.2 5031.6 3412.4 3461.6 3465.5 3467.3 3512.3 1021.5 1034.9 1067.8 1069.2 1070.1 12485.5 12514.2 12525.0 12527.0 12529.0 4371.5 4433.0 4436.1 4465.4 4465.5 9706.9 9996.0 10373.6 10393.3 10413.5 5009.2 5020.4 5025.6 5026.5 5028.6 4567.1 4576.3 4576.8 4580.1 4580.7 3543.1 3630.8 3644.2 3658.8 3659.4 1087.8 1158.4 1160.9 1167.3 1174.7 906.4 1072.1 1104.8 1105.7 1108.8 6884.0 7479.5 7854.6 7990.6 8045.1 592.2 606.6 608.7 613.6 615.6 5504.6 5538.2 5540.8 5545.4 5546.8 10587.7 10668.6 10730.5 10857.8 10864.1 10646.9 10722.9 10815.5 10870.5 10922.3 7764.0 7803.5 7807.3 7810.3 7820.6 6624.7 6815.2 7076.2 7726.8 7771.8 9501.8 9583.4 9587.0 9605.4 9619.4 9449.0 9864.7 9888.0 9897.3 9901.3 7827.1 7859.0 7911.3 7922.1 7929.0 8416.9 8561.1 8571.4 8575.6 8605.6 7766.1 7820.8 7846.9 7958.1 7989.6 6548.0 7700.8 7775.1 7827.3 7874.8 7191.0 7462.8 7485.9 7650.8 7679.2 6435.6 6490.2 6506.1 6517.5 6523.7 6771.2 6791.0 6800.8 6841.1 6842.8 10:02:49 INFO - 2015-10-26 10:02:49,815 DEBUG : BROWSER_OUTPUT: 10:02:49 INFO - 2015-10-26 10:02:49,815 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5095.59 Median:5861.99 stddev:1559.62 (26.6%) stddev-sans-first:1522.21 10:02:49 INFO - 2015-10-26 10:02:49,816 DEBUG : BROWSER_OUTPUT: Values: 318.2 320.5 321.2 325.2 333.6 5639.1 5844.6 5848.1 5858.8 5878.0 5630.2 5751.1 5814.1 5824.4 5829.5 4194.7 4242.5 4273.8 4276.9 4282.0 4190.5 4275.5 4308.8 4313.1 4317.7 4270.6 4274.6 4276.1 4280.2 4285.0 4121.7 4171.6 4192.1 4281.2 4287.5 3639.3 3717.5 3727.4 3737.7 3738.9 5555.9 5660.3 5672.6 5674.4 5681.9 4822.3 4866.6 5009.8 5051.7 5056.8 6029.6 6032.6 6051.4 6056.3 6056.8 4191.8 4200.9 4206.9 4211.1 4216.3 6318.7 6320.9 6322.9 6324.6 6331.8 4014.8 4070.7 4085.7 4321.2 4322.4 4340.2 4346.6 4379.5 4386.5 4390.4 4883.6 4933.7 5039.8 5039.9 5050.5 5956.2 6058.6 6076.6 6093.8 6097.6 1523.6 1549.2 1568.9 1581.0 1582.9 1021.2 1054.9 1075.3 1087.5 1088.8 5878.9 5884.4 5885.1 5899.1 5900.9 6299.8 6316.3 6324.7 6332.1 6346.2 5561.5 5865.2 5876.1 5887.2 5889.7 3370.3 3464.2 3480.5 3487.2 3491.3 6664.3 6667.4 6668.2 6668.6 6682.8 6459.0 6459.4 6460.0 6464.2 6469.2 6612.6 6614.3 6621.5 6640.3 6654.0 6149.5 6320.4 6337.9 6339.6 6345.9 6082.7 6129.5 6220.7 6221.8 6224.3 6623.1 6648.0 6651.8 6658.6 6664.2 4180.5 4389.3 4400.5 4401.8 4402.3 5949.2 6206.4 6305.7 6343.1 6353.3 6106.5 6171.5 6174.4 6179.0 6194.2 6125.7 6132.1 6136.0 6137.1 6145.5 5850.7 5896.5 5995.4 5996.1 5998.0 5971.3 6066.4 6172.7 6184.8 6210.1 6065.9 6081.3 6085.9 6086.0 6087.0 10:02:49 INFO - 2015-10-26 10:02:49,816 DEBUG : BROWSER_OUTPUT: 10:02:49 INFO - 2015-10-26 10:02:49,818 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12749.18 Median:452.74 stddev:15203.88 (3358.2%) stddev-sans-first:15219.59 10:02:49 INFO - 2015-10-26 10:02:49,818 DEBUG : BROWSER_OUTPUT: Values: 697.6 705.5 713.6 716.0 743.0 12749.9 13175.4 13202.7 13252.4 13346.6 12084.5 12198.1 12205.7 12207.2 12247.7 497.8 501.3 586.8 593.8 594.8 566.4 584.0 584.5 588.7 590.1 620.4 622.5 623.0 623.1 623.5 521.6 527.4 613.7 623.1 623.8 451.4 452.6 452.9 453.1 453.3 12016.9 12050.8 12075.5 12089.3 12120.9 11233.4 11342.1 11628.1 11856.4 11950.8 9376.0 9823.3 9826.9 9850.6 9854.9 3393.9 3451.1 3474.1 3515.3 3566.8 42200.0 46384.3 46566.3 46592.5 49141.3 6603.6 6675.0 6697.8 6737.7 6916.6 10051.9 10100.4 10324.6 10348.1 10377.8 6134.8 6291.7 6337.7 6412.3 6529.2 4037.4 4119.0 4141.4 4160.6 4312.4 2496.2 2497.0 2530.0 2568.4 2570.7 415.0 424.5 429.3 429.4 430.5 617.5 617.5 633.5 649.8 656.4 8531.6 8730.0 8851.8 8922.8 9140.5 535.4 553.8 601.8 607.7 651.3 4004.2 4034.2 4079.8 4111.6 4112.9 53232.7 55696.4 55985.7 57508.0 60021.0 49239.8 49333.5 49869.5 50332.5 50709.7 42119.6 42615.4 42953.4 43007.0 43190.0 8446.1 8587.7 8672.8 8767.9 8770.0 27690.7 29089.0 29171.6 29505.4 29714.6 38939.6 41739.6 42326.6 42490.8 42752.0 7641.8 7749.7 7867.1 7967.6 8075.3 12064.5 12117.0 12294.6 12436.5 12574.8 10957.1 11401.3 11764.9 11997.8 12086.9 10401.5 11435.7 11480.7 11712.7 11791.4 11025.7 11233.8 11665.1 11735.1 11948.8 9045.9 9128.0 9450.0 9528.8 9698.8 9589.5 9598.8 10038.6 10229.1 10292.9 10:02:49 INFO - 2015-10-26 10:02:49,819 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:02:49 INFO - 2015-10-26 10:02:49,819 DEBUG : BROWSER_OUTPUT: 10:02:50 INFO - 2015-10-26 10:02:50,299 INFO : Browser exited with error code: 0 10:02:51 INFO - 2015-10-26 10:02:51,055 INFO : Completed test dromaeo_css (00:18:37) 10:02:51 INFO - 2015-10-26 10:02:51,055 INFO : Starting test kraken 10:02:51 INFO - 2015-10-26 10:02:51,055 DEBUG : operating with platform_type : w7_ 10:02:51 INFO - 2015-10-26 10:02:51,056 INFO : Initialising browser for kraken test... 10:02:51 INFO - 2015-10-26 10:02:51,180 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpq32dfv\profile http://localhost:49849/getInfo.html 10:02:56 INFO - 2015-10-26 10:02:56,384 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:02:56 INFO - 2015-10-26 10:02:56,384 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:02:56 INFO - 2015-10-26 10:02:56,384 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 10:02:56 INFO - 2015-10-26 10:02:56,384 DEBUG : BROWSER_OUTPUT: __metrics 10:02:56 INFO - 2015-10-26 10:02:56,519 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:02:57 INFO - 2015-10-26 10:02:57,325 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 10:02:57 INFO - 2015-10-26 10:02:57,540 DEBUG : BROWSER_OUTPUT: console.error: 10:02:57 INFO - 2015-10-26 10:02:57,542 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] 10:02:57 INFO - 2015-10-26 10:02:57,542 DEBUG : BROWSER_OUTPUT: Stack: 10:02:57 INFO - 2015-10-26 10:02:57,542 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 10:02:57 INFO - 2015-10-26 10:02:57,542 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 10:02:57 INFO - 2015-10-26 10:02:57,542 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 10:02:57 INFO - 2015-10-26 10:02:57,542 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 10:02:57 INFO - 2015-10-26 10:02:57,542 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 10:02:57 INFO - 2015-10-26 10:02:57,542 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 10:02:57 INFO - 2015-10-26 10:02:57,542 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 10:02:57 INFO - 2015-10-26 10:02:57,542 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 10:02:57 INFO - 2015-10-26 10:02:57,542 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 10:02:57 INFO - 2015-10-26 10:02:57,542 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 10:02:57 INFO - 2015-10-26 10:02:57,542 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 10:02:57 INFO - 2015-10-26 10:02:57,542 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 10:02:57 INFO - 2015-10-26 10:02:57,542 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6558.43 10:04:33 INFO - RETURN: kraken: 1585.10 10:04:33 INFO - RETURN: v8_7: 20693.22 10:04:33 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,25]]", "result": "20693.22"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,25]]", "result": "1585.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,25]]", "result": "6558.43"}}} 10:04:33 INFO - Return code: 0 10:04:33 INFO - # TBPL SUCCESS # 10:04:33 INFO - Running post-action listener: _resource_record_post_action 10:04:33 INFO - Running post-run listener: _resource_record_post_run 10:04:34 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 692894720; Write bytes: 802298368; Read time: 6471600; Write time: 5408140 10:04:34 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 507904; Read time: 0; Write time: 330 10:04:34 INFO - run-tests - Wall time: 1227s; CPU: 13.0%; Read bytes: 692894720; Write bytes: 776659456; Read time: 6471600; Write time: 5055860 10:04:34 INFO - Running post-run listener: _upload_blobber_files 10:04:34 INFO - Blob upload gear active. 10:04:34 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:04:34 INFO - Copying logs to upload dir... 10:04:34 INFO - mkdir: C:\slave\test-pgo\build\upload\logs program finished with exit code 0 elapsedTime=1566.924000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 27 mins, 50 secs) (at 2015-10-26 10:06:17.814218) ========= ========= Started set props: build_url (results: 0, elapsed: 2 secs) (at 2015-10-26 10:06:17.818252) ========= '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-120 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-120 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-120 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/ewAmACm4QSKjAQDugRaWeA/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/ewAmACm4QSKjAQDugRaWeA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 2 secs) (at 2015-10-26 10:06:20.755853) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2015-10-26 10:06:20.756210) ========= '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-120 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-120 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-120 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: 1 secs) (at 2015-10-26 10:06:22.362493) ========= ========= Started reboot slave lost (results: 0, elapsed: 55 secs) (at 2015-10-26 10:06:22.362824) ========= ========= Finished reboot slave lost (results: 0, elapsed: 55 secs) (at 2015-10-26 10:07:17.685722) =========