builder: fx-team_win7-ix_test-dromaeojs-pgo slave: t-w732-ix-039 starttime: 1445677134.91 results: success (0) buildid: 20151023203058 builduid: b110a6be9b614a8aa508abc04abf952c revision: 04d81b3992f6066a4c72503ffc91cf0826f63428 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-24 01:58:54.910382) ========= master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-24 01:58:54.911751) ========= ========= Started set props: basedir (results: 0, elapsed: 8 secs) (at 2015-10-24 01:58:54.912056) ========= '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-039 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-039 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-039 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.556000 basedir: 'C:\\slave\\test-pgo' ========= Finished set props: basedir (results: 0, elapsed: 8 secs) (at 2015-10-24 01:59:03.150283) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2015-10-24 01:59:03.150658) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2015-10-24 01:59:04.256624) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-24 01:59:04.256972) ========= '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-039 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-039 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-039 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.601000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-24 01:59:05.111561) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-24 01:59:05.111859) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-24 01:59:05.112206) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-24 01:59:05.112492) ========= '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-039 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-039 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-039 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 --01:59:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 2.27 GB/s 01:59:05 (2.27 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.643000 ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-24 01:59:05.813381) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 12 secs) (at 2015-10-24 01:59:05.813944) ========= '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-039 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-039 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-039 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=12.901000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 12 secs) (at 2015-10-24 01:59:18.734883) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-24 01:59:18.735270) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 04d81b3992f6066a4c72503ffc91cf0826f63428 --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 04d81b3992f6066a4c72503ffc91cf0826f63428 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-039 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-039 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-039 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-24 01:59:19,089 truncating revision to first 12 chars 2015-10-24 01:59:19,089 Setting DEBUG logging. 2015-10-24 01:59:19,089 attempt 1/10 2015-10-24 01:59:19,089 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/04d81b3992f6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-24 01:59:19,565 unpacking tar archive at: fx-team-04d81b3992f6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.093000 ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-24 01:59:19.866480) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-24 01:59:19.866894) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-24 01:59:19.882248) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-24 01:59:19.882653) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-24 01:59:19.883147) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 21 secs) (at 2015-10-24 01:59:19.883445) ========= '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-039 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-039 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-039 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 01:59:20 INFO - MultiFileLogger online at 20151024 01:59:20 in C:\slave\test-pgo 01:59:20 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 01:59:20 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 01:59:20 INFO - {'append_to_log': False, 01:59:20 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 01:59:20 INFO - 'blob_upload_branch': 'Fx-Team', 01:59:20 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 01:59:20 INFO - 'branch': 'Fx-Team', 01:59:20 INFO - 'buildbot_json_path': 'buildprops.json', 01:59:20 INFO - 'config_files': ('talos/windows_config.py',), 01:59:20 INFO - 'default_actions': ('clobber', 01:59:20 INFO - 'read-buildbot-config', 01:59:20 INFO - 'download-and-extract', 01:59:20 INFO - 'populate-webroot', 01:59:20 INFO - 'create-virtualenv', 01:59:20 INFO - 'install', 01:59:20 INFO - 'run-tests'), 01:59:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:59:20 INFO - 'download_minidump_stackwalk': True, 01:59:20 INFO - 'download_symbols': 'ondemand', 01:59:20 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 01:59:20 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 01:59:20 INFO - 'hg': 'c:/mozilla-build/hg/hg', 01:59:20 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 01:59:20 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 01:59:20 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 01:59:20 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 01:59:20 INFO - 'C:/mozilla-build/tooltool.py'), 01:59:20 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 01:59:20 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 01:59:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:59:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:59:20 INFO - 'installer_path': 'installer.exe', 01:59:20 INFO - 'log_level': 'info', 01:59:20 INFO - 'log_name': 'talos', 01:59:20 INFO - 'log_to_console': True, 01:59:20 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 01:59:20 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 01:59:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 01:59:20 INFO - 'opt_config_files': (), 01:59:20 INFO - 'pip_index': False, 01:59:20 INFO - 'sps_profile': False, 01:59:20 INFO - 'sps_profile_interval': 0, 01:59:20 INFO - 'suite': 'dromaeojs', 01:59:20 INFO - 'system_bits': '32', 01:59:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:59:20 INFO - 'title': 'T-W732-IX-039', 01:59:20 INFO - 'use_talos_json': True, 01:59:20 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 01:59:20 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 01:59:20 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 01:59:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:59:20 INFO - 'work_dir': 'build'} 01:59:20 INFO - ##### 01:59:20 INFO - ##### Running clobber step. 01:59:20 INFO - ##### 01:59:20 INFO - Running pre-action listener: _resource_record_pre_action 01:59:20 INFO - Running main action method: clobber 01:59:20 INFO - rmtree: C:\slave\test-pgo\build 01:59:20 INFO - Using _rmtree_windows ... 01:59:20 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 02:02:17 INFO - Running post-action listener: _resource_record_post_action 02:02:17 INFO - ##### 02:02:17 INFO - ##### Running read-buildbot-config step. 02:02:17 INFO - ##### 02:02:17 INFO - Running pre-action listener: _resource_record_pre_action 02:02:17 INFO - Running main action method: read_buildbot_config 02:02:17 INFO - Using buildbot properties: 02:02:17 INFO - { 02:02:17 INFO - "properties": { 02:02:17 INFO - "buildnumber": 54, 02:02:17 INFO - "product": "firefox", 02:02:17 INFO - "script_repo_revision": "production", 02:02:17 INFO - "builddir": "fx-team_win7-ix_test-dromaeojs", 02:02:17 INFO - "repository": "", 02:02:17 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs", 02:02:17 INFO - "buildid": "20151023203058", 02:02:17 INFO - "slavename": "t-w732-ix-039", 02:02:17 INFO - "pgo_build": "True", 02:02:17 INFO - "basedir": "C:\\slave\\test-pgo", 02:02:17 INFO - "project": "", 02:02:17 INFO - "platform": "win7-ix", 02:02:17 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/", 02:02:17 INFO - "slavebuilddir": "test", 02:02:17 INFO - "scheduler": "tests-fx-team-win32-pgo-talos", 02:02:17 INFO - "branch": "fx-team", 02:02:17 INFO - "repo_path": "integration/fx-team", 02:02:17 INFO - "stage_platform": "win32-pgo", 02:02:17 INFO - "builduid": "b110a6be9b614a8aa508abc04abf952c", 02:02:17 INFO - "revision": "04d81b3992f6066a4c72503ffc91cf0826f63428" 02:02:17 INFO - }, 02:02:17 INFO - "sourcestamp": { 02:02:17 INFO - "repository": "", 02:02:17 INFO - "hasPatch": false, 02:02:17 INFO - "project": "", 02:02:17 INFO - "branch": "fx-team-win32-pgo-talos", 02:02:17 INFO - "changes": [ 02:02:17 INFO - { 02:02:17 INFO - "category": null, 02:02:17 INFO - "files": [ 02:02:17 INFO - { 02:02:17 INFO - "url": null, 02:02:17 INFO - "name": "https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 02:02:17 INFO - } 02:02:17 INFO - ], 02:02:17 INFO - "repository": "", 02:02:17 INFO - "rev": "04d81b3992f6066a4c72503ffc91cf0826f63428", 02:02:17 INFO - "who": "sendchange", 02:02:17 INFO - "when": 1445677125, 02:02:17 INFO - "number": 6569708, 02:02:17 INFO - "comments": "", 02:02:17 INFO - "project": "", 02:02:17 INFO - "at": "Sat 24 Oct 2015 01:58:45", 02:02:17 INFO - "branch": "fx-team-win32-pgo-talos", 02:02:17 INFO - "revlink": "", 02:02:17 INFO - "properties": [ 02:02:17 INFO - [ 02:02:17 INFO - "buildid", 02:02:17 INFO - "20151023203058", 02:02:17 INFO - "Change" 02:02:17 INFO - ], 02:02:17 INFO - [ 02:02:17 INFO - "builduid", 02:02:17 INFO - "b110a6be9b614a8aa508abc04abf952c", 02:02:17 INFO - "Change" 02:02:17 INFO - ], 02:02:17 INFO - [ 02:02:17 INFO - "pgo_build", 02:02:17 INFO - "True", 02:02:17 INFO - "Change" 02:02:17 INFO - ] 02:02:17 INFO - ], 02:02:17 INFO - "revision": "04d81b3992f6066a4c72503ffc91cf0826f63428" 02:02:17 INFO - } 02:02:17 INFO - ], 02:02:17 INFO - "revision": "04d81b3992f6066a4c72503ffc91cf0826f63428" 02:02:17 INFO - } 02:02:17 INFO - } 02:02:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 02:02:17 INFO - Running post-action listener: _resource_record_post_action 02:02:17 INFO - ##### 02:02:17 INFO - ##### Running download-and-extract step. 02:02:17 INFO - ##### 02:02:17 INFO - Running pre-action listener: _resource_record_pre_action 02:02:17 INFO - Running main action method: download_and_extract 02:02:17 INFO - mkdir: C:\slave\test-pgo\build\tests 02:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:17 INFO - https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 02:02:17 INFO - trying https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/test_packages.json 02:02:17 INFO - Downloading https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 02:02:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 02:02:19 INFO - Downloaded 1221 bytes. 02:02:19 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 02:02:19 INFO - Using the following test package requirements: 02:02:19 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 02:02:19 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:02:19 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 02:02:19 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:02:19 INFO - u'jsshell-win32.zip'], 02:02:19 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:02:19 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 02:02:19 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 02:02:19 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:02:19 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 02:02:19 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:02:19 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 02:02:19 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:02:19 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 02:02:19 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 02:02:19 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:02:19 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 02:02:19 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 02:02: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')]} 02:02:19 INFO - https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 02:02:19 INFO - trying https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 02:02:19 INFO - Downloading https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 02:02:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'}, attempt #1 02:02:20 INFO - Downloaded 18011086 bytes. 02:02:20 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] in C:\slave\test-pgo\build\tests 02:02:20 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 02:02:20 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 02:02:56 INFO - Return code: 0 02:02:56 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip', u'firefox-44.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 02:02: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')]} 02:02:56 INFO - https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 02:02:56 INFO - trying https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 02:02:56 INFO - Downloading https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 02:02:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'}, attempt #1 02:02:58 INFO - Downloaded 18011086 bytes. 02:02:58 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] in C:\slave\test-pgo\build\tests 02:02:58 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 02:02:58 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 02:03:20 INFO - Return code: 0 02:03: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')]} 02:03:20 INFO - https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 02:03:20 INFO - trying https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 02:03:20 INFO - Downloading https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip 02:03:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'}, attempt #1 02:03:22 INFO - Downloaded 11131307 bytes. 02:03:22 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] in C:\slave\test-pgo\build\tests 02:03:22 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip 02:03:22 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 02:03:26 INFO - Return code: 0 02:03:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:26 INFO - https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 02:03:26 INFO - trying https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 02:03:26 INFO - Downloading https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip 02:03:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.zip'}, attempt #1 02:03:29 INFO - Downloaded 53144561 bytes. 02:03:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 02:03:29 INFO - mkdir: C:\slave\test-pgo\properties 02:03:29 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 02:03:29 INFO - Writing to file C:\slave\test-pgo\properties\build_url 02:03:29 INFO - Contents: 02:03:29 INFO - build_url:https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 02:03:29 INFO - Running post-action listener: _resource_record_post_action 02:03:29 INFO - Running post-action listener: set_extra_try_arguments 02:03:29 INFO - ##### 02:03:29 INFO - ##### Running populate-webroot step. 02:03:29 INFO - ##### 02:03:29 INFO - Running pre-action listener: _resource_record_pre_action 02:03:29 INFO - Running main action method: populate_webroot 02:03:29 INFO - Guessing talos json url... 02:03:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:29 INFO - https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 02:03:29 INFO - trying https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 02:03:29 INFO - Downloading https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 02:03:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.txt', 'file_name': 'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.txt'}, attempt #1 02:03:30 INFO - Downloaded 103 bytes. 02:03:30 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 02:03:30 INFO - Contents: 02:03:30 INFO - 20151023203058 02:03:30 INFO - https://hg.mozilla.org/integration/fx-team/rev/04d81b3992f6066a4c72503ffc91cf0826f63428 02:03:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:30 INFO - trying https://hg.mozilla.org/integration/fx-team/raw-file/04d81b3992/testing/talos/talos.json 02:03:30 INFO - Downloading https://hg.mozilla.org/integration/fx-team/raw-file/04d81b3992/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json 02:03:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/fx-team/raw-file/04d81b3992/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1 02:03:30 INFO - Downloaded 4264 bytes. 02:03:30 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 02:03:30 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 02:03:30 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 02:03:30 INFO - u'../fennec_ids.txt'], 02:03:30 INFO - u'tests': [u'tcheck2']}, 02:03:30 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 02:03:30 INFO - u'--tppagecycles', 02:03:30 INFO - u'7'], 02:03:30 INFO - u'tests': [u'tsvgm']}}, 02:03:30 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 02:03:30 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 02:03:30 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 02:03:30 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 02:03:30 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 02:03:30 INFO - u'tests': [u'dromaeo_css', 02:03:30 INFO - u'kraken', 02:03:30 INFO - u'v8_7']}, 02:03:30 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:30 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 02:03:30 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:30 INFO - u'talos_options': [u'--e10s'], 02:03:30 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 02:03:30 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:30 INFO - u'tests': [u'damp', u'tps']}, 02:03:30 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:30 INFO - u'talos_options': [u'--e10s'], 02:03:30 INFO - u'tests': [u'damp', u'tps']}, 02:03:30 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 02:03:30 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 02:03:30 INFO - u'other': {u'tests': [u'a11yr', 02:03:30 INFO - u'ts_paint', 02:03:30 INFO - u'tpaint', 02:03:30 INFO - u'sessionrestore', 02:03:30 INFO - u'sessionrestore_no_auto_restore']}, 02:03:30 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 02:03:30 INFO - u'tests': [u'a11yr', 02:03:30 INFO - u'ts_paint', 02:03:30 INFO - u'tpaint', 02:03:30 INFO - u'sessionrestore', 02:03:30 INFO - u'sessionrestore_no_auto_restore']}, 02:03:30 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 02:03:30 INFO - u'tests': [u'a11yr', 02:03:30 INFO - u'ts_paint', 02:03:30 INFO - u'tpaint', 02:03:30 INFO - u'sessionrestore', 02:03:30 INFO - u'sessionrestore_no_auto_restore']}, 02:03:30 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 02:03:30 INFO - u'tests': [u'a11yr', 02:03:30 INFO - u'ts_paint', 02:03:30 INFO - u'tpaint', 02:03:30 INFO - u'sessionrestore', 02:03:30 INFO - u'sessionrestore_no_auto_restore']}, 02:03:30 INFO - u'other_l64': {u'tests': [u'a11yr', 02:03:30 INFO - u'ts_paint', 02:03:30 INFO - u'tpaint', 02:03:30 INFO - u'sessionrestore', 02:03:30 INFO - u'sessionrestore_no_auto_restore']}, 02:03:30 INFO - u'other_nol64': {u'tests': [u'a11yr', 02:03:30 INFO - u'ts_paint', 02:03:30 INFO - u'tpaint', 02:03:30 INFO - u'sessionrestore', 02:03:30 INFO - u'sessionrestore_no_auto_restore']}, 02:03:30 INFO - u'svgr': {u'tests': [u'tsvgx', 02:03:30 INFO - u'tsvgr_opacity', 02:03:30 INFO - u'tart', 02:03:30 INFO - u'tscrollx', 02:03:30 INFO - u'cart']}, 02:03:30 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 02:03:30 INFO - u'tests': [u'tsvgx', 02:03:30 INFO - u'tsvgr_opacity', 02:03:30 INFO - u'tart', 02:03:30 INFO - u'tscrollx', 02:03:30 INFO - u'cart']}, 02:03:30 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:30 INFO - u'tests': [u'tp5o']}, 02:03:30 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:30 INFO - u'talos_options': [u'--e10s'], 02:03:30 INFO - u'tests': [u'tp5o']}, 02:03:30 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:30 INFO - u'talos_options': [u'--xperf_path', 02:03:30 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:03:30 INFO - u'tests': [u'tp5n']}, 02:03:30 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:30 INFO - u'talos_options': [u'--e10s', 02:03:30 INFO - u'--xperf_path', 02:03:30 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 02:03:30 INFO - u'C:/slave/talos-data/talos/xperf.config'], 02:03:30 INFO - u'tests': [u'tp5n']}}, 02:03:30 INFO - u'talos.zip': {u'path': u'', 02:03:30 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:03:30 INFO - Running post-action listener: _resource_record_post_action 02:03:30 INFO - ##### 02:03:30 INFO - ##### Running create-virtualenv step. 02:03:30 INFO - ##### 02:03:30 INFO - Running pre-action listener: _resource_record_pre_action 02:03:30 INFO - Running main action method: create_virtualenv 02:03:30 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 02:03:30 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 02:03:30 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 02:03:30 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'! 02:03:30 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test-pgo\\build/venv'] in C:\slave\test-pgo\build 02:03:30 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test-pgo\build/venv 02:03:38 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 02:03:41 INFO - Installing distribute......................................................................................................................................................................................done. 02:03:42 INFO - Return code: 0 02:03:42 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 02:03:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:42 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01928B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012DE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x011F8B48>, '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 0x0121A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 02:03:43 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test-pgo\build 02:03:43 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:03:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:03:43 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:03:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:03:43 INFO - 'COMPUTERNAME': 'T-W732-IX-039', 02:03:43 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:03:43 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:03:43 INFO - 'DCLOCATION': 'SCL3', 02:03:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:03:43 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:03:43 INFO - 'HOMEDRIVE': 'C:', 02:03:43 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:03:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:03:43 INFO - 'KTS_VERSION': '1.19c', 02:03:43 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:03:43 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039', 02:03:43 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:03:43 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:03:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:43 INFO - 'MOZ_NO_REMOTE': '1', 02:03:43 INFO - 'NO_EM_RESTART': '1', 02:03:43 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:03:43 INFO - 'OS': 'Windows_NT', 02:03:43 INFO - 'OURDRIVE': 'C:', 02:03:43 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:03:43 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:03:43 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:03:43 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:03:43 INFO - 'PROCESSOR_LEVEL': '6', 02:03:43 INFO - 'PROCESSOR_REVISION': '1e05', 02:03:43 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:03:43 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:03:43 INFO - 'PROMPT': '$P$G', 02:03:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 02:03:43 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:03:43 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:03:43 INFO - 'PWD': 'C:\\slave\\test-pgo', 02:03:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:03:43 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:03:43 INFO - 'SYSTEMDRIVE': 'C:', 02:03:43 INFO - 'SYSTEMROOT': 'C:\\windows', 02:03:43 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:03:43 INFO - 'TEST1': 'testie', 02:03:43 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:03:43 INFO - 'USERDOMAIN': 'T-W732-IX-039', 02:03:43 INFO - 'USERNAME': 'cltbld', 02:03:43 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:03:43 INFO - 'WINDIR': 'C:\\windows', 02:03:43 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:03:43 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:03:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:03:43 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:03:43 INFO - Downloading/unpacking pip>=1.5 02:03:43 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache 02:03:48 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpip-1.5.5.tar.gz 02:03:48 INFO - Running setup.py egg_info for package pip 02:03:48 INFO - warning: no files found matching 'pip\cacert.pem' 02:03:48 INFO - warning: no files found matching '*.html' under directory 'docs' 02:03:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 02:03:48 INFO - no previously-included directories found matching 'docs\_build\_sources' 02:03:48 INFO - Installing collected packages: pip 02:03:48 INFO - Found existing installation: pip 0.8.2 02:03:48 INFO - Uninstalling pip: 02:03:48 INFO - Successfully uninstalled pip 02:03:48 INFO - Running setup.py install for pip 02:03:48 INFO - warning: no files found matching 'pip\cacert.pem' 02:03:48 INFO - warning: no files found matching '*.html' under directory 'docs' 02:03:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 02:03:48 INFO - no previously-included directories found matching 'docs\_build\_sources' 02:03:48 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:03:48 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 02:03:48 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:03:48 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 02:03:48 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:03:48 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 02:03:48 INFO - Successfully installed pip 02:03:48 INFO - Cleaning up... 02:03:48 INFO - Return code: 0 02:03:48 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 02:03:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:48 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 0x01928B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012DE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x011F8B48>, '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 0x0121A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 02:03:48 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in C:\slave\test-pgo\build 02:03:48 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:03:48 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:03:48 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:03:48 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:03:48 INFO - 'COMPUTERNAME': 'T-W732-IX-039', 02:03:48 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:03:48 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:03:48 INFO - 'DCLOCATION': 'SCL3', 02:03:48 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:03:48 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:03:48 INFO - 'HOMEDRIVE': 'C:', 02:03:48 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:03:48 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:03:48 INFO - 'KTS_VERSION': '1.19c', 02:03:48 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:03:48 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039', 02:03:48 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:03:48 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:03:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:48 INFO - 'MOZ_NO_REMOTE': '1', 02:03:48 INFO - 'NO_EM_RESTART': '1', 02:03:48 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:03:48 INFO - 'OS': 'Windows_NT', 02:03:48 INFO - 'OURDRIVE': 'C:', 02:03:48 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:03:48 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:03:48 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:03:48 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:03:48 INFO - 'PROCESSOR_LEVEL': '6', 02:03:48 INFO - 'PROCESSOR_REVISION': '1e05', 02:03:48 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:03:48 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:03:48 INFO - 'PROMPT': '$P$G', 02:03:48 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 02:03:48 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:03:48 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:03:48 INFO - 'PWD': 'C:\\slave\\test-pgo', 02:03:48 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:03:48 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:03:48 INFO - 'SYSTEMDRIVE': 'C:', 02:03:48 INFO - 'SYSTEMROOT': 'C:\\windows', 02:03:48 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:03:48 INFO - 'TEST1': 'testie', 02:03:48 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:03:48 INFO - 'USERDOMAIN': 'T-W732-IX-039', 02:03:48 INFO - 'USERNAME': 'cltbld', 02:03:48 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:03:48 INFO - 'WINDIR': 'C:\\windows', 02:03:48 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:03:48 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:03:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:03:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:49 INFO - Downloading/unpacking psutil>=0.7.1 02:03:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:50 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpsutil-3.1.1-cp27-none-win32.whl 02:03:50 INFO - Installing collected packages: psutil 02:03:50 INFO - Successfully installed psutil 02:03:50 INFO - Cleaning up... 02:03:50 INFO - Return code: 0 02:03:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 02:03: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')]} 02:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03: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')]} 02:03:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:50 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01928B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012DE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x011F8B48>, '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 0x0121A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 02:03:50 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in C:\slave\test-pgo\build 02:03:50 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:03:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:03:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:03:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:03:50 INFO - 'COMPUTERNAME': 'T-W732-IX-039', 02:03:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:03:50 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:03:50 INFO - 'DCLOCATION': 'SCL3', 02:03:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:03:50 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:03:50 INFO - 'HOMEDRIVE': 'C:', 02:03:50 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:03:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:03:50 INFO - 'KTS_VERSION': '1.19c', 02:03:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:03:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039', 02:03:50 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:03:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:50 INFO - 'MOZ_NO_REMOTE': '1', 02:03:50 INFO - 'NO_EM_RESTART': '1', 02:03:50 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:03:50 INFO - 'OS': 'Windows_NT', 02:03:50 INFO - 'OURDRIVE': 'C:', 02:03:50 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:03:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:03:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:03:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:03:50 INFO - 'PROCESSOR_LEVEL': '6', 02:03:50 INFO - 'PROCESSOR_REVISION': '1e05', 02:03:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:03:50 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:03:50 INFO - 'PROMPT': '$P$G', 02:03:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 02:03:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:03:50 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:03:50 INFO - 'PWD': 'C:\\slave\\test-pgo', 02:03:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:03:50 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:03:50 INFO - 'SYSTEMDRIVE': 'C:', 02:03:50 INFO - 'SYSTEMROOT': 'C:\\windows', 02:03:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:03:50 INFO - 'TEST1': 'testie', 02:03:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:03:50 INFO - 'USERDOMAIN': 'T-W732-IX-039', 02:03:50 INFO - 'USERNAME': 'cltbld', 02:03:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:03:50 INFO - 'WINDIR': 'C:\\windows', 02:03:50 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:03:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:03:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:03:51 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozsystemmonitor-0.0.tar.gz 02:03:51 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 02:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0) 02:03:51 INFO - Installing collected packages: mozsystemmonitor 02:03:51 INFO - Running setup.py install for mozsystemmonitor 02:03:51 INFO - Successfully installed mozsystemmonitor 02:03:51 INFO - Cleaning up... 02:03:51 INFO - Return code: 0 02:03:51 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 02:03: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')]} 02:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03: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')]} 02:03:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:51 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01928B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012DE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x011F8B48>, '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 0x0121A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 02:03:51 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test-pgo\build 02:03:51 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:03:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:03:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:03:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:03:51 INFO - 'COMPUTERNAME': 'T-W732-IX-039', 02:03:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:03:51 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:03:51 INFO - 'DCLOCATION': 'SCL3', 02:03:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:03:51 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:03:51 INFO - 'HOMEDRIVE': 'C:', 02:03:51 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:03:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:03:51 INFO - 'KTS_VERSION': '1.19c', 02:03:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:03:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039', 02:03:51 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:03:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:03:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:51 INFO - 'MOZ_NO_REMOTE': '1', 02:03:51 INFO - 'NO_EM_RESTART': '1', 02:03:51 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:03:51 INFO - 'OS': 'Windows_NT', 02:03:51 INFO - 'OURDRIVE': 'C:', 02:03:51 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:03:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:03:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:03:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:03:51 INFO - 'PROCESSOR_LEVEL': '6', 02:03:51 INFO - 'PROCESSOR_REVISION': '1e05', 02:03:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:03:51 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:03:51 INFO - 'PROMPT': '$P$G', 02:03:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 02:03:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:03:51 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:03:51 INFO - 'PWD': 'C:\\slave\\test-pgo', 02:03:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:03:51 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:03:51 INFO - 'SYSTEMDRIVE': 'C:', 02:03:51 INFO - 'SYSTEMROOT': 'C:\\windows', 02:03:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:03:51 INFO - 'TEST1': 'testie', 02:03:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:03:51 INFO - 'USERDOMAIN': 'T-W732-IX-039', 02:03:51 INFO - 'USERNAME': 'cltbld', 02:03:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:03:51 INFO - 'WINDIR': 'C:\\windows', 02:03:51 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:03:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:03:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:03:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:52 INFO - Downloading/unpacking blobuploader==1.2.4 02:03:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:53 INFO - Downloading blobuploader-1.2.4.tar.gz 02:03:53 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblobuploader-1.2.4.tar.gz 02:03:53 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 02:03:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:03:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:53 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2frequests-1.2.3.tar.gz 02:03:53 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 02:03:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:03:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:53 INFO - Downloading docopt-0.6.1.tar.gz 02:03:53 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fdocopt-0.6.1.tar.gz 02:03:53 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 02:03:54 INFO - Installing collected packages: blobuploader, requests, docopt 02:03:54 INFO - Running setup.py install for blobuploader 02:03:54 INFO - Running setup.py install for requests 02:03:54 INFO - Running setup.py install for docopt 02:03:55 INFO - Successfully installed blobuploader requests docopt 02:03:55 INFO - Cleaning up... 02:03:56 INFO - Return code: 0 02:03:56 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 02:03: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')]} 02:03:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03: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')]} 02:03:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:56 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01928B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012DE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x011F8B48>, '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 0x0121A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 02:03:56 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config 02:03:56 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --no-deps --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:03:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:03:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:03:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:03:56 INFO - 'COMPUTERNAME': 'T-W732-IX-039', 02:03:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:03:56 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:03:56 INFO - 'DCLOCATION': 'SCL3', 02:03:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:03:56 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:03:56 INFO - 'HOMEDRIVE': 'C:', 02:03:56 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:03:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:03:56 INFO - 'KTS_VERSION': '1.19c', 02:03:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:03:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039', 02:03:56 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:03:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:03:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:56 INFO - 'MOZ_NO_REMOTE': '1', 02:03:56 INFO - 'NO_EM_RESTART': '1', 02:03:56 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:03:56 INFO - 'OS': 'Windows_NT', 02:03:56 INFO - 'OURDRIVE': 'C:', 02:03:56 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:03:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:03:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:03:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:03:56 INFO - 'PROCESSOR_LEVEL': '6', 02:03:56 INFO - 'PROCESSOR_REVISION': '1e05', 02:03:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:03:56 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:03:56 INFO - 'PROMPT': '$P$G', 02:03:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 02:03:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:03:56 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:03:56 INFO - 'PWD': 'C:\\slave\\test-pgo', 02:03:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:03:56 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:03:56 INFO - 'SYSTEMDRIVE': 'C:', 02:03:56 INFO - 'SYSTEMROOT': 'C:\\windows', 02:03:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:03:56 INFO - 'TEST1': 'testie', 02:03:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:03:56 INFO - 'USERDOMAIN': 'T-W732-IX-039', 02:03:56 INFO - 'USERNAME': 'cltbld', 02:03:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:03:56 INFO - 'WINDIR': 'C:\\windows', 02:03:56 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:03:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:03:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:03:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:56 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 02:03:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bjgkah-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 02:03:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 02:03:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n2mpcj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 02:03:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 02:03:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nlghsf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 02:03:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 02:03:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sclryc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 02:03:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 02:03:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eamipm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 02:03:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 02:03:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b0d004-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 02:03:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 02:03:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-z0ctzv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 02:03:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 02:03:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rboyry-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 02:03:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 02:03:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oeltrh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 02:03:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 02:03:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xiu5y_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 02:03:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 02:03:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vcdazk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 02:03:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 02:03:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wnoumd-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 02:03:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 02:03:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-omuslb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 02:03:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 02:03:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iq1nvj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 02:03:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 02:03:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gonxcy-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 02:03:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 02:03:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yg3ozg-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 02:03:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 02:03:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-unuybr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 02:03:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:03:59 INFO - Running setup.py install for manifestparser 02:03:59 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:03:59 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 02:03:59 INFO - Running setup.py install for mozcrash 02:04:00 INFO - Running setup.py install for mozdebug 02:04:01 INFO - Running setup.py install for mozdevice 02:04:01 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:04:01 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 02:04:01 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:04:01 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 02:04:01 INFO - Running setup.py install for mozfile 02:04:02 INFO - Running setup.py install for mozhttpd 02:04:02 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:04:02 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 02:04:02 INFO - Running setup.py install for mozinfo 02:04:02 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:04:02 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 02:04:02 INFO - Running setup.py install for mozInstall 02:04:02 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:04:02 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 02:04:02 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:04:02 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 02:04:02 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:04:02 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 02:04:02 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:04:02 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 02:04:03 INFO - Running setup.py install for mozleak 02:04:03 INFO - Running setup.py install for mozlog 02:04:03 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:04:03 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 02:04:04 INFO - Running setup.py install for moznetwork 02:04:04 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:04:04 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 02:04:04 INFO - Running setup.py install for mozprocess 02:04:05 INFO - Running setup.py install for mozprofile 02:04:05 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:04:05 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 02:04:05 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:04:05 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 02:04:05 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:04:05 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 02:04:05 INFO - Running setup.py install for mozrunner 02:04:06 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:04:06 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 02:04:06 INFO - Running setup.py install for mozscreenshot 02:04:06 INFO - Running setup.py install for moztest 02:04:07 INFO - Running setup.py install for mozversion 02:04:07 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 02:04:07 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 02:04:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:04:07 INFO - Cleaning up... 02:04:07 INFO - Return code: 0 02:04:07 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 02:04:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:04:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:04:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:07 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01928B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012DE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x011F8B48>, '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 0x0121A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 02:04:07 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config 02:04:07 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:04:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:04:07 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:04:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:04:07 INFO - 'COMPUTERNAME': 'T-W732-IX-039', 02:04:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:04:07 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:04:07 INFO - 'DCLOCATION': 'SCL3', 02:04:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:04:07 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:04:07 INFO - 'HOMEDRIVE': 'C:', 02:04:07 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:04:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:04:07 INFO - 'KTS_VERSION': '1.19c', 02:04:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:04:07 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039', 02:04:07 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:04:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:04:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:07 INFO - 'MOZ_NO_REMOTE': '1', 02:04:07 INFO - 'NO_EM_RESTART': '1', 02:04:07 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:04:07 INFO - 'OS': 'Windows_NT', 02:04:07 INFO - 'OURDRIVE': 'C:', 02:04:07 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:04:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:04:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:04:07 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:04:07 INFO - 'PROCESSOR_LEVEL': '6', 02:04:07 INFO - 'PROCESSOR_REVISION': '1e05', 02:04:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:04:07 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:04:07 INFO - 'PROMPT': '$P$G', 02:04:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 02:04:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:04:07 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:04:07 INFO - 'PWD': 'C:\\slave\\test-pgo', 02:04:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:04:07 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:04:07 INFO - 'SYSTEMDRIVE': 'C:', 02:04:07 INFO - 'SYSTEMROOT': 'C:\\windows', 02:04:07 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:04:07 INFO - 'TEST1': 'testie', 02:04:07 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:04:07 INFO - 'USERDOMAIN': 'T-W732-IX-039', 02:04:07 INFO - 'USERNAME': 'cltbld', 02:04:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:04:07 INFO - 'WINDIR': 'C:\\windows', 02:04:07 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:04:07 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:04:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:04:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 02:04:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rcquq_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 02:04:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 1)) 02:04:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 02:04:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iecd7v-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 02:04:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 02:04:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 02:04:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zbhu3b-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 02:04:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 02:04:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 02:04:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mvskkf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 02:04:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 02:04:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 02:04:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1tpllc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 02:04:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 5)) 02:04:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 02:04:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bg9neo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 02:04:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 6)) 02:04:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 02:04:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hhzczb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 02:04:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 7)) 02:04:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 02:04:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ldn9ei-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 02:04:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 8)) 02:04:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 02:04:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mqnpow-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 02:04:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 9)) 02:04:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 02:04:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rqbkxn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 02:04:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 02:04:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 02:04:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1ijptt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 02:04:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 11)) 02:04:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 02:04:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-swdjpx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 02:04:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 12)) 02:04:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 02:04:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cv7mdb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 02:04:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 13)) 02:04:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 02:04:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cfp73o-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 02:04:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14)) 02:04:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 02:04:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sfht7u-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 02:04:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 15)) 02:04:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 02:04:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ypf8ns-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 02:04:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/moztest in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 16)) 02:04:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 02:04:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-enomks-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 02:04:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 17)) 02:04:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 02:04:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 02:04:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 02:04:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 02:04:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 02:04:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 02:04:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:11 INFO - Downloading blessings-1.5.1.tar.gz 02:04:11 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblessings-1.5.1.tar.gz 02:04:11 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 02:04:11 INFO - Installing collected packages: blessings 02:04:11 INFO - Running setup.py install for blessings 02:04:12 INFO - Successfully installed blessings 02:04:12 INFO - Cleaning up... 02:04:14 INFO - Return code: 0 02:04:14 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 02:04:14 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 02:04:14 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 02:04:15 INFO - Reading from file tmpfile_stdout 02:04:15 INFO - Using _rmtree_windows ... 02:04:15 INFO - Using _rmtree_windows ... 02:04:15 INFO - Current package versions: 02:04:15 INFO - blessings == 1.5.1 02:04:15 INFO - blobuploader == 1.2.4 02:04:15 INFO - docopt == 0.6.1 02:04:15 INFO - manifestparser == 1.1 02:04:15 INFO - mozInstall == 1.12 02:04:15 INFO - mozcrash == 0.16 02:04:15 INFO - mozdebug == 0.1 02:04:15 INFO - mozdevice == 0.46 02:04:15 INFO - mozfile == 1.2 02:04:15 INFO - mozhttpd == 0.7 02:04:15 INFO - mozinfo == 0.8 02:04:15 INFO - mozleak == 0.1 02:04:15 INFO - mozlog == 3.0 02:04:15 INFO - moznetwork == 0.27 02:04:15 INFO - mozprocess == 0.22 02:04:15 INFO - mozprofile == 0.27 02:04:15 INFO - mozrunner == 6.11 02:04:15 INFO - mozscreenshot == 0.1 02:04:15 INFO - mozsystemmonitor == 0.0 02:04:15 INFO - moztest == 0.7 02:04:15 INFO - mozversion == 1.4 02:04:15 INFO - psutil == 3.1.1 02:04:15 INFO - requests == 1.2.3 02:04:15 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 02:04:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:04:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:04:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:15 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-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 0x01928B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012DE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x011F8B48>, '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 0x0121A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 02:04:15 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\talos 02:04:15 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:04:15 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:04:15 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:04:15 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:04:15 INFO - 'COMPUTERNAME': 'T-W732-IX-039', 02:04:15 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:04:15 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:04:15 INFO - 'DCLOCATION': 'SCL3', 02:04:15 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:04:15 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:04:15 INFO - 'HOMEDRIVE': 'C:', 02:04:15 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:04:15 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:04:15 INFO - 'KTS_VERSION': '1.19c', 02:04:15 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:04:15 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039', 02:04:15 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:04:15 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:04:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:15 INFO - 'MOZ_NO_REMOTE': '1', 02:04:15 INFO - 'NO_EM_RESTART': '1', 02:04:15 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:04:15 INFO - 'OS': 'Windows_NT', 02:04:15 INFO - 'OURDRIVE': 'C:', 02:04:15 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:04:15 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:04:15 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:04:15 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:04:15 INFO - 'PROCESSOR_LEVEL': '6', 02:04:15 INFO - 'PROCESSOR_REVISION': '1e05', 02:04:15 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:04:15 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:04:15 INFO - 'PROMPT': '$P$G', 02:04:15 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 02:04:15 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:04:15 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:04:15 INFO - 'PWD': 'C:\\slave\\test-pgo', 02:04:15 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:04:15 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:04:15 INFO - 'SYSTEMDRIVE': 'C:', 02:04:15 INFO - 'SYSTEMROOT': 'C:\\windows', 02:04:15 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:04:15 INFO - 'TEST1': 'testie', 02:04:15 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:04:15 INFO - 'USERDOMAIN': 'T-W732-IX-039', 02:04:15 INFO - 'USERNAME': 'cltbld', 02:04:15 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:04:15 INFO - 'WINDIR': 'C:\\windows', 02:04:15 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:04:15 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:04:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:04:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 02:04:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 2)) 02:04:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3)) 02:04:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 4)) 02:04:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 5)) 02:04:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 6)) 02:04:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 7)) 02:04:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 8)) 02:04:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash>=0.15->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 02:04:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3)) 02:04:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozlog>=3.0->mozcrash>=0.15->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 02:04:15 INFO - Cleaning up... 02:04:15 INFO - Return code: 0 02:04:15 INFO - Running post-action listener: _resource_record_post_action 02:04:15 INFO - Running post-action listener: _start_resource_monitoring 02:04:15 INFO - Starting resource monitoring. 02:04:15 INFO - ##### 02:04:15 INFO - ##### Running install step. 02:04:15 INFO - ##### 02:04:15 INFO - Running pre-action listener: _resource_record_pre_action 02:04:15 INFO - Running main action method: install 02:04:15 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 02:04:15 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 02:04:16 INFO - Reading from file tmpfile_stdout 02:04:16 INFO - Using _rmtree_windows ... 02:04:16 INFO - Using _rmtree_windows ... 02:04:16 INFO - Detecting whether we're running mozinstall >=1.0... 02:04:16 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 02:04:16 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 02:04:16 INFO - Reading from file tmpfile_stdout 02:04:16 INFO - Output received: 02:04:16 INFO - Usage: mozinstall-script.py [options] installer 02:04:16 INFO - Options: 02:04:16 INFO - -h, --help show this help message and exit 02:04:16 INFO - -d DEST, --destination=DEST 02:04:16 INFO - Directory to install application into. [default: 02:04:16 INFO - "C:\slave\test-pgo"] 02:04:16 INFO - --app=APP Application being installed. [default: firefox] 02:04:16 INFO - Using _rmtree_windows ... 02:04:16 INFO - Using _rmtree_windows ... 02:04:16 INFO - mkdir: C:\slave\test-pgo\build\application 02:04:16 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test-pgo\\build\\application'] 02:04:16 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip --destination C:\slave\test-pgo\build\application 02:04:17 INFO - Reading from file tmpfile_stdout 02:04:17 INFO - Output received: 02:04:17 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 02:04:17 INFO - Using _rmtree_windows ... 02:04:17 INFO - Using _rmtree_windows ... 02:04:17 INFO - Running post-action listener: _resource_record_post_action 02:04:17 INFO - ##### 02:04:17 INFO - ##### Running run-tests step. 02:04:17 INFO - ##### 02:04:17 INFO - Running pre-action listener: _resource_record_pre_action 02:04:17 INFO - Running main action method: run_tests 02:04:17 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 02:04:17 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 02:04:17 INFO - Python 2.7.3 02:04:17 INFO - Return code: 0 02:04:17 INFO - grabbing minidump binary from tooltool 02:04:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:04:17 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x011F8B48>, '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 0x0121A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A9660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 02:04:17 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test-pgo\build 02:04:17 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test-pgo\build\tests\config/tooltool-manifests/win32/releng.manifest -o 02:04:17 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 02:04:19 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpjpkcja 02:04:19 INFO - INFO - File integrity verified, renaming tmpjpkcja to win32-minidump_stackwalk.exe 02:04:19 INFO - Return code: 0 02:04:19 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 02:04:19 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 02:04:19 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 02:04:19 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 02:04:19 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 02:04:19 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/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-039', '--webServer', 'localhost'] in C:\slave\test-pgo\build 02:04:19 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/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-039 --webServer localhost 02:04:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:04:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:04:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:04:19 INFO - 'COMPUTERNAME': 'T-W732-IX-039', 02:04:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:04:19 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:04:19 INFO - 'DCLOCATION': 'SCL3', 02:04:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:04:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:04:19 INFO - 'HOMEDRIVE': 'C:', 02:04:19 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:04:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:04:19 INFO - 'KTS_VERSION': '1.19c', 02:04:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:04:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039', 02:04:19 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 02:04:19 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 02:04:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:04:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:04:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:19 INFO - 'MOZ_NO_REMOTE': '1', 02:04:19 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 02:04:19 INFO - 'NO_EM_RESTART': '1', 02:04:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:04:19 INFO - 'OS': 'Windows_NT', 02:04:19 INFO - 'OURDRIVE': 'C:', 02:04: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;', 02:04:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:04:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:04:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:04:19 INFO - 'PROCESSOR_LEVEL': '6', 02:04:19 INFO - 'PROCESSOR_REVISION': '1e05', 02:04:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:04:19 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:04:19 INFO - 'PROMPT': '$P$G', 02:04:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 02:04:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:04:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:04:19 INFO - 'PWD': 'C:\\slave\\test-pgo', 02:04:19 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 02:04:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:04:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:04:19 INFO - 'SYSTEMDRIVE': 'C:', 02:04:19 INFO - 'SYSTEMROOT': 'C:\\windows', 02:04:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:04:19 INFO - 'TEST1': 'testie', 02:04:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:04:19 INFO - 'USERDOMAIN': 'T-W732-IX-039', 02:04:19 INFO - 'USERNAME': 'cltbld', 02:04:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:04:19 INFO - 'WINDIR': 'C:\\windows', 02:04:19 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:04:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:04:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:04:19 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/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-039', '--webServer', 'localhost'] with output_timeout 3600 02:04:20 INFO - mozversion INFO | application_buildid: 20151023203058 02:04:20 INFO - mozversion INFO | application_changeset: 04d81b3992f6066a4c72503ffc91cf0826f63428 02:04:20 INFO - mozversion INFO | application_display_name: Nightly 02:04:20 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:04:20 INFO - mozversion INFO | application_name: Firefox 02:04:20 INFO - mozversion INFO | application_remotingname: firefox 02:04:20 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 02:04:20 INFO - mozversion INFO | application_vendor: Mozilla 02:04:20 INFO - mozversion INFO | application_version: 44.0a1 02:04:20 INFO - mozversion INFO | platform_buildid: 20151023203058 02:04:20 INFO - mozversion INFO | platform_changeset: 04d81b3992f6066a4c72503ffc91cf0826f63428 02:04:20 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 02:04:20 INFO - mozversion INFO | platform_version: 44.0a1 02:04:20 INFO - 2015-10-24 02:04:20,417 DEBUG : using testdate: 1445677460 02:04:20 INFO - 2015-10-24 02:04:20,417 DEBUG : actual date: 1445677460 02:04:20 INFO - 2015-10-24 02:04:20,427 INFO : starting webserver on 'localhost:49893' 02:04:20 INFO - 2015-10-24 02:04:20,428 INFO : Starting test suite T-W732-IX-039 02:04:20 INFO - 2015-10-24 02:04:20,428 INFO : Starting test dromaeo_css 02:04:20 INFO - 2015-10-24 02:04:20,428 DEBUG : operating with platform_type : w7_ 02:04:20 INFO - 2015-10-24 02:04:20,430 INFO : Initialising browser for dromaeo_css test... 02:04:20 INFO - 2015-10-24 02:04:20,519 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpbefvk5\profile http://localhost:49893/getInfo.html 02:04:28 INFO - 2015-10-24 02:04:28,792 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:04:28 INFO - 2015-10-24 02:04:28,792 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:04:28 INFO - 2015-10-24 02:04:28,792 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 02:04:28 INFO - 2015-10-24 02:04:28,792 DEBUG : BROWSER_OUTPUT: __metrics 02:04:28 INFO - 2015-10-24 02:04:28,891 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:04:30 INFO - 2015-10-24 02:04:30,844 INFO : Browser initialized. 02:04:30 INFO - 2015-10-24 02:04:30,844 INFO : Running cycle 1/1 for dromaeo_css test... 02:04:30 INFO - 2015-10-24 02:04:30,844 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpbefvk5\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 02:04:42 INFO - 2015-10-24 02:04:42,255 DEBUG : BROWSER_OUTPUT: RSS: Main: 130052096 02:04:42 INFO - 2015-10-24 02:04:42,255 DEBUG : BROWSER_OUTPUT: 02:05:36 INFO - 2015-10-24 02:05:36,581 DEBUG : BROWSER_OUTPUT: 1445677536592 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:07:42 INFO - 2015-10-24 02:07:42,907 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49893/tests/dromaeo/cssquery-ext.html) 02:07:43 INFO - 2015-10-24 02:07:43,943 DEBUG : BROWSER_OUTPUT: RSS: Main: 172810240 02:07:43 INFO - 2015-10-24 02:07:43,943 DEBUG : BROWSER_OUTPUT: 02:10:46 INFO - 2015-10-24 02:10:46,177 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-ext.html (next: http://localhost:49893/tests/dromaeo/cssquery-jquery.html) 02:10:46 INFO - 2015-10-24 02:10:46,733 DEBUG : BROWSER_OUTPUT: RSS: Main: 194871296 02:10:46 INFO - 2015-10-24 02:10:46,733 DEBUG : BROWSER_OUTPUT: 02:13:48 INFO - 2015-10-24 02:13:48,628 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49893/tests/dromaeo/cssquery-mootools.html) 02:13:49 INFO - 2015-10-24 02:13:49,203 DEBUG : BROWSER_OUTPUT: RSS: Main: 179220480 02:13:49 INFO - 2015-10-24 02:13:49,203 DEBUG : BROWSER_OUTPUT: 02:16:51 INFO - 2015-10-24 02:16:51,078 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49893/tests/dromaeo/cssquery-prototype.html) 02:16:51 INFO - 2015-10-24 02:16:51,661 DEBUG : BROWSER_OUTPUT: RSS: Main: 139735040 02:16:51 INFO - 2015-10-24 02:16:51,661 DEBUG : BROWSER_OUTPUT: 02:19:54 INFO - 2015-10-24 02:19:54,407 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49893/tests/dromaeo/cssquery-yui.html) 02:19:54 INFO - 2015-10-24 02:19:54,986 DEBUG : BROWSER_OUTPUT: RSS: Main: 130416640 02:19:54 INFO - 2015-10-24 02:19:54,986 DEBUG : BROWSER_OUTPUT: 02:22:57 INFO - 2015-10-24 02:22:57,516 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-yui.html (next: http://localhost:49893/tests/dromaeo/cssquery-dojo.html) 02:22:57 INFO - 2015-10-24 02:22:57,772 DEBUG : BROWSER_OUTPUT: RSS: Main: 188088320 02:22:57 INFO - 2015-10-24 02:22:57,772 DEBUG : BROWSER_OUTPUT: 02:22:57 INFO - 2015-10-24 02:22:57,772 DEBUG : BROWSER_OUTPUT: __start_tp_report 02:22:57 INFO - 2015-10-24 02:22:57,772 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 02:22:57 INFO - 2015-10-24 02:22:57,772 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 02:22:57 INFO - 2015-10-24 02:22:57,772 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 02:22:57 INFO - 2015-10-24 02:22:57,772 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2714.1043455659633;2749.780017598592;2760.171948415475;2770.224337185588;2787.1359878437684;5139.74076351294;5140.023395554842;5191.636585439021;5288.391834938983;5288.947110528894;5208.2968799212185;5227.686338819664;5333.413324534298;5337.412884582703;5376.1398176291805;4421.867343979686;4543.568361005699;4556;4627.259638457847;4628.398308219944;4497.7526236057;4556.931646025312;4559.54404559543;4591.609713174367;4597.540245975392;4261.701680882339;4420;4459.15276097542;4563.406757121586;4582.9312560311455;4575.245084561044;4582.862514124565;4583.885402864922;4584.312353147021;4585.3122031695175;4444.622207112399;4486.0355023669745;4491.865244042684;4552.635789136861;4573.245414506602;5172.767225474862;5211.7133557654315;5270.6047046471285;5305.655132416419;5308.893822123536;5337.759800808973;5403.891922161574;5474.288342515487;5579.972100139474;5605.775768969236;5436.293281873371;5476.644018138808;5519.282493275889;5570.5822063345;5573.6098473106895;5027.698338099689;5113.054084994251;5114.181730923035;5198.168293073128;5229.032628963616;5500.972495137498;5597.216389705444;5640.097584386561;5738.799142030092;5753;5212.921806172911;5269.156934890476;5294.100002999499;5381.596380271495;5386.676799392049;5417.702026388508;5435.184722291688;5439.972800135974;5465.316835395575;5587.636803607834;5299.549538289211;5342.065138600807;5378.677279363251;5467.17992301158;5495.890082198333;5371.838844834661;5402.567794576425;5416.702081385482;5529.806456773994;5543.61194716374;3345.1469875181674;3416.4362880125;3447.7241820654285;3495.3358861816173;3500.4049311617086;3109.0206584925677;3130.1861516006;3147.7324427423923;3149.9685003150134;3214.260720034358;5257.553107985787;5313.229581710631;5324.254604355393;5373.919391209135;5417.377001644862;5475.6440831345835;5580.637258578179;5598.160275958558;5688.544916406678;5689.943100569024;5216.347956505437;5340;5440.455954404607;5449.536789372868;5460.808871689472;4344.522102568715;4400.669949753717;4414.911701766011;4428.645708343325;4429.468463784367;5747.137929310469;5814.215080964101;5830.62100963436;5947.791827286024;5956.16613674086;5400.189971504149;5556.111022236423;5686.061799802987;5691.544676425959;5694.345150307602;5622.971885140548;5691.459311365414;5725.685087320071;5781.132830075522;5814.069748840166;5325.9467405327005;5356.973215133899;5361.490658387447;5421.728913554385;5536.695481748542;5343.4389389113585;5547.916781248204;5645.520130788765;5652.519535839499;5688.601797874109;5168.9741551292;5326.600504962066;5426.457354264542;5431.646942948853;5466.890662186814;5317.096093664164;5329.934013197299;5387.703676297841;5508.669479831223;5537.36320323168;5175.353080864892;5261.73691315425;5403.324584426947;5422.945770542402;5427.3215848019;5386.973065134649;5426.07756681373;5440.61264377597;5441.211024401518;5466.5900057495055;5333.426325385766;5341.359036915595;5378.354597448331;5469.371022332323;5475.945240547703 02:22:57 INFO - 2015-10-24 02:22:57,772 DEBUG : BROWSER_OUTPUT: |1;ext.html;469.4694994656038;477.8614201881456;484.4767650936988;496.1565338923828;504.94950504949486;29808.168224018737;30061;30103.698963010364;30564.694353056464;37550;39545.20909581806;40367;40698.18603627926;40919.27130201774;40974.38538421926;8342.791430214233;8565.229129378355;8566.143385661446;8577.313814894793;8578.485290882567;7460.925390746104;7653.502522336058;8341.666333346659;10129.544170512345;10384.740381490485;9974.850377244347;10559.102476289487;10668.466576671175;10688.251822372436;10702.250842441032;7554.055743032121;10265.846012309785;10556.102731267774;10568;10601.469926503722;7457.254274572553;10457.058864702214;10490.213234007404;10687.732806679818;10700.518476668569;37717.811410943046;37802;38941.61058389436;38970.529430582224;38986.41520377197;3087.3979573983083;3156.71589556941;3475.7045651119683;3582.7091868048656;3595.3285661570294;29813.254668633457;29855.10434686941;30230;30329.393412131634;30380.8480957596;2628.6319915211884;2664.706882242952;2697.7302269773063;2704.7295270472796;2749.340158361999;304616.9999999978;319279;320702;321169;322337;46083.76958115188;54862.753135607105;55139.17291240635;55147.448525515036;55154;46953.29570056452;57016.31608413951;59518;60496.54855269185;60849;13992.30038498071;14367;14479.565613031626;14510.201938893462;14535.78196327056;9911.603535858629;10577.62978295756;10608.098311643596;10681.145508359312;10685.145188384911;17239.87940783845;17473.03898285581;17704.91147544254;17775.200115994812;17829.643407131785;7027.8594428111155;7222.343877530787;7410.296021877913;7441.293077157661;7518.135414427302;833.954132522717;841.1882533355341;914.4650379527918;1019.6991887393236;1021.8249013634231;26731.866340668174;27025.1892443227;27039.32401689934;27248.182554523395;28566;975.5073687787764;983.6163896080534;1010.3533738407425;1038.6364772329623;1056.8626078609732;13722.313884305744;14208;14241.359138838778;14330.57008289732;14443.233732868128;376385;381276;382066;382173.81307047495;384007;45544;48371;48536.514634853906;48553.86153461634;48722.512774872506;1202.5790973159499;1208.4441157067672;1213.5631172777794;1214.5202644955193;1222.9449674764658;23151.305460836204;27185.456290874467;27669.30826729348;28030.579541306488;28377.858110709316;46168.07663846771;46357.072858541964;46446.30330545112;46488.53511464977;46511.302330464394;315223;335363;336305;338010;338387;24278.27165185047;24448.87775561111;27373.31566710848;27429.17712468629;27690;39349.60650393459;39829;39961.40057899192;40314.39528407135;40375.19249614933;33069.83465082756;33703.32593348168;34882.30235395329;35707.10732231715;36323.27353452969;25163.87418062898;34794.82602586971;35742.821285893406;35749.28501430009;35851.28297433985;34208.144796379296;34512.48231276483;34711.82644086763;35621.82189089038;35789;29438;30745.385092298475;31573.684263157993;31614;31832;23925.64111538363;24050.759492405552;24399.75600243975;24751.752482474945;25215 02:22:57 INFO - 2015-10-24 02:22:57,782 DEBUG : BROWSER_OUTPUT: |2;jquery.html;316.5836924444356;316.92393825481884;318.76092930302275;323.7151306849973;333.36493978970054;12514.624561263154;17121.57196070099;17460.47618571442;17516.211770470327;18918.90540547299;15722.528324150266;16400;16491.422800201995;16625.916870415633;16700;5294.682319060859;5369.785208591652;5414.648047876895;5416.133418653017;5422.05114105032;5288.6562373445795;5330.626856120073;5390;5426.9185962210595;5448.782048718066;4129.648979836717;4185.183889141618;5274.208868669692;5455.945440545604;5462.890742185154;5825.766969321243;5917.5561832862495;6005.369436209223;6010.338862725097;6084.969575152118;5102.36220472441;5235.293235413209;5239.580833533309;5277.076511610453;5280.788768449257;20035.499112522302;20698;21033.78966210349;22310.77689223103;22645;2141.8286537076997;2154.256781410411;2227.4765430123907;2301.8043466305385;2324.953500929972;13648.317584120754;13796.241206733626;13886;13894.930525347309;14092.224927628977;1668.9749653755202;1700.0904516083835;1745.2408202431982;1782.2069179215255;1791.480470663506;71041.28958710398;72967.63516182438;74451.62774186149;74672.25327746707;75376.99999999945;6164.47601953839;6245;6247.344028876993;6260.937390626126;6288.339724328971;6840.863182736317;7067.89398159028;7071.36357727807;7094;7136.357727804522;4977.925331120036;5218.843434696965;5319.867003324948;5345.251664766935;5374.113271310268;5809;5819.912701309484;5901.675407852524;5915.201447804491;5924.289085309704;2794.8462834544293;2827.180117765825;2857.7142285771256;2875.7986940913934;2883.4521440926155;995.1193194023341;1023.181454836143;1038.8182068138196;1043.3583346242158;1056.7358160459885;403.824336413661;411.6315897271925;420.0255407454676;431.40896971149726;449.4471799686404;11044.447777611087;11711.531538738525;12010.579629713093;12123.878761212274;12158.270503769802;458.7339343180947;465.98136074557317;467.1684401764864;476.5782282679853;487.7902501924207;6020.789272375446;6077.574569780073;6243.7814676486105;6261.373862613793;6299.779507717208;318975.4202846353;324862;412032;426898;427402;72863;79363.60318198372;81911;85149.14850851412;89451.55274223717;486.013392812591;490.6663468841151;490.76443307212026;492.86938961175315;492.92113261877927;10600.310979786289;10854.240203186017;10950.616728414467;10959.452027398758;11076.446177691245;40175.79912100538;40260.3960940592;41722.374164388166;41978.16043679048;42022.36966445445;55833.720831395585;56101.438985611254;56234.71882640561;56302.155467667195;57449.42550574441;5779.306483222041;6022.668753218615;6037.758489660365;6074.726637301421;6080.0575910733905;11340.262882912584;11576.24754390962;11591.5942942;12050.879491205327;12320;9223.723288301362;10242.795144097225;10467.214958878267;10547.78904421892;10579.191491063364;9084.318676099187;10116.241281904035;10230.335028223439;10299.227557933034;10338.793224135625;10377.461979556221;10471;10611.681649550526;10719.517621706886;10756.300840445347;6030.39696030411;6228.5951413158;6239.313675495782;6243.250809902841;6256.968715156395;10066.899331006596;10505.054545090978;10519;10533.631322903662;10896 02:22:57 INFO - 2015-10-24 02:22:57,783 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1175.1480176871764;1246.906482013849;1290.987090129099;1416.3980308368943;1611.9200135908948;7747.418943579233;8855.158759917806;9069.319801014934;9073.863892041616;9112.179903808656;8782.868256976135;9091.72724818255;9106.134917182873;9129.269658427327;9163.908360916406;1954.140178321541;1971.5366888781125;2164.8051675349257;2188.868667879932;2189.4854709143337;2105.8736475811424;2151.7955794199524;2157.1371451419477;2158.8488805783522;2162.935111946644;2115.7249557557498;2194;2204.030226700255;2204.790544898232;2205.2502149269244;1970.2217624038497;2006.1173083843134;2171.9348419547437;2205.073868975034;2208.2160832904246;2184.355615093551;2190.386691726319;2199.8350123740784;2206.3270702435752;2212.7455342635644;8860.113988601153;9006.954965225197;9033.548322583845;9040.231580315682;9056.592453339616;4907.68100073494;4939.8024079036795;4986.227134794112;5002.774875130628;5017.197248440232;3152.7320177784914;3281.278118813857;3360.5799275090612;3385.542951701513;3387.8814241501677;977.2719324103517;1006.9395836249848;1026.2149455666422;1031.7265924529931;1034.6533911139777;12913.741725165539;13013.024023198202;13056.21662700241;13067.411966461532;13095.214287142802;4248.895287225282;4377.099335699094;4416.403785488982;4557.225271703818;4560.543945605413;10832.97086776755;10877.945610271898;10879.376265087307;10896;10930.617428389969;4951.257311403247;5072.51811077947;5087.949120508748;5099.107656160157;5106.463821298785;4601.861944141614;4609.516000819933;4618.930716039262;4621.214393553104;4624.884377890512;3472.68745812873;3576.1059735066233;3588.4796704477703;3590.2101537661674;3597.6582224688614;1080.2168427889717;1101.6419663609358;1111.649830303468;1112.0825319111766;1115.9609413670482;894.1460904835866;894.6242578117207;910.2354022620898;958.8178246133212;995.6564985080136;7296.817579560554;8194.934658494476;8242.052164001216;8453.830923381498;8472.576371181416;606.6663335165641;621.7450845153465;631.1321932343028;635.5773410681845;638.4413638066692;5842.8539286517325;6009.759609615654;6057.606255593372;6062.454379105901;6071.2714474263375;11083.72290692723;11217.607383741693;11221.158413119048;11247.381394023407;11271.549138034386;10832.675019749262;11145;11153.163512736577;11202.103831693445;11219.102471802236;7715.652795624211;8059.113497515269;8083;8122.3502119831455;8183.71357002502;8020.679172833255;8024.959875200587;8060.992375953006;8105.3920955929725;8115.066767321833;9625.422474651543;9739.172170365599;9744.610215591581;9757.97541258186;9792.51037448139;9749.268804839523;10034.397936123856;10050.145737612385;10069.446180460145;10092.394456332644;7852.136265010959;7929.881051784112;7932.881006784787;7937.801554961172;7942;8727.563621818757;8765.868511972198;8778;8806.735797926072;8827.16141966512;7884.329831964118;8137.226963438464;8151.836963260583;8242.381821363302;8250.092489825995;7905.367570594453;7958.960205198937;7992.720254791055;8142.063662678867;8146.103928567734;6753.797386078426;7143.785686429415;7968.402369822403;8001.8399632008195;8040.477368970998;6543.411093001652;6815.250322464426;6843.110395648534;6845.349691779273;6848.8972665409065;7013;7019.122609673791;7032.156141263081;7083.291670832876;7084.752033679003 02:22:57 INFO - 2015-10-24 02:22:57,811 DEBUG : BROWSER_OUTPUT: |4;prototype.html;317.9046286114167;321.31720094798555;330.5537524342138;332.8036458489491;333.42151367377613;5829.242198514198;5840.853978650536;5842.737076831542;5844.590878638497;5848.824535263938;5704.629199102055;5787.565932555054;5788.594798364105;5789.160571717109;5792.855178620536;4196.81114349855;4254.638355739766;4275.551067137937;4309.547497512763;4324.351347297915;4199.202151591203;4226.408302837605;4237.6609871210385;4266.57334266574;4274.337477690963;4236.766977816219;4238.936415953764;4239.851605193819;4241.8303267869405;4242.300020496615;4051.392291156321;4113.115680128787;4117.053077792125;4213.641840443566;4246.57534246576;3686.2443199144113;3698.8335524901445;3700.7964561949084;3704.9259014819554;3720.1629633332554;5530.308711411074;5636.2391077204875;5644.068728659766;5651.1523271509195;5655.26481557399;4905.607551395879;4921.212605983025;4965.3048573199785;4965.851024469272;4975.427825800043;5956.702164891782;5987.880242395127;5992.940070599282;5994.7901823436405;6002.249718785152;4138.710290279651;4141.378793181017;4145.0880806222585;4146.937795933063;4152.833886644561;6214.968925155391;6289.87420251597;6302.495800336008;6302.527310451688;6309;3889.922201555953;4058.6347228749555;4090;4129.194807012604;4262.296721041055;4232.238197124486;4267.551907049778;4275.893102672458;4296.419983302277;4311.2239796836875;4942.4316203637045;4957.851264462072;4988.575971042429;4994.950050499521;5005.549500544968;6000.639961602318;6013.609115407572;6014.278286605548;6014.548908831767;6017.518598512109;1554.6346608546983;1562.5937256313214;1569.0115227406661;1571.7799508068651;1573.4807513520511;1023.3143793658267;1040.8074506216224;1041.8541404203418;1043.2280112716535;1056.9524371403306;5904.468597826216;5905.911411328833;5907.084401917709;5914.142449344907;5915.526757859361;6221.191245138103;6225.844353891189;6227.19046523949;6238.532110091761;6247.09417134522;5894.793682221101;5909.2908850938165;5915.970420147958;5967.76128954837;5974.910376344272;3378.5945686517284;3436.810975396327;3469.5663042119736;3470.35798377301;3470.427379482357;6583.572067815577;6592.978088396306;6593.208814942238;6595.4393876521035;6632.867342653217;6366.84082897922;6368.044793281045;6373.298937116913;6383.489320854321;6400.23197216337;6591.110200123018;6595.439387652007;6606.570572912745;6614.040964060088;6620.602763834186;5983.850403739942;6186.072089186658;6186.659733714689;6256.812295631138;6277.623342599459;5851.853703657443;6077.574569780073;6102.359252278625;6110.1445797587485;6119.571629985859;6396.26442959047;6581.308962559054;6582;6587.0448784926875;6626.933730662825;4273.0385663225525;4274.187904298174;4275.593818587229;4282.978585107055;4306.956930430656;6004.189434426471;6289.150964619717;6291.559590828598;6391.904121438275;6395.552311338161;6201.658908759881;6209.441150296495;6212.968935155476;6215.906761398493;6220.657863817533;6002.429769171922;6142;6159.384061593805;6170.5372097092;6173.197484327188;5691.288588926384;5983.730732116978;6007.549433792396;6017.037274036134;6043.939560604338;6079.665618391031;6124.969375153096;6133.44798968095;6139.754409823736;6143.109249158758;5634.267545219259;5998.100284957117;6006.609570377911;6015.759369625166;6017.097435384728 02:22:57 INFO - 2015-10-24 02:22:57,812 DEBUG : BROWSER_OUTPUT: |5;yui.html;730.2587873308589;730.3791776989559;730.7223255163036;731.952423092499;747.424483147976;12398.194117382374;12947.67630809229;12960.54638087666;12990.93504532476;13003.739925201491;11664.7667046659;13144;13160.868391316084;13168.47326106957;13327.466901323935;504.9974750126244;509.8394005888152;594.1088367448826;605.9606125601844;606.2997238189897;568.2357229526281;587.3128439725531;602.8492876780805;603.3001718007113;604.1300527240782;608.8295277322356;624.2166081567626;626.035904706751;626.9749210031594;627.8775638750445;521.9582433405337;526.3000209721081;618.301319508952;623.6569886562418;623.7349126621168;449.74589357013184;450.17617759500035;450.4279564952492;450.8354450625531;451.6928488627732;11355.318680879174;11527.42362881861;11548.53805847765;11575.073994080454;11580.536778528849;9337.533123343874;9420.717378478657;9462.952685236598;9678.322517423783;9826.705198844045;9306.162445379881;9474.147326740627;9475.66835160773;10027.448490333029;10039.849402258971;2891.7108289171124;3024.470717624407;3074.8001379910233;3143.245621050954;3157.321175947159;46634.30048549274;46708.06583868303;47093.76453117747;47430;47950;6745.662716864137;6820.761273355409;6874.865647168262;7049.92950070503;7235.782926512108;10333.331667258015;10639.531396813358;10733.141348692086;10797.83803242952;10849.02358787697;6076.270847498328;6236.095766113888;6280.183576135073;6324.241091069009;6394.232692076887;3900.551436584772;3994.580569040267;4056.9594304057173;4091.3249313863475;4144.834206631761;2470.555300045973;2531.5190113878302;2876.2953076495974;2910.592517047615;2980.1804503761205;555.2448669809056;556.090791555801;590.8345663214307;592.602956019468;592.6473748119862;595.3332267860025;634.2072409488139;657.9506537009744;674.8818956682561;709.716113554572;8707.564621768886;8787.604557794915;8899.288056955427;8915.242204412698;8982.955085224663;531.131599834266;588.9469947704728;609.3784339973291;682.5597489619167;729.0922801112649;4355.1942890564715;4404.6256068233915;4425.75658338788;4600.2869555218995;4787.784549695272;51555.74222128865;53357.1996420054;54208.102716404734;54251;54313.185302220496;46435.07129857384;46506.23369092461;46573.30140047902;46828.7658561705;47024.5297547027;41549.79225103916;41717.165656686695;41963.37054944178;42204;43219;8793.736187914374;8837.425567338103;9052.63786462862;9077.954610226907;9156.862647060432;28455.857720711265;28869.711302887543;29579.112626621234;29934.401311973204;30067.699323007364;39978.60021399749;39991.60008399879;40117.79941100276;42098.368524471545;42131.36802947897;8124.431289809893;8161.79595510117;8237.629306681094;8406.915930840858;8420.915790842013;12583.93708031454;12735;13024.674383140497;13140.60578182656;13564.79652805189;12205.69485762863;12304.569340073054;12404.255744654989;12555.309457979534;12574.30841303737;11871.821922670993;12146.210496317712;12154.027677785933;12300.446479908247;12448.751024979269;10371.222158338305;10750.139988801033;10989.61536346224;11006.724831879266;12226.877731222574;9811.950940245253;10037;10289;10438.739031524272;10476.52855621514;9838.60645574169;10581.524290952057;10650.218656723891;10696;10913.56345746193 02:22:57 INFO - 2015-10-24 02:22:57,812 DEBUG : BROWSER_OUTPUT: __end_tp_report 02:22:57 INFO - 2015-10-24 02:22:57,812 DEBUG : BROWSER_OUTPUT: __start_cc_report 02:22:57 INFO - 2015-10-24 02:22:57,812 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,824 02:22:57 INFO - 2015-10-24 02:22:57,812 DEBUG : BROWSER_OUTPUT: __end_cc_report 02:22:57 INFO - 2015-10-24 02:22:57,813 DEBUG : BROWSER_OUTPUT: __startTimestamp1445678577796__endTimestamp 02:22:57 INFO - 2015-10-24 02:22:57,813 DEBUG : BROWSER_OUTPUT: 02:22:57 INFO - 2015-10-24 02:22:57,813 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 02:22:57 INFO - 2015-10-24 02:22:57,813 DEBUG : BROWSER_OUTPUT: Number of tests: 6 02:22:57 INFO - 2015-10-24 02:22:57,813 DEBUG : BROWSER_OUTPUT: 02:22:57 INFO - 2015-10-24 02:22:57,813 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5061.41 Median:5341.36 stddev:735.17 (13.8%) stddev-sans-first:713.99 02:22:57 INFO - 2015-10-24 02:22:57,813 DEBUG : BROWSER_OUTPUT: Values: 2714.1 2749.8 2760.2 2770.2 2787.1 5139.7 5140.0 5191.6 5288.4 5288.9 5208.3 5227.7 5333.4 5337.4 5376.1 4421.9 4543.6 4556.0 4627.3 4628.4 4497.8 4556.9 4559.5 4591.6 4597.5 4261.7 4420.0 4459.2 4563.4 4582.9 4575.2 4582.9 4583.9 4584.3 4585.3 4444.6 4486.0 4491.9 4552.6 4573.2 5172.8 5211.7 5270.6 5305.7 5308.9 5337.8 5403.9 5474.3 5580.0 5605.8 5436.3 5476.6 5519.3 5570.6 5573.6 5027.7 5113.1 5114.2 5198.2 5229.0 5501.0 5597.2 5640.1 5738.8 5753.0 5212.9 5269.2 5294.1 5381.6 5386.7 5417.7 5435.2 5440.0 5465.3 5587.6 5299.5 5342.1 5378.7 5467.2 5495.9 5371.8 5402.6 5416.7 5529.8 5543.6 3345.1 3416.4 3447.7 3495.3 3500.4 3109.0 3130.2 3147.7 3150.0 3214.3 5257.6 5313.2 5324.3 5373.9 5417.4 5475.6 5580.6 5598.2 5688.5 5689.9 5216.3 5340.0 5440.5 5449.5 5460.8 4344.5 4400.7 4414.9 4428.6 4429.5 5747.1 5814.2 5830.6 5947.8 5956.2 5400.2 5556.1 5686.1 5691.5 5694.3 5623.0 5691.5 5725.7 5781.1 5814.1 5325.9 5357.0 5361.5 5421.7 5536.7 5343.4 5547.9 5645.5 5652.5 5688.6 5169.0 5326.6 5426.5 5431.6 5466.9 5317.1 5329.9 5387.7 5508.7 5537.4 5175.4 5261.7 5403.3 5422.9 5427.3 5387.0 5426.1 5440.6 5441.2 5466.6 5333.4 5341.4 5378.4 5469.4 5475.9 02:22:57 INFO - 2015-10-24 02:22:57,813 DEBUG : BROWSER_OUTPUT: 02:22:57 INFO - 2015-10-24 02:22:57,813 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49368.29 Median:177703.42 stddev:90791.74 (51.1%) stddev-sans-first:90972.20 02:22:57 INFO - 2015-10-24 02:22:57,813 DEBUG : BROWSER_OUTPUT: Values: 469.5 477.9 484.5 496.2 504.9 29808.2 30061.0 30103.7 30564.7 37550.0 39545.2 40367.0 40698.2 40919.3 40974.4 8342.8 8565.2 8566.1 8577.3 8578.5 7460.9 7653.5 8341.7 10129.5 10384.7 9974.9 10559.1 10668.5 10688.3 10702.3 7554.1 10265.8 10556.1 10568.0 10601.5 7457.3 10457.1 10490.2 10687.7 10700.5 37717.8 37802.0 38941.6 38970.5 38986.4 3087.4 3156.7 3475.7 3582.7 3595.3 29813.3 29855.1 30230.0 30329.4 30380.8 2628.6 2664.7 2697.7 2704.7 2749.3 304617.0 319279.0 320702.0 321169.0 322337.0 46083.8 54862.8 55139.2 55147.4 55154.0 46953.3 57016.3 59518.0 60496.5 60849.0 13992.3 14367.0 14479.6 14510.2 14535.8 9911.6 10577.6 10608.1 10681.1 10685.1 17239.9 17473.0 17704.9 17775.2 17829.6 7027.9 7222.3 7410.3 7441.3 7518.1 834.0 841.2 914.5 1019.7 1021.8 26731.9 27025.2 27039.3 27248.2 28566.0 975.5 983.6 1010.4 1038.6 1056.9 13722.3 14208.0 14241.4 14330.6 14443.2 376385.0 381276.0 382066.0 382173.8 384007.0 45544.0 48371.0 48536.5 48553.9 48722.5 1202.6 1208.4 1213.6 1214.5 1222.9 23151.3 27185.5 27669.3 28030.6 28377.9 46168.1 46357.1 46446.3 46488.5 46511.3 315223.0 335363.0 336305.0 338010.0 338387.0 24278.3 24448.9 27373.3 27429.2 27690.0 39349.6 39829.0 39961.4 40314.4 40375.2 33069.8 33703.3 34882.3 35707.1 36323.3 25163.9 34794.8 35742.8 35749.3 35851.3 34208.1 34512.5 34711.8 35621.8 35789.0 29438.0 30745.4 31573.7 31614.0 31832.0 23925.6 24050.8 24399.8 24751.8 25215.0 02:22:57 INFO - 2015-10-24 02:22:57,815 DEBUG : BROWSER_OUTPUT: 02:22:57 INFO - 2015-10-24 02:22:57,815 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:23844.13 Median:427150.00 stddev:64167.71 (15.0%) stddev-sans-first:64323.40 02:22:57 INFO - 2015-10-24 02:22:57,815 DEBUG : BROWSER_OUTPUT: Values: 316.6 316.9 318.8 323.7 333.4 12514.6 17121.6 17460.5 17516.2 18918.9 15722.5 16400.0 16491.4 16625.9 16700.0 5294.7 5369.8 5414.6 5416.1 5422.1 5288.7 5330.6 5390.0 5426.9 5448.8 4129.6 4185.2 5274.2 5455.9 5462.9 5825.8 5917.6 6005.4 6010.3 6085.0 5102.4 5235.3 5239.6 5277.1 5280.8 20035.5 20698.0 21033.8 22310.8 22645.0 2141.8 2154.3 2227.5 2301.8 2325.0 13648.3 13796.2 13886.0 13894.9 14092.2 1669.0 1700.1 1745.2 1782.2 1791.5 71041.3 72967.6 74451.6 74672.3 75377.0 6164.5 6245.0 6247.3 6260.9 6288.3 6840.9 7067.9 7071.4 7094.0 7136.4 4977.9 5218.8 5319.9 5345.3 5374.1 5809.0 5819.9 5901.7 5915.2 5924.3 2794.8 2827.2 2857.7 2875.8 2883.5 995.1 1023.2 1038.8 1043.4 1056.7 403.8 411.6 420.0 431.4 449.4 11044.4 11711.5 12010.6 12123.9 12158.3 458.7 466.0 467.2 476.6 487.8 6020.8 6077.6 6243.8 6261.4 6299.8 318975.4 324862.0 412032.0 426898.0 427402.0 72863.0 79363.6 81911.0 85149.1 89451.6 486.0 490.7 490.8 492.9 492.9 10600.3 10854.2 10950.6 10959.5 11076.4 40175.8 40260.4 41722.4 41978.2 42022.4 55833.7 56101.4 56234.7 56302.2 57449.4 5779.3 6022.7 6037.8 6074.7 6080.1 11340.3 11576.2 11591.6 12050.9 12320.0 9223.7 10242.8 10467.2 10547.8 10579.2 9084.3 10116.2 10230.3 10299.2 10338.8 10377.5 10471.0 10611.7 10719.5 10756.3 6030.4 6228.6 6239.3 6243.3 6257.0 10066.9 10505.1 10519.0 10533.6 10896.0 02:22:57 INFO - 2015-10-24 02:22:57,818 DEBUG : BROWSER_OUTPUT: 02:22:57 INFO - 2015-10-24 02:22:57,818 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:6083.86 Median:5093.53 stddev:3502.12 (68.8%) stddev-sans-first:3492.51 02:22:57 INFO - 2015-10-24 02:22:57,818 DEBUG : BROWSER_OUTPUT: Values: 1175.1 1246.9 1291.0 1416.4 1611.9 7747.4 8855.2 9069.3 9073.9 9112.2 8782.9 9091.7 9106.1 9129.3 9163.9 1954.1 1971.5 2164.8 2188.9 2189.5 2105.9 2151.8 2157.1 2158.8 2162.9 2115.7 2194.0 2204.0 2204.8 2205.3 1970.2 2006.1 2171.9 2205.1 2208.2 2184.4 2190.4 2199.8 2206.3 2212.7 8860.1 9007.0 9033.5 9040.2 9056.6 4907.7 4939.8 4986.2 5002.8 5017.2 3152.7 3281.3 3360.6 3385.5 3387.9 977.3 1006.9 1026.2 1031.7 1034.7 12913.7 13013.0 13056.2 13067.4 13095.2 4248.9 4377.1 4416.4 4557.2 4560.5 10833.0 10877.9 10879.4 10896.0 10930.6 4951.3 5072.5 5087.9 5099.1 5106.5 4601.9 4609.5 4618.9 4621.2 4624.9 3472.7 3576.1 3588.5 3590.2 3597.7 1080.2 1101.6 1111.6 1112.1 1116.0 894.1 894.6 910.2 958.8 995.7 7296.8 8194.9 8242.1 8453.8 8472.6 606.7 621.7 631.1 635.6 638.4 5842.9 6009.8 6057.6 6062.5 6071.3 11083.7 11217.6 11221.2 11247.4 11271.5 10832.7 11145.0 11153.2 11202.1 11219.1 7715.7 8059.1 8083.0 8122.4 8183.7 8020.7 8025.0 8061.0 8105.4 8115.1 9625.4 9739.2 9744.6 9758.0 9792.5 9749.3 10034.4 10050.1 10069.4 10092.4 7852.1 7929.9 7932.9 7937.8 7942.0 8727.6 8765.9 8778.0 8806.7 8827.2 7884.3 8137.2 8151.8 8242.4 8250.1 7905.4 7959.0 7992.7 8142.1 8146.1 6753.8 7143.8 7968.4 8001.8 8040.5 6543.4 6815.3 6843.1 6845.3 6848.9 7013.0 7019.1 7032.2 7083.3 7084.8 02:22:57 INFO - 2015-10-24 02:22:57,819 DEBUG : BROWSER_OUTPUT: 02:22:57 INFO - 2015-10-24 02:22:57,819 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5063.45 Median:5846.71 stddev:1552.66 (26.6%) stddev-sans-first:1515.61 02:22:57 INFO - 2015-10-24 02:22:57,819 DEBUG : BROWSER_OUTPUT: Values: 317.9 321.3 330.6 332.8 333.4 5829.2 5840.9 5842.7 5844.6 5848.8 5704.6 5787.6 5788.6 5789.2 5792.9 4196.8 4254.6 4275.6 4309.5 4324.4 4199.2 4226.4 4237.7 4266.6 4274.3 4236.8 4238.9 4239.9 4241.8 4242.3 4051.4 4113.1 4117.1 4213.6 4246.6 3686.2 3698.8 3700.8 3704.9 3720.2 5530.3 5636.2 5644.1 5651.2 5655.3 4905.6 4921.2 4965.3 4965.9 4975.4 5956.7 5987.9 5992.9 5994.8 6002.2 4138.7 4141.4 4145.1 4146.9 4152.8 6215.0 6289.9 6302.5 6302.5 6309.0 3889.9 4058.6 4090.0 4129.2 4262.3 4232.2 4267.6 4275.9 4296.4 4311.2 4942.4 4957.9 4988.6 4995.0 5005.5 6000.6 6013.6 6014.3 6014.5 6017.5 1554.6 1562.6 1569.0 1571.8 1573.5 1023.3 1040.8 1041.9 1043.2 1057.0 5904.5 5905.9 5907.1 5914.1 5915.5 6221.2 6225.8 6227.2 6238.5 6247.1 5894.8 5909.3 5916.0 5967.8 5974.9 3378.6 3436.8 3469.6 3470.4 3470.4 6583.6 6593.0 6593.2 6595.4 6632.9 6366.8 6368.0 6373.3 6383.5 6400.2 6591.1 6595.4 6606.6 6614.0 6620.6 5983.9 6186.1 6186.7 6256.8 6277.6 5851.9 6077.6 6102.4 6110.1 6119.6 6396.3 6581.3 6582.0 6587.0 6626.9 4273.0 4274.2 4275.6 4283.0 4307.0 6004.2 6289.2 6291.6 6391.9 6395.6 6201.7 6209.4 6213.0 6215.9 6220.7 6002.4 6142.0 6159.4 6170.5 6173.2 5691.3 5983.7 6007.5 6017.0 6043.9 6079.7 6125.0 6133.4 6139.8 6143.1 5634.3 5998.1 6006.6 6015.8 6017.1 02:22:57 INFO - 2015-10-24 02:22:57,819 DEBUG : BROWSER_OUTPUT: 02:22:57 INFO - 2015-10-24 02:22:57,819 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12650.32 Median:46539.77 stddev:14727.30 (31.6%) stddev-sans-first:14741.41 02:22:57 INFO - 2015-10-24 02:22:57,819 DEBUG : BROWSER_OUTPUT: Values: 730.3 730.4 730.7 732.0 747.4 12398.2 12947.7 12960.5 12990.9 13003.7 11664.8 13144.0 13160.9 13168.5 13327.5 505.0 509.8 594.1 606.0 606.3 568.2 587.3 602.8 603.3 604.1 608.8 624.2 626.0 627.0 627.9 522.0 526.3 618.3 623.7 623.7 449.7 450.2 450.4 450.8 451.7 11355.3 11527.4 11548.5 11575.1 11580.5 9337.5 9420.7 9463.0 9678.3 9826.7 9306.2 9474.1 9475.7 10027.4 10039.8 2891.7 3024.5 3074.8 3143.2 3157.3 46634.3 46708.1 47093.8 47430.0 47950.0 6745.7 6820.8 6874.9 7049.9 7235.8 10333.3 10639.5 10733.1 10797.8 10849.0 6076.3 6236.1 6280.2 6324.2 6394.2 3900.6 3994.6 4057.0 4091.3 4144.8 2470.6 2531.5 2876.3 2910.6 2980.2 555.2 556.1 590.8 592.6 592.6 595.3 634.2 658.0 674.9 709.7 8707.6 8787.6 8899.3 8915.2 8983.0 531.1 588.9 609.4 682.6 729.1 4355.2 4404.6 4425.8 4600.3 4787.8 51555.7 53357.2 54208.1 54251.0 54313.2 46435.1 46506.2 46573.3 46828.8 47024.5 41549.8 41717.2 41963.4 42204.0 43219.0 8793.7 8837.4 9052.6 9078.0 9156.9 28455.9 28869.7 29579.1 29934.4 30067.7 39978.6 39991.6 40117.8 42098.4 42131.4 8124.4 8161.8 8237.6 8406.9 8420.9 12583.9 12735.0 13024.7 13140.6 13564.8 12205.7 12304.6 12404.3 12555.3 12574.3 11871.8 12146.2 12154.0 12300.4 12448.8 10371.2 10750.1 10989.6 11006.7 12226.9 9812.0 10037.0 10289.0 10438.7 10476.5 9838.6 10581.5 10650.2 10696.0 10913.6 02:22:57 INFO - 2015-10-24 02:22:57,821 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 02:22:57 INFO - 2015-10-24 02:22:57,821 DEBUG : BROWSER_OUTPUT: 02:22:58 INFO - 2015-10-24 02:22:58,223 INFO : Browser exited with error code: 0 02:22:59 INFO - 2015-10-24 02:22:59,009 INFO : Completed test dromaeo_css (00:18:38) 02:22:59 INFO - 2015-10-24 02:22:59,009 INFO : Starting test kraken 02:22:59 INFO - 2015-10-24 02:22:59,009 DEBUG : operating with platform_type : w7_ 02:22:59 INFO - 2015-10-24 02:22:59,009 INFO : Initialising browser for kraken test... 02:22:59 INFO - 2015-10-24 02:22:59,036 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpjhigln\profile http://localhost:49893/getInfo.html 02:23:03 INFO - 2015-10-24 02:23:03,766 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:23:03 INFO - 2015-10-24 02:23:03,766 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:23:03 INFO - 2015-10-24 02:23:03,766 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 02:23:03 INFO - 2015-10-24 02:23:03,766 DEBUG : BROWSER_OUTPUT: __metrics 02:23:03 INFO - 2015-10-24 02:23:03,846 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:23:04 INFO - 2015-10-24 02:23:04,502 DEBUG : BROWSER_OUTPUT: console.error: 02:23:04 INFO - 2015-10-24 02:23:04,502 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] 02:23:04 INFO - 2015-10-24 02:23:04,502 DEBUG : BROWSER_OUTPUT: Stack: 02:23:04 INFO - 2015-10-24 02:23:04,502 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 02:23:04 INFO - 2015-10-24 02:23:04,502 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 02:23:04 INFO - 2015-10-24 02:23:04,502 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 02:23:04 INFO - 2015-10-24 02:23:04,502 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 02:23:04 INFO - 2015-10-24 02:23:04,502 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 02:23:04 INFO - 2015-10-24 02:23:04,502 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 02:23:04 INFO - 2015-10-24 02:23:04,502 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 02:23:04 INFO - 2015-10-24 02:23:04,502 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 02:23:04 INFO - 2015-10-24 02:23:04,502 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 02:23:04 INFO - 2015-10-24 02:23:04,503 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 02:23:04 INFO - 2015-10-24 02:23:04,503 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 02:23:04 INFO - 2015-10-24 02:23:04,503 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 02:23:04 INFO - 2015-10-24 02:23:04,503 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6509.26 02:24:40 INFO - RETURN: kraken: 1600.00 02:24:40 INFO - RETURN: v8_7: 20015.99 02:24:40 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,25]]", "result": "20015.99"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,25]]", "result": "1600.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,25]]", "result": "6509.26"}}} 02:24:40 INFO - Return code: 0 02:24:40 INFO - # TBPL SUCCESS # 02:24:40 INFO - Running post-action listener: _resource_record_post_action 02:24:40 INFO - Running post-run listener: _resource_record_post_run 02:24:41 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 212641280; Write bytes: 447677952; Read time: 18366780; Write time: 4516670 02:24:41 INFO - install - Wall time: 2s; CPU: 12.0%; Read bytes: 0; Write bytes: 922624; Read time: 0; Write time: 127750 02:24:41 INFO - run-tests - Wall time: 1223s; CPU: 13.0%; Read bytes: 212641280; Write bytes: 424739328; Read time: 18366780; Write time: 4368980 02:24:41 INFO - Running post-run listener: _upload_blobber_files 02:24:41 INFO - Blob upload gear active. 02:24:41 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 02:24:41 INFO - Copying logs to upload dir... 02:24:41 INFO - mkdir: C:\slave\test-pgo\build\upload\logs program finished with exit code 0 elapsedTime=1521.621000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 21 secs) (at 2015-10-24 02:24:41.878968) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-24 02:24:41.879754) ========= '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-039 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-039 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-039 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/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/JNo6xzkgTlSPzrJxfwqCig/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-24 02:24:42.020666) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-24 02:24:42.021034) ========= '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-039 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-039 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-039 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-24 02:24:42.137389) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-10-24 02:24:42.137774) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-10-24 02:24:42.612785) =========