builder: mozilla-central_win7-ix_test-dromaeojs-pgo slave: t-w732-ix-200 starttime: 1445659771.35 results: success (0) buildid: 20151023163014 builduid: 7d9faf1a298b46f5bc8d7750d5cc591a revision: 26078ba1c7be11cf46706216cad8603b92335cd0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 21:09:31.351654) ========= master: http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 21:09:31.353659) ========= ========= Started set props: basedir (results: 0, elapsed: 1 mins, 32 secs) (at 2015-10-23 21:09:31.354421) ========= '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-200 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-200 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-200 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.629000 basedir: 'C:\\slave\\test-pgo' ========= Finished set props: basedir (results: 0, elapsed: 1 mins, 32 secs) (at 2015-10-23 21:11:03.842546) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 mins, 29 secs) (at 2015-10-23 21:11:03.842944) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 mins, 29 secs) (at 2015-10-23 21:13:33.691362) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 13 secs) (at 2015-10-23 21:13:33.691725) ========= '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-200 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-200 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-200 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.501000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 13 secs) (at 2015-10-23 21:13:47.423809) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 21:13:47.424135) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 21:13:47.424564) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2015-10-23 21:13:47.424897) ========= '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-200 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-200 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-200 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False --21:13:57-- 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% 8.25 MB/s 21:13:57 (8.25 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.729000 ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2015-10-23 21:14:00.256586) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 30 secs) (at 2015-10-23 21:14:00.256932) ========= '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-200 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-200 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-200 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=9.901000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 30 secs) (at 2015-10-23 21:14:31.144186) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2015-10-23 21:14:31.144526) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev 26078ba1c7be11cf46706216cad8603b92335cd0 --destination scripts --debug' in dir C:\\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 26078ba1c7be11cf46706216cad8603b92335cd0 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-200 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-200 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-200 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2015-10-23 21:14:32,457 truncating revision to first 12 chars 2015-10-23 21:14:32,457 Setting DEBUG logging. 2015-10-23 21:14:32,457 attempt 1/10 2015-10-23 21:14:32,457 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/26078ba1c7be?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-23 21:14:32,921 unpacking tar archive at: mozilla-central-26078ba1c7be/testing/mozharness/ program finished with exit code 0 elapsedTime=0.980000 ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2015-10-23 21:14:37.525600) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 57 secs) (at 2015-10-23 21:14:37.525932) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 57 secs) (at 2015-10-23 21:15:35.427486) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 21:15:35.427789) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 21:15:35.428237) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 41 secs) (at 2015-10-23 21:15:35.428581) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox' in dir C:\\slave\test-pgo\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-200 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-200 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-200 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 21:15:36 INFO - MultiFileLogger online at 20151023 21:15:36 in C:\slave\test-pgo 21:15:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:15:36 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 21:15:36 INFO - {'append_to_log': False, 21:15:36 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 21:15:36 INFO - 'blob_upload_branch': 'Firefox', 21:15:36 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 21:15:36 INFO - 'branch': 'Firefox', 21:15:36 INFO - 'buildbot_json_path': 'buildprops.json', 21:15:36 INFO - 'config_files': ('talos/windows_config.py',), 21:15:36 INFO - 'default_actions': ('clobber', 21:15:36 INFO - 'read-buildbot-config', 21:15:36 INFO - 'download-and-extract', 21:15:36 INFO - 'populate-webroot', 21:15:36 INFO - 'create-virtualenv', 21:15:36 INFO - 'install', 21:15:36 INFO - 'run-tests'), 21:15:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:15:36 INFO - 'download_minidump_stackwalk': True, 21:15:36 INFO - 'download_symbols': 'ondemand', 21:15:36 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 21:15:36 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 21:15:36 INFO - 'hg': 'c:/mozilla-build/hg/hg', 21:15:36 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 21:15:36 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 21:15:36 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 21:15:36 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 21:15:36 INFO - 'C:/mozilla-build/tooltool.py'), 21:15:36 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 21:15:36 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 21:15:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:15:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:15:36 INFO - 'installer_path': 'installer.exe', 21:15:36 INFO - 'log_level': 'info', 21:15:36 INFO - 'log_name': 'talos', 21:15:36 INFO - 'log_to_console': True, 21:15:36 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 21:15:36 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 21:15:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 21:15:36 INFO - 'opt_config_files': (), 21:15:36 INFO - 'pip_index': False, 21:15:36 INFO - 'sps_profile': False, 21:15:36 INFO - 'sps_profile_interval': 0, 21:15:36 INFO - 'suite': 'dromaeojs', 21:15:36 INFO - 'system_bits': '32', 21:15:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:15:36 INFO - 'title': 'T-W732-IX-200', 21:15:36 INFO - 'use_talos_json': True, 21:15:36 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 21:15:36 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 21:15:36 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 21:15:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:15:36 INFO - 'work_dir': 'build'} 21:15:36 INFO - ##### 21:15:36 INFO - ##### Running clobber step. 21:15:36 INFO - ##### 21:15:36 INFO - Running pre-action listener: _resource_record_pre_action 21:15:36 INFO - Running main action method: clobber 21:15:36 INFO - rmtree: C:\slave\test-pgo\build 21:15:36 INFO - Using _rmtree_windows ... 21:15:36 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 21:16:57 INFO - Running post-action listener: _resource_record_post_action 21:16:57 INFO - ##### 21:16:57 INFO - ##### Running read-buildbot-config step. 21:16:57 INFO - ##### 21:16:57 INFO - Running pre-action listener: _resource_record_pre_action 21:16:57 INFO - Running main action method: read_buildbot_config 21:16:57 INFO - Using buildbot properties: 21:16:57 INFO - { 21:16:57 INFO - "properties": { 21:16:57 INFO - "buildnumber": 208, 21:16:57 INFO - "product": "firefox", 21:16:57 INFO - "script_repo_revision": "production", 21:16:57 INFO - "builddir": "mozilla-central_win7-ix_test-dromaeojs", 21:16:57 INFO - "repository": "", 21:16:57 INFO - "buildername": "Windows 7 32-bit mozilla-central pgo talos dromaeojs", 21:16:57 INFO - "buildid": "20151023163014", 21:16:57 INFO - "slavename": "t-w732-ix-200", 21:16:57 INFO - "pgo_build": "True", 21:16:57 INFO - "basedir": "C:\\slave\\test-pgo", 21:16:57 INFO - "project": "", 21:16:57 INFO - "platform": "win7-ix", 21:16:57 INFO - "master": "http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/", 21:16:57 INFO - "slavebuilddir": "test", 21:16:57 INFO - "scheduler": "tests-mozilla-central-win32-pgo-talos", 21:16:57 INFO - "branch": "mozilla-central", 21:16:57 INFO - "repo_path": "mozilla-central", 21:16:57 INFO - "stage_platform": "win32-pgo", 21:16:57 INFO - "builduid": "7d9faf1a298b46f5bc8d7750d5cc591a", 21:16:57 INFO - "revision": "26078ba1c7be11cf46706216cad8603b92335cd0" 21:16:57 INFO - }, 21:16:57 INFO - "sourcestamp": { 21:16:57 INFO - "repository": "", 21:16:57 INFO - "hasPatch": false, 21:16:57 INFO - "project": "", 21:16:57 INFO - "branch": "mozilla-central-win32-pgo-talos", 21:16:57 INFO - "changes": [ 21:16:57 INFO - { 21:16:57 INFO - "category": null, 21:16:57 INFO - "files": [ 21:16:57 INFO - { 21:16:57 INFO - "url": null, 21:16:57 INFO - "name": "https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 21:16:57 INFO - } 21:16:57 INFO - ], 21:16:57 INFO - "repository": "", 21:16:57 INFO - "rev": "26078ba1c7be11cf46706216cad8603b92335cd0", 21:16:57 INFO - "who": "sendchange", 21:16:57 INFO - "when": 1445659243, 21:16:57 INFO - "number": 6569453, 21:16:57 INFO - "comments": "", 21:16:57 INFO - "project": "", 21:16:57 INFO - "at": "Fri 23 Oct 2015 21:00:43", 21:16:57 INFO - "branch": "mozilla-central-win32-pgo-talos", 21:16:57 INFO - "revlink": "", 21:16:57 INFO - "properties": [ 21:16:57 INFO - [ 21:16:57 INFO - "buildid", 21:16:57 INFO - "20151023163014", 21:16:57 INFO - "Change" 21:16:57 INFO - ], 21:16:57 INFO - [ 21:16:57 INFO - "builduid", 21:16:57 INFO - "7d9faf1a298b46f5bc8d7750d5cc591a", 21:16:57 INFO - "Change" 21:16:57 INFO - ], 21:16:57 INFO - [ 21:16:57 INFO - "pgo_build", 21:16:57 INFO - "True", 21:16:57 INFO - "Change" 21:16:57 INFO - ] 21:16:57 INFO - ], 21:16:57 INFO - "revision": "26078ba1c7be11cf46706216cad8603b92335cd0" 21:16:57 INFO - } 21:16:57 INFO - ], 21:16:57 INFO - "revision": "26078ba1c7be11cf46706216cad8603b92335cd0" 21:16:57 INFO - } 21:16:57 INFO - } 21:16:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 21:16:57 INFO - Running post-action listener: _resource_record_post_action 21:16:57 INFO - ##### 21:16:57 INFO - ##### Running download-and-extract step. 21:16:57 INFO - ##### 21:16:57 INFO - Running pre-action listener: _resource_record_pre_action 21:16:57 INFO - Running main action method: download_and_extract 21:16:57 INFO - mkdir: C:\slave\test-pgo\build\tests 21:16:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:16:57 INFO - https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 21:16:57 INFO - trying https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/test_packages.json 21:16:57 INFO - Downloading https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 21:16:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 21:16:58 INFO - Downloaded 1221 bytes. 21:16:58 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 21:16:58 INFO - Using the following test package requirements: 21:16:58 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 21:16:58 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:16:58 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 21:16:58 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:16:58 INFO - u'jsshell-win32.zip'], 21:16:58 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:16:58 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 21:16:58 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 21:16:58 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:16:58 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 21:16:58 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:16:58 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 21:16:58 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:16:58 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 21:16:58 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 21:16:58 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 21:16:58 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 21:16:58 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 21:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:16:58 INFO - https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 21:16:58 INFO - trying https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 21:16:58 INFO - Downloading https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 21:16:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'}, attempt #1 21:17:00 INFO - Downloaded 18011045 bytes. 21:17:00 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] in C:\slave\test-pgo\build\tests 21:17:00 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 21:17:00 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 21:17:29 INFO - Return code: 0 21:17:29 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip', u'firefox-44.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 21:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:17:29 INFO - https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 21:17:29 INFO - trying https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 21:17:29 INFO - Downloading https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 21:17:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'}, attempt #1 21:17:31 INFO - Downloaded 18011045 bytes. 21:17:31 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] in C:\slave\test-pgo\build\tests 21:17:31 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 21:17:31 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 21:17:52 INFO - Return code: 0 21:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:17:52 INFO - https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 21:17:52 INFO - trying https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 21:17:52 INFO - Downloading https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip 21:17:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'}, attempt #1 21:17:54 INFO - Downloaded 11131307 bytes. 21:17:54 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] in C:\slave\test-pgo\build\tests 21:17:54 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip 21:17:54 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 21:17:55 INFO - Return code: 0 21:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:17:55 INFO - https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 21:17:55 INFO - trying https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 21:17:55 INFO - Downloading https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip 21:17:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.zip'}, attempt #1 21:17:59 INFO - Downloaded 53258832 bytes. 21:17:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 21:17:59 INFO - mkdir: C:\slave\test-pgo\properties 21:17:59 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 21:17:59 INFO - Writing to file C:\slave\test-pgo\properties\build_url 21:17:59 INFO - Contents: 21:17:59 INFO - build_url:https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 21:17:59 INFO - Running post-action listener: _resource_record_post_action 21:17:59 INFO - Running post-action listener: set_extra_try_arguments 21:17:59 INFO - ##### 21:17:59 INFO - ##### Running populate-webroot step. 21:17:59 INFO - ##### 21:17:59 INFO - Running pre-action listener: _resource_record_pre_action 21:17:59 INFO - Running main action method: populate_webroot 21:17:59 INFO - Guessing talos json url... 21:17:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:17:59 INFO - https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 21:17:59 INFO - trying https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 21:17:59 INFO - Downloading https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 21:17:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt', 'file_name': 'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.txt'}, attempt #1 21:18:00 INFO - Downloaded 99 bytes. 21:18:00 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 21:18:00 INFO - Contents: 21:18:00 INFO - 20151023163014 21:18:00 INFO - https://hg.mozilla.org/mozilla-central/rev/26078ba1c7be11cf46706216cad8603b92335cd0 21:18:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:00 INFO - trying https://hg.mozilla.org/mozilla-central/raw-file/26078ba1c7/testing/talos/talos.json 21:18:00 INFO - Downloading https://hg.mozilla.org/mozilla-central/raw-file/26078ba1c7/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json 21:18:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/mozilla-central/raw-file/26078ba1c7/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1 21:18:01 INFO - Downloaded 4264 bytes. 21:18:01 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 21:18:01 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 21:18:01 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 21:18:01 INFO - u'../fennec_ids.txt'], 21:18:01 INFO - u'tests': [u'tcheck2']}, 21:18:01 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 21:18:01 INFO - u'--tppagecycles', 21:18:01 INFO - u'7'], 21:18:01 INFO - u'tests': [u'tsvgm']}}, 21:18:01 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 21:18:01 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 21:18:01 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 21:18:01 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 21:18:01 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 21:18:01 INFO - u'tests': [u'dromaeo_css', 21:18:01 INFO - u'kraken', 21:18:01 INFO - u'v8_7']}, 21:18:01 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:01 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 21:18:01 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:01 INFO - u'talos_options': [u'--e10s'], 21:18:01 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 21:18:01 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:01 INFO - u'tests': [u'damp', u'tps']}, 21:18:01 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:01 INFO - u'talos_options': [u'--e10s'], 21:18:01 INFO - u'tests': [u'damp', u'tps']}, 21:18:01 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 21:18:01 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 21:18:01 INFO - u'other': {u'tests': [u'a11yr', 21:18:01 INFO - u'ts_paint', 21:18:01 INFO - u'tpaint', 21:18:01 INFO - u'sessionrestore', 21:18:01 INFO - u'sessionrestore_no_auto_restore']}, 21:18:01 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 21:18:01 INFO - u'tests': [u'a11yr', 21:18:01 INFO - u'ts_paint', 21:18:01 INFO - u'tpaint', 21:18:01 INFO - u'sessionrestore', 21:18:01 INFO - u'sessionrestore_no_auto_restore']}, 21:18:01 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 21:18:01 INFO - u'tests': [u'a11yr', 21:18:01 INFO - u'ts_paint', 21:18:01 INFO - u'tpaint', 21:18:01 INFO - u'sessionrestore', 21:18:01 INFO - u'sessionrestore_no_auto_restore']}, 21:18:01 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 21:18:01 INFO - u'tests': [u'a11yr', 21:18:01 INFO - u'ts_paint', 21:18:01 INFO - u'tpaint', 21:18:01 INFO - u'sessionrestore', 21:18:01 INFO - u'sessionrestore_no_auto_restore']}, 21:18:01 INFO - u'other_l64': {u'tests': [u'a11yr', 21:18:01 INFO - u'ts_paint', 21:18:01 INFO - u'tpaint', 21:18:01 INFO - u'sessionrestore', 21:18:01 INFO - u'sessionrestore_no_auto_restore']}, 21:18:01 INFO - u'other_nol64': {u'tests': [u'a11yr', 21:18:01 INFO - u'ts_paint', 21:18:01 INFO - u'tpaint', 21:18:01 INFO - u'sessionrestore', 21:18:01 INFO - u'sessionrestore_no_auto_restore']}, 21:18:01 INFO - u'svgr': {u'tests': [u'tsvgx', 21:18:01 INFO - u'tsvgr_opacity', 21:18:01 INFO - u'tart', 21:18:01 INFO - u'tscrollx', 21:18:01 INFO - u'cart']}, 21:18:01 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 21:18:01 INFO - u'tests': [u'tsvgx', 21:18:01 INFO - u'tsvgr_opacity', 21:18:01 INFO - u'tart', 21:18:01 INFO - u'tscrollx', 21:18:01 INFO - u'cart']}, 21:18:01 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:01 INFO - u'tests': [u'tp5o']}, 21:18:01 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:01 INFO - u'talos_options': [u'--e10s'], 21:18:01 INFO - u'tests': [u'tp5o']}, 21:18:01 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:01 INFO - u'talos_options': [u'--xperf_path', 21:18:01 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:18:01 INFO - u'tests': [u'tp5n']}, 21:18:01 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:01 INFO - u'talos_options': [u'--e10s', 21:18:01 INFO - u'--xperf_path', 21:18:01 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 21:18:01 INFO - u'C:/slave/talos-data/talos/xperf.config'], 21:18:01 INFO - u'tests': [u'tp5n']}}, 21:18:01 INFO - u'talos.zip': {u'path': u'', 21:18:01 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:18:01 INFO - Running post-action listener: _resource_record_post_action 21:18:01 INFO - ##### 21:18:01 INFO - ##### Running create-virtualenv step. 21:18:01 INFO - ##### 21:18:01 INFO - Running pre-action listener: _resource_record_pre_action 21:18:01 INFO - Running main action method: create_virtualenv 21:18:01 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 21:18:01 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 21:18:01 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 21:18:01 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'! 21:18:01 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test-pgo\\build/venv'] in C:\slave\test-pgo\build 21:18:01 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test-pgo\build/venv 21:18:01 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 21:18:07 INFO - Installing distribute......................................................................................................................................................................................done. 21:18:10 INFO - Return code: 0 21:18:10 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 21:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:10 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 0x0192CB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01985EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184AE58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01348B48>, '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 0x0136A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECF660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-200', 'USERDOMAIN': 'T-W732-IX-200', '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-200', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 21:18:10 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test-pgo\build 21:18:10 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:18:10 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:18:10 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:18:10 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:18:10 INFO - 'COMPUTERNAME': 'T-W732-IX-200', 21:18:10 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:18:10 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:18:10 INFO - 'DCLOCATION': 'SCL3', 21:18:10 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:18:10 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:18:10 INFO - 'HOMEDRIVE': 'C:', 21:18:10 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:18:10 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:18:10 INFO - 'KTS_VERSION': '1.19c', 21:18:10 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:18:10 INFO - 'LOGONSERVER': '\\\\T-W732-IX-200', 21:18:10 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:18:10 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:18:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:10 INFO - 'MOZ_NO_REMOTE': '1', 21:18:10 INFO - 'NO_EM_RESTART': '1', 21:18:10 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:18:10 INFO - 'OS': 'Windows_NT', 21:18:10 INFO - 'OURDRIVE': 'C:', 21:18:10 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:18:10 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:18:10 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:18:10 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:18:10 INFO - 'PROCESSOR_LEVEL': '6', 21:18:10 INFO - 'PROCESSOR_REVISION': '1e05', 21:18:10 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:18:10 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:18:10 INFO - 'PROMPT': '$P$G', 21:18:10 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 21:18:10 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:18:10 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:18:10 INFO - 'PWD': 'C:\\slave\\test-pgo', 21:18:10 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:18:10 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:18:10 INFO - 'SYSTEMDRIVE': 'C:', 21:18:10 INFO - 'SYSTEMROOT': 'C:\\windows', 21:18:10 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:10 INFO - 'TEST1': 'testie', 21:18:10 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:10 INFO - 'USERDOMAIN': 'T-W732-IX-200', 21:18:10 INFO - 'USERNAME': 'cltbld', 21:18:10 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:18:10 INFO - 'WINDIR': 'C:\\windows', 21:18:10 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:18:10 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:18:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:18:10 INFO - Ignoring indexes: http://pypi.python.org/simple/ 21:18:10 INFO - Downloading/unpacking pip>=1.5 21:18:10 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache 21:18:15 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpip-1.5.5.tar.gz 21:18:15 INFO - Running setup.py egg_info for package pip 21:18:15 INFO - warning: no files found matching 'pip\cacert.pem' 21:18:15 INFO - warning: no files found matching '*.html' under directory 'docs' 21:18:15 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 21:18:15 INFO - no previously-included directories found matching 'docs\_build\_sources' 21:18:15 INFO - Installing collected packages: pip 21:18:15 INFO - Found existing installation: pip 0.8.2 21:18:15 INFO - Uninstalling pip: 21:18:15 INFO - Successfully uninstalled pip 21:18:15 INFO - Running setup.py install for pip 21:18:15 INFO - warning: no files found matching 'pip\cacert.pem' 21:18:15 INFO - warning: no files found matching '*.html' under directory 'docs' 21:18:15 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 21:18:15 INFO - no previously-included directories found matching 'docs\_build\_sources' 21:18:15 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:15 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:15 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:15 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:15 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:15 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:15 INFO - Successfully installed pip 21:18:15 INFO - Cleaning up... 21:18:15 INFO - Return code: 0 21:18:15 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 21:18: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')]} 21:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18: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')]} 21:18:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18: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', '--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 0x0192CB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01985EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184AE58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01348B48>, '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 0x0136A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECF660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-200', 'USERDOMAIN': 'T-W732-IX-200', '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-200', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 21:18:15 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in C:\slave\test-pgo\build 21:18:15 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 21:18:15 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:18:15 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:18:15 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:18:15 INFO - 'COMPUTERNAME': 'T-W732-IX-200', 21:18:15 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:18:15 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:18:15 INFO - 'DCLOCATION': 'SCL3', 21:18:15 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:18:15 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:18:15 INFO - 'HOMEDRIVE': 'C:', 21:18:15 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:18:15 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:18:15 INFO - 'KTS_VERSION': '1.19c', 21:18:15 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:18:15 INFO - 'LOGONSERVER': '\\\\T-W732-IX-200', 21:18:15 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:18:15 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:15 INFO - 'MOZ_NO_REMOTE': '1', 21:18:15 INFO - 'NO_EM_RESTART': '1', 21:18:15 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:18:15 INFO - 'OS': 'Windows_NT', 21:18:15 INFO - 'OURDRIVE': 'C:', 21:18: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;', 21:18:15 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:18:15 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:18:15 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:18:15 INFO - 'PROCESSOR_LEVEL': '6', 21:18:15 INFO - 'PROCESSOR_REVISION': '1e05', 21:18:15 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:18:15 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:18:15 INFO - 'PROMPT': '$P$G', 21:18:15 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 21:18:15 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:18:15 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:18:15 INFO - 'PWD': 'C:\\slave\\test-pgo', 21:18:15 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:18:15 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:18:15 INFO - 'SYSTEMDRIVE': 'C:', 21:18:15 INFO - 'SYSTEMROOT': 'C:\\windows', 21:18:15 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:15 INFO - 'TEST1': 'testie', 21:18:15 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:15 INFO - 'USERDOMAIN': 'T-W732-IX-200', 21:18:15 INFO - 'USERNAME': 'cltbld', 21:18:15 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:18:15 INFO - 'WINDIR': 'C:\\windows', 21:18:15 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:18:15 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:16 INFO - Downloading/unpacking psutil>=0.7.1 21:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:17 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpsutil-3.1.1-cp27-none-win32.whl 21:18:17 INFO - Installing collected packages: psutil 21:18:17 INFO - Successfully installed psutil 21:18:17 INFO - Cleaning up... 21:18:17 INFO - Return code: 0 21:18:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 21:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:17 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 0x0192CB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01985EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184AE58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01348B48>, '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 0x0136A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECF660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-200', 'USERDOMAIN': 'T-W732-IX-200', '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-200', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 21:18:17 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in C:\slave\test-pgo\build 21:18:17 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 21:18:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:18:17 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:18:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:18:17 INFO - 'COMPUTERNAME': 'T-W732-IX-200', 21:18:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:18:17 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:18:17 INFO - 'DCLOCATION': 'SCL3', 21:18:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:18:17 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:18:17 INFO - 'HOMEDRIVE': 'C:', 21:18:17 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:18:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:18:17 INFO - 'KTS_VERSION': '1.19c', 21:18:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:18:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-200', 21:18:17 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:18:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:18:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:17 INFO - 'MOZ_NO_REMOTE': '1', 21:18:17 INFO - 'NO_EM_RESTART': '1', 21:18:17 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:18:17 INFO - 'OS': 'Windows_NT', 21:18:17 INFO - 'OURDRIVE': 'C:', 21:18:17 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:18:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:18:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:18:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:18:17 INFO - 'PROCESSOR_LEVEL': '6', 21:18:17 INFO - 'PROCESSOR_REVISION': '1e05', 21:18:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:18:17 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:18:17 INFO - 'PROMPT': '$P$G', 21:18:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 21:18:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:18:17 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:18:17 INFO - 'PWD': 'C:\\slave\\test-pgo', 21:18:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:18:17 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:18:17 INFO - 'SYSTEMDRIVE': 'C:', 21:18:17 INFO - 'SYSTEMROOT': 'C:\\windows', 21:18:17 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:17 INFO - 'TEST1': 'testie', 21:18:17 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:17 INFO - 'USERDOMAIN': 'T-W732-IX-200', 21:18:17 INFO - 'USERNAME': 'cltbld', 21:18:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:18:17 INFO - 'WINDIR': 'C:\\windows', 21:18:17 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:18:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:18:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:18 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:19 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:18:19 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozsystemmonitor-0.0.tar.gz 21:18:19 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 21:18:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0) 21:18:19 INFO - Installing collected packages: mozsystemmonitor 21:18:19 INFO - Running setup.py install for mozsystemmonitor 21:18:19 INFO - Successfully installed mozsystemmonitor 21:18:19 INFO - Cleaning up... 21:18:19 INFO - Return code: 0 21:18:19 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 21:18: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')]} 21:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18: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')]} 21:18:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:19 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x0192CB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01985EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184AE58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01348B48>, '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 0x0136A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECF660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-200', 'USERDOMAIN': 'T-W732-IX-200', '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-200', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 21:18:19 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test-pgo\build 21:18:19 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:18:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:18:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:18:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:18:19 INFO - 'COMPUTERNAME': 'T-W732-IX-200', 21:18:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:18:19 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:18:19 INFO - 'DCLOCATION': 'SCL3', 21:18:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:18:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:18:19 INFO - 'HOMEDRIVE': 'C:', 21:18:19 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:18:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:18:19 INFO - 'KTS_VERSION': '1.19c', 21:18:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:18:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-200', 21:18:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:18:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:18:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:19 INFO - 'MOZ_NO_REMOTE': '1', 21:18:19 INFO - 'NO_EM_RESTART': '1', 21:18:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:18:19 INFO - 'OS': 'Windows_NT', 21:18:19 INFO - 'OURDRIVE': 'C:', 21:18: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;', 21:18:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:18:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:18:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:18:19 INFO - 'PROCESSOR_LEVEL': '6', 21:18:19 INFO - 'PROCESSOR_REVISION': '1e05', 21:18:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:18:19 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:18:19 INFO - 'PROMPT': '$P$G', 21:18:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 21:18:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:18:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:18:19 INFO - 'PWD': 'C:\\slave\\test-pgo', 21:18:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:18:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:18:19 INFO - 'SYSTEMDRIVE': 'C:', 21:18:19 INFO - 'SYSTEMROOT': 'C:\\windows', 21:18:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:19 INFO - 'TEST1': 'testie', 21:18:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:19 INFO - 'USERDOMAIN': 'T-W732-IX-200', 21:18:19 INFO - 'USERNAME': 'cltbld', 21:18:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:18:19 INFO - 'WINDIR': 'C:\\windows', 21:18:19 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:18:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:18:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:18:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:19 INFO - Downloading/unpacking blobuploader==1.2.4 21:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:20 INFO - Downloading blobuploader-1.2.4.tar.gz 21:18:20 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblobuploader-1.2.4.tar.gz 21:18:20 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 21:18:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:21 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2frequests-1.2.3.tar.gz 21:18:21 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 21:18:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:21 INFO - Downloading docopt-0.6.1.tar.gz 21:18:21 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fdocopt-0.6.1.tar.gz 21:18:21 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 21:18:21 INFO - Installing collected packages: blobuploader, requests, docopt 21:18:21 INFO - Running setup.py install for blobuploader 21:18:22 INFO - Running setup.py install for requests 21:18:22 INFO - Running setup.py install for docopt 21:18:22 INFO - Successfully installed blobuploader requests docopt 21:18:22 INFO - Cleaning up... 21:18:24 INFO - Return code: 0 21:18:24 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 21:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:24 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 0x0192CB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01985EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184AE58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01348B48>, '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 0x0136A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECF660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-200', 'USERDOMAIN': 'T-W732-IX-200', '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-200', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 21:18:24 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config 21:18:24 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --no-deps --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:18:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:18:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:18:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:18:24 INFO - 'COMPUTERNAME': 'T-W732-IX-200', 21:18:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:18:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:18:24 INFO - 'DCLOCATION': 'SCL3', 21:18:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:18:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:18:24 INFO - 'HOMEDRIVE': 'C:', 21:18:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:18:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:18:24 INFO - 'KTS_VERSION': '1.19c', 21:18:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:18:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-200', 21:18:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:18:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:18:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:24 INFO - 'MOZ_NO_REMOTE': '1', 21:18:24 INFO - 'NO_EM_RESTART': '1', 21:18:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:18:24 INFO - 'OS': 'Windows_NT', 21:18:24 INFO - 'OURDRIVE': 'C:', 21:18:24 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:18:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:18:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:18:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:18:24 INFO - 'PROCESSOR_LEVEL': '6', 21:18:24 INFO - 'PROCESSOR_REVISION': '1e05', 21:18:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:18:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:18:24 INFO - 'PROMPT': '$P$G', 21:18:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 21:18:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:18:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:18:24 INFO - 'PWD': 'C:\\slave\\test-pgo', 21:18:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:18:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:18:24 INFO - 'SYSTEMDRIVE': 'C:', 21:18:24 INFO - 'SYSTEMROOT': 'C:\\windows', 21:18:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:24 INFO - 'TEST1': 'testie', 21:18:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:24 INFO - 'USERDOMAIN': 'T-W732-IX-200', 21:18:24 INFO - 'USERNAME': 'cltbld', 21:18:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:18:24 INFO - 'WINDIR': 'C:\\windows', 21:18:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:18:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:18:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 21:18:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6b27on-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 21:18:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 21:18:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0f1dji-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 21:18:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 21:18:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wvgyl9-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 21:18:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 21:18:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pvzrk9-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 21:18:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 21:18:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wlu6vb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 21:18:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 21:18:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3ahu35-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 21:18:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 21:18:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yloutu-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 21:18:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 21:18:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l7nlll-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 21:18:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 21:18:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mgpl94-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 21:18:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 21:18:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jwkmnf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 21:18:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 21:18:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h9xzri-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 21:18:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 21:18:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xpdunm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 21:18:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 21:18:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gmc4c3-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 21:18:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 21:18:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f9zvav-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 21:18:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 21:18:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0ykb1a-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:18:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 21:18:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v3hrlj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 21:18:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 21:18:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vduqcd-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 21:18:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:18:27 INFO - Running setup.py install for manifestparser 21:18:27 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:27 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:28 INFO - Running setup.py install for mozcrash 21:18:28 INFO - Running setup.py install for mozdebug 21:18:28 INFO - Running setup.py install for mozdevice 21:18:28 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:28 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:28 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:28 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:29 INFO - Running setup.py install for mozfile 21:18:29 INFO - Running setup.py install for mozhttpd 21:18:29 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:29 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:30 INFO - Running setup.py install for mozinfo 21:18:30 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:30 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:30 INFO - Running setup.py install for mozInstall 21:18:30 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:30 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:30 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:30 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:30 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:30 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:30 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:30 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:30 INFO - Running setup.py install for mozleak 21:18:31 INFO - Running setup.py install for mozlog 21:18:31 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:31 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:32 INFO - Running setup.py install for moznetwork 21:18:32 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:32 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:32 INFO - Running setup.py install for mozprocess 21:18:33 INFO - Running setup.py install for mozprofile 21:18:33 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:33 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:33 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:33 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:33 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:33 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:33 INFO - Running setup.py install for mozrunner 21:18:33 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:33 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:34 INFO - Running setup.py install for mozscreenshot 21:18:34 INFO - Running setup.py install for moztest 21:18:35 INFO - Running setup.py install for mozversion 21:18:35 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 21:18:35 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 21:18:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:18:35 INFO - Cleaning up... 21:18:35 INFO - Return code: 0 21:18:35 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 21:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:35 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 0x0192CB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01985EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184AE58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01348B48>, '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 0x0136A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECF660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-200', 'USERDOMAIN': 'T-W732-IX-200', '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-200', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 21:18:35 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config 21:18:35 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:18:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:18:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:18:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:18:35 INFO - 'COMPUTERNAME': 'T-W732-IX-200', 21:18:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:18:35 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:18:35 INFO - 'DCLOCATION': 'SCL3', 21:18:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:18:35 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:18:35 INFO - 'HOMEDRIVE': 'C:', 21:18:35 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:18:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:18:35 INFO - 'KTS_VERSION': '1.19c', 21:18:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:18:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-200', 21:18:35 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:18:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:35 INFO - 'MOZ_NO_REMOTE': '1', 21:18:35 INFO - 'NO_EM_RESTART': '1', 21:18:35 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:18:35 INFO - 'OS': 'Windows_NT', 21:18:35 INFO - 'OURDRIVE': 'C:', 21:18:35 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:18:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:18:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:18:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:18:35 INFO - 'PROCESSOR_LEVEL': '6', 21:18:35 INFO - 'PROCESSOR_REVISION': '1e05', 21:18:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:18:35 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:18:35 INFO - 'PROMPT': '$P$G', 21:18:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 21:18:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:18:35 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:18:35 INFO - 'PWD': 'C:\\slave\\test-pgo', 21:18:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:18:35 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:18:35 INFO - 'SYSTEMDRIVE': 'C:', 21:18:35 INFO - 'SYSTEMROOT': 'C:\\windows', 21:18:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:35 INFO - 'TEST1': 'testie', 21:18:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:35 INFO - 'USERDOMAIN': 'T-W732-IX-200', 21:18:35 INFO - 'USERNAME': 'cltbld', 21:18:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:18:35 INFO - 'WINDIR': 'C:\\windows', 21:18:35 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:18:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 21:18:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y7oyws-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 21:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 1)) 21:18:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 21:18:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fi7rxh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 21:18:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 21:18:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 21:18:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aejium-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 21:18:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 21:18:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 21:18:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jncpmc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 21:18:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 21:18:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 21:18:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-epmttf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 21:18:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 5)) 21:18:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 21:18:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-akir7g-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 21:18:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 6)) 21:18:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 21:18:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-npvahe-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 21:18:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 7)) 21:18:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 21:18:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qxm6lr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 21:18:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 8)) 21:18:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 21:18:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y4tz8f-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 21:18:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 9)) 21:18:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 21:18:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bzgwbx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 21:18:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 21:18:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 21:18:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lj87i8-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 21:18:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 11)) 21:18:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 21:18:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yaktty-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 21:18:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 12)) 21:18:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 21:18:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hyd6to-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 21:18:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 13)) 21:18:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 21:18:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eh7us9-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 21:18:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14)) 21:18:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 21:18:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yvxe0v-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:18:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 15)) 21:18:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 21:18:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4ouc4d-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 21:18:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/moztest in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 16)) 21:18:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 21:18:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-htkptw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 21:18:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 17)) 21:18:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 21:18:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 21:18:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 21:18:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 21:18:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 21:18:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 21:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:39 INFO - Downloading blessings-1.5.1.tar.gz 21:18:39 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblessings-1.5.1.tar.gz 21:18:39 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 21:18:39 INFO - Installing collected packages: blessings 21:18:39 INFO - Running setup.py install for blessings 21:18:39 INFO - Successfully installed blessings 21:18:39 INFO - Cleaning up... 21:18:42 INFO - Return code: 0 21:18:42 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 21:18:42 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 21:18:42 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 21:18:42 INFO - Reading from file tmpfile_stdout 21:18:42 INFO - Using _rmtree_windows ... 21:18:42 INFO - Using _rmtree_windows ... 21:18:42 INFO - Current package versions: 21:18:42 INFO - blessings == 1.5.1 21:18:42 INFO - blobuploader == 1.2.4 21:18:42 INFO - docopt == 0.6.1 21:18:42 INFO - manifestparser == 1.1 21:18:42 INFO - mozInstall == 1.12 21:18:42 INFO - mozcrash == 0.16 21:18:42 INFO - mozdebug == 0.1 21:18:42 INFO - mozdevice == 0.46 21:18:42 INFO - mozfile == 1.2 21:18:42 INFO - mozhttpd == 0.7 21:18:42 INFO - mozinfo == 0.8 21:18:42 INFO - mozleak == 0.1 21:18:42 INFO - mozlog == 3.0 21:18:42 INFO - moznetwork == 0.27 21:18:42 INFO - mozprocess == 0.22 21:18:42 INFO - mozprofile == 0.27 21:18:42 INFO - mozrunner == 6.11 21:18:42 INFO - mozscreenshot == 0.1 21:18:42 INFO - mozsystemmonitor == 0.0 21:18:42 INFO - moztest == 0.7 21:18:42 INFO - mozversion == 1.4 21:18:42 INFO - psutil == 3.1.1 21:18:42 INFO - requests == 1.2.3 21:18:42 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 21:18: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')]} 21:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18: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')]} 21:18:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18: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', '-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 0x0192CB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01985EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184AE58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01348B48>, '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 0x0136A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECF660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-200', 'USERDOMAIN': 'T-W732-IX-200', '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-200', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 21:18:42 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\talos 21:18:42 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:18:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:18:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:18:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:18:42 INFO - 'COMPUTERNAME': 'T-W732-IX-200', 21:18:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:18:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:18:42 INFO - 'DCLOCATION': 'SCL3', 21:18:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:18:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:18:42 INFO - 'HOMEDRIVE': 'C:', 21:18:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:18:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:18:42 INFO - 'KTS_VERSION': '1.19c', 21:18:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:18:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-200', 21:18:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:18:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:42 INFO - 'MOZ_NO_REMOTE': '1', 21:18:42 INFO - 'NO_EM_RESTART': '1', 21:18:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:18:42 INFO - 'OS': 'Windows_NT', 21:18:42 INFO - 'OURDRIVE': 'C:', 21:18:42 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:18:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:18:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:18:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:18:42 INFO - 'PROCESSOR_LEVEL': '6', 21:18:42 INFO - 'PROCESSOR_REVISION': '1e05', 21:18:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:18:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:18:42 INFO - 'PROMPT': '$P$G', 21:18:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 21:18:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:18:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:18:42 INFO - 'PWD': 'C:\\slave\\test-pgo', 21:18:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:18:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:18:42 INFO - 'SYSTEMDRIVE': 'C:', 21:18:42 INFO - 'SYSTEMROOT': 'C:\\windows', 21:18:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:42 INFO - 'TEST1': 'testie', 21:18:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:42 INFO - 'USERDOMAIN': 'T-W732-IX-200', 21:18:42 INFO - 'USERNAME': 'cltbld', 21:18:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:18:42 INFO - 'WINDIR': 'C:\\windows', 21:18:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:18:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:18:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 21:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 2)) 21:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3)) 21:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 4)) 21:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 5)) 21:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 6)) 21:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 7)) 21:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 8)) 21:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash>=0.15->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 21:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3)) 21:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozlog>=3.0->mozcrash>=0.15->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 21:18:43 INFO - Cleaning up... 21:18:43 INFO - Return code: 0 21:18:43 INFO - Running post-action listener: _resource_record_post_action 21:18:43 INFO - Running post-action listener: _start_resource_monitoring 21:18:43 INFO - Starting resource monitoring. 21:18:43 INFO - ##### 21:18:43 INFO - ##### Running install step. 21:18:43 INFO - ##### 21:18:43 INFO - Running pre-action listener: _resource_record_pre_action 21:18:43 INFO - Running main action method: install 21:18:43 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 21:18:43 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 21:18:43 INFO - Reading from file tmpfile_stdout 21:18:43 INFO - Using _rmtree_windows ... 21:18:43 INFO - Using _rmtree_windows ... 21:18:43 INFO - Detecting whether we're running mozinstall >=1.0... 21:18:43 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 21:18:43 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 21:18:43 INFO - Reading from file tmpfile_stdout 21:18:43 INFO - Output received: 21:18:43 INFO - Usage: mozinstall-script.py [options] installer 21:18:43 INFO - Options: 21:18:43 INFO - -h, --help show this help message and exit 21:18:43 INFO - -d DEST, --destination=DEST 21:18:43 INFO - Directory to install application into. [default: 21:18:43 INFO - "C:\slave\test-pgo"] 21:18:43 INFO - --app=APP Application being installed. [default: firefox] 21:18:43 INFO - Using _rmtree_windows ... 21:18:43 INFO - Using _rmtree_windows ... 21:18:43 INFO - mkdir: C:\slave\test-pgo\build\application 21:18:43 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test-pgo\\build\\application'] 21:18:43 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip --destination C:\slave\test-pgo\build\application 21:18:45 INFO - Reading from file tmpfile_stdout 21:18:45 INFO - Output received: 21:18:45 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 21:18:45 INFO - Using _rmtree_windows ... 21:18:45 INFO - Using _rmtree_windows ... 21:18:45 INFO - Running post-action listener: _resource_record_post_action 21:18:45 INFO - ##### 21:18:45 INFO - ##### Running run-tests step. 21:18:45 INFO - ##### 21:18:45 INFO - Running pre-action listener: _resource_record_pre_action 21:18:45 INFO - Running main action method: run_tests 21:18:45 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 21:18:45 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 21:18:45 INFO - Python 2.7.3 21:18:45 INFO - Return code: 0 21:18:45 INFO - grabbing minidump binary from tooltool 21:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:45 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 0x01348B48>, '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 0x0136A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECF660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 21:18:45 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test-pgo\build 21:18:45 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test-pgo\build\tests\config/tooltool-manifests/win32/releng.manifest -o 21:18:45 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 21:18:45 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmprpmplm 21:18:45 INFO - INFO - File integrity verified, renaming tmprpmplm to win32-minidump_stackwalk.exe 21:18:45 INFO - Return code: 0 21:18:45 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 21:18:45 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 21:18:45 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 21:18:45 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 21:18:45 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 21:18:45 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-200', '--webServer', 'localhost'] in C:\slave\test-pgo\build 21:18:45 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName Firefox --suite dromaeojs --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-200 --webServer localhost 21:18:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:18:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:18:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:18:45 INFO - 'COMPUTERNAME': 'T-W732-IX-200', 21:18:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:18:45 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:18:45 INFO - 'DCLOCATION': 'SCL3', 21:18:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:18:45 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:18:45 INFO - 'HOMEDRIVE': 'C:', 21:18:45 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:18:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:18:45 INFO - 'KTS_VERSION': '1.19c', 21:18:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:18:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-200', 21:18:45 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 21:18:45 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 21:18:45 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:18:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:45 INFO - 'MOZ_NO_REMOTE': '1', 21:18:45 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 21:18:45 INFO - 'NO_EM_RESTART': '1', 21:18:45 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:18:45 INFO - 'OS': 'Windows_NT', 21:18:45 INFO - 'OURDRIVE': 'C:', 21:18:45 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:18:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:18:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:18:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:18:45 INFO - 'PROCESSOR_LEVEL': '6', 21:18:45 INFO - 'PROCESSOR_REVISION': '1e05', 21:18:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:18:45 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:18:45 INFO - 'PROMPT': '$P$G', 21:18:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 21:18:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:18:45 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:18:45 INFO - 'PWD': 'C:\\slave\\test-pgo', 21:18:45 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 21:18:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:18:45 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:18:45 INFO - 'SYSTEMDRIVE': 'C:', 21:18:45 INFO - 'SYSTEMROOT': 'C:\\windows', 21:18:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:45 INFO - 'TEST1': 'testie', 21:18:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:45 INFO - 'USERDOMAIN': 'T-W732-IX-200', 21:18:45 INFO - 'USERNAME': 'cltbld', 21:18:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:18:45 INFO - 'WINDIR': 'C:\\windows', 21:18:45 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:18:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:18:45 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-200', '--webServer', 'localhost'] with output_timeout 3600 21:18:47 INFO - mozversion INFO | application_buildid: 20151023163014 21:18:47 INFO - mozversion INFO | application_changeset: 26078ba1c7be11cf46706216cad8603b92335cd0 21:18:47 INFO - mozversion INFO | application_display_name: Nightly 21:18:47 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:18:47 INFO - mozversion INFO | application_name: Firefox 21:18:47 INFO - mozversion INFO | application_remotingname: firefox 21:18:47 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 21:18:47 INFO - mozversion INFO | application_vendor: Mozilla 21:18:47 INFO - mozversion INFO | application_version: 44.0a1 21:18:47 INFO - mozversion INFO | platform_buildid: 20151023163014 21:18:47 INFO - mozversion INFO | platform_changeset: 26078ba1c7be11cf46706216cad8603b92335cd0 21:18:47 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 21:18:47 INFO - mozversion INFO | platform_version: 44.0a1 21:18:47 INFO - 2015-10-23 21:18:47,210 DEBUG : using testdate: 1445660327 21:18:47 INFO - 2015-10-23 21:18:47,210 DEBUG : actual date: 1445660327 21:18:47 INFO - 2015-10-23 21:18:47,257 INFO : starting webserver on 'localhost:49891' 21:18:47 INFO - 2015-10-23 21:18:47,259 INFO : Starting test suite T-W732-IX-200 21:18:47 INFO - 2015-10-23 21:18:47,259 INFO : Starting test dromaeo_css 21:18:47 INFO - 2015-10-23 21:18:47,259 DEBUG : operating with platform_type : w7_ 21:18:47 INFO - 2015-10-23 21:18:47,260 INFO : Initialising browser for dromaeo_css test... 21:18:47 INFO - 2015-10-23 21:18:47,351 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp0pz_xi\profile http://localhost:49891/getInfo.html 21:18:55 INFO - 2015-10-23 21:18:55,966 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 21:18:55 INFO - 2015-10-23 21:18:55,966 DEBUG : BROWSER_OUTPUT: colorDepth:24 21:18:55 INFO - 2015-10-23 21:18:55,966 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 21:18:55 INFO - 2015-10-23 21:18:55,966 DEBUG : BROWSER_OUTPUT: __metrics 21:18:56 INFO - 2015-10-23 21:18:56,046 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:18:56 INFO - 2015-10-23 21:18:56,789 DEBUG : BROWSER_OUTPUT: console.error: 21:18:56 INFO - 2015-10-23 21:18:56,789 DEBUG : BROWSER_OUTPUT: Message: Error: Transaction canceled due to a closed connection. 21:18:56 INFO - 2015-10-23 21:18:56,789 DEBUG : BROWSER_OUTPUT: Stack: 21:18:56 INFO - 2015-10-23 21:18:56,789 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise<@resource://gre/modules/Sqlite.jsm:562:1 21:18:56 INFO - 2015-10-23 21:18:56,789 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 21:18:56 INFO - 2015-10-23 21:18:56,789 DEBUG : BROWSER_OUTPUT: 21:18:57 INFO - 2015-10-23 21:18:57,408 INFO : Browser initialized. 21:18:57 INFO - 2015-10-23 21:18:57,408 INFO : Running cycle 1/1 for dromaeo_css test... 21:18:57 INFO - 2015-10-23 21:18:57,408 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp0pz_xi\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 21:19:08 INFO - 2015-10-23 21:19:08,815 DEBUG : BROWSER_OUTPUT: RSS: Main: 127250432 21:19:08 INFO - 2015-10-23 21:19:08,815 DEBUG : BROWSER_OUTPUT: 21:20:01 INFO - 2015-10-23 21:20:01,318 DEBUG : BROWSER_OUTPUT: 1445660401320 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:22:09 INFO - 2015-10-23 21:22:09,092 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49891/tests/dromaeo/cssquery-ext.html) 21:22:10 INFO - 2015-10-23 21:22:10,075 DEBUG : BROWSER_OUTPUT: RSS: Main: 185270272 21:22:10 INFO - 2015-10-23 21:22:10,075 DEBUG : BROWSER_OUTPUT: 21:25:12 INFO - 2015-10-23 21:25:12,394 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-ext.html (next: http://localhost:49891/tests/dromaeo/cssquery-jquery.html) 21:25:12 INFO - 2015-10-23 21:25:12,950 DEBUG : BROWSER_OUTPUT: RSS: Main: 192270336 21:25:12 INFO - 2015-10-23 21:25:12,950 DEBUG : BROWSER_OUTPUT: 21:28:14 INFO - 2015-10-23 21:28:14,720 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49891/tests/dromaeo/cssquery-mootools.html) 21:28:15 INFO - 2015-10-23 21:28:15,288 DEBUG : BROWSER_OUTPUT: RSS: Main: 150171648 21:28:15 INFO - 2015-10-23 21:28:15,289 DEBUG : BROWSER_OUTPUT: 21:31:17 INFO - 2015-10-23 21:31:17,183 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49891/tests/dromaeo/cssquery-prototype.html) 21:31:17 INFO - 2015-10-23 21:31:17,743 DEBUG : BROWSER_OUTPUT: RSS: Main: 139726848 21:31:17 INFO - 2015-10-23 21:31:17,743 DEBUG : BROWSER_OUTPUT: 21:34:20 INFO - 2015-10-23 21:34:20,463 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49891/tests/dromaeo/cssquery-yui.html) 21:34:21 INFO - 2015-10-23 21:34:21,029 DEBUG : BROWSER_OUTPUT: RSS: Main: 128532480 21:34:21 INFO - 2015-10-23 21:34:21,030 DEBUG : BROWSER_OUTPUT: 21:37:23 INFO - 2015-10-23 21:37:23,479 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-yui.html (next: http://localhost:49891/tests/dromaeo/cssquery-dojo.html) 21:37:23 INFO - 2015-10-23 21:37:23,726 DEBUG : BROWSER_OUTPUT: RSS: Main: 190083072 21:37:23 INFO - 2015-10-23 21:37:23,726 DEBUG : BROWSER_OUTPUT: 21:37:23 INFO - 2015-10-23 21:37:23,726 DEBUG : BROWSER_OUTPUT: __start_tp_report 21:37:23 INFO - 2015-10-23 21:37:23,726 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 21:37:23 INFO - 2015-10-23 21:37:23,726 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 21:37:23 INFO - 2015-10-23 21:37:23,726 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 21:37:23 INFO - 2015-10-23 21:37:23,727 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2757.2555410039304;2808.0733357991867;2835.135283738459;2844.0045983905634;2893.088677066724;5091.109055915209;5222.112240919043;5261.605379596531;5362.22247774073;5366.168243922199;5188.8702782430455;5242.973785131069;5264.8157314494;5367.919481207785;5402.567794576435;4490.41624588803;4585.495595484494;4660.930086048712;4674.158651442756;4674.9766251168785;4467.195904737146;4468.463784345867;4603.332516785064;4628.143793398232;4628.814847406116;4633.49031606523;4639.257718764999;4641.466231383401;4648.279516674921;4648.558386953234;4373.715708478939;4432.423784907974;4585.266357382836;4626.722396656194;4637.397138372024;4483.932741008887;4544.568266014723;4546.909061818745;4620.630349572026;4623.907521849578;5129.717865517395;5230.189320655304;5240.3187585614005;5348.732563371816;5377.542908852753;5372.355317361902;5454.481824226692;5485.835424937219;5528.530074943635;5656.971715141439;5557.694326812024;5565.499105080522;5579.888402231933;5657.405972372924;5663.688497132656;5163.251328557338;5175.870603234912;5177.715225662587;5237.109691352479;5262.263283140325;5487.862803429946;5514.420985796514;5667.659940403589;5671.631343962629;5796.652200867961;5423.810166644149;5470.097433923438;5482.17767334903;5543.861403464945;5589.9379117967455;5567.276254086943;5571.637843540157;5577.581681373913;5663.348714897839;5680.573956953245;5396.622236443412;5488.862778430571;5490.780368785204;5510;5611.8877622448135;5487.64330318536;5492.835214943558;5577.96807590597;5590.720463976866;5604.41153678866;3469.8959031228596;3488.284901595167;3516.1033936346066;3556.6621170988715;3556.87550935716;3149.4960806271347;3190.4257233697695;3247.9837600812316;3257.4299497587826;3269.54226408303;5219;5314.0700377433795;5400.729963501809;5415.620906536505;5424.050791111618;5496.780128794803;5596.300462442195;5617.9438205618235;5723.255976723082;5751.051076572402;5170.250313704567;5271.6309858310315;5275.841724748264;5306.973465132727;5426.457354264542;4384.254676704968;4390.385346051556;4397.34039894012;4473.910521789546;4502.707324023928;5742.684152371493;5880.26496687914;5886.352501224862;5969.701514924236;5987.042073268256;5469.945300547103;5553.028220061554;5565.972170139124;5597.608167428241;5717.942820571741;5736.827895163152;5869.325027621878;5886.117082437498;5976.372480889444;5978.8206353809455;5498.697571633598;5525.060739674184;5606.803761868315;5633.239512665901;5634.971825140848;5517.200005999188;5604.327480702342;5612.550995920398;5616.10142377234;5619.129034999582;5493.533049690661;5531.032069387921;5572.4706152915405;5599.300087489064;5613;5455.208994695826;5455.672659640434;5496.862578435571;5549.667019978814;5578.693171875586;5215.55667768244;5229.320188375639;5302.28419163408;5313.707746074003;5421.403645598902;5391.86770778141;5528.695921724344;5587.050201465842;5591.776328946797;5611.859703507445;5105.591552675852;5280.735963201748;5504.862378440571;5513.862153446035;5515.61390702647 21:37:23 INFO - 2015-10-23 21:37:23,729 DEBUG : BROWSER_OUTPUT: |1;ext.html;471.67218782945855;473.495727050691;481.5281024595896;516.3726072821521;517.6505858545481;31446;31716;31724.84137579312;31857.68142318576;37151.628483715154;38186.42720359197;39175.804120979425;39706.00734981627;39835.40246896299;40015.199696006064;8040.994875640545;8111.107778144399;8220.05469371024;8270.37972152088;8275.668973241063;7634.274743899321;7758.069031716173;9927.453990030546;10184.796304073914;10199.54102065409;9961.501924903761;10035.448050357227;10046.04562566555;10056.245781566375;10066.547005384775;7809.609519524001;8009.639566219534;9931.404115753041;9979.50102494876;10012.54943527543;9600.711978640651;9794.755131121708;9843;9851.901480985242;9852.556634951445;34791.65208347909;34927.650723492945;35170.824145879364;35176.47235291472;35194.12014699653;3070.2170946408746;3230.16015835882;3244.67553244676;3274.721648659843;3462.9307413851584;29267.853660731562;29393.262886342258;29433.85283073571;30344.54483182754;30357.848210759024;2748.367875388652;2763.46112508061;2767.9308017299527;2780.624615676878;2815.6761972373233;314777;315382;315442;316744;317384.9999999977;44572;45302.80686845433;51424.48575514272;51594.48976327539;51855.48144518582;55191.448085519434;57003.42996570064;57094.7145264271;57131.14303285454;57275.71362143163;14021.088629239066;14066.43734250639;14104.365303561364;14106.929465352607;14154.85845141556;9142.771430714152;9642.132208101304;9717.85423218638;9720.368176068674;9725.61097556104;16579.66840663204;16674;16791.91604041972;16836.410725624544;16845.54666293407;7235.454627232723;7294;7430.888536671954;7470;7492.850142997218;844.7423535821633;921.1295325917079;934.5981228071994;962.41292811385;991.5884907763342;27305.726942730715;27774.583381249297;28040.43919121647;28169.859150704524;28515.144545663665;968;982.081753560409;982.1504398695077;1023.498485741996;1068.3429690740295;13677.179369237878;13941.616048593463;14165.291735413188;14208.573742787732;14555.199464029369;380917;385206;389169;389578;390395;45988.77005615018;46453;46484;46865;46925.29612055822;1175.1597607710528;1183.4845179694203;1187.9168458207841;1188.3879801901855;1197.3115458611333;22476.66285005759;25793;25893.352666183495;26776;29418.411631767674;42734;42803;43001.56998429976;43102.35346469868;43266.783666081465;303863;319042;319373;322147;413930;24160.87919560391;26407.33981650474;26556.203313900613;26912;26934.191974240803;36987.8150609256;37291.11785376823;37627;37739;37993.62006379901;31309.686903131587;32141.839290803397;32153.678463215067;32526.512102318957;32707.34585308233;30932.22669433282;31687.683123168474;32215.35569288648;32537.34925301528;34362.14094647654;33522.832385837915;34358.65641343554;34472.31055378828;34719;34732.13169670779;29229.7077029227;30416.695833041387;31625.36749264956;32600;33617.66382336145;24276.757232427448;24594.754052459248;24985.75014249907;25014.37464063413;25150.49699005973 21:37:23 INFO - 2015-10-23 21:37:23,733 DEBUG : BROWSER_OUTPUT: |2;jquery.html;318.158470844616;318.40935065453584;321.01128524145633;322.8401941039186;326.14712526742574;11619.186656934018;11807.232529885552;17093.14534273286;17456.65086698267;17471.737923931134;17367.263273672626;17574.209160587772;17802.28790848368;17865.28538858444;18098.63802723945;5432.565394768419;5528.087865502207;5548.167774833777;5552.139418390156;5553.972230138844;5331.413544510101;5512.5589952803675;5533.723313834313;5534.916976245359;5548.77804887804;4416.933745993812;4459.977700111511;5460.781568737246;5468.72656367182;5475.4524547545125;5818.650880947157;5910.88178236435;6048.879022419571;6052.152698622196;6056;5178.17149256121;5335.51980321769;5335.546478549328;5341.439148889388;5350.250964864922;22502;22845.31464056081;22927.54144917109;22945.42636434086;23069.76930230693;2027.9898600507051;2108.7153234313323;2119.8198153157;2203.5813195492806;2371.620540713495;13984.93007534966;14046.508372207027;14384.928075359556;14450.49423270191;14630.561083167522;1679.202378870027;1791.3282519055354;1793.3723196881117;1802.2208586528275;1816.327958655306;77093.24937954808;80247.14192164836;80381;80528;80596.59701701454;10002.699919002442;10093.848592271122;10267.383956962607;10292.845607315896;10326.638567650096;12840;12890.871091289155;12899.548515801902;12919.160254583421;12922.612321630366;7807.765767026998;7886.0536735592095;7888.368930485547;7924.643391047417;7926.722564710208;7866.567338796307;7917.841643167219;7922.0889597697005;7941.324987376138;7957.283844454027;5682.54539636828;5736.311642602915;5746.5977381583725;5754.913676294859;5764.827055188351;1675.2126500544725;1721.8106008339073;1727.2745446912327;1755.6752000879878;1755.9648807023789;383.25648242409636;400.9398590211492;431.60508135056074;436.1603912468485;450.6755136301857;11235.83146252807;11759.412029398496;11810.173287869766;11914.880851191378;12351.567695130627;442.1135623075777;448.6657440207001;453.62575874903337;457.4647662235193;458.09983382653246;6292.433680968735;6421.197350331208;6443.323451037667;6501.252355979028;6535.411812936859;363913;368384.73883936106;399055;401404;402051;80617;82311.59547079237;82848.58575707083;84697.15302847016;85019.5749021251;471.90561887622613;476.35929674587624;482.0696056610705;482.2212127414271;483.15447966059395;11229.66311010671;11238.494267757807;11359.943200283946;11397;11421.885781142415;42005.15989680251;42367.788161059;42901.570984289756;43275.35086973635;43516;51964.740176298874;53538.46461535335;59081.40918590759;59369;59389.70305148447;6117.3270940197435;6258.906116408349;6262.53031022666;6267.529935254783;6284.402981716729;11912.52349905994;12442.080744565434;12480.812787808009;12489.875101248872;12507.687307817378;10406.323588966692;10491.108255798343;10596.523156457824;10653.201009924132;10678.412687302272;9560.378575392577;10155.593776248867;10331.380117192992;10452.68641940743;10913.672589822318;10840.728981775519;10891.945540272249;10902.950233436168;11339.376334301693;11374.829377559177;6215.160953271251;6287.088372186099;6377.266614339411;6379.936200637934;6387.584806987531;10625.9468702656;10652.946735266583;10789.946050269698;10881.619143329945;10972.890271097187 21:37:23 INFO - 2015-10-23 21:37:23,734 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1194.3252062584645;1264.721761212533;1316.7366526694664;1470.4338829550622;1580.0835515401068;7793.766187014385;8075.232852878975;8423.957880210599;8439.535825529594;8466.957665211665;8500.832287766627;8549.444286121397;8566.143385661431;8579.95710021449;8585.27025202857;2008.658528050235;2033.8372930165626;2213.524092320151;2228.710267665201;2241.5965126277265;2205.7683943185975;2265.796078352948;2271.931842044741;2272.1593010586107;2273.193016479151;2222.7332720073614;2237.8433509654333;2243.14760391052;2250.167438047916;2252.144185209618;2023.9981209301416;2035.735354403918;2232.586971410294;2259.152817693365;2259.581977334182;2222.0889435331596;2251.7297924249033;2274.0449011415244;2286.805621522156;2287.645414960684;8720.563971801384;8779.517126558037;8786.253168480687;8787.472751634858;8788.384813063089;5054.342935418409;5101.642884998052;5132.563732082777;5133.332666753336;5134.509762765056;3312.4700047992205;3365.074604483762;3392.9321413571593;3418.8974330770116;3428.4171690812623;1010.6109147978008;1014.1937159957852;1015.9847602285971;1028.1209565821264;1047.7747284333905;12785.232886026868;12798.424070916739;12811.935940320333;12824.615261542169;12825.87174128256;4541.5231400703115;4576.199165146153;4595.6783025187915;4606.286025666027;4622.168009758208;10449.68650940473;10455.790884182274;10456.215783816244;10482.161427085815;10486.947565262277;5138.717370544656;5139.306193663882;5139.717315547606;5150.922736158961;5158.329417175743;4602.608778253886;4665.976670116627;4689.648276379286;4691.061787642485;4696.483386827446;3627.2926779277786;3644.6355364463343;3711.684506816897;3744.8876533704033;3766.60450652683;1176.9058475321954;1180.4274926660562;1181.4092953523239;1188.0911103006208;1188.477070089158;845.3997661660167;1131.1685910855515;1144.0618692671928;1145.0496088246734;1146.2606618731038;7139.714411423485;7672.539647621161;8098.7165449209;8099.878501822477;8123.228293312126;582.9565078509505;599.3467120838308;601.5157797972587;606.3148642034473;606.6057062909056;5851.619644723164;5886.94113058864;5888.057910734347;5899.76400943966;5900.970495147497;10321.741956450998;10611.363318200933;10804.405757683246;10836.945815270874;11023.17326200538;10989.230753847154;11074;11105.555777768961;11129.833052504218;11140.777184456427;7262.745803896838;7577.810554736176;7726.4205184610255;7777.455578109478;7780.377569794403;7514.774556763306;7576.772696819105;7594.582297973665;7608.961955190189;7843;9686.56410461545;9744.561494732612;9762.75593110178;9781.608735650494;9783.168430683472;9670;9743.853842192515;9897.752556185866;9908.752281193028;9915.702528924145;7917.524948503108;7929.960350198212;7993.160718124514;8000.559969201517;8042.195780421911;8679.696210632597;8711.651533938571;8724.43291186071;8730.476171429735;8735.606897689746;7468.775936721907;8023.037235531773;8090.069641991011;8171.305439037748;8196.631151598312;7892.763217103496;7987.880181797394;8034;8041.11547729761;8057.879131812911;7707.383409327128;7960.880586791319;7979.9601001994615;8008.319292860172;8035.638396272298;6907.412869906114;6939.375456208965;6956.130483689539;6965.512414131165;6975.581465112109;6955.965220174069;6972.616506092011;7041.471889608198;7049.436045116481;7063.788086357417 21:37:23 INFO - 2015-10-23 21:37:23,811 DEBUG : BROWSER_OUTPUT: |4;prototype.html;315.2905961586432;316.800415738085;318.193379782252;326.08533064753374;328.9144822346191;5788.160716696082;5818.185454036438;5841.620294680848;5848.502877255428;5860.413958604137;5659.688717120557;5662.490375866171;5697.202391665171;5721.914171287423;5811;4176.18564379946;4289.764062976535;4295.978520107411;4296.677749168809;4300.6774491913075;3996.18078293949;4265.573442655741;4269.5943885331;4283;4286.314189729644;4002.7398219115667;4090.713650044498;4215.747055176684;4224.197402493532;4253.106847562016;4174.248635245654;4219.915601687964;4256.936145957813;4257.978710106429;4260.850870219543;3732.9440058399146;3767.7174211934216;3768.9434658480145;3774.1319496515957;3775.9433608495897;5379.031774280628;5486.506214440679;5520.447955204448;5598.636088654224;5635.7463914123555;5036.043151801146;5070.692834760175;5080.390353157608;5082.440931497532;5087.79648814047;5721.34204566476;5871.6183448075735;6016.4885984691355;6128.540359473057;6132.570720049598;4267.679924005712;4278.807453664562;4288.292431748758;4289.120730250323;4290.056187638715;6383;6386.43532334581;6390.488760899117;6398.776042838478;6412.39082287182;4110.671146308288;4409.713368631029;4419.779011049434;4424.889377765518;4425.247707889666;4365.869023929287;4475.1049790042125;4482.5069242383315;4488.236999710057;4499.932501012488;5031.823886163967;5038.697678139323;5124.385073791169;5142.71715055668;5149.356330458692;6073.423024812636;6136.23297087864;6137.41694539018;6139.539534534839;6143.56995010354;1615.9192040397934;1617.8220395756457;1627.9918600406922;1628.1940439482296;1632.6734653069434;1067.524951396621;1082.3181395720646;1110.6168371911679;1115.5537784885948;1119.2948442481186;5877.764889404461;5915.290165180206;5920.496757775637;5922.940770592325;5928.229330187048;6313.305536390993;6341.460975817107;6349.714262858182;6352.36476352361;6362.554621176566;5770.451807078321;5909.349971503131;5914.4381283777975;5921.02303119989;5922.526197904157;3301.7688761786926;3369.679880411357;3429.5713035870517;3456.6197718250974;3467.6358982306997;6427.389398007182;6656.96671516649;6660.83347916306;6691.464682825363;6695.531312808154;6198.101275315054;6392.456641185551;6475.255345635218;6476.481881449473;6492.513061520405;6470.64411457365;6664.83337916556;6666.566673166229;6667.599944003376;6669.499787515956;6231.75072997075;6313;6320.905186422299;6333.65164915934;6354.4281014708895;6195.256569211723;6206.596571222856;6213.782517611862;6223.284322302812;6224.875502490016;6298.118263443213;6661.267260601427;6661.866762664623;6675.532712710258;6677.499187561049;4192.3711443283755;4332.4801023877335;4355.586219309161;4363.3018717005125;4363.956360436355;5766.509846662912;6000.309964354155;6148.600340977822;6150.354212807591;6326.652034137983;5893.322267939242;6174.073888916519;6175.938240617536;6224.751009959551;6232.719527621359;6139.693015349126;6142.109394137914;6156.538259630456;6170.5372097092;6172.290186628596;5690.317161940594;5990.580659353977;6009.879802404015;6013.789517366871;6022.36765139654;5862.472377485876;6173.660448675366;6178.7219575120125;6180.505559555315;6193.1329613855;5926.881462370815;5969.3732158122775;6000.819975400745;6001.309849367204;6061.515078793598 21:37:23 INFO - 2015-10-23 21:37:23,812 DEBUG : BROWSER_OUTPUT: |5;yui.html;752.9736459223926;760.475272062277;762.7368557847544;770.8265640230952;774.1213722425048;12885.871141288584;13062.281574513398;13115.409806558715;13194;13256.33718314084;11669.708257293572;11974.401279936012;11989.820152697695;11996.520139194421;12075.275483470998;498.94262159851536;502.1513641945119;590.9349971503132;592.7540070870583;600.8047384600001;575.3642225341001;588.9016983326087;593.2495393327445;596.263614436171;600.1537831657364;610.5085406247969;629.3046183966696;630.1083966187839;630.152444961526;630.1618846933591;523.8192823475895;525.2908573425883;621.5245337316957;628.3967391304353;628.9182406287154;452.3011946542567;458.35372125303496;458.3560098062228;458.47046661106475;459.3867187304952;11276;11328.830067548994;11358.432078396047;11365.03397211238;11411.972922436937;9707.223422126213;9725.868398898127;11458.312501249868;11937.164398492108;12003.579874704343;10041.849372259421;10097.949510252474;10362.119219866321;10565.683029509128;10734.980176883184;3235.7249633781157;3237.8057316560844;3249.675032496755;3254.707076363115;3344.6822551857535;46472.76763616194;46539.534604654196;46582.53417465816;46583.76708116438;46666;6979.685914133876;6989.790306290819;6990.965045174843;7089.680964356617;7200.675969581382;11191.496382662799;11241.943790280997;11324.660260192208;11331.093512519143;11338.14963877696;6467.935320646733;6511.641859697667;6550.508711846631;6574.276829548746;6706.66466676674;4093.3655283431117;4133.958660413417;4147.605977432199;4171.812268447867;4270.42349282849;2461.126300163452;2493.7755601995545;2548.1845809340834;2555.718870924197;2574.3564108972755;411.1591794779683;420.8190478094449;423.0439207391256;433.45168362022065;434.61970775571376;646.1341801985362;656.4321861589787;694.5346617766108;709.9574025558483;717.7344382578375;9036.96759974866;9259.398139120936;9354.812903742024;9459.86124876115;9511.667091651898;596.5645079092287;669.1869378704897;716.9677364518609;718.8813845715503;722.2488611843729;4030.5919745728925;4367.868963931023;4458.643308535309;4487.663425243055;4520.59314661682;54764;55263.723681381336;57473.42526574681;58640.70679646575;59018;47865.282020769715;48231.75884120627;48338.5166148341;49175.01649966981;49232.261516077284;40631.18737625231;41242.1751564973;41350.58649413467;41516.1696766069;41622.583774161874;9055.275577953616;9063.501507417155;9074.863877041718;9172.908270917205;9503.524823758922;28556.85721571379;29270.853645731637;29273.7072629271;29306.267343316587;29341.119766407042;38845.02887427837;39323;39574.2085158301;40016;40268;8324.500529968222;8325.25072743456;8554.101819309131;8650.610722517627;8824.641343494315;12624;13064.804027939399;13068.934655326662;13170.802437963246;13325.423492228067;12279.815802763145;12341.259524428204;12392.75214495723;12525.248485090924;12683.936580317039;11499.54001839917;11506.194566380273;11586.826197607212;11705.180637355303;11953.103517236068;12080.879191208327;12099.935580202704;12188.634340969786;12233.449494772578;12595.559155429515;9799.85300220483;9988.60045598168;10128.601434629185;10208.54061567246;10614.628488002883;10652.733681658021;10696;10828.18788590843;10914;11150.10799136051 21:37:23 INFO - 2015-10-23 21:37:23,812 DEBUG : BROWSER_OUTPUT: __end_tp_report 21:37:23 INFO - 2015-10-23 21:37:23,812 DEBUG : BROWSER_OUTPUT: __start_cc_report 21:37:23 INFO - 2015-10-23 21:37:23,812 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,710 21:37:23 INFO - 2015-10-23 21:37:23,812 DEBUG : BROWSER_OUTPUT: __end_cc_report 21:37:23 INFO - 2015-10-23 21:37:23,812 DEBUG : BROWSER_OUTPUT: __startTimestamp1445661443737__endTimestamp 21:37:23 INFO - 2015-10-23 21:37:23,812 DEBUG : BROWSER_OUTPUT: 21:37:23 INFO - 2015-10-23 21:37:23,812 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 21:37:23 INFO - 2015-10-23 21:37:23,813 DEBUG : BROWSER_OUTPUT: Number of tests: 6 21:37:23 INFO - 2015-10-23 21:37:23,813 DEBUG : BROWSER_OUTPUT: 21:37:23 INFO - 2015-10-23 21:37:23,813 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5119.45 Median:5421.40 stddev:736.34 (13.6%) stddev-sans-first:714.90 21:37:23 INFO - 2015-10-23 21:37:23,813 DEBUG : BROWSER_OUTPUT: Values: 2757.3 2808.1 2835.1 2844.0 2893.1 5091.1 5222.1 5261.6 5362.2 5366.2 5188.9 5243.0 5264.8 5367.9 5402.6 4490.4 4585.5 4660.9 4674.2 4675.0 4467.2 4468.5 4603.3 4628.1 4628.8 4633.5 4639.3 4641.5 4648.3 4648.6 4373.7 4432.4 4585.3 4626.7 4637.4 4483.9 4544.6 4546.9 4620.6 4623.9 5129.7 5230.2 5240.3 5348.7 5377.5 5372.4 5454.5 5485.8 5528.5 5657.0 5557.7 5565.5 5579.9 5657.4 5663.7 5163.3 5175.9 5177.7 5237.1 5262.3 5487.9 5514.4 5667.7 5671.6 5796.7 5423.8 5470.1 5482.2 5543.9 5589.9 5567.3 5571.6 5577.6 5663.3 5680.6 5396.6 5488.9 5490.8 5510.0 5611.9 5487.6 5492.8 5578.0 5590.7 5604.4 3469.9 3488.3 3516.1 3556.7 3556.9 3149.5 3190.4 3248.0 3257.4 3269.5 5219.0 5314.1 5400.7 5415.6 5424.1 5496.8 5596.3 5617.9 5723.3 5751.1 5170.3 5271.6 5275.8 5307.0 5426.5 4384.3 4390.4 4397.3 4473.9 4502.7 5742.7 5880.3 5886.4 5969.7 5987.0 5469.9 5553.0 5566.0 5597.6 5717.9 5736.8 5869.3 5886.1 5976.4 5978.8 5498.7 5525.1 5606.8 5633.2 5635.0 5517.2 5604.3 5612.6 5616.1 5619.1 5493.5 5531.0 5572.5 5599.3 5613.0 5455.2 5455.7 5496.9 5549.7 5578.7 5215.6 5229.3 5302.3 5313.7 5421.4 5391.9 5528.7 5587.1 5591.8 5611.9 5105.6 5280.7 5504.9 5513.9 5515.6 21:37:23 INFO - 2015-10-23 21:37:23,813 DEBUG : BROWSER_OUTPUT: 21:37:23 INFO - 2015-10-23 21:37:23,813 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48934.78 Median:33988.16 stddev:91814.13 (270.1%) stddev-sans-first:91999.59 21:37:23 INFO - 2015-10-23 21:37:23,813 DEBUG : BROWSER_OUTPUT: Values: 471.7 473.5 481.5 516.4 517.7 31446.0 31716.0 31724.8 31857.7 37151.6 38186.4 39175.8 39706.0 39835.4 40015.2 8041.0 8111.1 8220.1 8270.4 8275.7 7634.3 7758.1 9927.5 10184.8 10199.5 9961.5 10035.4 10046.0 10056.2 10066.5 7809.6 8009.6 9931.4 9979.5 10012.5 9600.7 9794.8 9843.0 9851.9 9852.6 34791.7 34927.7 35170.8 35176.5 35194.1 3070.2 3230.2 3244.7 3274.7 3462.9 29267.9 29393.3 29433.9 30344.5 30357.8 2748.4 2763.5 2767.9 2780.6 2815.7 314777.0 315382.0 315442.0 316744.0 317385.0 44572.0 45302.8 51424.5 51594.5 51855.5 55191.4 57003.4 57094.7 57131.1 57275.7 14021.1 14066.4 14104.4 14106.9 14154.9 9142.8 9642.1 9717.9 9720.4 9725.6 16579.7 16674.0 16791.9 16836.4 16845.5 7235.5 7294.0 7430.9 7470.0 7492.9 844.7 921.1 934.6 962.4 991.6 27305.7 27774.6 28040.4 28169.9 28515.1 968.0 982.1 982.2 1023.5 1068.3 13677.2 13941.6 14165.3 14208.6 14555.2 380917.0 385206.0 389169.0 389578.0 390395.0 45988.8 46453.0 46484.0 46865.0 46925.3 1175.2 1183.5 1187.9 1188.4 1197.3 22476.7 25793.0 25893.4 26776.0 29418.4 42734.0 42803.0 43001.6 43102.4 43266.8 303863.0 319042.0 319373.0 322147.0 413930.0 24160.9 26407.3 26556.2 26912.0 26934.2 36987.8 37291.1 37627.0 37739.0 37993.6 31309.7 32141.8 32153.7 32526.5 32707.3 30932.2 31687.7 32215.4 32537.3 34362.1 33522.8 34358.7 34472.3 34719.0 34732.1 29229.7 30416.7 31625.4 32600.0 33617.7 24276.8 24594.8 24985.8 25014.4 25150.5 21:37:23 INFO - 2015-10-23 21:37:23,813 DEBUG : BROWSER_OUTPUT: 21:37:23 INFO - 2015-10-23 21:37:23,815 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:24884.87 Median:222028.08 stddev:64498.74 (29.0%) stddev-sans-first:64653.30 21:37:23 INFO - 2015-10-23 21:37:23,815 DEBUG : BROWSER_OUTPUT: Values: 318.2 318.4 321.0 322.8 326.1 11619.2 11807.2 17093.1 17456.7 17471.7 17367.3 17574.2 17802.3 17865.3 18098.6 5432.6 5528.1 5548.2 5552.1 5554.0 5331.4 5512.6 5533.7 5534.9 5548.8 4416.9 4460.0 5460.8 5468.7 5475.5 5818.7 5910.9 6048.9 6052.2 6056.0 5178.2 5335.5 5335.5 5341.4 5350.3 22502.0 22845.3 22927.5 22945.4 23069.8 2028.0 2108.7 2119.8 2203.6 2371.6 13984.9 14046.5 14384.9 14450.5 14630.6 1679.2 1791.3 1793.4 1802.2 1816.3 77093.2 80247.1 80381.0 80528.0 80596.6 10002.7 10093.8 10267.4 10292.8 10326.6 12840.0 12890.9 12899.5 12919.2 12922.6 7807.8 7886.1 7888.4 7924.6 7926.7 7866.6 7917.8 7922.1 7941.3 7957.3 5682.5 5736.3 5746.6 5754.9 5764.8 1675.2 1721.8 1727.3 1755.7 1756.0 383.3 400.9 431.6 436.2 450.7 11235.8 11759.4 11810.2 11914.9 12351.6 442.1 448.7 453.6 457.5 458.1 6292.4 6421.2 6443.3 6501.3 6535.4 363913.0 368384.7 399055.0 401404.0 402051.0 80617.0 82311.6 82848.6 84697.2 85019.6 471.9 476.4 482.1 482.2 483.2 11229.7 11238.5 11359.9 11397.0 11421.9 42005.2 42367.8 42901.6 43275.4 43516.0 51964.7 53538.5 59081.4 59369.0 59389.7 6117.3 6258.9 6262.5 6267.5 6284.4 11912.5 12442.1 12480.8 12489.9 12507.7 10406.3 10491.1 10596.5 10653.2 10678.4 9560.4 10155.6 10331.4 10452.7 10913.7 10840.7 10891.9 10903.0 11339.4 11374.8 6215.2 6287.1 6377.3 6379.9 6387.6 10625.9 10652.9 10789.9 10881.6 10972.9 21:37:23 INFO - 2015-10-23 21:37:23,816 DEBUG : BROWSER_OUTPUT: 21:37:23 INFO - 2015-10-23 21:37:23,816 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:6019.61 Median:5139.51 stddev:3377.58 (65.7%) stddev-sans-first:3367.58 21:37:23 INFO - 2015-10-23 21:37:23,816 DEBUG : BROWSER_OUTPUT: Values: 1194.3 1264.7 1316.7 1470.4 1580.1 7793.8 8075.2 8424.0 8439.5 8467.0 8500.8 8549.4 8566.1 8580.0 8585.3 2008.7 2033.8 2213.5 2228.7 2241.6 2205.8 2265.8 2271.9 2272.2 2273.2 2222.7 2237.8 2243.1 2250.2 2252.1 2024.0 2035.7 2232.6 2259.2 2259.6 2222.1 2251.7 2274.0 2286.8 2287.6 8720.6 8779.5 8786.3 8787.5 8788.4 5054.3 5101.6 5132.6 5133.3 5134.5 3312.5 3365.1 3392.9 3418.9 3428.4 1010.6 1014.2 1016.0 1028.1 1047.8 12785.2 12798.4 12811.9 12824.6 12825.9 4541.5 4576.2 4595.7 4606.3 4622.2 10449.7 10455.8 10456.2 10482.2 10486.9 5138.7 5139.3 5139.7 5150.9 5158.3 4602.6 4666.0 4689.6 4691.1 4696.5 3627.3 3644.6 3711.7 3744.9 3766.6 1176.9 1180.4 1181.4 1188.1 1188.5 845.4 1131.2 1144.1 1145.0 1146.3 7139.7 7672.5 8098.7 8099.9 8123.2 583.0 599.3 601.5 606.3 606.6 5851.6 5886.9 5888.1 5899.8 5901.0 10321.7 10611.4 10804.4 10836.9 11023.2 10989.2 11074.0 11105.6 11129.8 11140.8 7262.7 7577.8 7726.4 7777.5 7780.4 7514.8 7576.8 7594.6 7609.0 7843.0 9686.6 9744.6 9762.8 9781.6 9783.2 9670.0 9743.9 9897.8 9908.8 9915.7 7917.5 7930.0 7993.2 8000.6 8042.2 8679.7 8711.7 8724.4 8730.5 8735.6 7468.8 8023.0 8090.1 8171.3 8196.6 7892.8 7987.9 8034.0 8041.1 8057.9 7707.4 7960.9 7980.0 8008.3 8035.6 6907.4 6939.4 6956.1 6965.5 6975.6 6956.0 6972.6 7041.5 7049.4 7063.8 21:37:23 INFO - 2015-10-23 21:37:23,818 DEBUG : BROWSER_OUTPUT: 21:37:23 INFO - 2015-10-23 21:37:23,818 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5100.13 Median:5829.90 stddev:1546.01 (26.5%) stddev-sans-first:1508.06 21:37:23 INFO - 2015-10-23 21:37:23,819 DEBUG : BROWSER_OUTPUT: Values: 315.3 316.8 318.2 326.1 328.9 5788.2 5818.2 5841.6 5848.5 5860.4 5659.7 5662.5 5697.2 5721.9 5811.0 4176.2 4289.8 4296.0 4296.7 4300.7 3996.2 4265.6 4269.6 4283.0 4286.3 4002.7 4090.7 4215.7 4224.2 4253.1 4174.2 4219.9 4256.9 4258.0 4260.9 3732.9 3767.7 3768.9 3774.1 3775.9 5379.0 5486.5 5520.4 5598.6 5635.7 5036.0 5070.7 5080.4 5082.4 5087.8 5721.3 5871.6 6016.5 6128.5 6132.6 4267.7 4278.8 4288.3 4289.1 4290.1 6383.0 6386.4 6390.5 6398.8 6412.4 4110.7 4409.7 4419.8 4424.9 4425.2 4365.9 4475.1 4482.5 4488.2 4499.9 5031.8 5038.7 5124.4 5142.7 5149.4 6073.4 6136.2 6137.4 6139.5 6143.6 1615.9 1617.8 1628.0 1628.2 1632.7 1067.5 1082.3 1110.6 1115.6 1119.3 5877.8 5915.3 5920.5 5922.9 5928.2 6313.3 6341.5 6349.7 6352.4 6362.6 5770.5 5909.3 5914.4 5921.0 5922.5 3301.8 3369.7 3429.6 3456.6 3467.6 6427.4 6657.0 6660.8 6691.5 6695.5 6198.1 6392.5 6475.3 6476.5 6492.5 6470.6 6664.8 6666.6 6667.6 6669.5 6231.8 6313.0 6320.9 6333.7 6354.4 6195.3 6206.6 6213.8 6223.3 6224.9 6298.1 6661.3 6661.9 6675.5 6677.5 4192.4 4332.5 4355.6 4363.3 4364.0 5766.5 6000.3 6148.6 6150.4 6326.7 5893.3 6174.1 6175.9 6224.8 6232.7 6139.7 6142.1 6156.5 6170.5 6172.3 5690.3 5990.6 6009.9 6013.8 6022.4 5862.5 6173.7 6178.7 6180.5 6193.1 5926.9 5969.4 6000.8 6001.3 6061.5 21:37:23 INFO - 2015-10-23 21:37:23,819 DEBUG : BROWSER_OUTPUT: 21:37:23 INFO - 2015-10-23 21:37:23,819 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12831.47 Median:434.04 stddev:14951.46 (3444.8%) stddev-sans-first:14965.88 21:37:23 INFO - 2015-10-23 21:37:23,819 DEBUG : BROWSER_OUTPUT: Values: 753.0 760.5 762.7 770.8 774.1 12885.9 13062.3 13115.4 13194.0 13256.3 11669.7 11974.4 11989.8 11996.5 12075.3 498.9 502.2 590.9 592.8 600.8 575.4 588.9 593.2 596.3 600.2 610.5 629.3 630.1 630.2 630.2 523.8 525.3 621.5 628.4 628.9 452.3 458.4 458.4 458.5 459.4 11276.0 11328.8 11358.4 11365.0 11412.0 9707.2 9725.9 11458.3 11937.2 12003.6 10041.8 10097.9 10362.1 10565.7 10735.0 3235.7 3237.8 3249.7 3254.7 3344.7 46472.8 46539.5 46582.5 46583.8 46666.0 6979.7 6989.8 6991.0 7089.7 7200.7 11191.5 11241.9 11324.7 11331.1 11338.1 6467.9 6511.6 6550.5 6574.3 6706.7 4093.4 4134.0 4147.6 4171.8 4270.4 2461.1 2493.8 2548.2 2555.7 2574.4 411.2 420.8 423.0 433.5 434.6 646.1 656.4 694.5 710.0 717.7 9037.0 9259.4 9354.8 9459.9 9511.7 596.6 669.2 717.0 718.9 722.2 4030.6 4367.9 4458.6 4487.7 4520.6 54764.0 55263.7 57473.4 58640.7 59018.0 47865.3 48231.8 48338.5 49175.0 49232.3 40631.2 41242.2 41350.6 41516.2 41622.6 9055.3 9063.5 9074.9 9172.9 9503.5 28556.9 29270.9 29273.7 29306.3 29341.1 38845.0 39323.0 39574.2 40016.0 40268.0 8324.5 8325.3 8554.1 8650.6 8824.6 12624.0 13064.8 13068.9 13170.8 13325.4 12279.8 12341.3 12392.8 12525.2 12683.9 11499.5 11506.2 11586.8 11705.2 11953.1 12080.9 12099.9 12188.6 12233.4 12595.6 9799.9 9988.6 10128.6 10208.5 10614.6 10652.7 10696.0 10828.2 10914.0 11150.1 21:37:23 INFO - 2015-10-23 21:37:23,821 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 21:37:23 INFO - 2015-10-23 21:37:23,821 DEBUG : BROWSER_OUTPUT: 21:37:24 INFO - 2015-10-23 21:37:24,302 INFO : Browser exited with error code: 0 21:37:25 INFO - 2015-10-23 21:37:25,036 INFO : Completed test dromaeo_css (00:18:37) 21:37:25 INFO - 2015-10-23 21:37:25,036 INFO : Starting test kraken 21:37:25 INFO - 2015-10-23 21:37:25,036 DEBUG : operating with platform_type : w7_ 21:37:25 INFO - 2015-10-23 21:37:25,036 INFO : Initialising browser for kraken test... 21:37:25 INFO - 2015-10-23 21:37:25,062 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpycbwez\profile http://localhost:49891/getInfo.html 21:37:29 INFO - 2015-10-23 21:37:29,686 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 21:37:29 INFO - 2015-10-23 21:37:29,686 DEBUG : BROWSER_OUTPUT: colorDepth:24 21:37:29 INFO - 2015-10-23 21:37:29,686 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 21:37:29 INFO - 2015-10-23 21:37:29,686 DEBUG : BROWSER_OUTPUT: __metrics 21:37:29 INFO - 2015-10-23 21:37:29,769 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:37:30 INFO - 2015-10-23 21:37:30,496 DEBUG : BROWSER_OUTPUT: console.error: 21:37:30 INFO - 2015-10-23 21:37:30,496 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] 21:37:30 INFO - 2015-10-23 21:37:30,496 DEBUG : BROWSER_OUTPUT: Stack: 21:37:30 INFO - 2015-10-23 21:37:30,496 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 21:37:30 INFO - 2015-10-23 21:37:30,496 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 21:37:30 INFO - 2015-10-23 21:37:30,496 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 21:37:30 INFO - 2015-10-23 21:37:30,496 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 21:37:30 INFO - 2015-10-23 21:37:30,496 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 21:37:30 INFO - 2015-10-23 21:37:30,496 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 21:37:30 INFO - 2015-10-23 21:37:30,496 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 21:37:30 INFO - 2015-10-23 21:37:30,496 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 21:37:30 INFO - 2015-10-23 21:37:30,496 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 21:37:30 INFO - 2015-10-23 21:37:30,496 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 21:37:30 INFO - 2015-10-23 21:37:30,496 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 21:37:30 INFO - 2015-10-23 21:37:30,497 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 21:37:30 INFO - 2015-10-23 21:37:30,497 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6630.64 21:39:05 INFO - RETURN: kraken: 1580.50 21:39:05 INFO - RETURN: v8_7: 20561.11 21:39:05 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,25]]", "result": "20561.11"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,25]]", "result": "1580.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,25]]", "result": "6630.64"}}} 21:39:05 INFO - Return code: 0 21:39:05 INFO - # TBPL SUCCESS # 21:39:05 INFO - Running post-action listener: _resource_record_post_action 21:39:05 INFO - Running post-run listener: _resource_record_post_run 21:39:06 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 68630016; Write bytes: 402978816; Read time: 1010810; Write time: 4222740 21:39:06 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 16384; Read time: 0; Write time: 90 21:39:07 INFO - run-tests - Wall time: 1221s; CPU: 13.0%; Read bytes: 68630016; Write bytes: 378374144; Read time: 1010810; Write time: 4130980 21:39:07 INFO - Running post-run listener: _upload_blobber_files 21:39:07 INFO - Blob upload gear active. 21:39:07 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 21:39:07 INFO - Copying logs to upload dir... 21:39:07 INFO - mkdir: C:\slave\test-pgo\build\upload\logs program finished with exit code 0 elapsedTime=1411.376000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 41 secs) (at 2015-10-23 21:39:16.934320) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-23 21:39:16.937567) ========= '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-200 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-200 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-200 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/gxZuX1BORTK8bJFL-juINw/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/gxZuX1BORTK8bJFL-juINw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-23 21:39:17.304493) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 21:39:17.304900) ========= '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-200 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-200 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-200 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 21:39:17.478118) ========= ========= Started reboot slave lost (results: 0, elapsed: 8 secs) (at 2015-10-23 21:39:17.478499) ========= ========= Finished reboot slave lost (results: 0, elapsed: 8 secs) (at 2015-10-23 21:39:25.898109) =========