builder: ash_win7-ix_test-dromaeojs-pgo slave: t-w732-ix-149 starttime: 1446003879.43 results: success (0) buildid: 20151027040234 builduid: bc4deb6becf443439a2dfef5c179095f revision: a759c44ddb56eac3fd2e6cd23310e8f89be4ec18 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-27 20:44:39.429729) ========= master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-27 20:44:39.431596) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-27 20:44:39.431955) ========= '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-149 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-149 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-149 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.678000 basedir: 'C:\\slave\\test-pgo' ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-27 20:44:40.302571) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-27 20:44:40.303300) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-27 20:44:40.461801) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 12 secs) (at 2015-10-27 20:44:40.462265) ========= '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-149 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-149 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-149 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: 12 secs) (at 2015-10-27 20:44:52.589960) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-27 20:44:52.590272) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-27 20:44:52.590653) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-27 20:44:52.590980) ========= '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-149 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-149 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-149 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 --20:44:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 2.45 GB/s 20:44:53 (2.45 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.806000 ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-27 20:44:53.932254) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 12 secs) (at 2015-10-27 20:44:53.932649) ========= '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-149 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-149 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-149 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=12.701000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 12 secs) (at 2015-10-27 20:45:06.651316) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-10-27 20:45:06.652122) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo projects/ash --rev a759c44ddb56eac3fd2e6cd23310e8f89be4ec18 --destination scripts --debug' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/ash --rev a759c44ddb56eac3fd2e6cd23310e8f89be4ec18 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-149 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-149 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-149 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-27 20:45:07,055 truncating revision to first 12 chars 2015-10-27 20:45:07,055 Setting DEBUG logging. 2015-10-27 20:45:07,055 attempt 1/10 2015-10-27 20:45:07,055 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/ash/a759c44ddb56?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-27 20:45:08,522 unpacking tar archive at: ash-a759c44ddb56/testing/mozharness/ program finished with exit code 0 elapsedTime=2.198000 ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-10-27 20:45:08.888724) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-27 20:45:08.889369) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-27 20:45:08.907624) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-27 20:45:08.908146) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-27 20:45:08.908741) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 37 secs) (at 2015-10-27 20:45:08.909250) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Ash' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Ash' 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', 'Ash', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Ash'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-149 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-149 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-149 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 20:45:09 INFO - MultiFileLogger online at 20151027 20:45:09 in C:\slave\test-pgo 20:45:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Ash --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Ash 20:45:09 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 20:45:09 INFO - {'append_to_log': False, 20:45:09 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 20:45:09 INFO - 'blob_upload_branch': 'Ash', 20:45:09 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 20:45:09 INFO - 'branch': 'Ash', 20:45:09 INFO - 'buildbot_json_path': 'buildprops.json', 20:45:09 INFO - 'config_files': ('talos/windows_config.py',), 20:45:09 INFO - 'default_actions': ('clobber', 20:45:09 INFO - 'read-buildbot-config', 20:45:09 INFO - 'download-and-extract', 20:45:09 INFO - 'populate-webroot', 20:45:09 INFO - 'create-virtualenv', 20:45:09 INFO - 'install', 20:45:09 INFO - 'run-tests'), 20:45:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:45:09 INFO - 'download_minidump_stackwalk': True, 20:45:09 INFO - 'download_symbols': 'ondemand', 20:45:09 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 20:45:09 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 20:45:09 INFO - 'hg': 'c:/mozilla-build/hg/hg', 20:45:09 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 20:45:09 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 20:45:09 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 20:45:09 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 20:45:09 INFO - 'C:/mozilla-build/tooltool.py'), 20:45:09 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 20:45:09 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 20:45:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:45:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:45:09 INFO - 'installer_path': 'installer.exe', 20:45:09 INFO - 'log_level': 'info', 20:45:09 INFO - 'log_name': 'talos', 20:45:09 INFO - 'log_to_console': True, 20:45:09 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 20:45:09 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 20:45:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 20:45:09 INFO - 'opt_config_files': (), 20:45:09 INFO - 'pip_index': False, 20:45:09 INFO - 'sps_profile': False, 20:45:09 INFO - 'sps_profile_interval': 0, 20:45:09 INFO - 'suite': 'dromaeojs', 20:45:09 INFO - 'system_bits': '32', 20:45:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:45:09 INFO - 'title': 'T-W732-IX-149', 20:45:09 INFO - 'use_talos_json': True, 20:45:09 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 20:45:09 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 20:45:09 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 20:45:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:45:09 INFO - 'work_dir': 'build'} 20:45:09 INFO - ##### 20:45:09 INFO - ##### Running clobber step. 20:45:09 INFO - ##### 20:45:09 INFO - Running pre-action listener: _resource_record_pre_action 20:45:09 INFO - Running main action method: clobber 20:45:09 INFO - rmtree: C:\slave\test-pgo\build 20:45:09 INFO - Using _rmtree_windows ... 20:45:09 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 20:47:46 INFO - Running post-action listener: _resource_record_post_action 20:47:46 INFO - ##### 20:47:46 INFO - ##### Running read-buildbot-config step. 20:47:46 INFO - ##### 20:47:46 INFO - Running pre-action listener: _resource_record_pre_action 20:47:46 INFO - Running main action method: read_buildbot_config 20:47:46 INFO - Using buildbot properties: 20:47:46 INFO - { 20:47:46 INFO - "properties": { 20:47:46 INFO - "buildnumber": 0, 20:47:46 INFO - "product": "firefox", 20:47:46 INFO - "script_repo_revision": "production", 20:47:46 INFO - "builddir": "ash_win7-ix_test-dromaeojs", 20:47:46 INFO - "repository": "", 20:47:46 INFO - "buildername": "Windows 7 32-bit ash pgo talos dromaeojs", 20:47:46 INFO - "buildid": "20151027040234", 20:47:46 INFO - "slavename": "t-w732-ix-149", 20:47:46 INFO - "stage_platform": "win32-pgo", 20:47:46 INFO - "basedir": "C:\\slave\\test-pgo", 20:47:46 INFO - "nightly_build": "True", 20:47:46 INFO - "project": "", 20:47:46 INFO - "platform": "win7-ix", 20:47:46 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/", 20:47:46 INFO - "slavebuilddir": "test", 20:47:46 INFO - "scheduler": "tests-ash-win32-pgo-talos", 20:47:46 INFO - "branch": "ash", 20:47:46 INFO - "repo_path": "projects/ash", 20:47:46 INFO - "pgo_build": "True", 20:47:46 INFO - "builduid": "bc4deb6becf443439a2dfef5c179095f", 20:47:46 INFO - "revision": "a759c44ddb56eac3fd2e6cd23310e8f89be4ec18" 20:47:46 INFO - }, 20:47:46 INFO - "sourcestamp": { 20:47:46 INFO - "repository": "", 20:47:46 INFO - "hasPatch": false, 20:47:46 INFO - "project": "", 20:47:46 INFO - "branch": "ash-win32-pgo-talos", 20:47:46 INFO - "changes": [ 20:47:46 INFO - { 20:47:46 INFO - "category": null, 20:47:46 INFO - "files": [ 20:47:46 INFO - { 20:47:46 INFO - "url": null, 20:47:46 INFO - "name": "https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 20:47:46 INFO - } 20:47:46 INFO - ], 20:47:46 INFO - "repository": "", 20:47:46 INFO - "rev": "a759c44ddb56eac3fd2e6cd23310e8f89be4ec18", 20:47:46 INFO - "who": "sendchange", 20:47:46 INFO - "when": 1445961225, 20:47:46 INFO - "number": 6580439, 20:47:46 INFO - "comments": "", 20:47:46 INFO - "project": "", 20:47:46 INFO - "at": "Tue 27 Oct 2015 08:53:45", 20:47:46 INFO - "branch": "ash-win32-pgo-talos", 20:47:46 INFO - "revlink": "", 20:47:46 INFO - "properties": [ 20:47:46 INFO - [ 20:47:46 INFO - "buildid", 20:47:46 INFO - "20151027040234", 20:47:46 INFO - "Change" 20:47:46 INFO - ], 20:47:46 INFO - [ 20:47:46 INFO - "builduid", 20:47:46 INFO - "bc4deb6becf443439a2dfef5c179095f", 20:47:46 INFO - "Change" 20:47:46 INFO - ], 20:47:46 INFO - [ 20:47:46 INFO - "nightly_build", 20:47:46 INFO - "True", 20:47:46 INFO - "Change" 20:47:46 INFO - ], 20:47:46 INFO - [ 20:47:46 INFO - "pgo_build", 20:47:46 INFO - "True", 20:47:46 INFO - "Change" 20:47:46 INFO - ] 20:47:46 INFO - ], 20:47:46 INFO - "revision": "a759c44ddb56eac3fd2e6cd23310e8f89be4ec18" 20:47:46 INFO - } 20:47:46 INFO - ], 20:47:46 INFO - "revision": "a759c44ddb56eac3fd2e6cd23310e8f89be4ec18" 20:47:46 INFO - } 20:47:46 INFO - } 20:47:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 20:47:46 INFO - Running post-action listener: _resource_record_post_action 20:47:46 INFO - ##### 20:47:46 INFO - ##### Running download-and-extract step. 20:47:46 INFO - ##### 20:47:46 INFO - Running pre-action listener: _resource_record_pre_action 20:47:46 INFO - Running main action method: download_and_extract 20:47:46 INFO - mkdir: C:\slave\test-pgo\build\tests 20:47:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:47:46 INFO - https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 20:47:46 INFO - trying https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/test_packages.json 20:47:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 20:47:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 20:47:47 INFO - Downloaded 1221 bytes. 20:47:47 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 20:47:47 INFO - Using the following test package requirements: 20:47:47 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 20:47:47 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 20:47:47 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 20:47:47 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 20:47:47 INFO - u'jsshell-win32.zip'], 20:47:47 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 20:47:47 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 20:47:47 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 20:47:47 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 20:47:47 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 20:47:47 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 20:47:47 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 20:47:47 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 20:47:47 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 20:47:47 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 20:47:47 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 20:47:47 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 20:47:47 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 20:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:47:47 INFO - https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 20:47:47 INFO - trying https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 20:47:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/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 20:47:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/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 20:47:49 INFO - Downloaded 18005716 bytes. 20:47:49 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 20:47:49 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 20:47:49 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 20:48:19 INFO - Return code: 0 20:48:19 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 20:48: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')]} 20:48:19 INFO - https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 20:48:19 INFO - trying https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 20:48:19 INFO - Downloading https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/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 20:48:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/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 20:48:21 INFO - Downloaded 18005716 bytes. 20:48:21 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 20:48:21 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 20:48:21 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 20:48:46 INFO - Return code: 0 20:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:48:46 INFO - https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 20:48:46 INFO - trying https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 20:48:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/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 20:48:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/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 20:48:47 INFO - Downloaded 11131307 bytes. 20:48:47 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 20:48:47 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip 20:48:47 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 20:48:50 INFO - Return code: 0 20:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:48:50 INFO - https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 20:48:50 INFO - trying https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 20:48:50 INFO - Downloading https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip 20:48:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/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 20:48:54 INFO - Downloaded 51923319 bytes. 20:48:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 20:48:54 INFO - mkdir: C:\slave\test-pgo\properties 20:48:54 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 20:48:54 INFO - Writing to file C:\slave\test-pgo\properties\build_url 20:48:54 INFO - Contents: 20:48:54 INFO - build_url:https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 20:48:54 INFO - Running post-action listener: _resource_record_post_action 20:48:54 INFO - Running post-action listener: set_extra_try_arguments 20:48:54 INFO - ##### 20:48:54 INFO - ##### Running populate-webroot step. 20:48:54 INFO - ##### 20:48:54 INFO - Running pre-action listener: _resource_record_pre_action 20:48:54 INFO - Running main action method: populate_webroot 20:48:54 INFO - Guessing talos json url... 20:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:48:54 INFO - https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 20:48:54 INFO - trying https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 20:48:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 20:48:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/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 20:48:55 INFO - Downloaded 96 bytes. 20:48:55 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 20:48:55 INFO - Contents: 20:48:55 INFO - 20151027040234 20:48:55 INFO - https://hg.mozilla.org/projects/ash/rev/a759c44ddb56eac3fd2e6cd23310e8f89be4ec18 20:48: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')]} 20:48:55 INFO - trying https://hg.mozilla.org/projects/ash/raw-file/a759c44ddb/testing/talos/talos.json 20:48:55 INFO - Downloading https://hg.mozilla.org/projects/ash/raw-file/a759c44ddb/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json 20:48:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/projects/ash/raw-file/a759c44ddb/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1 20:48:55 INFO - Downloaded 4264 bytes. 20:48:55 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 20:48:55 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 20:48:55 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 20:48:55 INFO - u'../fennec_ids.txt'], 20:48:55 INFO - u'tests': [u'tcheck2']}, 20:48:55 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 20:48:55 INFO - u'--tppagecycles', 20:48:55 INFO - u'7'], 20:48:55 INFO - u'tests': [u'tsvgm']}}, 20:48:55 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 20:48:55 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 20:48:55 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 20:48:55 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 20:48:55 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 20:48:55 INFO - u'tests': [u'dromaeo_css', 20:48:55 INFO - u'kraken', 20:48:55 INFO - u'v8_7']}, 20:48:55 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:48:55 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 20:48:55 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:48:55 INFO - u'talos_options': [u'--e10s'], 20:48:55 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 20:48:55 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:48:55 INFO - u'tests': [u'damp', u'tps']}, 20:48:55 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:48:55 INFO - u'talos_options': [u'--e10s'], 20:48:55 INFO - u'tests': [u'damp', u'tps']}, 20:48:55 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 20:48:55 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 20:48:55 INFO - u'other': {u'tests': [u'a11yr', 20:48:55 INFO - u'ts_paint', 20:48:55 INFO - u'tpaint', 20:48:55 INFO - u'sessionrestore', 20:48:55 INFO - u'sessionrestore_no_auto_restore']}, 20:48:55 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 20:48:55 INFO - u'tests': [u'a11yr', 20:48:55 INFO - u'ts_paint', 20:48:55 INFO - u'tpaint', 20:48:55 INFO - u'sessionrestore', 20:48:55 INFO - u'sessionrestore_no_auto_restore']}, 20:48:55 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 20:48:55 INFO - u'tests': [u'a11yr', 20:48:55 INFO - u'ts_paint', 20:48:55 INFO - u'tpaint', 20:48:55 INFO - u'sessionrestore', 20:48:55 INFO - u'sessionrestore_no_auto_restore']}, 20:48:55 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 20:48:55 INFO - u'tests': [u'a11yr', 20:48:55 INFO - u'ts_paint', 20:48:55 INFO - u'tpaint', 20:48:55 INFO - u'sessionrestore', 20:48:55 INFO - u'sessionrestore_no_auto_restore']}, 20:48:55 INFO - u'other_l64': {u'tests': [u'a11yr', 20:48:55 INFO - u'ts_paint', 20:48:55 INFO - u'tpaint', 20:48:55 INFO - u'sessionrestore', 20:48:55 INFO - u'sessionrestore_no_auto_restore']}, 20:48:55 INFO - u'other_nol64': {u'tests': [u'a11yr', 20:48:55 INFO - u'ts_paint', 20:48:55 INFO - u'tpaint', 20:48:55 INFO - u'sessionrestore', 20:48:55 INFO - u'sessionrestore_no_auto_restore']}, 20:48:55 INFO - u'svgr': {u'tests': [u'tsvgx', 20:48:55 INFO - u'tsvgr_opacity', 20:48:55 INFO - u'tart', 20:48:55 INFO - u'tscrollx', 20:48:55 INFO - u'cart']}, 20:48:55 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 20:48:55 INFO - u'tests': [u'tsvgx', 20:48:55 INFO - u'tsvgr_opacity', 20:48:55 INFO - u'tart', 20:48:55 INFO - u'tscrollx', 20:48:55 INFO - u'cart']}, 20:48:55 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:48:55 INFO - u'tests': [u'tp5o']}, 20:48:55 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:48:55 INFO - u'talos_options': [u'--e10s'], 20:48:55 INFO - u'tests': [u'tp5o']}, 20:48:55 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:48:55 INFO - u'talos_options': [u'--xperf_path', 20:48:55 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:48:55 INFO - u'tests': [u'tp5n']}, 20:48:55 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:48:55 INFO - u'talos_options': [u'--e10s', 20:48:55 INFO - u'--xperf_path', 20:48:55 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 20:48:55 INFO - u'C:/slave/talos-data/talos/xperf.config'], 20:48:55 INFO - u'tests': [u'tp5n']}}, 20:48:55 INFO - u'talos.zip': {u'path': u'', 20:48:55 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:48:55 INFO - Running post-action listener: _resource_record_post_action 20:48:55 INFO - ##### 20:48:55 INFO - ##### Running create-virtualenv step. 20:48:55 INFO - ##### 20:48:55 INFO - Running pre-action listener: _resource_record_pre_action 20:48:55 INFO - Running main action method: create_virtualenv 20:48:55 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 20:48:55 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 20:48:55 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 20:48:55 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'! 20:48:55 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 20:48:55 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 20:49:00 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 20:49:06 INFO - Installing distribute......................................................................................................................................................................................done. 20:49:07 INFO - Return code: 0 20:49:07 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 20:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49:07 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--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 0x01989B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194CEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137C288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, '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 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A09660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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 20:49:07 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 20:49:07 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 20:49:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:49:07 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:49:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:49:07 INFO - 'COMPUTERNAME': 'T-W732-IX-149', 20:49:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:49:07 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:49:07 INFO - 'DCLOCATION': 'SCL3', 20:49:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:49:07 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:49:07 INFO - 'HOMEDRIVE': 'C:', 20:49:07 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:49:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:49:07 INFO - 'KTS_VERSION': '1.19c', 20:49:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:49:07 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149', 20:49:07 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:49:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:07 INFO - 'MOZ_NO_REMOTE': '1', 20:49:07 INFO - 'NO_EM_RESTART': '1', 20:49:07 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:49:07 INFO - 'OS': 'Windows_NT', 20:49:07 INFO - 'OURDRIVE': 'C:', 20:49:07 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 20:49:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:49:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:49:07 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:49:07 INFO - 'PROCESSOR_LEVEL': '6', 20:49:07 INFO - 'PROCESSOR_REVISION': '1e05', 20:49:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:49:07 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:49:07 INFO - 'PROMPT': '$P$G', 20:49:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:49:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:49:07 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:49:07 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:49:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:49:07 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:49:07 INFO - 'SYSTEMDRIVE': 'C:', 20:49:07 INFO - 'SYSTEMROOT': 'C:\\windows', 20:49:07 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:49:07 INFO - 'TEST1': 'testie', 20:49:07 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:49:07 INFO - 'USERDOMAIN': 'T-W732-IX-149', 20:49:07 INFO - 'USERNAME': 'cltbld', 20:49:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:49:07 INFO - 'WINDIR': 'C:\\windows', 20:49:07 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:49:07 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:49:09 INFO - Ignoring indexes: http://pypi.python.org/simple/ 20:49:09 INFO - Downloading/unpacking pip>=1.5 20:49:09 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache 20:49:13 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 20:49:13 INFO - Running setup.py egg_info for package pip 20:49:13 INFO - warning: no files found matching 'pip\cacert.pem' 20:49:13 INFO - warning: no files found matching '*.html' under directory 'docs' 20:49:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 20:49:13 INFO - no previously-included directories found matching 'docs\_build\_sources' 20:49:13 INFO - Installing collected packages: pip 20:49:13 INFO - Found existing installation: pip 0.8.2 20:49:13 INFO - Uninstalling pip: 20:49:13 INFO - Successfully uninstalled pip 20:49:13 INFO - Running setup.py install for pip 20:49:13 INFO - warning: no files found matching 'pip\cacert.pem' 20:49:13 INFO - warning: no files found matching '*.html' under directory 'docs' 20:49:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 20:49:13 INFO - no previously-included directories found matching 'docs\_build\_sources' 20:49:13 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:13 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:13 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:13 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:13 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:13 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:13 INFO - Successfully installed pip 20:49:13 INFO - Cleaning up... 20:49:13 INFO - Return code: 0 20:49:13 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 20:49:13 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')]} 20:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49:13 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')]} 20:49:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49:13 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 0x01989B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194CEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137C288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, '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 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A09660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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 20:49:13 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 20:49:13 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 20:49:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:49:13 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:49:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:49:13 INFO - 'COMPUTERNAME': 'T-W732-IX-149', 20:49:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:49:13 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:49:13 INFO - 'DCLOCATION': 'SCL3', 20:49:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:49:13 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:49:13 INFO - 'HOMEDRIVE': 'C:', 20:49:13 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:49:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:49:13 INFO - 'KTS_VERSION': '1.19c', 20:49:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:49:13 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149', 20:49:13 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:49:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:49:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:13 INFO - 'MOZ_NO_REMOTE': '1', 20:49:13 INFO - 'NO_EM_RESTART': '1', 20:49:13 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:49:13 INFO - 'OS': 'Windows_NT', 20:49:13 INFO - 'OURDRIVE': 'C:', 20:49:13 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;', 20:49:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:49:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:49:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:49:13 INFO - 'PROCESSOR_LEVEL': '6', 20:49:13 INFO - 'PROCESSOR_REVISION': '1e05', 20:49:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:49:13 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:49:13 INFO - 'PROMPT': '$P$G', 20:49:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:49:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:49:13 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:49:13 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:49:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:49:13 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:49:13 INFO - 'SYSTEMDRIVE': 'C:', 20:49:13 INFO - 'SYSTEMROOT': 'C:\\windows', 20:49:13 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:49:13 INFO - 'TEST1': 'testie', 20:49:13 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:49:13 INFO - 'USERDOMAIN': 'T-W732-IX-149', 20:49:13 INFO - 'USERNAME': 'cltbld', 20:49:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:49:13 INFO - 'WINDIR': 'C:\\windows', 20:49:13 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:49:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:49:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:49:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:49:15 INFO - Downloading/unpacking psutil>=0.7.1 20:49:15 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 20:49:15 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 20:49:16 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 20:49:16 INFO - Installing collected packages: psutil 20:49:16 INFO - Successfully installed psutil 20:49:16 INFO - Cleaning up... 20:49:16 INFO - Return code: 0 20:49:16 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 20:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49:16 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01989B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194CEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137C288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, '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 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A09660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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 20:49: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 20:49: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 20:49:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:49:17 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:49:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:49:17 INFO - 'COMPUTERNAME': 'T-W732-IX-149', 20:49:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:49:17 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:49:17 INFO - 'DCLOCATION': 'SCL3', 20:49:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:49:17 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:49:17 INFO - 'HOMEDRIVE': 'C:', 20:49:17 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:49:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:49:17 INFO - 'KTS_VERSION': '1.19c', 20:49:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:49:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149', 20:49:17 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:49:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:49:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:17 INFO - 'MOZ_NO_REMOTE': '1', 20:49:17 INFO - 'NO_EM_RESTART': '1', 20:49:17 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:49:17 INFO - 'OS': 'Windows_NT', 20:49:17 INFO - 'OURDRIVE': 'C:', 20:49: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;', 20:49:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:49:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:49:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:49:17 INFO - 'PROCESSOR_LEVEL': '6', 20:49:17 INFO - 'PROCESSOR_REVISION': '1e05', 20:49:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:49:17 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:49:17 INFO - 'PROMPT': '$P$G', 20:49:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:49:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:49:17 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:49:17 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:49:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:49:17 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:49:17 INFO - 'SYSTEMDRIVE': 'C:', 20:49:17 INFO - 'SYSTEMROOT': 'C:\\windows', 20:49:17 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:49:17 INFO - 'TEST1': 'testie', 20:49:17 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:49:17 INFO - 'USERDOMAIN': 'T-W732-IX-149', 20:49:17 INFO - 'USERNAME': 'cltbld', 20:49:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:49:17 INFO - 'WINDIR': 'C:\\windows', 20:49:17 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:49:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:49:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:49:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:49:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 20:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:49:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:49:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 20:49:18 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 20:49:18 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 20:49:18 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) 20:49:18 INFO - Installing collected packages: mozsystemmonitor 20:49:18 INFO - Running setup.py install for mozsystemmonitor 20:49:18 INFO - Successfully installed mozsystemmonitor 20:49:18 INFO - Cleaning up... 20:49:18 INFO - Return code: 0 20:49:18 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 20:49:18 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')]} 20:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49:18 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')]} 20:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49:18 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 0x01989B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194CEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137C288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, '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 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A09660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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 20:49:18 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 20:49:18 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 20:49:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:49:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:49:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:49:18 INFO - 'COMPUTERNAME': 'T-W732-IX-149', 20:49:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:49:18 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:49:18 INFO - 'DCLOCATION': 'SCL3', 20:49:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:49:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:49:18 INFO - 'HOMEDRIVE': 'C:', 20:49:18 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:49:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:49:18 INFO - 'KTS_VERSION': '1.19c', 20:49:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:49:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149', 20:49:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:49:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:18 INFO - 'MOZ_NO_REMOTE': '1', 20:49:18 INFO - 'NO_EM_RESTART': '1', 20:49:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:49:18 INFO - 'OS': 'Windows_NT', 20:49:18 INFO - 'OURDRIVE': 'C:', 20:49:18 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;', 20:49:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:49:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:49:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:49:18 INFO - 'PROCESSOR_LEVEL': '6', 20:49:18 INFO - 'PROCESSOR_REVISION': '1e05', 20:49:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:49:18 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:49:18 INFO - 'PROMPT': '$P$G', 20:49:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:49:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:49:18 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:49:18 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:49:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:49:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:49:18 INFO - 'SYSTEMDRIVE': 'C:', 20:49:18 INFO - 'SYSTEMROOT': 'C:\\windows', 20:49:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:49:18 INFO - 'TEST1': 'testie', 20:49:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:49:18 INFO - 'USERDOMAIN': 'T-W732-IX-149', 20:49:18 INFO - 'USERNAME': 'cltbld', 20:49:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:49:18 INFO - 'WINDIR': 'C:\\windows', 20:49:18 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:49:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:49:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:49:19 INFO - Downloading/unpacking blobuploader==1.2.4 20:49: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 20:49: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 20:49:20 INFO - Downloading blobuploader-1.2.4.tar.gz 20:49: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 20:49:20 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 20:49:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:49: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 20:49: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 20:49:20 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 20:49:20 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 20:49:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:49: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 20:49: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 20:49:21 INFO - Downloading docopt-0.6.1.tar.gz 20:49: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 20:49:21 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 20:49:21 INFO - Installing collected packages: blobuploader, requests, docopt 20:49:21 INFO - Running setup.py install for blobuploader 20:49:21 INFO - Running setup.py install for requests 20:49:22 INFO - Running setup.py install for docopt 20:49:22 INFO - Successfully installed blobuploader requests docopt 20:49:22 INFO - Cleaning up... 20:49:23 INFO - Return code: 0 20:49:23 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 20:49:23 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')]} 20:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49:23 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')]} 20:49:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49:23 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 0x01989B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194CEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137C288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, '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 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A09660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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 20:49:23 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 20:49:23 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 20:49:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:49:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:49:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:49:23 INFO - 'COMPUTERNAME': 'T-W732-IX-149', 20:49:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:49:23 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:49:23 INFO - 'DCLOCATION': 'SCL3', 20:49:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:49:23 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:49:23 INFO - 'HOMEDRIVE': 'C:', 20:49:23 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:49:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:49:23 INFO - 'KTS_VERSION': '1.19c', 20:49:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:49:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149', 20:49:23 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:49:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:49:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:23 INFO - 'MOZ_NO_REMOTE': '1', 20:49:23 INFO - 'NO_EM_RESTART': '1', 20:49:23 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:49:23 INFO - 'OS': 'Windows_NT', 20:49:23 INFO - 'OURDRIVE': 'C:', 20:49:23 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;', 20:49:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:49:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:49:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:49:23 INFO - 'PROCESSOR_LEVEL': '6', 20:49:23 INFO - 'PROCESSOR_REVISION': '1e05', 20:49:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:49:23 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:49:23 INFO - 'PROMPT': '$P$G', 20:49:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:49:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:49:23 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:49:23 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:49:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:49:23 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:49:23 INFO - 'SYSTEMDRIVE': 'C:', 20:49:23 INFO - 'SYSTEMROOT': 'C:\\windows', 20:49:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:49:23 INFO - 'TEST1': 'testie', 20:49:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:49:23 INFO - 'USERDOMAIN': 'T-W732-IX-149', 20:49:23 INFO - 'USERNAME': 'cltbld', 20:49:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:49:23 INFO - 'WINDIR': 'C:\\windows', 20:49:23 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:49:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:49:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:49:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:49:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 20:49:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tfwpbt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 20:49:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 20:49:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cfa4z2-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 20:49:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 20:49:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nuasxm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 20:49:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 20:49:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g6gwfa-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 20:49:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 20:49:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-c_ghmz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 20:49:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 20:49:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0p4tbj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 20:49:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 20:49:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a9wofb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 20:49:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 20:49:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mix4su-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 20:49:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 20:49:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-m26gyu-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 20:49:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 20:49:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iefhx9-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 20:49:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 20:49:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wvhlpg-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 20:49:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 20:49:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mrlbzw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 20:49:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 20:49:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sjouws-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 20:49:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 20:49:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ujsbtd-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 20:49:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 20:49:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dvk0se-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 20:49:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 20:49:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wshdja-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 20:49:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 20:49:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jxtfqp-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 20:49:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:49:26 INFO - Running setup.py install for manifestparser 20:49:27 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:27 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:27 INFO - Running setup.py install for mozcrash 20:49:27 INFO - Running setup.py install for mozdebug 20:49:28 INFO - Running setup.py install for mozdevice 20:49:28 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:28 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:28 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:28 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:29 INFO - Running setup.py install for mozfile 20:49:29 INFO - Running setup.py install for mozhttpd 20:49:29 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:29 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:30 INFO - Running setup.py install for mozinfo 20:49:30 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:30 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:30 INFO - Running setup.py install for mozInstall 20:49:30 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:30 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:30 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:30 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:30 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:30 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:30 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:30 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:30 INFO - Running setup.py install for mozleak 20:49:31 INFO - Running setup.py install for mozlog 20:49:31 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:31 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:31 INFO - Running setup.py install for moznetwork 20:49:32 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:32 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:32 INFO - Running setup.py install for mozprocess 20:49:32 INFO - Running setup.py install for mozprofile 20:49:32 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:32 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:32 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:32 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:32 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:32 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:33 INFO - Running setup.py install for mozrunner 20:49:33 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:33 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:34 INFO - Running setup.py install for mozscreenshot 20:49:34 INFO - Running setup.py install for moztest 20:49:34 INFO - Running setup.py install for mozversion 20:49:34 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:49:34 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 20:49:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:49:35 INFO - Cleaning up... 20:49:35 INFO - Return code: 0 20:49:35 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 20:49: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')]} 20:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49: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')]} 20:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49: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 0x01989B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194CEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137C288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, '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 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A09660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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 20:49: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 20:49: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 20:49:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:49:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:49:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:49:35 INFO - 'COMPUTERNAME': 'T-W732-IX-149', 20:49:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:49:35 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:49:35 INFO - 'DCLOCATION': 'SCL3', 20:49:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:49:35 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:49:35 INFO - 'HOMEDRIVE': 'C:', 20:49:35 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:49:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:49:35 INFO - 'KTS_VERSION': '1.19c', 20:49:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:49:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149', 20:49:35 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:49:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:35 INFO - 'MOZ_NO_REMOTE': '1', 20:49:35 INFO - 'NO_EM_RESTART': '1', 20:49:35 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:49:35 INFO - 'OS': 'Windows_NT', 20:49:35 INFO - 'OURDRIVE': 'C:', 20:49: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;', 20:49:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:49:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:49:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:49:35 INFO - 'PROCESSOR_LEVEL': '6', 20:49:35 INFO - 'PROCESSOR_REVISION': '1e05', 20:49:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:49:35 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:49:35 INFO - 'PROMPT': '$P$G', 20:49:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:49:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:49:35 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:49:35 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:49:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:49:35 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:49:35 INFO - 'SYSTEMDRIVE': 'C:', 20:49:35 INFO - 'SYSTEMROOT': 'C:\\windows', 20:49:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:49:35 INFO - 'TEST1': 'testie', 20:49:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:49:35 INFO - 'USERDOMAIN': 'T-W732-IX-149', 20:49:35 INFO - 'USERNAME': 'cltbld', 20:49:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:49:35 INFO - 'WINDIR': 'C:\\windows', 20:49:35 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:49:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:49:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 20:49:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9lxahi-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 20:49: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)) 20:49:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 20:49:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zvvhqb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 20:49:35 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)) 20:49:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 20:49:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-z_zrpb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 20:49: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)) 20:49:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 20:49:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hxkgn1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 20:49: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)) 20:49:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 20:49:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0jeqlj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 20:49: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)) 20:49:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 20:49:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-moe0il-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 20:49: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)) 20:49:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 20:49:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qxysyf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 20:49: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)) 20:49:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 20:49:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w3gukg-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 20:49:37 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)) 20:49:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 20:49:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-osrxwe-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 20:49: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)) 20:49:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 20:49:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lhkcyh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 20:49: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)) 20:49:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 20:49:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nob3wn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 20:49: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)) 20:49:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 20:49:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qsrx8f-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 20:49: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)) 20:49:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 20:49:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x3d5xa-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 20:49: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)) 20:49:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 20:49:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-figghx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 20:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14)) 20:49:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 20:49:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rdcrya-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 20:49:38 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)) 20:49:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 20:49:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xunphn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 20:49: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)) 20:49:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 20:49:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qute60-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 20:49: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)) 20:49: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)) 20:49: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)) 20:49: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)) 20:49: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)) 20:49: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)) 20:49:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 20:49: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 20:49: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 20:49:39 INFO - Downloading blessings-1.5.1.tar.gz 20:49: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 20:49:39 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 20:49:39 INFO - Installing collected packages: blessings 20:49:39 INFO - Running setup.py install for blessings 20:49:39 INFO - Successfully installed blessings 20:49:39 INFO - Cleaning up... 20:49:42 INFO - Return code: 0 20:49:42 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 20:49:42 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 20:49:42 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 20:49:42 INFO - Reading from file tmpfile_stdout 20:49:42 INFO - Using _rmtree_windows ... 20:49:42 INFO - Using _rmtree_windows ... 20:49:42 INFO - Current package versions: 20:49:42 INFO - blessings == 1.5.1 20:49:42 INFO - blobuploader == 1.2.4 20:49:42 INFO - docopt == 0.6.1 20:49:42 INFO - manifestparser == 1.1 20:49:42 INFO - mozInstall == 1.12 20:49:42 INFO - mozcrash == 0.16 20:49:42 INFO - mozdebug == 0.1 20:49:42 INFO - mozdevice == 0.46 20:49:42 INFO - mozfile == 1.2 20:49:42 INFO - mozhttpd == 0.7 20:49:42 INFO - mozinfo == 0.8 20:49:42 INFO - mozleak == 0.1 20:49:42 INFO - mozlog == 3.0 20:49:42 INFO - moznetwork == 0.27 20:49:42 INFO - mozprocess == 0.22 20:49:42 INFO - mozprofile == 0.27 20:49:42 INFO - mozrunner == 6.10 20:49:42 INFO - mozscreenshot == 0.1 20:49:42 INFO - mozsystemmonitor == 0.0 20:49:42 INFO - moztest == 0.7 20:49:42 INFO - mozversion == 1.4 20:49:42 INFO - psutil == 3.1.1 20:49:42 INFO - requests == 1.2.3 20:49:42 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 20:49: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')]} 20:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49: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')]} 20:49:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49: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 0x01989B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194CEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137C288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, '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 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A09660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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 20:49: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 20:49: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 20:49:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:49:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:49:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:49:42 INFO - 'COMPUTERNAME': 'T-W732-IX-149', 20:49:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:49:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:49:42 INFO - 'DCLOCATION': 'SCL3', 20:49:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:49:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:49:42 INFO - 'HOMEDRIVE': 'C:', 20:49:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:49:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:49:42 INFO - 'KTS_VERSION': '1.19c', 20:49:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:49:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149', 20:49:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:49:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:49:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:42 INFO - 'MOZ_NO_REMOTE': '1', 20:49:42 INFO - 'NO_EM_RESTART': '1', 20:49:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:49:42 INFO - 'OS': 'Windows_NT', 20:49:42 INFO - 'OURDRIVE': 'C:', 20:49: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;', 20:49:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:49:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:49:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:49:42 INFO - 'PROCESSOR_LEVEL': '6', 20:49:42 INFO - 'PROCESSOR_REVISION': '1e05', 20:49:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:49:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:49:42 INFO - 'PROMPT': '$P$G', 20:49:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:49:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:49:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:49:42 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:49:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:49:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:49:42 INFO - 'SYSTEMDRIVE': 'C:', 20:49:42 INFO - 'SYSTEMROOT': 'C:\\windows', 20:49:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:49:42 INFO - 'TEST1': 'testie', 20:49:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:49:42 INFO - 'USERDOMAIN': 'T-W732-IX-149', 20:49:42 INFO - 'USERNAME': 'cltbld', 20:49:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:49:42 INFO - 'WINDIR': 'C:\\windows', 20:49:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:49:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:49:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:49:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:49: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)) 20:49: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)) 20:49: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)) 20:49: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)) 20:49: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)) 20:49: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)) 20:49: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)) 20:49: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)) 20:49: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)) 20:49: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)) 20:49: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)) 20:49:43 INFO - Cleaning up... 20:49:43 INFO - Return code: 0 20:49:43 INFO - Running post-action listener: _resource_record_post_action 20:49:43 INFO - Running post-action listener: _start_resource_monitoring 20:49:43 INFO - Starting resource monitoring. 20:49:43 INFO - ##### 20:49:43 INFO - ##### Running install step. 20:49:43 INFO - ##### 20:49:43 INFO - Running pre-action listener: _resource_record_pre_action 20:49:43 INFO - Running main action method: install 20:49:43 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 20:49:43 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 20:49:43 INFO - Reading from file tmpfile_stdout 20:49:43 INFO - Using _rmtree_windows ... 20:49:43 INFO - Using _rmtree_windows ... 20:49:43 INFO - Detecting whether we're running mozinstall >=1.0... 20:49: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'] 20:49:43 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 20:49:43 INFO - Reading from file tmpfile_stdout 20:49:43 INFO - Output received: 20:49:43 INFO - Usage: mozinstall-script.py [options] installer 20:49:43 INFO - Options: 20:49:43 INFO - -h, --help show this help message and exit 20:49:43 INFO - -d DEST, --destination=DEST 20:49:43 INFO - Directory to install application into. [default: 20:49:43 INFO - "C:\slave\test-pgo"] 20:49:43 INFO - --app=APP Application being installed. [default: firefox] 20:49:43 INFO - Using _rmtree_windows ... 20:49:43 INFO - Using _rmtree_windows ... 20:49:43 INFO - mkdir: C:\slave\test-pgo\build\application 20:49: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'] 20:49: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 20:49:45 INFO - Reading from file tmpfile_stdout 20:49:45 INFO - Output received: 20:49:45 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 20:49:45 INFO - Using _rmtree_windows ... 20:49:45 INFO - Using _rmtree_windows ... 20:49:45 INFO - Running post-action listener: _resource_record_post_action 20:49:45 INFO - ##### 20:49:45 INFO - ##### Running run-tests step. 20:49:45 INFO - ##### 20:49:45 INFO - Running pre-action listener: _resource_record_pre_action 20:49:45 INFO - Running main action method: run_tests 20:49:45 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 20:49:45 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 20:49:45 INFO - Python 2.7.3 20:49:45 INFO - Return code: 0 20:49:45 INFO - grabbing minidump binary from tooltool 20:49: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')]} 20:49: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 0x01298B48>, '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 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A09660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 20:49: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 20:49: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 20:49:45 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 20:49:46 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmphihw_u 20:49:46 INFO - INFO - File integrity verified, renaming tmphihw_u to win32-minidump_stackwalk.exe 20:49:46 INFO - Return code: 0 20:49:46 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 20:49:46 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 20:49:46 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 20:49:46 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 20:49:46 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 20:49:46 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Ash', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-149', '--webServer', 'localhost'] in C:\slave\test-pgo\build 20:49:46 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName Ash --suite dromaeojs --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-149 --webServer localhost 20:49:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:49:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:49:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:49:46 INFO - 'COMPUTERNAME': 'T-W732-IX-149', 20:49:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:49:46 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:49:46 INFO - 'DCLOCATION': 'SCL3', 20:49:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:49:46 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:49:46 INFO - 'HOMEDRIVE': 'C:', 20:49:46 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:49:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:49:46 INFO - 'KTS_VERSION': '1.19c', 20:49:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:49:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149', 20:49:46 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 20:49:46 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 20:49:46 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:49:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:49:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:46 INFO - 'MOZ_NO_REMOTE': '1', 20:49:46 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 20:49:46 INFO - 'NO_EM_RESTART': '1', 20:49:46 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:49:46 INFO - 'OS': 'Windows_NT', 20:49:46 INFO - 'OURDRIVE': 'C:', 20:49:46 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 20:49:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:49:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:49:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:49:46 INFO - 'PROCESSOR_LEVEL': '6', 20:49:46 INFO - 'PROCESSOR_REVISION': '1e05', 20:49:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:49:46 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:49:46 INFO - 'PROMPT': '$P$G', 20:49:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:49:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:49:46 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:49:46 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:49:46 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 20:49:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:49:46 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:49:46 INFO - 'SYSTEMDRIVE': 'C:', 20:49:46 INFO - 'SYSTEMROOT': 'C:\\windows', 20:49:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:49:46 INFO - 'TEST1': 'testie', 20:49:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:49:46 INFO - 'USERDOMAIN': 'T-W732-IX-149', 20:49:46 INFO - 'USERNAME': 'cltbld', 20:49:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:49:46 INFO - 'WINDIR': 'C:\\windows', 20:49:46 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:49:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:49:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:49:46 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Ash', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-149', '--webServer', 'localhost'] with output_timeout 3600 20:49:48 INFO - mozversion INFO | application_buildid: 20151027040234 20:49:48 INFO - mozversion INFO | application_changeset: a759c44ddb56eac3fd2e6cd23310e8f89be4ec18 20:49:48 INFO - mozversion INFO | application_display_name: Nightly 20:49:48 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:49:48 INFO - mozversion INFO | application_name: Firefox 20:49:48 INFO - mozversion INFO | application_remotingname: firefox 20:49:48 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/projects/ash 20:49:48 INFO - mozversion INFO | application_vendor: Mozilla 20:49:48 INFO - mozversion INFO | application_version: 44.0a1 20:49:48 INFO - mozversion INFO | platform_buildid: 20151027040234 20:49:48 INFO - mozversion INFO | platform_changeset: a759c44ddb56eac3fd2e6cd23310e8f89be4ec18 20:49:48 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/projects/ash 20:49:48 INFO - mozversion INFO | platform_version: 44.0a1 20:49:48 INFO - 2015-10-27 20:49:48,013 DEBUG : using testdate: 1446004188 20:49:48 INFO - 2015-10-27 20:49:48,013 DEBUG : actual date: 1446004188 20:49:48 INFO - 2015-10-27 20:49:48,023 INFO : starting webserver on 'localhost:49689' 20:49:48 INFO - 2015-10-27 20:49:48,023 INFO : Starting test suite T-W732-IX-149 20:49:48 INFO - 2015-10-27 20:49:48,025 INFO : Starting test dromaeo_css 20:49:48 INFO - 2015-10-27 20:49:48,025 DEBUG : operating with platform_type : w7_ 20:49:48 INFO - 2015-10-27 20:49:48,026 INFO : Initialising browser for dromaeo_css test... 20:49:48 INFO - 2015-10-27 20:49:48,220 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpqme2hi\profile http://localhost:49689/getInfo.html 20:49:55 INFO - 2015-10-27 20:49:55,838 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 20:49:55 INFO - 2015-10-27 20:49:55,838 DEBUG : BROWSER_OUTPUT: colorDepth:24 20:49:55 INFO - 2015-10-27 20:49:55,838 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 20:49:55 INFO - 2015-10-27 20:49:55,838 DEBUG : BROWSER_OUTPUT: __metrics 20:49:55 INFO - 2015-10-27 20:49:55,934 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:49:57 INFO - 2015-10-27 20:49:57,357 DEBUG : BROWSER_OUTPUT: console.error: 20:49:57 INFO - 2015-10-27 20:49:57,357 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] 20:49:57 INFO - 2015-10-27 20:49:57,357 DEBUG : BROWSER_OUTPUT: Stack: 20:49:57 INFO - 2015-10-27 20:49:57,357 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 20:49:57 INFO - 2015-10-27 20:49:57,357 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 20:49:57 INFO - 2015-10-27 20:49:57,358 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 20:49:57 INFO - 2015-10-27 20:49:57,358 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 20:49:57 INFO - 2015-10-27 20:49:57,358 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 20:49:57 INFO - 2015-10-27 20:49:57,358 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 20:49:57 INFO - 2015-10-27 20:49:57,358 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 20:49:57 INFO - 2015-10-27 20:49:57,358 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 20:49:57 INFO - 2015-10-27 20:49:57,358 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 20:49:57 INFO - 2015-10-27 20:49:57,358 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 20:49:57 INFO - 2015-10-27 20:49:57,358 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 20:49:57 INFO - 2015-10-27 20:49:57,358 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 20:49:57 INFO - 2015-10-27 20:49:57,358 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23 21:08:31 INFO - 2015-10-27 21:08:31,151 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 21:08:31 INFO - 2015-10-27 21:08:31,151 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 21:08:31 INFO - 2015-10-27 21:08:31,151 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 21:08:31 INFO - 2015-10-27 21:08:31,151 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 21:08:31 INFO - 2015-10-27 21:08:31,151 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 21:08:31 INFO - 2015-10-27 21:08:31,151 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 21:08:31 INFO - 2015-10-27 21:08:31,151 DEBUG : BROWSER_OUTPUT: 21:08:31 INFO - 2015-10-27 21:08:31,776 INFO : Browser initialized. 21:08:31 INFO - 2015-10-27 21:08:31,776 INFO : Running cycle 1/1 for kraken test... 21:08:31 INFO - 2015-10-27 21:08:31,776 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpotogvf\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 21:08:43 INFO - 2015-10-27 21:08:43,144 DEBUG : BROWSER_OUTPUT: RSS: Main: 137469952 21:08:43 INFO - 2015-10-27 21:08:43,144 DEBUG : BROWSER_OUTPUT: 21:08:45 INFO - 2015-10-27 21:08:45,530 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49689/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49689/tests/kraken/driver.html?testName=audio-beat-detection) 21:08:46 INFO - 2015-10-27 21:08:46,105 DEBUG : BROWSER_OUTPUT: RSS: Main: 171896832 21:08:46 INFO - 2015-10-27 21:08:46,105 DEBUG : BROWSER_OUTPUT: 21:08:48 INFO - 2015-10-27 21:08:48,664 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49689/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49689/tests/kraken/driver.html?testName=audio-dft) 21:08:49 INFO - 2015-10-27 21:08:49,232 DEBUG : BROWSER_OUTPUT: RSS: Main: 175525888 21:08:49 INFO - 2015-10-27 21:08:49,232 DEBUG : BROWSER_OUTPUT: 21:08:52 INFO - 2015-10-27 21:08:52,013 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49689/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49689/tests/kraken/driver.html?testName=audio-fft) 21:08:52 INFO - 2015-10-27 21:08:52,588 DEBUG : BROWSER_OUTPUT: RSS: Main: 167018496 21:08:52 INFO - 2015-10-27 21:08:52,588 DEBUG : BROWSER_OUTPUT: 21:08:54 INFO - 2015-10-27 21:08:54,644 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49689/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49689/tests/kraken/driver.html?testName=audio-oscillator) 21:08:55 INFO - 2015-10-27 21:08:55,203 DEBUG : BROWSER_OUTPUT: RSS: Main: 164839424 21:08:55 INFO - 2015-10-27 21:08:55,203 DEBUG : BROWSER_OUTPUT: 21:08:57 INFO - 2015-10-27 21:08:57,351 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49689/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49689/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:08:57 INFO - 2015-10-27 21:08:57,911 DEBUG : BROWSER_OUTPUT: RSS: Main: 163332096 21:08:57 INFO - 2015-10-27 21:08:57,911 DEBUG : BROWSER_OUTPUT: 21:09:00 INFO - 2015-10-27 21:09:00,986 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49689/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49689/tests/kraken/driver.html?testName=imaging-darkroom) 21:09:01 INFO - 2015-10-27 21:09:01,553 DEBUG : BROWSER_OUTPUT: RSS: Main: 160137216 21:09:01 INFO - 2015-10-27 21:09:01,553 DEBUG : BROWSER_OUTPUT: 21:09:04 INFO - 2015-10-27 21:09:04,888 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49689/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49689/tests/kraken/driver.html?testName=imaging-desaturate) 21:09:05 INFO - 2015-10-27 21:09:05,450 DEBUG : BROWSER_OUTPUT: RSS: Main: 160468992 21:09:05 INFO - 2015-10-27 21:09:05,450 DEBUG : BROWSER_OUTPUT: 21:09:08 INFO - 2015-10-27 21:09:08,250 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49689/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49689/tests/kraken/driver.html?testName=json-parse-financial) 21:09:08 INFO - 2015-10-27 21:09:08,825 DEBUG : BROWSER_OUTPUT: RSS: Main: 160219136 21:09:08 INFO - 2015-10-27 21:09:08,825 DEBUG : BROWSER_OUTPUT: 21:09:10 INFO - 2015-10-27 21:09:10,641 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49689/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49689/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:09:11 INFO - 2015-10-27 21:09:11,196 DEBUG : BROWSER_OUTPUT: RSS: Main: 190431232 21:09:11 INFO - 2015-10-27 21:09:11,196 DEBUG : BROWSER_OUTPUT: 21:09:13 INFO - 2015-10-27 21:09:13,102 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49689/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49689/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:09:13 INFO - 2015-10-27 21:09:13,670 DEBUG : BROWSER_OUTPUT: RSS: Main: 195514368 21:09:13 INFO - 2015-10-27 21:09:13,671 DEBUG : BROWSER_OUTPUT: 21:09:15 INFO - 2015-10-27 21:09:15,950 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49689/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49689/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:09:16 INFO - 2015-10-27 21:09:16,513 DEBUG : BROWSER_OUTPUT: RSS: Main: 201244672 21:09:16 INFO - 2015-10-27 21:09:16,513 DEBUG : BROWSER_OUTPUT: 21:09:18 INFO - 2015-10-27 21:09:18,960 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49689/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49689/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:09:19 INFO - 2015-10-27 21:09:19,519 DEBUG : BROWSER_OUTPUT: RSS: Main: 202813440 21:09:19 INFO - 2015-10-27 21:09:19,519 DEBUG : BROWSER_OUTPUT: 21:09:22 INFO - 2015-10-27 21:09:22,539 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49689/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49689/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:09:23 INFO - 2015-10-27 21:09:23,098 DEBUG : BROWSER_OUTPUT: RSS: Main: 202719232 21:09:23 INFO - 2015-10-27 21:09:23,098 DEBUG : BROWSER_OUTPUT: 21:09:24 INFO - 2015-10-27 21:09:24,937 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49689/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49689/tests/kraken/driver.html?testName=ai-astar) 21:09:25 INFO - 2015-10-27 21:09:25,193 DEBUG : BROWSER_OUTPUT: RSS: Main: 249184256 21:09:25 INFO - 2015-10-27 21:09:25,193 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,193 DEBUG : BROWSER_OUTPUT: __start_tp_report 21:09:25 INFO - 2015-10-27 21:09:25,193 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 21:09:25 INFO - 2015-10-27 21:09:25,193 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 21:09:25 INFO - 2015-10-27 21:09:25,193 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 21:09:25 INFO - 2015-10-27 21:09:25,193 DEBUG : BROWSER_OUTPUT: |0;ai-astar;93;95;97;90;96;96;102;97;94;94 21:09:25 INFO - 2015-10-27 21:09:25,193 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;131;130;144;161;131;130;138;135;154;129 21:09:25 INFO - 2015-10-27 21:09:25,193 DEBUG : BROWSER_OUTPUT: |2;audio-dft;156;155;156;160;162;157;156;159;159;160 21:09:25 INFO - 2015-10-27 21:09:25,193 DEBUG : BROWSER_OUTPUT: |3;audio-fft;99;105;79;100;81;77;77;76;99;81 21:09:25 INFO - 2015-10-27 21:09:25,193 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;103;95;112;94;94;95;121;95;95;117 21:09:25 INFO - 2015-10-27 21:09:25,193 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;117;116;117;117;116;122;116;117;118;117 21:09:25 INFO - 2015-10-27 21:09:25,193 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;140;139;140;139;140;141;139;139;139;139 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;92;91;91;93;93;93;92;91;93;92 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;78;78;78;79;80;80;78;78;79;90 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;79;78;78;78;78;78;78;80;79;81 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;96;100;96;97;104;100;100;97;99;100 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;121;119;122;123;128;120;122;123;129;123 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;186;195;196;192;194;184;195;193;195;185 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;84;84;84;84;84;85;84;84;86;84 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: __end_tp_report 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: __start_cc_report 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,827 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: __end_cc_report 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: __startTimestamp1446005365186__endTimestamp 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: Number of tests: 14 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:95.40 Median:95.50 stddev:3.13 (3.3%) stddev-sans-first:3.20 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: Values: 93.0 95.0 97.0 90.0 96.0 96.0 102.0 97.0 94.0 94.0 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:138.30 Median:136.50 stddev:11.24 (8.2%) stddev-sans-first:11.60 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: Values: 131.0 130.0 144.0 161.0 131.0 130.0 138.0 135.0 154.0 129.0 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:158.00 Median:159.00 stddev:2.31 (1.5%) stddev-sans-first:2.33 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: Values: 156.0 155.0 156.0 160.0 162.0 157.0 156.0 159.0 159.0 160.0 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.40 Median:80.00 stddev:11.72 (14.7%) stddev-sans-first:11.66 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: Values: 99.0 105.0 79.0 100.0 81.0 77.0 77.0 76.0 99.0 81.0 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,194 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.10 Median:94.50 stddev:10.60 (11.2%) stddev-sans-first:11.24 21:09:25 INFO - 2015-10-27 21:09:25,196 DEBUG : BROWSER_OUTPUT: Values: 103.0 95.0 112.0 94.0 94.0 95.0 121.0 95.0 95.0 117.0 21:09:25 INFO - 2015-10-27 21:09:25,196 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,197 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:1.77 (1.5%) stddev-sans-first:1.87 21:09:25 INFO - 2015-10-27 21:09:25,197 DEBUG : BROWSER_OUTPUT: Values: 117.0 116.0 117.0 117.0 116.0 122.0 116.0 117.0 118.0 117.0 21:09:25 INFO - 2015-10-27 21:09:25,197 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,197 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:139.50 Median:139.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 21:09:25 INFO - 2015-10-27 21:09:25,197 DEBUG : BROWSER_OUTPUT: Values: 140.0 139.0 140.0 139.0 140.0 141.0 139.0 139.0 139.0 139.0 21:09:25 INFO - 2015-10-27 21:09:25,197 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,198 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:92.10 Median:92.50 stddev:0.88 (0.9%) stddev-sans-first:0.93 21:09:25 INFO - 2015-10-27 21:09:25,198 DEBUG : BROWSER_OUTPUT: Values: 92.0 91.0 91.0 93.0 93.0 93.0 92.0 91.0 93.0 92.0 21:09:25 INFO - 2015-10-27 21:09:25,198 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,198 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:79.80 Median:79.00 stddev:3.68 (4.7%) stddev-sans-first:3.84 21:09:25 INFO - 2015-10-27 21:09:25,200 DEBUG : BROWSER_OUTPUT: Values: 78.0 78.0 78.0 79.0 80.0 80.0 78.0 78.0 79.0 90.0 21:09:25 INFO - 2015-10-27 21:09:25,200 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,200 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:78.70 Median:78.50 stddev:1.06 (1.3%) stddev-sans-first:1.12 21:09:25 INFO - 2015-10-27 21:09:25,200 DEBUG : BROWSER_OUTPUT: Values: 79.0 78.0 78.0 78.0 78.0 78.0 78.0 80.0 79.0 81.0 21:09:25 INFO - 2015-10-27 21:09:25,200 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,200 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:98.90 Median:96.00 stddev:2.47 (2.6%) stddev-sans-first:2.39 21:09:25 INFO - 2015-10-27 21:09:25,200 DEBUG : BROWSER_OUTPUT: Values: 96.0 100.0 96.0 97.0 104.0 100.0 100.0 97.0 99.0 100.0 21:09:25 INFO - 2015-10-27 21:09:25,200 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,201 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:123.00 stddev:3.20 (2.6%) stddev-sans-first:3.31 21:09:25 INFO - 2015-10-27 21:09:25,201 DEBUG : BROWSER_OUTPUT: Values: 121.0 119.0 122.0 123.0 128.0 120.0 122.0 123.0 129.0 123.0 21:09:25 INFO - 2015-10-27 21:09:25,201 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,203 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.50 Median:194.50 stddev:4.65 (2.4%) stddev-sans-first:4.48 21:09:25 INFO - 2015-10-27 21:09:25,203 DEBUG : BROWSER_OUTPUT: Values: 186.0 195.0 196.0 192.0 194.0 184.0 195.0 193.0 195.0 185.0 21:09:25 INFO - 2015-10-27 21:09:25,203 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,204 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:84.30 Median:84.00 stddev:0.67 (0.8%) stddev-sans-first:0.71 21:09:25 INFO - 2015-10-27 21:09:25,204 DEBUG : BROWSER_OUTPUT: Values: 84.0 84.0 84.0 84.0 84.0 85.0 84.0 84.0 86.0 84.0 21:09:25 INFO - 2015-10-27 21:09:25,204 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 21:09:25 INFO - 2015-10-27 21:09:25,204 DEBUG : BROWSER_OUTPUT: 21:09:25 INFO - 2015-10-27 21:09:25,765 INFO : Browser exited with error code: 0 21:09:26 INFO - 2015-10-27 21:09:26,503 INFO : Completed test kraken (00:01:00) 21:09:26 INFO - 2015-10-27 21:09:26,503 INFO : Starting test v8_7 21:09:26 INFO - 2015-10-27 21:09:26,503 DEBUG : operating with platform_type : w7_ 21:09:26 INFO - 2015-10-27 21:09:26,503 INFO : Initialising browser for v8_7 test... 21:09:26 INFO - 2015-10-27 21:09:26,529 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp7hzkdw\profile http://localhost:49689/getInfo.html 21:09:31 INFO - 2015-10-27 21:09:31,236 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 21:09:31 INFO - 2015-10-27 21:09:31,236 DEBUG : BROWSER_OUTPUT: colorDepth:24 21:09:31 INFO - 2015-10-27 21:09:31,236 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 21:09:31 INFO - 2015-10-27 21:09:31,236 DEBUG : BROWSER_OUTPUT: __metrics 21:09:31 INFO - 2015-10-27 21:09:31,311 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:09:31 INFO - 2015-10-27 21:09:31,786 DEBUG : BROWSER_OUTPUT: console.error: 21:09:31 INFO - 2015-10-27 21:09:31,786 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:09:31 INFO - 2015-10-27 21:09:31,786 DEBUG : BROWSER_OUTPUT: Stack: 21:09:31 INFO - 2015-10-27 21:09:31,786 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 21:09:31 INFO - 2015-10-27 21:09:31,788 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 21:09:31 INFO - 2015-10-27 21:09:31,788 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 21:09:31 INFO - 2015-10-27 21:09:31,788 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 21:09:31 INFO - 2015-10-27 21:09:31,788 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 21:09:31 INFO - 2015-10-27 21:09:31,788 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 21:09:31 INFO - 2015-10-27 21:09:31,788 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 21:09:31 INFO - 2015-10-27 21:09:31,788 DEBUG : BROWSER_OUTPUT: setAncestorsLastModified<@resource://gre/modules/Bookmarks.jsm:1347:9 21:09:31 INFO - 2015-10-27 21:09:31,788 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 21:09:31 INFO - 2015-10-27 21:09:31,788 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 21:09:31 INFO - 2015-10-27 21:09:31,789 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 21:09:31 INFO - 2015-10-27 21:09:31,789 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:808:13 21:09:31 INFO - 2015-10-27 21:09:31,789 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 21:09:31 INFO - 2015-10-27 21:09:31,789 DEBUG : BROWSER_OUTPUT: Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:934:23 21:09:31 INFO - 2015-10-27 21:09:31,789 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 21:09:31 INFO - 2015-10-27 21:09:31,789 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 21:09:31 INFO - 2015-10-27 21:09:31,789 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 21:09:31 INFO - 2015-10-27 21:09:31,789 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 21:09:31 INFO - 2015-10-27 21:09:31,789 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 21:09:31 INFO - 2015-10-27 21:09:31,789 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 21:09:31 INFO - 2015-10-27 21:09:31,789 DEBUG : BROWSER_OUTPUT: 21:09:32 INFO - 2015-10-27 21:09:32,348 INFO : Browser initialized. 21:09:32 INFO - 2015-10-27 21:09:32,348 INFO : Running cycle 1/1 for v8_7 test... 21:09:32 INFO - 2015-10-27 21:09:32,348 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp7hzkdw\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\v8_7\v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 21:09:43 INFO - 2015-10-27 21:09:43,717 DEBUG : BROWSER_OUTPUT: RSS: Main: 134725632 21:09:43 INFO - 2015-10-27 21:09:43,717 DEBUG : BROWSER_OUTPUT: 21:10:05 INFO - 2015-10-27 21:10:05,236 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49689/tests/v8_7/run.html (next: http://localhost:49689/tests/v8_7/run.html) 21:10:05 INFO - 2015-10-27 21:10:05,492 DEBUG : BROWSER_OUTPUT: RSS: Main: 439029760 21:10:05 INFO - 2015-10-27 21:10:05,492 DEBUG : BROWSER_OUTPUT: 21:10:05 INFO - 2015-10-27 21:10:05,492 DEBUG : BROWSER_OUTPUT: __start_tp_report 21:10:05 INFO - 2015-10-27 21:10:05,492 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 21:10:05 INFO - 2015-10-27 21:10:05,492 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 21:10:05 INFO - 2015-10-27 21:10:05,492 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 21:10:05 INFO - 2015-10-27 21:10:05,492 DEBUG : BROWSER_OUTPUT: |0;Richards;154.03573629081947 21:10:05 INFO - 2015-10-27 21:10:05,492 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;204.66639377814164 21:10:05 INFO - 2015-10-27 21:10:05,492 DEBUG : BROWSER_OUTPUT: |2;Encrypt;295.5082742316785 21:10:05 INFO - 2015-10-27 21:10:05,492 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5324.468085106383 21:10:05 INFO - 2015-10-27 21:10:05,492 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1416.4305949008499 21:10:05 INFO - 2015-10-27 21:10:05,492 DEBUG : BROWSER_OUTPUT: |5;Earley;639.386189258312 21:10:05 INFO - 2015-10-27 21:10:05,492 DEBUG : BROWSER_OUTPUT: |6;Boyer;10468.75 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: |7;RegExp;29588.235294117647 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: |8;Splay;750.7507507507507 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;5112.244897959184 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: __end_tp_report 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: __startTimestamp1446005405486__endTimestamp 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: Number of tests: 10 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:154.04 Median:154.04 stddev:0.00 (0.0%) 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: Values: 154.0 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:204.67 Median:204.67 stddev:0.00 (0.0%) 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: Values: 204.7 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:295.51 Median:295.51 stddev:0.00 (0.0%) 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: Values: 295.5 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5324.47 Median:5324.47 stddev:0.00 (0.0%) 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: Values: 5324.5 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1416.43 Median:1416.43 stddev:0.00 (0.0%) 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: Values: 1416.4 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:639.39 Median:639.39 stddev:0.00 (0.0%) 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: Values: 639.4 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10468.75 Median:10468.75 stddev:0.00 (0.0%) 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: Values: 10468.8 21:10:05 INFO - 2015-10-27 21:10:05,493 DEBUG : BROWSER_OUTPUT: 21:10:05 INFO - 2015-10-27 21:10:05,494 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:29588.24 Median:29588.24 stddev:0.00 (0.0%) 21:10:05 INFO - 2015-10-27 21:10:05,494 DEBUG : BROWSER_OUTPUT: Values: 29588.2 21:10:05 INFO - 2015-10-27 21:10:05,494 DEBUG : BROWSER_OUTPUT: 21:10:05 INFO - 2015-10-27 21:10:05,494 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:750.75 Median:750.75 stddev:0.00 (0.0%) 21:10:05 INFO - 2015-10-27 21:10:05,494 DEBUG : BROWSER_OUTPUT: Values: 750.8 21:10:05 INFO - 2015-10-27 21:10:05,494 DEBUG : BROWSER_OUTPUT: 21:10:05 INFO - 2015-10-27 21:10:05,494 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:5112.24 Median:5112.24 stddev:0.00 (0.0%) 21:10:05 INFO - 2015-10-27 21:10:05,494 DEBUG : BROWSER_OUTPUT: Values: 5112.2 21:10:05 INFO - 2015-10-27 21:10:05,494 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 21:10:05 INFO - 2015-10-27 21:10:05,494 DEBUG : BROWSER_OUTPUT: 21:10:06 INFO - 2015-10-27 21:10:06,086 INFO : Browser exited with error code: 0 21:10:06 INFO - 2015-10-27 21:10:06,744 INFO : Completed test v8_7 (00:00:40) 21:10:06 INFO - 2015-10-27 21:10:06,880 INFO : Completed test suite (00:20:18) 21:10:06 INFO - 2015-10-27 21:10:06,880 DEBUG : Working with test: dromaeo_css 21:10:06 INFO - 2015-10-27 21:10:06,880 DEBUG : Generating results file: dromaeo_css 21:10:06 INFO - 2015-10-27 21:10:06,880 DEBUG : Working with test: kraken 21:10:06 INFO - 2015-10-27 21:10:06,881 DEBUG : Generating results file: kraken 21:10:06 INFO - 2015-10-27 21:10:06,881 INFO : javascript benchmark 21:10:06 INFO - 2015-10-27 21:10:06,881 DEBUG : Working with test: v8_7 21:10:06 INFO - 2015-10-27 21:10:06,881 DEBUG : Generating results file: v8_7 21:10:06 INFO - 2015-10-27 21:10:06,881 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 21:10:08 INFO - 2015-10-27 21:10:08,464 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,140,25]] 21:10:08 INFO - 2015-10-27 21:10:08,464 DEBUG : process_Request line: dromaeo_css 6474.63 graph.html#tests=[[72,140,25]] 21:10:08 INFO - 2015-10-27 21:10:08,464 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 21:10:09 INFO - 2015-10-27 21:10:09,394 DEBUG : process_Request line: kraken 1586.30 graph.html#tests=[[232,140,25]] 21:10:09 INFO - 2015-10-27 21:10:09,394 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 21:10:09 INFO - 2015-10-27 21:10:09,423 DEBUG : process_Request line: v8_7 20267.61 graph.html#tests=[[230,140,25]] 21:10:09 INFO - 2015-10-27 21:10:09,424 INFO : javascript benchmark 21:10:09 INFO - 2015-10-27 21:10:09,426 INFO : TALOSDATA: [{"talos_counters": {}, "results": {"jquery.html": [328.55972996185125, 335.09524284432007, 338.64442335547676, 338.83905145056093, 341.6532219796905, 11964.102692298069, 12210.0, 17146.314147434103, 17348.56628584286, 17510.64978700427, 11199.048080913111, 11234.831477527843, 11389.658310250685, 11393.430328483584, 11534.596289129882, 4351.956480435203, 4441.511433742302, 4445.377647129404, 4446.133004064209, 4447.644188464915, 4396.978015109936, 4420.850578849506, 4429.446319210098, 4439.112177564484, 4446.599806017458, 4291.35629655551, 4320.6327462165755, 4388.144311859189, 4390.165868484977, 4395.758233297167, 4262.29672104104, 4299.290617048199, 4306.20335237982, 4358.8038538265855, 4386.824527018915, 3710.8144592770254, 3756.7182461315238, 3762.868299609528, 3769.5099637047283, 3773.6792372648356, 12764.680882977907, 12887.871121288854, 13083.149595276373, 13106.934465327708, 13153.671158221025, 1947.0362170725439, 2026.9594608107755, 2046.5599896022277, 2124.9787502124937, 2141.29337318686, 9581.61673533058, 9720.222382209406, 9837.458939758311, 9869.703908882744, 9894.653687120915, 1577.4557777566836, 1600.3194648963056, 1618.7895573575477, 1641.4172968596215, 1652.4051341517045, 60140.398596014355, 69866.95199572011, 69904.99999999949, 71015.64492177557, 71909.64045179792, 6175.413335733098, 6191.84520386994, 6221.0, 6313.368663133741, 6402.263739669997, 7328.560286382834, 7336.633168341562, 7352.264773522712, 7354.448416368687, 7355.264473552602, 5291.523762861284, 5304.2839216706025, 5306.628536002443, 5396.91904621431, 5397.865053373697, 5323.574114070942, 5794.420557944172, 5816.476517113396, 5828.0, 5829.533637309005, 2790.3582176099612, 2832.2919270182456, 2864.756495697847, 2872.167071549269, 2875.8130721503453, 1029.361795686664, 1029.5675816157081, 1032.87605487342, 1035.4253389368973, 1084.387321163556, 403.8707613563631, 416.6229562246172, 441.5341814385854, 441.8652311045098, 456.29502418763093, 10623.568109330306, 11600.013998810027, 11602.70993225176, 12171.634850954308, 12245.0, 404.6196575219343, 472.23733670122505, 482.7031375703917, 484.7866938547028, 497.54474655689637, 6186.443220110212, 6382.329855365213, 6383.042543618401, 6398.104265402847, 6421.325760795049, 336391.0, 338996.18532424525, 470023.0, 474126.0, 476603.0, 84097.57951210205, 84509.0, 85298.57350713207, 85647.5717621408, 85937.57031214803, 485.74741134609803, 505.88617561049205, 506.07388479083926, 506.6630690590716, 507.6598678885156, 10801.567937282734, 10916.727081823017, 11007.06439952613, 11068.667939961813, 11075.889241107809, 37978.62021379861, 38591.80704096462, 38770.80614596909, 38964.610353896096, 39297.2140557193, 53850.461495384545, 54241.45758542522, 55841.162382565104, 59942.0, 60551.09173362491, 5997.280326360865, 6067.908981365194, 6119.785807496716, 6169.444749972524, 6303.401176888188, 12213.75572488563, 12254.816177757162, 12297.385130743249, 12331.44508497102, 12377.566785162477, 10169.491525423551, 10252.589896404359, 10368.0, 10546.736331591464, 10589.152867770714, 10122.59509619607, 10260.640877569249, 10405.0, 10470.26708130454, 10540.631077912543, 10751.731206719895, 11026.558937642405, 11054.447277636247, 11168.0, 11180.720481988015, 6053.364396738455, 6293.842653933688, 6366.93633063682, 6367.395097465918, 6394.241583564557, 10105.44420056904, 10529.631462898762, 10788.514516846917, 10860.945695271474, 10889.455527223765], "dojo.html": [2737.3704048068944, 2781.582762585613, 2789.5397259452193, 2807.4946509628257, 2830.716928307169, 4914.410270767513, 5124.308218390512, 5208.0, 5216.400113986892, 5265.552428043616, 5245.10833158363, 5247.658902171356, 5263.0526505229045, 5287.444818294081, 5339.279197308362, 4425.623821975136, 4548.658850586219, 4569.246074397721, 4635.675502714811, 4654.697444666086, 4482.8655140345845, 4548.818047278105, 4550.089982003596, 4556.270996640538, 4622.514635963226, 4414.911701765963, 4521.751303678297, 4558.133954548625, 4571.1086338163905, 4612.146752850716, 4409.691321607489, 4456.821727130911, 4560.087982403499, 4579.496255411902, 4646.512116227781, 4423.623991960687, 4430.556944305576, 4458.308962110878, 4488.214562451558, 4524.208263553898, 5174.146265866128, 5205.167173252262, 5232.843014709565, 5277.920831187535, 5284.947150528484, 5328.333958255218, 5418.322709661293, 5465.562754979592, 5566.387697353288, 5582.972085139589, 5502.0921547944545, 5565.972170139164, 5608.130739735348, 5613.775448982035, 5624.3813180550105, 5117.257997590365, 5143.099957507421, 5149.665271757323, 5206.349206349207, 5213.296205012275, 5583.330000399897, 5644.774209031592, 5653.660780353192, 5665.66006039639, 5739.454751798572, 5332.706701131475, 5333.959877823787, 5374.059539580558, 5484.698341591251, 5492.1212605982855, 5473.06957817172, 5509.228707980886, 5599.776008959596, 5618.747156377973, 5622.0, 5376.005438993799, 5455.863603409946, 5458.836234912879, 5489.39616642177, 5558.360788509292, 5442.700651464207, 5450.645708029045, 5474.0146773580345, 5558.861028474321, 5578.497935185853, 3366.596008478998, 3464.7747896386654, 3490.1798077451776, 3493.7379696522353, 3495.091276268138, 3133.1853718033476, 3152.7320177785145, 3195.7123858852815, 3213.79110357826, 3214.035789263212, 5136.871578210575, 5143.7685304161405, 5174.379074511083, 5283.339582552181, 5310.097283461821, 5461.535769459561, 5547.1094613805535, 5552.7778888844805, 5576.498115169654, 5656.830295091154, 5061.215511595708, 5245.082110630625, 5264.684118952875, 5307.310049693515, 5309.097453432925, 4330.458692663417, 4334.956650433455, 4362.912741745147, 4383.780810959439, 4396.912061758747, 5840.269966254218, 5853.94146058534, 5915.881682366415, 5934.851628709231, 5948.0, 5371.516563509303, 5494.972525137348, 5498.230247765396, 5572.832815015556, 5667.121596152603, 5640.0, 5723.971380143073, 5858.531317494505, 5923.555733319932, 5938.554608404301, 5328.57371410294, 5430.8099216527235, 5439.836804895859, 5455.536279416294, 5458.9454105458435, 5465.808696695596, 5544.3346798384455, 5550.4449555044175, 5560.805371811967, 5726.0, 5327.920081198863, 5379.54273886724, 5444.591655625764, 5481.314835645544, 5484.232207490874, 5283.8679033022945, 5322.281491998686, 5412.107002344726, 5514.944850551604, 5538.6122971391615, 5196.792128314825, 5240.6855588663275, 5277.419483856849, 5325.4940780625775, 5435.646682965595, 5336.1995700643665, 5375.5162035417, 5419.83740487786, 5437.0, 5491.450854914477, 5318.0, 5351.518363347317, 5364.570834333166, 5403.648762830403, 5421.647592906448], "mootools.html": [1237.9257244565324, 1238.139493052329, 1370.7464119137958, 1374.374659529914, 1388.8333399992002, 7610.238976102387, 7665.616719164033, 8512.531810750406, 8872.334574906883, 8979.191872731453, 8597.248880358273, 8618.439801412904, 8816.382853200263, 8868.512231827246, 8871.0, 2022.3225219551468, 2035.9491012724652, 2040.7755146933825, 2051.825594824442, 2052.969205461919, 1777.0848013273176, 1824.9087545622733, 2037.4193354893857, 2060.9484762880898, 2079.303433349822, 2017.213286818142, 2020.1313435222849, 2020.3029954665617, 2024.9392518224477, 2026.8074532919422, 1985.1067019841057, 2016.9596608067757, 2027.9594408111755, 2030.725852009982, 2033.4103110098054, 1952.5021119614407, 2034.5727397246596, 2047.0890453748088, 2067.286786058822, 2068.6690129579347, 8749.737507874774, 8772.078931712142, 8785.692500762443, 8834.204921557026, 8849.247813935823, 4909.901801963976, 5020.573251273646, 5033.597312215014, 5033.672811267256, 5038.899222015576, 3265.2816380396275, 3283.310504793996, 3310.1724568857785, 3408.079818449005, 3421.1789170599127, 949.3022628368144, 967.3760424526212, 987.521052289639, 990.3315262197988, 1012.1599072769584, 12783.93608031954, 12786.232826030468, 12820.294883781387, 12836.229826210363, 12859.421326040258, 4307.935380969288, 4336.674749393808, 4407.757573333497, 4422.513523512411, 4454.799534020977, 9651.276154288456, 9860.992397318887, 9962.551685174185, 9969.900300997042, 9971.900280997097, 4953.777080031407, 4971.0, 4973.055119527278, 4993.350864387607, 4994.325766021613, 4393.648508119343, 4441.71128876622, 4502.0, 4543.704659197208, 4543.750093744876, 3596.226811235597, 3624.637536246407, 3641.1807343347537, 3663.102539877747, 3709.497653450357, 1038.787048655024, 1052.8736551613856, 1060.4485667452882, 1103.2883789955433, 1107.341132026443, 907.945523268606, 910.5947853205392, 913.5295322908707, 914.3051281026468, 914.9313801464916, 7697.461177717618, 7743.651535680908, 7789.883151752728, 7834.334081603013, 7933.127355990837, 962.1629182611033, 984.2716389871443, 1005.0954141272766, 1017.4861694844057, 1038.8493668418037, 5619.7471113799975, 5767.134929760569, 5776.277965254343, 5824.563157763185, 5888.499477544457, 11185.552577896955, 11387.20289579738, 11422.14333924959, 11434.371109588887, 11476.254043487314, 10979.1765617579, 11301.943490282496, 11302.830457543143, 11306.378149201708, 11334.886651133547, 7916.920830791733, 7975.202479752094, 8027.678892844221, 8120.512769233865, 8129.1464396238725, 7450.6647200875, 7600.3539699126195, 7610.733624323344, 7639.465237433326, 7657.349125324187, 9421.738782692242, 9820.705378838646, 9865.654702085392, 9890.752731181778, 9939.950300248453, 9995.850062249217, 10128.44293563861, 10202.693919182437, 10251.282410231212, 10312.639057632947, 8009.439339246197, 8045.879311810445, 8117.837643246984, 8122.878156827534, 8124.593770311579, 7620.3522700571075, 8759.693410730593, 8781.29749620016, 8845.734627961172, 8851.291896648381, 6713.865722685421, 7427.628618568941, 8125.106238313662, 8168.83662326762, 8354.289885359569, 7933.167017463315, 7956.6419511120985, 8086.15095414973, 8106.513609183467, 8126.228008339198, 7969.402294828027, 7986.92013079885, 8009.959950200445, 8032.429622520737, 8060.596970151586, 6658.201015878126, 6698.626781509876, 6757.729690812313, 6796.2524122347495, 6801.863962720817, 7029.718811247493, 7099.929000710134, 7113.57318560888, 7125.501214914906, 7137.750178743927], "ext.html": [476.7663844716088, 514.1875836178837, 518.2485396175547, 518.9740512974352, 522.1071966936538, 31249.84375078124, 31285.530717039263, 31396.0, 31523.527147092813, 39212.21575568487, 41340.79329603355, 41653.16693666125, 41699.79150104253, 41729.58270417295, 41750.0, 8379.162083791633, 8401.705940292075, 8495.787605309855, 8538.06081331053, 8570.828583428327, 7572.167061623217, 7654.272844079831, 8309.501429914195, 10538.262321637489, 10706.143508519339, 10482.056614904657, 10562.471876406187, 10565.207609429324, 10604.257701960865, 10627.628033018807, 7576.356009739179, 10068.7345347297, 10132.797344053077, 10142.594296228215, 10157.949210253975, 7455.291747283999, 9601.807963840683, 10426.436034594795, 10473.47632618366, 10475.319104258198, 38536.42195367072, 38822.611773882185, 38892.22215555673, 39090.413643795364, 39130.80434597837, 2901.1296611016824, 2991.2820922978544, 3203.423383790917, 3228.983855080733, 3356.8153751543655, 31261.531077033862, 31377.686223137705, 31435.84282078575, 31541.842290788627, 32423.0, 2786.6238057862133, 2822.8588570571387, 2826.3499395139233, 2863.7995340326183, 2905.7239562241552, 328274.0, 328293.0, 329635.35182324174, 332225.0, 333171.0, 46450.303245451345, 50997.23504147441, 51760.48239517632, 53370.0, 54234.18648720272, 46892.5310746895, 58848.0, 59127.70436147792, 59183.704081479314, 59645.403545963985, 13944.442222311196, 14014.089084209494, 14144.151350918977, 14153.929230353782, 14178.574642760734, 10622.415767132881, 10655.680329590125, 10706.357618542912, 10715.46422678863, 10742.355458672504, 16780.580485487717, 17127.400540981005, 17649.205785739672, 17664.555411694597, 17802.732959005625, 7275.126984761862, 7374.1151061872915, 7422.554646721214, 7637.312641862259, 7644.732434364882, 829.531314807132, 916.6837441082815, 947.8625599288065, 1020.0666390252835, 1031.8194315994672, 28324.71675283262, 29005.70994290072, 29478.852605737262, 30368.392632147676, 30787.538186927213, 939.234523863063, 941.8822647169104, 964.991583794936, 1000.1548691355792, 1051.2063392138887, 14508.564743057725, 14559.0, 14878.479253226302, 14883.776743348859, 14937.598086839593, 404369.0, 410345.0, 411719.0, 412183.0, 416901.0, 48542.0, 48701.51298487041, 48896.26655600169, 48902.75548622234, 48973.51026489761, 1081.2431298091367, 1094.2996482251365, 1158.102470585303, 1172.8592568891788, 1181.173178774861, 26674.59988100219, 28366.0, 28492.0, 29108.854455727585, 29418.705812941596, 42484.362734559625, 42883.7855810719, 43149.0, 43157.3526397098, 43233.78383108064, 369047.0, 370354.0, 372743.0, 375793.0, 377147.0, 25695.100671476408, 27742.5838612425, 27992.440151196453, 28006.719932801225, 28025.43949121047, 40496.0, 41248.251049371334, 41261.0, 41474.585254147074, 41675.7916210417, 30939.226519337197, 32410.679917415877, 32795.67204328022, 33193.66806332002, 34581.30837383188, 35529.644703552636, 36193.45709814408, 36251.27497450089, 36353.0, 36799.63200367962, 35538.0, 36195.819020904724, 36344.27311453809, 36506.8174659125, 36550.4517432244, 30051.24871878133, 30335.544966825957, 31070.37859242848, 31162.376752465276, 33110.0, 21016.26443074546, 25271.494570108127, 25272.87363563244, 25348.366290842878, 25664.486710265315], "prototype.html": [325.26003342396046, 332.38508758796223, 338.8881669049213, 339.64337445682025, 340.3431377441537, 5755.712214389285, 5803.361630220677, 5844.7954321598645, 5845.766169353226, 5856.0, 5727.341355744091, 5748.942510574893, 5796.188533605288, 5800.970995145029, 5809.941900580993, 4221.7255878367805, 4279.999999999992, 4316.935245971313, 4320.870373888788, 4325.653947684194, 4313.870583882489, 4335.219660461115, 4340.609345158935, 4347.2826983547675, 4353.08585197109, 4278.0, 4289.356596510533, 4290.206311832306, 4290.4851417829905, 4298.398224248608, 4157.750534967911, 4193.979030104861, 4231.492220933477, 4246.532881383046, 4249.2563801334645, 3731.552213734369, 3758.229562939618, 3761.22894806564, 3768.321702093622, 3794.7343685941996, 5488.670679759227, 5583.274174357348, 5617.943820561783, 5624.465675760796, 5658.8019419320535, 4958.380202474691, 5036.7733451994745, 5052.873678158038, 5072.644914855961, 5073.797048118071, 5967.283925928873, 5986.191864098378, 5991.2510936132985, 5997.640141591519, 6003.339632640406, 4200.642945349649, 4215.32554791235, 4221.894452638678, 4222.0289333453175, 4228.746275223466, 6259.280182778994, 6262.6868656566985, 6271.404216599416, 6276.403741644536, 6282.277538083134, 4092.9386059209137, 4184.0, 4295.871123866289, 4348.869533913988, 4356.956430435718, 4432.047109871329, 4445.644348452116, 4448.844290449819, 4448.998975230547, 4449.555044495524, 4927.753612319441, 4994.425641051253, 5064.341635587423, 5069.695818250917, 5073.644844860898, 6023.939760602338, 6024.1566180734735, 6026.849328766728, 6027.126066720301, 6031.668258245751, 1596.074276919384, 1597.9201039947957, 1600.0479714569813, 1601.255416231458, 1604.4625050607945, 1081.729567608098, 1099.5381939585234, 1105.867295924494, 1109.4896347679996, 1120.7478317378525, 5793.478586927113, 5813.244278243886, 5813.82558523245, 5909.527237820964, 5918.437748413894, 6243.531735119885, 6267.059941008885, 6274.74505098982, 6276.995680691159, 6293.465055470245, 5882.000059989812, 5886.470217680344, 5894.970525147432, 5903.144044113579, 5910.202122713465, 3341.799492030486, 3438.277961628086, 3452.896413107611, 3459.4291941829315, 3461.7576769625884, 6573.474122070318, 6598.934010659832, 6601.174853143357, 6601.3068627794355, 6609.074729537869, 6380.904286435707, 6383.553151279366, 6404.519661025442, 6409.134766806422, 6416.486681065504, 6539.051837483538, 6591.406773390418, 6592.868142637216, 6608.669566521655, 6608.900866487006, 6057.939420605738, 6228.657423841732, 6250.031245157008, 6254.468370188585, 6270.372962703876, 6024.487918526975, 6088.4215999479975, 6091.695415229132, 6106.603070800384, 6107.69461526913, 6531.542792004514, 6536.7712130075215, 6597.703103360456, 6606.636634985122, 6613.074169616161, 4200.243956087811, 4377.321515165155, 4446.199684056901, 4462.0, 4466.196084704784, 6146.170267014075, 6156.353582873913, 6221.160143380585, 6241.188645476059, 6296.937030629819, 6085.056816193497, 6099.420555047263, 6109.572329936862, 6112.663803490851, 6113.480354169767, 6041.9395806041375, 6043.7884674036195, 6051.0, 6055.333913269625, 6057.4851137653795, 5799.420057994167, 5823.155642431909, 5916.763329466945, 5918.763249469973, 5920.851978700567, 5762.567807414377, 5891.94108058931, 6034.0, 6073.817785466443, 6077.331493535796, 6002.69986500682, 6049.637021778707, 6064.090386441892, 6069.484093852072, 6078.088286756845], "yui.html": [664.1664710787961, 713.2974020589718, 735.6285076036601, 754.7018927523632, 776.6427443376047, 12716.173448725825, 12883.613491595255, 12907.0, 13007.08950373473, 14267.0, 12925.217669462312, 13016.609501714964, 13040.413181406859, 13043.152195107312, 13069.869301306984, 505.4162442379058, 509.5083244668894, 603.0321334258507, 610.2768219659717, 611.6605284067341, 603.6197195766683, 607.8298076538554, 611.4069352727847, 612.7732738886596, 613.4724137241968, 631.2551189596275, 632.1308201223318, 637.018990754238, 637.0698779781525, 637.1812221295636, 523.5471317310537, 524.8215606693724, 627.159606127791, 635.2504045226631, 637.5887552528616, 450.7430764464224, 452.0664827131995, 454.3956537804712, 455.30565887022226, 455.48530160918284, 11515.884841151565, 11774.352410617412, 12171.0, 12203.145779795417, 12232.449539770732, 9353.251739860863, 9549.09283618055, 9552.761180970461, 9591.952040239754, 9863.60545578176, 9331.486768227745, 9478.146966772954, 9712.0, 9837.803243935154, 10088.991100889925, 3028.545718142296, 3099.2406860319143, 3117.2206948262933, 3120.7503399728193, 3134.0754477429214, 47149.0, 47227.99999999966, 47319.52680473185, 47417.0, 47611.0, 6990.825229369307, 7160.57036577807, 7219.989201511793, 7284.453665975073, 7444.957705921067, 10323.793524129474, 10544.0, 10862.891371086345, 10893.891061089445, 10903.236773425942, 6504.837379065467, 6548.0, 6624.6025238485845, 6693.631850248185, 6695.866082678319, 3869.004851121612, 3899.5515515715488, 3926.096997690547, 3932.174243408859, 3946.980265098656, 2553.476537309847, 2599.233226198276, 2600.4409052053898, 2629.2375211188923, 2635.2489540481256, 412.56680485490716, 419.42329297216327, 438.46945196312817, 438.9012472193731, 438.95829896159813, 576.9336526299529, 579.1920271221622, 603.4900509069829, 618.5639124417276, 625.5902383938527, 8904.8664270036, 9226.123518265755, 9837.704868853945, 9933.801323973625, 10112.74718132038, 604.8760004199129, 606.8877257707338, 705.9717611295594, 711.6939715922204, 739.1979702023258, 4008.518977722692, 4570.611498022638, 4686.484486706459, 4704.811807527661, 4793.712377257376, 57955.71022144862, 58151.4184858146, 58315.416845831845, 58358.708206458694, 58492.0, 48757.0, 49271.260931086064, 49664.50335496671, 49882.0, 49985.75007124941, 40199.397009044886, 41165.79417102895, 41537.58462415398, 41679.0, 41756.79121604433, 9242.260619150586, 9324.0, 9356.859647105162, 9407.623695052394, 9432.481213533321, 29017.419651607273, 30924.845375772977, 30970.380592388476, 31078.22304442407, 31129.68870311268, 41024.589754102075, 41273.587264126974, 41461.37807932823, 41526.792366037975, 42064.15871682488, 11569.479373428048, 11579.768404632028, 11632.88367116318, 11790.23363481371, 11935.283882967049, 13144.27706476153, 13195.736085278433, 13331.733365332833, 13357.53248636293, 13732.0, 11823.46794394237, 12274.386280686109, 12592.685182870502, 12637.0, 12680.492780289054, 11700.2979821211, 11756.353400563052, 11819.82270265964, 12246.326452044867, 12602.747945040865, 11826.290422574673, 12136.878631213574, 12299.631011069681, 12561.748765024831, 12984.870151298366, 9986.500674966368, 10290.742731431774, 10328.1440933069, 10451.58193672275, 10817.56729730802, 9580.329376943548, 10420.855681676438, 10484.737881553023, 10508.42203678805, 10647.893738891979]}, "summary": {"suite": 6474.629709908573, "subtests": {"jquery.html": {"filtered": 6633.692006335722, "value": 6633.692006335722}, "dojo.html": {"filtered": 4998.295955680188, "value": 4998.295955680188}, "mootools.html": {"filtered": 4664.149554977084, "value": 4664.149554977084}, "ext.html": {"filtered": 18048.098230889613, "value": 18048.098230889613}, "prototype.html": {"filtered": 4597.60244085085, "value": 4597.60244085085}, "yui.html": {"filtered": 5740.369291532541, "value": 5740.369291532541}}}, "test_machine": {"platform": "x86", "osversion": "6.1.7601", "os": "win", "name": "T-W732-IX-149"}, "testrun": {"date": 1446004188, "suite": "dromaeo_css", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "44.0a1", "id": "20151027040234", "branch": "Ash", "revision": "a759c44ddb56eac3fd2e6cd23310e8f89be4ec18"}}, {"talos_counters": {}, "results": {"audio-dft": [156.0, 155.0, 156.0, 160.0, 162.0, 157.0, 156.0, 159.0, 159.0, 160.0], "ai-astar": [93.0, 95.0, 97.0, 90.0, 96.0, 96.0, 102.0, 97.0, 94.0, 94.0], "imaging-gaussian-blur": [117.0, 116.0, 117.0, 117.0, 116.0, 122.0, 116.0, 117.0, 118.0, 117.0], "audio-beat-detection": [131.0, 130.0, 144.0, 161.0, 131.0, 130.0, 138.0, 135.0, 154.0, 129.0], "json-stringify-tinderbox": [79.0, 78.0, 78.0, 78.0, 78.0, 78.0, 78.0, 80.0, 79.0, 81.0], "stanford-crypto-pbkdf2": [186.0, 195.0, 196.0, 192.0, 194.0, 184.0, 195.0, 193.0, 195.0, 185.0], "json-parse-financial": [78.0, 78.0, 78.0, 79.0, 80.0, 80.0, 78.0, 78.0, 79.0, 90.0], "stanford-crypto-sha256-iterative": [84.0, 84.0, 84.0, 84.0, 84.0, 85.0, 84.0, 84.0, 86.0, 84.0], "audio-fft": [99.0, 105.0, 79.0, 100.0, 81.0, 77.0, 77.0, 76.0, 99.0, 81.0], "imaging-darkroom": [140.0, 139.0, 140.0, 139.0, 140.0, 141.0, 139.0, 139.0, 139.0, 139.0], "stanford-crypto-ccm": [121.0, 119.0, 122.0, 123.0, 128.0, 120.0, 122.0, 123.0, 129.0, 123.0], "audio-oscillator": [103.0, 95.0, 112.0, 94.0, 94.0, 95.0, 121.0, 95.0, 95.0, 117.0], "stanford-crypto-aes": [96.0, 100.0, 96.0, 97.0, 104.0, 100.0, 100.0, 97.0, 99.0, 100.0], "imaging-desaturate": [92.0, 91.0, 91.0, 93.0, 93.0, 93.0, 92.0, 91.0, 93.0, 92.0]}, "summary": {"suite": 1586.3, "subtests": {"audio-fft": {"filtered": 87.4, "value": 87.4}, "stanford-crypto-pbkdf2": {"filtered": 191.5, "value": 191.5}, "audio-beat-detection": {"filtered": 138.3, "value": 138.3}, "stanford-crypto-ccm": {"filtered": 123.0, "value": 123.0}, "imaging-darkroom": {"filtered": 139.5, "value": 139.5}, "json-parse-financial": {"filtered": 79.8, "value": 79.8}, "audio-oscillator": {"filtered": 102.1, "value": 102.1}, "ai-astar": {"filtered": 95.4, "value": 95.4}, "audio-dft": {"filtered": 158.0, "value": 158.0}, "stanford-crypto-sha256-iterative": {"filtered": 84.3, "value": 84.3}, "json-stringify-tinderbox": {"filtered": 78.7, "value": 78.7}, "imaging-gaussian-blur": {"filtered": 117.3, "value": 117.3}, "stanford-crypto-aes": {"filtered": 98.9, "value": 98.9}, "imaging-desaturate": {"filtered": 92.1, "value": 92.1}}}, "test_machine": {"platform": "x86", "osversion": "6.1.7601", "os": "win", "name": "T-W732-IX-149"}, "testrun": {"date": 1446004188, "suite": "kraken", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "44.0a1", "id": "20151027040234", "branch": "Ash", "revision": "a759c44ddb56eac3fd2e6cd23310e8f89be4ec18"}}, {"talos_counters": {}, "results": {"DeltaBlue": [204.66639377814164], "Encrypt": [295.5082742316785], "NavierStokes": [5112.244897959184], "Decrypt": [5324.468085106383], "Splay": [750.7507507507507], "Richards": [154.03573629081947], "RegExp": [29588.235294117647], "Earley": [639.386189258312], "Boyer": [10468.75], "RayTrace": [1416.4305949008499]}, "summary": {"suite": 20267.61342547481, "subtests": {"DeltaBlue": {"filtered": 323.052548, "value": 323.052548}, "Encrypt": {"filtered": 900.7565040000001, "value": 900.7565040000001}, "NavierStokes": {"filtered": 290.28343313373273, "value": 290.28343313373273}, "Decrypt": {"filtered": 49.99203596403597, "value": 49.99203596403597}, "Splay": {"filtered": 108.54601199999996, "value": 108.54601199999996}, "RegExp": {"filtered": 30.78875745526839, "value": 30.78875745526839}, "Richards": {"filtered": 229.18058399999993, "value": 229.18058399999993}, "Earley": {"filtered": 1042.3481320000003, "value": 1042.3481320000003}, "Boyer": {"filtered": 63.66213731343284, "value": 63.66213731343284}, "RayTrace": {"filtered": 522.4322339999999, "value": 522.4322339999999}}}, "test_machine": {"platform": "x86", "osversion": "6.1.7601", "os": "win", "name": "T-W732-IX-149"}, "testrun": {"date": 1446004188, "suite": "v8_7", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "44.0a1", "id": "20151027040234", "branch": "Ash", "revision": "a759c44ddb56eac3fd2e6cd23310e8f89be4ec18"}}] 21:10:09 INFO - RETURN: dromaeo_css: 6474.63 21:10:09 INFO - RETURN: kraken: 1586.30 21:10:09 INFO - RETURN: v8_7: 20267.61 21:10:09 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,140,25]]", "result": "20267.61"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,140,25]]", "result": "1586.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,140,25]]", "result": "6474.63"}}} 21:10:09 INFO - Return code: 0 21:10:09 INFO - # TBPL SUCCESS # 21:10:09 INFO - Running post-action listener: _resource_record_post_action 21:10:09 INFO - Running post-run listener: _resource_record_post_run 21:10:10 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 87906304; Write bytes: 489272832; Read time: 1728450; Write time: 5033090 21:10:10 INFO - install - Wall time: 2s; CPU: 10.0%; Read bytes: 0; Write bytes: 39494144; Read time: 0; Write time: 40850 21:10:10 INFO - run-tests - Wall time: 1224s; CPU: 13.0%; Read bytes: 87906304; Write bytes: 429580800; Read time: 1728450; Write time: 4415060 21:10:10 INFO - Running post-run listener: _upload_blobber_files 21:10:10 INFO - Blob upload gear active. 21:10:10 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 21:10:10 INFO - Copying logs to upload dir... 21:10:10 INFO - mkdir: C:\slave\test-pgo\build\upload\logs program finished with exit code 0 elapsedTime=1501.713000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 37 secs) (at 2015-10-27 21:10:46.418134) ========= ========= Started set props: build_url (results: 0, elapsed: 1 secs) (at 2015-10-27 21:10:46.418976) ========= '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-149 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-149 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-149 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/Fd9qL3NXSi-umYR3XfduQA/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/Fd9qL3NXSi-umYR3XfduQA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 1 secs) (at 2015-10-27 21:10:47.446419) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-27 21:10:47.446792) ========= '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-149 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-149 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-149 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-27 21:10:47.827340) ========= ========= Started reboot slave lost (results: 0, elapsed: 24 secs) (at 2015-10-27 21:10:47.827654) ========= ========= Finished reboot slave lost (results: 0, elapsed: 24 secs) (at 2015-10-27 21:11:12.340102) =========