builder: fx-team_win7-ix_test-dromaeojs-pgo slave: t-w732-ix-124 starttime: 1445521864.57 results: success (0) buildid: 20151021233015 builduid: 5ebbfb7bf99c461dbaf48f43d2226577 revision: 646c308ad3bc4ccfea01deaf32a6927cf82be3f7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-22 06:51:04.570369) ========= master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-22 06:51:04.572757) ========= ========= Started set props: basedir (results: 0, elapsed: 1 secs) (at 2015-10-22 06:51:04.573277) ========= '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-124 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-124 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-124 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.646000 basedir: 'C:\\slave\\test-pgo' ========= Finished set props: basedir (results: 0, elapsed: 1 secs) (at 2015-10-22 06:51:05.603510) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2015-10-22 06:51:05.603867) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2015-10-22 06:51:07.086759) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-22 06:51:07.087101) ========= '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-124 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-124 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-124 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-22 06:51:07.727601) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-22 06:51:07.728048) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-22 06:51:07.728551) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2015-10-22 06:51:07.728929) ========= '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-124 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-124 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-124 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 --06:51:08-- 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.27 GB/s 06:51:09 (2.27 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.484000 ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2015-10-22 06:51:11.131899) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2015-10-22 06:51:11.132221) ========= '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-124 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-124 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-124 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=11.501000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2015-10-22 06:51:22.806655) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2015-10-22 06:51:22.807035) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 646c308ad3bc4ccfea01deaf32a6927cf82be3f7 --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 646c308ad3bc4ccfea01deaf32a6927cf82be3f7 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-124 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-124 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-124 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-22 06:51:23,869 truncating revision to first 12 chars 2015-10-22 06:51:23,871 Setting DEBUG logging. 2015-10-22 06:51:23,871 attempt 1/10 2015-10-22 06:51:23,871 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/646c308ad3bc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-22 06:51:26,128 unpacking tar archive at: fx-team-646c308ad3bc/testing/mozharness/ program finished with exit code 0 elapsedTime=3.993000 ========= Finished 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2015-10-22 06:51:35.962392) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-22 06:51:35.962749) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-22 06:51:36.796273) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-22 06:51:36.796565) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-22 06:51:36.796979) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 26 mins, 8 secs) (at 2015-10-22 06:51:36.797267) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--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', '--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-124 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-124 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-124 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 06:51:37 INFO - MultiFileLogger online at 20151022 06:51:37 in C:\slave\test-pgo 06:51:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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 06:51:37 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 06:51:37 INFO - {'append_to_log': False, 06:51:37 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 06:51:37 INFO - 'blob_upload_branch': 'Fx-Team', 06:51:37 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 06:51:37 INFO - 'branch': 'Fx-Team', 06:51:37 INFO - 'buildbot_json_path': 'buildprops.json', 06:51:37 INFO - 'config_files': ('talos/windows_config.py',), 06:51:37 INFO - 'default_actions': ('clobber', 06:51:37 INFO - 'read-buildbot-config', 06:51:37 INFO - 'download-and-extract', 06:51:37 INFO - 'populate-webroot', 06:51:37 INFO - 'create-virtualenv', 06:51:37 INFO - 'install', 06:51:37 INFO - 'run-tests'), 06:51:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:51:37 INFO - 'download_minidump_stackwalk': True, 06:51:37 INFO - 'download_symbols': 'ondemand', 06:51:37 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 06:51:37 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 06:51:37 INFO - 'hg': 'c:/mozilla-build/hg/hg', 06:51:37 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 06:51:37 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 06:51:37 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 06:51:37 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 06:51:37 INFO - 'C:/mozilla-build/tooltool.py'), 06:51:37 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 06:51:37 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 06:51:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:51:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:51:37 INFO - 'installer_path': 'installer.exe', 06:51:37 INFO - 'log_level': 'info', 06:51:37 INFO - 'log_name': 'talos', 06:51:37 INFO - 'log_to_console': True, 06:51:37 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 06:51:37 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 06:51:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 06:51:37 INFO - 'opt_config_files': (), 06:51:37 INFO - 'pip_index': False, 06:51:37 INFO - 'sps_profile': False, 06:51:37 INFO - 'sps_profile_interval': 0, 06:51:37 INFO - 'suite': 'dromaeojs', 06:51:37 INFO - 'system_bits': '32', 06:51:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:51:37 INFO - 'title': 'T-W732-IX-124', 06:51:37 INFO - 'use_talos_json': True, 06:51:37 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 06:51:37 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 06:51:37 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 06:51:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:51:37 INFO - 'work_dir': 'build'} 06:51:37 INFO - ##### 06:51:37 INFO - ##### Running clobber step. 06:51:37 INFO - ##### 06:51:37 INFO - Running pre-action listener: _resource_record_pre_action 06:51:37 INFO - Running main action method: clobber 06:51:37 INFO - rmtree: C:\slave\test-pgo\build 06:51:37 INFO - Using _rmtree_windows ... 06:51:37 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 06:54:51 INFO - Running post-action listener: _resource_record_post_action 06:54:51 INFO - ##### 06:54:51 INFO - ##### Running read-buildbot-config step. 06:54:51 INFO - ##### 06:54:51 INFO - Running pre-action listener: _resource_record_pre_action 06:54:51 INFO - Running main action method: read_buildbot_config 06:54:51 INFO - Using buildbot properties: 06:54:51 INFO - { 06:54:51 INFO - "properties": { 06:54:51 INFO - "buildnumber": 52, 06:54:51 INFO - "product": "firefox", 06:54:51 INFO - "script_repo_revision": "production", 06:54:51 INFO - "builddir": "fx-team_win7-ix_test-dromaeojs", 06:54:51 INFO - "repository": "", 06:54:51 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs", 06:54:51 INFO - "buildid": "20151021233015", 06:54:51 INFO - "slavename": "t-w732-ix-124", 06:54:51 INFO - "pgo_build": "True", 06:54:51 INFO - "basedir": "C:\\slave\\test-pgo", 06:54:51 INFO - "project": "", 06:54:51 INFO - "platform": "win7-ix", 06:54:51 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/", 06:54:51 INFO - "slavebuilddir": "test", 06:54:51 INFO - "scheduler": "tests-fx-team-win32-pgo-talos", 06:54:51 INFO - "branch": "fx-team", 06:54:51 INFO - "repo_path": "integration/fx-team", 06:54:51 INFO - "stage_platform": "win32-pgo", 06:54:51 INFO - "builduid": "5ebbfb7bf99c461dbaf48f43d2226577", 06:54:51 INFO - "revision": "646c308ad3bc4ccfea01deaf32a6927cf82be3f7" 06:54:51 INFO - }, 06:54:51 INFO - "sourcestamp": { 06:54:51 INFO - "repository": "", 06:54:51 INFO - "hasPatch": false, 06:54:51 INFO - "project": "", 06:54:51 INFO - "branch": "fx-team-win32-pgo-talos", 06:54:51 INFO - "changes": [ 06:54:51 INFO - { 06:54:51 INFO - "category": null, 06:54:51 INFO - "files": [ 06:54:51 INFO - { 06:54:51 INFO - "url": null, 06:54:51 INFO - "name": "https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 06:54:51 INFO - } 06:54:51 INFO - ], 06:54:51 INFO - "repository": "", 06:54:51 INFO - "rev": "646c308ad3bc4ccfea01deaf32a6927cf82be3f7", 06:54:51 INFO - "who": "sendchange", 06:54:51 INFO - "when": 1445520701, 06:54:51 INFO - "number": 6559682, 06:54:51 INFO - "comments": "", 06:54:51 INFO - "project": "", 06:54:51 INFO - "at": "Thu 22 Oct 2015 06:31:41", 06:54:51 INFO - "branch": "fx-team-win32-pgo-talos", 06:54:51 INFO - "revlink": "", 06:54:51 INFO - "properties": [ 06:54:51 INFO - [ 06:54:51 INFO - "buildid", 06:54:51 INFO - "20151021233015", 06:54:51 INFO - "Change" 06:54:51 INFO - ], 06:54:51 INFO - [ 06:54:51 INFO - "builduid", 06:54:51 INFO - "5ebbfb7bf99c461dbaf48f43d2226577", 06:54:51 INFO - "Change" 06:54:51 INFO - ], 06:54:51 INFO - [ 06:54:51 INFO - "pgo_build", 06:54:51 INFO - "True", 06:54:51 INFO - "Change" 06:54:51 INFO - ] 06:54:51 INFO - ], 06:54:51 INFO - "revision": "646c308ad3bc4ccfea01deaf32a6927cf82be3f7" 06:54:51 INFO - } 06:54:51 INFO - ], 06:54:51 INFO - "revision": "646c308ad3bc4ccfea01deaf32a6927cf82be3f7" 06:54:51 INFO - } 06:54:51 INFO - } 06:54:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 06:54:51 INFO - Running post-action listener: _resource_record_post_action 06:54:51 INFO - ##### 06:54:51 INFO - ##### Running download-and-extract step. 06:54:51 INFO - ##### 06:54:51 INFO - Running pre-action listener: _resource_record_pre_action 06:54:51 INFO - Running main action method: download_and_extract 06:54:51 INFO - mkdir: C:\slave\test-pgo\build\tests 06:54: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')]} 06:54:51 INFO - https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 06:54:51 INFO - trying https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/test_packages.json 06:54:51 INFO - Downloading https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 06:54:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 06:54:52 INFO - Downloaded 1221 bytes. 06:54:52 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 06:54:52 INFO - Using the following test package requirements: 06:54:52 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 06:54:52 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 06:54:52 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 06:54:52 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 06:54:52 INFO - u'jsshell-win32.zip'], 06:54:52 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 06:54:52 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 06:54:52 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 06:54:52 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 06:54:52 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 06:54:52 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 06:54:52 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 06:54:52 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 06:54:52 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 06:54:52 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 06:54:52 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 06:54:52 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 06:54:52 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 06:54:52 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')]} 06:54:52 INFO - https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 06:54:52 INFO - trying https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 06:54:52 INFO - Downloading https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/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 06:54:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/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 06:54:55 INFO - Downloaded 18006809 bytes. 06:54:55 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 06:54:55 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 06:54:55 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 06:55:25 INFO - Return code: 0 06:55:25 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 06:55: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')]} 06:55:25 INFO - https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 06:55:25 INFO - trying https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 06:55:25 INFO - Downloading https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/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 06:55:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/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 06:55:27 INFO - Downloaded 18006809 bytes. 06:55:27 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 06:55:27 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 06:55:27 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 06:55:50 INFO - Return code: 0 06:55: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')]} 06:55:50 INFO - https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 06:55:50 INFO - trying https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 06:55:50 INFO - Downloading https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/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 06:55:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/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 06:55:52 INFO - Downloaded 11128611 bytes. 06:55:52 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 06:55:52 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip 06:55:52 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 06:55:53 INFO - Return code: 0 06:55:53 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')]} 06:55:53 INFO - https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 06:55:53 INFO - trying https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 06:55:53 INFO - Downloading https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip 06:55:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/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 06:55:55 INFO - Downloaded 53527980 bytes. 06:55:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 06:55:55 INFO - mkdir: C:\slave\test-pgo\properties 06:55:55 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 06:55:55 INFO - Writing to file C:\slave\test-pgo\properties\build_url 06:55:55 INFO - Contents: 06:55:55 INFO - build_url:https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 06:55:55 INFO - Running post-action listener: _resource_record_post_action 06:55:55 INFO - Running post-action listener: set_extra_try_arguments 06:55:55 INFO - ##### 06:55:55 INFO - ##### Running populate-webroot step. 06:55:55 INFO - ##### 06:55:55 INFO - Running pre-action listener: _resource_record_pre_action 06:55:55 INFO - Running main action method: populate_webroot 06:55:55 INFO - Guessing talos json url... 06:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:55:55 INFO - https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 06:55:55 INFO - trying https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 06:55:55 INFO - Downloading https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 06:55:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/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 06:55:56 INFO - Downloaded 103 bytes. 06:55:56 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 06:55:56 INFO - Contents: 06:55:56 INFO - 20151021233015 06:55:56 INFO - https://hg.mozilla.org/integration/fx-team/rev/646c308ad3bc4ccfea01deaf32a6927cf82be3f7 06:55: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')]} 06:55:56 INFO - trying https://hg.mozilla.org/integration/fx-team/raw-file/646c308ad3/testing/talos/talos.json 06:55:56 INFO - Downloading https://hg.mozilla.org/integration/fx-team/raw-file/646c308ad3/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json 06:55:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/fx-team/raw-file/646c308ad3/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1 06:55:56 INFO - Downloaded 4264 bytes. 06:55:56 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 06:55:56 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 06:55:56 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 06:55:56 INFO - u'../fennec_ids.txt'], 06:55:56 INFO - u'tests': [u'tcheck2']}, 06:55:56 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 06:55:56 INFO - u'--tppagecycles', 06:55:56 INFO - u'7'], 06:55:56 INFO - u'tests': [u'tsvgm']}}, 06:55:56 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 06:55:56 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 06:55:56 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 06:55:56 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 06:55:56 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 06:55:56 INFO - u'tests': [u'dromaeo_css', 06:55:56 INFO - u'kraken', 06:55:56 INFO - u'v8_7']}, 06:55:56 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:55:56 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 06:55:56 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:55:56 INFO - u'talos_options': [u'--e10s'], 06:55:56 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 06:55:56 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:55:56 INFO - u'tests': [u'damp', u'tps']}, 06:55:56 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:55:56 INFO - u'talos_options': [u'--e10s'], 06:55:56 INFO - u'tests': [u'damp', u'tps']}, 06:55:56 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 06:55:56 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 06:55:56 INFO - u'other': {u'tests': [u'a11yr', 06:55:56 INFO - u'ts_paint', 06:55:56 INFO - u'tpaint', 06:55:56 INFO - u'sessionrestore', 06:55:56 INFO - u'sessionrestore_no_auto_restore']}, 06:55:56 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 06:55:56 INFO - u'tests': [u'a11yr', 06:55:56 INFO - u'ts_paint', 06:55:56 INFO - u'tpaint', 06:55:56 INFO - u'sessionrestore', 06:55:56 INFO - u'sessionrestore_no_auto_restore']}, 06:55:56 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 06:55:56 INFO - u'tests': [u'a11yr', 06:55:56 INFO - u'ts_paint', 06:55:56 INFO - u'tpaint', 06:55:56 INFO - u'sessionrestore', 06:55:56 INFO - u'sessionrestore_no_auto_restore']}, 06:55:56 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 06:55:56 INFO - u'tests': [u'a11yr', 06:55:56 INFO - u'ts_paint', 06:55:56 INFO - u'tpaint', 06:55:56 INFO - u'sessionrestore', 06:55:56 INFO - u'sessionrestore_no_auto_restore']}, 06:55:56 INFO - u'other_l64': {u'tests': [u'a11yr', 06:55:56 INFO - u'ts_paint', 06:55:56 INFO - u'tpaint', 06:55:56 INFO - u'sessionrestore', 06:55:56 INFO - u'sessionrestore_no_auto_restore']}, 06:55:56 INFO - u'other_nol64': {u'tests': [u'a11yr', 06:55:56 INFO - u'ts_paint', 06:55:56 INFO - u'tpaint', 06:55:56 INFO - u'sessionrestore', 06:55:56 INFO - u'sessionrestore_no_auto_restore']}, 06:55:56 INFO - u'svgr': {u'tests': [u'tsvgx', 06:55:56 INFO - u'tsvgr_opacity', 06:55:56 INFO - u'tart', 06:55:56 INFO - u'tscrollx', 06:55:56 INFO - u'cart']}, 06:55:56 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 06:55:56 INFO - u'tests': [u'tsvgx', 06:55:56 INFO - u'tsvgr_opacity', 06:55:56 INFO - u'tart', 06:55:56 INFO - u'tscrollx', 06:55:56 INFO - u'cart']}, 06:55:56 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:55:56 INFO - u'tests': [u'tp5o']}, 06:55:56 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:55:56 INFO - u'talos_options': [u'--e10s'], 06:55:56 INFO - u'tests': [u'tp5o']}, 06:55:56 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:55:56 INFO - u'talos_options': [u'--xperf_path', 06:55:56 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:55:56 INFO - u'tests': [u'tp5n']}, 06:55:56 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:55:56 INFO - u'talos_options': [u'--e10s', 06:55:56 INFO - u'--xperf_path', 06:55:56 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 06:55:56 INFO - u'C:/slave/talos-data/talos/xperf.config'], 06:55:56 INFO - u'tests': [u'tp5n']}}, 06:55:56 INFO - u'talos.zip': {u'path': u'', 06:55:56 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:55:56 INFO - Running post-action listener: _resource_record_post_action 06:55:56 INFO - ##### 06:55:56 INFO - ##### Running create-virtualenv step. 06:55:56 INFO - ##### 06:55:56 INFO - Running pre-action listener: _resource_record_pre_action 06:55:56 INFO - Running main action method: create_virtualenv 06:55:56 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 06:55:56 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 06:55:56 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 06:55:56 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'! 06:55:56 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 06:55:56 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 06:56:06 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 06:56:09 INFO - Installing distribute......................................................................................................................................................................................done. 06:56:11 INFO - Return code: 0 06:56:11 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 06:56:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:11 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01908B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x013AE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8B48>, '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 0x012EA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01989660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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 06:56:11 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test-pgo\build 06:56:11 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:56:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:56:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:56:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:56:11 INFO - 'COMPUTERNAME': 'T-W732-IX-124', 06:56:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:56:11 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:56:11 INFO - 'DCLOCATION': 'SCL3', 06:56:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:56:11 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:56:11 INFO - 'HOMEDRIVE': 'C:', 06:56:11 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:56:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:56:11 INFO - 'KTS_VERSION': '1.19c', 06:56:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:56:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124', 06:56:11 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:56:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:56:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:11 INFO - 'MOZ_NO_REMOTE': '1', 06:56:11 INFO - 'NO_EM_RESTART': '1', 06:56:11 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:56:11 INFO - 'OS': 'Windows_NT', 06:56:11 INFO - 'OURDRIVE': 'C:', 06:56:11 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:56:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:56:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:56:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:56:11 INFO - 'PROCESSOR_LEVEL': '6', 06:56:11 INFO - 'PROCESSOR_REVISION': '1e05', 06:56:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:56:11 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:56:11 INFO - 'PROMPT': '$P$G', 06:56:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 06:56:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:56:11 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:56:11 INFO - 'PWD': 'C:\\slave\\test-pgo', 06:56:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:56:11 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:56:11 INFO - 'SYSTEMDRIVE': 'C:', 06:56:11 INFO - 'SYSTEMROOT': 'C:\\windows', 06:56:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:56:11 INFO - 'TEST1': 'testie', 06:56:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:56:11 INFO - 'USERDOMAIN': 'T-W732-IX-124', 06:56:11 INFO - 'USERNAME': 'cltbld', 06:56:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:56:11 INFO - 'WINDIR': 'C:\\windows', 06:56:11 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:56:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:56:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:56:12 INFO - Ignoring indexes: http://pypi.python.org/simple/ 06:56:12 INFO - Downloading/unpacking pip>=1.5 06:56:12 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache 06:56:16 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 06:56:16 INFO - Running setup.py egg_info for package pip 06:56:16 INFO - warning: no files found matching 'pip\cacert.pem' 06:56:16 INFO - warning: no files found matching '*.html' under directory 'docs' 06:56:16 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 06:56:16 INFO - no previously-included directories found matching 'docs\_build\_sources' 06:56:16 INFO - Installing collected packages: pip 06:56:16 INFO - Found existing installation: pip 0.8.2 06:56:16 INFO - Uninstalling pip: 06:56:16 INFO - Successfully uninstalled pip 06:56:16 INFO - Running setup.py install for pip 06:56:16 INFO - warning: no files found matching 'pip\cacert.pem' 06:56:16 INFO - warning: no files found matching '*.html' under directory 'docs' 06:56:16 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 06:56:16 INFO - no previously-included directories found matching 'docs\_build\_sources' 06:56:16 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:16 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:16 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:16 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:16 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:16 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:16 INFO - Successfully installed pip 06:56:16 INFO - Cleaning up... 06:56:16 INFO - Return code: 0 06:56:16 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 06:56: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')]} 06:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56: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')]} 06:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:16 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--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 0x01908B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x013AE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8B48>, '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 0x012EA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01989660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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 06:56:16 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 06:56:16 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 06:56:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:56:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:56:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:56:16 INFO - 'COMPUTERNAME': 'T-W732-IX-124', 06:56:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:56:16 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:56:16 INFO - 'DCLOCATION': 'SCL3', 06:56:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:56:16 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:56:16 INFO - 'HOMEDRIVE': 'C:', 06:56:16 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:56:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:56:16 INFO - 'KTS_VERSION': '1.19c', 06:56:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:56:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124', 06:56:16 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:56:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:16 INFO - 'MOZ_NO_REMOTE': '1', 06:56:16 INFO - 'NO_EM_RESTART': '1', 06:56:16 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:56:16 INFO - 'OS': 'Windows_NT', 06:56:16 INFO - 'OURDRIVE': 'C:', 06:56:16 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:56:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:56:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:56:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:56:16 INFO - 'PROCESSOR_LEVEL': '6', 06:56:16 INFO - 'PROCESSOR_REVISION': '1e05', 06:56:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:56:16 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:56:16 INFO - 'PROMPT': '$P$G', 06:56:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 06:56:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:56:16 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:56:16 INFO - 'PWD': 'C:\\slave\\test-pgo', 06:56:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:56:16 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:56:16 INFO - 'SYSTEMDRIVE': 'C:', 06:56:16 INFO - 'SYSTEMROOT': 'C:\\windows', 06:56:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:56:16 INFO - 'TEST1': 'testie', 06:56:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:56:16 INFO - 'USERDOMAIN': 'T-W732-IX-124', 06:56:16 INFO - 'USERNAME': 'cltbld', 06:56:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:56:16 INFO - 'WINDIR': 'C:\\windows', 06:56:16 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:56:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:56:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:17 INFO - Downloading/unpacking psutil>=0.7.1 06:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:18 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 06:56:18 INFO - Installing collected packages: psutil 06:56:18 INFO - Successfully installed psutil 06:56:18 INFO - Cleaning up... 06:56:19 INFO - Return code: 0 06:56:19 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 06:56: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')]} 06:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56: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')]} 06:56:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:19 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 0x01908B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x013AE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8B48>, '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 0x012EA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01989660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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 06:56:19 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 06:56:19 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 06:56:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:56:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:56:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:56:19 INFO - 'COMPUTERNAME': 'T-W732-IX-124', 06:56:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:56:19 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:56:19 INFO - 'DCLOCATION': 'SCL3', 06:56:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:56:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:56:19 INFO - 'HOMEDRIVE': 'C:', 06:56:19 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:56:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:56:19 INFO - 'KTS_VERSION': '1.19c', 06:56:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:56:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124', 06:56:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:56:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:19 INFO - 'MOZ_NO_REMOTE': '1', 06:56:19 INFO - 'NO_EM_RESTART': '1', 06:56:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:56:19 INFO - 'OS': 'Windows_NT', 06:56:19 INFO - 'OURDRIVE': 'C:', 06:56:19 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;', 06:56:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:56:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:56:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:56:19 INFO - 'PROCESSOR_LEVEL': '6', 06:56:19 INFO - 'PROCESSOR_REVISION': '1e05', 06:56:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:56:19 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:56:19 INFO - 'PROMPT': '$P$G', 06:56:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 06:56:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:56:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:56:19 INFO - 'PWD': 'C:\\slave\\test-pgo', 06:56:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:56:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:56:19 INFO - 'SYSTEMDRIVE': 'C:', 06:56:19 INFO - 'SYSTEMROOT': 'C:\\windows', 06:56:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:56:19 INFO - 'TEST1': 'testie', 06:56:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:56:19 INFO - 'USERDOMAIN': 'T-W732-IX-124', 06:56:19 INFO - 'USERNAME': 'cltbld', 06:56:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:56:19 INFO - 'WINDIR': 'C:\\windows', 06:56:19 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:56:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:56:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:19 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:20 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:56:20 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 06:56:20 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 06:56:20 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) 06:56:20 INFO - Installing collected packages: mozsystemmonitor 06:56:20 INFO - Running setup.py install for mozsystemmonitor 06:56:20 INFO - Successfully installed mozsystemmonitor 06:56:20 INFO - Cleaning up... 06:56:20 INFO - Return code: 0 06:56:20 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 06:56:20 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')]} 06:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56:20 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')]} 06:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:20 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 0x01908B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x013AE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8B48>, '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 0x012EA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01989660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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 06:56:20 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 06:56:20 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 06:56:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:56:20 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:56:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:56:20 INFO - 'COMPUTERNAME': 'T-W732-IX-124', 06:56:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:56:20 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:56:20 INFO - 'DCLOCATION': 'SCL3', 06:56:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:56:20 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:56:20 INFO - 'HOMEDRIVE': 'C:', 06:56:20 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:56:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:56:20 INFO - 'KTS_VERSION': '1.19c', 06:56:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:56:20 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124', 06:56:20 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:56:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:20 INFO - 'MOZ_NO_REMOTE': '1', 06:56:20 INFO - 'NO_EM_RESTART': '1', 06:56:20 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:56:20 INFO - 'OS': 'Windows_NT', 06:56:20 INFO - 'OURDRIVE': 'C:', 06:56:20 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;', 06:56:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:56:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:56:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:56:20 INFO - 'PROCESSOR_LEVEL': '6', 06:56:20 INFO - 'PROCESSOR_REVISION': '1e05', 06:56:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:56:20 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:56:20 INFO - 'PROMPT': '$P$G', 06:56:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 06:56:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:56:20 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:56:20 INFO - 'PWD': 'C:\\slave\\test-pgo', 06:56:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:56:20 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:56:20 INFO - 'SYSTEMDRIVE': 'C:', 06:56:20 INFO - 'SYSTEMROOT': 'C:\\windows', 06:56:20 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:56:20 INFO - 'TEST1': 'testie', 06:56:20 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:56:20 INFO - 'USERDOMAIN': 'T-W732-IX-124', 06:56:20 INFO - 'USERNAME': 'cltbld', 06:56:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:56:20 INFO - 'WINDIR': 'C:\\windows', 06:56:20 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:56:20 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:56:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:21 INFO - Downloading/unpacking blobuploader==1.2.4 06:56:21 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 06:56:21 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 06:56:22 INFO - Downloading blobuploader-1.2.4.tar.gz 06:56:22 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 06:56:22 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 06:56:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:56:22 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 06:56:22 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 06:56:22 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 06:56:22 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 06:56:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:56:22 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 06:56:22 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 06:56:22 INFO - Downloading docopt-0.6.1.tar.gz 06:56:22 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 06:56:22 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 06:56:22 INFO - Installing collected packages: blobuploader, requests, docopt 06:56:22 INFO - Running setup.py install for blobuploader 06:56:23 INFO - Running setup.py install for requests 06:56:24 INFO - Running setup.py install for docopt 06:56:24 INFO - Successfully installed blobuploader requests docopt 06:56:24 INFO - Cleaning up... 06:56:25 INFO - Return code: 0 06:56:25 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 06:56: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')]} 06:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56: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')]} 06:56:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:25 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 0x01908B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x013AE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8B48>, '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 0x012EA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01989660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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 06:56:25 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 06:56:25 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 06:56:25 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:56:25 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:56:25 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:56:25 INFO - 'COMPUTERNAME': 'T-W732-IX-124', 06:56:25 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:56:25 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:56:25 INFO - 'DCLOCATION': 'SCL3', 06:56:25 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:56:25 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:56:25 INFO - 'HOMEDRIVE': 'C:', 06:56:25 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:56:25 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:56:25 INFO - 'KTS_VERSION': '1.19c', 06:56:25 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:56:25 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124', 06:56:25 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:56:25 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:25 INFO - 'MOZ_NO_REMOTE': '1', 06:56:25 INFO - 'NO_EM_RESTART': '1', 06:56:25 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:56:25 INFO - 'OS': 'Windows_NT', 06:56:25 INFO - 'OURDRIVE': 'C:', 06:56:25 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;', 06:56:25 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:56:25 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:56:25 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:56:25 INFO - 'PROCESSOR_LEVEL': '6', 06:56:25 INFO - 'PROCESSOR_REVISION': '1e05', 06:56:25 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:56:25 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:56:25 INFO - 'PROMPT': '$P$G', 06:56:25 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 06:56:25 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:56:25 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:56:25 INFO - 'PWD': 'C:\\slave\\test-pgo', 06:56:25 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:56:25 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:56:25 INFO - 'SYSTEMDRIVE': 'C:', 06:56:25 INFO - 'SYSTEMROOT': 'C:\\windows', 06:56:25 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:56:25 INFO - 'TEST1': 'testie', 06:56:25 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:56:25 INFO - 'USERDOMAIN': 'T-W732-IX-124', 06:56:25 INFO - 'USERNAME': 'cltbld', 06:56:25 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:56:25 INFO - 'WINDIR': 'C:\\windows', 06:56:25 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:56:25 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:56:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 06:56:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xqdfrl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 06:56:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 06:56:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l5y0ub-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 06:56:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 06:56:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vuvu8m-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 06:56:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 06:56:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dgv_4l-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 06:56:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 06:56:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kphhby-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 06:56:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 06:56:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ujiv57-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 06:56:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 06:56:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0nrkdp-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 06:56:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 06:56:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-spmuds-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 06:56:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 06:56:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dyprks-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 06:56:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 06:56:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x6zvl6-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 06:56:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 06:56:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5l1fra-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 06:56:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 06:56:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v4q8tt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 06:56:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 06:56:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h4dup7-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 06:56:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 06:56:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rxpsmp-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 06:56:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 06:56:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5bst17-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:56:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 06:56:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lqime2-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 06:56:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 06:56:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uz94f2-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 06:56:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:56:28 INFO - Running setup.py install for manifestparser 06:56:28 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:28 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:28 INFO - Running setup.py install for mozcrash 06:56:29 INFO - Running setup.py install for mozdebug 06:56:29 INFO - Running setup.py install for mozdevice 06:56:30 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:30 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:30 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:30 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:30 INFO - Running setup.py install for mozfile 06:56:30 INFO - Running setup.py install for mozhttpd 06:56:31 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:31 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:31 INFO - Running setup.py install for mozinfo 06:56:31 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:31 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:31 INFO - Running setup.py install for mozInstall 06:56:31 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:31 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:31 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:31 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:31 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:31 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:31 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:31 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:31 INFO - Running setup.py install for mozleak 06:56:32 INFO - Running setup.py install for mozlog 06:56:32 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:32 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:33 INFO - Running setup.py install for moznetwork 06:56:33 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:33 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:33 INFO - Running setup.py install for mozprocess 06:56:34 INFO - Running setup.py install for mozprofile 06:56:34 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:34 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:34 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:34 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:34 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:34 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:34 INFO - Running setup.py install for mozrunner 06:56:34 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:34 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:35 INFO - Running setup.py install for mozscreenshot 06:56:35 INFO - Running setup.py install for moztest 06:56:36 INFO - Running setup.py install for mozversion 06:56:36 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:56:36 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 06:56:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:56:36 INFO - Cleaning up... 06:56:36 INFO - Return code: 0 06:56:36 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 06:56:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:36 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-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 0x01908B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x013AE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8B48>, '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 0x012EA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01989660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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 06:56:36 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 06:56:36 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 06:56:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:56:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:56:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:56:36 INFO - 'COMPUTERNAME': 'T-W732-IX-124', 06:56:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:56:36 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:56:36 INFO - 'DCLOCATION': 'SCL3', 06:56:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:56:36 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:56:36 INFO - 'HOMEDRIVE': 'C:', 06:56:36 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:56:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:56:36 INFO - 'KTS_VERSION': '1.19c', 06:56:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:56:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124', 06:56:36 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:56:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:56:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:36 INFO - 'MOZ_NO_REMOTE': '1', 06:56:36 INFO - 'NO_EM_RESTART': '1', 06:56:36 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:56:36 INFO - 'OS': 'Windows_NT', 06:56:36 INFO - 'OURDRIVE': 'C:', 06:56:36 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:56:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:56:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:56:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:56:36 INFO - 'PROCESSOR_LEVEL': '6', 06:56:36 INFO - 'PROCESSOR_REVISION': '1e05', 06:56:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:56:36 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:56:36 INFO - 'PROMPT': '$P$G', 06:56:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 06:56:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:56:36 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:56:36 INFO - 'PWD': 'C:\\slave\\test-pgo', 06:56:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:56:36 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:56:36 INFO - 'SYSTEMDRIVE': 'C:', 06:56:36 INFO - 'SYSTEMROOT': 'C:\\windows', 06:56:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:56:36 INFO - 'TEST1': 'testie', 06:56:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:56:36 INFO - 'USERDOMAIN': 'T-W732-IX-124', 06:56:36 INFO - 'USERNAME': 'cltbld', 06:56:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:56:36 INFO - 'WINDIR': 'C:\\windows', 06:56:36 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:56:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:56:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:56:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 06:56:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bniwbs-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 06:56:37 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)) 06:56:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 06:56:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9hq2gh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 06:56:37 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)) 06:56:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 06:56:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xxdl4b-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 06:56:37 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)) 06:56:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 06:56:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-txvrjj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 06:56:37 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)) 06:56:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 06:56:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1ogsdu-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 06:56:37 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)) 06:56:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 06:56:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ydwdx6-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 06:56:37 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)) 06:56:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 06:56:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8cjpk_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 06:56:37 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)) 06:56:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 06:56:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fvpq3o-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 06:56:38 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)) 06:56:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 06:56:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bjy_ux-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 06:56:38 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)) 06:56:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 06:56:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zsb8go-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 06:56:38 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)) 06:56:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 06:56:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vzbdt9-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 06:56:38 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)) 06:56:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 06:56:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qjglu1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 06:56:38 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)) 06:56:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 06:56:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jxyv7l-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 06:56:38 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)) 06:56:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 06:56:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-982liu-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 06:56:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14)) 06:56:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 06:56:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pcrsto-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:56:39 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)) 06:56:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 06:56:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tmpa18-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 06:56:39 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)) 06:56:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 06:56:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kax8x8-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 06:56:39 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)) 06:56:39 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)) 06:56:39 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)) 06:56:39 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)) 06:56:39 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)) 06:56:39 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)) 06:56:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 06:56:39 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 06:56:39 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 06:56:40 INFO - Downloading blessings-1.5.1.tar.gz 06:56:40 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 06:56:40 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 06:56:40 INFO - Installing collected packages: blessings 06:56:40 INFO - Running setup.py install for blessings 06:56:40 INFO - Successfully installed blessings 06:56:40 INFO - Cleaning up... 06:56:43 INFO - Return code: 0 06:56:43 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 06:56:43 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 06:56:43 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 06:56:44 INFO - Reading from file tmpfile_stdout 06:56:44 INFO - Using _rmtree_windows ... 06:56:44 INFO - Using _rmtree_windows ... 06:56:44 INFO - Current package versions: 06:56:44 INFO - blessings == 1.5.1 06:56:44 INFO - blobuploader == 1.2.4 06:56:44 INFO - docopt == 0.6.1 06:56:44 INFO - manifestparser == 1.1 06:56:44 INFO - mozInstall == 1.12 06:56:44 INFO - mozcrash == 0.16 06:56:44 INFO - mozdebug == 0.1 06:56:44 INFO - mozdevice == 0.46 06:56:44 INFO - mozfile == 1.2 06:56:44 INFO - mozhttpd == 0.7 06:56:44 INFO - mozinfo == 0.8 06:56:44 INFO - mozleak == 0.1 06:56:44 INFO - mozlog == 3.0 06:56:44 INFO - moznetwork == 0.27 06:56:44 INFO - mozprocess == 0.22 06:56:44 INFO - mozprofile == 0.27 06:56:44 INFO - mozrunner == 6.10 06:56:44 INFO - mozscreenshot == 0.1 06:56:44 INFO - mozsystemmonitor == 0.0 06:56:44 INFO - moztest == 0.7 06:56:44 INFO - mozversion == 1.4 06:56:44 INFO - psutil == 3.1.1 06:56:44 INFO - requests == 1.2.3 06:56:44 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 06:56:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:44 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 0x01908B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x013AE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8B48>, '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 0x012EA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01989660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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 06:56:44 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 06:56:44 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 06:56:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:56:44 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:56:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:56:44 INFO - 'COMPUTERNAME': 'T-W732-IX-124', 06:56:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:56:44 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:56:44 INFO - 'DCLOCATION': 'SCL3', 06:56:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:56:44 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:56:44 INFO - 'HOMEDRIVE': 'C:', 06:56:44 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:56:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:56:44 INFO - 'KTS_VERSION': '1.19c', 06:56:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:56:44 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124', 06:56:44 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:56:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:56:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:44 INFO - 'MOZ_NO_REMOTE': '1', 06:56:44 INFO - 'NO_EM_RESTART': '1', 06:56:44 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:56:44 INFO - 'OS': 'Windows_NT', 06:56:44 INFO - 'OURDRIVE': 'C:', 06:56:44 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;', 06:56:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:56:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:56:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:56:44 INFO - 'PROCESSOR_LEVEL': '6', 06:56:44 INFO - 'PROCESSOR_REVISION': '1e05', 06:56:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:56:44 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:56:44 INFO - 'PROMPT': '$P$G', 06:56:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 06:56:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:56:44 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:56:44 INFO - 'PWD': 'C:\\slave\\test-pgo', 06:56:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:56:44 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:56:44 INFO - 'SYSTEMDRIVE': 'C:', 06:56:44 INFO - 'SYSTEMROOT': 'C:\\windows', 06:56:44 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:56:44 INFO - 'TEST1': 'testie', 06:56:44 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:56:44 INFO - 'USERDOMAIN': 'T-W732-IX-124', 06:56:44 INFO - 'USERNAME': 'cltbld', 06:56:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:56:44 INFO - 'WINDIR': 'C:\\windows', 06:56:44 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:56:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:56:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:44 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)) 06:56:44 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)) 06:56:44 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)) 06:56:44 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)) 06:56:44 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)) 06:56:44 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)) 06:56:44 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)) 06:56:44 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)) 06:56:44 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)) 06:56:44 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)) 06:56:44 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)) 06:56:44 INFO - Cleaning up... 06:56:44 INFO - Return code: 0 06:56:44 INFO - Running post-action listener: _resource_record_post_action 06:56:44 INFO - Running post-action listener: _start_resource_monitoring 06:56:44 INFO - Starting resource monitoring. 06:56:44 INFO - ##### 06:56:44 INFO - ##### Running install step. 06:56:44 INFO - ##### 06:56:44 INFO - Running pre-action listener: _resource_record_pre_action 06:56:44 INFO - Running main action method: install 06:56:44 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 06:56:44 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 06:56:44 INFO - Reading from file tmpfile_stdout 06:56:44 INFO - Using _rmtree_windows ... 06:56:44 INFO - Using _rmtree_windows ... 06:56:44 INFO - Detecting whether we're running mozinstall >=1.0... 06:56:44 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 06:56:44 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 06:56:45 INFO - Reading from file tmpfile_stdout 06:56:45 INFO - Output received: 06:56:45 INFO - Usage: mozinstall-script.py [options] installer 06:56:45 INFO - Options: 06:56:45 INFO - -h, --help show this help message and exit 06:56:45 INFO - -d DEST, --destination=DEST 06:56:45 INFO - Directory to install application into. [default: 06:56:45 INFO - "C:\slave\test-pgo"] 06:56:45 INFO - --app=APP Application being installed. [default: firefox] 06:56:45 INFO - Using _rmtree_windows ... 06:56:45 INFO - Using _rmtree_windows ... 06:56:45 INFO - mkdir: C:\slave\test-pgo\build\application 06:56:45 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'] 06:56:45 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 06:56:46 INFO - Reading from file tmpfile_stdout 06:56:46 INFO - Output received: 06:56:46 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 06:56:46 INFO - Using _rmtree_windows ... 06:56:46 INFO - Using _rmtree_windows ... 06:56:46 INFO - Running post-action listener: _resource_record_post_action 06:56:46 INFO - ##### 06:56:46 INFO - ##### Running run-tests step. 06:56:46 INFO - ##### 06:56:46 INFO - Running pre-action listener: _resource_record_pre_action 06:56:46 INFO - Running main action method: run_tests 06:56:46 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 06:56:46 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 06:56:46 INFO - Python 2.7.3 06:56:46 INFO - Return code: 0 06:56:46 INFO - grabbing minidump binary from tooltool 06:56:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:46 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 0x012C8B48>, '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 0x012EA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01989660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 06:56:46 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 06:56:46 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 06:56:46 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 06:56:46 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpg9boqr 06:56:46 INFO - INFO - File integrity verified, renaming tmpg9boqr to win32-minidump_stackwalk.exe 06:56:46 INFO - Return code: 0 06:56:46 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 06:56:46 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 06:56:46 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 06:56:46 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 06:56:46 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 06:56:46 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-124', '--webServer', 'localhost'] in C:\slave\test-pgo\build 06:56:46 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-124 --webServer localhost 06:56:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:56:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:56:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:56:46 INFO - 'COMPUTERNAME': 'T-W732-IX-124', 06:56:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:56:46 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:56:46 INFO - 'DCLOCATION': 'SCL3', 06:56:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:56:46 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:56:46 INFO - 'HOMEDRIVE': 'C:', 06:56:46 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:56:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:56:46 INFO - 'KTS_VERSION': '1.19c', 06:56:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:56:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124', 06:56:46 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 06:56:46 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 06:56:46 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:56:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:56:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:46 INFO - 'MOZ_NO_REMOTE': '1', 06:56:46 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 06:56:46 INFO - 'NO_EM_RESTART': '1', 06:56:46 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:56:46 INFO - 'OS': 'Windows_NT', 06:56:46 INFO - 'OURDRIVE': 'C:', 06:56:46 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:56:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:56:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:56:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:56:46 INFO - 'PROCESSOR_LEVEL': '6', 06:56:46 INFO - 'PROCESSOR_REVISION': '1e05', 06:56:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:56:46 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:56:46 INFO - 'PROMPT': '$P$G', 06:56:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 06:56:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:56:46 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:56:46 INFO - 'PWD': 'C:\\slave\\test-pgo', 06:56:46 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 06:56:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:56:46 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:56:46 INFO - 'SYSTEMDRIVE': 'C:', 06:56:46 INFO - 'SYSTEMROOT': 'C:\\windows', 06:56:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:56:46 INFO - 'TEST1': 'testie', 06:56:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:56:46 INFO - 'USERDOMAIN': 'T-W732-IX-124', 06:56:46 INFO - 'USERNAME': 'cltbld', 06:56:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:56:46 INFO - 'WINDIR': 'C:\\windows', 06:56:46 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:56:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:56:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:56:46 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', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-124', '--webServer', 'localhost'] with output_timeout 3600 06:56:48 INFO - mozversion INFO | application_buildid: 20151021233015 06:56:48 INFO - mozversion INFO | application_changeset: 646c308ad3bc4ccfea01deaf32a6927cf82be3f7 06:56:48 INFO - mozversion INFO | application_display_name: Nightly 06:56:48 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:56:48 INFO - mozversion INFO | application_name: Firefox 06:56:48 INFO - mozversion INFO | application_remotingname: firefox 06:56:48 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 06:56:48 INFO - mozversion INFO | application_vendor: Mozilla 06:56:48 INFO - mozversion INFO | application_version: 44.0a1 06:56:48 INFO - mozversion INFO | platform_buildid: 20151021233015 06:56:48 INFO - mozversion INFO | platform_changeset: 646c308ad3bc4ccfea01deaf32a6927cf82be3f7 06:56:48 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 06:56:48 INFO - mozversion INFO | platform_version: 44.0a1 06:56:48 INFO - 2015-10-22 06:56:48,632 DEBUG : using testdate: 1445522208 06:56:48 INFO - 2015-10-22 06:56:48,632 DEBUG : actual date: 1445522208 06:56:48 INFO - 2015-10-22 06:56:48,642 INFO : starting webserver on 'localhost:49791' 06:56:48 INFO - 2015-10-22 06:56:48,644 INFO : Starting test suite T-W732-IX-124 06:56:48 INFO - 2015-10-22 06:56:48,644 INFO : Starting test dromaeo_css 06:56:48 INFO - 2015-10-22 06:56:48,644 DEBUG : operating with platform_type : w7_ 06:56:48 INFO - 2015-10-22 06:56:48,644 INFO : Initialising browser for dromaeo_css test... 06:56:48 INFO - 2015-10-22 06:56:48,775 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpztxctv\profile http://localhost:49791/getInfo.html 06:56:57 INFO - 2015-10-22 06:56:57,220 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:56:57 INFO - 2015-10-22 06:56:57,220 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:56:57 INFO - 2015-10-22 06:56:57,220 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 06:56:57 INFO - 2015-10-22 06:56:57,220 DEBUG : BROWSER_OUTPUT: __metrics 06:56:57 INFO - 2015-10-22 06:56:57,335 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:56:58 INFO - 2015-10-22 06:56:58,338 DEBUG : BROWSER_OUTPUT: console.error: 06:56:58 INFO - 2015-10-22 06:56:58,338 DEBUG : BROWSER_OUTPUT: Message: Error: Transaction canceled due to a closed connection. 06:56:58 INFO - 2015-10-22 06:56:58,338 DEBUG : BROWSER_OUTPUT: Stack: 06:56:58 INFO - 2015-10-22 06:56:58,338 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise<@resource://gre/modules/Sqlite.jsm:562:1 06:56:58 INFO - 2015-10-22 06:56:58,338 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 06:56:58 INFO - 2015-10-22 06:56:58,338 DEBUG : BROWSER_OUTPUT: 06:56:58 INFO - 2015-10-22 06:56:58,944 INFO : Browser initialized. 06:56:58 INFO - 2015-10-22 06:56:58,944 INFO : Running cycle 1/1 for dromaeo_css test... 06:56:58 INFO - 2015-10-22 06:56:58,944 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpztxctv\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:57:10 INFO - 2015-10-22 06:57:10,332 DEBUG : BROWSER_OUTPUT: RSS: Main: 127221760 06:57:10 INFO - 2015-10-22 06:57:10,332 DEBUG : BROWSER_OUTPUT: 06:58:01 INFO - 2015-10-22 06:58:01,907 DEBUG : BROWSER_OUTPUT: 1445522281914 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] 07:00:10 INFO - 2015-10-22 07:00:10,622 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49791/tests/dromaeo/cssquery-ext.html) 07:00:11 INFO - 2015-10-22 07:00:11,578 DEBUG : BROWSER_OUTPUT: RSS: Main: 182312960 07:00:11 INFO - 2015-10-22 07:00:11,578 DEBUG : BROWSER_OUTPUT: 07:03:13 INFO - 2015-10-22 07:03:13,865 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/dromaeo/cssquery-ext.html (next: http://localhost:49791/tests/dromaeo/cssquery-jquery.html) 07:03:14 INFO - 2015-10-22 07:03:14,421 DEBUG : BROWSER_OUTPUT: RSS: Main: 186064896 07:03:14 INFO - 2015-10-22 07:03:14,421 DEBUG : BROWSER_OUTPUT: 07:06:16 INFO - 2015-10-22 07:06:16,288 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49791/tests/dromaeo/cssquery-mootools.html) 07:06:16 INFO - 2015-10-22 07:06:16,851 DEBUG : BROWSER_OUTPUT: RSS: Main: 162525184 07:06:16 INFO - 2015-10-22 07:06:16,851 DEBUG : BROWSER_OUTPUT: 07:09:18 INFO - 2015-10-22 07:09:18,746 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49791/tests/dromaeo/cssquery-prototype.html) 07:09:19 INFO - 2015-10-22 07:09:19,345 DEBUG : BROWSER_OUTPUT: RSS: Main: 136806400 07:09:19 INFO - 2015-10-22 07:09:19,345 DEBUG : BROWSER_OUTPUT: 07:12:22 INFO - 2015-10-22 07:12:22,007 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49791/tests/dromaeo/cssquery-yui.html) 07:12:22 INFO - 2015-10-22 07:12:22,582 DEBUG : BROWSER_OUTPUT: RSS: Main: 132382720 07:12:22 INFO - 2015-10-22 07:12:22,582 DEBUG : BROWSER_OUTPUT: 07:15:25 INFO - 2015-10-22 07:15:25,121 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/dromaeo/cssquery-yui.html (next: http://localhost:49791/tests/dromaeo/cssquery-dojo.html) 07:15:25 INFO - 2015-10-22 07:15:25,377 DEBUG : BROWSER_OUTPUT: RSS: Main: 189779968 07:15:25 INFO - 2015-10-22 07:15:25,377 DEBUG : BROWSER_OUTPUT: 07:15:25 INFO - 2015-10-22 07:15:25,377 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:15:25 INFO - 2015-10-22 07:15:25,377 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:15:25 INFO - 2015-10-22 07:15:25,377 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:15:25 INFO - 2015-10-22 07:15:25,377 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:15:25 INFO - 2015-10-22 07:15:25,377 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2725.0462338181637;2737.6167336572885;2761.9861900690494;2775.9722402775965;2781.2073559035666;5261.973690131549;5282.2076688496745;5292.867678308045;5305.151175811871;5369.5972802039905;5293.9735301323435;5313.415524292324;5396.730163491829;5403.891922161554;5421.457854214576;4452;4556.293774464947;4586.7018643788215;4644.187267228239;4654.906901861961;4441.067375851059;4513.684042117053;4537.047220083787;4579.839705610304;4582.747948862811;4499.167653984024;4563.452385713719;4577.382053422795;4665.88335291617;4673.2055550556315;4442.489113751928;4449.621782148522;4550.294704320836;4638.234691275936;4651.976740116312;4316.525182229952;4508.030773383705;4539.72761634303;4541.977290113561;4598.011427543094;5210.400803907562;5249.68501889884;5288.656237344599;5320.680759154463;5336.813211537617;5336.226247194135;5421.756020979065;5506.91739623906;5609.298837645294;5623.072193088176;5510.063289240798;5514;5596.4403559644115;5605.327360716699;5621.662700237999;5049.419316778581;5131.589472842164;5152.665076770036;5163.819266325698;5236.057509648262;5551.3893471718075;5555.9443705695785;5677;5702.401247868997;5822.737976791054;5224.451432599599;5322.733863306819;5324.893502129936;5366.302380690485;5488.533474654699;5537.197106419547;5538.944610553843;5596.132599447093;5605.775768969195;5660.320761508563;5298.178782288752;5460.262864513319;5464.180372944011;5552.278203833475;5560.555155587544;5266.315379000782;5428.430014848463;5506.5870059744875;5529.557635389159;5539.058360078716;3429.9828500857334;3454.1709989602314;3509.78941263525;3516.2967406518796;3517.982410087933;3073.969260307413;3150.936981260362;3177.586913701204;3180.7773455858332;3223.871045158214;5304.575633949275;5311.867203319948;5389.353277606712;5456.236126942231;5470.753816078286;5516.668999860021;5542.972285138549;5557.472040156179;5649.378568357477;5667.064934285879;5130.358705161855;5269.104252277046;5277.973610132001;5292.8147514837565;5301.469853014745;4232.724872883253;4321.2005778931;4445.666575006888;4452.755098469551;4455.933161002587;5663.292088488939;5757.596968212185;5837.036888913367;5922.378150294243;5944.197533332945;5415.6479828811;5442.809501667422;5541.778328866801;5559.916601250903;5674.177244299636;5459.508644222039;5837.824865254049;5954.612950158225;5978.4021597839865;5985.700714964148;5409.188621706776;5464.207689885024;5568.303962004749;5569.941711074883;5585.357683866408;5495.670259784426;5571.498565128995;5588.133839254922;5630.324361076697;5638.520725738194;5164.277001219757;5500.119980803052;5559.471850174227;5584.190292407659;5589.357223919302;5282.550983166474;5365.785368585213;5449.972750136224;5531.557475402039;5570.053090974625;5170.663906846043;5288.391834938878;5299.258103865539;5303.284056652303;5394.9730251350065;5416.8645783854145;5423.728813559385;5456.427075157051;5524.033294173584;5537.7784888604;5322.654027488201;5398.379186393615;5455.1271796512365;5488.670679759227;5520.1167813149705 07:15:25 INFO - 2015-10-22 07:15:25,377 DEBUG : BROWSER_OUTPUT: |1;ext.html;473.27825066773164;512.9435762066172;519.2652396858446;521.3144714700169;526.7840185523934;32055.35889282213;32206.838965805196;32358.676413235862;32409.51385729216;32443.51334729978;32301.67698323016;32835.67164328356;38026.42960355597;38059.809700951526;38556.80721596395;8499.320054395648;8668.609912553919;8676.089010653885;8695.652173913037;8701.521416322099;7788.649510772;8519.57402129894;8600.612972416257;8636.309095272394;8636.568171591396;7840.882386764203;10851.457427128651;10975.231733778639;11060.612878549253;11067.50196241171;10570.841437378445;10665.413402262873;10679.519421626046;10682.985116413929;10694.893051069506;7835.686572537092;7994;10452.738681532946;10549.683509494727;10627;38100.42849357262;38178.04554886122;38571.614283857365;38874.416883746766;38941.80529097365;3178.396104740092;3191.1702957231055;3202.327511222646;3222.0978126124487;3264.836758162106;30466;30706.53940190899;30889.691103088906;31202.375952481052;31829.84085079583;2744.382513934369;2761.1716485054403;2777.597248398971;2789.5955086512545;2827.434513097389;346803;351327;352178;353451;354359;46403.5359646406;53492.73253633785;53557.73221133869;53594.732026340396;53683.463165367844;47005.764971174925;58392;59072.7046364774;59081.704591476766;59123;14402.927985360006;14496.85503144955;14525.491607793676;14544.927275363556;14547;11024;11053;11095.55617775296;11097.72255693598;11106.777864442665;17417.738733919003;17513.649727005642;17544.736828947574;17551.82448175502;17564.209610567556;7521.398288136936;7746.845063098706;7757.650905709256;7785.1436342002335;7801.113587972486;841.3437518735396;868.9435186712843;894.874717539545;925.5742358515024;1020.9229263127472;28206.15381538516;28460.907982327946;28693.713062869523;28920.710792892223;28960.42079158405;980.8773903262093;988.4859872866069;990.6136606723384;1057.038095333243;1069.1767339148812;14639.268036598127;14738.189399583125;14823.70352592942;15071.924640376728;15088;423043;426690;427710;427933;428581;47515.5248447518;48443.5155648439;48642.27036594454;48650;48666.27000594994;1092.5520536580123;1109.633820839105;1175.735459521601;1176.582313278791;1179.3572502985894;27883.72116278811;27964.161075167776;28340.291492712848;28659.856700716362;28691.856540718;39738;40721.79639101786;42744.572554275306;44193.77903110464;45179.54820451885;276210;279703;284865;287233;288572;24514.63228051545;24930.75069249357;27614.861925690242;28023.299417514725;28154.71845281521;38968.41547376735;39492.6811710125;39908.401373978835;40023.79988100041;40071.39892901667;33874;36345.636543634224;38215.23569528538;38499.80750096231;38762;35345;35794;35830.28339433249;36178.8191059043;36713.81643091767;35855;36401.453978190875;36592.08519787026;36685.26629467342;36780.44829327509;31123.37753244968;33023;33108.66891331056;33260.83369583137;34018;25435.745642543337;25694.871525643;26011.47977040411;26056.73943260543;26321.341966450993 07:15:25 INFO - 2015-10-22 07:15:25,382 DEBUG : BROWSER_OUTPUT: |2;jquery.html;324.7385854387218;329.42844165373083;331.06309145119315;332.55769826131245;336.50701721977316;15267;15657.608559786011;17685;17789.82210177898;18198.090095495238;11309.604163854247;11629.592964246252;11671.474783634736;16912.15439228037;17309.913450432763;4448.955510444895;4539.137563862872;4541.318802179683;4550.135474259897;4551.704139230956;3709.5733990591157;3798.9240215195678;4513.368128462002;4938.580220681229;5039.294498770175;4952.405711314647;4969.676970996874;4971.776270067838;4971.801127954877;4977.7262250576205;4812.23004319309;4931.482194369593;4956.231784073474;4962.6526143169995;4986.476419975905;4181.330987042089;4189.07840275139;4192.727472714264;4193.1404062167185;4205.201011807747;13734.93132534331;14065.437382504688;14110.858891411057;14189.78715319271;14209.36057877398;1362.6729584899651;1901.2735039322943;1970.9211631534722;1993.4617653233545;2173.4566358410398;9896.25778066648;9903.603855845757;10096.091351778301;10200.94899525505;10212.2851400402;1543.40578877132;1561.055561385357;1566.1777566777419;1567.851054149854;1659.5021493552006;73325;75261.62369188174;77527;77828.22171778267;78511;6361.8727625448155;6381.1704478417505;6400.519961002943;6503.772367967098;6560.770373036921;7548;7548.509346892434;7550.320471157622;7573.35626471745;7573.545587264672;5355.036093503207;5436.700981446058;5448.455154484599;5473.644213126134;5475.863103422366;5816.331121921034;5892.175095486636;5930.851728706731;5937.52499800015;5982.521398288127;2864.899728509492;2882.553204253344;2884.6971068037974;2892.1179040392462;2901.796874218827;1007.5818535307911;1027.9794404112026;1030.258214085857;1061.368485750977;1085.6688709943544;391.39520590043026;427.14570858283435;436.9300911854088;440.80163926233325;441.3247730971619;11096.278741881752;11524.59663911759;12223.266604003611;12413.93793031029;12480.251184928933;393.77161246476976;471.0719881832784;471.24130150457734;477.6608607888369;484.9563539281411;6321.241451025814;6411;6468.417842394207;6497.740090396331;6519.380658837403;437300;438306;439499;439765;440321;83045;84210;85357;86097;86823.56588217018;508.6541152016664;524.8241838983906;525.3905469655258;525.6136739496467;526.7155735902646;11151.498182581965;11387.829182562102;11598.362090085126;11601;11648.592299269485;38775;38939.415908761956;39858.40212396874;40111.39832902567;40372.596274036885;54581;54679.72660136674;55744;58655.70672146612;60543.03755068224;5856.180134781219;5959.67221802805;6255.061740738925;6386.552941294065;6413.102165696713;12320.691982700504;12400.379981001095;12606.873931260938;12646;12648.114631976041;10502.474876256003;10578.365298081831;10719.732006699895;10768.676939691823;10842;10115.7471063224;10382.169426446017;10433.269671123253;10491.737706557396;10514.842277365999;10389.636362727268;10603.840942386025;10935.28920620157;11047.83428248593;11067.667969960914;6191.133241346125;6427.292997770148;6467.579607325509;6471.191101112361;6494.09082728409;10609.098226650822;10619.15046796239;10632.149428045583;10716.035556799925;10805.621803237163 07:15:25 INFO - 2015-10-22 07:15:25,384 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1274.7514234724229;1338.7523308187986;1447.8552144785522;1593.6015996001;1593.7210988077084;8203.179682031794;8328.167183281668;9019.323550733689;9449.102335278147;9450;8900.19898209161;9124.498152601604;9161.816763664723;9162.221211197057;9173.587188576514;2293.9197128100477;2318.710161229846;2319.3158018384615;2323.604987152188;2328.5924963131383;2024.4230394337617;2062.5771716798095;2252.774722527742;2309.9653505197434;2337.345543247885;2189.069645400706;2193.3748881568754;2210.613142700029;2213.756486786452;2218.1238410827714;2045.9693104603443;2305.008846196135;2307.90768369265;2312.8843557822124;2319.6288593824993;2010.1858747207405;2208.2271205078255;2246.4496198431325;2246.7416247131628;2249.0216755711153;9098.363114581916;9109.772255693595;9146;9170.633174673005;9178.082191780835;5156.432792392834;5159.509846564571;5217.113090774577;5233;5239.5546378557865;3277.4428347180797;3278.5737854078816;3304.9173770655684;3349.1459677782254;3392;1034.4465710844738;1061.3419679798574;1086.1745073744012;1088.9328458111095;1089.9019088282014;12834.422450989729;12958.287294198815;12959.740805183843;12965.870341296655;12973.156744811557;4491.797869095899;4523.117991991596;4571.405717256735;4683.555062269079;4689.695169813951;10531.420771857469;10584.947075264574;10597;10609.73475663099;10624.309419887682;5093.0577843099145;5094.439611642716;5097.158968770185;5099.847004589794;5102.719350435762;4612.446506419251;4634.513376095529;4648.604868586208;4655.580997710223;4657.184992626277;3607.4769158471877;3621.0947263184203;3655.1775850433974;3671.761335513183;3683.208110256308;1154.157465050501;1164.9650510484698;1173.20808454293;1181.5923506390282;1183.810590305547;1125.2092433562016;1142.4287856071965;1161.2451906260828;1162.0935670177275;1162.436218434059;7879.921200788033;7984.520928744294;7985.880211796828;7986.081600616004;8042.557659328676;449.56167736456695;457.1246063787809;459.3224993135099;462.02973755113993;470.9529047095332;5950.375210602911;6059.151718759377;6071.210742603522;6139.570230083851;6374.649394283266;11298.661040168809;11380.374079425712;11413.714657133472;11413.828792568118;11460.828087578693;10816.621418250323;11340.20618556693;11344.546218151181;11360.943195284137;11361.091112710963;8174.7956301092945;8220.424570280024;8227.670893164326;8257.79355516117;8266.486743134144;7920.84158316842;8042;8044.517328960281;8150.959245203736;8179.918200817916;9667.564959576977;9884.85172722424;9916.305858589829;9952.25358098131;9982.201423885926;10020.298579099393;10198.235132364955;10218.744531386477;10286.2799604027;10321.48392580383;8128.146544612967;8164.265216130577;8167.183281671785;8199.303059240032;8201.548914809742;8597.355198360023;8819.338549608678;8886.111388861062;8893.64425422976;8963.551822408985;7947.165547617648;7975.601219938864;8115.59422028908;8229.711960081368;8294.25351718324;7850.4112191587;7921.366290696616;7963.641636126504;8007.639656215368;8023.83952320962;7617.200193979774;7853.175416581405;8101.554414507259;8110.270075693216;8122.147174546824;6743;6779.389854913082;6798.93201067983;6806.727730890709;6810.353016463428;7043.6830342633675;7135.072440582691;7144.714211431485;7150.821229469097;7151.356377926011 07:15:25 INFO - 2015-10-22 07:15:25,459 DEBUG : BROWSER_OUTPUT: |4;prototype.html;323.73615503364755;326.86108403928324;332.67896479896893;335.3628106597465;340.48926610084874;5824.796132135376;5825.796097136601;5841.7955371562;5849.502792262658;5849.64902105874;5753.482186603215;5773.191753154551;5773.307203135619;5777.480026797587;5778.7977420790285;4117.835286588533;4181.038361176916;4224.978875105636;4258.936115958255;4263.12605915788;4230.894227644303;4235.9364609530885;4240.151969606076;4242.427272318244;4245.787710614472;4194.2869712149;4198.181354635847;4198.874033778991;4204.0540878302445;4205.600467955539;4100.2414553307735;4158.459400277975;4175.331946888514;4197.601227883346;4230.661547076226;3688.2992231475932;3698.7410881238325;3700.2414505026404;3702.148505843644;3704.462852886343;5495.587830912657;5620.044592419298;5644.322681278232;5647.350554686182;5648.604597678164;4715.31627913954;4872.074305881871;4952;4994.600431965434;5009.824656137018;5979.252593425822;5984.431479009487;5988.550858685572;5989.970050149765;5996.100584912255;4124;4129.112240868198;4129.132882094764;4132.483439570054;4134.359174327984;6215.689215539205;6271.372862713738;6285.811425657238;6290.43386095249;6299.68501574924;3800.7529510581726;4059.3505039193587;4110.013596736761;4241.575842415796;4255.829766809293;4010.63904248619;4290.249206388869;4321.049369138785;4326.4808223012815;4327.437433133672;4824.0834241494;4961;4963.6277279204205;4967.279744437036;4974.651774375831;5973.223480947449;5987.760489580455;5998.970005149947;6002.339742628307;6002.54980876436;1522.8477152284684;1541.4142625802124;1547.9628648805328;1558.5869744517486;1561.5861796623904;1023.5752162852333;1042.3954106618144;1048.5176818663347;1057.5875408590655;1067.6263307842194;5958.612690175125;5971.2834459864225;5971.492423144082;5976.133460648181;5983.1623572699855;6234;6237.532185086136;6243.188385509946;6249.468795152372;6256.74973001084;5969.283685957626;5978.55160862937;5983.311919129269;5988.28140623128;5992;3346.6151392590164;3353.782004169721;3417.6069751978343;3444.7071998880374;3444.7953309881195;6501.349865013555;6631.933680663132;6649.401553860176;6683.064370988066;6686.1642294713165;6379.266384365857;6398.744050238031;6399.360063993563;6402.551821372459;6408.67956602168;6634.237062737847;6637.269900310962;6640.83397915056;6654.966725166343;6662.300458451889;6080.604760690541;6105.084237364431;6209.192804935329;6243.656598887105;6259.311475737756;5977.402259773988;6123.326434092335;6139.785107521216;6141.07883817431;6145.9692701538;6378.489720822416;6573.244076931214;6616.768413105518;6625.503087268377;6647.800565983028;4043.1307268936143;4325.437693099877;4342.1532801103485;4346.36977638247;4348.0869017506675;5898.793542226002;5948.405159484017;6158.876822463436;6280.309165991646;6319.96840015797;6066.211392518944;6130.570860039755;6131.202943617302;6134.018557031032;6139.662318572521;6064.48451881595;6068.180795592715;6073.028315469681;6087.208662873798;6097.4817140543555;5944.227250457413;5961.225040744675;5962.344142144274;5967.701614919323;5973.1338955852025;6061.424164704346;6096.512279017757;6121.204243448323;6125.1424800527075;6125.663088530174;5991.191189189403;6032.668203248688;6033.245844269466;6037.758489660365;6039.969800150971 07:15:25 INFO - 2015-10-22 07:15:25,460 DEBUG : BROWSER_OUTPUT: |5;yui.html;670.4636290967225;673.9157605299338;714.5534041224234;742.0538813013404;759.6429678051315;11230.270032447885;11537.596184133557;12681.492740290389;13122.868771312296;13146.934265328684;13301.46794128236;13430.597082087554;13437.462501499953;13438.865611343883;13448.462061517552;511.78760814262034;514.6063261604876;583.4632138432652;588.6232811000973;595.3926994465643;588.9734961926703;591.5858898770856;593.2495393327445;595.8718875441779;596.5198015597442;613.7790395457654;619.8326451858018;620.3796203796204;620.5811077522654;621.1118101115403;615.8460384903774;616.8612062285995;617.4134572156424;618.3229363846581;618.452669387593;434.62405019658087;444.824294403712;445.19419850071154;445.54999450555175;445.77933922708144;12150.57472988441;12472.251664900135;12507.874921250763;12514.06144539154;12582.370881455981;10064.94967525165;10216.387016779017;10572.947135264352;10667.413292268922;10681.786364272748;10322.483875806254;10341.172706183563;10348.120409765179;10385.13928181183;10385.376877387382;3277.2953814929665;3318.8008719476666;3335.7164641005543;3339.933201335984;3363.007912665745;48133.99999999965;49980.75009624965;50581;50705.23942140871;50797;7739.883901741478;9727.270454715925;9807.55865986018;9924.206063514901;10212;16172.75740863888;16241;16329.591760206089;16364.754528682079;16379.42672006498;7306.926930730731;7438.367738742268;7554.924450755532;7577.128630207597;7812.882806757903;4122.505299364096;4217.97891010543;4300.0969796343115;4332.090261045153;4426.380306757057;2849.9287517811804;2885.783566232541;2906.5640153977074;2953.512670409358;2958.467475854367;461.68836035675787;466.77361479682327;470.0716085730723;474.23411190927095;479.2667219154699;604.9032154855201;626.2266101364811;629.6285191736897;637.5473413876107;646.9902951455732;8715.389922705477;8961.193492585568;8970.910290897138;9081.137291957253;9262.351635385457;652.305294860981;669.3908543225641;678.1141872806418;717.0636244578443;744.6128013432984;4116.338207247293;4572.199865023607;4578.381918441034;4691.976540117277;4850.345203397496;61146.38853611496;62070.68964655148;62179.378206218265;62835.68582157151;63174.36825631685;53124.73437632787;53913.73043134759;54073.40018195065;54086.45913540814;54248.186277205874;44192.33711494266;44252;44713;46075.078498430514;46194;9309.953450232706;9333.626654933998;9385.859212111687;9617.303996782337;9630.614775408905;31364.529532057495;32125.518117228727;32242.83878580592;32263.516047259778;32455.67544324621;43148.352774707775;43278.56721432746;43349.78325108354;44568;44744.32883506685;8487.745367638981;8815.382923195571;8871.64513419456;9172.174504294646;9217.124373184537;13470.79793803074;13535.796963045364;13693.863061369259;13931.233782142079;13950.372233249731;12708.173968692005;12729.236245825281;12820.166689165175;12845.678858028625;13366.93316533411;11870.465829037541;11897.881021189678;12181.512739490676;12249;12376.443060062498;12640.431180596712;12676;12739.681507962376;12870.613881583567;12992.220466772023;8991.640334386813;9648.228141748503;9676.516174191403;9986.300958932805;10146.188304935735;10063.798724025331;10071.84892226632;10131.392116473035;10356.637517687146;10411.531481083219 07:15:25 INFO - 2015-10-22 07:15:25,461 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:15:25 INFO - 2015-10-22 07:15:25,461 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:15:25 INFO - 2015-10-22 07:15:25,461 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,731 07:15:25 INFO - 2015-10-22 07:15:25,461 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:15:25 INFO - 2015-10-22 07:15:25,461 DEBUG : BROWSER_OUTPUT: __startTimestamp1445523325399__endTimestamp 07:15:25 INFO - 2015-10-22 07:15:25,461 DEBUG : BROWSER_OUTPUT: 07:15:25 INFO - 2015-10-22 07:15:25,461 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:15:25 INFO - 2015-10-22 07:15:25,461 DEBUG : BROWSER_OUTPUT: Number of tests: 6 07:15:25 INFO - 2015-10-22 07:15:25,461 DEBUG : BROWSER_OUTPUT: 07:15:25 INFO - 2015-10-22 07:15:25,461 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5086.83 Median:5366.30 stddev:742.40 (13.8%) stddev-sans-first:721.18 07:15:25 INFO - 2015-10-22 07:15:25,463 DEBUG : BROWSER_OUTPUT: Values: 2725.0 2737.6 2762.0 2776.0 2781.2 5262.0 5282.2 5292.9 5305.2 5369.6 5294.0 5313.4 5396.7 5403.9 5421.5 4452.0 4556.3 4586.7 4644.2 4654.9 4441.1 4513.7 4537.0 4579.8 4582.7 4499.2 4563.5 4577.4 4665.9 4673.2 4442.5 4449.6 4550.3 4638.2 4652.0 4316.5 4508.0 4539.7 4542.0 4598.0 5210.4 5249.7 5288.7 5320.7 5336.8 5336.2 5421.8 5506.9 5609.3 5623.1 5510.1 5514.0 5596.4 5605.3 5621.7 5049.4 5131.6 5152.7 5163.8 5236.1 5551.4 5555.9 5677.0 5702.4 5822.7 5224.5 5322.7 5324.9 5366.3 5488.5 5537.2 5538.9 5596.1 5605.8 5660.3 5298.2 5460.3 5464.2 5552.3 5560.6 5266.3 5428.4 5506.6 5529.6 5539.1 3430.0 3454.2 3509.8 3516.3 3518.0 3074.0 3150.9 3177.6 3180.8 3223.9 5304.6 5311.9 5389.4 5456.2 5470.8 5516.7 5543.0 5557.5 5649.4 5667.1 5130.4 5269.1 5278.0 5292.8 5301.5 4232.7 4321.2 4445.7 4452.8 4455.9 5663.3 5757.6 5837.0 5922.4 5944.2 5415.6 5442.8 5541.8 5559.9 5674.2 5459.5 5837.8 5954.6 5978.4 5985.7 5409.2 5464.2 5568.3 5569.9 5585.4 5495.7 5571.5 5588.1 5630.3 5638.5 5164.3 5500.1 5559.5 5584.2 5589.4 5282.6 5365.8 5450.0 5531.6 5570.1 5170.7 5288.4 5299.3 5303.3 5395.0 5416.9 5423.7 5456.4 5524.0 5537.8 5322.7 5398.4 5455.1 5488.7 5520.1 07:15:25 INFO - 2015-10-22 07:15:25,463 DEBUG : BROWSER_OUTPUT: 07:15:25 INFO - 2015-10-22 07:15:25,463 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:50379.46 Median:33567.42 stddev:94644.97 (282.0%) stddev-sans-first:94836.30 07:15:25 INFO - 2015-10-22 07:15:25,463 DEBUG : BROWSER_OUTPUT: Values: 473.3 512.9 519.3 521.3 526.8 32055.4 32206.8 32358.7 32409.5 32443.5 32301.7 32835.7 38026.4 38059.8 38556.8 8499.3 8668.6 8676.1 8695.7 8701.5 7788.6 8519.6 8600.6 8636.3 8636.6 7840.9 10851.5 10975.2 11060.6 11067.5 10570.8 10665.4 10679.5 10683.0 10694.9 7835.7 7994.0 10452.7 10549.7 10627.0 38100.4 38178.0 38571.6 38874.4 38941.8 3178.4 3191.2 3202.3 3222.1 3264.8 30466.0 30706.5 30889.7 31202.4 31829.8 2744.4 2761.2 2777.6 2789.6 2827.4 346803.0 351327.0 352178.0 353451.0 354359.0 46403.5 53492.7 53557.7 53594.7 53683.5 47005.8 58392.0 59072.7 59081.7 59123.0 14402.9 14496.9 14525.5 14544.9 14547.0 11024.0 11053.0 11095.6 11097.7 11106.8 17417.7 17513.6 17544.7 17551.8 17564.2 7521.4 7746.8 7757.7 7785.1 7801.1 841.3 868.9 894.9 925.6 1020.9 28206.2 28460.9 28693.7 28920.7 28960.4 980.9 988.5 990.6 1057.0 1069.2 14639.3 14738.2 14823.7 15071.9 15088.0 423043.0 426690.0 427710.0 427933.0 428581.0 47515.5 48443.5 48642.3 48650.0 48666.3 1092.6 1109.6 1175.7 1176.6 1179.4 27883.7 27964.2 28340.3 28659.9 28691.9 39738.0 40721.8 42744.6 44193.8 45179.5 276210.0 279703.0 284865.0 287233.0 288572.0 24514.6 24930.8 27614.9 28023.3 28154.7 38968.4 39492.7 39908.4 40023.8 40071.4 33874.0 36345.6 38215.2 38499.8 38762.0 35345.0 35794.0 35830.3 36178.8 36713.8 35855.0 36401.5 36592.1 36685.3 36780.4 31123.4 33023.0 33108.7 33260.8 34018.0 25435.7 25694.9 26011.5 26056.7 26321.3 07:15:25 INFO - 2015-10-22 07:15:25,463 DEBUG : BROWSER_OUTPUT: 07:15:25 INFO - 2015-10-22 07:15:25,463 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25192.36 Median:4199.17 stddev:72829.66 (1734.4%) stddev-sans-first:73010.03 07:15:25 INFO - 2015-10-22 07:15:25,463 DEBUG : BROWSER_OUTPUT: Values: 324.7 329.4 331.1 332.6 336.5 15267.0 15657.6 17685.0 17789.8 18198.1 11309.6 11629.6 11671.5 16912.2 17309.9 4449.0 4539.1 4541.3 4550.1 4551.7 3709.6 3798.9 4513.4 4938.6 5039.3 4952.4 4969.7 4971.8 4971.8 4977.7 4812.2 4931.5 4956.2 4962.7 4986.5 4181.3 4189.1 4192.7 4193.1 4205.2 13734.9 14065.4 14110.9 14189.8 14209.4 1362.7 1901.3 1970.9 1993.5 2173.5 9896.3 9903.6 10096.1 10200.9 10212.3 1543.4 1561.1 1566.2 1567.9 1659.5 73325.0 75261.6 77527.0 77828.2 78511.0 6361.9 6381.2 6400.5 6503.8 6560.8 7548.0 7548.5 7550.3 7573.4 7573.5 5355.0 5436.7 5448.5 5473.6 5475.9 5816.3 5892.2 5930.9 5937.5 5982.5 2864.9 2882.6 2884.7 2892.1 2901.8 1007.6 1028.0 1030.3 1061.4 1085.7 391.4 427.1 436.9 440.8 441.3 11096.3 11524.6 12223.3 12413.9 12480.3 393.8 471.1 471.2 477.7 485.0 6321.2 6411.0 6468.4 6497.7 6519.4 437300.0 438306.0 439499.0 439765.0 440321.0 83045.0 84210.0 85357.0 86097.0 86823.6 508.7 524.8 525.4 525.6 526.7 11151.5 11387.8 11598.4 11601.0 11648.6 38775.0 38939.4 39858.4 40111.4 40372.6 54581.0 54679.7 55744.0 58655.7 60543.0 5856.2 5959.7 6255.1 6386.6 6413.1 12320.7 12400.4 12606.9 12646.0 12648.1 10502.5 10578.4 10719.7 10768.7 10842.0 10115.7 10382.2 10433.3 10491.7 10514.8 10389.6 10603.8 10935.3 11047.8 11067.7 6191.1 6427.3 6467.6 6471.2 6494.1 10609.1 10619.2 10632.1 10716.0 10805.6 07:15:25 INFO - 2015-10-22 07:15:25,466 DEBUG : BROWSER_OUTPUT: 07:15:25 INFO - 2015-10-22 07:15:25,466 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:6158.33 Median:4656.38 stddev:3495.50 (75.1%) stddev-sans-first:3486.03 07:15:25 INFO - 2015-10-22 07:15:25,466 DEBUG : BROWSER_OUTPUT: Values: 1274.8 1338.8 1447.9 1593.6 1593.7 8203.2 8328.2 9019.3 9449.1 9450.0 8900.2 9124.5 9161.8 9162.2 9173.6 2293.9 2318.7 2319.3 2323.6 2328.6 2024.4 2062.6 2252.8 2310.0 2337.3 2189.1 2193.4 2210.6 2213.8 2218.1 2046.0 2305.0 2307.9 2312.9 2319.6 2010.2 2208.2 2246.4 2246.7 2249.0 9098.4 9109.8 9146.0 9170.6 9178.1 5156.4 5159.5 5217.1 5233.0 5239.6 3277.4 3278.6 3304.9 3349.1 3392.0 1034.4 1061.3 1086.2 1088.9 1089.9 12834.4 12958.3 12959.7 12965.9 12973.2 4491.8 4523.1 4571.4 4683.6 4689.7 10531.4 10584.9 10597.0 10609.7 10624.3 5093.1 5094.4 5097.2 5099.8 5102.7 4612.4 4634.5 4648.6 4655.6 4657.2 3607.5 3621.1 3655.2 3671.8 3683.2 1154.2 1165.0 1173.2 1181.6 1183.8 1125.2 1142.4 1161.2 1162.1 1162.4 7879.9 7984.5 7985.9 7986.1 8042.6 449.6 457.1 459.3 462.0 471.0 5950.4 6059.2 6071.2 6139.6 6374.6 11298.7 11380.4 11413.7 11413.8 11460.8 10816.6 11340.2 11344.5 11360.9 11361.1 8174.8 8220.4 8227.7 8257.8 8266.5 7920.8 8042.0 8044.5 8151.0 8179.9 9667.6 9884.9 9916.3 9952.3 9982.2 10020.3 10198.2 10218.7 10286.3 10321.5 8128.1 8164.3 8167.2 8199.3 8201.5 8597.4 8819.3 8886.1 8893.6 8963.6 7947.2 7975.6 8115.6 8229.7 8294.3 7850.4 7921.4 7963.6 8007.6 8023.8 7617.2 7853.2 8101.6 8110.3 8122.1 6743.0 6779.4 6798.9 6806.7 6810.4 7043.7 7135.1 7144.7 7150.8 7151.4 07:15:25 INFO - 2015-10-22 07:15:25,467 DEBUG : BROWSER_OUTPUT: 07:15:25 INFO - 2015-10-22 07:15:25,467 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5057.45 Median:5921.51 stddev:1556.92 (26.3%) stddev-sans-first:1520.20 07:15:25 INFO - 2015-10-22 07:15:25,467 DEBUG : BROWSER_OUTPUT: Values: 323.7 326.9 332.7 335.4 340.5 5824.8 5825.8 5841.8 5849.5 5849.6 5753.5 5773.2 5773.3 5777.5 5778.8 4117.8 4181.0 4225.0 4258.9 4263.1 4230.9 4235.9 4240.2 4242.4 4245.8 4194.3 4198.2 4198.9 4204.1 4205.6 4100.2 4158.5 4175.3 4197.6 4230.7 3688.3 3698.7 3700.2 3702.1 3704.5 5495.6 5620.0 5644.3 5647.4 5648.6 4715.3 4872.1 4952.0 4994.6 5009.8 5979.3 5984.4 5988.6 5990.0 5996.1 4124.0 4129.1 4129.1 4132.5 4134.4 6215.7 6271.4 6285.8 6290.4 6299.7 3800.8 4059.4 4110.0 4241.6 4255.8 4010.6 4290.2 4321.0 4326.5 4327.4 4824.1 4961.0 4963.6 4967.3 4974.7 5973.2 5987.8 5999.0 6002.3 6002.5 1522.8 1541.4 1548.0 1558.6 1561.6 1023.6 1042.4 1048.5 1057.6 1067.6 5958.6 5971.3 5971.5 5976.1 5983.2 6234.0 6237.5 6243.2 6249.5 6256.7 5969.3 5978.6 5983.3 5988.3 5992.0 3346.6 3353.8 3417.6 3444.7 3444.8 6501.3 6631.9 6649.4 6683.1 6686.2 6379.3 6398.7 6399.4 6402.6 6408.7 6634.2 6637.3 6640.8 6655.0 6662.3 6080.6 6105.1 6209.2 6243.7 6259.3 5977.4 6123.3 6139.8 6141.1 6146.0 6378.5 6573.2 6616.8 6625.5 6647.8 4043.1 4325.4 4342.2 4346.4 4348.1 5898.8 5948.4 6158.9 6280.3 6320.0 6066.2 6130.6 6131.2 6134.0 6139.7 6064.5 6068.2 6073.0 6087.2 6097.5 5944.2 5961.2 5962.3 5967.7 5973.1 6061.4 6096.5 6121.2 6125.1 6125.7 5991.2 6032.7 6033.2 6037.8 6040.0 07:15:25 INFO - 2015-10-22 07:15:25,467 DEBUG : BROWSER_OUTPUT: 07:15:25 INFO - 2015-10-22 07:15:25,467 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13852.97 Median:24658.26 stddev:16423.27 (66.6%) stddev-sans-first:16439.51 07:15:25 INFO - 2015-10-22 07:15:25,467 DEBUG : BROWSER_OUTPUT: Values: 670.5 673.9 714.6 742.1 759.6 11230.3 11537.6 12681.5 13122.9 13146.9 13301.5 13430.6 13437.5 13438.9 13448.5 511.8 514.6 583.5 588.6 595.4 589.0 591.6 593.2 595.9 596.5 613.8 619.8 620.4 620.6 621.1 615.8 616.9 617.4 618.3 618.5 434.6 444.8 445.2 445.5 445.8 12150.6 12472.3 12507.9 12514.1 12582.4 10064.9 10216.4 10572.9 10667.4 10681.8 10322.5 10341.2 10348.1 10385.1 10385.4 3277.3 3318.8 3335.7 3339.9 3363.0 48134.0 49980.8 50581.0 50705.2 50797.0 7739.9 9727.3 9807.6 9924.2 10212.0 16172.8 16241.0 16329.6 16364.8 16379.4 7306.9 7438.4 7554.9 7577.1 7812.9 4122.5 4218.0 4300.1 4332.1 4426.4 2849.9 2885.8 2906.6 2953.5 2958.5 461.7 466.8 470.1 474.2 479.3 604.9 626.2 629.6 637.5 647.0 8715.4 8961.2 8970.9 9081.1 9262.4 652.3 669.4 678.1 717.1 744.6 4116.3 4572.2 4578.4 4692.0 4850.3 61146.4 62070.7 62179.4 62835.7 63174.4 53124.7 53913.7 54073.4 54086.5 54248.2 44192.3 44252.0 44713.0 46075.1 46194.0 9310.0 9333.6 9385.9 9617.3 9630.6 31364.5 32125.5 32242.8 32263.5 32455.7 43148.4 43278.6 43349.8 44568.0 44744.3 8487.7 8815.4 8871.6 9172.2 9217.1 13470.8 13535.8 13693.9 13931.2 13950.4 12708.2 12729.2 12820.2 12845.7 13366.9 11870.5 11897.9 12181.5 12249.0 12376.4 12640.4 12676.0 12739.7 12870.6 12992.2 8991.6 9648.2 9676.5 9986.3 10146.2 10063.8 10071.8 10131.4 10356.6 10411.5 07:15:25 INFO - 2015-10-22 07:15:25,469 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:15:25 INFO - 2015-10-22 07:15:25,469 DEBUG : BROWSER_OUTPUT: 07:15:25 INFO - 2015-10-22 07:15:25,851 INFO : Browser exited with error code: 0 07:15:26 INFO - 2015-10-22 07:15:26,634 INFO : Completed test dromaeo_css (00:18:37) 07:15:26 INFO - 2015-10-22 07:15:26,634 INFO : Starting test kraken 07:15:26 INFO - 2015-10-22 07:15:26,634 DEBUG : operating with platform_type : w7_ 07:15:26 INFO - 2015-10-22 07:15:26,634 INFO : Initialising browser for kraken test... 07:15:26 INFO - 2015-10-22 07:15:26,661 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmfhdim\profile http://localhost:49791/getInfo.html 07:15:30 INFO - 2015-10-22 07:15:30,937 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:15:30 INFO - 2015-10-22 07:15:30,937 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:15:30 INFO - 2015-10-22 07:15:30,937 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 07:15:30 INFO - 2015-10-22 07:15:30,937 DEBUG : BROWSER_OUTPUT: __metrics 07:15:31 INFO - 2015-10-22 07:15:31,013 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:15:32 INFO - 2015-10-22 07:15:32,099 DEBUG : BROWSER_OUTPUT: console.error: 07:15:32 INFO - 2015-10-22 07:15:32,099 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] 07:15:32 INFO - 2015-10-22 07:15:32,099 DEBUG : BROWSER_OUTPUT: Stack: 07:15:32 INFO - 2015-10-22 07:15:32,099 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 07:15:32 INFO - 2015-10-22 07:15:32,099 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 07:15:32 INFO - 2015-10-22 07:15:32,099 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 07:15:32 INFO - 2015-10-22 07:15:32,099 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 07:15:32 INFO - 2015-10-22 07:15:32,099 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 07:15:32 INFO - 2015-10-22 07:15:32,101 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 07:15:32 INFO - 2015-10-22 07:15:32,101 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 07:15:32 INFO - 2015-10-22 07:15:32,101 DEBUG : BROWSER_OUTPUT: setAncestorsLastModified<@resource://gre/modules/Bookmarks.jsm:1347:9 07:15:32 INFO - 2015-10-22 07:15:32,101 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 07:15:32 INFO - 2015-10-22 07:15:32,101 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 07:15:32 INFO - 2015-10-22 07:15:32,101 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 07:15:32 INFO - 2015-10-22 07:15:32,101 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:808:13 07:15:32 INFO - 2015-10-22 07:15:32,101 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 07:15:32 INFO - 2015-10-22 07:15:32,101 DEBUG : BROWSER_OUTPUT: Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:934:23 07:15:32 INFO - 2015-10-22 07:15:32,101 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 07:15:32 INFO - 2015-10-22 07:15:32,101 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 07:15:32 INFO - 2015-10-22 07:15:32,101 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 07:15:32 INFO - 2015-10-22 07:15:32,101 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 07:15:32 INFO - 2015-10-22 07:15:32,101 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 07:15:32 INFO - 2015-10-22 07:15:32,101 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 07:15:32 INFO - 2015-10-22 07:15:32,101 DEBUG : BROWSER_OUTPUT: 07:15:32 INFO - 2015-10-22 07:15:32,684 INFO : Browser initialized. 07:15:32 INFO - 2015-10-22 07:15:32,684 INFO : Running cycle 1/1 for kraken test... 07:15:32 INFO - 2015-10-22 07:15:32,684 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmfhdim\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:15:44 INFO - 2015-10-22 07:15:44,078 DEBUG : BROWSER_OUTPUT: RSS: Main: 127627264 07:15:44 INFO - 2015-10-22 07:15:44,078 DEBUG : BROWSER_OUTPUT: 07:15:46 INFO - 2015-10-22 07:15:46,420 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49791/tests/kraken/driver.html?testName=audio-beat-detection) 07:15:46 INFO - 2015-10-22 07:15:46,994 DEBUG : BROWSER_OUTPUT: RSS: Main: 162332672 07:15:46 INFO - 2015-10-22 07:15:46,994 DEBUG : BROWSER_OUTPUT: 07:15:49 INFO - 2015-10-22 07:15:49,589 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49791/tests/kraken/driver.html?testName=audio-dft) 07:15:50 INFO - 2015-10-22 07:15:50,150 DEBUG : BROWSER_OUTPUT: RSS: Main: 165871616 07:15:50 INFO - 2015-10-22 07:15:50,150 DEBUG : BROWSER_OUTPUT: 07:15:52 INFO - 2015-10-22 07:15:52,845 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49791/tests/kraken/driver.html?testName=audio-fft) 07:15:53 INFO - 2015-10-22 07:15:53,413 DEBUG : BROWSER_OUTPUT: RSS: Main: 159744000 07:15:53 INFO - 2015-10-22 07:15:53,413 DEBUG : BROWSER_OUTPUT: 07:15:55 INFO - 2015-10-22 07:15:55,415 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49791/tests/kraken/driver.html?testName=audio-oscillator) 07:15:55 INFO - 2015-10-22 07:15:55,976 DEBUG : BROWSER_OUTPUT: RSS: Main: 155947008 07:15:55 INFO - 2015-10-22 07:15:55,976 DEBUG : BROWSER_OUTPUT: 07:15:58 INFO - 2015-10-22 07:15:58,111 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49791/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:15:58 INFO - 2015-10-22 07:15:58,665 DEBUG : BROWSER_OUTPUT: RSS: Main: 153874432 07:15:58 INFO - 2015-10-22 07:15:58,665 DEBUG : BROWSER_OUTPUT: 07:16:01 INFO - 2015-10-22 07:16:01,766 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49791/tests/kraken/driver.html?testName=imaging-darkroom) 07:16:02 INFO - 2015-10-22 07:16:02,341 DEBUG : BROWSER_OUTPUT: RSS: Main: 151171072 07:16:02 INFO - 2015-10-22 07:16:02,341 DEBUG : BROWSER_OUTPUT: 07:16:05 INFO - 2015-10-22 07:16:05,677 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49791/tests/kraken/driver.html?testName=imaging-desaturate) 07:16:06 INFO - 2015-10-22 07:16:06,244 DEBUG : BROWSER_OUTPUT: RSS: Main: 151748608 07:16:06 INFO - 2015-10-22 07:16:06,244 DEBUG : BROWSER_OUTPUT: 07:16:09 INFO - 2015-10-22 07:16:09,012 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49791/tests/kraken/driver.html?testName=json-parse-financial) 07:16:09 INFO - 2015-10-22 07:16:09,575 DEBUG : BROWSER_OUTPUT: RSS: Main: 151617536 07:16:09 INFO - 2015-10-22 07:16:09,575 DEBUG : BROWSER_OUTPUT: 07:16:11 INFO - 2015-10-22 07:16:11,349 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49791/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:16:11 INFO - 2015-10-22 07:16:11,904 DEBUG : BROWSER_OUTPUT: RSS: Main: 181891072 07:16:11 INFO - 2015-10-22 07:16:11,904 DEBUG : BROWSER_OUTPUT: 07:16:13 INFO - 2015-10-22 07:16:13,759 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49791/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:16:14 INFO - 2015-10-22 07:16:14,326 DEBUG : BROWSER_OUTPUT: RSS: Main: 186826752 07:16:14 INFO - 2015-10-22 07:16:14,326 DEBUG : BROWSER_OUTPUT: 07:16:16 INFO - 2015-10-22 07:16:16,562 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49791/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:16:17 INFO - 2015-10-22 07:16:17,128 DEBUG : BROWSER_OUTPUT: RSS: Main: 191082496 07:16:17 INFO - 2015-10-22 07:16:17,128 DEBUG : BROWSER_OUTPUT: 07:16:19 INFO - 2015-10-22 07:16:19,592 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49791/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:16:20 INFO - 2015-10-22 07:16:20,151 DEBUG : BROWSER_OUTPUT: RSS: Main: 194256896 07:16:20 INFO - 2015-10-22 07:16:20,151 DEBUG : BROWSER_OUTPUT: 07:16:23 INFO - 2015-10-22 07:16:23,145 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49791/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:16:23 INFO - 2015-10-22 07:16:23,706 DEBUG : BROWSER_OUTPUT: RSS: Main: 192937984 07:16:23 INFO - 2015-10-22 07:16:23,706 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,540 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49791/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49791/tests/kraken/driver.html?testName=ai-astar) 07:16:25 INFO - 2015-10-22 07:16:25,796 DEBUG : BROWSER_OUTPUT: RSS: Main: 238563328 07:16:25 INFO - 2015-10-22 07:16:25,796 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,796 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:16:25 INFO - 2015-10-22 07:16:25,796 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:16:25 INFO - 2015-10-22 07:16:25,796 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:16:25 INFO - 2015-10-22 07:16:25,796 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:16:25 INFO - 2015-10-22 07:16:25,796 DEBUG : BROWSER_OUTPUT: |0;ai-astar;98;86;95;99;93;95;96;98;97;101 07:16:25 INFO - 2015-10-22 07:16:25,796 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;132;138;150;172;133;138;140;135;154;132 07:16:25 INFO - 2015-10-22 07:16:25,796 DEBUG : BROWSER_OUTPUT: |2;audio-dft;150;151;151;151;150;152;150;150;151;150 07:16:25 INFO - 2015-10-22 07:16:25,796 DEBUG : BROWSER_OUTPUT: |3;audio-fft;96;90;77;97;85;76;77;76;101;80 07:16:25 INFO - 2015-10-22 07:16:25,796 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;96;112;95;95;95;119;95;98;118 07:16:25 INFO - 2015-10-22 07:16:25,796 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;117;116;116;117;116;116;116;117;116;116 07:16:25 INFO - 2015-10-22 07:16:25,796 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;141;142;138;139;140;139;139;139;139;139 07:16:25 INFO - 2015-10-22 07:16:25,796 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;89;89;89;89;89;89;91;89;89;89 07:16:25 INFO - 2015-10-22 07:16:25,798 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;79;78;79;79;79;79;79;79;78;91 07:16:25 INFO - 2015-10-22 07:16:25,798 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;75;75;75;75;74;74;78;74;74;75 07:16:25 INFO - 2015-10-22 07:16:25,798 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;94;95;96;96;95;99;97;96;96;95 07:16:25 INFO - 2015-10-22 07:16:25,798 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;120;121;121;129;119;120;116;122;119 07:16:25 INFO - 2015-10-22 07:16:25,798 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;183;191;190;190;190;184;192;190;193;184 07:16:25 INFO - 2015-10-22 07:16:25,798 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;84;85;85;83;85;84;84;84;87;83 07:16:25 INFO - 2015-10-22 07:16:25,798 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:16:25 INFO - 2015-10-22 07:16:25,798 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:16:25 INFO - 2015-10-22 07:16:25,798 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,806 07:16:25 INFO - 2015-10-22 07:16:25,798 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:16:25 INFO - 2015-10-22 07:16:25,798 DEBUG : BROWSER_OUTPUT: __startTimestamp1445523385797__endTimestamp 07:16:25 INFO - 2015-10-22 07:16:25,798 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,798 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: Number of tests: 14 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:95.80 Median:96.50 stddev:4.13 (4.3%) stddev-sans-first:4.30 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: Values: 98.0 86.0 95.0 99.0 93.0 95.0 96.0 98.0 97.0 101.0 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.40 Median:139.00 stddev:12.79 (9.2%) stddev-sans-first:13.00 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: Values: 132.0 138.0 150.0 172.0 133.0 138.0 140.0 135.0 154.0 132.0 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:150.60 Median:151.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: Values: 150.0 151.0 151.0 151.0 150.0 152.0 150.0 150.0 151.0 150.0 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:85.50 Median:82.50 stddev:9.77 (11.8%) stddev-sans-first:9.59 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: Values: 96.0 90.0 77.0 97.0 85.0 76.0 77.0 76.0 101.0 80.0 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:101.80 Median:95.00 stddev:10.23 (10.8%) stddev-sans-first:10.55 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 112.0 95.0 95.0 95.0 119.0 95.0 98.0 118.0 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:116.30 Median:116.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: Values: 117.0 116.0 116.0 117.0 116.0 116.0 116.0 117.0 116.0 116.0 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:139.50 Median:139.00 stddev:1.18 (0.8%) stddev-sans-first:1.12 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: Values: 141.0 142.0 138.0 139.0 140.0 139.0 139.0 139.0 139.0 139.0 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:89.20 Median:89.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 89.0 89.0 89.0 89.0 91.0 89.0 89.0 89.0 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,799 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:80.00 Median:79.00 stddev:3.89 (4.9%) stddev-sans-first:4.11 07:16:25 INFO - 2015-10-22 07:16:25,801 DEBUG : BROWSER_OUTPUT: Values: 79.0 78.0 79.0 79.0 79.0 79.0 79.0 79.0 78.0 91.0 07:16:25 INFO - 2015-10-22 07:16:25,801 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,801 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:74.90 Median:75.00 stddev:1.20 (1.6%) stddev-sans-first:1.27 07:16:25 INFO - 2015-10-22 07:16:25,801 DEBUG : BROWSER_OUTPUT: Values: 75.0 75.0 75.0 75.0 74.0 74.0 78.0 74.0 74.0 75.0 07:16:25 INFO - 2015-10-22 07:16:25,801 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,801 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:95.90 Median:96.00 stddev:1.37 (1.4%) stddev-sans-first:1.27 07:16:25 INFO - 2015-10-22 07:16:25,801 DEBUG : BROWSER_OUTPUT: Values: 94.0 95.0 96.0 96.0 95.0 99.0 97.0 96.0 96.0 95.0 07:16:25 INFO - 2015-10-22 07:16:25,801 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,801 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:120.70 Median:120.50 stddev:3.33 (2.8%) stddev-sans-first:3.53 07:16:25 INFO - 2015-10-22 07:16:25,802 DEBUG : BROWSER_OUTPUT: Values: 120.0 120.0 121.0 121.0 129.0 119.0 120.0 116.0 122.0 119.0 07:16:25 INFO - 2015-10-22 07:16:25,802 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,802 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.70 Median:190.00 stddev:3.62 (1.9%) stddev-sans-first:3.20 07:16:25 INFO - 2015-10-22 07:16:25,802 DEBUG : BROWSER_OUTPUT: Values: 183.0 191.0 190.0 190.0 190.0 184.0 192.0 190.0 193.0 184.0 07:16:25 INFO - 2015-10-22 07:16:25,802 DEBUG : BROWSER_OUTPUT: 07:16:25 INFO - 2015-10-22 07:16:25,802 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:84.40 Median:84.50 stddev:1.17 (1.4%) stddev-sans-first:1.24 07:16:25 INFO - 2015-10-22 07:16:25,803 DEBUG : BROWSER_OUTPUT: Values: 84.0 85.0 85.0 83.0 85.0 84.0 84.0 84.0 87.0 83.0 07:16:25 INFO - 2015-10-22 07:16:25,803 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:16:25 INFO - 2015-10-22 07:16:25,803 DEBUG : BROWSER_OUTPUT: 07:16:26 INFO - 2015-10-22 07:16:26,424 INFO : Browser exited with error code: 0 07:16:27 INFO - 2015-10-22 07:16:27,158 INFO : Completed test kraken (00:01:00) 07:16:27 INFO - 2015-10-22 07:16:27,158 INFO : Starting test v8_7 07:16:27 INFO - 2015-10-22 07:16:27,158 DEBUG : operating with platform_type : w7_ 07:16:27 INFO - 2015-10-22 07:16:27,158 INFO : Initialising browser for v8_7 test... 07:16:27 INFO - 2015-10-22 07:16:27,184 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp2lco57\profile http://localhost:49791/getInfo.html 07:16:31 INFO - 2015-10-22 07:16:31,470 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:16:31 INFO - 2015-10-22 07:16:31,470 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:16:31 INFO - 2015-10-22 07:16:31,470 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 07:16:31 INFO - 2015-10-22 07:16:31,470 DEBUG : BROWSER_OUTPUT: __metrics 07:16:31 INFO - 2015-10-22 07:16:31,605 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:16:32 INFO - 2015-10-22 07:16:32,372 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 121: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 07:16:32 INFO - 2015-10-22 07:16:32,588 DEBUG : BROWSER_OUTPUT: console.error: 07:16:32 INFO - 2015-10-22 07:16:32,588 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] 07:16:32 INFO - 2015-10-22 07:16:32,588 DEBUG : BROWSER_OUTPUT: Stack: 07:16:32 INFO - 2015-10-22 07:16:32,588 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 07:16:32 INFO - 2015-10-22 07:16:32,588 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 07:16:32 INFO - 2015-10-22 07:16:32,588 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 07:16:32 INFO - 2015-10-22 07:16:32,588 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 07:16:32 INFO - 2015-10-22 07:16:32,588 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 07:16:32 INFO - 2015-10-22 07:16:32,588 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 07:16:32 INFO - 2015-10-22 07:16:32,588 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 07:16:32 INFO - 2015-10-22 07:16:32,588 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 07:16:32 INFO - 2015-10-22 07:16:32,588 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 07:16:32 INFO - 2015-10-22 07:16:32,588 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 07:16:32 INFO - 2015-10-22 07:16:32,588 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 07:16:32 INFO - 2015-10-22 07:16:32,588 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 07:16:32 INFO - 2015-10-22 07:16:32,588 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6570.28 07:17:08 INFO - RETURN: kraken: 1565.70 07:17:08 INFO - RETURN: v8_7: 20636.19 07:17:08 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,25]]", "result": "20636.19"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,25]]", "result": "1565.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,25]]", "result": "6570.28"}}} 07:17:08 INFO - Return code: 0 07:17:08 INFO - # TBPL SUCCESS # 07:17:08 INFO - Running post-action listener: _resource_record_post_action 07:17:08 INFO - Running post-run listener: _resource_record_post_run 07:17:09 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 108284416; Write bytes: 444259328; Read time: 1600330; Write time: 5077490 07:17:09 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 16384; Write bytes: 541184; Read time: 660; Write time: 3650 07:17:09 INFO - run-tests - Wall time: 1222s; CPU: 13.0%; Read bytes: 108268032; Write bytes: 420063744; Read time: 1599670; Write time: 5054950 07:17:09 INFO - Running post-run listener: _upload_blobber_files 07:17:09 INFO - Blob upload gear active. 07:17:09 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 07:17:09 INFO - Copying logs to upload dir... 07:17:09 INFO - mkdir: C:\slave\test-pgo\build\upload\logs program finished with exit code 0 elapsedTime=1531.946000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 26 mins, 8 secs) (at 2015-10-22 07:17:45.732926) ========= ========= Started set props: build_url (results: 0, elapsed: 1 mins, 26 secs) (at 2015-10-22 07:17:45.733831) ========= '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-124 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-124 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-124 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/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/ogqb-SWGRu6g7tcaRg23vA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 1 mins, 26 secs) (at 2015-10-22 07:19:12.294337) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 mins, 57 secs) (at 2015-10-22 07:19:12.294670) ========= '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-124 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-124 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-124 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= Finished 'rm -f ...' (results: 0, elapsed: 1 mins, 57 secs) (at 2015-10-22 07:21:09.599581) ========= ========= Started reboot slave lost (results: 0, elapsed: 7 secs) (at 2015-10-22 07:21:09.599910) ========= ========= Finished reboot slave lost (results: 0, elapsed: 7 secs) (at 2015-10-22 07:21:17.588957) =========