builder: mozilla-central_win7-ix_test-dromaeojs-pgo slave: t-w732-ix-118 starttime: 1445886875.57 results: success (0) buildid: 20151026073252 builduid: 929939baafa144d6a3b26d16d30ff838 revision: 28068d907290d1f5138a0b9e59ae2233a1c1b7a3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-26 12:14:35.573482) ========= master: http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-26 12:14:35.575345) ========= ========= Started set props: basedir (results: 0, elapsed: 4 secs) (at 2015-10-26 12:14:35.575646) ========= '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-118 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-118 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-118 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.579000 basedir: 'C:\\slave\\test-pgo' ========= Finished set props: basedir (results: 0, elapsed: 4 secs) (at 2015-10-26 12:14:39.647854) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2015-10-26 12:14:39.648134) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2015-10-26 12:14:44.069608) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2015-10-26 12:14:44.069870) ========= '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-118 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-118 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-118 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: 9 secs) (at 2015-10-26 12:14:54.043313) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-26 12:14:54.043585) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-26 12:14:54.043924) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 9 secs) (at 2015-10-26 12:14:54.046270) ========= '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-118 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-118 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-118 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 --12:15:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 2.45 GB/s 12:15:03 (2.45 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.130000 ========= Finished 'bash -c ...' (results: 0, elapsed: 9 secs) (at 2015-10-26 12:15:03.597302) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 22 secs) (at 2015-10-26 12:15:03.597578) ========= '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-118 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-118 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-118 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.705000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 22 secs) (at 2015-10-26 12:15:25.912063) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2015-10-26 12:15:25.913146) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev 28068d907290d1f5138a0b9e59ae2233a1c1b7a3 --destination scripts --debug' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 28068d907290d1f5138a0b9e59ae2233a1c1b7a3 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-118 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-118 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-118 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-26 12:15:26,252 truncating revision to first 12 chars 2015-10-26 12:15:26,252 Setting DEBUG logging. 2015-10-26 12:15:26,252 attempt 1/10 2015-10-26 12:15:26,252 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/28068d907290?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-26 12:15:27,967 unpacking tar archive at: mozilla-central-28068d907290/testing/mozharness/ program finished with exit code 0 elapsedTime=2.580000 ========= Finished 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2015-10-26 12:15:39.740460) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 25 secs) (at 2015-10-26 12:15:39.740746) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 25 secs) (at 2015-10-26 12:16:05.731837) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-26 12:16:05.732101) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-26 12:16:05.732496) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 7 secs) (at 2015-10-26 12:16:05.732783) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox' in dir C:\slave\test-pgo\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-118 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-118 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-118 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 12:16:06 INFO - MultiFileLogger online at 20151026 12:16:06 in C:\slave\test-pgo 12:16:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:16:06 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 12:16:07 INFO - {'append_to_log': False, 12:16:07 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 12:16:07 INFO - 'blob_upload_branch': 'Firefox', 12:16:07 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 12:16:07 INFO - 'branch': 'Firefox', 12:16:07 INFO - 'buildbot_json_path': 'buildprops.json', 12:16:07 INFO - 'config_files': ('talos/windows_config.py',), 12:16:07 INFO - 'default_actions': ('clobber', 12:16:07 INFO - 'read-buildbot-config', 12:16:07 INFO - 'download-and-extract', 12:16:07 INFO - 'populate-webroot', 12:16:07 INFO - 'create-virtualenv', 12:16:07 INFO - 'install', 12:16:07 INFO - 'run-tests'), 12:16:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:16:07 INFO - 'download_minidump_stackwalk': True, 12:16:07 INFO - 'download_symbols': 'ondemand', 12:16:07 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 12:16:07 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 12:16:07 INFO - 'hg': 'c:/mozilla-build/hg/hg', 12:16:07 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 12:16:07 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 12:16:07 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 12:16:07 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 12:16:07 INFO - 'C:/mozilla-build/tooltool.py'), 12:16:07 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 12:16:07 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 12:16:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:16:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:16:07 INFO - 'installer_path': 'installer.exe', 12:16:07 INFO - 'log_level': 'info', 12:16:07 INFO - 'log_name': 'talos', 12:16:07 INFO - 'log_to_console': True, 12:16:07 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 12:16:07 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 12:16:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 12:16:07 INFO - 'opt_config_files': (), 12:16:07 INFO - 'pip_index': False, 12:16:07 INFO - 'sps_profile': False, 12:16:07 INFO - 'sps_profile_interval': 0, 12:16:07 INFO - 'suite': 'dromaeojs', 12:16:07 INFO - 'system_bits': '32', 12:16:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:16:07 INFO - 'title': 'T-W732-IX-118', 12:16:07 INFO - 'use_talos_json': True, 12:16:07 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 12:16:07 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 12:16:07 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 12:16:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:16:07 INFO - 'work_dir': 'build'} 12:16:07 INFO - ##### 12:16:07 INFO - ##### Running clobber step. 12:16:07 INFO - ##### 12:16:07 INFO - Running pre-action listener: _resource_record_pre_action 12:16:07 INFO - Running main action method: clobber 12:16:07 INFO - rmtree: C:\slave\test-pgo\build 12:16:07 INFO - Using _rmtree_windows ... 12:16:07 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 12:17:22 INFO - Running post-action listener: _resource_record_post_action 12:17:22 INFO - ##### 12:17:22 INFO - ##### Running read-buildbot-config step. 12:17:22 INFO - ##### 12:17:22 INFO - Running pre-action listener: _resource_record_pre_action 12:17:22 INFO - Running main action method: read_buildbot_config 12:17:22 INFO - Using buildbot properties: 12:17:22 INFO - { 12:17:22 INFO - "properties": { 12:17:22 INFO - "buildnumber": 13, 12:17:22 INFO - "product": "firefox", 12:17:22 INFO - "script_repo_revision": "production", 12:17:22 INFO - "builddir": "mozilla-central_win7-ix_test-dromaeojs", 12:17:22 INFO - "repository": "", 12:17:22 INFO - "buildername": "Windows 7 32-bit mozilla-central pgo talos dromaeojs", 12:17:22 INFO - "buildid": "20151026073252", 12:17:22 INFO - "slavename": "t-w732-ix-118", 12:17:22 INFO - "pgo_build": "True", 12:17:22 INFO - "basedir": "C:\\slave\\test-pgo", 12:17:22 INFO - "project": "", 12:17:22 INFO - "platform": "win7-ix", 12:17:22 INFO - "master": "http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/", 12:17:22 INFO - "slavebuilddir": "test", 12:17:22 INFO - "scheduler": "tests-mozilla-central-win32-pgo-talos", 12:17:22 INFO - "branch": "mozilla-central", 12:17:22 INFO - "repo_path": "mozilla-central", 12:17:22 INFO - "stage_platform": "win32-pgo", 12:17:22 INFO - "builduid": "929939baafa144d6a3b26d16d30ff838", 12:17:22 INFO - "revision": "28068d907290d1f5138a0b9e59ae2233a1c1b7a3" 12:17:22 INFO - }, 12:17:22 INFO - "sourcestamp": { 12:17:22 INFO - "repository": "", 12:17:22 INFO - "hasPatch": false, 12:17:22 INFO - "project": "", 12:17:22 INFO - "branch": "mozilla-central-win32-pgo-talos", 12:17:22 INFO - "changes": [ 12:17:22 INFO - { 12:17:22 INFO - "category": null, 12:17:22 INFO - "files": [ 12:17:22 INFO - { 12:17:22 INFO - "url": null, 12:17:22 INFO - "name": "https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 12:17:22 INFO - } 12:17:22 INFO - ], 12:17:22 INFO - "repository": "", 12:17:22 INFO - "rev": "28068d907290d1f5138a0b9e59ae2233a1c1b7a3", 12:17:22 INFO - "who": "sendchange", 12:17:22 INFO - "when": 1445886458, 12:17:22 INFO - "number": 6575350, 12:17:22 INFO - "comments": "", 12:17:22 INFO - "project": "", 12:17:22 INFO - "at": "Mon 26 Oct 2015 12:07:38", 12:17:22 INFO - "branch": "mozilla-central-win32-pgo-talos", 12:17:22 INFO - "revlink": "", 12:17:22 INFO - "properties": [ 12:17:22 INFO - [ 12:17:22 INFO - "buildid", 12:17:22 INFO - "20151026073252", 12:17:22 INFO - "Change" 12:17:22 INFO - ], 12:17:22 INFO - [ 12:17:22 INFO - "builduid", 12:17:22 INFO - "929939baafa144d6a3b26d16d30ff838", 12:17:22 INFO - "Change" 12:17:22 INFO - ], 12:17:22 INFO - [ 12:17:22 INFO - "pgo_build", 12:17:22 INFO - "True", 12:17:22 INFO - "Change" 12:17:22 INFO - ] 12:17:22 INFO - ], 12:17:22 INFO - "revision": "28068d907290d1f5138a0b9e59ae2233a1c1b7a3" 12:17:22 INFO - } 12:17:22 INFO - ], 12:17:22 INFO - "revision": "28068d907290d1f5138a0b9e59ae2233a1c1b7a3" 12:17:22 INFO - } 12:17:22 INFO - } 12:17:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 12:17:22 INFO - Running post-action listener: _resource_record_post_action 12:17:22 INFO - ##### 12:17:22 INFO - ##### Running download-and-extract step. 12:17:22 INFO - ##### 12:17:22 INFO - Running pre-action listener: _resource_record_pre_action 12:17:22 INFO - Running main action method: download_and_extract 12:17:22 INFO - mkdir: C:\slave\test-pgo\build\tests 12:17:22 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')]} 12:17:22 INFO - https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 12:17:22 INFO - trying https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/test_packages.json 12:17:22 INFO - Downloading https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 12:17:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 12:17:24 INFO - Downloaded 1221 bytes. 12:17:24 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 12:17:24 INFO - Using the following test package requirements: 12:17:24 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 12:17:24 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 12:17:24 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 12:17:24 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 12:17:24 INFO - u'jsshell-win32.zip'], 12:17:24 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 12:17:24 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 12:17:24 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 12:17:24 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 12:17:24 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 12:17:24 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 12:17:24 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 12:17:24 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 12:17:24 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 12:17:24 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 12:17:24 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 12:17:24 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 12:17:24 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 12:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:24 INFO - https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 12:17:24 INFO - trying https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 12:17:24 INFO - Downloading https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/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 12:17:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/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 12:17:25 INFO - Downloaded 18016575 bytes. 12:17:25 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 12:17:25 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 12:17:25 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 12:17:59 INFO - Return code: 0 12:17:59 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 12:17:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:59 INFO - https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 12:17:59 INFO - trying https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 12:17:59 INFO - Downloading https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/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 12:17:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/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 12:18:00 INFO - Downloaded 18016575 bytes. 12:18:00 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] in C:\slave\test-pgo\build\tests 12:18:00 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 12:18:00 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 12:18:24 INFO - Return code: 0 12:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:18:24 INFO - https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 12:18:24 INFO - trying https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 12:18:24 INFO - Downloading https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/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 12:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/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 12:18:33 INFO - Downloaded 11131307 bytes. 12:18:33 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 12:18:33 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip 12:18:33 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 12:18:34 INFO - Return code: 0 12:18:34 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')]} 12:18:34 INFO - https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 12:18:34 INFO - trying https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 12:18:34 INFO - Downloading https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip 12:18:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/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 12:18:51 INFO - Downloaded 53387573 bytes. 12:18:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 12:18:51 INFO - mkdir: C:\slave\test-pgo\properties 12:18:51 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 12:18:51 INFO - Writing to file C:\slave\test-pgo\properties\build_url 12:18:51 INFO - Contents: 12:18:51 INFO - build_url:https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 12:18:51 INFO - Running post-action listener: _resource_record_post_action 12:18:51 INFO - Running post-action listener: set_extra_try_arguments 12:18:51 INFO - ##### 12:18:51 INFO - ##### Running populate-webroot step. 12:18:51 INFO - ##### 12:18:51 INFO - Running pre-action listener: _resource_record_pre_action 12:18:51 INFO - Running main action method: populate_webroot 12:18:51 INFO - Guessing talos json url... 12:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:18:51 INFO - https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 12:18:51 INFO - trying https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 12:18:51 INFO - Downloading https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 12:18:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/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 12:18:53 INFO - Downloaded 99 bytes. 12:18:53 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 12:18:53 INFO - Contents: 12:18:53 INFO - 20151026073252 12:18:53 INFO - https://hg.mozilla.org/mozilla-central/rev/28068d907290d1f5138a0b9e59ae2233a1c1b7a3 12:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:18:53 INFO - trying https://hg.mozilla.org/mozilla-central/raw-file/28068d9072/testing/talos/talos.json 12:18:53 INFO - Downloading https://hg.mozilla.org/mozilla-central/raw-file/28068d9072/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json 12:18:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/mozilla-central/raw-file/28068d9072/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1 12:18:57 INFO - Downloaded 4264 bytes. 12:18:57 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 12:18:57 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 12:18:57 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 12:18:57 INFO - u'../fennec_ids.txt'], 12:18:57 INFO - u'tests': [u'tcheck2']}, 12:18:57 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 12:18:57 INFO - u'--tppagecycles', 12:18:57 INFO - u'7'], 12:18:57 INFO - u'tests': [u'tsvgm']}}, 12:18:57 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 12:18:57 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 12:18:57 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 12:18:57 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 12:18:57 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 12:18:57 INFO - u'tests': [u'dromaeo_css', 12:18:57 INFO - u'kraken', 12:18:57 INFO - u'v8_7']}, 12:18:57 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:18:57 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 12:18:57 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:18:57 INFO - u'talos_options': [u'--e10s'], 12:18:57 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 12:18:57 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:18:57 INFO - u'tests': [u'damp', u'tps']}, 12:18:57 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:18:57 INFO - u'talos_options': [u'--e10s'], 12:18:57 INFO - u'tests': [u'damp', u'tps']}, 12:18:57 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 12:18:57 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 12:18:57 INFO - u'other': {u'tests': [u'a11yr', 12:18:57 INFO - u'ts_paint', 12:18:57 INFO - u'tpaint', 12:18:57 INFO - u'sessionrestore', 12:18:57 INFO - u'sessionrestore_no_auto_restore']}, 12:18:57 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 12:18:57 INFO - u'tests': [u'a11yr', 12:18:57 INFO - u'ts_paint', 12:18:57 INFO - u'tpaint', 12:18:57 INFO - u'sessionrestore', 12:18:57 INFO - u'sessionrestore_no_auto_restore']}, 12:18:57 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 12:18:57 INFO - u'tests': [u'a11yr', 12:18:57 INFO - u'ts_paint', 12:18:57 INFO - u'tpaint', 12:18:57 INFO - u'sessionrestore', 12:18:57 INFO - u'sessionrestore_no_auto_restore']}, 12:18:57 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 12:18:57 INFO - u'tests': [u'a11yr', 12:18:57 INFO - u'ts_paint', 12:18:57 INFO - u'tpaint', 12:18:57 INFO - u'sessionrestore', 12:18:57 INFO - u'sessionrestore_no_auto_restore']}, 12:18:57 INFO - u'other_l64': {u'tests': [u'a11yr', 12:18:57 INFO - u'ts_paint', 12:18:57 INFO - u'tpaint', 12:18:57 INFO - u'sessionrestore', 12:18:57 INFO - u'sessionrestore_no_auto_restore']}, 12:18:57 INFO - u'other_nol64': {u'tests': [u'a11yr', 12:18:57 INFO - u'ts_paint', 12:18:57 INFO - u'tpaint', 12:18:57 INFO - u'sessionrestore', 12:18:57 INFO - u'sessionrestore_no_auto_restore']}, 12:18:57 INFO - u'svgr': {u'tests': [u'tsvgx', 12:18:57 INFO - u'tsvgr_opacity', 12:18:57 INFO - u'tart', 12:18:57 INFO - u'tscrollx', 12:18:57 INFO - u'cart']}, 12:18:57 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 12:18:57 INFO - u'tests': [u'tsvgx', 12:18:57 INFO - u'tsvgr_opacity', 12:18:57 INFO - u'tart', 12:18:57 INFO - u'tscrollx', 12:18:57 INFO - u'cart']}, 12:18:57 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:18:57 INFO - u'tests': [u'tp5o']}, 12:18:57 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:18:57 INFO - u'talos_options': [u'--e10s'], 12:18:57 INFO - u'tests': [u'tp5o']}, 12:18:57 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:18:57 INFO - u'talos_options': [u'--xperf_path', 12:18:57 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:18:57 INFO - u'tests': [u'tp5n']}, 12:18:57 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:18:57 INFO - u'talos_options': [u'--e10s', 12:18:57 INFO - u'--xperf_path', 12:18:57 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 12:18:57 INFO - u'C:/slave/talos-data/talos/xperf.config'], 12:18:57 INFO - u'tests': [u'tp5n']}}, 12:18:57 INFO - u'talos.zip': {u'path': u'', 12:18:57 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:18:57 INFO - Running post-action listener: _resource_record_post_action 12:18:57 INFO - ##### 12:18:57 INFO - ##### Running create-virtualenv step. 12:18:57 INFO - ##### 12:18:57 INFO - Running pre-action listener: _resource_record_pre_action 12:18:57 INFO - Running main action method: create_virtualenv 12:18:57 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 12:18:57 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 12:18:57 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 12:18:57 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'! 12:18:57 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 12:18:57 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 12:19:00 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 12:19:05 INFO - Installing distribute......................................................................................................................................................................................done. 12:19:07 INFO - Return code: 0 12:19:07 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 12:19: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')]} 12:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19: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')]} 12:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19: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 0x01938B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x013DE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012F8B48>, '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 0x0131A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019B9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-118', 'USERDOMAIN': 'T-W732-IX-118', '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-118', '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 12:19: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 12:19: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 12:19:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:19:07 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:19:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:19:07 INFO - 'COMPUTERNAME': 'T-W732-IX-118', 12:19:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:19:07 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:19:07 INFO - 'DCLOCATION': 'SCL3', 12:19:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:19:07 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:19:07 INFO - 'HOMEDRIVE': 'C:', 12:19:07 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:19:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:19:07 INFO - 'KTS_VERSION': '1.19c', 12:19:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:19:07 INFO - 'LOGONSERVER': '\\\\T-W732-IX-118', 12:19:07 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:19:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:07 INFO - 'MOZ_NO_REMOTE': '1', 12:19:07 INFO - 'NO_EM_RESTART': '1', 12:19:07 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:19:07 INFO - 'OS': 'Windows_NT', 12:19:07 INFO - 'OURDRIVE': 'C:', 12:19: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;', 12:19:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:19:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:19:07 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:19:07 INFO - 'PROCESSOR_LEVEL': '6', 12:19:07 INFO - 'PROCESSOR_REVISION': '1e05', 12:19:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:19:07 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:19:07 INFO - 'PROMPT': '$P$G', 12:19:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:19:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:19:07 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:19:07 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:19:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:19:07 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:19:07 INFO - 'SYSTEMDRIVE': 'C:', 12:19:07 INFO - 'SYSTEMROOT': 'C:\\windows', 12:19:07 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:19:07 INFO - 'TEST1': 'testie', 12:19:07 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:19:07 INFO - 'USERDOMAIN': 'T-W732-IX-118', 12:19:07 INFO - 'USERNAME': 'cltbld', 12:19:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:19:07 INFO - 'WINDIR': 'C:\\windows', 12:19:07 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:19:07 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:19:09 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:19:09 INFO - Downloading/unpacking pip>=1.5 12:19:09 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache 12:19:13 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pub.build.mozilla.org%2fpub%2fpip-1.5.5.tar.gz 12:19:13 INFO - Running setup.py egg_info for package pip 12:19:13 INFO - warning: no files found matching 'pip\cacert.pem' 12:19:13 INFO - warning: no files found matching '*.html' under directory 'docs' 12:19:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 12:19:13 INFO - no previously-included directories found matching 'docs\_build\_sources' 12:19:13 INFO - Installing collected packages: pip 12:19:13 INFO - Found existing installation: pip 0.8.2 12:19:13 INFO - Uninstalling pip: 12:19:13 INFO - Successfully uninstalled pip 12:19:13 INFO - Running setup.py install for pip 12:19:13 INFO - warning: no files found matching 'pip\cacert.pem' 12:19:13 INFO - warning: no files found matching '*.html' under directory 'docs' 12:19:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 12:19:13 INFO - no previously-included directories found matching 'docs\_build\_sources' 12:19:13 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:13 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:13 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:13 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:13 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:13 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:13 INFO - Successfully installed pip 12:19:13 INFO - Cleaning up... 12:19:13 INFO - Return code: 0 12:19:13 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 12:19: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')]} 12:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19: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')]} 12:19:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19: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 0x01938B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x013DE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012F8B48>, '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 0x0131A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019B9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-118', 'USERDOMAIN': 'T-W732-IX-118', '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-118', '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 12:19: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 12:19: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 12:19:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:19:13 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:19:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:19:13 INFO - 'COMPUTERNAME': 'T-W732-IX-118', 12:19:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:19:13 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:19:13 INFO - 'DCLOCATION': 'SCL3', 12:19:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:19:13 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:19:13 INFO - 'HOMEDRIVE': 'C:', 12:19:13 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:19:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:19:13 INFO - 'KTS_VERSION': '1.19c', 12:19:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:19:13 INFO - 'LOGONSERVER': '\\\\T-W732-IX-118', 12:19:13 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:19:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:19:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:13 INFO - 'MOZ_NO_REMOTE': '1', 12:19:13 INFO - 'NO_EM_RESTART': '1', 12:19:13 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:19:13 INFO - 'OS': 'Windows_NT', 12:19:13 INFO - 'OURDRIVE': 'C:', 12:19: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;', 12:19:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:19:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:19:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:19:13 INFO - 'PROCESSOR_LEVEL': '6', 12:19:13 INFO - 'PROCESSOR_REVISION': '1e05', 12:19:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:19:13 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:19:13 INFO - 'PROMPT': '$P$G', 12:19:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:19:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:19:13 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:19:13 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:19:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:19:13 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:19:13 INFO - 'SYSTEMDRIVE': 'C:', 12:19:13 INFO - 'SYSTEMROOT': 'C:\\windows', 12:19:13 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:19:13 INFO - 'TEST1': 'testie', 12:19:13 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:19:13 INFO - 'USERDOMAIN': 'T-W732-IX-118', 12:19:13 INFO - 'USERNAME': 'cltbld', 12:19:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:19:13 INFO - 'WINDIR': 'C:\\windows', 12:19:13 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:19:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:19:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:19:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:15 INFO - Downloading/unpacking psutil>=0.7.1 12:19: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 12:19: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 12:19: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 12:19:16 INFO - Installing collected packages: psutil 12:19:16 INFO - Successfully installed psutil 12:19:16 INFO - Cleaning up... 12:19:17 INFO - Return code: 0 12:19:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 12:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:17 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01938B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x013DE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012F8B48>, '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 0x0131A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019B9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-118', 'USERDOMAIN': 'T-W732-IX-118', '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-118', '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 12:19: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 12:19: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 12:19:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:19:17 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:19:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:19:17 INFO - 'COMPUTERNAME': 'T-W732-IX-118', 12:19:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:19:17 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:19:17 INFO - 'DCLOCATION': 'SCL3', 12:19:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:19:17 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:19:17 INFO - 'HOMEDRIVE': 'C:', 12:19:17 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:19:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:19:17 INFO - 'KTS_VERSION': '1.19c', 12:19:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:19:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-118', 12:19:17 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:19:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:17 INFO - 'MOZ_NO_REMOTE': '1', 12:19:17 INFO - 'NO_EM_RESTART': '1', 12:19:17 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:19:17 INFO - 'OS': 'Windows_NT', 12:19:17 INFO - 'OURDRIVE': 'C:', 12:19: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;', 12:19:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:19:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:19:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:19:17 INFO - 'PROCESSOR_LEVEL': '6', 12:19:17 INFO - 'PROCESSOR_REVISION': '1e05', 12:19:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:19:17 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:19:17 INFO - 'PROMPT': '$P$G', 12:19:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:19:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:19:17 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:19:17 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:19:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:19:17 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:19:17 INFO - 'SYSTEMDRIVE': 'C:', 12:19:17 INFO - 'SYSTEMROOT': 'C:\\windows', 12:19:17 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:19:17 INFO - 'TEST1': 'testie', 12:19:17 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:19:17 INFO - 'USERDOMAIN': 'T-W732-IX-118', 12:19:17 INFO - 'USERNAME': 'cltbld', 12:19:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:19:17 INFO - 'WINDIR': 'C:\\windows', 12:19:17 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:19:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:19: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 12:19: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 12:19:19 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:19:19 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozsystemmonitor-0.0.tar.gz 12:19:19 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 12:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0) 12:19:19 INFO - Installing collected packages: mozsystemmonitor 12:19:19 INFO - Running setup.py install for mozsystemmonitor 12:19:19 INFO - Successfully installed mozsystemmonitor 12:19:19 INFO - Cleaning up... 12:19:20 INFO - Return code: 0 12:19:20 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 12:19:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:20 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01938B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x013DE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012F8B48>, '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 0x0131A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019B9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-118', 'USERDOMAIN': 'T-W732-IX-118', '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-118', '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 12:19:20 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test-pgo\build 12:19:20 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:19:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:19:20 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:19:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:19:20 INFO - 'COMPUTERNAME': 'T-W732-IX-118', 12:19:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:19:20 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:19:20 INFO - 'DCLOCATION': 'SCL3', 12:19:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:19:20 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:19:20 INFO - 'HOMEDRIVE': 'C:', 12:19:20 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:19:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:19:20 INFO - 'KTS_VERSION': '1.19c', 12:19:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:19:20 INFO - 'LOGONSERVER': '\\\\T-W732-IX-118', 12:19:20 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:19:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:20 INFO - 'MOZ_NO_REMOTE': '1', 12:19:20 INFO - 'NO_EM_RESTART': '1', 12:19:20 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:19:20 INFO - 'OS': 'Windows_NT', 12:19:20 INFO - 'OURDRIVE': 'C:', 12:19:20 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 12:19:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:19:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:19:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:19:20 INFO - 'PROCESSOR_LEVEL': '6', 12:19:20 INFO - 'PROCESSOR_REVISION': '1e05', 12:19:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:19:20 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:19:20 INFO - 'PROMPT': '$P$G', 12:19:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:19:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:19:20 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:19:20 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:19:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:19:20 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:19:20 INFO - 'SYSTEMDRIVE': 'C:', 12:19:20 INFO - 'SYSTEMROOT': 'C:\\windows', 12:19:20 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:19:20 INFO - 'TEST1': 'testie', 12:19:20 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:19:20 INFO - 'USERDOMAIN': 'T-W732-IX-118', 12:19:20 INFO - 'USERNAME': 'cltbld', 12:19:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:19:20 INFO - 'WINDIR': 'C:\\windows', 12:19:20 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:19:20 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:19:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:20 INFO - Downloading/unpacking blobuploader==1.2.4 12:19: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 12:19: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 12:19:22 INFO - Downloading blobuploader-1.2.4.tar.gz 12:19:22 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblobuploader-1.2.4.tar.gz 12:19:22 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 12:19:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:23 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 12:19:23 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 12:19:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:19:23 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 12:19:23 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 12:19:23 INFO - Downloading docopt-0.6.1.tar.gz 12:19:23 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 12:19:23 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 12:19:23 INFO - Installing collected packages: blobuploader, requests, docopt 12:19:23 INFO - Running setup.py install for blobuploader 12:19:24 INFO - Running setup.py install for requests 12:19:24 INFO - Running setup.py install for docopt 12:19:25 INFO - Successfully installed blobuploader requests docopt 12:19:25 INFO - Cleaning up... 12:19:26 INFO - Return code: 0 12:19:26 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 12:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:26 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 0x01938B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x013DE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012F8B48>, '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 0x0131A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019B9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-118', 'USERDOMAIN': 'T-W732-IX-118', '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-118', '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 12:19:26 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 12:19:26 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 12:19:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:19:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:19:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:19:26 INFO - 'COMPUTERNAME': 'T-W732-IX-118', 12:19:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:19:26 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:19:26 INFO - 'DCLOCATION': 'SCL3', 12:19:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:19:26 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:19:26 INFO - 'HOMEDRIVE': 'C:', 12:19:26 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:19:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:19:26 INFO - 'KTS_VERSION': '1.19c', 12:19:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:19:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-118', 12:19:26 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:19:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:26 INFO - 'MOZ_NO_REMOTE': '1', 12:19:26 INFO - 'NO_EM_RESTART': '1', 12:19:26 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:19:26 INFO - 'OS': 'Windows_NT', 12:19:26 INFO - 'OURDRIVE': 'C:', 12:19:26 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;', 12:19:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:19:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:19:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:19:26 INFO - 'PROCESSOR_LEVEL': '6', 12:19:26 INFO - 'PROCESSOR_REVISION': '1e05', 12:19:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:19:26 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:19:26 INFO - 'PROMPT': '$P$G', 12:19:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:19:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:19:26 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:19:26 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:19:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:19:26 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:19:26 INFO - 'SYSTEMDRIVE': 'C:', 12:19:26 INFO - 'SYSTEMROOT': 'C:\\windows', 12:19:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:19:26 INFO - 'TEST1': 'testie', 12:19:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:19:26 INFO - 'USERDOMAIN': 'T-W732-IX-118', 12:19:26 INFO - 'USERNAME': 'cltbld', 12:19:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:19:26 INFO - 'WINDIR': 'C:\\windows', 12:19:26 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:19:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 12:19:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6xltjw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 12:19:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 12:19:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6ipcgj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 12:19:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 12:19:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f4hyrl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 12:19:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 12:19:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ombpuq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 12:19:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 12:19:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oxn4b6-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 12:19:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 12:19:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_7tmxj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 12:19:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 12:19:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kw1tdr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 12:19:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 12:19:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-spc6av-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 12:19:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 12:19:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wyqqiz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 12:19:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 12:19:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-84e_mp-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 12:19:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 12:19:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hm1gg7-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 12:19:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 12:19:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yvffrd-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 12:19:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 12:19:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5_fgig-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 12:19:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 12:19:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-os48bo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 12:19:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 12:19:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4p5fw1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 12:19:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 12:19:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-olny54-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 12:19:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 12:19:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rcwiqc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 12:19:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:19:28 INFO - Running setup.py install for manifestparser 12:19:28 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:28 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:29 INFO - Running setup.py install for mozcrash 12:19:29 INFO - Running setup.py install for mozdebug 12:19:29 INFO - Running setup.py install for mozdevice 12:19:30 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:30 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:30 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:30 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:30 INFO - Running setup.py install for mozfile 12:19:30 INFO - Running setup.py install for mozhttpd 12:19:31 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:31 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:31 INFO - Running setup.py install for mozinfo 12:19:31 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:31 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:31 INFO - Running setup.py install for mozInstall 12:19:31 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:31 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:31 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:31 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:31 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:31 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:31 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:31 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:32 INFO - Running setup.py install for mozleak 12:19:32 INFO - Running setup.py install for mozlog 12:19:32 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:32 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:33 INFO - Running setup.py install for moznetwork 12:19:33 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:33 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:33 INFO - Running setup.py install for mozprocess 12:19:34 INFO - Running setup.py install for mozprofile 12:19:34 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:34 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:34 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:34 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:34 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:34 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:34 INFO - Running setup.py install for mozrunner 12:19:35 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:35 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:35 INFO - Running setup.py install for mozscreenshot 12:19:35 INFO - Running setup.py install for moztest 12:19:36 INFO - Running setup.py install for mozversion 12:19:36 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:19:36 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 12:19:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:19:36 INFO - Cleaning up... 12:19:36 INFO - Return code: 0 12:19:36 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 12:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:36 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01938B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x013DE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012F8B48>, '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 0x0131A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019B9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-118', 'USERDOMAIN': 'T-W732-IX-118', '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-118', '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 12:19:36 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config 12:19:36 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:19:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:19:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:19:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:19:36 INFO - 'COMPUTERNAME': 'T-W732-IX-118', 12:19:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:19:36 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:19:36 INFO - 'DCLOCATION': 'SCL3', 12:19:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:19:36 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:19:36 INFO - 'HOMEDRIVE': 'C:', 12:19:36 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:19:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:19:36 INFO - 'KTS_VERSION': '1.19c', 12:19:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:19:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-118', 12:19:36 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:19:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:19:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:36 INFO - 'MOZ_NO_REMOTE': '1', 12:19:36 INFO - 'NO_EM_RESTART': '1', 12:19:36 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:19:36 INFO - 'OS': 'Windows_NT', 12:19:36 INFO - 'OURDRIVE': 'C:', 12:19:36 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 12:19:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:19:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:19:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:19:36 INFO - 'PROCESSOR_LEVEL': '6', 12:19:36 INFO - 'PROCESSOR_REVISION': '1e05', 12:19:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:19:36 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:19:36 INFO - 'PROMPT': '$P$G', 12:19:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:19:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:19:36 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:19:36 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:19:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:19:36 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:19:36 INFO - 'SYSTEMDRIVE': 'C:', 12:19:36 INFO - 'SYSTEMROOT': 'C:\\windows', 12:19:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:19:36 INFO - 'TEST1': 'testie', 12:19:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:19:36 INFO - 'USERDOMAIN': 'T-W732-IX-118', 12:19:36 INFO - 'USERNAME': 'cltbld', 12:19:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:19:36 INFO - 'WINDIR': 'C:\\windows', 12:19:36 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:19:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:19:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:19:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 12:19:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bulfby-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 12:19:37 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 1)) 12:19:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 12:19:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mtg92l-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 12:19:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 12:19:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 12:19:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fhte79-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 12:19:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 12:19:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 12:19:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ayko5i-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 12:19:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 12:19:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 12:19:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9k9ai3-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 12:19:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 5)) 12:19:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 12:19:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zul_oo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 12:19:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 6)) 12:19:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 12:19:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eywyfm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 12:19:38 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)) 12:19:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 12:19:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uo0v7s-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 12:19:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 8)) 12:19:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 12:19:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ab6yaz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 12:19:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 9)) 12:19:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 12:19:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mloosw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 12:19:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 12:19:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 12:19:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1fge3n-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 12:19:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 11)) 12:19:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 12:19:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uzn8lg-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 12:19:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 12)) 12:19:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 12:19:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zd9sdt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 12:19:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 13)) 12:19:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 12:19:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o3hnqu-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 12:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14)) 12:19:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 12:19:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-m09wek-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 12:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 15)) 12:19:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 12:19:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kg4b5w-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 12:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/moztest in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 16)) 12:19:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 12:19:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5d1vby-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 12:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 17)) 12:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 12:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 12:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 12:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 12:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 12:19:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 12:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:41 INFO - Downloading blessings-1.5.1.tar.gz 12:19:41 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 12:19:41 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 12:19:41 INFO - Installing collected packages: blessings 12:19:41 INFO - Running setup.py install for blessings 12:19:41 INFO - Successfully installed blessings 12:19:41 INFO - Cleaning up... 12:19:44 INFO - Return code: 0 12:19:44 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 12:19:44 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 12:19:44 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 12:19:44 INFO - Reading from file tmpfile_stdout 12:19:44 INFO - Using _rmtree_windows ... 12:19:44 INFO - Using _rmtree_windows ... 12:19:44 INFO - Current package versions: 12:19:44 INFO - blessings == 1.5.1 12:19:44 INFO - blobuploader == 1.2.4 12:19:44 INFO - docopt == 0.6.1 12:19:44 INFO - manifestparser == 1.1 12:19:44 INFO - mozInstall == 1.12 12:19:44 INFO - mozcrash == 0.16 12:19:44 INFO - mozdebug == 0.1 12:19:44 INFO - mozdevice == 0.46 12:19:44 INFO - mozfile == 1.2 12:19:44 INFO - mozhttpd == 0.7 12:19:44 INFO - mozinfo == 0.8 12:19:44 INFO - mozleak == 0.1 12:19:44 INFO - mozlog == 3.0 12:19:44 INFO - moznetwork == 0.27 12:19:44 INFO - mozprocess == 0.22 12:19:44 INFO - mozprofile == 0.27 12:19:44 INFO - mozrunner == 6.11 12:19:44 INFO - mozscreenshot == 0.1 12:19:44 INFO - mozsystemmonitor == 0.0 12:19:44 INFO - moztest == 0.7 12:19:44 INFO - mozversion == 1.4 12:19:44 INFO - psutil == 3.1.1 12:19:44 INFO - requests == 1.2.3 12:19:44 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 12:19:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:44 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01938B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x013DE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012F8B48>, '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 0x0131A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019B9660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-118', 'USERDOMAIN': 'T-W732-IX-118', '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-118', '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 12:19:44 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\talos 12:19:44 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:19:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:19:44 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:19:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:19:44 INFO - 'COMPUTERNAME': 'T-W732-IX-118', 12:19:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:19:44 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:19:44 INFO - 'DCLOCATION': 'SCL3', 12:19:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:19:44 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:19:44 INFO - 'HOMEDRIVE': 'C:', 12:19:44 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:19:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:19:44 INFO - 'KTS_VERSION': '1.19c', 12:19:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:19:44 INFO - 'LOGONSERVER': '\\\\T-W732-IX-118', 12:19:44 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:19:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:19:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:44 INFO - 'MOZ_NO_REMOTE': '1', 12:19:44 INFO - 'NO_EM_RESTART': '1', 12:19:44 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:19:44 INFO - 'OS': 'Windows_NT', 12:19:44 INFO - 'OURDRIVE': 'C:', 12:19:44 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 12:19:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:19:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:19:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:19:44 INFO - 'PROCESSOR_LEVEL': '6', 12:19:44 INFO - 'PROCESSOR_REVISION': '1e05', 12:19:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:19:44 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:19:44 INFO - 'PROMPT': '$P$G', 12:19:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:19:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:19:44 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:19:44 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:19:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:19:44 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:19:44 INFO - 'SYSTEMDRIVE': 'C:', 12:19:44 INFO - 'SYSTEMROOT': 'C:\\windows', 12:19:44 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:19:44 INFO - 'TEST1': 'testie', 12:19:44 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:19:44 INFO - 'USERDOMAIN': 'T-W732-IX-118', 12:19:44 INFO - 'USERNAME': 'cltbld', 12:19:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:19:44 INFO - 'WINDIR': 'C:\\windows', 12:19:44 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:19:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:19:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:19:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:45 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)) 12:19:45 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)) 12:19:45 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)) 12:19:45 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)) 12:19:45 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)) 12:19:45 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)) 12:19:45 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)) 12:19:45 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)) 12:19:45 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)) 12:19:45 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)) 12:19:45 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)) 12:19:45 INFO - Cleaning up... 12:19:45 INFO - Return code: 0 12:19:45 INFO - Running post-action listener: _resource_record_post_action 12:19:45 INFO - Running post-action listener: _start_resource_monitoring 12:19:45 INFO - Starting resource monitoring. 12:19:45 INFO - ##### 12:19:45 INFO - ##### Running install step. 12:19:45 INFO - ##### 12:19:45 INFO - Running pre-action listener: _resource_record_pre_action 12:19:45 INFO - Running main action method: install 12:19:45 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 12:19:45 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 12:19:45 INFO - Reading from file tmpfile_stdout 12:19:45 INFO - Using _rmtree_windows ... 12:19:45 INFO - Using _rmtree_windows ... 12:19:45 INFO - Detecting whether we're running mozinstall >=1.0... 12:19:45 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 12:19:45 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 12:19:45 INFO - Reading from file tmpfile_stdout 12:19:45 INFO - Output received: 12:19:45 INFO - Usage: mozinstall-script.py [options] installer 12:19:45 INFO - Options: 12:19:45 INFO - -h, --help show this help message and exit 12:19:45 INFO - -d DEST, --destination=DEST 12:19:45 INFO - Directory to install application into. [default: 12:19:45 INFO - "C:\slave\test-pgo"] 12:19:45 INFO - --app=APP Application being installed. [default: firefox] 12:19:45 INFO - Using _rmtree_windows ... 12:19:45 INFO - Using _rmtree_windows ... 12:19:45 INFO - mkdir: C:\slave\test-pgo\build\application 12:19:45 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test-pgo\\build\\application'] 12:19:45 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip --destination C:\slave\test-pgo\build\application 12:19:46 INFO - Reading from file tmpfile_stdout 12:19:46 INFO - Output received: 12:19:46 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 12:19:46 INFO - Using _rmtree_windows ... 12:19:46 INFO - Using _rmtree_windows ... 12:19:46 INFO - Running post-action listener: _resource_record_post_action 12:19:46 INFO - ##### 12:19:46 INFO - ##### Running run-tests step. 12:19:46 INFO - ##### 12:19:46 INFO - Running pre-action listener: _resource_record_pre_action 12:19:46 INFO - Running main action method: run_tests 12:19:46 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 12:19:46 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 12:19:46 INFO - Python 2.7.3 12:19:46 INFO - Return code: 0 12:19:46 INFO - grabbing minidump binary from tooltool 12:19: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')]} 12:19:46 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012F8B48>, '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 0x0131A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019B9660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 12:19:46 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test-pgo\build 12:19:46 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test-pgo\build\tests\config/tooltool-manifests/win32/releng.manifest -o 12:19:47 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 12:19:49 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmptjvty1 12:19:49 INFO - INFO - File integrity verified, renaming tmptjvty1 to win32-minidump_stackwalk.exe 12:19:49 INFO - Return code: 0 12:19:49 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 12:19:49 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 12:19:49 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 12:19:49 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 12:19:49 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 12:19:49 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-118', '--webServer', 'localhost'] in C:\slave\test-pgo\build 12:19:49 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName Firefox --suite dromaeojs --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-118 --webServer localhost 12:19:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:19:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:19:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:19:49 INFO - 'COMPUTERNAME': 'T-W732-IX-118', 12:19:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:19:49 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:19:49 INFO - 'DCLOCATION': 'SCL3', 12:19:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:19:49 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:19:49 INFO - 'HOMEDRIVE': 'C:', 12:19:49 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:19:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:19:49 INFO - 'KTS_VERSION': '1.19c', 12:19:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:19:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-118', 12:19:49 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 12:19:49 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 12:19:49 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:19:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:49 INFO - 'MOZ_NO_REMOTE': '1', 12:19:49 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 12:19:49 INFO - 'NO_EM_RESTART': '1', 12:19:49 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:19:49 INFO - 'OS': 'Windows_NT', 12:19:49 INFO - 'OURDRIVE': 'C:', 12:19:49 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;', 12:19:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:19:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:19:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:19:49 INFO - 'PROCESSOR_LEVEL': '6', 12:19:49 INFO - 'PROCESSOR_REVISION': '1e05', 12:19:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:19:49 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:19:50 INFO - 'PROMPT': '$P$G', 12:19:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:19:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:19:50 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:19:50 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:19:50 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 12:19:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:19:50 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:19:50 INFO - 'SYSTEMDRIVE': 'C:', 12:19:50 INFO - 'SYSTEMROOT': 'C:\\windows', 12:19:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:19:50 INFO - 'TEST1': 'testie', 12:19:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:19:50 INFO - 'USERDOMAIN': 'T-W732-IX-118', 12:19:50 INFO - 'USERNAME': 'cltbld', 12:19:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:19:50 INFO - 'WINDIR': 'C:\\windows', 12:19:50 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:19:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:19:50 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-118', '--webServer', 'localhost'] with output_timeout 3600 12:19:51 INFO - mozversion INFO | application_buildid: 20151026073252 12:19:51 INFO - mozversion INFO | application_changeset: 28068d907290d1f5138a0b9e59ae2233a1c1b7a3 12:19:51 INFO - mozversion INFO | application_display_name: Nightly 12:19:51 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:19:51 INFO - mozversion INFO | application_name: Firefox 12:19:51 INFO - mozversion INFO | application_remotingname: firefox 12:19:51 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 12:19:51 INFO - mozversion INFO | application_vendor: Mozilla 12:19:51 INFO - mozversion INFO | application_version: 44.0a1 12:19:51 INFO - mozversion INFO | platform_buildid: 20151026073252 12:19:51 INFO - mozversion INFO | platform_changeset: 28068d907290d1f5138a0b9e59ae2233a1c1b7a3 12:19:51 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 12:19:51 INFO - mozversion INFO | platform_version: 44.0a1 12:19:51 INFO - 2015-10-26 12:19:51,065 DEBUG : using testdate: 1445887191 12:19:51 INFO - 2015-10-26 12:19:51,065 DEBUG : actual date: 1445887191 12:19:51 INFO - 2015-10-26 12:19:51,417 INFO : starting webserver on 'localhost:49319' 12:19:51 INFO - 2015-10-26 12:19:51,420 INFO : Starting test suite T-W732-IX-118 12:19:51 INFO - 2015-10-26 12:19:51,420 INFO : Starting test dromaeo_css 12:19:51 INFO - 2015-10-26 12:19:51,420 DEBUG : operating with platform_type : w7_ 12:19:51 INFO - 2015-10-26 12:19:51,421 INFO : Initialising browser for dromaeo_css test... 12:19:51 INFO - 2015-10-26 12:19:51,512 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp16jl3q\profile http://localhost:49319/getInfo.html 12:19:59 INFO - 2015-10-26 12:19:59,796 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:19:59 INFO - 2015-10-26 12:19:59,796 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:19:59 INFO - 2015-10-26 12:19:59,796 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 12:19:59 INFO - 2015-10-26 12:19:59,796 DEBUG : BROWSER_OUTPUT: __metrics 12:19:59 INFO - 2015-10-26 12:19:59,862 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:20:01 INFO - 2015-10-26 12:20:01,361 DEBUG : BROWSER_OUTPUT: console.error: 12:20:01 INFO - 2015-10-26 12:20:01,365 DEBUG : BROWSER_OUTPUT: Message: Error: Transaction canceled due to a closed connection. 12:20:01 INFO - 2015-10-26 12:20:01,365 DEBUG : BROWSER_OUTPUT: Stack: 12:20:01 INFO - 2015-10-26 12:20:01,365 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise<@resource://gre/modules/Sqlite.jsm:562:1 12:20:01 INFO - 2015-10-26 12:20:01,365 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 12:20:01 INFO - 2015-10-26 12:20:01,365 DEBUG : BROWSER_OUTPUT: 12:20:01 INFO - 2015-10-26 12:20:01,957 INFO : Browser initialized. 12:20:01 INFO - 2015-10-26 12:20:01,957 INFO : Running cycle 1/1 for dromaeo_css test... 12:20:01 INFO - 2015-10-26 12:20:01,957 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp16jl3q\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:20:13 INFO - 2015-10-26 12:20:13,401 DEBUG : BROWSER_OUTPUT: RSS: Main: 127537152 12:20:13 INFO - 2015-10-26 12:20:13,401 DEBUG : BROWSER_OUTPUT: 12:21:04 INFO - 2015-10-26 12:21:04,569 DEBUG : BROWSER_OUTPUT: 1445887264556 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:23:14 INFO - 2015-10-26 12:23:13,993 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49319/tests/dromaeo/cssquery-ext.html) 12:23:14 INFO - 2015-10-26 12:23:14,907 DEBUG : BROWSER_OUTPUT: RSS: Main: 174735360 12:23:14 INFO - 2015-10-26 12:23:14,907 DEBUG : BROWSER_OUTPUT: 12:26:17 INFO - 2015-10-26 12:26:17,171 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/dromaeo/cssquery-ext.html (next: http://localhost:49319/tests/dromaeo/cssquery-jquery.html) 12:26:17 INFO - 2015-10-26 12:26:17,726 DEBUG : BROWSER_OUTPUT: RSS: Main: 188092416 12:26:17 INFO - 2015-10-26 12:26:17,726 DEBUG : BROWSER_OUTPUT: 12:29:19 INFO - 2015-10-26 12:29:19,595 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49319/tests/dromaeo/cssquery-mootools.html) 12:29:20 INFO - 2015-10-26 12:29:20,150 DEBUG : BROWSER_OUTPUT: RSS: Main: 153817088 12:29:20 INFO - 2015-10-26 12:29:20,150 DEBUG : BROWSER_OUTPUT: 12:32:22 INFO - 2015-10-26 12:32:22,128 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49319/tests/dromaeo/cssquery-prototype.html) 12:32:22 INFO - 2015-10-26 12:32:22,694 DEBUG : BROWSER_OUTPUT: RSS: Main: 133644288 12:32:22 INFO - 2015-10-26 12:32:22,694 DEBUG : BROWSER_OUTPUT: 12:35:25 INFO - 2015-10-26 12:35:25,322 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49319/tests/dromaeo/cssquery-yui.html) 12:35:25 INFO - 2015-10-26 12:35:25,897 DEBUG : BROWSER_OUTPUT: RSS: Main: 136208384 12:35:25 INFO - 2015-10-26 12:35:25,897 DEBUG : BROWSER_OUTPUT: 12:38:28 INFO - 2015-10-26 12:38:28,351 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/dromaeo/cssquery-yui.html (next: http://localhost:49319/tests/dromaeo/cssquery-dojo.html) 12:38:28 INFO - 2015-10-26 12:38:28,598 DEBUG : BROWSER_OUTPUT: RSS: Main: 181833728 12:38:28 INFO - 2015-10-26 12:38:28,598 DEBUG : BROWSER_OUTPUT: 12:38:28 INFO - 2015-10-26 12:38:28,598 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:38:28 INFO - 2015-10-26 12:38:28,598 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:38:28 INFO - 2015-10-26 12:38:28,598 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:38:28 INFO - 2015-10-26 12:38:28,599 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:38:28 INFO - 2015-10-26 12:38:28,599 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2677.250369896429;2704.337437327855;2716.77676605368;2739.63014992976;2748.1480740970314;5201.583873290142;5221.608379371543;5250.894982100355;5300.390455097665;5319.388270348911;5224.346956630421;5235.057689615867;5303.204519322104;5323.547498462625;5324.787008519665;4460.665450091256;4485.753283569403;4621.0757848430285;4637.211674015409;4642.07158568286;4495.977520112411;4518.638508919295;4564.178447879381;4608.746518941457;4661.277501987197;4510.188166130095;4510.819567217307;4551.34005569194;4565.018521017981;4673.135469938055;4441.933370999438;4463.598276155161;4535.886602834922;4582.702124361939;4591.747453890035;4385.693001489897;4461.397711308964;4461.933071003938;4478.955210447886;4523.27627579589;5193.948060519422;5194.480551944813;5195.168772996302;5231.712255825928;5287.180487024518;5346.572274218053;5433.239346491494;5444.3194600674915;5587.357453892813;5606.607537472379;5312.681239125664;5488;5540.63985840919;5542.889142217174;5569.498745112919;5095.057414378315;5111.6677415968215;5168.22476628505;5185.922211166836;5195.9740201298755;5501.697406642593;5574.108142697149;5593.356763972114;5706.42935706426;5706.914396284059;5317.627766056339;5331.040412725669;5376.059189641797;5396.271503346998;5429.53848922846;5475.178723191553;5511.476409741068;5518.53092487135;5569.164625306236;5584.692841893735;5149.995750828627;5175.430702622709;5317.73411329432;5500.532454741312;5549.944500554942;5455.318085239345;5465.152901300305;5472.2338872557875;5535.391106978229;5557;3422.606400263952;3480.582330120402;3486.459598762196;3487.7907325560545;3494.8602055917477;3121.07928161193;3149.5905532281117;3181.872725091017;3214.5981752280964;3220.9194770130466;4904.730239836772;5185.533302002762;5220.582353411718;5237.554807841376;5308.044551980681;5404.135338345845;5464.016477034172;5547.805826796042;5662.68855212959;5681.005823980787;5129.102407078821;5220.582353411718;5222.556082732935;5261.631685782035;5338.492843179892;4337.631301339358;4338.848140315074;4345.717528360647;4391.890202744957;4419.756913369732;5741.25363702716;5774.682392468455;5854.414558544111;5894.351621321651;5898;5451.100568406168;5539.224508568723;5546.72266386671;5639.464250896158;5670;5687.971560142173;5700.173474846207;5714.514266287412;5775.33583637887;5814.5348372130975;5356.973215133899;5429.077056900415;5441.891162176814;5521.420250873601;5543.805966791143;5457.426970168075;5538.6399884007415;5546.972265138648;5571.860703482445;5692.573057020657;5313.910648317008;5366.946330536801;5377.758000889891;5428.45715428454;5457.45425457467;5225.9216111759115;5266.683998960075;5417.377001644703;5497.01053810318;5501.174823776466;5124.8206312778875;5232.084385232493;5237.816676416307;5343.038253114477;5383.353997520323;5380.838574842761;5390.97304513475;5431.34823821144;5475.206095116107;5480.561555075664;5140.408852981955;5269.973650131725;5351.491608297206;5436.238926550365;5466 12:38:28 INFO - 2015-10-26 12:38:28,599 DEBUG : BROWSER_OUTPUT: |1;ext.html;479.91601469742784;483.9540243676852;509.52614068915904;518.1191973644804;522.2192821731512;30547.541786873215;30592.940705929395;38191.42712859302;38403.80798096005;38504.614953850454;39062.60937390625;39404.60595394045;39980;40002.199956000935;40003.39994900079;7603.961980190119;7737.381009519225;8021.157778433253;8200.671973121069;8201.056878458963;9915.305928585001;10156.695299141;10163.59345626174;10171.38971661699;10172.982701729841;9722.222222222204;9826.656067037655;9992.900070999305;10003.34978226413;10037.949810250939;9811.705648830512;9931;9949.801003979952;9952.253580981456;9956.15372693322;9492.240620750323;9570.521473926276;9708.17480514157;9781.16860066888;9884.95057524715;35011.82494087539;36695.81652091722;37393.813030934674;37462.62537374619;37502.62476377112;3186.0123361757696;3349.9330013399594;3486.2330287336745;3534.8939531814085;3804.9429258561145;29319.853400733075;29378.70621293781;29532.26169345762;29997.850010749808;30240.24399390011;2584.3151564835334;2590.119359417788;2663.7868970482314;2664.906728264521;2747.368105335784;303722;320955.3952230247;321256;321310;322844;47106.76446617745;47171.764141179076;51232.48767512352;52828.73585632047;53929.73035134799;52299.47700523022;52903.73548132235;52991.47008529942;53132.46867531275;53169.202461963105;12733.554325598745;12935.417906194056;12943.223406595635;12954.935225323814;12955.417006234742;9700.078492543196;10233;10240.48797560119;10247.692569222936;10252.743681407876;16787.160641968097;17303.480895573153;17376.39182628627;17421.82578174202;17483.213255403538;7374;7589.734359297398;7604.353629941406;7644.655990480442;7666.731664391719;824.6165533027171;907.5552979040223;996.875390576178;1011.5195282241024;1040.3341861208833;27965.860170699423;28175.013874514294;28545.000924967528;28638.713612864023;28840.278993025342;967.7048500207504;985.4333758089127;994.3089552760762;999.7600575861741;1019.9541020654088;14570.927145364205;14634.560963171121;14701.632459188606;14879.553613391616;15009;365956;378635;381187;382250;383873;47620.52379476161;48136.75931620319;48192;48233;48238.27642585364;1054.981942425563;1060.3266925502207;1077.2459278505078;1110;1112.7496313329436;27424.725752743016;27623;28233.858830705714;28264.85867570649;28351.57472637871;42762;43025.78487107545;43039.13921721611;43059;43120;352331;357898;359112;361095;361137;24462.63306050375;24887.377815554755;25551.339162954348;25722.871385642953;28184.577231341136;39484.210315794095;39958.2008359837;40075;40242.59757402506;42689.786551067045;34652.82673586616;34737;34773.304533909686;35237.82381088078;36723.265534688624;35334;35533.289334212655;35642.63448165598;36250.63749362578;36579.56772133659;36085.076603934125;36124.63875361318;37454.62545374512;37596.24807503889;37956.810215948746;30324;31820.68179318177;31968.36063278768;32116.35767284688;32809.17977050593;20575.588488229852;24279.635805462578;25214.873925630254;25319.74680253174;25400.491990160463 12:38:28 INFO - 2015-10-26 12:38:28,609 DEBUG : BROWSER_OUTPUT: |2;jquery.html;323.7604172912043;325.0913696219067;332.3502552509845;338.3250415421234;339.338290333849;11223.158263130277;12163.148579599432;16626.41807536735;17020.48938531843;17026.48920532383;16250.43123490678;16543.751843722355;16680.91574047686;16807.159642017912;16871.40950066748;5229.7385130743505;5252.632315737881;5344.438833922441;5345.305110335661;5350.3312085989255;5390.838274851761;5408.864778380533;5443.292371991635;5446.373667028302;5449.291592093023;4305.720128191658;4404.845830395937;5363.678179309234;5364.436734142917;5364.892702145955;5883.235179426669;6055.364186760371;6079.726412311456;6080.939190608082;6092.573519853634;3959.4852669152824;5390.78436862525;5405.216243644689;5416.485433883775;5423.078076726966;13762.724745505135;13814.102083364649;13900.16599004063;14030.859691403057;14033.578992630237;2073.533454972634;2120.204923153817;2129.084493667722;2142.389419015589;2188.5513469738667;10155.339902906288;10173.440460774655;10393.688189354332;10395.636152734694;10494.003069708366;1672.3728601774335;1676.555712736126;1693.652801175756;1697.1768692184405;1701.8042925063528;70824;72449;72709;73507.26492735058;73783.2621673787;6511.088447617242;6547.377999090078;6601.702923368364;6646.767363142267;6656.434203092695;7667.808304792425;7686.46194766361;7707.499012564165;7717.768466945889;7735.226477352332;5263.499967503081;5282;5331.866703332447;5332.146856503018;5366.302380690563;5587.245721827582;5628.465295796893;5634.830955071355;5641.435856414326;5659.717014149276;2941.7646588272887;3002.309468822183;3007.0978706387996;3020.3808219314988;3022.7128422799797;1091.9181061420425;1098.2751384086464;1100.3947828694186;1103.189155970361;1154.5612667186415;426.44349124392966;430.8255156661557;432.2932006169872;441.68640265411915;446.5422941485004;12126.27242365461;12126.818097728541;12333.876661233453;12411.255324680547;12459.626211213677;479.05625916943563;482.1802935010496;482.8720389096892;488.836239859651;490.11778798163164;5218.660787048906;6242.687865606792;6565.868682626417;6590;6720.361565651256;436400;441130;441756;443328;444476;80017.59991200006;82628.42133540308;82686.17313826905;83476.16523834805;84333.31333373298;520.8723862653674;521.5228066319352;525.5322762741087;526.6550409481796;527.2223470381157;11471.770564588494;11701.473433695333;11722.58970936013;11769.17615766888;11781.410929453665;37995.050123747125;40197;40634.39048414217;40854.387184192856;41581.79209103935;54506.182407264714;55198.17202741882;55343.44656553383;55783.72108139433;56421;6391.456726178141;6569.901451478136;6646.867062658623;6648.468122550088;6654.800355989134;12671.636520969503;12701.110922235355;12875.84434216044;13036.869631303945;13096.607101786962;10572.471376431302;10739.624113156315;10797.62208322705;10935.671929842118;11129.443527823738;8626.353023523136;10427.63503277412;10440.321379110334;10487.265891387835;10520.947395262974;10775.299605525615;10934.835977460503;11063.059639930696;11146.108311334912;11167.664970050912;6438.742450302123;6523.18460192476;6587.571807832475;6588.40704336612;6626.53614246998;10845.511951962339;10978.505967231335;11047.889521104686;11065.225434219527;11208 12:38:28 INFO - 2015-10-26 12:38:28,611 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1221.157401393039;1278.3288773393967;1394.247106562456;1590.9261248657153;1597.073697255592;8372.28835548979;8573.314134869212;9021.864672029924;9148.58831352589;9152.542372881362;9029.638814447431;9160.992290848018;9329.860052099224;9341.579628916697;9359.719208423758;2205.900734466953;2262.988685056572;2283.5432913417385;2285.1887579909144;2286.6112760830697;2022.9797702023013;2061.6804395318673;2229.8662080275153;2268.115434980359;2307.284741730061;2171.9348419547437;2215.8005779479845;2223.1774243529917;2229.9108035678632;2231.497912969577;2010.9195632174697;2130.648443006902;2268.5009297954425;2286.3941055620276;2293.243229734184;2053.476363527291;2220.6891035255076;2266.5466906618744;2272.625016872214;2273.886305684709;9154.542272886329;9163.541822908894;9259.222233330041;9289.814203715889;9307.115823996777;5149.76826042829;5318.893622127574;5381.0583147948955;5381.327334083239;5382.273393091921;3436.9312613747834;3633.3278343506536;3658.7255955803157;3670.5778835434;3675.8237364043516;1051.81593221186;1056.75166335911;1091.0834898685143;1091.0998426298336;1104.3373975614647;13738.587842364745;13768.586942391645;13830.585082447542;13893.79159312601;13916.30418479082;4485.394471746337;4504.77476126199;4749.168895443284;4762.595179409789;4811.591014763784;11136.610218642309;11160.16298777595;11165.944170279097;11177.329360238411;11208.215424920178;5302.151655735064;5303.3218224797;5304.814331498456;5308.469153084738;5309.840704778863;4804.279358096313;4808.158572249842;4814.446338671098;4818.855609253848;4825.613950883921;3765.2093060457064;3788.3749181385315;3810.504634397566;3828.502294701671;3850.4609354690365;1174.2015429507846;1191.7966981859402;1194.778965891313;1199.4722322178213;1200.2918278215895;1062.3201151263197;1087.9619213327496;1163.6567212672282;1164.7495788405533;1165.341582006164;8236.299914507334;8283.58582070894;8307.418480706414;8311.916880831235;8343.41596088268;466.2935652486486;468.4098036474067;474.0211463328216;478.7366948178488;478.83958873776976;6046.879062418727;6154.876902462015;6212.9068063979075;6562.770303039466;6714.362135597111;11980.341081240615;12057.939710301393;12073.396330183456;12092.334921579222;12114.81827772584;11167.888321116685;11317.547298108148;11486.483108260149;11509.079273658088;11962.222455540363;8251.504909705436;8381.07808141104;8451.53516556583;8471.618777155043;8473.98312202552;8059.43583949118;8141.959290203511;8204.466709663853;8273.296769774637;8283.792905177177;10161.593536258466;10256.897431025594;10366.896331036593;10400.323978941344;10414.479276036016;10342.5345859435;10572.471376431302;10770.246082774443;10802.351858888178;10803.567857285621;8289.378296627656;8289.4197406181;8401.327893768606;8408.495490270603;8424.957875210584;8165.918340816754;8719.738407847775;8865.734027978913;8866.911330886867;8882.422642528216;8096.797580060545;8364.916350836414;8369.916300837158;8599.14008599135;8610.354223433142;7875.566843823644;7981.082175549886;8007.359411247203;8027.478213916077;8164.265216130577;8140.87788683182;8213.630386632736;8215.424920255524;8218.958905205674;8234.588270586328;6553.951367781132;7087.562532171995;7101.005859179822;7118.786436406916;7140.285971402818;7043.50695451334;7173.641317933978;7240.637968101469;7257.165425976081;7271.636418179176 12:38:28 INFO - 2015-10-26 12:38:28,645 DEBUG : BROWSER_OUTPUT: |4;prototype.html;326.6945406045348;328.2352119561422;333.5330537247854;333.8197373418353;344.045274363641;5723.771049158034;5744.454276843693;5746.942530574693;5750;5778.075507918734;5521.779128834842;5590.217369568264;5627.6060675752715;5687.687177205252;5705.74324155413;4182.97908510457;4246.2356775780345;4248.596383343593;4260.211860805761;4269.167512335096;4123.587641235882;4160.292750232453;4251.511076226243;4257.552956939523;4263.531011588722;4163.229802486535;4200.348945913388;4241.300185469394;4243.04531480416;4253.340732186516;4078.0416602098485;4222.0922501662135;4224.324108142682;4238.194742998836;4256.851010214627;3769.340365436065;3774.1319496515957;3774.924501509982;3778.357679194516;3778.7732736035928;5347.037533244014;5490.258815059956;5537.695426751527;5576.776928922838;5588.30146231721;4847.927281090751;4998.300237966688;5025.597952163818;5027.648064635477;5029.4216165141115;5727.713614319309;5874.765009399619;5885.558583106242;5910.113482977545;5969.761209551612;4273.764942928137;4274.551172126913;4282.42187304713;4288.935665965013;4290.506591741927;6032.66820324882;6189.2572891252885;6190.380961903818;6226.40849119333;6234.18955535782;4391.275439552502;4402.471703395613;4406.581374769392;4416.823327066945;4417.050334178167;4446.977195245047;4472.105578884236;4473.507914129443;4478.373027776114;4478.596926276586;4891.315215869781;4927.088488629615;4952.777125029383;4953.578945789576;4960.206366981266;5929.644221346733;5937.050071988461;5941.494972927349;5941.940580594139;5954.46409823118;1527.8930474866875;1528.0220658778392;1531.5788158256346;1541.0907564536976;1543.760717088853;1022.4274406332478;1027.4246422003553;1050.222835102019;1052.726291164303;1054.4622242656146;5787.334456537468;5834.5624078194305;5836.416358364129;5867.442592953663;5868.501177399883;6031.7587296507645;6080.969595152084;6150.7539698411565;6194.318624951252;6196.287426945959;5850.3272123705465;5858.765649373977;5867.090600956859;5875.030619947746;5876.7355469004;3304.653011433813;3334.2664613784927;3338.8664453422075;3361.7814842035677;3400.4219282721997;6396.872062558816;6438.484921206292;6443.96778016107;6449.742010319628;6482.805515834532;6023.728932198062;6198.101275315054;6235.438810507076;6259.123722678829;6264.467520260829;6441.774537891151;6444.13004244421;6444.291127975919;6444.9033264502;6455.354464553601;5934.821955341346;6198.690065496798;6220.160278362363;6254.405831446005;6262.06069089622;6026.15633811258;6154.230721159855;6161.137440758208;6163.3528479509005;6167.105769663463;6528.804135875931;6552.868942621026;6557.803265902031;6559.048937904073;6578.473722102316;4149.543550209414;4308.655307575324;4320.503142138694;4324.459442569679;4331.566843315643;5654.547636189177;5882.294124705063;6126.172966649446;6162.691865406622;6269.153664255449;5964;5989.790357337472;6020.856037352889;6045.879082418415;6047.244094488189;5963.73163207648;5971.104334349709;5980.43185897339;6000.969995149996;6002.429769171922;5703.458171473703;5848.590598658053;5850.765969361178;5856.970715146397;5870.442307980735;6011.489023432882;6032.75868965254;6036.154938308729;6039.184710064085;6042.305134909541;5717.914231286451;5847.152162936435;5927.2590926134235;5935.7032148391545;5944.078667806497 12:38:28 INFO - 2015-10-26 12:38:28,647 DEBUG : BROWSER_OUTPUT: |5;yui.html;670.678074524228;676.803860655697;741.4809633256722;741.9723682699463;782.0146615264766;12683.239005659643;12695.93652031741;13140.934295328521;13343.065985381027;13355.332233388328;11704.1221908357;12264.509419623226;12319.076069294793;12346.567870124547;12356.196847204925;560.6383882395852;589.331109191068;599.6761748655721;599.7001499250375;600.3876046432636;505.3455774771679;511.5089514066482;593.2762030323021;598.5660396212754;599.5173885022555;620.5439002333281;620.9937900620981;624.307019208678;624.4099326136802;626.586452941059;535.8499620106377;538.5987439357665;622.847402386416;623.2427600465434;623.9095331176976;441.3225698552706;445.91750526152765;453.64615599832183;453.90468001719677;454.20287395620477;12161.993271110256;12439.191452555555;12454.25274483534;12464.626061218178;12499.062570307264;11422.485988130471;11670.241434306743;11859.169858109935;12091.879081209163;12114.757704845853;10178.18574514037;10451.634192803216;10650;10703.250772445932;10901.672949811518;3484.5818501779695;3572.732045096602;3588.9641103588892;3599.478075679012;3707.925841483155;47314.5268547317;48022;48230.75884620554;48302.7584862077;48522;9180.21968132703;9382.530873456437;9446.669366572274;9542.141207291377;9685.757856053655;16186.838131618768;16447.506574802774;16864.83135168657;16867.409640662765;16948.322067117424;7338.77983660491;7449.739259125905;7489.588072656056;7503.474756766974;7663.808404789925;4068.715189936676;4241.5745644236895;4263.211305908417;4275.5724427557;4375.212461756851;2813.620161278201;2843.1896909380725;2843.6018957345987;2845.38824152804;2848;481.3862325534971;487.08185071141156;497.1746900145741;503.7657489267508;504.1102454168396;567.3050513121393;596.2814808156162;617.8146556033172;665.8601693644389;727.3235890621772;9412.388194767318;9946.20430365569;10080;10123.898761012295;10130.671849138364;682.8566001139717;714.7784186902077;720.218562859293;726.4515290955401;728.3226599262631;4197.601227883346;4511.278195488706;4524.09518096382;4598.471175814757;4629.976850115728;60022.099668505005;60608.393916060275;60631;60634.39365606376;60708.392916070276;48459.51540484621;48784.26823597649;48850.511494884595;49351.753241233564;51075.48924510782;43098.13803723908;43248.78375608165;43751.56248437475;43867.78066109713;44273.33589996152;8641.913580864111;8975.416597921114;9215.67745128944;9315.068493150631;9547.412933338719;29285.41429171362;29388.706112939453;31456.685433145376;31559.368812624078;31599.526007109453;39493.2101357977;39893.80849532088;40129;40211;40245.1950960985;8645.654173833227;8728.69449569262;8760.080191580048;8830.697895612826;9212.401193922373;13039.348032598142;13064.632358422432;13141.211527308393;13246.668833279246;13262.336883155996;12074.154809163625;12278.570250041206;12293.323867187391;12340.321282329558;12457.750844983231;11763.882361176278;12019.519219231133;12181.69545761363;12281.324527150735;12489.0633202512;8493.405461617627;11794.823077654013;12081;12229.93885030569;12519.248845069325;10529.315594486334;10648.274764672682;10876.401797901191;10952.78094438091;11253.381064041556;10315.793684126425;10472.58620086309;10488.31825931312;10727.409992450179;10840.728981775519 12:38:28 INFO - 2015-10-26 12:38:28,648 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:38:28 INFO - 2015-10-26 12:38:28,648 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:38:28 INFO - 2015-10-26 12:38:28,648 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,655 12:38:28 INFO - 2015-10-26 12:38:28,648 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:38:28 INFO - 2015-10-26 12:38:28,648 DEBUG : BROWSER_OUTPUT: __startTimestamp1445888308612__endTimestamp 12:38:28 INFO - 2015-10-26 12:38:28,648 DEBUG : BROWSER_OUTPUT: 12:38:28 INFO - 2015-10-26 12:38:28,648 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:38:28 INFO - 2015-10-26 12:38:28,648 DEBUG : BROWSER_OUTPUT: Number of tests: 6 12:38:28 INFO - 2015-10-26 12:38:28,648 DEBUG : BROWSER_OUTPUT: 12:38:28 INFO - 2015-10-26 12:38:28,648 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5047.93 Median:5317.63 stddev:729.26 (13.7%) stddev-sans-first:707.38 12:38:28 INFO - 2015-10-26 12:38:28,648 DEBUG : BROWSER_OUTPUT: Values: 2677.3 2704.3 2716.8 2739.6 2748.1 5201.6 5221.6 5250.9 5300.4 5319.4 5224.3 5235.1 5303.2 5323.5 5324.8 4460.7 4485.8 4621.1 4637.2 4642.1 4496.0 4518.6 4564.2 4608.7 4661.3 4510.2 4510.8 4551.3 4565.0 4673.1 4441.9 4463.6 4535.9 4582.7 4591.7 4385.7 4461.4 4461.9 4479.0 4523.3 5193.9 5194.5 5195.2 5231.7 5287.2 5346.6 5433.2 5444.3 5587.4 5606.6 5312.7 5488.0 5540.6 5542.9 5569.5 5095.1 5111.7 5168.2 5185.9 5196.0 5501.7 5574.1 5593.4 5706.4 5706.9 5317.6 5331.0 5376.1 5396.3 5429.5 5475.2 5511.5 5518.5 5569.2 5584.7 5150.0 5175.4 5317.7 5500.5 5549.9 5455.3 5465.2 5472.2 5535.4 5557.0 3422.6 3480.6 3486.5 3487.8 3494.9 3121.1 3149.6 3181.9 3214.6 3220.9 4904.7 5185.5 5220.6 5237.6 5308.0 5404.1 5464.0 5547.8 5662.7 5681.0 5129.1 5220.6 5222.6 5261.6 5338.5 4337.6 4338.8 4345.7 4391.9 4419.8 5741.3 5774.7 5854.4 5894.4 5898.0 5451.1 5539.2 5546.7 5639.5 5670.0 5688.0 5700.2 5714.5 5775.3 5814.5 5357.0 5429.1 5441.9 5521.4 5543.8 5457.4 5538.6 5547.0 5571.9 5692.6 5313.9 5366.9 5377.8 5428.5 5457.5 5225.9 5266.7 5417.4 5497.0 5501.2 5124.8 5232.1 5237.8 5343.0 5383.4 5380.8 5391.0 5431.3 5475.2 5480.6 5140.4 5270.0 5351.5 5436.2 5466.0 12:38:28 INFO - 2015-10-26 12:38:28,648 DEBUG : BROWSER_OUTPUT: 12:38:28 INFO - 2015-10-26 12:38:28,648 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49923.96 Median:198590.04 stddev:92810.94 (46.7%) stddev-sans-first:92997.06 12:38:28 INFO - 2015-10-26 12:38:28,648 DEBUG : BROWSER_OUTPUT: Values: 479.9 484.0 509.5 518.1 522.2 30547.5 30592.9 38191.4 38403.8 38504.6 39062.6 39404.6 39980.0 40002.2 40003.4 7604.0 7737.4 8021.2 8200.7 8201.1 9915.3 10156.7 10163.6 10171.4 10173.0 9722.2 9826.7 9992.9 10003.3 10037.9 9811.7 9931.0 9949.8 9952.3 9956.2 9492.2 9570.5 9708.2 9781.2 9885.0 35011.8 36695.8 37393.8 37462.6 37502.6 3186.0 3349.9 3486.2 3534.9 3804.9 29319.9 29378.7 29532.3 29997.9 30240.2 2584.3 2590.1 2663.8 2664.9 2747.4 303722.0 320955.4 321256.0 321310.0 322844.0 47106.8 47171.8 51232.5 52828.7 53929.7 52299.5 52903.7 52991.5 53132.5 53169.2 12733.6 12935.4 12943.2 12954.9 12955.4 9700.1 10233.0 10240.5 10247.7 10252.7 16787.2 17303.5 17376.4 17421.8 17483.2 7374.0 7589.7 7604.4 7644.7 7666.7 824.6 907.6 996.9 1011.5 1040.3 27965.9 28175.0 28545.0 28638.7 28840.3 967.7 985.4 994.3 999.8 1020.0 14570.9 14634.6 14701.6 14879.6 15009.0 365956.0 378635.0 381187.0 382250.0 383873.0 47620.5 48136.8 48192.0 48233.0 48238.3 1055.0 1060.3 1077.2 1110.0 1112.7 27424.7 27623.0 28233.9 28264.9 28351.6 42762.0 43025.8 43039.1 43059.0 43120.0 352331.0 357898.0 359112.0 361095.0 361137.0 24462.6 24887.4 25551.3 25722.9 28184.6 39484.2 39958.2 40075.0 40242.6 42689.8 34652.8 34737.0 34773.3 35237.8 36723.3 35334.0 35533.3 35642.6 36250.6 36579.6 36085.1 36124.6 37454.6 37596.2 37956.8 30324.0 31820.7 31968.4 32116.4 32809.2 20575.6 24279.6 25214.9 25319.7 25400.5 12:38:28 INFO - 2015-10-26 12:38:28,650 DEBUG : BROWSER_OUTPUT: 12:38:28 INFO - 2015-10-26 12:38:28,650 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25253.18 Median:2423.27 stddev:73018.99 (3013.2%) stddev-sans-first:73199.84 12:38:28 INFO - 2015-10-26 12:38:28,650 DEBUG : BROWSER_OUTPUT: Values: 323.8 325.1 332.4 338.3 339.3 11223.2 12163.1 16626.4 17020.5 17026.5 16250.4 16543.8 16680.9 16807.2 16871.4 5229.7 5252.6 5344.4 5345.3 5350.3 5390.8 5408.9 5443.3 5446.4 5449.3 4305.7 4404.8 5363.7 5364.4 5364.9 5883.2 6055.4 6079.7 6080.9 6092.6 3959.5 5390.8 5405.2 5416.5 5423.1 13762.7 13814.1 13900.2 14030.9 14033.6 2073.5 2120.2 2129.1 2142.4 2188.6 10155.3 10173.4 10393.7 10395.6 10494.0 1672.4 1676.6 1693.7 1697.2 1701.8 70824.0 72449.0 72709.0 73507.3 73783.3 6511.1 6547.4 6601.7 6646.8 6656.4 7667.8 7686.5 7707.5 7717.8 7735.2 5263.5 5282.0 5331.9 5332.1 5366.3 5587.2 5628.5 5634.8 5641.4 5659.7 2941.8 3002.3 3007.1 3020.4 3022.7 1091.9 1098.3 1100.4 1103.2 1154.6 426.4 430.8 432.3 441.7 446.5 12126.3 12126.8 12333.9 12411.3 12459.6 479.1 482.2 482.9 488.8 490.1 5218.7 6242.7 6565.9 6590.0 6720.4 436400.0 441130.0 441756.0 443328.0 444476.0 80017.6 82628.4 82686.2 83476.2 84333.3 520.9 521.5 525.5 526.7 527.2 11471.8 11701.5 11722.6 11769.2 11781.4 37995.1 40197.0 40634.4 40854.4 41581.8 54506.2 55198.2 55343.4 55783.7 56421.0 6391.5 6569.9 6646.9 6648.5 6654.8 12671.6 12701.1 12875.8 13036.9 13096.6 10572.5 10739.6 10797.6 10935.7 11129.4 8626.4 10427.6 10440.3 10487.3 10520.9 10775.3 10934.8 11063.1 11146.1 11167.7 6438.7 6523.2 6587.6 6588.4 6626.5 10845.5 10978.5 11047.9 11065.2 11208.0 12:38:28 INFO - 2015-10-26 12:38:28,651 DEBUG : BROWSER_OUTPUT: 12:38:28 INFO - 2015-10-26 12:38:28,651 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:6329.78 Median:4755.88 stddev:3646.36 (76.7%) stddev-sans-first:3636.37 12:38:28 INFO - 2015-10-26 12:38:28,653 DEBUG : BROWSER_OUTPUT: Values: 1221.2 1278.3 1394.2 1590.9 1597.1 8372.3 8573.3 9021.9 9148.6 9152.5 9029.6 9161.0 9329.9 9341.6 9359.7 2205.9 2263.0 2283.5 2285.2 2286.6 2023.0 2061.7 2229.9 2268.1 2307.3 2171.9 2215.8 2223.2 2229.9 2231.5 2010.9 2130.6 2268.5 2286.4 2293.2 2053.5 2220.7 2266.5 2272.6 2273.9 9154.5 9163.5 9259.2 9289.8 9307.1 5149.8 5318.9 5381.1 5381.3 5382.3 3436.9 3633.3 3658.7 3670.6 3675.8 1051.8 1056.8 1091.1 1091.1 1104.3 13738.6 13768.6 13830.6 13893.8 13916.3 4485.4 4504.8 4749.2 4762.6 4811.6 11136.6 11160.2 11165.9 11177.3 11208.2 5302.2 5303.3 5304.8 5308.5 5309.8 4804.3 4808.2 4814.4 4818.9 4825.6 3765.2 3788.4 3810.5 3828.5 3850.5 1174.2 1191.8 1194.8 1199.5 1200.3 1062.3 1088.0 1163.7 1164.7 1165.3 8236.3 8283.6 8307.4 8311.9 8343.4 466.3 468.4 474.0 478.7 478.8 6046.9 6154.9 6212.9 6562.8 6714.4 11980.3 12057.9 12073.4 12092.3 12114.8 11167.9 11317.5 11486.5 11509.1 11962.2 8251.5 8381.1 8451.5 8471.6 8474.0 8059.4 8142.0 8204.5 8273.3 8283.8 10161.6 10256.9 10366.9 10400.3 10414.5 10342.5 10572.5 10770.2 10802.4 10803.6 8289.4 8289.4 8401.3 8408.5 8425.0 8165.9 8719.7 8865.7 8866.9 8882.4 8096.8 8364.9 8369.9 8599.1 8610.4 7875.6 7981.1 8007.4 8027.5 8164.3 8140.9 8213.6 8215.4 8219.0 8234.6 6554.0 7087.6 7101.0 7118.8 7140.3 7043.5 7173.6 7240.6 7257.2 7271.6 12:38:28 INFO - 2015-10-26 12:38:28,653 DEBUG : BROWSER_OUTPUT: 12:38:28 INFO - 2015-10-26 12:38:28,654 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5018.97 Median:5745.70 stddev:1505.28 (26.2%) stddev-sans-first:1467.73 12:38:28 INFO - 2015-10-26 12:38:28,654 DEBUG : BROWSER_OUTPUT: Values: 326.7 328.2 333.5 333.8 344.0 5723.8 5744.5 5746.9 5750.0 5778.1 5521.8 5590.2 5627.6 5687.7 5705.7 4183.0 4246.2 4248.6 4260.2 4269.2 4123.6 4160.3 4251.5 4257.6 4263.5 4163.2 4200.3 4241.3 4243.0 4253.3 4078.0 4222.1 4224.3 4238.2 4256.9 3769.3 3774.1 3774.9 3778.4 3778.8 5347.0 5490.3 5537.7 5576.8 5588.3 4847.9 4998.3 5025.6 5027.6 5029.4 5727.7 5874.8 5885.6 5910.1 5969.8 4273.8 4274.6 4282.4 4288.9 4290.5 6032.7 6189.3 6190.4 6226.4 6234.2 4391.3 4402.5 4406.6 4416.8 4417.1 4447.0 4472.1 4473.5 4478.4 4478.6 4891.3 4927.1 4952.8 4953.6 4960.2 5929.6 5937.1 5941.5 5941.9 5954.5 1527.9 1528.0 1531.6 1541.1 1543.8 1022.4 1027.4 1050.2 1052.7 1054.5 5787.3 5834.6 5836.4 5867.4 5868.5 6031.8 6081.0 6150.8 6194.3 6196.3 5850.3 5858.8 5867.1 5875.0 5876.7 3304.7 3334.3 3338.9 3361.8 3400.4 6396.9 6438.5 6444.0 6449.7 6482.8 6023.7 6198.1 6235.4 6259.1 6264.5 6441.8 6444.1 6444.3 6444.9 6455.4 5934.8 6198.7 6220.2 6254.4 6262.1 6026.2 6154.2 6161.1 6163.4 6167.1 6528.8 6552.9 6557.8 6559.0 6578.5 4149.5 4308.7 4320.5 4324.5 4331.6 5654.5 5882.3 6126.2 6162.7 6269.2 5964.0 5989.8 6020.9 6045.9 6047.2 5963.7 5971.1 5980.4 6001.0 6002.4 5703.5 5848.6 5850.8 5857.0 5870.4 6011.5 6032.8 6036.2 6039.2 6042.3 5717.9 5847.2 5927.3 5935.7 5944.1 12:38:28 INFO - 2015-10-26 12:38:28,654 DEBUG : BROWSER_OUTPUT: 12:38:28 INFO - 2015-10-26 12:38:28,654 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13465.26 Median:43809.67 stddev:15526.21 (35.4%) stddev-sans-first:15540.04 12:38:28 INFO - 2015-10-26 12:38:28,654 DEBUG : BROWSER_OUTPUT: Values: 670.7 676.8 741.5 742.0 782.0 12683.2 12695.9 13140.9 13343.1 13355.3 11704.1 12264.5 12319.1 12346.6 12356.2 560.6 589.3 599.7 599.7 600.4 505.3 511.5 593.3 598.6 599.5 620.5 621.0 624.3 624.4 626.6 535.8 538.6 622.8 623.2 623.9 441.3 445.9 453.6 453.9 454.2 12162.0 12439.2 12454.3 12464.6 12499.1 11422.5 11670.2 11859.2 12091.9 12114.8 10178.2 10451.6 10650.0 10703.3 10901.7 3484.6 3572.7 3589.0 3599.5 3707.9 47314.5 48022.0 48230.8 48302.8 48522.0 9180.2 9382.5 9446.7 9542.1 9685.8 16186.8 16447.5 16864.8 16867.4 16948.3 7338.8 7449.7 7489.6 7503.5 7663.8 4068.7 4241.6 4263.2 4275.6 4375.2 2813.6 2843.2 2843.6 2845.4 2848.0 481.4 487.1 497.2 503.8 504.1 567.3 596.3 617.8 665.9 727.3 9412.4 9946.2 10080.0 10123.9 10130.7 682.9 714.8 720.2 726.5 728.3 4197.6 4511.3 4524.1 4598.5 4630.0 60022.1 60608.4 60631.0 60634.4 60708.4 48459.5 48784.3 48850.5 49351.8 51075.5 43098.1 43248.8 43751.6 43867.8 44273.3 8641.9 8975.4 9215.7 9315.1 9547.4 29285.4 29388.7 31456.7 31559.4 31599.5 39493.2 39893.8 40129.0 40211.0 40245.2 8645.7 8728.7 8760.1 8830.7 9212.4 13039.3 13064.6 13141.2 13246.7 13262.3 12074.2 12278.6 12293.3 12340.3 12457.8 11763.9 12019.5 12181.7 12281.3 12489.1 8493.4 11794.8 12081.0 12229.9 12519.2 10529.3 10648.3 10876.4 10952.8 11253.4 10315.8 10472.6 10488.3 10727.4 10840.7 12:38:28 INFO - 2015-10-26 12:38:28,655 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:38:28 INFO - 2015-10-26 12:38:28,655 DEBUG : BROWSER_OUTPUT: 12:38:29 INFO - 2015-10-26 12:38:29,095 INFO : Browser exited with error code: 0 12:38:29 INFO - 2015-10-26 12:38:29,835 INFO : Completed test dromaeo_css (00:18:38) 12:38:29 INFO - 2015-10-26 12:38:29,835 INFO : Starting test kraken 12:38:29 INFO - 2015-10-26 12:38:29,835 DEBUG : operating with platform_type : w7_ 12:38:29 INFO - 2015-10-26 12:38:29,835 INFO : Initialising browser for kraken test... 12:38:29 INFO - 2015-10-26 12:38:29,861 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpglnl2t\profile http://localhost:49319/getInfo.html 12:38:34 INFO - 2015-10-26 12:38:34,645 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:38:34 INFO - 2015-10-26 12:38:34,645 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:38:34 INFO - 2015-10-26 12:38:34,645 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 12:38:34 INFO - 2015-10-26 12:38:34,645 DEBUG : BROWSER_OUTPUT: __metrics 12:38:34 INFO - 2015-10-26 12:38:34,700 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:38:35 INFO - 2015-10-26 12:38:35,684 INFO : Browser initialized. 12:38:35 INFO - 2015-10-26 12:38:35,684 INFO : Running cycle 1/1 for kraken test... 12:38:35 INFO - 2015-10-26 12:38:35,684 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpglnl2t\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:38:47 INFO - 2015-10-26 12:38:47,086 DEBUG : BROWSER_OUTPUT: RSS: Main: 130469888 12:38:47 INFO - 2015-10-26 12:38:47,086 DEBUG : BROWSER_OUTPUT: 12:38:49 INFO - 2015-10-26 12:38:49,447 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49319/tests/kraken/driver.html?testName=audio-beat-detection) 12:38:50 INFO - 2015-10-26 12:38:50,022 DEBUG : BROWSER_OUTPUT: RSS: Main: 164749312 12:38:50 INFO - 2015-10-26 12:38:50,022 DEBUG : BROWSER_OUTPUT: 12:38:52 INFO - 2015-10-26 12:38:52,582 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49319/tests/kraken/driver.html?testName=audio-dft) 12:38:53 INFO - 2015-10-26 12:38:53,150 DEBUG : BROWSER_OUTPUT: RSS: Main: 168235008 12:38:53 INFO - 2015-10-26 12:38:53,150 DEBUG : BROWSER_OUTPUT: 12:38:55 INFO - 2015-10-26 12:38:55,897 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49319/tests/kraken/driver.html?testName=audio-fft) 12:38:56 INFO - 2015-10-26 12:38:56,471 DEBUG : BROWSER_OUTPUT: RSS: Main: 161476608 12:38:56 INFO - 2015-10-26 12:38:56,471 DEBUG : BROWSER_OUTPUT: 12:38:58 INFO - 2015-10-26 12:38:58,486 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49319/tests/kraken/driver.html?testName=audio-oscillator) 12:38:59 INFO - 2015-10-26 12:38:59,049 DEBUG : BROWSER_OUTPUT: RSS: Main: 160116736 12:38:59 INFO - 2015-10-26 12:38:59,049 DEBUG : BROWSER_OUTPUT: 12:39:01 INFO - 2015-10-26 12:39:01,184 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49319/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:39:01 INFO - 2015-10-26 12:39:01,740 DEBUG : BROWSER_OUTPUT: RSS: Main: 157204480 12:39:01 INFO - 2015-10-26 12:39:01,740 DEBUG : BROWSER_OUTPUT: 12:39:04 INFO - 2015-10-26 12:39:04,801 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49319/tests/kraken/driver.html?testName=imaging-darkroom) 12:39:05 INFO - 2015-10-26 12:39:05,375 DEBUG : BROWSER_OUTPUT: RSS: Main: 152936448 12:39:05 INFO - 2015-10-26 12:39:05,375 DEBUG : BROWSER_OUTPUT: 12:39:08 INFO - 2015-10-26 12:39:08,697 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49319/tests/kraken/driver.html?testName=imaging-desaturate) 12:39:09 INFO - 2015-10-26 12:39:09,263 DEBUG : BROWSER_OUTPUT: RSS: Main: 153374720 12:39:09 INFO - 2015-10-26 12:39:09,263 DEBUG : BROWSER_OUTPUT: 12:39:12 INFO - 2015-10-26 12:39:12,030 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49319/tests/kraken/driver.html?testName=json-parse-financial) 12:39:12 INFO - 2015-10-26 12:39:12,598 DEBUG : BROWSER_OUTPUT: RSS: Main: 153235456 12:39:12 INFO - 2015-10-26 12:39:12,598 DEBUG : BROWSER_OUTPUT: 12:39:14 INFO - 2015-10-26 12:39:14,392 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49319/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:39:14 INFO - 2015-10-26 12:39:14,947 DEBUG : BROWSER_OUTPUT: RSS: Main: 183488512 12:39:14 INFO - 2015-10-26 12:39:14,947 DEBUG : BROWSER_OUTPUT: 12:39:16 INFO - 2015-10-26 12:39:16,831 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49319/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:39:17 INFO - 2015-10-26 12:39:17,398 DEBUG : BROWSER_OUTPUT: RSS: Main: 188502016 12:39:17 INFO - 2015-10-26 12:39:17,398 DEBUG : BROWSER_OUTPUT: 12:39:19 INFO - 2015-10-26 12:39:19,654 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49319/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:39:20 INFO - 2015-10-26 12:39:20,220 DEBUG : BROWSER_OUTPUT: RSS: Main: 194179072 12:39:20 INFO - 2015-10-26 12:39:20,220 DEBUG : BROWSER_OUTPUT: 12:39:22 INFO - 2015-10-26 12:39:22,641 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49319/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:39:23 INFO - 2015-10-26 12:39:23,196 DEBUG : BROWSER_OUTPUT: RSS: Main: 196034560 12:39:23 INFO - 2015-10-26 12:39:23,196 DEBUG : BROWSER_OUTPUT: 12:39:26 INFO - 2015-10-26 12:39:26,217 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49319/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:39:26 INFO - 2015-10-26 12:39:26,779 DEBUG : BROWSER_OUTPUT: RSS: Main: 195809280 12:39:26 INFO - 2015-10-26 12:39:26,779 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,618 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49319/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49319/tests/kraken/driver.html?testName=ai-astar) 12:39:28 INFO - 2015-10-26 12:39:28,874 DEBUG : BROWSER_OUTPUT: RSS: Main: 242421760 12:39:28 INFO - 2015-10-26 12:39:28,874 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,874 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:39:28 INFO - 2015-10-26 12:39:28,875 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:39:28 INFO - 2015-10-26 12:39:28,875 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:39:28 INFO - 2015-10-26 12:39:28,875 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:39:28 INFO - 2015-10-26 12:39:28,875 DEBUG : BROWSER_OUTPUT: |0;ai-astar;89;95;95;86;93;95;95;95;94;94 12:39:28 INFO - 2015-10-26 12:39:28,875 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;132;131;154;154;127;130;137;135;156;133 12:39:28 INFO - 2015-10-26 12:39:28,875 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;159;158;162;153;153;151;151;151;150 12:39:28 INFO - 2015-10-26 12:39:28,875 DEBUG : BROWSER_OUTPUT: |3;audio-fft;84;99;93;106;78;77;76;77;99;83 12:39:28 INFO - 2015-10-26 12:39:28,875 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;95;113;96;96;96;119;96;96;118 12:39:28 INFO - 2015-10-26 12:39:28,875 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;121;116;117;116;116;116;116;116;117;117 12:39:28 INFO - 2015-10-26 12:39:28,875 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;139;139;140;139;139;139;140;140;139;139 12:39:28 INFO - 2015-10-26 12:39:28,875 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;90;89;89;89;89;89;89;90;90;89 12:39:28 INFO - 2015-10-26 12:39:28,875 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;80;81;81;80;81;80;82;80;82;93 12:39:28 INFO - 2015-10-26 12:39:28,875 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;77;77;77;77;78;80;77;77;77;77 12:39:28 INFO - 2015-10-26 12:39:28,875 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;96;96;96;99;97;97;96;98;98;98 12:39:28 INFO - 2015-10-26 12:39:28,875 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;123;121;124;119;120;119;120;124;126;121 12:39:28 INFO - 2015-10-26 12:39:28,877 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;185;192;195;193;193;187;194;193;193;189 12:39:28 INFO - 2015-10-26 12:39:28,877 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;86;84;86;84;85;85;87;84;87;85 12:39:28 INFO - 2015-10-26 12:39:28,877 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:39:28 INFO - 2015-10-26 12:39:28,877 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:39:28 INFO - 2015-10-26 12:39:28,877 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,818 12:39:28 INFO - 2015-10-26 12:39:28,877 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:39:28 INFO - 2015-10-26 12:39:28,877 DEBUG : BROWSER_OUTPUT: __startTimestamp1445888368875__endTimestamp 12:39:28 INFO - 2015-10-26 12:39:28,877 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,877 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:39:28 INFO - 2015-10-26 12:39:28,877 DEBUG : BROWSER_OUTPUT: Number of tests: 14 12:39:28 INFO - 2015-10-26 12:39:28,877 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,877 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:93.10 Median:95.00 stddev:3.11 (3.3%) stddev-sans-first:2.92 12:39:28 INFO - 2015-10-26 12:39:28,877 DEBUG : BROWSER_OUTPUT: Values: 89.0 95.0 95.0 86.0 93.0 95.0 95.0 95.0 94.0 94.0 12:39:28 INFO - 2015-10-26 12:39:28,877 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,878 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:138.90 Median:136.00 stddev:11.22 (8.2%) stddev-sans-first:11.62 12:39:28 INFO - 2015-10-26 12:39:28,878 DEBUG : BROWSER_OUTPUT: Values: 132.0 131.0 154.0 154.0 127.0 130.0 137.0 135.0 156.0 133.0 12:39:28 INFO - 2015-10-26 12:39:28,878 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,878 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:154.70 Median:155.50 stddev:4.35 (2.8%) stddev-sans-first:4.32 12:39:28 INFO - 2015-10-26 12:39:28,878 DEBUG : BROWSER_OUTPUT: Values: 159.0 159.0 158.0 162.0 153.0 153.0 151.0 151.0 151.0 150.0 12:39:28 INFO - 2015-10-26 12:39:28,878 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,878 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.20 Median:83.50 stddev:11.11 (13.3%) stddev-sans-first:11.73 12:39:28 INFO - 2015-10-26 12:39:28,878 DEBUG : BROWSER_OUTPUT: Values: 84.0 99.0 93.0 106.0 78.0 77.0 76.0 77.0 99.0 83.0 12:39:28 INFO - 2015-10-26 12:39:28,878 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,878 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.00 Median:96.00 stddev:10.24 (10.7%) stddev-sans-first:10.54 12:39:28 INFO - 2015-10-26 12:39:28,878 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 113.0 96.0 96.0 96.0 119.0 96.0 96.0 118.0 12:39:28 INFO - 2015-10-26 12:39:28,878 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,881 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:116.50 stddev:1.55 (1.3%) stddev-sans-first:0.50 12:39:28 INFO - 2015-10-26 12:39:28,881 DEBUG : BROWSER_OUTPUT: Values: 121.0 116.0 117.0 116.0 116.0 116.0 116.0 116.0 117.0 117.0 12:39:28 INFO - 2015-10-26 12:39:28,881 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,881 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:139.30 Median:139.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 12:39:28 INFO - 2015-10-26 12:39:28,881 DEBUG : BROWSER_OUTPUT: Values: 139.0 139.0 140.0 139.0 139.0 139.0 140.0 140.0 139.0 139.0 12:39:28 INFO - 2015-10-26 12:39:28,881 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,882 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:89.30 Median:89.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 12:39:28 INFO - 2015-10-26 12:39:28,882 DEBUG : BROWSER_OUTPUT: Values: 90.0 89.0 89.0 89.0 89.0 89.0 89.0 90.0 90.0 89.0 12:39:28 INFO - 2015-10-26 12:39:28,882 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,882 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:82.00 Median:81.00 stddev:3.94 (4.9%) stddev-sans-first:4.12 12:39:28 INFO - 2015-10-26 12:39:28,884 DEBUG : BROWSER_OUTPUT: Values: 80.0 81.0 81.0 80.0 81.0 80.0 82.0 80.0 82.0 93.0 12:39:28 INFO - 2015-10-26 12:39:28,884 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,884 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:77.40 Median:77.00 stddev:0.97 (1.3%) stddev-sans-first:1.01 12:39:28 INFO - 2015-10-26 12:39:28,884 DEBUG : BROWSER_OUTPUT: Values: 77.0 77.0 77.0 77.0 78.0 80.0 77.0 77.0 77.0 77.0 12:39:28 INFO - 2015-10-26 12:39:28,884 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,884 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:97.10 Median:97.50 stddev:1.10 (1.1%) stddev-sans-first:1.09 12:39:28 INFO - 2015-10-26 12:39:28,884 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 99.0 97.0 97.0 96.0 98.0 98.0 98.0 12:39:28 INFO - 2015-10-26 12:39:28,884 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,884 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:121.70 Median:122.00 stddev:2.41 (2.0%) stddev-sans-first:2.51 12:39:28 INFO - 2015-10-26 12:39:28,885 DEBUG : BROWSER_OUTPUT: Values: 123.0 121.0 124.0 119.0 120.0 119.0 120.0 124.0 126.0 121.0 12:39:28 INFO - 2015-10-26 12:39:28,885 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,885 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.40 Median:193.00 stddev:3.27 (1.7%) stddev-sans-first:2.52 12:39:28 INFO - 2015-10-26 12:39:28,887 DEBUG : BROWSER_OUTPUT: Values: 185.0 192.0 195.0 193.0 193.0 187.0 194.0 193.0 193.0 189.0 12:39:28 INFO - 2015-10-26 12:39:28,887 DEBUG : BROWSER_OUTPUT: 12:39:28 INFO - 2015-10-26 12:39:28,887 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:85.30 Median:85.50 stddev:1.16 (1.4%) stddev-sans-first:1.20 12:39:28 INFO - 2015-10-26 12:39:28,888 DEBUG : BROWSER_OUTPUT: Values: 86.0 84.0 86.0 84.0 85.0 85.0 87.0 84.0 87.0 85.0 12:39:28 INFO - 2015-10-26 12:39:28,888 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:39:28 INFO - 2015-10-26 12:39:28,888 DEBUG : BROWSER_OUTPUT: 12:39:29 INFO - 2015-10-26 12:39:29,467 INFO : Browser exited with error code: 0 12:39:30 INFO - 2015-10-26 12:39:30,404 INFO : Completed test kraken (00:01:00) 12:39:30 INFO - 2015-10-26 12:39:30,404 INFO : Starting test v8_7 12:39:30 INFO - 2015-10-26 12:39:30,404 DEBUG : operating with platform_type : w7_ 12:39:30 INFO - 2015-10-26 12:39:30,404 INFO : Initialising browser for v8_7 test... 12:39:30 INFO - 2015-10-26 12:39:30,430 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpbjoaac\profile http://localhost:49319/getInfo.html 12:39:34 INFO - 2015-10-26 12:39:34,552 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:39:34 INFO - 2015-10-26 12:39:34,552 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:39:34 INFO - 2015-10-26 12:39:34,552 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 12:39:34 INFO - 2015-10-26 12:39:34,552 DEBUG : BROWSER_OUTPUT: __metrics 12:39:34 INFO - 2015-10-26 12:39:34,648 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:39:35 INFO - 2015-10-26 12:39:35,411 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 12:39:35 INFO - 2015-10-26 12:39:35,625 DEBUG : BROWSER_OUTPUT: console.error: 12:39:35 INFO - 2015-10-26 12:39:35,625 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] 12:39:35 INFO - 2015-10-26 12:39:35,625 DEBUG : BROWSER_OUTPUT: Stack: 12:39:35 INFO - 2015-10-26 12:39:35,625 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 12:39:35 INFO - 2015-10-26 12:39:35,625 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 12:39:35 INFO - 2015-10-26 12:39:35,625 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 12:39:35 INFO - 2015-10-26 12:39:35,625 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 12:39:35 INFO - 2015-10-26 12:39:35,625 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 12:39:35 INFO - 2015-10-26 12:39:35,625 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 12:39:35 INFO - 2015-10-26 12:39:35,625 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 12:39:35 INFO - 2015-10-26 12:39:35,625 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 12:39:35 INFO - 2015-10-26 12:39:35,625 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 12:39:35 INFO - 2015-10-26 12:39:35,627 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 12:39:35 INFO - 2015-10-26 12:39:35,627 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 12:39:35 INFO - 2015-10-26 12:39:35,627 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 12:39:35 INFO - 2015-10-26 12:39:35,627 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6602.24 12:40:11 INFO - RETURN: kraken: 1576.20 12:40:11 INFO - RETURN: v8_7: 20648.00 12:40:11 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,25]]", "result": "20648.00"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,25]]", "result": "1576.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,25]]", "result": "6602.24"}}} 12:40:11 INFO - Return code: 0 12:40:11 INFO - # TBPL SUCCESS # 12:40:11 INFO - Running post-action listener: _resource_record_post_action 12:40:11 INFO - Running post-run listener: _resource_record_post_run 12:40:12 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 136330752; Write bytes: 433053696; Read time: 1630870; Write time: 4447700 12:40:12 INFO - install - Wall time: 2s; CPU: 14.0%; Read bytes: 0; Write bytes: 2331648; Read time: 0; Write time: 54640 12:40:12 INFO - run-tests - Wall time: 1225s; CPU: 13.0%; Read bytes: 136330752; Write bytes: 407694336; Read time: 1630870; Write time: 4374890 12:40:12 INFO - Running post-run listener: _upload_blobber_files 12:40:12 INFO - Blob upload gear active. 12:40:12 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 12:40:12 INFO - Copying logs to upload dir... 12:40:12 INFO - mkdir: C:\slave\test-pgo\build\upload\logs program finished with exit code 0 elapsedTime=1446.136000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 7 secs) (at 2015-10-26 12:40:12.848924) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-26 12:40:12.852620) ========= '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-118 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-118 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-118 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/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/K9hqn8jXRDGVMarb8rDeLQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-26 12:40:13.075709) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 12:40:13.076051) ========= '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-118 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-118 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-118 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-26 12:40:13.289432) ========= ========= Started reboot slave lost (results: 0, elapsed: 10 secs) (at 2015-10-26 12:40:13.289812) ========= ========= Finished reboot slave lost (results: 0, elapsed: 10 secs) (at 2015-10-26 12:40:23.463553) =========