builder: mozilla-central_win7-ix_test-dromaeojs-pgo slave: t-w732-ix-020 starttime: 1445921170.58 results: success (0) buildid: 20151026163226 builduid: 6c8f418f16814ea6b7bb3beb0192c304 revision: 6c7c983bce46a460c2766fbdd73283f6d2b03a69 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-26 21:46:10.585254) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-26 21:46:10.588933) ========= ========= Started set props: basedir (results: 0, elapsed: 25 secs) (at 2015-10-26 21:46:10.589302) ========= '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-020 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-020 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-020 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.577000 basedir: 'C:\\slave\\test-pgo' ========= Finished set props: basedir (results: 0, elapsed: 25 secs) (at 2015-10-26 21:46:36.177505) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 16 secs) (at 2015-10-26 21:46:36.177858) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 16 secs) (at 2015-10-26 21:46:52.573729) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2015-10-26 21:46:52.574023) ========= '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-020 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-020 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-020 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: 2 secs) (at 2015-10-26 21:46:54.662516) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-26 21:46:54.662782) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-26 21:46:54.663115) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 10 secs) (at 2015-10-26 21:46:54.663408) ========= '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-020 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-020 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-020 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 --21:47:03-- 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% 21.26 MB/s 21:47:03 (21.26 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.774000 ========= Finished 'bash -c ...' (results: 0, elapsed: 10 secs) (at 2015-10-26 21:47:04.893713) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2015-10-26 21:47:04.893999) ========= '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-020 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-020 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-020 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.404000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2015-10-26 21:47:18.918975) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-10-26 21:47:18.919315) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev 6c7c983bce46a460c2766fbdd73283f6d2b03a69 --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 6c7c983bce46a460c2766fbdd73283f6d2b03a69 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-020 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-020 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-020 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 21:47:19,270 truncating revision to first 12 chars 2015-10-26 21:47:19,272 Setting DEBUG logging. 2015-10-26 21:47:19,272 attempt 1/10 2015-10-26 21:47:19,272 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6c7c983bce46?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-26 21:47:19,999 unpacking tar archive at: mozilla-central-6c7c983bce46/testing/mozharness/ program finished with exit code 0 elapsedTime=1.481000 ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-10-26 21:47:21.393123) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-26 21:47:21.393414) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-26 21:47:21.473211) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-26 21:47:21.473466) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-26 21:47:21.473820) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 38 secs) (at 2015-10-26 21:47:21.474100) ========= '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-020 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-020 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-020 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 21:47:21 INFO - MultiFileLogger online at 20151026 21:47:21 in C:\slave\test-pgo 21:47:21 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 21:47:21 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 21:47:21 INFO - {'append_to_log': False, 21:47:21 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 21:47:21 INFO - 'blob_upload_branch': 'Firefox', 21:47:21 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 21:47:21 INFO - 'branch': 'Firefox', 21:47:21 INFO - 'buildbot_json_path': 'buildprops.json', 21:47:21 INFO - 'config_files': ('talos/windows_config.py',), 21:47:21 INFO - 'default_actions': ('clobber', 21:47:21 INFO - 'read-buildbot-config', 21:47:21 INFO - 'download-and-extract', 21:47:21 INFO - 'populate-webroot', 21:47:21 INFO - 'create-virtualenv', 21:47:21 INFO - 'install', 21:47:21 INFO - 'run-tests'), 21:47:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:47:21 INFO - 'download_minidump_stackwalk': True, 21:47:21 INFO - 'download_symbols': 'ondemand', 21:47:21 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 21:47:21 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 21:47:21 INFO - 'hg': 'c:/mozilla-build/hg/hg', 21:47:21 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 21:47:21 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 21:47:21 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 21:47:21 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 21:47:21 INFO - 'C:/mozilla-build/tooltool.py'), 21:47:21 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 21:47:21 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 21:47:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:47:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:47:21 INFO - 'installer_path': 'installer.exe', 21:47:21 INFO - 'log_level': 'info', 21:47:21 INFO - 'log_name': 'talos', 21:47:21 INFO - 'log_to_console': True, 21:47:21 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 21:47:21 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 21:47:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 21:47:21 INFO - 'opt_config_files': (), 21:47:21 INFO - 'pip_index': False, 21:47:21 INFO - 'sps_profile': False, 21:47:21 INFO - 'sps_profile_interval': 0, 21:47:21 INFO - 'suite': 'dromaeojs', 21:47:21 INFO - 'system_bits': '32', 21:47:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:47:21 INFO - 'title': 'T-W732-IX-020', 21:47:21 INFO - 'use_talos_json': True, 21:47:21 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 21:47:21 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 21:47:21 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 21:47:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:47:21 INFO - 'work_dir': 'build'} 21:47:21 INFO - ##### 21:47:21 INFO - ##### Running clobber step. 21:47:21 INFO - ##### 21:47:21 INFO - Running pre-action listener: _resource_record_pre_action 21:47:21 INFO - Running main action method: clobber 21:47:21 INFO - rmtree: C:\slave\test-pgo\build 21:47:21 INFO - Using _rmtree_windows ... 21:47:21 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 21:48:57 INFO - Running post-action listener: _resource_record_post_action 21:48:57 INFO - ##### 21:48:57 INFO - ##### Running read-buildbot-config step. 21:48:57 INFO - ##### 21:48:57 INFO - Running pre-action listener: _resource_record_pre_action 21:48:57 INFO - Running main action method: read_buildbot_config 21:48:57 INFO - Using buildbot properties: 21:48:57 INFO - { 21:48:57 INFO - "properties": { 21:48:57 INFO - "buildnumber": 48, 21:48:57 INFO - "product": "firefox", 21:48:57 INFO - "script_repo_revision": "production", 21:48:57 INFO - "builddir": "mozilla-central_win7-ix_test-dromaeojs", 21:48:57 INFO - "repository": "", 21:48:57 INFO - "buildername": "Windows 7 32-bit mozilla-central pgo talos dromaeojs", 21:48:57 INFO - "buildid": "20151026163226", 21:48:57 INFO - "slavename": "t-w732-ix-020", 21:48:57 INFO - "pgo_build": "True", 21:48:57 INFO - "basedir": "C:\\slave\\test-pgo", 21:48:57 INFO - "project": "", 21:48:57 INFO - "platform": "win7-ix", 21:48:57 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 21:48:57 INFO - "slavebuilddir": "test", 21:48:57 INFO - "scheduler": "tests-mozilla-central-win32-pgo-talos", 21:48:57 INFO - "branch": "mozilla-central", 21:48:57 INFO - "repo_path": "mozilla-central", 21:48:57 INFO - "stage_platform": "win32-pgo", 21:48:57 INFO - "builduid": "6c8f418f16814ea6b7bb3beb0192c304", 21:48:57 INFO - "revision": "6c7c983bce46a460c2766fbdd73283f6d2b03a69" 21:48:57 INFO - }, 21:48:57 INFO - "sourcestamp": { 21:48:57 INFO - "repository": "", 21:48:57 INFO - "hasPatch": false, 21:48:57 INFO - "project": "", 21:48:57 INFO - "branch": "mozilla-central-win32-pgo-talos", 21:48:57 INFO - "changes": [ 21:48:57 INFO - { 21:48:57 INFO - "category": null, 21:48:57 INFO - "files": [ 21:48:57 INFO - { 21:48:57 INFO - "url": null, 21:48:57 INFO - "name": "https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 21:48:57 INFO - } 21:48:57 INFO - ], 21:48:57 INFO - "repository": "", 21:48:57 INFO - "rev": "6c7c983bce46a460c2766fbdd73283f6d2b03a69", 21:48:57 INFO - "who": "sendchange", 21:48:57 INFO - "when": 1445921010, 21:48:57 INFO - "number": 6578103, 21:48:57 INFO - "comments": "", 21:48:57 INFO - "project": "", 21:48:57 INFO - "at": "Mon 26 Oct 2015 21:43:30", 21:48:57 INFO - "branch": "mozilla-central-win32-pgo-talos", 21:48:57 INFO - "revlink": "", 21:48:57 INFO - "properties": [ 21:48:57 INFO - [ 21:48:57 INFO - "buildid", 21:48:57 INFO - "20151026163226", 21:48:57 INFO - "Change" 21:48:57 INFO - ], 21:48:57 INFO - [ 21:48:57 INFO - "builduid", 21:48:57 INFO - "6c8f418f16814ea6b7bb3beb0192c304", 21:48:57 INFO - "Change" 21:48:57 INFO - ], 21:48:57 INFO - [ 21:48:57 INFO - "pgo_build", 21:48:57 INFO - "True", 21:48:57 INFO - "Change" 21:48:57 INFO - ] 21:48:57 INFO - ], 21:48:57 INFO - "revision": "6c7c983bce46a460c2766fbdd73283f6d2b03a69" 21:48:57 INFO - } 21:48:57 INFO - ], 21:48:57 INFO - "revision": "6c7c983bce46a460c2766fbdd73283f6d2b03a69" 21:48:57 INFO - } 21:48:57 INFO - } 21:48:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 21:48:57 INFO - Running post-action listener: _resource_record_post_action 21:48:57 INFO - ##### 21:48:57 INFO - ##### Running download-and-extract step. 21:48:57 INFO - ##### 21:48:57 INFO - Running pre-action listener: _resource_record_pre_action 21:48:57 INFO - Running main action method: download_and_extract 21:48:57 INFO - mkdir: C:\slave\test-pgo\build\tests 21:48:57 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')]} 21:48:57 INFO - https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 21:48:57 INFO - trying https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/test_packages.json 21:48:57 INFO - Downloading https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 21:48:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 21:48:58 INFO - Downloaded 1221 bytes. 21:48:58 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 21:48:58 INFO - Using the following test package requirements: 21:48:58 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 21:48:58 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:48:58 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 21:48:58 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:48:58 INFO - u'jsshell-win32.zip'], 21:48:58 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:48:58 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 21:48:58 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 21:48:58 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:48:58 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 21:48:58 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:48:58 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 21:48:58 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:48:58 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 21:48:58 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 21:48:58 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:48:58 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 21:48:58 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 21:48:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:48:58 INFO - https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 21:48:58 INFO - trying https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 21:48:58 INFO - Downloading https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/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 21:48:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/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 21:49:00 INFO - Downloaded 18017271 bytes. 21:49:00 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 21:49:00 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 21:49:00 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 21:49:36 INFO - Return code: 0 21:49:36 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 21:49: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')]} 21:49:36 INFO - https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 21:49:36 INFO - trying https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 21:49:36 INFO - Downloading https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/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 21:49:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/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 21:49:37 INFO - Downloaded 18017271 bytes. 21:49:37 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 21:49:37 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 21:49:37 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 21:50:03 INFO - Return code: 0 21:50:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:50:03 INFO - https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 21:50:03 INFO - trying https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 21:50:03 INFO - Downloading https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/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 21:50:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/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 21:50:05 INFO - Downloaded 11131307 bytes. 21:50:05 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 21:50:05 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip 21:50:05 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 21:50:08 INFO - Return code: 0 21:50:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:50:08 INFO - https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 21:50:08 INFO - trying https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 21:50:08 INFO - Downloading https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip 21:50:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/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 21:50:12 INFO - Downloaded 53217137 bytes. 21:50:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 21:50:12 INFO - mkdir: C:\slave\test-pgo\properties 21:50:12 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 21:50:12 INFO - Writing to file C:\slave\test-pgo\properties\build_url 21:50:12 INFO - Contents: 21:50:12 INFO - build_url:https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 21:50:12 INFO - Running post-action listener: _resource_record_post_action 21:50:12 INFO - Running post-action listener: set_extra_try_arguments 21:50:12 INFO - ##### 21:50:12 INFO - ##### Running populate-webroot step. 21:50:12 INFO - ##### 21:50:12 INFO - Running pre-action listener: _resource_record_pre_action 21:50:12 INFO - Running main action method: populate_webroot 21:50:12 INFO - Guessing talos json url... 21:50:12 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')]} 21:50:12 INFO - https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 21:50:12 INFO - trying https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 21:50:12 INFO - Downloading https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 21:50:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JORWTJE0Tb2X3Yclsg5gxw/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 21:50:12 INFO - Downloaded 99 bytes. 21:50:12 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 21:50:12 INFO - Contents: 21:50:12 INFO - 20151026163226 21:50:12 INFO - https://hg.mozilla.org/mozilla-central/rev/6c7c983bce46a460c2766fbdd73283f6d2b03a69 21:50:12 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')]} 21:50:12 INFO - trying https://hg.mozilla.org/mozilla-central/raw-file/6c7c983bce/testing/talos/talos.json 21:50:12 INFO - Downloading https://hg.mozilla.org/mozilla-central/raw-file/6c7c983bce/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json 21:50:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/mozilla-central/raw-file/6c7c983bce/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1 21:50:13 INFO - Downloaded 4264 bytes. 21:50:13 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 21:50:13 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 21:50:13 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 21:50:13 INFO - u'../fennec_ids.txt'], 21:50:13 INFO - u'tests': [u'tcheck2']}, 21:50:13 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 21:50:13 INFO - u'--tppagecycles', 21:50:13 INFO - u'7'], 21:50:13 INFO - u'tests': [u'tsvgm']}}, 21:50:13 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 21:50:13 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 21:50:13 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 21:50:13 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 21:50:13 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 21:50:13 INFO - u'tests': [u'dromaeo_css', 21:50:13 INFO - u'kraken', 21:50:13 INFO - u'v8_7']}, 21:50:13 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:50:13 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 21:50:13 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:50:13 INFO - u'talos_options': [u'--e10s'], 21:50:13 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 21:50:13 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:50:13 INFO - u'tests': [u'damp', u'tps']}, 21:50:13 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:50:13 INFO - u'talos_options': [u'--e10s'], 21:50:13 INFO - u'tests': [u'damp', u'tps']}, 21:50:13 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 21:50:13 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 21:50:13 INFO - u'other': {u'tests': [u'a11yr', 21:50:13 INFO - u'ts_paint', 21:50:13 INFO - u'tpaint', 21:50:13 INFO - u'sessionrestore', 21:50:13 INFO - u'sessionrestore_no_auto_restore']}, 21:50:13 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 21:50:13 INFO - u'tests': [u'a11yr', 21:50:13 INFO - u'ts_paint', 21:50:13 INFO - u'tpaint', 21:50:13 INFO - u'sessionrestore', 21:50:13 INFO - u'sessionrestore_no_auto_restore']}, 21:50:13 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 21:50:13 INFO - u'tests': [u'a11yr', 21:50:13 INFO - u'ts_paint', 21:50:13 INFO - u'tpaint', 21:50:13 INFO - u'sessionrestore', 21:50:13 INFO - u'sessionrestore_no_auto_restore']}, 21:50:13 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 21:50:13 INFO - u'tests': [u'a11yr', 21:50:13 INFO - u'ts_paint', 21:50:13 INFO - u'tpaint', 21:50:13 INFO - u'sessionrestore', 21:50:13 INFO - u'sessionrestore_no_auto_restore']}, 21:50:13 INFO - u'other_l64': {u'tests': [u'a11yr', 21:50:13 INFO - u'ts_paint', 21:50:13 INFO - u'tpaint', 21:50:13 INFO - u'sessionrestore', 21:50:13 INFO - u'sessionrestore_no_auto_restore']}, 21:50:13 INFO - u'other_nol64': {u'tests': [u'a11yr', 21:50:13 INFO - u'ts_paint', 21:50:13 INFO - u'tpaint', 21:50:13 INFO - u'sessionrestore', 21:50:13 INFO - u'sessionrestore_no_auto_restore']}, 21:50:13 INFO - u'svgr': {u'tests': [u'tsvgx', 21:50:13 INFO - u'tsvgr_opacity', 21:50:13 INFO - u'tart', 21:50:13 INFO - u'tscrollx', 21:50:13 INFO - u'cart']}, 21:50:13 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 21:50:13 INFO - u'tests': [u'tsvgx', 21:50:13 INFO - u'tsvgr_opacity', 21:50:13 INFO - u'tart', 21:50:13 INFO - u'tscrollx', 21:50:13 INFO - u'cart']}, 21:50:13 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:50:13 INFO - u'tests': [u'tp5o']}, 21:50:13 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:50:13 INFO - u'talos_options': [u'--e10s'], 21:50:13 INFO - u'tests': [u'tp5o']}, 21:50:13 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:50:13 INFO - u'talos_options': [u'--xperf_path', 21:50:13 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:50:13 INFO - u'tests': [u'tp5n']}, 21:50:13 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:50:13 INFO - u'talos_options': [u'--e10s', 21:50:13 INFO - u'--xperf_path', 21:50:13 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 21:50:13 INFO - u'C:/slave/talos-data/talos/xperf.config'], 21:50:13 INFO - u'tests': [u'tp5n']}}, 21:50:13 INFO - u'talos.zip': {u'path': u'', 21:50:13 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:50:13 INFO - Running post-action listener: _resource_record_post_action 21:50:13 INFO - ##### 21:50:13 INFO - ##### Running create-virtualenv step. 21:50:13 INFO - ##### 21:50:13 INFO - Running pre-action listener: _resource_record_pre_action 21:50:13 INFO - Running main action method: create_virtualenv 21:50:13 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 21:50:13 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 21:50:13 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 21:50:13 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'! 21:50:13 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 21:50:13 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 21:50:18 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 21:50:21 INFO - Installing distribute......................................................................................................................................................................................done. 21:50:22 INFO - Return code: 0 21:50:22 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 21:50:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:50:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:22 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 0x019A8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01968EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0144E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01368B48>, '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 0x0138A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A29660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-020', 'USERDOMAIN': 'T-W732-IX-020', '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-020', '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 21:50:22 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 21:50:22 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 21:50:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:50:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:50:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:50:22 INFO - 'COMPUTERNAME': 'T-W732-IX-020', 21:50:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:50:22 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:50:22 INFO - 'DCLOCATION': 'SCL3', 21:50:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:50:22 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:50:22 INFO - 'HOMEDRIVE': 'C:', 21:50:22 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:50:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:50:22 INFO - 'KTS_VERSION': '1.19c', 21:50:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:50:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-020', 21:50:22 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:50:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:50:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:22 INFO - 'MOZ_NO_REMOTE': '1', 21:50:22 INFO - 'NO_EM_RESTART': '1', 21:50:22 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:50:22 INFO - 'OS': 'Windows_NT', 21:50:22 INFO - 'OURDRIVE': 'C:', 21:50:22 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:50:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:50:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:50:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:50:22 INFO - 'PROCESSOR_LEVEL': '6', 21:50:22 INFO - 'PROCESSOR_REVISION': '1e05', 21:50:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:50:22 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:50:22 INFO - 'PROMPT': '$P$G', 21:50:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 21:50:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:50:22 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:50:22 INFO - 'PWD': 'C:\\slave\\test-pgo', 21:50:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:50:22 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:50:22 INFO - 'SYSTEMDRIVE': 'C:', 21:50:22 INFO - 'SYSTEMROOT': 'C:\\windows', 21:50:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:50:22 INFO - 'TEST1': 'testie', 21:50:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:50:22 INFO - 'USERDOMAIN': 'T-W732-IX-020', 21:50:22 INFO - 'USERNAME': 'cltbld', 21:50:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:50:22 INFO - 'WINDIR': 'C:\\windows', 21:50:22 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:50:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:50:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:50:23 INFO - Ignoring indexes: http://pypi.python.org/simple/ 21:50:23 INFO - Downloading/unpacking pip>=1.5 21:50:23 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache 21:50:27 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 21:50:27 INFO - Running setup.py egg_info for package pip 21:50:27 INFO - warning: no files found matching 'pip\cacert.pem' 21:50:27 INFO - warning: no files found matching '*.html' under directory 'docs' 21:50:27 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 21:50:27 INFO - no previously-included directories found matching 'docs\_build\_sources' 21:50:27 INFO - Installing collected packages: pip 21:50:27 INFO - Found existing installation: pip 0.8.2 21:50:27 INFO - Uninstalling pip: 21:50:27 INFO - Successfully uninstalled pip 21:50:27 INFO - Running setup.py install for pip 21:50:27 INFO - warning: no files found matching 'pip\cacert.pem' 21:50:27 INFO - warning: no files found matching '*.html' under directory 'docs' 21:50:27 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 21:50:27 INFO - no previously-included directories found matching 'docs\_build\_sources' 21:50:27 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:27 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:27 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:27 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:27 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:27 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:27 INFO - Successfully installed pip 21:50:27 INFO - Cleaning up... 21:50:27 INFO - Return code: 0 21:50:27 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 21:50:27 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')]} 21:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50:27 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')]} 21:50:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:27 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 0x019A8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01968EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0144E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01368B48>, '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 0x0138A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A29660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-020', 'USERDOMAIN': 'T-W732-IX-020', '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-020', '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 21:50:27 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 21:50:27 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 21:50:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:50:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:50:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:50:27 INFO - 'COMPUTERNAME': 'T-W732-IX-020', 21:50:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:50:27 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:50:27 INFO - 'DCLOCATION': 'SCL3', 21:50:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:50:27 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:50:27 INFO - 'HOMEDRIVE': 'C:', 21:50:27 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:50:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:50:27 INFO - 'KTS_VERSION': '1.19c', 21:50:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:50:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-020', 21:50:27 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:50:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:50:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:27 INFO - 'MOZ_NO_REMOTE': '1', 21:50:27 INFO - 'NO_EM_RESTART': '1', 21:50:27 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:50:27 INFO - 'OS': 'Windows_NT', 21:50:27 INFO - 'OURDRIVE': 'C:', 21:50:27 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:50:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:50:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:50:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:50:27 INFO - 'PROCESSOR_LEVEL': '6', 21:50:27 INFO - 'PROCESSOR_REVISION': '1e05', 21:50:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:50:27 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:50:27 INFO - 'PROMPT': '$P$G', 21:50:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 21:50:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:50:27 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:50:27 INFO - 'PWD': 'C:\\slave\\test-pgo', 21:50:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:50:27 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:50:27 INFO - 'SYSTEMDRIVE': 'C:', 21:50:27 INFO - 'SYSTEMROOT': 'C:\\windows', 21:50:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:50:27 INFO - 'TEST1': 'testie', 21:50:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:50:27 INFO - 'USERDOMAIN': 'T-W732-IX-020', 21:50:27 INFO - 'USERNAME': 'cltbld', 21:50:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:50:27 INFO - 'WINDIR': 'C:\\windows', 21:50:27 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:50:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:50:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:50:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:50:28 INFO - Downloading/unpacking psutil>=0.7.1 21:50:28 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 21:50:28 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 21:50:29 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 21:50:29 INFO - Installing collected packages: psutil 21:50:29 INFO - Successfully installed psutil 21:50:29 INFO - Cleaning up... 21:50:29 INFO - Return code: 0 21:50:29 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 21:50:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:50:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:29 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 0x019A8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01968EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0144E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01368B48>, '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 0x0138A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A29660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-020', 'USERDOMAIN': 'T-W732-IX-020', '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-020', '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 21:50:29 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 21:50:29 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 21:50:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:50:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:50:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:50:29 INFO - 'COMPUTERNAME': 'T-W732-IX-020', 21:50:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:50:29 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:50:29 INFO - 'DCLOCATION': 'SCL3', 21:50:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:50:29 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:50:29 INFO - 'HOMEDRIVE': 'C:', 21:50:29 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:50:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:50:29 INFO - 'KTS_VERSION': '1.19c', 21:50:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:50:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-020', 21:50:29 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:50:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:29 INFO - 'MOZ_NO_REMOTE': '1', 21:50:29 INFO - 'NO_EM_RESTART': '1', 21:50:29 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:50:29 INFO - 'OS': 'Windows_NT', 21:50:29 INFO - 'OURDRIVE': 'C:', 21:50:29 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;', 21:50:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:50:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:50:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:50:29 INFO - 'PROCESSOR_LEVEL': '6', 21:50:29 INFO - 'PROCESSOR_REVISION': '1e05', 21:50:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:50:29 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:50:29 INFO - 'PROMPT': '$P$G', 21:50:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 21:50:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:50:29 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:50:29 INFO - 'PWD': 'C:\\slave\\test-pgo', 21:50:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:50:29 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:50:29 INFO - 'SYSTEMDRIVE': 'C:', 21:50:29 INFO - 'SYSTEMROOT': 'C:\\windows', 21:50:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:50:29 INFO - 'TEST1': 'testie', 21:50:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:50:29 INFO - 'USERDOMAIN': 'T-W732-IX-020', 21:50:29 INFO - 'USERNAME': 'cltbld', 21:50:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:50:29 INFO - 'WINDIR': 'C:\\windows', 21:50:29 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:50:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:50:30 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:50:30 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 21:50:30 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 21:50:31 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:50:31 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 21:50:31 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 21:50:31 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) 21:50:31 INFO - Installing collected packages: mozsystemmonitor 21:50:31 INFO - Running setup.py install for mozsystemmonitor 21:50:31 INFO - Successfully installed mozsystemmonitor 21:50:31 INFO - Cleaning up... 21:50:31 INFO - Return code: 0 21:50:31 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 21:50:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:50:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:31 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 0x019A8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01968EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0144E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01368B48>, '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 0x0138A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A29660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-020', 'USERDOMAIN': 'T-W732-IX-020', '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-020', '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 21:50:31 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 21:50:31 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 21:50:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:50:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:50:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:50:31 INFO - 'COMPUTERNAME': 'T-W732-IX-020', 21:50:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:50:31 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:50:31 INFO - 'DCLOCATION': 'SCL3', 21:50:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:50:31 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:50:31 INFO - 'HOMEDRIVE': 'C:', 21:50:31 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:50:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:50:31 INFO - 'KTS_VERSION': '1.19c', 21:50:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:50:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-020', 21:50:31 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:50:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:50:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:31 INFO - 'MOZ_NO_REMOTE': '1', 21:50:31 INFO - 'NO_EM_RESTART': '1', 21:50:31 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:50:31 INFO - 'OS': 'Windows_NT', 21:50:31 INFO - 'OURDRIVE': 'C:', 21:50:31 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;', 21:50:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:50:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:50:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:50:31 INFO - 'PROCESSOR_LEVEL': '6', 21:50:31 INFO - 'PROCESSOR_REVISION': '1e05', 21:50:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:50:31 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:50:31 INFO - 'PROMPT': '$P$G', 21:50:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 21:50:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:50:31 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:50:31 INFO - 'PWD': 'C:\\slave\\test-pgo', 21:50:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:50:31 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:50:31 INFO - 'SYSTEMDRIVE': 'C:', 21:50:31 INFO - 'SYSTEMROOT': 'C:\\windows', 21:50:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:50:31 INFO - 'TEST1': 'testie', 21:50:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:50:31 INFO - 'USERDOMAIN': 'T-W732-IX-020', 21:50:31 INFO - 'USERNAME': 'cltbld', 21:50:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:50:31 INFO - 'WINDIR': 'C:\\windows', 21:50:31 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:50:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:50:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:50:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:50:31 INFO - Downloading/unpacking blobuploader==1.2.4 21:50:31 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 21:50:31 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 21:50:32 INFO - Downloading blobuploader-1.2.4.tar.gz 21:50:32 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 21:50:32 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 21:50:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:50:32 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 21:50:32 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 21:50:33 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 21:50:33 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 21:50:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:50: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 21:50: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 21:50:33 INFO - Downloading docopt-0.6.1.tar.gz 21:50:33 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 21:50:33 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 21:50:33 INFO - Installing collected packages: blobuploader, requests, docopt 21:50:33 INFO - Running setup.py install for blobuploader 21:50:33 INFO - Running setup.py install for requests 21:50:34 INFO - Running setup.py install for docopt 21:50:34 INFO - Successfully installed blobuploader requests docopt 21:50:34 INFO - Cleaning up... 21:50:36 INFO - Return code: 0 21:50:36 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 21:50: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')]} 21:50:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50: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')]} 21:50:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:36 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 0x019A8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01968EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0144E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01368B48>, '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 0x0138A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A29660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-020', 'USERDOMAIN': 'T-W732-IX-020', '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-020', '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 21:50:36 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 21:50:36 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 21:50:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:50:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:50:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:50:36 INFO - 'COMPUTERNAME': 'T-W732-IX-020', 21:50:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:50:36 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:50:36 INFO - 'DCLOCATION': 'SCL3', 21:50:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:50:36 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:50:36 INFO - 'HOMEDRIVE': 'C:', 21:50:36 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:50:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:50:36 INFO - 'KTS_VERSION': '1.19c', 21:50:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:50:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-020', 21:50:36 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:50:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:50:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:36 INFO - 'MOZ_NO_REMOTE': '1', 21:50:36 INFO - 'NO_EM_RESTART': '1', 21:50:36 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:50:36 INFO - 'OS': 'Windows_NT', 21:50:36 INFO - 'OURDRIVE': 'C:', 21:50: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;', 21:50:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:50:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:50:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:50:36 INFO - 'PROCESSOR_LEVEL': '6', 21:50:36 INFO - 'PROCESSOR_REVISION': '1e05', 21:50:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:50:36 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:50:36 INFO - 'PROMPT': '$P$G', 21:50:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 21:50:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:50:36 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:50:36 INFO - 'PWD': 'C:\\slave\\test-pgo', 21:50:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:50:36 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:50:36 INFO - 'SYSTEMDRIVE': 'C:', 21:50:36 INFO - 'SYSTEMROOT': 'C:\\windows', 21:50:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:50:36 INFO - 'TEST1': 'testie', 21:50:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:50:36 INFO - 'USERDOMAIN': 'T-W732-IX-020', 21:50:36 INFO - 'USERNAME': 'cltbld', 21:50:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:50:36 INFO - 'WINDIR': 'C:\\windows', 21:50:36 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:50:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:50:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:50:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:50:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 21:50:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mndwnt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 21:50:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 21:50:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1vremc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 21:50:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 21:50:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lmi5sk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 21:50:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 21:50:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qve4ql-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 21:50:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 21:50:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n9wwhl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 21:50:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 21:50:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-njots1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 21:50:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 21:50:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oo6sgu-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 21:50:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 21:50:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-p_ukde-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 21:50:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 21:50:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1jd2r5-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 21:50:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 21:50:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lyazmr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 21:50:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 21:50:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oxlxlr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 21:50:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 21:50:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yx2r3p-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 21:50:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 21:50:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oy3ykw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 21:50:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 21:50:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ucpeez-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 21:50:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 21:50:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mku9bk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:50:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 21:50:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-s4pnjh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 21:50:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 21:50:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-imt9zk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 21:50:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:50:39 INFO - Running setup.py install for manifestparser 21:50:39 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:39 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:40 INFO - Running setup.py install for mozcrash 21:50:40 INFO - Running setup.py install for mozdebug 21:50:40 INFO - Running setup.py install for mozdevice 21:50:41 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:41 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:41 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:41 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:41 INFO - Running setup.py install for mozfile 21:50:41 INFO - Running setup.py install for mozhttpd 21:50:42 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:42 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:42 INFO - Running setup.py install for mozinfo 21:50:42 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:42 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:42 INFO - Running setup.py install for mozInstall 21:50:42 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:42 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:42 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:42 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:42 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:42 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:42 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:42 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:43 INFO - Running setup.py install for mozleak 21:50:43 INFO - Running setup.py install for mozlog 21:50:43 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:43 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:44 INFO - Running setup.py install for moznetwork 21:50:44 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:44 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:44 INFO - Running setup.py install for mozprocess 21:50:45 INFO - Running setup.py install for mozprofile 21:50:45 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:45 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:45 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:45 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:45 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:45 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:45 INFO - Running setup.py install for mozrunner 21:50:46 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:46 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:46 INFO - Running setup.py install for mozscreenshot 21:50:46 INFO - Running setup.py install for moztest 21:50:47 INFO - Running setup.py install for mozversion 21:50:47 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:50:47 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 21:50:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:50:47 INFO - Cleaning up... 21:50:47 INFO - Return code: 0 21:50:47 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 21:50:47 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')]} 21:50:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50:47 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')]} 21:50:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:47 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 0x019A8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01968EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0144E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01368B48>, '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 0x0138A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A29660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-020', 'USERDOMAIN': 'T-W732-IX-020', '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-020', '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 21:50:47 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 21:50:47 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 21:50:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:50:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:50:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:50:47 INFO - 'COMPUTERNAME': 'T-W732-IX-020', 21:50:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:50:47 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:50:47 INFO - 'DCLOCATION': 'SCL3', 21:50:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:50:47 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:50:47 INFO - 'HOMEDRIVE': 'C:', 21:50:47 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:50:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:50:47 INFO - 'KTS_VERSION': '1.19c', 21:50:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:50:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-020', 21:50:47 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:50:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:50:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:47 INFO - 'MOZ_NO_REMOTE': '1', 21:50:47 INFO - 'NO_EM_RESTART': '1', 21:50:47 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:50:47 INFO - 'OS': 'Windows_NT', 21:50:47 INFO - 'OURDRIVE': 'C:', 21:50:47 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;', 21:50:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:50:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:50:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:50:47 INFO - 'PROCESSOR_LEVEL': '6', 21:50:47 INFO - 'PROCESSOR_REVISION': '1e05', 21:50:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:50:47 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:50:47 INFO - 'PROMPT': '$P$G', 21:50:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 21:50:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:50:47 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:50:47 INFO - 'PWD': 'C:\\slave\\test-pgo', 21:50:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:50:47 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:50:47 INFO - 'SYSTEMDRIVE': 'C:', 21:50:47 INFO - 'SYSTEMROOT': 'C:\\windows', 21:50:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:50:47 INFO - 'TEST1': 'testie', 21:50:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:50:47 INFO - 'USERDOMAIN': 'T-W732-IX-020', 21:50:47 INFO - 'USERNAME': 'cltbld', 21:50:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:50:47 INFO - 'WINDIR': 'C:\\windows', 21:50:47 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:50:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:50:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:50:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:50:47 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 21:50:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ez4id3-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 21:50:48 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)) 21:50:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 21:50:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vnb0ah-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 21:50:48 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)) 21:50:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 21:50:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-edbs0r-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 21:50:48 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)) 21:50:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 21:50:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xl9fow-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 21:50:48 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)) 21:50:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 21:50:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-syak6v-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 21:50:48 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)) 21:50:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 21:50:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dmww7k-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 21:50:48 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)) 21:50:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 21:50:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lijxgo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 21:50:48 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)) 21:50:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 21:50:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4cuuiq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 21:50:49 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)) 21:50:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 21:50:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mxz5br-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 21:50:49 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)) 21:50:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 21:50:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-c23vv1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 21:50:49 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)) 21:50:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 21:50:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-q1lgd2-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 21:50:49 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)) 21:50:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 21:50:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fpueep-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 21:50:49 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)) 21:50:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 21:50:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6bmzfv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 21:50:49 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)) 21:50:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 21:50:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e8m21m-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 21:50:50 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)) 21:50:50 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 21:50:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iilpv6-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:50:50 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)) 21:50:50 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 21:50:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8wtfsu-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 21:50:50 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)) 21:50:50 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 21:50:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-drbouu-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 21:50:50 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)) 21:50:50 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)) 21:50:50 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)) 21:50:50 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)) 21:50:50 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)) 21:50:50 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)) 21:50:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 21:50:50 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 21:50:50 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 21:50:51 INFO - Downloading blessings-1.5.1.tar.gz 21:50:51 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 21:50:51 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 21:50:51 INFO - Installing collected packages: blessings 21:50:51 INFO - Running setup.py install for blessings 21:50:51 INFO - Successfully installed blessings 21:50:51 INFO - Cleaning up... 21:50:54 INFO - Return code: 0 21:50:54 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 21:50:54 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 21:50:54 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 21:50:54 INFO - Reading from file tmpfile_stdout 21:50:54 INFO - Using _rmtree_windows ... 21:50:54 INFO - Using _rmtree_windows ... 21:50:54 INFO - Current package versions: 21:50:54 INFO - blessings == 1.5.1 21:50:54 INFO - blobuploader == 1.2.4 21:50:54 INFO - docopt == 0.6.1 21:50:54 INFO - manifestparser == 1.1 21:50:54 INFO - mozInstall == 1.12 21:50:54 INFO - mozcrash == 0.16 21:50:54 INFO - mozdebug == 0.1 21:50:54 INFO - mozdevice == 0.46 21:50:54 INFO - mozfile == 1.2 21:50:54 INFO - mozhttpd == 0.7 21:50:54 INFO - mozinfo == 0.8 21:50:54 INFO - mozleak == 0.1 21:50:54 INFO - mozlog == 3.0 21:50:54 INFO - moznetwork == 0.27 21:50:54 INFO - mozprocess == 0.22 21:50:54 INFO - mozprofile == 0.27 21:50:54 INFO - mozrunner == 6.11 21:50:54 INFO - mozscreenshot == 0.1 21:50:54 INFO - mozsystemmonitor == 0.0 21:50:54 INFO - moztest == 0.7 21:50:54 INFO - mozversion == 1.4 21:50:54 INFO - psutil == 3.1.1 21:50:54 INFO - requests == 1.2.3 21:50:54 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 21:50:54 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')]} 21:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50:54 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')]} 21:50:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:54 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 0x019A8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01968EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0144E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01368B48>, '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 0x0138A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A29660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-020', 'USERDOMAIN': 'T-W732-IX-020', '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-020', '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 21:50:55 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 21:50:55 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 21:50:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:50:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:50:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:50:55 INFO - 'COMPUTERNAME': 'T-W732-IX-020', 21:50:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:50:55 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:50:55 INFO - 'DCLOCATION': 'SCL3', 21:50:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:50:55 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:50:55 INFO - 'HOMEDRIVE': 'C:', 21:50:55 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:50:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:50:55 INFO - 'KTS_VERSION': '1.19c', 21:50:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:50:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-020', 21:50:55 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:50:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:50:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:55 INFO - 'MOZ_NO_REMOTE': '1', 21:50:55 INFO - 'NO_EM_RESTART': '1', 21:50:55 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:50:55 INFO - 'OS': 'Windows_NT', 21:50:55 INFO - 'OURDRIVE': 'C:', 21:50:55 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:50:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:50:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:50:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:50:55 INFO - 'PROCESSOR_LEVEL': '6', 21:50:55 INFO - 'PROCESSOR_REVISION': '1e05', 21:50:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:50:55 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:50:55 INFO - 'PROMPT': '$P$G', 21:50:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 21:50:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:50:55 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:50:55 INFO - 'PWD': 'C:\\slave\\test-pgo', 21:50:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:50:55 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:50:55 INFO - 'SYSTEMDRIVE': 'C:', 21:50:55 INFO - 'SYSTEMROOT': 'C:\\windows', 21:50:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:50:55 INFO - 'TEST1': 'testie', 21:50:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:50:55 INFO - 'USERDOMAIN': 'T-W732-IX-020', 21:50:55 INFO - 'USERNAME': 'cltbld', 21:50:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:50:55 INFO - 'WINDIR': 'C:\\windows', 21:50:55 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:50:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:50:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:50:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:50:55 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)) 21:50:55 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)) 21:50:55 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)) 21:50:55 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)) 21:50:55 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)) 21:50:55 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)) 21:50:55 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)) 21:50:55 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)) 21:50:55 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)) 21:50:55 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)) 21:50:55 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)) 21:50:55 INFO - Cleaning up... 21:50:55 INFO - Return code: 0 21:50:55 INFO - Running post-action listener: _resource_record_post_action 21:50:55 INFO - Running post-action listener: _start_resource_monitoring 21:50:55 INFO - Starting resource monitoring. 21:50:55 INFO - ##### 21:50:55 INFO - ##### Running install step. 21:50:55 INFO - ##### 21:50:55 INFO - Running pre-action listener: _resource_record_pre_action 21:50:55 INFO - Running main action method: install 21:50:55 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 21:50:55 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 21:50:55 INFO - Reading from file tmpfile_stdout 21:50:55 INFO - Using _rmtree_windows ... 21:50:55 INFO - Using _rmtree_windows ... 21:50:55 INFO - Detecting whether we're running mozinstall >=1.0... 21:50:55 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 21:50:55 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 21:50:56 INFO - Reading from file tmpfile_stdout 21:50:56 INFO - Output received: 21:50:56 INFO - Usage: mozinstall-script.py [options] installer 21:50:56 INFO - Options: 21:50:56 INFO - -h, --help show this help message and exit 21:50:56 INFO - -d DEST, --destination=DEST 21:50:56 INFO - Directory to install application into. [default: 21:50:56 INFO - "C:\slave\test-pgo"] 21:50:56 INFO - --app=APP Application being installed. [default: firefox] 21:50:56 INFO - Using _rmtree_windows ... 21:50:56 INFO - Using _rmtree_windows ... 21:50:56 INFO - mkdir: C:\slave\test-pgo\build\application 21:50:56 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'] 21:50:56 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 21:50:58 INFO - Reading from file tmpfile_stdout 21:50:58 INFO - Output received: 21:50:58 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 21:50:58 INFO - Using _rmtree_windows ... 21:50:58 INFO - Using _rmtree_windows ... 21:50:58 INFO - Running post-action listener: _resource_record_post_action 21:50:58 INFO - ##### 21:50:58 INFO - ##### Running run-tests step. 21:50:58 INFO - ##### 21:50:58 INFO - Running pre-action listener: _resource_record_pre_action 21:50:58 INFO - Running main action method: run_tests 21:50:58 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 21:50:58 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 21:50:58 INFO - Python 2.7.3 21:50:58 INFO - Return code: 0 21:50:58 INFO - grabbing minidump binary from tooltool 21:50:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:50:58 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 0x01368B48>, '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 0x0138A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A29660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 21:50:58 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 21:50:58 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 21:50:58 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 21:50:59 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpiimnmp 21:50:59 INFO - INFO - File integrity verified, renaming tmpiimnmp to win32-minidump_stackwalk.exe 21:50:59 INFO - Return code: 0 21:50:59 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 21:50:59 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 21:50:59 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 21:50:59 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 21:50:59 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 21:50:59 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/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-020', '--webServer', 'localhost'] in C:\slave\test-pgo\build 21:50:59 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/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-020 --webServer localhost 21:50:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:50:59 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:50:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:50:59 INFO - 'COMPUTERNAME': 'T-W732-IX-020', 21:50:59 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:50:59 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:50:59 INFO - 'DCLOCATION': 'SCL3', 21:50:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:50:59 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:50:59 INFO - 'HOMEDRIVE': 'C:', 21:50:59 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:50:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:50:59 INFO - 'KTS_VERSION': '1.19c', 21:50:59 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:50:59 INFO - 'LOGONSERVER': '\\\\T-W732-IX-020', 21:50:59 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 21:50:59 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 21:50:59 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:50:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:50:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:59 INFO - 'MOZ_NO_REMOTE': '1', 21:50:59 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 21:50:59 INFO - 'NO_EM_RESTART': '1', 21:50:59 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:50:59 INFO - 'OS': 'Windows_NT', 21:50:59 INFO - 'OURDRIVE': 'C:', 21:50:59 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:50:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:50:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:50:59 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:50:59 INFO - 'PROCESSOR_LEVEL': '6', 21:50:59 INFO - 'PROCESSOR_REVISION': '1e05', 21:50:59 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:50:59 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:50:59 INFO - 'PROMPT': '$P$G', 21:50:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 21:50:59 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:50:59 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:50:59 INFO - 'PWD': 'C:\\slave\\test-pgo', 21:50:59 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 21:50:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:50:59 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:50:59 INFO - 'SYSTEMDRIVE': 'C:', 21:50:59 INFO - 'SYSTEMROOT': 'C:\\windows', 21:50:59 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:50:59 INFO - 'TEST1': 'testie', 21:50:59 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:50:59 INFO - 'USERDOMAIN': 'T-W732-IX-020', 21:50:59 INFO - 'USERNAME': 'cltbld', 21:50:59 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:50:59 INFO - 'WINDIR': 'C:\\windows', 21:50:59 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:50:59 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:50:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:50:59 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/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-020', '--webServer', 'localhost'] with output_timeout 3600 21:51:00 INFO - mozversion INFO | application_buildid: 20151026163226 21:51:00 INFO - mozversion INFO | application_changeset: 6c7c983bce46a460c2766fbdd73283f6d2b03a69 21:51:00 INFO - mozversion INFO | application_display_name: Nightly 21:51:00 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:51:00 INFO - mozversion INFO | application_name: Firefox 21:51:00 INFO - mozversion INFO | application_remotingname: firefox 21:51:00 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 21:51:00 INFO - mozversion INFO | application_vendor: Mozilla 21:51:00 INFO - mozversion INFO | application_version: 44.0a1 21:51:00 INFO - mozversion INFO | platform_buildid: 20151026163226 21:51:00 INFO - mozversion INFO | platform_changeset: 6c7c983bce46a460c2766fbdd73283f6d2b03a69 21:51:00 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 21:51:00 INFO - mozversion INFO | platform_version: 44.0a1 21:51:00 INFO - 2015-10-26 21:51:00,286 DEBUG : using testdate: 1445921460 21:51:00 INFO - 2015-10-26 21:51:00,286 DEBUG : actual date: 1445921460 21:51:00 INFO - 2015-10-26 21:51:00,309 INFO : starting webserver on 'localhost:49790' 21:51:00 INFO - 2015-10-26 21:51:00,311 INFO : Starting test suite T-W732-IX-020 21:51:00 INFO - 2015-10-26 21:51:00,311 INFO : Starting test dromaeo_css 21:51:00 INFO - 2015-10-26 21:51:00,311 DEBUG : operating with platform_type : w7_ 21:51:00 INFO - 2015-10-26 21:51:00,312 INFO : Initialising browser for dromaeo_css test... 21:51:00 INFO - 2015-10-26 21:51:00,404 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpuszh61\profile http://localhost:49790/getInfo.html 21:51:08 INFO - 2015-10-26 21:51:08,256 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 21:51:08 INFO - 2015-10-26 21:51:08,256 DEBUG : BROWSER_OUTPUT: colorDepth:24 21:51:08 INFO - 2015-10-26 21:51:08,256 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 21:51:08 INFO - 2015-10-26 21:51:08,256 DEBUG : BROWSER_OUTPUT: __metrics 21:51:08 INFO - 2015-10-26 21:51:08,355 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:51:10 INFO - 2015-10-26 21:51:10,094 INFO : Browser initialized. 21:51:10 INFO - 2015-10-26 21:51:10,094 INFO : Running cycle 1/1 for dromaeo_css test... 21:51:10 INFO - 2015-10-26 21:51:10,094 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpuszh61\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 21:51:21 INFO - 2015-10-26 21:51:21,503 DEBUG : BROWSER_OUTPUT: RSS: Main: 130117632 21:51:21 INFO - 2015-10-26 21:51:21,503 DEBUG : BROWSER_OUTPUT: 21:52:12 INFO - 2015-10-26 21:52:12,828 DEBUG : BROWSER_OUTPUT: 1445921532814 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] 21:54:22 INFO - 2015-10-26 21:54:22,571 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49790/tests/dromaeo/cssquery-ext.html) 21:54:23 INFO - 2015-10-26 21:54:23,591 DEBUG : BROWSER_OUTPUT: RSS: Main: 197603328 21:54:23 INFO - 2015-10-26 21:54:23,591 DEBUG : BROWSER_OUTPUT: 21:57:25 INFO - 2015-10-26 21:57:25,934 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/dromaeo/cssquery-ext.html (next: http://localhost:49790/tests/dromaeo/cssquery-jquery.html) 21:57:26 INFO - 2015-10-26 21:57:26,489 DEBUG : BROWSER_OUTPUT: RSS: Main: 198000640 21:57:26 INFO - 2015-10-26 21:57:26,489 DEBUG : BROWSER_OUTPUT: 22:00:28 INFO - 2015-10-26 22:00:28,368 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49790/tests/dromaeo/cssquery-mootools.html) 22:00:28 INFO - 2015-10-26 22:00:28,924 DEBUG : BROWSER_OUTPUT: RSS: Main: 149106688 22:00:28 INFO - 2015-10-26 22:00:28,924 DEBUG : BROWSER_OUTPUT: 22:03:30 INFO - 2015-10-26 22:03:30,782 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49790/tests/dromaeo/cssquery-prototype.html) 22:03:31 INFO - 2015-10-26 22:03:31,381 DEBUG : BROWSER_OUTPUT: RSS: Main: 135360512 22:03:31 INFO - 2015-10-26 22:03:31,381 DEBUG : BROWSER_OUTPUT: 22:06:34 INFO - 2015-10-26 22:06:34,029 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49790/tests/dromaeo/cssquery-yui.html) 22:06:34 INFO - 2015-10-26 22:06:34,608 DEBUG : BROWSER_OUTPUT: RSS: Main: 132583424 22:06:34 INFO - 2015-10-26 22:06:34,609 DEBUG : BROWSER_OUTPUT: 22:09:37 INFO - 2015-10-26 22:09:37,058 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/dromaeo/cssquery-yui.html (next: http://localhost:49790/tests/dromaeo/cssquery-dojo.html) 22:09:37 INFO - 2015-10-26 22:09:37,305 DEBUG : BROWSER_OUTPUT: RSS: Main: 184049664 22:09:37 INFO - 2015-10-26 22:09:37,305 DEBUG : BROWSER_OUTPUT: 22:09:37 INFO - 2015-10-26 22:09:37,305 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:09:37 INFO - 2015-10-26 22:09:37,305 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:09:37 INFO - 2015-10-26 22:09:37,305 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:09:37 INFO - 2015-10-26 22:09:37,305 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:09:37 INFO - 2015-10-26 22:09:37,306 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2741.6435863337765;2776.625155603993;2804.9298767530795;2815.788815838812;2824.858757062146;5255.474452554743;5269.235960785683;5305.628605997582;5402.648827826193;5415.431379705134;5230.450802665722;5301.045811753883;5394.541463975557;5417.160340147274;5423.674579525231;4514.367988481616;4575.359449677031;4580.954190458095;4677.672562920597;4680.063987202573;4587.174308624446;4589.632829373642;4630.837920672777;4646.907061858761;4648.093621743761;4583.427071616048;4588.197065513522;4630.236011058171;4636.420447444069;4637.0725854829;4385.868423947287;4534.38785763921;4600.102979918917;4643.8606841794635;4650.0932318197965;4540.88647783808;4565.315202719618;4651.651126165551;4651.813927442899;4661.88345291367;5174.948250517485;5187.403448603422;5224.8693782655355;5344.14493681013;5366.651167674088;5480.369757477902;5507.642003269774;5594.049011668027;5604.971975140098;5643.943560564424;5427.158790387457;5632.915506267409;5714.399988001242;5722;5785.942140578582;5175.663581867204;5201.921971170435;5206;5299.814506492293;5336.866578335534;5629.183768353566;5700;5719.084946408638;5770.42295770428;5845.269341332333;5450.672959622356;5482.396936337;5588.329400471969;5588.720563971785;5595.804146854841;5563.36021357541;5590.832275031756;5633.774649014075;5671.319441667027;5678.3753787084215;5399.568034557226;5423.267858839085;5477.4248703886315;5512.476314750093;5601.887962240732;5315.989961907225;5589.496945274945;5651;5682.744276507485;5696.031674615326;3474.652534746505;3504.737144714106;3568.7323450741296;3569.1790888095884;3574.2136729919375;3169.8415079245947;3190.5533225348468;3233.288676491168;3275.9181020474202;3281.753868459875;5415.431379705153;5440.347158341025;5472.808451704172;5529.170624406291;5530.723463826873;5525.419830917776;5667.064934285797;5669.376368599451;5770.13447982806;5776.913346299809;5239.764210610531;5287.180487024518;5308.389535203424;5425.864353391118;5449.972750136224;4237.978810105929;4402.361657559636;4434.556544345604;4438.822447102079;4449.510553839075;5655.4627310407095;5897.705114744245;5906.173135760997;5912.320083190488;5915.7633694652595;5468.207109969112;5555.916661250165;5674.177244299636;5748.281464816898;5769.74036168365;5745;5847.152162936435;5848.619839710405;5920.644761314335;5966.164736936746;5374.4088150304215;5433.565314774887;5542.2795036644975;5585.273914391103;5628.634138780966;5629.408912064338;5672.801451949162;5712.514436272963;5758.452946970031;5837.1244313351635;5306.734663266899;5495.148252020943;5589.580781441489;5677.517411019944;5677.943220567741;5370.194470829407;5404.78380864761;5495;5626.943730562642;5628.9305032043785;5240.68555886648;5308.2568440419145;5322.654027488201;5351.250824884597;5435;5405.78376864921;5557.41647127062;5639.2668953035845;5671.206031155559;5674.546036317167;5311.336082989626;5441.809536666356;5453.345598528202;5528.502434780889;5575.247341608993 22:09:37 INFO - 2015-10-26 22:09:37,308 DEBUG : BROWSER_OUTPUT: |1;ext.html;478.4760687047686;499.091653191192;509.41417370024465;515.945825688303;517.0589527060748;32331;32609.51085733713;38523.42214866779;38976.22047559047;39009;39676;40214;40473.59526404735;40524.59475405245;40539;7853.450258481908;7923.484973476734;8194.828370481828;8338.24955753982;8341.54121523316;10184.64353747619;10190.59237630494;10377.948110259476;10397.584096636125;10440.738981525485;10118.392896426201;10293.073623373895;10382.48087595621;10393;10396.636117735843;9963.551640176172;10194.796104077914;10240.795184096276;10253.58985640581;10266.230032747537;10076.848847267296;10177.134943529809;10186.796264074752;10201.642942496977;10213.080822725917;37538.24923501542;37554.24891502182;37627.623723762685;37712.85158632548;37820.81089594562;3055.724984751361;3065.1264389648845;3146.718628993984;3150.102220867042;3221.951670724941;28550.428991420264;29562.26094347637;29640;29894.70105298941;30707.38585228283;2507.9749202507924;2547.9490410191693;2572.4726431081585;2575.6780402449695;2577.497388009339;318231;318517;321258;321285;322059;45953;52986.73506632442;53303.73348133312;53316.204111013074;53332.73333633307;46141.53858461372;58763.412365875796;59170.70414647985;59307.40692593019;59309;14674.266286685623;14798;14847.777283340758;14944.327505262288;14952.476663316731;11236.943815281034;11247.718807029725;11336.659900203007;11341.036011939077;11360.431978401048;17589;17621.383251586132;17715;17756.55608609795;17833.375831845824;7648.694052237959;7853.921460785434;7855.253750893656;7904.32813210872;7919.247671471201;852.3309202276183;920.7744102694883;960.9471479068578;989.0702739424918;1044.5665049004579;27283.590746138823;28176;28781.424371512872;28850.71149288522;28978.27554311159;951.4861974533812;984.8276551603441;990.4354517924858;992.9553170107363;1103.5475455063547;15658.765118523232;15704.371825127095;15841.366345346287;15992.520224393287;16129.112898790445;324357;329534;330820;331509;336199;49039.264411033866;49485.257721134214;49560.256596151085;49683.006339873726;49991.75004124956;1216.8357271768375;1216.8683124693944;1231.5689508671894;1233.993830030862;1238.2261086820738;24434;29712.554311685773;30440.238994025327;30512.694873051874;30565;44984.550154498036;45233;45359.319610206534;45610;45701.77149114233;329126;333926;334434;335187;335787;32566.672498687607;33409.49885751667;37589.81205093957;38733.612663873;39237.41143882787;42376.78811605922;43314.13371732485;44052;44154.11691766211;44309;34890.47664285089;36481.63518364782;37467.92215443294;37757.244855102195;37994.240115198096;29919.401611968075;33078.66921330852;36636.633633663325;36851.63148368482;37249.627503724616;31657.683423165472;36297.45553816748;36631.32864361481;36813.81593092124;36943.07642308964;32141.196470088435;33393.499097514046;33985.32029359449;34335.31329373349;34459.13852153716;26442.603360949986;26676;26942.191734248005;27156.32109197286;27177 22:09:37 INFO - 2015-10-26 22:09:37,312 DEBUG : BROWSER_OUTPUT: |2;jquery.html;298.333225241585;298.5298155405237;298.69980669427224;299.613498586823;304.90547930141656;10519.684409467709;14110.153390796559;16172.0296782193;16597.502074937755;16666.749998750027;17185.484435466926;17305.39431119911;17441;17608.11959402028;17727.73408398875;5428.45715428457;5534.363548191949;5539.750711218005;5544.667319960815;5557.944420555803;4208.810603522848;4562.794674239667;5508.476694714016;6038.0942858571125;6192.690365481731;6095.939040609582;6115.755369785204;6116.877662446748;6122.418370254819;6124.8468788280425;5946.256717910262;6009.128676341906;6023.879522409571;6037.09443583466;6107.908381374283;4939.901201975977;5203.713795741232;5206.281926964093;5215.713135777531;5216.66091704038;22089;22215.66676499854;22407.887960560092;22418.215362462237;22668.206612768474;1900.8574356923286;2014.5668681233458;2055.496403381181;2089.3314139475374;2120.7879212078824;14240.074395164385;14241.715165696629;14352.354144063438;14723;14882.702345953021;1698.5074328444737;1764.5765016396101;1773.9201735921915;1813.37662209113;1826.9451916442529;77357;79244.99999999942;79367;79789;80920.59539702322;9650.517474126265;9879.950600247097;10091.646792362231;10123.240756943258;10176;12368.505259789688;12507.312097834525;12597.74804503923;12608.81086783699;12657.873421265853;7717.459777815499;7788.987431633851;7796.649150788228;7818.569978651229;7824.139344672081;7779.144294127642;7783.33841623457;7835.863799749119;7836.764897053097;7839.490433121829;5679.119736440858;5679.8864022720145;5696.829095127153;5725.112607545837;5791.189233507313;1631.0132369916266;1647.2669662000294;1658.6185177409263;1732.5755189978536;1752.1589636974325;376.495496035314;399.2035888402597;402.12537730436185;416.0887656033277;419.8656429942389;11053.723656908642;11203.607873724544;11446.969772720495;11506.482208300647;11515.827262591069;436.56561721087724;437.5668088592271;446.3082222555027;446.33050424363455;446.90168163004085;5942.108683697394;6012.248468941382;6028.849278768066;6028.879422411527;6046.486048685823;409734;410724.7855042882;410732;411698;413238;77386;78165;79528;79572.60213698895;80275.59862200769;438.2396541999628;442.40938347306064;444.8887778055486;448.1641738158376;448.30512705307297;9827.754306142404;10178.745531361776;10247.795044098928;10400.739981500523;10418.426986515511;39481;40793.79603101966;41232.58767412287;41478;42041.78979105085;54838.72580637071;55402.16896746471;55846.720766395905;55883.72058139846;56355.436445635016;5486.725663716718;5775.133729940542;5824.15549745293;5916.023856063702;5927.762889484372;11383.373914434786;11615.593454229062;11627.069834413058;11634.418279086181;11701.76596468083;8854.114588541095;8873.423227490193;9799.804003920024;9953.850692239477;9973.102420782165;9431.292653050912;9540.141387274902;9571.37786043905;9695.951520242354;9803.558839852081;8663.436876603;8909.420887642542;9231.861522077308;9741.853872191781;9793.069658382443;5515.724213789214;5819.156222347651;5833.795817146549;5835.56233282514;5843.386444423274;9482.051794820738;9627.229821614113;9754.804903902024;9775.804483910424;9795.363301385387 22:09:37 INFO - 2015-10-26 22:09:37,313 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1239.7644447554965;1242.6147894152807;1270.4410059573788;1516.8786497080234;1537.9769303460444;6937.653117344125;7007.579545227283;7135.215126336099;7141;7141.821454463641;7106.715731370739;7115.3951914087365;7115.501914865961;7129.215786263507;7134.821629459253;1990.7013947907822;2008.5781985783003;2010.5576773109892;2010.5576773109892;2012.4968757810548;2014.8388128949723;2037.8064083912004;2042.509797648561;2047.04812262298;2060.206820374159;1846.6122114355935;1880.341880341883;2002.3692536850845;2035.8676686015435;2035.8778473291575;1910.2836436336374;1969.6848504239324;1987.4137129546818;1996.1616121228974;1999.4601457606439;2000.6598878190598;2007.5382661987824;2009.954823491776;2010.3566858605252;2021.82814460771;6384.201974753156;6553.180852393451;6913.585184888872;7056.329648683367;7088.043114179572;4243.299855523835;4397.1865204937185;4405.1850407674365;4405.647548196137;4411.183930972748;2753.559430491112;2811.507986102444;2835.4896118698625;2835.6313679221776;2838.262051866509;815.9959200204021;840.6007146605375;864.8789169516273;869.3002133282705;872;9418.481983490974;9477.431354118706;9496.097870702271;9497.905020949771;9506.619735210652;3810.6951443884977;3882.7864467453996;3904.570497245301;3916.412538119305;3930.21395720857;8302.501849889026;8310.626021828912;8312.625931833081;8320.8335833283;8321.126281740451;4394.692371534026;4404;4409.889752756207;4412.161689279027;4412.558744125626;3986.661133803659;4027.4160246764054;4045;4048.068944142805;4050.3721923101966;3065.095796739967;3104.363605460875;3130.2174456385906;3133.248020475115;3135.858886350074;990.4700984973149;1011.1607365886297;1014.8274793285159;1028.6759670703855;1029.1355261580309;1106.0543235533664;1118.0608289037248;1140.43548443521;1140.6349968010156;1140.8916152965455;6458.58019228758;6492.967535162294;6494.220693516714;6494.6103233806125;6499.837504062341;375.89662842718474;383.2354452866549;387.78671730548086;387.82741679952153;389.6707282346413;5119.411267704261;5153.458886816905;5169.9741501293;5172.818951336685;5179.119549676564;8081.232282933112;8192.713255036046;8308.875366869503;8318.916810831935;8412.200840920103;8260.958695206486;8415.663373464993;8434.536100514408;8451.915480845113;8466.830663386698;6615.5776508051;6658.966705166443;6667.86664266712;6669.533132680763;6673.399394054559;6201.596896201732;6303.1175635412;6320.115183874169;6401.935980640134;6429.32492088324;7644;7670.808229794076;7699;7699.923000769921;7700.229977002255;7723.266289702469;7835.804104897423;7868.291853733192;7896.84206315882;7899.763007109796;6370.4903607712195;6412.839679008062;6425.614463132227;6437.549371544134;6447.4197322241225;6780.966095169492;6788;6809.353111454404;6824.07874936897;6828.248892621708;6255.687215639109;6369;6388.776392826415;6500.3174666659315;6531.11829902976;6268.717907694256;6360.268569114611;6370.713317900798;6385.233771947396;6385.93614063872;6142.9114101205805;6318;6331.683415829282;6340.7463701451425;6344.429001389897;5523.6685798852195;5566.165075238585;5573.331200255833;5579.944200557942;5587.217789509553;5807.303123625192;5816.098504731774;5819.592628516132;5840.386759390372;5844.883102338014 22:09:37 INFO - 2015-10-26 22:09:37,342 DEBUG : BROWSER_OUTPUT: |4;prototype.html;256.1636257618874;269.873159614981;271.7689963530999;271.9909137101353;272.50948293072463;5975.223220981272;5976.850578735533;5977.52179825614;5989.9101513477335;6018.969905150469;5884.146798714184;5921.230240068796;5924.37794031627;5927.2294601701715;5934.495567876736;4089.1003979124544;4099.795010249476;4102.856400025999;4110.157417729373;4111.0339070318605;4082.632563069323;4141.4409054777525;4143.585641435848;4144.937825932613;4147.958520414787;3827.789471579062;4025.496812898388;4035.515738111416;4049.068714195737;4059.4316795648483;4023.7988100595;4093.488313960755;4137.958620413788;4149.170165966789;4155.501339839238;3212.5020621803656;3421.230223199785;3469.5489586353865;3482.9129271768156;3540.3804334241663;5663.575231857627;5726.226959360516;5798.913016304717;5799.4780469757925;5810.012297909366;4698.412698412699;4858.878528036792;4899.632527560448;4899.755012249373;4908.141075311806;6092.695365231765;6160.168377269056;6163.8150855474405;6181.96909015452;6192.133101365813;3906.843726250975;3907.3357529219816;3909.5894931032117;3916.74541154824;3921.29416704993;6475.417212450855;6518.413342799171;6525.216973963107;6528.543001989862;6528.706208220641;3692.464592634053;3839.6928245740273;3906.2382835346834;3988.222296652125;4001.7198796083994;4066.2274167908595;4101.46680931477;4111.280525907954;4117.485314335708;4119.196756632487;4881.121398148296;4904.8773780655765;4905.3132561441425;4906.607471402279;4911.312416261726;6211.9068213976825;6223.96888015557;6225.035119556476;6227.750889964351;6243;1437.8418373978855;1451.9419223231164;1455.345094707386;1457.883369330451;1459.1463993563673;908.6320040383655;919.6505327975326;945.9668911588199;947.2706016367496;947.303731757158;5976.133460648269;6022.006368949162;6157.538184636171;6176.227971503562;6180.412860778219;6483.092367068614;6523.217213934358;6525.184351956005;6553.246376666649;6553.737850485927;6000.249968753906;6147.44672979434;6150.753969841246;6153.446189842935;6156.107364432132;3525.9294814103573;3588.3361578108133;3593.2993066352333;3625.3836847735947;3631.618680038611;6658.667066646648;6684.799456016327;6688.531802773857;6696.665166741644;6697.832554186087;6469.611823290617;6529.88991871383;6539.411452969255;6549.148610680581;6564.901526477011;6392.648404337713;6566.638834864033;6621.4040736333;6685.665716714047;6687.598744075371;6281.309056003927;6412.9679351602945;6430.935690643034;6504.089427480062;6601.00984852276;6410.935890641033;6432.6140431572385;6435.4529864962005;6445.2265728112925;6445.70994305248;6778.4916131289365;6850.486213533905;6865.27914568963;6873.896891546531;6883.174019117738;4031.7580945143386;4105.2610530103675;4128.050548373831;4137.668986481134;4142.5443201247235;6468.482521398371;6522.412982831568;6559.573627714183;6574.474042076718;6594.868102638016;6100.0849872519475;6349.714262858275;6361.809145725636;6368.968155159195;6372.330905254881;6294.685265736786;6296.433321001132;6299.181106456132;6304.558680892477;6316.494680425647;6037.215162028908;6113.480354169946;6153.723082461211;6166.969165154145;6173.84565385869;6229.221347331583;6247.156633854372;6370.980643097082;6371.93628063732;6373.458256048102;6200.81397558074;6219.284782250099;6229.813105606839;6231.906521402273;6235 22:09:37 INFO - 2015-10-26 22:09:37,344 DEBUG : BROWSER_OUTPUT: |5;yui.html;713.9357457828796;729.6862349189846;735.2500449541468;740.551966060533;749.5652521537507;12363.258204507736;13224.7355052899;13394.866051339495;13421;13425.127366721157;12526;12561.183523070995;12939.48242070316;12957.48170073196;12991.870081299185;515.5257163409653;520.6901893373456;579.2035950567969;609.695152423787;617.5954749638994;599.4784537452423;613.9999999999977;614.735663664624;618.1623899616023;618.8391018335243;642.1427394428443;643.7231990244193;644.0918305189683;645.3514218210692;645.6029541831763;536.0806217337266;542.0974078159879;634.096412612026;636.9140166077613;639.0414378432351;464.4426687974444;465.348512083083;466.13299263370106;466.31218952045464;467.4157303370786;12356.320402377865;12374;12383.690407739788;12385.380730963416;12401.565945191966;12517;12769.680757981125;12874.935625321907;13037.15258508194;13124.934375328157;10876.510557024874;10960.506777194965;11145.387003714792;11190.776184476346;11324.490397932113;3480.7737497062863;3523.894283171509;3548.503209550665;3573.4282514797505;3663.8534458621625;56815;57502.13746793802;57548.13677794836;58479.41520584825;58829.117563236585;9595.568199431042;10005.44970026641;10172.542235599416;10221.386716797017;10661.946690266499;18071;18166.455006349832;18245.452636420927;18255.361062362754;18264.908675456536;7350.96324518374;7596.08846938371;7654.2345765423015;7703.845923081507;7728.381729461629;4142.855000074983;4251.277282861921;4303.612674859278;4373.431453911035;4437.201303765289;2976.241058530061;2991.087718245956;3005.5040918248683;3008.578798968146;3013.382256637384;447.09909532292454;447.427293064871;453.0463374596493;453.89106614412304;457.5150340638933;605.5549171359047;613.294711082259;635.139386131794;673.2795908378087;721.5742711800063;9468.763280918032;9585.376950498336;9620.75948101303;9635.614575416905;9907.405555666683;569.5898952754011;598.1655590451296;673.0324735683121;713.5255055388109;722.8987941688168;4222.767747773841;4559.886002849956;4617.861464156081;4648.325992731103;4681.110588988081;71181.64409178024;71473;71511.6424417885;71646.28353716397;71677.64161179161;58141.70929145327;59417.40582594205;59474.70262648659;59854;59936.32196892065;52640.736796315774;53205.46794532005;53619.73190134102;55517.44482555203;57224.42775572274;9827.705168844946;9843.753906152117;9902;10053.246006549392;10133.341332813343;40115.598844012355;40294.79852600718;40589;41387;41605;50661.24008139955;51891.48108519018;52404;52736;54899.72550137224;8087;8094.83810323802;8278.627461764356;8368.83262334762;8392.370572207232;13534.526291579747;13538.458461661423;14081.436742530184;14316.427342906167;14981.550553483414;13245.668858278621;13360.532381366995;13452.730945380841;13550.32248387557;13694.627944659105;13206.603801885958;13354.866451335361;13453.260070696206;13470.93264533671;13649.863501365257;8773.605187766694;12688.87311126877;13113;13181.143225690299;13556.322183890963;10751.784964300827;10777.299475534066;11096.833547496955;11340;11934.701632459259;10894.509747061244;11022.834657479983;11351.54593816238;11369.43152842338;11400.885991139983 22:09:37 INFO - 2015-10-26 22:09:37,345 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:09:37 INFO - 2015-10-26 22:09:37,345 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:09:37 INFO - 2015-10-26 22:09:37,345 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,767 22:09:37 INFO - 2015-10-26 22:09:37,345 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:09:37 INFO - 2015-10-26 22:09:37,345 DEBUG : BROWSER_OUTPUT: __startTimestamp1445922577305__endTimestamp 22:09:37 INFO - 2015-10-26 22:09:37,345 DEBUG : BROWSER_OUTPUT: 22:09:37 INFO - 2015-10-26 22:09:37,345 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:09:37 INFO - 2015-10-26 22:09:37,345 DEBUG : BROWSER_OUTPUT: Number of tests: 6 22:09:37 INFO - 2015-10-26 22:09:37,345 DEBUG : BROWSER_OUTPUT: 22:09:37 INFO - 2015-10-26 22:09:37,345 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5148.30 Median:5425.86 stddev:749.45 (13.8%) stddev-sans-first:727.58 22:09:37 INFO - 2015-10-26 22:09:37,346 DEBUG : BROWSER_OUTPUT: Values: 2741.6 2776.6 2804.9 2815.8 2824.9 5255.5 5269.2 5305.6 5402.6 5415.4 5230.5 5301.0 5394.5 5417.2 5423.7 4514.4 4575.4 4581.0 4677.7 4680.1 4587.2 4589.6 4630.8 4646.9 4648.1 4583.4 4588.2 4630.2 4636.4 4637.1 4385.9 4534.4 4600.1 4643.9 4650.1 4540.9 4565.3 4651.7 4651.8 4661.9 5174.9 5187.4 5224.9 5344.1 5366.7 5480.4 5507.6 5594.0 5605.0 5643.9 5427.2 5632.9 5714.4 5722.0 5785.9 5175.7 5201.9 5206.0 5299.8 5336.9 5629.2 5700.0 5719.1 5770.4 5845.3 5450.7 5482.4 5588.3 5588.7 5595.8 5563.4 5590.8 5633.8 5671.3 5678.4 5399.6 5423.3 5477.4 5512.5 5601.9 5316.0 5589.5 5651.0 5682.7 5696.0 3474.7 3504.7 3568.7 3569.2 3574.2 3169.8 3190.6 3233.3 3275.9 3281.8 5415.4 5440.3 5472.8 5529.2 5530.7 5525.4 5667.1 5669.4 5770.1 5776.9 5239.8 5287.2 5308.4 5425.9 5450.0 4238.0 4402.4 4434.6 4438.8 4449.5 5655.5 5897.7 5906.2 5912.3 5915.8 5468.2 5555.9 5674.2 5748.3 5769.7 5745.0 5847.2 5848.6 5920.6 5966.2 5374.4 5433.6 5542.3 5585.3 5628.6 5629.4 5672.8 5712.5 5758.5 5837.1 5306.7 5495.1 5589.6 5677.5 5677.9 5370.2 5404.8 5495.0 5626.9 5628.9 5240.7 5308.3 5322.7 5351.3 5435.0 5405.8 5557.4 5639.3 5671.2 5674.5 5311.3 5441.8 5453.3 5528.5 5575.2 22:09:37 INFO - 2015-10-26 22:09:37,346 DEBUG : BROWSER_OUTPUT: 22:09:37 INFO - 2015-10-26 22:09:37,346 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48972.43 Median:181949.33 stddev:85969.52 (47.2%) stddev-sans-first:86133.58 22:09:37 INFO - 2015-10-26 22:09:37,346 DEBUG : BROWSER_OUTPUT: Values: 478.5 499.1 509.4 515.9 517.1 32331.0 32609.5 38523.4 38976.2 39009.0 39676.0 40214.0 40473.6 40524.6 40539.0 7853.5 7923.5 8194.8 8338.2 8341.5 10184.6 10190.6 10377.9 10397.6 10440.7 10118.4 10293.1 10382.5 10393.0 10396.6 9963.6 10194.8 10240.8 10253.6 10266.2 10076.8 10177.1 10186.8 10201.6 10213.1 37538.2 37554.2 37627.6 37712.9 37820.8 3055.7 3065.1 3146.7 3150.1 3222.0 28550.4 29562.3 29640.0 29894.7 30707.4 2508.0 2547.9 2572.5 2575.7 2577.5 318231.0 318517.0 321258.0 321285.0 322059.0 45953.0 52986.7 53303.7 53316.2 53332.7 46141.5 58763.4 59170.7 59307.4 59309.0 14674.3 14798.0 14847.8 14944.3 14952.5 11236.9 11247.7 11336.7 11341.0 11360.4 17589.0 17621.4 17715.0 17756.6 17833.4 7648.7 7853.9 7855.3 7904.3 7919.2 852.3 920.8 960.9 989.1 1044.6 27283.6 28176.0 28781.4 28850.7 28978.3 951.5 984.8 990.4 993.0 1103.5 15658.8 15704.4 15841.4 15992.5 16129.1 324357.0 329534.0 330820.0 331509.0 336199.0 49039.3 49485.3 49560.3 49683.0 49991.8 1216.8 1216.9 1231.6 1234.0 1238.2 24434.0 29712.6 30440.2 30512.7 30565.0 44984.6 45233.0 45359.3 45610.0 45701.8 329126.0 333926.0 334434.0 335187.0 335787.0 32566.7 33409.5 37589.8 38733.6 39237.4 42376.8 43314.1 44052.0 44154.1 44309.0 34890.5 36481.6 37467.9 37757.2 37994.2 29919.4 33078.7 36636.6 36851.6 37249.6 31657.7 36297.5 36631.3 36813.8 36943.1 32141.2 33393.5 33985.3 34335.3 34459.1 26442.6 26676.0 26942.2 27156.3 27177.0 22:09:37 INFO - 2015-10-26 22:09:37,346 DEBUG : BROWSER_OUTPUT: 22:09:37 INFO - 2015-10-26 22:09:37,346 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25087.84 Median:5205.00 stddev:68180.71 (1309.9%) stddev-sans-first:68346.57 22:09:37 INFO - 2015-10-26 22:09:37,348 DEBUG : BROWSER_OUTPUT: Values: 298.3 298.5 298.7 299.6 304.9 10519.7 14110.2 16172.0 16597.5 16666.7 17185.5 17305.4 17441.0 17608.1 17727.7 5428.5 5534.4 5539.8 5544.7 5557.9 4208.8 4562.8 5508.5 6038.1 6192.7 6095.9 6115.8 6116.9 6122.4 6124.8 5946.3 6009.1 6023.9 6037.1 6107.9 4939.9 5203.7 5206.3 5215.7 5216.7 22089.0 22215.7 22407.9 22418.2 22668.2 1900.9 2014.6 2055.5 2089.3 2120.8 14240.1 14241.7 14352.4 14723.0 14882.7 1698.5 1764.6 1773.9 1813.4 1826.9 77357.0 79245.0 79367.0 79789.0 80920.6 9650.5 9880.0 10091.6 10123.2 10176.0 12368.5 12507.3 12597.7 12608.8 12657.9 7717.5 7789.0 7796.6 7818.6 7824.1 7779.1 7783.3 7835.9 7836.8 7839.5 5679.1 5679.9 5696.8 5725.1 5791.2 1631.0 1647.3 1658.6 1732.6 1752.2 376.5 399.2 402.1 416.1 419.9 11053.7 11203.6 11447.0 11506.5 11515.8 436.6 437.6 446.3 446.3 446.9 5942.1 6012.2 6028.8 6028.9 6046.5 409734.0 410724.8 410732.0 411698.0 413238.0 77386.0 78165.0 79528.0 79572.6 80275.6 438.2 442.4 444.9 448.2 448.3 9827.8 10178.7 10247.8 10400.7 10418.4 39481.0 40793.8 41232.6 41478.0 42041.8 54838.7 55402.2 55846.7 55883.7 56355.4 5486.7 5775.1 5824.2 5916.0 5927.8 11383.4 11615.6 11627.1 11634.4 11701.8 8854.1 8873.4 9799.8 9953.9 9973.1 9431.3 9540.1 9571.4 9696.0 9803.6 8663.4 8909.4 9231.9 9741.9 9793.1 5515.7 5819.2 5833.8 5835.6 5843.4 9482.1 9627.2 9754.8 9775.8 9795.4 22:09:37 INFO - 2015-10-26 22:09:37,349 DEBUG : BROWSER_OUTPUT: 22:09:37 INFO - 2015-10-26 22:09:37,349 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4907.03 Median:6228.64 stddev:2577.96 (41.4%) stddev-sans-first:2570.45 22:09:37 INFO - 2015-10-26 22:09:37,349 DEBUG : BROWSER_OUTPUT: Values: 1239.8 1242.6 1270.4 1516.9 1538.0 6937.7 7007.6 7135.2 7141.0 7141.8 7106.7 7115.4 7115.5 7129.2 7134.8 1990.7 2008.6 2010.6 2010.6 2012.5 2014.8 2037.8 2042.5 2047.0 2060.2 1846.6 1880.3 2002.4 2035.9 2035.9 1910.3 1969.7 1987.4 1996.2 1999.5 2000.7 2007.5 2010.0 2010.4 2021.8 6384.2 6553.2 6913.6 7056.3 7088.0 4243.3 4397.2 4405.2 4405.6 4411.2 2753.6 2811.5 2835.5 2835.6 2838.3 816.0 840.6 864.9 869.3 872.0 9418.5 9477.4 9496.1 9497.9 9506.6 3810.7 3882.8 3904.6 3916.4 3930.2 8302.5 8310.6 8312.6 8320.8 8321.1 4394.7 4404.0 4409.9 4412.2 4412.6 3986.7 4027.4 4045.0 4048.1 4050.4 3065.1 3104.4 3130.2 3133.2 3135.9 990.5 1011.2 1014.8 1028.7 1029.1 1106.1 1118.1 1140.4 1140.6 1140.9 6458.6 6493.0 6494.2 6494.6 6499.8 375.9 383.2 387.8 387.8 389.7 5119.4 5153.5 5170.0 5172.8 5179.1 8081.2 8192.7 8308.9 8318.9 8412.2 8261.0 8415.7 8434.5 8451.9 8466.8 6615.6 6659.0 6667.9 6669.5 6673.4 6201.6 6303.1 6320.1 6401.9 6429.3 7644.0 7670.8 7699.0 7699.9 7700.2 7723.3 7835.8 7868.3 7896.8 7899.8 6370.5 6412.8 6425.6 6437.5 6447.4 6781.0 6788.0 6809.4 6824.1 6828.2 6255.7 6369.0 6388.8 6500.3 6531.1 6268.7 6360.3 6370.7 6385.2 6385.9 6142.9 6318.0 6331.7 6340.7 6344.4 5523.7 5566.2 5573.3 5579.9 5587.2 5807.3 5816.1 5819.6 5840.4 5844.9 22:09:37 INFO - 2015-10-26 22:09:37,351 DEBUG : BROWSER_OUTPUT: 22:09:37 INFO - 2015-10-26 22:09:37,351 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5124.05 Median:6106.78 stddev:1678.49 (27.5%) stddev-sans-first:1642.95 22:09:37 INFO - 2015-10-26 22:09:37,351 DEBUG : BROWSER_OUTPUT: Values: 256.2 269.9 271.8 272.0 272.5 5975.2 5976.9 5977.5 5989.9 6019.0 5884.1 5921.2 5924.4 5927.2 5934.5 4089.1 4099.8 4102.9 4110.2 4111.0 4082.6 4141.4 4143.6 4144.9 4148.0 3827.8 4025.5 4035.5 4049.1 4059.4 4023.8 4093.5 4138.0 4149.2 4155.5 3212.5 3421.2 3469.5 3482.9 3540.4 5663.6 5726.2 5798.9 5799.5 5810.0 4698.4 4858.9 4899.6 4899.8 4908.1 6092.7 6160.2 6163.8 6182.0 6192.1 3906.8 3907.3 3909.6 3916.7 3921.3 6475.4 6518.4 6525.2 6528.5 6528.7 3692.5 3839.7 3906.2 3988.2 4001.7 4066.2 4101.5 4111.3 4117.5 4119.2 4881.1 4904.9 4905.3 4906.6 4911.3 6211.9 6224.0 6225.0 6227.8 6243.0 1437.8 1451.9 1455.3 1457.9 1459.1 908.6 919.7 946.0 947.3 947.3 5976.1 6022.0 6157.5 6176.2 6180.4 6483.1 6523.2 6525.2 6553.2 6553.7 6000.2 6147.4 6150.8 6153.4 6156.1 3525.9 3588.3 3593.3 3625.4 3631.6 6658.7 6684.8 6688.5 6696.7 6697.8 6469.6 6529.9 6539.4 6549.1 6564.9 6392.6 6566.6 6621.4 6685.7 6687.6 6281.3 6413.0 6430.9 6504.1 6601.0 6410.9 6432.6 6435.5 6445.2 6445.7 6778.5 6850.5 6865.3 6873.9 6883.2 4031.8 4105.3 4128.1 4137.7 4142.5 6468.5 6522.4 6559.6 6574.5 6594.9 6100.1 6349.7 6361.8 6369.0 6372.3 6294.7 6296.4 6299.2 6304.6 6316.5 6037.2 6113.5 6153.7 6167.0 6173.8 6229.2 6247.2 6371.0 6371.9 6373.5 6200.8 6219.3 6229.8 6231.9 6235.0 22:09:37 INFO - 2015-10-26 22:09:37,351 DEBUG : BROWSER_OUTPUT: 22:09:37 INFO - 2015-10-26 22:09:37,351 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15526.50 Median:2508.70 stddev:19242.09 (767.0%) stddev-sans-first:19263.92 22:09:37 INFO - 2015-10-26 22:09:37,351 DEBUG : BROWSER_OUTPUT: Values: 713.9 729.7 735.3 740.6 749.6 12363.3 13224.7 13394.9 13421.0 13425.1 12526.0 12561.2 12939.5 12957.5 12991.9 515.5 520.7 579.2 609.7 617.6 599.5 614.0 614.7 618.2 618.8 642.1 643.7 644.1 645.4 645.6 536.1 542.1 634.1 636.9 639.0 464.4 465.3 466.1 466.3 467.4 12356.3 12374.0 12383.7 12385.4 12401.6 12517.0 12769.7 12874.9 13037.2 13124.9 10876.5 10960.5 11145.4 11190.8 11324.5 3480.8 3523.9 3548.5 3573.4 3663.9 56815.0 57502.1 57548.1 58479.4 58829.1 9595.6 10005.4 10172.5 10221.4 10661.9 18071.0 18166.5 18245.5 18255.4 18264.9 7351.0 7596.1 7654.2 7703.8 7728.4 4142.9 4251.3 4303.6 4373.4 4437.2 2976.2 2991.1 3005.5 3008.6 3013.4 447.1 447.4 453.0 453.9 457.5 605.6 613.3 635.1 673.3 721.6 9468.8 9585.4 9620.8 9635.6 9907.4 569.6 598.2 673.0 713.5 722.9 4222.8 4559.9 4617.9 4648.3 4681.1 71181.6 71473.0 71511.6 71646.3 71677.6 58141.7 59417.4 59474.7 59854.0 59936.3 52640.7 53205.5 53619.7 55517.4 57224.4 9827.7 9843.8 9902.0 10053.2 10133.3 40115.6 40294.8 40589.0 41387.0 41605.0 50661.2 51891.5 52404.0 52736.0 54899.7 8087.0 8094.8 8278.6 8368.8 8392.4 13534.5 13538.5 14081.4 14316.4 14981.6 13245.7 13360.5 13452.7 13550.3 13694.6 13206.6 13354.9 13453.3 13470.9 13649.9 8773.6 12688.9 13113.0 13181.1 13556.3 10751.8 10777.3 11096.8 11340.0 11934.7 10894.5 11022.8 11351.5 11369.4 11400.9 22:09:37 INFO - 2015-10-26 22:09:37,352 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:09:37 INFO - 2015-10-26 22:09:37,352 DEBUG : BROWSER_OUTPUT: 22:09:37 INFO - 2015-10-26 22:09:37,903 INFO : Browser exited with error code: 0 22:09:38 INFO - 2015-10-26 22:09:38,635 INFO : Completed test dromaeo_css (00:18:38) 22:09:38 INFO - 2015-10-26 22:09:38,635 INFO : Starting test kraken 22:09:38 INFO - 2015-10-26 22:09:38,635 DEBUG : operating with platform_type : w7_ 22:09:38 INFO - 2015-10-26 22:09:38,635 INFO : Initialising browser for kraken test... 22:09:38 INFO - 2015-10-26 22:09:38,661 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmphlttfg\profile http://localhost:49790/getInfo.html 22:09:43 INFO - 2015-10-26 22:09:43,371 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:09:43 INFO - 2015-10-26 22:09:43,371 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:09:43 INFO - 2015-10-26 22:09:43,371 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 22:09:43 INFO - 2015-10-26 22:09:43,371 DEBUG : BROWSER_OUTPUT: __metrics 22:09:43 INFO - 2015-10-26 22:09:43,426 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:09:44 INFO - 2015-10-26 22:09:44,382 INFO : Browser initialized. 22:09:44 INFO - 2015-10-26 22:09:44,382 INFO : Running cycle 1/1 for kraken test... 22:09:44 INFO - 2015-10-26 22:09:44,382 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmphlttfg\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:09:55 INFO - 2015-10-26 22:09:55,775 DEBUG : BROWSER_OUTPUT: RSS: Main: 130310144 22:09:55 INFO - 2015-10-26 22:09:55,775 DEBUG : BROWSER_OUTPUT: 22:09:58 INFO - 2015-10-26 22:09:58,130 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49790/tests/kraken/driver.html?testName=audio-beat-detection) 22:09:58 INFO - 2015-10-26 22:09:58,697 DEBUG : BROWSER_OUTPUT: RSS: Main: 164696064 22:09:58 INFO - 2015-10-26 22:09:58,697 DEBUG : BROWSER_OUTPUT: 22:10:01 INFO - 2015-10-26 22:10:01,273 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49790/tests/kraken/driver.html?testName=audio-dft) 22:10:01 INFO - 2015-10-26 22:10:01,832 DEBUG : BROWSER_OUTPUT: RSS: Main: 171454464 22:10:01 INFO - 2015-10-26 22:10:01,832 DEBUG : BROWSER_OUTPUT: 22:10:04 INFO - 2015-10-26 22:10:04,566 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49790/tests/kraken/driver.html?testName=audio-fft) 22:10:05 INFO - 2015-10-26 22:10:05,134 DEBUG : BROWSER_OUTPUT: RSS: Main: 161660928 22:10:05 INFO - 2015-10-26 22:10:05,134 DEBUG : BROWSER_OUTPUT: 22:10:07 INFO - 2015-10-26 22:10:07,168 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49790/tests/kraken/driver.html?testName=audio-oscillator) 22:10:07 INFO - 2015-10-26 22:10:07,723 DEBUG : BROWSER_OUTPUT: RSS: Main: 159035392 22:10:07 INFO - 2015-10-26 22:10:07,723 DEBUG : BROWSER_OUTPUT: 22:10:09 INFO - 2015-10-26 22:10:09,878 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49790/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:10:10 INFO - 2015-10-26 22:10:10,434 DEBUG : BROWSER_OUTPUT: RSS: Main: 156454912 22:10:10 INFO - 2015-10-26 22:10:10,434 DEBUG : BROWSER_OUTPUT: 22:10:13 INFO - 2015-10-26 22:10:13,529 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49790/tests/kraken/driver.html?testName=imaging-darkroom) 22:10:14 INFO - 2015-10-26 22:10:14,095 DEBUG : BROWSER_OUTPUT: RSS: Main: 152739840 22:10:14 INFO - 2015-10-26 22:10:14,095 DEBUG : BROWSER_OUTPUT: 22:10:17 INFO - 2015-10-26 22:10:17,530 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49790/tests/kraken/driver.html?testName=imaging-desaturate) 22:10:18 INFO - 2015-10-26 22:10:18,091 DEBUG : BROWSER_OUTPUT: RSS: Main: 153210880 22:10:18 INFO - 2015-10-26 22:10:18,092 DEBUG : BROWSER_OUTPUT: 22:10:20 INFO - 2015-10-26 22:10:20,855 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49790/tests/kraken/driver.html?testName=json-parse-financial) 22:10:21 INFO - 2015-10-26 22:10:21,430 DEBUG : BROWSER_OUTPUT: RSS: Main: 153137152 22:10:21 INFO - 2015-10-26 22:10:21,430 DEBUG : BROWSER_OUTPUT: 22:10:23 INFO - 2015-10-26 22:10:23,213 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49790/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:10:23 INFO - 2015-10-26 22:10:23,773 DEBUG : BROWSER_OUTPUT: RSS: Main: 183230464 22:10:23 INFO - 2015-10-26 22:10:23,773 DEBUG : BROWSER_OUTPUT: 22:10:25 INFO - 2015-10-26 22:10:25,674 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49790/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:10:26 INFO - 2015-10-26 22:10:26,249 DEBUG : BROWSER_OUTPUT: RSS: Main: 188076032 22:10:26 INFO - 2015-10-26 22:10:26,249 DEBUG : BROWSER_OUTPUT: 22:10:28 INFO - 2015-10-26 22:10:28,496 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49790/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:10:29 INFO - 2015-10-26 22:10:29,059 DEBUG : BROWSER_OUTPUT: RSS: Main: 194310144 22:10:29 INFO - 2015-10-26 22:10:29,059 DEBUG : BROWSER_OUTPUT: 22:10:31 INFO - 2015-10-26 22:10:31,486 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49790/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:10:32 INFO - 2015-10-26 22:10:32,046 DEBUG : BROWSER_OUTPUT: RSS: Main: 195874816 22:10:32 INFO - 2015-10-26 22:10:32,046 DEBUG : BROWSER_OUTPUT: 22:10:35 INFO - 2015-10-26 22:10:35,081 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49790/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:10:35 INFO - 2015-10-26 22:10:35,641 DEBUG : BROWSER_OUTPUT: RSS: Main: 195911680 22:10:35 INFO - 2015-10-26 22:10:35,641 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,496 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49790/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49790/tests/kraken/driver.html?testName=ai-astar) 22:10:37 INFO - 2015-10-26 22:10:37,752 DEBUG : BROWSER_OUTPUT: RSS: Main: 242528256 22:10:37 INFO - 2015-10-26 22:10:37,752 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,752 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:10:37 INFO - 2015-10-26 22:10:37,752 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:10:37 INFO - 2015-10-26 22:10:37,752 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:10:37 INFO - 2015-10-26 22:10:37,752 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:10:37 INFO - 2015-10-26 22:10:37,752 DEBUG : BROWSER_OUTPUT: |0;ai-astar;88;95;101;86;95;95;94;96;96;94 22:10:37 INFO - 2015-10-26 22:10:37,752 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;133;132;146;156;135;131;140;135;152;131 22:10:37 INFO - 2015-10-26 22:10:37,752 DEBUG : BROWSER_OUTPUT: |2;audio-dft;153;154;152;152;152;157;153;153;154;153 22:10:37 INFO - 2015-10-26 22:10:37,752 DEBUG : BROWSER_OUTPUT: |3;audio-fft;97;103;81;107;86;78;76;77;103;82 22:10:37 INFO - 2015-10-26 22:10:37,752 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;95;112;95;96;96;117;110;95;118 22:10:37 INFO - 2015-10-26 22:10:37,752 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;116;117;118;116;116;117;117;116;116;116 22:10:37 INFO - 2015-10-26 22:10:37,752 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;149;149;139;149;149;149;148;153;154;139 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;90;90;91;90;90;90;90;91;90;89 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;79;82;80;81;80;80;80;81;80;94 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;80;78;78;78;78;78;78;78;78;78 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;96;97;98;98;97;97;98;97;97;99 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;121;122;120;116;122;119;120;117;120;118 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;188;192;194;194;195;188;196;199;200;186 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;85;85;85;85;90;85;84;86;85;90 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,815 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: __startTimestamp1445922637747__endTimestamp 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: Number of tests: 14 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:94.00 Median:95.00 stddev:4.22 (4.4%) stddev-sans-first:3.87 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: Values: 88.0 95.0 101.0 86.0 95.0 95.0 94.0 96.0 96.0 94.0 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:139.10 Median:137.50 stddev:9.15 (6.7%) stddev-sans-first:9.43 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: Values: 133.0 132.0 146.0 156.0 135.0 131.0 140.0 135.0 152.0 131.0 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:153.30 Median:153.00 stddev:1.49 (1.0%) stddev-sans-first:1.58 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: Values: 153.0 154.0 152.0 152.0 152.0 157.0 153.0 153.0 154.0 153.0 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:89.00 Median:79.50 stddev:12.18 (15.3%) stddev-sans-first:12.57 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: Values: 97.0 103.0 81.0 107.0 86.0 78.0 76.0 77.0 103.0 82.0 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.90 Median:95.00 stddev:10.03 (10.6%) stddev-sans-first:10.22 22:10:37 INFO - 2015-10-26 22:10:37,753 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 112.0 95.0 96.0 96.0 117.0 110.0 95.0 118.0 22:10:37 INFO - 2015-10-26 22:10:37,755 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,756 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:116.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 22:10:37 INFO - 2015-10-26 22:10:37,756 DEBUG : BROWSER_OUTPUT: Values: 116.0 117.0 118.0 116.0 116.0 117.0 117.0 116.0 116.0 116.0 22:10:37 INFO - 2015-10-26 22:10:37,757 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,757 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.80 Median:149.00 stddev:5.03 (3.4%) stddev-sans-first:5.32 22:10:37 INFO - 2015-10-26 22:10:37,757 DEBUG : BROWSER_OUTPUT: Values: 149.0 149.0 139.0 149.0 149.0 149.0 148.0 153.0 154.0 139.0 22:10:37 INFO - 2015-10-26 22:10:37,757 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,759 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:90.10 Median:90.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 22:10:37 INFO - 2015-10-26 22:10:37,759 DEBUG : BROWSER_OUTPUT: Values: 90.0 90.0 91.0 90.0 90.0 90.0 90.0 91.0 90.0 89.0 22:10:37 INFO - 2015-10-26 22:10:37,759 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,759 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:81.70 Median:80.50 stddev:4.40 (5.5%) stddev-sans-first:4.56 22:10:37 INFO - 2015-10-26 22:10:37,759 DEBUG : BROWSER_OUTPUT: Values: 79.0 82.0 80.0 81.0 80.0 80.0 80.0 81.0 80.0 94.0 22:10:37 INFO - 2015-10-26 22:10:37,759 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,759 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:78.20 Median:78.00 stddev:0.63 (0.8%) stddev-sans-first:0.00 22:10:37 INFO - 2015-10-26 22:10:37,759 DEBUG : BROWSER_OUTPUT: Values: 80.0 78.0 78.0 78.0 78.0 78.0 78.0 78.0 78.0 78.0 22:10:37 INFO - 2015-10-26 22:10:37,759 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,760 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:97.40 Median:97.50 stddev:0.84 (0.9%) stddev-sans-first:0.73 22:10:37 INFO - 2015-10-26 22:10:37,760 DEBUG : BROWSER_OUTPUT: Values: 96.0 97.0 98.0 98.0 97.0 97.0 98.0 97.0 97.0 99.0 22:10:37 INFO - 2015-10-26 22:10:37,760 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,760 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:119.50 Median:120.00 stddev:2.01 (1.7%) stddev-sans-first:2.06 22:10:37 INFO - 2015-10-26 22:10:37,762 DEBUG : BROWSER_OUTPUT: Values: 121.0 122.0 120.0 116.0 122.0 119.0 120.0 117.0 120.0 118.0 22:10:37 INFO - 2015-10-26 22:10:37,762 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,762 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.20 Median:194.50 stddev:4.71 (2.4%) stddev-sans-first:4.60 22:10:37 INFO - 2015-10-26 22:10:37,763 DEBUG : BROWSER_OUTPUT: Values: 188.0 192.0 194.0 194.0 195.0 188.0 196.0 199.0 200.0 186.0 22:10:37 INFO - 2015-10-26 22:10:37,763 DEBUG : BROWSER_OUTPUT: 22:10:37 INFO - 2015-10-26 22:10:37,763 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:86.00 Median:85.00 stddev:2.16 (2.5%) stddev-sans-first:2.26 22:10:37 INFO - 2015-10-26 22:10:37,763 DEBUG : BROWSER_OUTPUT: Values: 85.0 85.0 85.0 85.0 90.0 85.0 84.0 86.0 85.0 90.0 22:10:37 INFO - 2015-10-26 22:10:37,763 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:10:37 INFO - 2015-10-26 22:10:37,765 DEBUG : BROWSER_OUTPUT: 22:10:38 INFO - 2015-10-26 22:10:38,318 INFO : Browser exited with error code: 0 22:10:39 INFO - 2015-10-26 22:10:39,105 INFO : Completed test kraken (00:01:00) 22:10:39 INFO - 2015-10-26 22:10:39,105 INFO : Starting test v8_7 22:10:39 INFO - 2015-10-26 22:10:39,105 DEBUG : operating with platform_type : w7_ 22:10:39 INFO - 2015-10-26 22:10:39,105 INFO : Initialising browser for v8_7 test... 22:10:39 INFO - 2015-10-26 22:10:39,131 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpg7gu2f\profile http://localhost:49790/getInfo.html 22:10:43 INFO - 2015-10-26 22:10:43,655 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:10:43 INFO - 2015-10-26 22:10:43,655 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:10:43 INFO - 2015-10-26 22:10:43,655 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 22:10:43 INFO - 2015-10-26 22:10:43,655 DEBUG : BROWSER_OUTPUT: __metrics 22:10:43 INFO - 2015-10-26 22:10:43,730 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:10:44 INFO - 2015-10-26 22:10:44,385 DEBUG : BROWSER_OUTPUT: console.error: 22:10:44 INFO - 2015-10-26 22:10:44,385 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] 22:10:44 INFO - 2015-10-26 22:10:44,385 DEBUG : BROWSER_OUTPUT: Stack: 22:10:44 INFO - 2015-10-26 22:10:44,385 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 22:10:44 INFO - 2015-10-26 22:10:44,385 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 22:10:44 INFO - 2015-10-26 22:10:44,385 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 22:10:44 INFO - 2015-10-26 22:10:44,385 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 22:10:44 INFO - 2015-10-26 22:10:44,385 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 22:10:44 INFO - 2015-10-26 22:10:44,385 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 22:10:44 INFO - 2015-10-26 22:10:44,385 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 22:10:44 INFO - 2015-10-26 22:10:44,385 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 22:10:44 INFO - 2015-10-26 22:10:44,387 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 22:10:44 INFO - 2015-10-26 22:10:44,387 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 22:10:44 INFO - 2015-10-26 22:10:44,387 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 22:10:44 INFO - 2015-10-26 22:10:44,387 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 22:10:44 INFO - 2015-10-26 22:10:44,387 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6559.42 22:11:19 INFO - RETURN: kraken: 1588.70 22:11:19 INFO - RETURN: v8_7: 20675.75 22:11:19 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,25]]", "result": "20675.75"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,25]]", "result": "1588.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,25]]", "result": "6559.42"}}} 22:11:19 INFO - Return code: 0 22:11:19 INFO - # TBPL SUCCESS # 22:11:19 INFO - Running post-action listener: _resource_record_post_action 22:11:19 INFO - Running post-run listener: _resource_record_post_run 22:11:20 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 134182400; Write bytes: 451272192; Read time: 1531700; Write time: 5524720 22:11:20 INFO - install - Wall time: 3s; CPU: 9.0%; Read bytes: 0; Write bytes: 24135168; Read time: 0; Write time: 400950 22:11:20 INFO - run-tests - Wall time: 1222s; CPU: 13.0%; Read bytes: 134182400; Write bytes: 424861184; Read time: 1531700; Write time: 4878160 22:11:20 INFO - Running post-run listener: _upload_blobber_files 22:11:20 INFO - Blob upload gear active. 22:11:20 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 22:11:20 INFO - Copying logs to upload dir... 22:11:20 INFO - mkdir: C:\slave\test-pgo\build\upload\logs program finished with exit code 0 elapsedTime=1439.609000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 38 secs) (at 2015-10-26 22:11:59.565281) ========= ========= Started set props: build_url (results: 0, elapsed: 21 secs) (at 2015-10-26 22:11:59.570675) ========= '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-020 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-020 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-020 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/JORWTJE0Tb2X3Yclsg5gxw/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/JORWTJE0Tb2X3Yclsg5gxw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 21 secs) (at 2015-10-26 22:12:21.511858) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 10 secs) (at 2015-10-26 22:12:21.512386) ========= '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-020 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-020 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-020 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= Finished 'rm -f ...' (results: 0, elapsed: 10 secs) (at 2015-10-26 22:12:32.029499) ========= ========= Started reboot slave lost (results: 0, elapsed: 1 mins, 9 secs) (at 2015-10-26 22:12:32.029823) ========= ========= Finished reboot slave lost (results: 0, elapsed: 1 mins, 9 secs) (at 2015-10-26 22:13:41.822407) =========