builder: fx-team_win7-ix_test-dromaeojs-e10s-pgo slave: t-w732-ix-092 starttime: 1445662521.61 results: success (0) buildid: 20151023173019 builduid: 3aef88cdf0dc441e8360aa882f3adcce revision: 335ea7a95492650e23c9ba14648d4f43620babab ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 21:55:21.612831) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 21:55:21.615317) ========= ========= Started set props: basedir (results: 0, elapsed: 8 secs) (at 2015-10-23 21:55:21.615659) ========= '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-092 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-092 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-092 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.539000 basedir: 'C:\\slave\\test-pgo' ========= Finished set props: basedir (results: 0, elapsed: 8 secs) (at 2015-10-23 21:55:30.060299) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2015-10-23 21:55:30.060613) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2015-10-23 21:55:31.317724) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 21:55:31.318025) ========= '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-092 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-092 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-092 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.501000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 21:55:32.196400) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 21:55:32.196853) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 21:55:32.197234) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 21:55:32.197513) ========= '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-092 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-092 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-092 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:55:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 2.45 GB/s 21:55:33 (2.45 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.798000 ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 21:55:33.028152) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 19 secs) (at 2015-10-23 21:55:33.028446) ========= '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-092 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-092 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-092 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=14.001000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 19 secs) (at 2015-10-23 21:55:52.716119) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 19 secs) (at 2015-10-23 21:55:52.716484) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 335ea7a95492650e23c9ba14648d4f43620babab --destination scripts --debug' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 335ea7a95492650e23c9ba14648d4f43620babab --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-092 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-092 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-092 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2015-10-23 21:55:56,158 truncating revision to first 12 chars 2015-10-23 21:55:56,158 Setting DEBUG logging. 2015-10-23 21:55:56,158 attempt 1/10 2015-10-23 21:55:56,158 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/335ea7a95492?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-23 21:55:56,772 unpacking tar archive at: fx-team-335ea7a95492/testing/mozharness/ program finished with exit code 0 elapsedTime=1.393000 ========= Finished 'bash -c ...' (results: 0, elapsed: 19 secs) (at 2015-10-23 21:56:12.405231) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 5 secs) (at 2015-10-23 21:56:12.405511) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 5 secs) (at 2015-10-23 21:56:17.659772) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 21:56:17.660030) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 21:56:17.660408) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 17 secs) (at 2015-10-23 21:56:17.660691) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs-e10s' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team' 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-092 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-092 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-092 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:56:18 INFO - MultiFileLogger online at 20151023 21:56:18 in C:\slave\test-pgo 21:56:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 21:56:18 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 21:56:18 INFO - {'append_to_log': False, 21:56:18 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 21:56:18 INFO - 'blob_upload_branch': 'Fx-Team', 21:56:18 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 21:56:18 INFO - 'branch': 'Fx-Team', 21:56:18 INFO - 'buildbot_json_path': 'buildprops.json', 21:56:18 INFO - 'config_files': ('talos/windows_config.py',), 21:56:18 INFO - 'default_actions': ('clobber', 21:56:18 INFO - 'read-buildbot-config', 21:56:18 INFO - 'download-and-extract', 21:56:18 INFO - 'populate-webroot', 21:56:18 INFO - 'create-virtualenv', 21:56:18 INFO - 'install', 21:56:18 INFO - 'run-tests'), 21:56:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:56:18 INFO - 'download_minidump_stackwalk': True, 21:56:18 INFO - 'download_symbols': 'ondemand', 21:56:18 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 21:56:18 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 21:56:18 INFO - 'hg': 'c:/mozilla-build/hg/hg', 21:56:18 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 21:56:18 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 21:56:18 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 21:56:18 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 21:56:18 INFO - 'C:/mozilla-build/tooltool.py'), 21:56:18 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 21:56:18 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 21:56:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:56:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:56:18 INFO - 'installer_path': 'installer.exe', 21:56:18 INFO - 'log_level': 'info', 21:56:18 INFO - 'log_name': 'talos', 21:56:18 INFO - 'log_to_console': True, 21:56:18 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 21:56:18 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 21:56:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 21:56:18 INFO - 'opt_config_files': (), 21:56:18 INFO - 'pip_index': False, 21:56:18 INFO - 'sps_profile': False, 21:56:18 INFO - 'sps_profile_interval': 0, 21:56:18 INFO - 'suite': 'dromaeojs-e10s', 21:56:18 INFO - 'system_bits': '32', 21:56:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:56:18 INFO - 'title': 'T-W732-IX-092', 21:56:18 INFO - 'use_talos_json': True, 21:56:18 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 21:56:18 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 21:56:18 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 21:56:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:56:18 INFO - 'work_dir': 'build'} 21:56:18 INFO - ##### 21:56:18 INFO - ##### Running clobber step. 21:56:18 INFO - ##### 21:56:18 INFO - Running pre-action listener: _resource_record_pre_action 21:56:18 INFO - Running main action method: clobber 21:56:18 INFO - rmtree: C:\slave\test-pgo\build 21:56:18 INFO - Using _rmtree_windows ... 21:56:18 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 21:59:16 INFO - Running post-action listener: _resource_record_post_action 21:59:16 INFO - ##### 21:59:16 INFO - ##### Running read-buildbot-config step. 21:59:16 INFO - ##### 21:59:16 INFO - Running pre-action listener: _resource_record_pre_action 21:59:16 INFO - Running main action method: read_buildbot_config 21:59:16 INFO - Using buildbot properties: 21:59:16 INFO - { 21:59:16 INFO - "properties": { 21:59:16 INFO - "buildnumber": 92, 21:59:16 INFO - "product": "firefox", 21:59:16 INFO - "script_repo_revision": "production", 21:59:16 INFO - "builddir": "fx-team_win7-ix_test-dromaeojs-e10s", 21:59:16 INFO - "repository": "", 21:59:16 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs-e10s", 21:59:16 INFO - "buildid": "20151023173019", 21:59:16 INFO - "slavename": "t-w732-ix-092", 21:59:16 INFO - "pgo_build": "True", 21:59:16 INFO - "basedir": "C:\\slave\\test-pgo", 21:59:16 INFO - "project": "", 21:59:16 INFO - "platform": "win7-ix", 21:59:16 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 21:59:16 INFO - "slavebuilddir": "test", 21:59:16 INFO - "scheduler": "tests-fx-team-win32-pgo-talos", 21:59:16 INFO - "branch": "fx-team", 21:59:16 INFO - "repo_path": "integration/fx-team", 21:59:16 INFO - "stage_platform": "win32-pgo", 21:59:16 INFO - "builduid": "3aef88cdf0dc441e8360aa882f3adcce", 21:59:16 INFO - "revision": "335ea7a95492650e23c9ba14648d4f43620babab" 21:59:16 INFO - }, 21:59:16 INFO - "sourcestamp": { 21:59:16 INFO - "repository": "", 21:59:16 INFO - "hasPatch": false, 21:59:16 INFO - "project": "", 21:59:16 INFO - "branch": "fx-team-win32-pgo-talos", 21:59:16 INFO - "changes": [ 21:59:16 INFO - { 21:59:16 INFO - "category": null, 21:59:16 INFO - "files": [ 21:59:16 INFO - { 21:59:16 INFO - "url": null, 21:59:16 INFO - "name": "https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 21:59:16 INFO - } 21:59:16 INFO - ], 21:59:16 INFO - "repository": "", 21:59:16 INFO - "rev": "335ea7a95492650e23c9ba14648d4f43620babab", 21:59:16 INFO - "who": "sendchange", 21:59:16 INFO - "when": 1445662371, 21:59:16 INFO - "number": 6569523, 21:59:16 INFO - "comments": "", 21:59:16 INFO - "project": "", 21:59:16 INFO - "at": "Fri 23 Oct 2015 21:52:51", 21:59:16 INFO - "branch": "fx-team-win32-pgo-talos", 21:59:16 INFO - "revlink": "", 21:59:16 INFO - "properties": [ 21:59:16 INFO - [ 21:59:16 INFO - "buildid", 21:59:16 INFO - "20151023173019", 21:59:16 INFO - "Change" 21:59:16 INFO - ], 21:59:16 INFO - [ 21:59:16 INFO - "builduid", 21:59:16 INFO - "3aef88cdf0dc441e8360aa882f3adcce", 21:59:16 INFO - "Change" 21:59:16 INFO - ], 21:59:16 INFO - [ 21:59:16 INFO - "pgo_build", 21:59:16 INFO - "True", 21:59:16 INFO - "Change" 21:59:16 INFO - ] 21:59:16 INFO - ], 21:59:16 INFO - "revision": "335ea7a95492650e23c9ba14648d4f43620babab" 21:59:16 INFO - } 21:59:16 INFO - ], 21:59:16 INFO - "revision": "335ea7a95492650e23c9ba14648d4f43620babab" 21:59:16 INFO - } 21:59:16 INFO - } 21:59:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 21:59:16 INFO - Running post-action listener: _resource_record_post_action 21:59:16 INFO - ##### 21:59:16 INFO - ##### Running download-and-extract step. 21:59:16 INFO - ##### 21:59:16 INFO - Running pre-action listener: _resource_record_pre_action 21:59:16 INFO - Running main action method: download_and_extract 21:59:16 INFO - mkdir: C:\slave\test-pgo\build\tests 21:59:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:16 INFO - https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 21:59:16 INFO - trying https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/test_packages.json 21:59:16 INFO - Downloading https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 21:59:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 21:59:17 INFO - Downloaded 1221 bytes. 21:59:17 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 21:59:17 INFO - Using the following test package requirements: 21:59:17 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 21:59:17 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:59:17 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 21:59:17 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:59:17 INFO - u'jsshell-win32.zip'], 21:59:17 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:59:17 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 21:59:17 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 21:59:17 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:59:17 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 21:59:17 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:59:17 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 21:59:17 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:59:17 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 21:59:17 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 21:59:17 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:59:17 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 21:59:17 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 21:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:17 INFO - https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 21:59:17 INFO - trying https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 21:59:17 INFO - Downloading https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/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:59:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/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:59:19 INFO - Downloaded 18011096 bytes. 21:59:19 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:59:19 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 21:59:19 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:59:50 INFO - Return code: 0 21:59:50 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:59:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:50 INFO - https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 21:59:50 INFO - trying https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 21:59:50 INFO - Downloading https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/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:59:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/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:59:51 INFO - Downloaded 18011096 bytes. 21:59:51 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:59:51 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 21:59:51 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 22:00:19 INFO - Return code: 0 22:00:19 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')]} 22:00:19 INFO - https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 22:00:19 INFO - trying https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 22:00:19 INFO - Downloading https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/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 22:00:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/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 22:00:21 INFO - Downloaded 11131307 bytes. 22:00:21 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 22:00:21 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip 22:00:21 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 22:00:25 INFO - Return code: 0 22:00:25 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')]} 22:00:25 INFO - https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 22:00:25 INFO - trying https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 22:00:25 INFO - Downloading https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip 22:00:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/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 22:00:29 INFO - Downloaded 53150362 bytes. 22:00:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 22:00:29 INFO - mkdir: C:\slave\test-pgo\properties 22:00:29 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 22:00:29 INFO - Writing to file C:\slave\test-pgo\properties\build_url 22:00:29 INFO - Contents: 22:00:29 INFO - build_url:https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 22:00:29 INFO - Running post-action listener: _resource_record_post_action 22:00:29 INFO - Running post-action listener: set_extra_try_arguments 22:00:29 INFO - ##### 22:00:29 INFO - ##### Running populate-webroot step. 22:00:29 INFO - ##### 22:00:29 INFO - Running pre-action listener: _resource_record_pre_action 22:00:29 INFO - Running main action method: populate_webroot 22:00:29 INFO - Guessing talos json url... 22:00: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')]} 22:00:29 INFO - https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 22:00:29 INFO - trying https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 22:00:29 INFO - Downloading https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 22:00:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/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 22:00:30 INFO - Downloaded 103 bytes. 22:00:30 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 22:00:30 INFO - Contents: 22:00:30 INFO - 20151023173019 22:00:30 INFO - https://hg.mozilla.org/integration/fx-team/rev/335ea7a95492650e23c9ba14648d4f43620babab 22:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:30 INFO - trying https://hg.mozilla.org/integration/fx-team/raw-file/335ea7a954/testing/talos/talos.json 22:00:30 INFO - Downloading https://hg.mozilla.org/integration/fx-team/raw-file/335ea7a954/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json 22:00:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/fx-team/raw-file/335ea7a954/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1 22:00:30 INFO - Downloaded 4264 bytes. 22:00:30 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 22:00:30 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 22:00:30 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 22:00:30 INFO - u'../fennec_ids.txt'], 22:00:30 INFO - u'tests': [u'tcheck2']}, 22:00:30 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 22:00:30 INFO - u'--tppagecycles', 22:00:30 INFO - u'7'], 22:00:30 INFO - u'tests': [u'tsvgm']}}, 22:00:30 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 22:00:30 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 22:00:30 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 22:00:30 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 22:00:30 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 22:00:30 INFO - u'tests': [u'dromaeo_css', 22:00:30 INFO - u'kraken', 22:00:30 INFO - u'v8_7']}, 22:00:30 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:30 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 22:00:30 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:30 INFO - u'talos_options': [u'--e10s'], 22:00:30 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 22:00:30 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:30 INFO - u'tests': [u'damp', u'tps']}, 22:00:30 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:30 INFO - u'talos_options': [u'--e10s'], 22:00:30 INFO - u'tests': [u'damp', u'tps']}, 22:00:30 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 22:00:30 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 22:00:30 INFO - u'other': {u'tests': [u'a11yr', 22:00:30 INFO - u'ts_paint', 22:00:30 INFO - u'tpaint', 22:00:30 INFO - u'sessionrestore', 22:00:30 INFO - u'sessionrestore_no_auto_restore']}, 22:00:30 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 22:00:30 INFO - u'tests': [u'a11yr', 22:00:30 INFO - u'ts_paint', 22:00:30 INFO - u'tpaint', 22:00:30 INFO - u'sessionrestore', 22:00:30 INFO - u'sessionrestore_no_auto_restore']}, 22:00:30 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 22:00:30 INFO - u'tests': [u'a11yr', 22:00:30 INFO - u'ts_paint', 22:00:30 INFO - u'tpaint', 22:00:30 INFO - u'sessionrestore', 22:00:30 INFO - u'sessionrestore_no_auto_restore']}, 22:00:30 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 22:00:30 INFO - u'tests': [u'a11yr', 22:00:30 INFO - u'ts_paint', 22:00:30 INFO - u'tpaint', 22:00:30 INFO - u'sessionrestore', 22:00:30 INFO - u'sessionrestore_no_auto_restore']}, 22:00:30 INFO - u'other_l64': {u'tests': [u'a11yr', 22:00:30 INFO - u'ts_paint', 22:00:30 INFO - u'tpaint', 22:00:30 INFO - u'sessionrestore', 22:00:30 INFO - u'sessionrestore_no_auto_restore']}, 22:00:30 INFO - u'other_nol64': {u'tests': [u'a11yr', 22:00:30 INFO - u'ts_paint', 22:00:30 INFO - u'tpaint', 22:00:30 INFO - u'sessionrestore', 22:00:30 INFO - u'sessionrestore_no_auto_restore']}, 22:00:30 INFO - u'svgr': {u'tests': [u'tsvgx', 22:00:30 INFO - u'tsvgr_opacity', 22:00:30 INFO - u'tart', 22:00:30 INFO - u'tscrollx', 22:00:30 INFO - u'cart']}, 22:00:30 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 22:00:30 INFO - u'tests': [u'tsvgx', 22:00:30 INFO - u'tsvgr_opacity', 22:00:30 INFO - u'tart', 22:00:30 INFO - u'tscrollx', 22:00:30 INFO - u'cart']}, 22:00:30 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:30 INFO - u'tests': [u'tp5o']}, 22:00:30 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:30 INFO - u'talos_options': [u'--e10s'], 22:00:30 INFO - u'tests': [u'tp5o']}, 22:00:30 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:30 INFO - u'talos_options': [u'--xperf_path', 22:00:30 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:00:30 INFO - u'tests': [u'tp5n']}, 22:00:30 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:30 INFO - u'talos_options': [u'--e10s', 22:00:30 INFO - u'--xperf_path', 22:00:30 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 22:00:30 INFO - u'C:/slave/talos-data/talos/xperf.config'], 22:00:30 INFO - u'tests': [u'tp5n']}}, 22:00:30 INFO - u'talos.zip': {u'path': u'', 22:00:30 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:00:30 INFO - Running post-action listener: _resource_record_post_action 22:00:30 INFO - ##### 22:00:30 INFO - ##### Running create-virtualenv step. 22:00:30 INFO - ##### 22:00:30 INFO - Running pre-action listener: _resource_record_pre_action 22:00:30 INFO - Running main action method: create_virtualenv 22:00:30 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 22:00:30 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 22:00:30 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 22:00:30 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'! 22:00:30 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 22:00:30 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 22:00:38 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 22:00:41 INFO - Installing distribute......................................................................................................................................................................................done. 22:00:42 INFO - Return code: 0 22:00:42 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 22:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:42 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 0x01968B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0140E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01328B48>, '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 0x0134A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-092', 'USERDOMAIN': 'T-W732-IX-092', '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-092', '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 22:00:42 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 22:00:42 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 22:00:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:00:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:00:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:00:42 INFO - 'COMPUTERNAME': 'T-W732-IX-092', 22:00:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:00:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:00:42 INFO - 'DCLOCATION': 'SCL3', 22:00:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:00:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:00:42 INFO - 'HOMEDRIVE': 'C:', 22:00:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:00:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:00:42 INFO - 'KTS_VERSION': '1.19c', 22:00:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:00:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-092', 22:00:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:00:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:42 INFO - 'MOZ_NO_REMOTE': '1', 22:00:42 INFO - 'NO_EM_RESTART': '1', 22:00:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:00:42 INFO - 'OS': 'Windows_NT', 22:00:42 INFO - 'OURDRIVE': 'C:', 22:00:42 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;', 22:00:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:00:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:00:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:00:42 INFO - 'PROCESSOR_LEVEL': '6', 22:00:42 INFO - 'PROCESSOR_REVISION': '1e05', 22:00:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:00:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:00:42 INFO - 'PROMPT': '$P$G', 22:00:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:00:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:00:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:00:42 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:00:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:00:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:00:42 INFO - 'SYSTEMDRIVE': 'C:', 22:00:42 INFO - 'SYSTEMROOT': 'C:\\windows', 22:00:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:00:42 INFO - 'TEST1': 'testie', 22:00:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:00:42 INFO - 'USERDOMAIN': 'T-W732-IX-092', 22:00:42 INFO - 'USERNAME': 'cltbld', 22:00:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:00:42 INFO - 'WINDIR': 'C:\\windows', 22:00:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:00:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:00:43 INFO - Ignoring indexes: http://pypi.python.org/simple/ 22:00:43 INFO - Downloading/unpacking pip>=1.5 22:00:43 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache 22:00:47 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 22:00:47 INFO - Running setup.py egg_info for package pip 22:00:47 INFO - warning: no files found matching 'pip\cacert.pem' 22:00:47 INFO - warning: no files found matching '*.html' under directory 'docs' 22:00:47 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 22:00:47 INFO - no previously-included directories found matching 'docs\_build\_sources' 22:00:47 INFO - Installing collected packages: pip 22:00:47 INFO - Found existing installation: pip 0.8.2 22:00:47 INFO - Uninstalling pip: 22:00:47 INFO - Successfully uninstalled pip 22:00:47 INFO - Running setup.py install for pip 22:00:47 INFO - warning: no files found matching 'pip\cacert.pem' 22:00:47 INFO - warning: no files found matching '*.html' under directory 'docs' 22:00:47 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 22:00:47 INFO - no previously-included directories found matching 'docs\_build\_sources' 22:00:47 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:00:47 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 22:00:47 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:00:47 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 22:00:47 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:00:47 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 22:00:47 INFO - Successfully installed pip 22:00:47 INFO - Cleaning up... 22:00:47 INFO - Return code: 0 22:00:47 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 22:00: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')]} 22:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00: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')]} 22:00:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00: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', '--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 0x01968B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0140E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01328B48>, '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 0x0134A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-092', 'USERDOMAIN': 'T-W732-IX-092', '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-092', '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 22:00:47 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 22:00:47 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 22:00:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:00:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:00:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:00:47 INFO - 'COMPUTERNAME': 'T-W732-IX-092', 22:00:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:00:47 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:00:47 INFO - 'DCLOCATION': 'SCL3', 22:00:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:00:47 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:00:47 INFO - 'HOMEDRIVE': 'C:', 22:00:47 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:00:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:00:47 INFO - 'KTS_VERSION': '1.19c', 22:00:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:00:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-092', 22:00:47 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:00:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:00:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:47 INFO - 'MOZ_NO_REMOTE': '1', 22:00:47 INFO - 'NO_EM_RESTART': '1', 22:00:47 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:00:47 INFO - 'OS': 'Windows_NT', 22:00:47 INFO - 'OURDRIVE': 'C:', 22:00: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;', 22:00:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:00:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:00:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:00:47 INFO - 'PROCESSOR_LEVEL': '6', 22:00:47 INFO - 'PROCESSOR_REVISION': '1e05', 22:00:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:00:47 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:00:47 INFO - 'PROMPT': '$P$G', 22:00:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:00:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:00:47 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:00:47 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:00:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:00:47 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:00:47 INFO - 'SYSTEMDRIVE': 'C:', 22:00:47 INFO - 'SYSTEMROOT': 'C:\\windows', 22:00:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:00:47 INFO - 'TEST1': 'testie', 22:00:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:00:47 INFO - 'USERDOMAIN': 'T-W732-IX-092', 22:00:47 INFO - 'USERNAME': 'cltbld', 22:00:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:00:47 INFO - 'WINDIR': 'C:\\windows', 22:00:47 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:00:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:00:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:00:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:49 INFO - Downloading/unpacking psutil>=0.7.1 22:00:49 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 22:00:49 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 22:00:49 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 22:00:49 INFO - Installing collected packages: psutil 22:00:50 INFO - Successfully installed psutil 22:00:50 INFO - Cleaning up... 22:00:50 INFO - Return code: 0 22:00:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 22:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:50 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01968B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0140E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01328B48>, '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 0x0134A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-092', 'USERDOMAIN': 'T-W732-IX-092', '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-092', '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 22:00:50 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in C:\slave\test-pgo\build 22:00:50 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 22:00:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:00:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:00:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:00:50 INFO - 'COMPUTERNAME': 'T-W732-IX-092', 22:00:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:00:50 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:00:50 INFO - 'DCLOCATION': 'SCL3', 22:00:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:00:50 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:00:50 INFO - 'HOMEDRIVE': 'C:', 22:00:50 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:00:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:00:50 INFO - 'KTS_VERSION': '1.19c', 22:00:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:00:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-092', 22:00:50 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:00:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:50 INFO - 'MOZ_NO_REMOTE': '1', 22:00:50 INFO - 'NO_EM_RESTART': '1', 22:00:50 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:00:50 INFO - 'OS': 'Windows_NT', 22:00:50 INFO - 'OURDRIVE': 'C:', 22:00:50 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 22:00:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:00:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:00:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:00:50 INFO - 'PROCESSOR_LEVEL': '6', 22:00:50 INFO - 'PROCESSOR_REVISION': '1e05', 22:00:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:00:50 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:00:50 INFO - 'PROMPT': '$P$G', 22:00:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:00:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:00:50 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:00:50 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:00:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:00:50 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:00:50 INFO - 'SYSTEMDRIVE': 'C:', 22:00:50 INFO - 'SYSTEMROOT': 'C:\\windows', 22:00:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:00:50 INFO - 'TEST1': 'testie', 22:00:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:00:50 INFO - 'USERDOMAIN': 'T-W732-IX-092', 22:00:50 INFO - 'USERNAME': 'cltbld', 22:00:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:00:50 INFO - 'WINDIR': 'C:\\windows', 22:00:50 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:00:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:00: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 22:00: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 22:00:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:00:51 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 22:00:51 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 22:00:51 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) 22:00:51 INFO - Installing collected packages: mozsystemmonitor 22:00:51 INFO - Running setup.py install for mozsystemmonitor 22:00:51 INFO - Successfully installed mozsystemmonitor 22:00:51 INFO - Cleaning up... 22:00:51 INFO - Return code: 0 22:00:51 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 22:00:51 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')]} 22:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:51 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')]} 22:00:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:51 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 0x01968B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0140E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01328B48>, '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 0x0134A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-092', 'USERDOMAIN': 'T-W732-IX-092', '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-092', '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 22:00:51 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 22:00:51 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 22:00:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:00:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:00:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:00:51 INFO - 'COMPUTERNAME': 'T-W732-IX-092', 22:00:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:00:51 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:00:51 INFO - 'DCLOCATION': 'SCL3', 22:00:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:00:51 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:00:51 INFO - 'HOMEDRIVE': 'C:', 22:00:51 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:00:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:00:51 INFO - 'KTS_VERSION': '1.19c', 22:00:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:00:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-092', 22:00:51 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:00:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:51 INFO - 'MOZ_NO_REMOTE': '1', 22:00:51 INFO - 'NO_EM_RESTART': '1', 22:00:51 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:00:51 INFO - 'OS': 'Windows_NT', 22:00:51 INFO - 'OURDRIVE': 'C:', 22:00:51 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;', 22:00:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:00:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:00:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:00:51 INFO - 'PROCESSOR_LEVEL': '6', 22:00:51 INFO - 'PROCESSOR_REVISION': '1e05', 22:00:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:00:51 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:00:51 INFO - 'PROMPT': '$P$G', 22:00:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:00:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:00:51 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:00:51 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:00:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:00:51 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:00:51 INFO - 'SYSTEMDRIVE': 'C:', 22:00:51 INFO - 'SYSTEMROOT': 'C:\\windows', 22:00:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:00:51 INFO - 'TEST1': 'testie', 22:00:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:00:51 INFO - 'USERDOMAIN': 'T-W732-IX-092', 22:00:51 INFO - 'USERNAME': 'cltbld', 22:00:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:00:51 INFO - 'WINDIR': 'C:\\windows', 22:00:51 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:00:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:00:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:52 INFO - Downloading/unpacking blobuploader==1.2.4 22:00:52 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 22:00:52 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 22:00:53 INFO - Downloading blobuploader-1.2.4.tar.gz 22:00:53 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 22:00:53 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 22:00:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:00:53 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 22:00:53 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 22:00:53 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 22:00:53 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 22:00:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:00:53 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 22:00:53 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 22:00:53 INFO - Downloading docopt-0.6.1.tar.gz 22:00:53 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 22:00:53 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 22:00:53 INFO - Installing collected packages: blobuploader, requests, docopt 22:00:53 INFO - Running setup.py install for blobuploader 22:00:54 INFO - Running setup.py install for requests 22:00:55 INFO - Running setup.py install for docopt 22:00:55 INFO - Successfully installed blobuploader requests docopt 22:00:55 INFO - Cleaning up... 22:00:56 INFO - Return code: 0 22:00:56 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 22:00:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:56 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 0x01968B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0140E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01328B48>, '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 0x0134A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-092', 'USERDOMAIN': 'T-W732-IX-092', '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-092', '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 22:00:56 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 22:00:56 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 22:00:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:00:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:00:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:00:56 INFO - 'COMPUTERNAME': 'T-W732-IX-092', 22:00:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:00:56 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:00:56 INFO - 'DCLOCATION': 'SCL3', 22:00:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:00:56 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:00:56 INFO - 'HOMEDRIVE': 'C:', 22:00:56 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:00:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:00:56 INFO - 'KTS_VERSION': '1.19c', 22:00:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:00:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-092', 22:00:56 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:00:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:00:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:56 INFO - 'MOZ_NO_REMOTE': '1', 22:00:56 INFO - 'NO_EM_RESTART': '1', 22:00:56 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:00:56 INFO - 'OS': 'Windows_NT', 22:00:56 INFO - 'OURDRIVE': 'C:', 22:00:56 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 22:00:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:00:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:00:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:00:56 INFO - 'PROCESSOR_LEVEL': '6', 22:00:56 INFO - 'PROCESSOR_REVISION': '1e05', 22:00:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:00:56 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:00:56 INFO - 'PROMPT': '$P$G', 22:00:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:00:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:00:56 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:00:56 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:00:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:00:56 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:00:56 INFO - 'SYSTEMDRIVE': 'C:', 22:00:56 INFO - 'SYSTEMROOT': 'C:\\windows', 22:00:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:00:56 INFO - 'TEST1': 'testie', 22:00:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:00:56 INFO - 'USERDOMAIN': 'T-W732-IX-092', 22:00:56 INFO - 'USERNAME': 'cltbld', 22:00:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:00:56 INFO - 'WINDIR': 'C:\\windows', 22:00:56 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:00:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:00:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:00:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:56 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 22:00:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jd1uvg-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 22:00:56 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 22:00:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kqlbm_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 22:00:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 22:00:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sqe6ca-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 22:00:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 22:00:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v9nzvi-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 22:00:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 22:00:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cxugjw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 22:00:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 22:00:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g7_nam-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 22:00:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 22:00:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qrcnfu-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 22:00:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 22:00:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ewhwxq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 22:00:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 22:00:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gejsie-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 22:00:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 22:00:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_ugf0o-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 22:00:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 22:00:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-c3jfb0-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 22:00:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 22:00:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vogsfv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 22:00:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 22:00:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ivfooj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 22:00:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 22:00:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w15e1i-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 22:00:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 22:00:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-udetdv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:00:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 22:00:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-azespr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 22:00:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 22:00:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6uckdv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 22:00:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:00:59 INFO - Running setup.py install for manifestparser 22:00:59 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:00:59 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 22:00:59 INFO - Running setup.py install for mozcrash 22:00:59 INFO - Running setup.py install for mozdebug 22:01:00 INFO - Running setup.py install for mozdevice 22:01:00 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:01:00 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 22:01:00 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:01:00 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 22:01:01 INFO - Running setup.py install for mozfile 22:01:01 INFO - Running setup.py install for mozhttpd 22:01:01 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:01:01 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 22:01:01 INFO - Running setup.py install for mozinfo 22:01:02 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:01:02 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 22:01:02 INFO - Running setup.py install for mozInstall 22:01:02 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:01:02 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 22:01:02 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:01:02 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 22:01:02 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:01:02 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 22:01:02 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:01:02 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 22:01:02 INFO - Running setup.py install for mozleak 22:01:02 INFO - Running setup.py install for mozlog 22:01:03 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:01:03 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 22:01:03 INFO - Running setup.py install for moznetwork 22:01:04 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:01:04 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 22:01:04 INFO - Running setup.py install for mozprocess 22:01:04 INFO - Running setup.py install for mozprofile 22:01:04 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:01:04 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 22:01:04 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:01:04 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 22:01:04 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:01:04 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 22:01:05 INFO - Running setup.py install for mozrunner 22:01:05 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:01:05 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 22:01:06 INFO - Running setup.py install for mozscreenshot 22:01:06 INFO - Running setup.py install for moztest 22:01:06 INFO - Running setup.py install for mozversion 22:01:06 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:01:06 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 22:01:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:01:07 INFO - Cleaning up... 22:01:07 INFO - Return code: 0 22:01:07 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 22:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:07 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 0x01968B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0140E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01328B48>, '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 0x0134A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-092', 'USERDOMAIN': 'T-W732-IX-092', '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-092', '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 22:01:07 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 22:01:07 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 22:01:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:01:07 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:01:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:01:07 INFO - 'COMPUTERNAME': 'T-W732-IX-092', 22:01:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:01:07 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:01:07 INFO - 'DCLOCATION': 'SCL3', 22:01:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:01:07 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:01:07 INFO - 'HOMEDRIVE': 'C:', 22:01:07 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:01:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:01:07 INFO - 'KTS_VERSION': '1.19c', 22:01:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:01:07 INFO - 'LOGONSERVER': '\\\\T-W732-IX-092', 22:01:07 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:01:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:07 INFO - 'MOZ_NO_REMOTE': '1', 22:01:07 INFO - 'NO_EM_RESTART': '1', 22:01:07 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:01:07 INFO - 'OS': 'Windows_NT', 22:01:07 INFO - 'OURDRIVE': 'C:', 22:01:07 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;', 22:01:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:01:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:01:07 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:01:07 INFO - 'PROCESSOR_LEVEL': '6', 22:01:07 INFO - 'PROCESSOR_REVISION': '1e05', 22:01:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:01:07 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:01:07 INFO - 'PROMPT': '$P$G', 22:01:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:01:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:01:07 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:01:07 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:01:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:01:07 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:01:07 INFO - 'SYSTEMDRIVE': 'C:', 22:01:07 INFO - 'SYSTEMROOT': 'C:\\windows', 22:01:07 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:01:07 INFO - 'TEST1': 'testie', 22:01:07 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:01:07 INFO - 'USERDOMAIN': 'T-W732-IX-092', 22:01:07 INFO - 'USERNAME': 'cltbld', 22:01:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:01:07 INFO - 'WINDIR': 'C:\\windows', 22:01:07 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:01:07 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:07 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 22:01:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vodnjc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 22:01:07 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)) 22:01:07 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 22:01:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8zk1wu-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 22:01:07 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)) 22:01:07 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 22:01:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3oxgbe-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 22:01:08 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)) 22:01:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 22:01:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iyg_2r-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 22:01:08 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)) 22:01:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 22:01:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-krjazc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 22:01:08 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)) 22:01:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 22:01:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-di1yyh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 22:01:08 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)) 22:01:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 22:01:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jahkgw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 22:01:08 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)) 22:01:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 22:01:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dj0dqf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 22:01:08 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)) 22:01:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 22:01:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kvo0pp-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 22:01:08 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)) 22:01:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 22:01:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-48hxdk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 22:01:09 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)) 22:01:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 22:01:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rz7adu-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 22:01:09 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)) 22:01:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 22:01:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_1r2cq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 22:01:09 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)) 22:01:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 22:01:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-md84r8-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 22:01:09 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)) 22:01:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 22:01:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xku1su-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 22:01:09 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)) 22:01:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 22:01:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dkmvmy-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:01:09 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)) 22:01:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 22:01:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jtalmt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 22:01:09 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)) 22:01:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 22:01:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hwfxsw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 22:01:10 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)) 22:01:10 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)) 22:01:10 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)) 22:01:10 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)) 22:01:10 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)) 22:01:10 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)) 22:01:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 22:01:10 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 22:01:10 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 22:01:10 INFO - Downloading blessings-1.5.1.tar.gz 22:01:11 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 22:01:11 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 22:01:11 INFO - Installing collected packages: blessings 22:01:11 INFO - Running setup.py install for blessings 22:01:11 INFO - Successfully installed blessings 22:01:11 INFO - Cleaning up... 22:01:14 INFO - Return code: 0 22:01:14 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 22:01:14 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 22:01:14 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 22:01:14 INFO - Reading from file tmpfile_stdout 22:01:14 INFO - Using _rmtree_windows ... 22:01:14 INFO - Using _rmtree_windows ... 22:01:14 INFO - Current package versions: 22:01:14 INFO - blessings == 1.5.1 22:01:14 INFO - blobuploader == 1.2.4 22:01:14 INFO - docopt == 0.6.1 22:01:14 INFO - manifestparser == 1.1 22:01:14 INFO - mozInstall == 1.12 22:01:14 INFO - mozcrash == 0.16 22:01:14 INFO - mozdebug == 0.1 22:01:14 INFO - mozdevice == 0.46 22:01:14 INFO - mozfile == 1.2 22:01:14 INFO - mozhttpd == 0.7 22:01:14 INFO - mozinfo == 0.8 22:01:14 INFO - mozleak == 0.1 22:01:14 INFO - mozlog == 3.0 22:01:14 INFO - moznetwork == 0.27 22:01:14 INFO - mozprocess == 0.22 22:01:14 INFO - mozprofile == 0.27 22:01:14 INFO - mozrunner == 6.11 22:01:14 INFO - mozscreenshot == 0.1 22:01:14 INFO - mozsystemmonitor == 0.0 22:01:14 INFO - moztest == 0.7 22:01:14 INFO - mozversion == 1.4 22:01:14 INFO - psutil == 3.1.1 22:01:14 INFO - requests == 1.2.3 22:01:14 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 22:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:14 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 0x01968B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0140E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01328B48>, '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 0x0134A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-092', 'USERDOMAIN': 'T-W732-IX-092', '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-092', '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 22:01:14 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 22:01:14 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 22:01:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:01:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:01:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:01:14 INFO - 'COMPUTERNAME': 'T-W732-IX-092', 22:01:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:01:14 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:01:14 INFO - 'DCLOCATION': 'SCL3', 22:01:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:01:14 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:01:14 INFO - 'HOMEDRIVE': 'C:', 22:01:14 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:01:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:01:14 INFO - 'KTS_VERSION': '1.19c', 22:01:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:01:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-092', 22:01:14 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:01:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:01:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:14 INFO - 'MOZ_NO_REMOTE': '1', 22:01:14 INFO - 'NO_EM_RESTART': '1', 22:01:14 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:01:14 INFO - 'OS': 'Windows_NT', 22:01:14 INFO - 'OURDRIVE': 'C:', 22:01:14 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;', 22:01:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:01:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:01:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:01:14 INFO - 'PROCESSOR_LEVEL': '6', 22:01:14 INFO - 'PROCESSOR_REVISION': '1e05', 22:01:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:01:14 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:01:14 INFO - 'PROMPT': '$P$G', 22:01:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:01:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:01:14 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:01:14 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:01:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:01:14 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:01:14 INFO - 'SYSTEMDRIVE': 'C:', 22:01:14 INFO - 'SYSTEMROOT': 'C:\\windows', 22:01:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:01:14 INFO - 'TEST1': 'testie', 22:01:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:01:14 INFO - 'USERDOMAIN': 'T-W732-IX-092', 22:01:14 INFO - 'USERNAME': 'cltbld', 22:01:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:01:14 INFO - 'WINDIR': 'C:\\windows', 22:01:14 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:01:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:01:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:01:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:15 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)) 22:01:15 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)) 22:01:15 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)) 22:01:15 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)) 22:01:15 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)) 22:01:15 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)) 22:01:15 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)) 22:01:15 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)) 22:01:15 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)) 22:01:15 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)) 22:01:15 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)) 22:01:15 INFO - Cleaning up... 22:01:15 INFO - Return code: 0 22:01:15 INFO - Running post-action listener: _resource_record_post_action 22:01:15 INFO - Running post-action listener: _start_resource_monitoring 22:01:15 INFO - Starting resource monitoring. 22:01:15 INFO - ##### 22:01:15 INFO - ##### Running install step. 22:01:15 INFO - ##### 22:01:15 INFO - Running pre-action listener: _resource_record_pre_action 22:01:15 INFO - Running main action method: install 22:01:15 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 22:01:15 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 22:01:15 INFO - Reading from file tmpfile_stdout 22:01:15 INFO - Using _rmtree_windows ... 22:01:15 INFO - Using _rmtree_windows ... 22:01:15 INFO - Detecting whether we're running mozinstall >=1.0... 22:01:15 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 22:01:15 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 22:01:15 INFO - Reading from file tmpfile_stdout 22:01:15 INFO - Output received: 22:01:15 INFO - Usage: mozinstall-script.py [options] installer 22:01:15 INFO - Options: 22:01:15 INFO - -h, --help show this help message and exit 22:01:15 INFO - -d DEST, --destination=DEST 22:01:15 INFO - Directory to install application into. [default: 22:01:15 INFO - "C:\slave\test-pgo"] 22:01:15 INFO - --app=APP Application being installed. [default: firefox] 22:01:15 INFO - Using _rmtree_windows ... 22:01:15 INFO - Using _rmtree_windows ... 22:01:15 INFO - mkdir: C:\slave\test-pgo\build\application 22:01:15 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'] 22:01:15 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 22:01:17 INFO - Reading from file tmpfile_stdout 22:01:17 INFO - Output received: 22:01:17 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 22:01:17 INFO - Using _rmtree_windows ... 22:01:17 INFO - Using _rmtree_windows ... 22:01:17 INFO - Running post-action listener: _resource_record_post_action 22:01:17 INFO - ##### 22:01:17 INFO - ##### Running run-tests step. 22:01:17 INFO - ##### 22:01:17 INFO - Running pre-action listener: _resource_record_pre_action 22:01:17 INFO - Running main action method: run_tests 22:01:17 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 22:01:17 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 22:01:17 INFO - Python 2.7.3 22:01:17 INFO - Return code: 0 22:01:17 INFO - grabbing minidump binary from tooltool 22:01:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:17 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 0x01328B48>, '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 0x0134A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E9660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 22:01:17 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 22:01:17 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 22:01:17 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 22:01:17 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpcgtyu4 22:01:17 INFO - INFO - File integrity verified, renaming tmpcgtyu4 to win32-minidump_stackwalk.exe 22:01:17 INFO - Return code: 0 22:01:17 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 22:01:17 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 22:01:17 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 22:01:17 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 22:01:17 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 22:01:17 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-092', '--webServer', 'localhost'] in C:\slave\test-pgo\build 22:01:17 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName Fx-Team --suite dromaeojs-e10s --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-092 --webServer localhost 22:01:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:01:17 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:01:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:01:17 INFO - 'COMPUTERNAME': 'T-W732-IX-092', 22:01:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:01:17 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:01:17 INFO - 'DCLOCATION': 'SCL3', 22:01:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:01:17 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:01:17 INFO - 'HOMEDRIVE': 'C:', 22:01:17 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:01:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:01:17 INFO - 'KTS_VERSION': '1.19c', 22:01:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:01:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-092', 22:01:17 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 22:01:17 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 22:01:17 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:01:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:01:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:17 INFO - 'MOZ_NO_REMOTE': '1', 22:01:17 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 22:01:17 INFO - 'NO_EM_RESTART': '1', 22:01:17 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:01:17 INFO - 'OS': 'Windows_NT', 22:01:17 INFO - 'OURDRIVE': 'C:', 22:01:17 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 22:01:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:01:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:01:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:01:17 INFO - 'PROCESSOR_LEVEL': '6', 22:01:17 INFO - 'PROCESSOR_REVISION': '1e05', 22:01:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:01:17 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:01:17 INFO - 'PROMPT': '$P$G', 22:01:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:01:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:01:17 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:01:17 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:01:17 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 22:01:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:01:17 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:01:17 INFO - 'SYSTEMDRIVE': 'C:', 22:01:17 INFO - 'SYSTEMROOT': 'C:\\windows', 22:01:17 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:01:17 INFO - 'TEST1': 'testie', 22:01:17 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:01:17 INFO - 'USERDOMAIN': 'T-W732-IX-092', 22:01:17 INFO - 'USERNAME': 'cltbld', 22:01:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:01:17 INFO - 'WINDIR': 'C:\\windows', 22:01:17 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:01:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:01:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:01:17 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-092', '--webServer', 'localhost'] with output_timeout 3600 22:01:18 INFO - mozversion INFO | application_buildid: 20151023173019 22:01:18 INFO - mozversion INFO | application_changeset: 335ea7a95492650e23c9ba14648d4f43620babab 22:01:18 INFO - mozversion INFO | application_display_name: Nightly 22:01:18 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:01:18 INFO - mozversion INFO | application_name: Firefox 22:01:18 INFO - mozversion INFO | application_remotingname: firefox 22:01:18 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 22:01:18 INFO - mozversion INFO | application_vendor: Mozilla 22:01:18 INFO - mozversion INFO | application_version: 44.0a1 22:01:18 INFO - mozversion INFO | platform_buildid: 20151023173019 22:01:18 INFO - mozversion INFO | platform_changeset: 335ea7a95492650e23c9ba14648d4f43620babab 22:01:18 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 22:01:18 INFO - mozversion INFO | platform_version: 44.0a1 22:01:18 INFO - 2015-10-23 22:01:18,898 DEBUG : using testdate: 1445662878 22:01:18 INFO - 2015-10-23 22:01:18,898 DEBUG : actual date: 1445662878 22:01:18 INFO - 2015-10-23 22:01:18,907 INFO : starting webserver on 'localhost:49728' 22:01:18 INFO - 2015-10-23 22:01:18,908 INFO : Starting test suite T-W732-IX-092.e 22:01:18 INFO - 2015-10-23 22:01:18,908 INFO : Starting test dromaeo_css 22:01:18 INFO - 2015-10-23 22:01:18,908 DEBUG : operating with platform_type : w7_ 22:01:18 INFO - 2015-10-23 22:01:18,910 INFO : Initialising browser for dromaeo_css test... 22:01:19 INFO - 2015-10-23 22:01:19,009 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpv0cdu3\profile http://localhost:49728/getInfo.html 22:01:27 INFO - 2015-10-23 22:01:27,802 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:01:27 INFO - 2015-10-23 22:01:27,802 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:01:27 INFO - 2015-10-23 22:01:27,802 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 22:01:27 INFO - 2015-10-23 22:01:27,802 DEBUG : BROWSER_OUTPUT: __metrics 22:01:28 INFO - 2015-10-23 22:01:28,477 DEBUG : BROWSER_OUTPUT: [Child 784] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 333 22:01:28 INFO - 2015-10-23 22:01:28,477 DEBUG : BROWSER_OUTPUT: [Child 784] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 333 22:01:29 INFO - 2015-10-23 22:01:29,063 INFO : Browser initialized. 22:01:29 INFO - 2015-10-23 22:01:29,063 INFO : Running cycle 1/1 for dromaeo_css test... 22:01:29 INFO - 2015-10-23 22:01:29,063 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpv0cdu3\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:01:40 INFO - 2015-10-23 22:01:40,499 DEBUG : BROWSER_OUTPUT: RSS: Main: 135495680 22:01:40 INFO - 2015-10-23 22:01:40,499 DEBUG : BROWSER_OUTPUT: 22:02:30 INFO - 2015-10-23 22:02:30,105 DEBUG : BROWSER_OUTPUT: 1445662950126 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] 22:04:37 INFO - 2015-10-23 22:04:37,332 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49728/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49728/tests/dromaeo/cssquery-ext.html) 22:04:38 INFO - 2015-10-23 22:04:38,447 DEBUG : BROWSER_OUTPUT: RSS: Main: 123559936 22:04:38 INFO - 2015-10-23 22:04:38,447 DEBUG : BROWSER_OUTPUT: 22:07:40 INFO - 2015-10-23 22:07:40,371 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49728/tests/dromaeo/cssquery-ext.html (next: http://localhost:49728/tests/dromaeo/cssquery-jquery.html) 22:07:40 INFO - 2015-10-23 22:07:40,946 DEBUG : BROWSER_OUTPUT: RSS: Main: 87367680 22:07:40 INFO - 2015-10-23 22:07:40,947 DEBUG : BROWSER_OUTPUT: 22:10:42 INFO - 2015-10-23 22:10:42,494 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49728/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49728/tests/dromaeo/cssquery-mootools.html) 22:10:43 INFO - 2015-10-23 22:10:43,075 DEBUG : BROWSER_OUTPUT: RSS: Main: 75087872 22:10:43 INFO - 2015-10-23 22:10:43,075 DEBUG : BROWSER_OUTPUT: 22:13:44 INFO - 2015-10-23 22:13:44,719 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49728/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49728/tests/dromaeo/cssquery-prototype.html) 22:13:45 INFO - 2015-10-23 22:13:45,338 DEBUG : BROWSER_OUTPUT: RSS: Main: 70815744 22:13:45 INFO - 2015-10-23 22:13:45,338 DEBUG : BROWSER_OUTPUT: 22:16:47 INFO - 2015-10-23 22:16:47,651 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49728/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49728/tests/dromaeo/cssquery-yui.html) 22:16:48 INFO - 2015-10-23 22:16:48,246 DEBUG : BROWSER_OUTPUT: RSS: Main: 71970816 22:16:48 INFO - 2015-10-23 22:16:48,246 DEBUG : BROWSER_OUTPUT: 22:19:50 INFO - 2015-10-23 22:19:50,513 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49728/tests/dromaeo/cssquery-yui.html (next: http://localhost:49728/tests/dromaeo/cssquery-dojo.html) 22:19:50 INFO - 2015-10-23 22:19:50,769 DEBUG : BROWSER_OUTPUT: RSS: Main: 66379776 22:19:50 INFO - 2015-10-23 22:19:50,769 DEBUG : BROWSER_OUTPUT: 22:19:50 INFO - 2015-10-23 22:19:50,769 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:19:50 INFO - 2015-10-23 22:19:50,769 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:19:50 INFO - 2015-10-23 22:19:50,769 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:19:50 INFO - 2015-10-23 22:19:50,769 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:19:50 INFO - 2015-10-23 22:19:50,769 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2857.98571007145;2915.6355455568055;2931.208573685105;2932.2815910102013;2948.3071478202623;5512.834614961557;5620.1288800235925;5714.228579141814;5784.045632470643;5790.131480277961;5608;5651.519620832234;5698.7435565399555;5793.246877905866;5814.302283725949;4705.129551033053;4752.358431611731;4784.90430191396;4878.731669758162;4891.192953162724;1051.2905320152386;4398;4757.881052973686;4793.328933949232;4898.951010489885;4744.64415168862;4792.58841345725;4794.065157294329;4817.56641902227;4835;4492.68551201416;4697.506761789997;4832.8791780205775;4889.633277504201;4896.290037944514;4632.884177895546;4711.976440117777;4713.316569097462;4717.386739723848;4739;5547.140193270049;5581.162825576196;5640.323161220639;5640.5487560995025;5655.688937108457;5891.941080589182;5941.584089113764;5946.970265148647;5972.701364931736;5989.9101513477335;6018.368071352489;6047.485963693091;6049;6068.059450785136;6104.145419641254;5390.110631745757;5452.209429632681;5516.14499752539;5534.944650553523;5629.296337957755;6056.182415373956;6129.84675383119;6136.938630613726;6189.412005859436;6264.561480696308;5814.127880817911;5866.325372582122;5873.119032145127;5982.880342393128;5990.191324171268;5893.3222679391565;5938.109283607493;6090.33006369299;6103.969480152571;6112.083187521907;5823.56323275756;5853.9414605854245;5886.676232807236;5956.434138756811;5957.642541447441;5884.205632239678;5943.375945525744;5987.401259873977;6009.729562169713;6037.486813620803;3541.327147841902;3559;3586.264815712773;3662.3957047087465;3664.908377290589;3250.4799232122746;3260.201250693548;3275.7870738401925;3278.426275401795;3346.9330613387597;5732.054211055212;5768.221290125864;5796.681182535001;5823.79616713413;5839.036558967808;5881.411858814085;5924.8815023700145;5992.34084250732;6076.301225359052;6169.84575385619;5627.380988091307;5671.461211184931;5733.168690539848;5750.597458177971;5760.596758226968;4578.88552786183;4649.395578574764;4699.906001879944;4744.833930812405;4786.856394308176;6203.534734894901;6322.36776322364;6380.649064301508;6516.283208847023;6525.988471786881;5835.095560188178;5994.820155395345;6014.0678194879865;6089.725962331794;6151.415615516519;6288.465480434214;6385.7126429309865;6422.293547709654;6498.317676644074;6524.575902566318;5756.165356023437;5851.093080572518;5953.851153721192;5954.672493012926;5979.5814292999075;6110.083487476914;6121.510279177566;6177.166082578974;6196.47329976957;6232.657203853832;5876.794312199053;5960.314563825215;5961.552883533665;5987.072003839412;6083.117947897442;5679.375268720355;5744.971275143597;5817.505512031525;5826.213461182686;5834.533237340917;5660.716964151694;5724.771009159587;5768.826935191951;5774.653520788766;5795.884082318245;5817.680027598522;5944.970275148597;6027.57806953509;6040.335563087969;6058.878822423615;5817.185594016925;5839.883202336015;5881.2942446906645;5938.762449501972;5948.464638182411 22:19:50 INFO - 2015-10-23 22:19:50,769 DEBUG : BROWSER_OUTPUT: |1;ext.html;471.11195396677266;476.86171010407;496.30269471392694;496.3770468062581;514.1285521041835;36675.63324366756;37034;38292.808535957316;39355.80322098389;39687.206255874866;38191;38588;38655.80672096636;39287.607123928756;39767.40348894769;8157.836843263131;8231.670733170697;8339.08270090291;8573.356998225127;8692.608832602516;7997.40019498537;8107.270345668889;8144.633491492897;8205.384596155282;8218.21926916942;8105.594720263993;8302.792430189264;8718.782030449258;8737.257333126692;8743.08197639245;8006.759797206093;8079.515229086273;8112.188781121899;8175.95912020439;8565.528895910753;8061.516309021419;8248.711295104642;8491.957540212321;8590.441621294658;8591.785205369853;36051.27897442036;38168.80915595432;38378.80810595957;38735.22529549393;38800.417993730116;2603.5053339865367;2994.2364697002126;3093.8453077346044;3114.314850732835;3431.4551709778784;28634;28649.85675071632;28823;29309.70690293091;29787.404251915057;2651.8196524448927;2667.5331816932153;2680.9865950670123;2722.060888993294;2728.6043523688954;270179;270719;271823;272376;272569.63715181494;41910.580894190665;43714.34428483575;46210;48573;49869.25196122061;51196.488035119175;51351;51917.48082519202;52221.21668174981;52375.21437178445;11969.700757481132;12480.126391152533;12615.36923153857;12698.682532936751;12728.1726687765;9488.715338539854;9502.619895204252;9523.28575356851;9720.659776907774;9921.305508614329;16033.797465190157;16391.34434622602;16778;17150.31398744061;17207;6935.965320173367;7067.92932070683;7138.964305178441;7146.178189508273;7289.817754556072;808.8847663360388;965.2519297544319;983.4738414947968;1021.6424251511911;1050.2700623067049;25070.74929250684;25245.242642720747;25956.09153679612;26287.21138365852;26304.73695263023;891.6299735609779;942;960.1406740966935;969.1132613658565;1032.6024480574883;13941.790873136912;14228.359723812453;14341.78487322691;14459.349329280207;14529.636759080897;383484;392762;393315;395114;397191;46640.300395494094;47883;47883.76058119687;48182.27726584104;48694.75652621714;1064.1433645915113;1129.271619805221;1143.9084873210122;1151.3092144713105;1185.1348515583672;26783.330416739736;26857.86571067132;26892.462150757266;26923.865380673757;27050.91796328125;39116.41325380179;40600.79699601483;42717.35923961205;43118.13763724771;43340.13319733651;354864;355516;360407;360793;362087;26000.8699956499;26576.202713918614;26766.866165669046;27626.585601215596;27677.30806729848;37571.24857502889;38126;38561.80719096387;38933.8053309743;40037.1992560153;32595.39514623522;33143.66856331406;33570.66429335676;34052.65947340495;34543.30913381769;33640.49539256864;34087.48868766921;34194.658053419145;34309.142271443416;34384.48423273703;33750.662493375734;33806.661933381336;34043.638254469544;35040.12399690028;35108.52069941153;29313.413731725672;29397.559036614897;30545.389092218476;30589.694103058686;32346.353072937938;22484.32547023592;22488.887555562116;22710.65934010958;23036.76963230346;23122.421939450975 22:19:50 INFO - 2015-10-23 22:19:50,775 DEBUG : BROWSER_OUTPUT: |2;jquery.html;320.1515982645989;321.1009174311926;322.41642626845413;326.84964916138574;332.11160146607835;9861.852072218922;10372.740681482948;16664.999999999985;16674.333026678934;17455.650886982254;10945.124390048797;11099.223054386197;11124.38815865127;11155.721106972329;11410.20128590999;4271.423357846697;4287.699861009731;4309.965608254028;4426.579474949875;4560.361549383089;4257.212415703089;4470.865874023784;4534.36518887356;4572.657050721193;4819.590334821544;4458.219811532985;4484.372187893698;4516.9096618067615;4726.952730472703;4750.5486978737135;4449.977750111245;4728;6315.873642546922;6631.104800851896;6787.287334829821;5807.245058142415;6045.455908968214;6083.513318934474;6292.276388215368;6447.742090316428;22081.889590551946;22136.446588835246;22219.66670499944;23456.296311110695;23655.526889462115;2058.2384517728387;2122.2466024561368;2241.4732537853592;2343.203310874311;2362.078789272185;14322.928385358111;14427.206503642296;14437.575279000848;14490.855091449055;15138.243087845674;1693.5850716574396;1736.9652606947916;1759.3490408548791;1811.6829554827852;1826.53423377038;72428.6378568109;76499;78348.21651783466;79183.60408197923;81335.593322033;14436.927815361067;14660.680538751563;14810;15473.22633868302;15549.067055976451;20662;20816.791832081788;21872.343829685135;22004.669929951062;22010.33968980933;8149.147798270558;8229.25936665703;8457.619407126695;8469.11074337198;8540.316774658013;6801.387875091265;6891.345322194484;6892.965535172292;6907.309269073053;6913.10129683148;6548.1814773153355;6606.009098635147;6804.387605115563;6975.651217439107;7016.824579385556;979.3683074417104;985.4678473624306;1047.4972013433594;2609.739026097413;2660.2950218192195;420.60463164625764;427.55107137505496;437.3090516983159;443.2553310438494;448.78906913750484;11734.823977640342;11844.230125041844;11974.401279935968;12076.396180190955;12223.144379893321;437.63676148796424;441.0847491455243;454.48189064465873;456.60731770677194;462.1657907477036;6233.379321376384;6311.368863113744;6378.74485020594;6508.21901371829;6660.367265109807;416204;428223;445920;459929;463016;45894;78811.21188788154;81392;82151.58924205341;82712;467.3457159976061;473.4933621025539;474.54443734015734;483.44645381038515;494.4807951650752;9914.603415863285;10096.596136154765;10118.494075296352;10381.273310868166;10440.164786817188;38461.80769096137;39026.804865975486;39895.80312590627;40300.79849600851;41569.1686166281;50990.7252318695;51903.740481297355;52561.68595785136;52992.4700753003;54363;5813.6221145625395;5886.646801191942;6108.816735497942;6123.4488895999575;6263.404976527223;11117.833232501358;11145.777084458428;11577.826332605186;11826;12243.632691019284;9968.800623987625;10044.146247569039;10183.796324073624;10399.94800025995;10671.94664026675;10170.288079834341;10295.94852025735;10409.115225205942;10535.157187424835;10673.519691613741;10369;10377.377357358582;10640.41477718702;10665.200109991627;10715.196360272856;5944.910826337522;5955.255593050869;5980.372060933713;6230.345813689679;6280.560360774702;10007.599696012077;10213.33613315132;10231.232657550565;10381.844272336071;10583.470826458492 22:19:50 INFO - 2015-10-23 22:19:50,776 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1210.915235933485;1255.7111864271217;1427.650225694705;1560.7502799552074;1564.0224859462835;8821.161989610993;9134.17792398684;9252.722418327452;9279.164875161234;9353.719388418342;9348.39235449697;9385.76535586609;9392.436453812776;9426.293028022908;9431.952840235806;2150.4301360139575;2224.644056950888;2228.699125618037;2242.091952759135;2242.405762472938;2224.7663995280504;2230.520438105807;2238.29493709481;2239.8656080635214;2253.4704344479032;2193.0679461228992;2227.265002549163;2253.549290141978;2254.7068881045443;2256.4697296135396;2208.9447763805874;2232.6762619420174;2269.636858102704;2270.897809598572;2278.692376529172;2229.598672238996;2232.3860938242115;2235.8323125765633;2244.8989795459242;2248.4716091718433;8995.100489951017;8996.100389961017;9058.818823623556;9081.45511269326;9088.591013404413;5080.491950804927;5130.153524668425;5143.459936706629;5144.871378215537;5150.150225212836;3273.7053665170247;3320.933581328384;3361.058903507022;3368.6631336866362;3374.493825926106;994.5226291380178;1032.411525430505;1034.8395998620226;1044.958201671932;1046.8168070587617;12950.546730864375;12955.222686638832;13016.869831301756;13017.28404937728;13022.2837743925;4203.327467605169;4351.086271882877;4399.890002749957;4430.335449682573;4435.756033418193;10077.042680945298;10112.696619101438;10120.493975301206;10124.987501249816;10132.54403551842;4948.950510494921;4949.208126699711;4956.925646115311;4962.776675049631;4963.975180124076;4480.081583275421;4563.429571303587;4567;4572.542745725401;4575.931361029588;3453.8618455262013;3478.7390945678667;3488.7383446241633;3501.089716673632;3509.8069606171907;996.1681995533665;1011.3426272922659;1011.4083261292079;1021.1014307409432;1025;930.692871352452;939.1359948847077;939.8402271613842;940.7836197674574;966.970990870275;7915.722949696733;7922.524648521107;7962.800929976681;8007.759767206877;8009.519428834288;916.6562539047857;942.1285311087217;944.1313991128178;963.7397902566267;963.7783309838776;5719.2279042328755;5759.740811663485;5782.277215348082;5789.2763404574425;5803.65178089316;11235.606753763743;11284.774304513863;11415.714607134722;11426.88573114275;11438.913303236179;11380.317180969168;11510.654680359603;11534.653960381202;11554.94222528882;11559.537618495166;8166.060902996232;8277.420580559423;8305.626246818907;8355.707550235835;8359.874601880976;7272;7440.590767507839;7641.808954775953;7685.00094987648;7698.114716807639;9608.279379046739;9805.166560842408;9875.506224688594;9878;9994.650187243411;10071.69784906454;10243.385396875914;10291.794164116825;10293.794124117327;10304;7437.590932498764;7637.389008879388;7641.2740789624895;7669.041369828771;7684.769456916301;8741.694040708544;8801;8802.779930501789;8816.250618697482;8821.691240806542;8087.150849160754;8114.553699546345;8189;8224.054233763194;8276.255137037455;7774;7863.252990965849;7890.132085470479;8096.352291816758;8136.918630813853;7611.505252158592;7677.30904218623;7735.458517903693;7744.6902123914415;7753.418493613114;6856.5200435969;6870.492270321397;6885.896711549431;6903.827404314731;6920.100386949664;6637.668116594247;6832.829179270558;6855.07456493367;6859.324863303415;6892.276310987274 22:19:50 INFO - 2015-10-23 22:19:50,855 DEBUG : BROWSER_OUTPUT: |4;prototype.html;311.8144703901179;317.4064499386148;318.50154508194674;320.8171342334869;323.0631169608135;6115.113308570255;6254.186955695759;6264.937350626492;6268.4671802896755;6316.052592111186;6146.569740118193;6237.906431403532;6285.968570157154;6288.371162883721;6288.465480434169;4426.889327766816;4429.446319210091;4433.1577000369825;4437.5784300491405;4440.311751678495;4422.181896349186;4427.645788336926;4434.201843668138;4434.4678638563255;4435.135148645999;4306.741595504264;4318.8488402905905;4358.825646974117;4377.124575084979;4382.824687012516;4298.656107511391;4337.0458499130145;4348.73907565544;4352.5212226654885;4353.0423306872435;3707.1658876752786;3765.491658626078;3767.9449754068905;3770.6417890300377;3773.396256598931;5883.058710606325;5897.911531327033;5898.203742494751;5907;5914.733836977303;5175.844724658266;5206.401263854667;5278.287431196778;5352.4915133062295;5353.839384818461;6394.776182833579;6418.646974416405;6483.870322593569;6487.351264873475;6509.511786616023;4314.309710446345;4337.0458499130145;4355.150745604608;4361.978190109061;4368.475782906072;6572.145621069279;6752.155980502437;6822.351876571718;6834.829129271759;6839.658017099126;4414.227510185769;4468.776561171929;4472;4474.619657329098;4484.260097083945;4537.069900670354;4565.3836732040745;4578.153041687298;4579.358889755437;4593.494715581283;5199.7920083197005;5308.469153084738;5310.8141215057285;5346.91979620306;5363.731813409314;6402.807915762534;6548.27968923418;6554.541182117206;6555.180602424697;6559.376859198369;1611.73406387947;1621.9189040547926;1626.3332033866295;1626.568959225806;1626.6665333606581;1044.770150566874;1067.64767626683;1072.9034386905216;1077.4451157653814;1093.971666633362;6214.316425193225;6310.652914089677;6340.239171299381;6361.427471527585;6410.294867564564;6534.411902928759;6822.590644561243;6846.828829279308;6860.759873404407;6867.931320686829;6365.108884756138;6400.167978162809;6415.26224484181;6415.711292991827;6419.550631455847;3745.6254374562327;3784.4890939723336;3801.3157631626573;3807.961920380816;3810.428435734662;7147.3210045045635;7161.92838071623;7220.67506962188;7223.638818059076;7225.927740722631;6891.104156459628;6906.896396554056;6909.689063992032;6909.930900691029;6914.723411063602;7191.928080719231;7208.531445456028;7217.422606191492;7218.169910460364;7228.38558722504;6637.236717777518;6863.931360686329;6913.3777959983845;6923.099997000358;6937.687804048931;6690.264070952094;6717.16035495563;6738.73045078211;6778.694958726967;6796.048553202457;7310.744123955636;7311.086114235721;7323.560586364835;7332.266773322625;7340.2292759261645;4423.513413524574;4547.590716835501;4556.908861822679;4561.2017896867255;4562.58936695699;6647.135872336566;6738.427233685192;6842.93157068423;6895.689693963684;6901.654917254017;6665.266820649822;6693.995900614947;6698.665066746546;6704.530682852153;6716.093327400738;6590.736370545124;6602.10871532337;6609.801705948829;6639.966800165968;6642.368974947372;6366.681665916778;6458.644774537445;6468.062130991074;6485.156929599122;6496.70764815575;6532.8366790830605;6580.5722628029025;6640.103586015826;6645.169353830771;6650.068990341453;6449.322821103717;6493.090967264492;6517.4460170886005;6522.739090436519;6536.869262614816 22:19:50 INFO - 2015-10-23 22:19:50,857 DEBUG : BROWSER_OUTPUT: |5;yui.html;725.9201487836341;726.440640706656;729.9744508942188;733.7651951375559;745.4632664481574;11822.467988940496;12068.51725930964;12099.758004839898;12121.151519393647;12144;10656.78686426271;10805.027547520722;10929.945350273238;10936.234463587552;11044.502997365118;596.9432903874136;602.105872778923;604.0425924909024;604.3110853626869;604.6644112517552;575.1774961804617;598.291025135214;602.3073465514649;602.3163709190079;603.297158809988;588.5968111843401;623.1400667079424;626.9686515674198;629.0815409502133;629.433509841142;618.0883197283993;621.4903778901325;621.9129321894939;624.7157543317779;625.8247690646626;456.1242414564045;456.3861606139737;456.9680122391434;457.2683706070294;458.5987261146497;11228.719282017933;11341.092712582973;11357.432128393548;11360.545578176863;11367.147463940155;11282.492287847066;11650.30098194111;11671.92116273071;11747.765044699143;12329.876701232963;9847.113759761656;10167.4916254187;10216.13162881156;10231.744206394826;10307.33002354852;3173.222560472676;3174.68253174683;3291.8683252669866;3293.324868401972;3506.1935754776546;44424;44787.552124478665;44882;44968.550314496766;45627.771861140485;7142.428605711634;7191.532550384209;7229.457790665721;7230.963845180846;7266.200717921024;11123.833142502868;11239.325640461599;11270;11278.887211127947;11289.774204516028;6353.936460635427;6449.097126402307;6675.332466753286;6678.098456708282;6742.292059333798;4001.2997725398523;4056.14820887611;4067.5525692173837;4080.2451546464;4185.958140418617;2532.328932832801;2553.438243586408;2559.1426871998087;2560.2319304208663;2566.974330256711;428.1977620980716;433.83731100837184;434.611023134293;438.82447021191143;439.9296112621965;585.2863580581205;654.9050387693758;655.2399216908364;711.2602892991231;720.5352547606765;9072.637094516278;9112.544372781334;9203.35576509638;9270.582823772946;9504.192143667728;565.2453973944747;610.7892776991931;664.3290276820446;676.1109141479038;680.0988689985788;4114.897127571775;4554.795034223468;4594.333821595917;4666.440027196758;4721.3390125382475;58254.417455825744;58385.41614583885;58426;58462.41537584655;58528;49453;50439.243411348856;50679;50692.49307506951;50733;39646.40530392047;40310.79844600758;40401.455476028605;41773.582264177574;41784.79107604503;9162.4696140042;9253.72238832836;9348.95325523368;9379.484128373006;9585.376950498196;29038.419231615673;29251.561226582045;29257.122286331443;29262;29610;39767.00582485461;40004.59995400125;40228.19543609053;40322.1935561293;40494.392584110676;7922.168172342052;8038.718644847402;8312.963323985421;8315.625796839036;8659.13408659129;12994.285314307805;13142.60572182836;13175.472981081033;13256.602301930958;13277.070605057554;12285;12559.434825432694;12654.683632909251;12752.171108877894;12972.027097967879;11343.48954297075;11416.885831141915;11500.48247828833;11506.079513639057;11679.883201168219;12052.758944820878;12136;12569.685757855761;12699.301538415106;12804;9825.615402155248;10179.847302290324;10344.741381465523;10470.319429237075;10685.679429617125;10122.493875306058;10162.695119146438;10202.795944080928;10242.590296388062;10961.177911656818 22:19:50 INFO - 2015-10-23 22:19:50,858 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:19:50 INFO - 2015-10-23 22:19:50,858 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:19:50 INFO - 2015-10-23 22:19:50,858 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,279 22:19:50 INFO - 2015-10-23 22:19:50,858 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:19:50 INFO - 2015-10-23 22:19:50,858 DEBUG : BROWSER_OUTPUT: __startTimestamp1445663990748__endTimestamp 22:19:50 INFO - 2015-10-23 22:19:50,858 DEBUG : BROWSER_OUTPUT: 22:19:50 INFO - 2015-10-23 22:19:50,858 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:19:50 INFO - 2015-10-23 22:19:50,858 DEBUG : BROWSER_OUTPUT: Number of tests: 6 22:19:50 INFO - 2015-10-23 22:19:50,858 DEBUG : BROWSER_OUTPUT: 22:19:50 INFO - 2015-10-23 22:19:50,858 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5448.02 Median:5817.19 stddev:925.07 (15.9%) stddev-sans-first:905.32 22:19:50 INFO - 2015-10-23 22:19:50,858 DEBUG : BROWSER_OUTPUT: Values: 2858.0 2915.6 2931.2 2932.3 2948.3 5512.8 5620.1 5714.2 5784.0 5790.1 5608.0 5651.5 5698.7 5793.2 5814.3 4705.1 4752.4 4784.9 4878.7 4891.2 1051.3 4398.0 4757.9 4793.3 4899.0 4744.6 4792.6 4794.1 4817.6 4835.0 4492.7 4697.5 4832.9 4889.6 4896.3 4632.9 4712.0 4713.3 4717.4 4739.0 5547.1 5581.2 5640.3 5640.5 5655.7 5891.9 5941.6 5947.0 5972.7 5989.9 6018.4 6047.5 6049.0 6068.1 6104.1 5390.1 5452.2 5516.1 5534.9 5629.3 6056.2 6129.8 6136.9 6189.4 6264.6 5814.1 5866.3 5873.1 5982.9 5990.2 5893.3 5938.1 6090.3 6104.0 6112.1 5823.6 5853.9 5886.7 5956.4 5957.6 5884.2 5943.4 5987.4 6009.7 6037.5 3541.3 3559.0 3586.3 3662.4 3664.9 3250.5 3260.2 3275.8 3278.4 3346.9 5732.1 5768.2 5796.7 5823.8 5839.0 5881.4 5924.9 5992.3 6076.3 6169.8 5627.4 5671.5 5733.2 5750.6 5760.6 4578.9 4649.4 4699.9 4744.8 4786.9 6203.5 6322.4 6380.6 6516.3 6526.0 5835.1 5994.8 6014.1 6089.7 6151.4 6288.5 6385.7 6422.3 6498.3 6524.6 5756.2 5851.1 5953.9 5954.7 5979.6 6110.1 6121.5 6177.2 6196.5 6232.7 5876.8 5960.3 5961.6 5987.1 6083.1 5679.4 5745.0 5817.5 5826.2 5834.5 5660.7 5724.8 5768.8 5774.7 5795.9 5817.7 5945.0 6027.6 6040.3 6058.9 5817.2 5839.9 5881.3 5938.8 5948.5 22:19:50 INFO - 2015-10-23 22:19:50,858 DEBUG : BROWSER_OUTPUT: 22:19:50 INFO - 2015-10-23 22:19:50,858 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48305.98 Median:37302.62 stddev:90960.18 (243.8%) stddev-sans-first:91144.44 22:19:50 INFO - 2015-10-23 22:19:50,858 DEBUG : BROWSER_OUTPUT: Values: 471.1 476.9 496.3 496.4 514.1 36675.6 37034.0 38292.8 39355.8 39687.2 38191.0 38588.0 38655.8 39287.6 39767.4 8157.8 8231.7 8339.1 8573.4 8692.6 7997.4 8107.3 8144.6 8205.4 8218.2 8105.6 8302.8 8718.8 8737.3 8743.1 8006.8 8079.5 8112.2 8176.0 8565.5 8061.5 8248.7 8492.0 8590.4 8591.8 36051.3 38168.8 38378.8 38735.2 38800.4 2603.5 2994.2 3093.8 3114.3 3431.5 28634.0 28649.9 28823.0 29309.7 29787.4 2651.8 2667.5 2681.0 2722.1 2728.6 270179.0 270719.0 271823.0 272376.0 272569.6 41910.6 43714.3 46210.0 48573.0 49869.3 51196.5 51351.0 51917.5 52221.2 52375.2 11969.7 12480.1 12615.4 12698.7 12728.2 9488.7 9502.6 9523.3 9720.7 9921.3 16033.8 16391.3 16778.0 17150.3 17207.0 6936.0 7067.9 7139.0 7146.2 7289.8 808.9 965.3 983.5 1021.6 1050.3 25070.7 25245.2 25956.1 26287.2 26304.7 891.6 942.0 960.1 969.1 1032.6 13941.8 14228.4 14341.8 14459.3 14529.6 383484.0 392762.0 393315.0 395114.0 397191.0 46640.3 47883.0 47883.8 48182.3 48694.8 1064.1 1129.3 1143.9 1151.3 1185.1 26783.3 26857.9 26892.5 26923.9 27050.9 39116.4 40600.8 42717.4 43118.1 43340.1 354864.0 355516.0 360407.0 360793.0 362087.0 26000.9 26576.2 26766.9 27626.6 27677.3 37571.2 38126.0 38561.8 38933.8 40037.2 32595.4 33143.7 33570.7 34052.7 34543.3 33640.5 34087.5 34194.7 34309.1 34384.5 33750.7 33806.7 34043.6 35040.1 35108.5 29313.4 29397.6 30545.4 30589.7 32346.4 22484.3 22488.9 22710.7 23036.8 23122.4 22:19:50 INFO - 2015-10-23 22:19:50,859 DEBUG : BROWSER_OUTPUT: 22:19:50 INFO - 2015-10-23 22:19:50,859 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25957.75 Median:216255.35 stddev:73059.19 (33.8%) stddev-sans-first:73238.78 22:19:50 INFO - 2015-10-23 22:19:50,859 DEBUG : BROWSER_OUTPUT: Values: 320.2 321.1 322.4 326.8 332.1 9861.9 10372.7 16665.0 16674.3 17455.7 10945.1 11099.2 11124.4 11155.7 11410.2 4271.4 4287.7 4310.0 4426.6 4560.4 4257.2 4470.9 4534.4 4572.7 4819.6 4458.2 4484.4 4516.9 4727.0 4750.5 4450.0 4728.0 6315.9 6631.1 6787.3 5807.2 6045.5 6083.5 6292.3 6447.7 22081.9 22136.4 22219.7 23456.3 23655.5 2058.2 2122.2 2241.5 2343.2 2362.1 14322.9 14427.2 14437.6 14490.9 15138.2 1693.6 1737.0 1759.3 1811.7 1826.5 72428.6 76499.0 78348.2 79183.6 81335.6 14436.9 14660.7 14810.0 15473.2 15549.1 20662.0 20816.8 21872.3 22004.7 22010.3 8149.1 8229.3 8457.6 8469.1 8540.3 6801.4 6891.3 6893.0 6907.3 6913.1 6548.2 6606.0 6804.4 6975.7 7016.8 979.4 985.5 1047.5 2609.7 2660.3 420.6 427.6 437.3 443.3 448.8 11734.8 11844.2 11974.4 12076.4 12223.1 437.6 441.1 454.5 456.6 462.2 6233.4 6311.4 6378.7 6508.2 6660.4 416204.0 428223.0 445920.0 459929.0 463016.0 45894.0 78811.2 81392.0 82151.6 82712.0 467.3 473.5 474.5 483.4 494.5 9914.6 10096.6 10118.5 10381.3 10440.2 38461.8 39026.8 39895.8 40300.8 41569.2 50990.7 51903.7 52561.7 52992.5 54363.0 5813.6 5886.6 6108.8 6123.4 6263.4 11117.8 11145.8 11577.8 11826.0 12243.6 9968.8 10044.1 10183.8 10399.9 10671.9 10170.3 10295.9 10409.1 10535.2 10673.5 10369.0 10377.4 10640.4 10665.2 10715.2 5944.9 5955.3 5980.4 6230.3 6280.6 10007.6 10213.3 10231.2 10381.8 10583.5 22:19:50 INFO - 2015-10-23 22:19:50,861 DEBUG : BROWSER_OUTPUT: 22:19:50 INFO - 2015-10-23 22:19:50,861 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:6098.00 Median:5144.17 stddev:3488.94 (67.8%) stddev-sans-first:3479.39 22:19:50 INFO - 2015-10-23 22:19:50,862 DEBUG : BROWSER_OUTPUT: Values: 1210.9 1255.7 1427.7 1560.8 1564.0 8821.2 9134.2 9252.7 9279.2 9353.7 9348.4 9385.8 9392.4 9426.3 9432.0 2150.4 2224.6 2228.7 2242.1 2242.4 2224.8 2230.5 2238.3 2239.9 2253.5 2193.1 2227.3 2253.5 2254.7 2256.5 2208.9 2232.7 2269.6 2270.9 2278.7 2229.6 2232.4 2235.8 2244.9 2248.5 8995.1 8996.1 9058.8 9081.5 9088.6 5080.5 5130.2 5143.5 5144.9 5150.2 3273.7 3320.9 3361.1 3368.7 3374.5 994.5 1032.4 1034.8 1045.0 1046.8 12950.5 12955.2 13016.9 13017.3 13022.3 4203.3 4351.1 4399.9 4430.3 4435.8 10077.0 10112.7 10120.5 10125.0 10132.5 4949.0 4949.2 4956.9 4962.8 4964.0 4480.1 4563.4 4567.0 4572.5 4575.9 3453.9 3478.7 3488.7 3501.1 3509.8 996.2 1011.3 1011.4 1021.1 1025.0 930.7 939.1 939.8 940.8 967.0 7915.7 7922.5 7962.8 8007.8 8009.5 916.7 942.1 944.1 963.7 963.8 5719.2 5759.7 5782.3 5789.3 5803.7 11235.6 11284.8 11415.7 11426.9 11438.9 11380.3 11510.7 11534.7 11554.9 11559.5 8166.1 8277.4 8305.6 8355.7 8359.9 7272.0 7440.6 7641.8 7685.0 7698.1 9608.3 9805.2 9875.5 9878.0 9994.7 10071.7 10243.4 10291.8 10293.8 10304.0 7437.6 7637.4 7641.3 7669.0 7684.8 8741.7 8801.0 8802.8 8816.3 8821.7 8087.2 8114.6 8189.0 8224.1 8276.3 7774.0 7863.3 7890.1 8096.4 8136.9 7611.5 7677.3 7735.5 7744.7 7753.4 6856.5 6870.5 6885.9 6903.8 6920.1 6637.7 6832.8 6855.1 6859.3 6892.3 22:19:50 INFO - 2015-10-23 22:19:50,862 DEBUG : BROWSER_OUTPUT: 22:19:50 INFO - 2015-10-23 22:19:50,864 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5449.53 Median:6313.35 stddev:1726.71 (27.4%) stddev-sans-first:1687.95 22:19:50 INFO - 2015-10-23 22:19:50,864 DEBUG : BROWSER_OUTPUT: Values: 311.8 317.4 318.5 320.8 323.1 6115.1 6254.2 6264.9 6268.5 6316.1 6146.6 6237.9 6286.0 6288.4 6288.5 4426.9 4429.4 4433.2 4437.6 4440.3 4422.2 4427.6 4434.2 4434.5 4435.1 4306.7 4318.8 4358.8 4377.1 4382.8 4298.7 4337.0 4348.7 4352.5 4353.0 3707.2 3765.5 3767.9 3770.6 3773.4 5883.1 5897.9 5898.2 5907.0 5914.7 5175.8 5206.4 5278.3 5352.5 5353.8 6394.8 6418.6 6483.9 6487.4 6509.5 4314.3 4337.0 4355.2 4362.0 4368.5 6572.1 6752.2 6822.4 6834.8 6839.7 4414.2 4468.8 4472.0 4474.6 4484.3 4537.1 4565.4 4578.2 4579.4 4593.5 5199.8 5308.5 5310.8 5346.9 5363.7 6402.8 6548.3 6554.5 6555.2 6559.4 1611.7 1621.9 1626.3 1626.6 1626.7 1044.8 1067.6 1072.9 1077.4 1094.0 6214.3 6310.7 6340.2 6361.4 6410.3 6534.4 6822.6 6846.8 6860.8 6867.9 6365.1 6400.2 6415.3 6415.7 6419.6 3745.6 3784.5 3801.3 3808.0 3810.4 7147.3 7161.9 7220.7 7223.6 7225.9 6891.1 6906.9 6909.7 6909.9 6914.7 7191.9 7208.5 7217.4 7218.2 7228.4 6637.2 6863.9 6913.4 6923.1 6937.7 6690.3 6717.2 6738.7 6778.7 6796.0 7310.7 7311.1 7323.6 7332.3 7340.2 4423.5 4547.6 4556.9 4561.2 4562.6 6647.1 6738.4 6842.9 6895.7 6901.7 6665.3 6694.0 6698.7 6704.5 6716.1 6590.7 6602.1 6609.8 6640.0 6642.4 6366.7 6458.6 6468.1 6485.2 6496.7 6532.8 6580.6 6640.1 6645.2 6650.1 6449.3 6493.1 6517.4 6522.7 6536.9 22:19:50 INFO - 2015-10-23 22:19:50,865 DEBUG : BROWSER_OUTPUT: 22:19:50 INFO - 2015-10-23 22:19:50,865 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12838.96 Median:434.22 stddev:15096.74 (3476.7%) stddev-sans-first:15111.68 22:19:50 INFO - 2015-10-23 22:19:50,865 DEBUG : BROWSER_OUTPUT: Values: 725.9 726.4 730.0 733.8 745.5 11822.5 12068.5 12099.8 12121.2 12144.0 10656.8 10805.0 10929.9 10936.2 11044.5 596.9 602.1 604.0 604.3 604.7 575.2 598.3 602.3 602.3 603.3 588.6 623.1 627.0 629.1 629.4 618.1 621.5 621.9 624.7 625.8 456.1 456.4 457.0 457.3 458.6 11228.7 11341.1 11357.4 11360.5 11367.1 11282.5 11650.3 11671.9 11747.8 12329.9 9847.1 10167.5 10216.1 10231.7 10307.3 3173.2 3174.7 3291.9 3293.3 3506.2 44424.0 44787.6 44882.0 44968.6 45627.8 7142.4 7191.5 7229.5 7231.0 7266.2 11123.8 11239.3 11270.0 11278.9 11289.8 6353.9 6449.1 6675.3 6678.1 6742.3 4001.3 4056.1 4067.6 4080.2 4186.0 2532.3 2553.4 2559.1 2560.2 2567.0 428.2 433.8 434.6 438.8 439.9 585.3 654.9 655.2 711.3 720.5 9072.6 9112.5 9203.4 9270.6 9504.2 565.2 610.8 664.3 676.1 680.1 4114.9 4554.8 4594.3 4666.4 4721.3 58254.4 58385.4 58426.0 58462.4 58528.0 49453.0 50439.2 50679.0 50692.5 50733.0 39646.4 40310.8 40401.5 41773.6 41784.8 9162.5 9253.7 9349.0 9379.5 9585.4 29038.4 29251.6 29257.1 29262.0 29610.0 39767.0 40004.6 40228.2 40322.2 40494.4 7922.2 8038.7 8313.0 8315.6 8659.1 12994.3 13142.6 13175.5 13256.6 13277.1 12285.0 12559.4 12654.7 12752.2 12972.0 11343.5 11416.9 11500.5 11506.1 11679.9 12052.8 12136.0 12569.7 12699.3 12804.0 9825.6 10179.8 10344.7 10470.3 10685.7 10122.5 10162.7 10202.8 10242.6 10961.2 22:19:50 INFO - 2015-10-23 22:19:50,865 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:19:50 INFO - 2015-10-23 22:19:50,865 DEBUG : BROWSER_OUTPUT: 22:19:50 INFO - 2015-10-23 22:19:50,953 DEBUG : BROWSER_OUTPUT: [Child 3760] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 333 22:19:51 INFO - 2015-10-23 22:19:51,295 INFO : Browser exited with error code: 0 22:19:52 INFO - 2015-10-23 22:19:52,029 INFO : Completed test dromaeo_css (00:18:33) 22:19:52 INFO - 2015-10-23 22:19:52,029 INFO : Starting test kraken 22:19:52 INFO - 2015-10-23 22:19:52,029 DEBUG : operating with platform_type : w7_ 22:19:52 INFO - 2015-10-23 22:19:52,029 INFO : Initialising browser for kraken test... 22:19:52 INFO - 2015-10-23 22:19:52,053 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpaboamu\profile http://localhost:49728/getInfo.html 22:19:56 INFO - 2015-10-23 22:19:56,926 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:19:56 INFO - 2015-10-23 22:19:56,926 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:19:56 INFO - 2015-10-23 22:19:56,926 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 22:19:56 INFO - 2015-10-23 22:19:56,926 DEBUG : BROWSER_OUTPUT: __metrics 22:19:57 INFO - 2015-10-23 22:19:57,233 DEBUG : BROWSER_OUTPUT: console.error: 22:19:57 INFO - 2015-10-23 22:19:57,236 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:19:57 INFO - 2015-10-23 22:19:57,236 DEBUG : BROWSER_OUTPUT: Stack: 22:19:57 INFO - 2015-10-23 22:19:57,236 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 22:19:57 INFO - 2015-10-23 22:19:57,236 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 22:19:57 INFO - 2015-10-23 22:19:57,236 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 22:19:57 INFO - 2015-10-23 22:19:57,236 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 22:19:57 INFO - 2015-10-23 22:19:57,236 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 22:19:57 INFO - 2015-10-23 22:19:57,236 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 22:19:57 INFO - 2015-10-23 22:19:57,236 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 22:19:57 INFO - 2015-10-23 22:19:57,236 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:791:13 22:19:57 INFO - 2015-10-23 22:19:57,236 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 22:19:57 INFO - 2015-10-23 22:19:57,236 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 22:19:57 INFO - 2015-10-23 22:19:57,236 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 22:19:57 INFO - 2015-10-23 22:19:57,236 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 22:19:57 INFO - 2015-10-23 22:19:57,236 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6731.46 22:21:33 INFO - RETURN: kraken: 1632.10 22:21:33 INFO - RETURN: v8_7: 20438.30 22:21:33 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,47]]", "result": "20438.30"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,47]]", "result": "1632.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,47]]", "result": "6731.46"}}} 22:21:33 INFO - Return code: 0 22:21:33 INFO - # TBPL SUCCESS # 22:21:33 INFO - Running post-action listener: _resource_record_post_action 22:21:33 INFO - Running post-run listener: _resource_record_post_run 22:21:34 INFO - Total resource usage - Wall time: 1217s; CPU: 13.0%; Read bytes: 113145856; Write bytes: 544758272; Read time: 1758960; Write time: 5211870 22:21:34 INFO - install - Wall time: 2s; CPU: 14.0%; Read bytes: 0; Write bytes: 16384; Read time: 0; Write time: 60 22:21:34 INFO - run-tests - Wall time: 1216s; CPU: 13.0%; Read bytes: 113145856; Write bytes: 520229376; Read time: 1758960; Write time: 4933490 22:21:34 INFO - Running post-run listener: _upload_blobber_files 22:21:34 INFO - Blob upload gear active. 22:21:34 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 22:21:34 INFO - Copying logs to upload dir... 22:21:34 INFO - mkdir: C:\slave\test-pgo\build\upload\logs program finished with exit code 0 elapsedTime=1516.451000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 17 secs) (at 2015-10-23 22:21:34.775716) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-23 22:21:34.779825) ========= '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-092 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-092 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-092 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/pJSsAYqpSIWqbQMjg25yQw/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/pJSsAYqpSIWqbQMjg25yQw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-23 22:21:34.896916) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 22:21:34.897260) ========= '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-092 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-092 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-092 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: 0 secs) (at 2015-10-23 22:21:35.012110) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-10-23 22:21:35.012447) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-10-23 22:21:35.516479) =========