builder: mozilla-inbound_win7-ix_test-dromaeojs-pgo
slave: t-w732-ix-063
starttime: 1445497196.11
results: success (0)
buildid: 20151021193145
builduid: 1e8271267dd140e78a555e1d96db5fe9
revision: 2708f83e4c15836f7925e69a4227778ae9a1809e
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-21 23:59:56.114633) =========
master: http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-21 23:59:56.115925) =========
========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-21 23:59:56.116240) =========
'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-063
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-063
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-063
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.585000
basedir: 'C:\\slave\\test-pgo'
========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-21 23:59:56.800763) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2015-10-21 23:59:56.801078) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2015-10-21 23:59:58.409349) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2015-10-21 23:59:58.409841) =========
'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-063
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-063
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-063
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: 1 secs) (at 2015-10-21 23:59:59.880575) =========
========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-21 23:59:59.880887) =========
script_repo_url: https://hg.mozilla.org/build/mozharness
========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-21 23:59:59.881257) =========
========= Started 'bash -c ...' (results: 0, elapsed: 16 secs) (at 2015-10-21 23:59:59.881531) =========
'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-063
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-063
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-063
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
--00:00:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 2.27 GB/s
00:00:15 (2.27 GB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=0.857000
========= Finished 'bash -c ...' (results: 0, elapsed: 16 secs) (at 2015-10-22 00:00:15.910956) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 13 secs) (at 2015-10-22 00:00:15.911288) =========
'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-063
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-063
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-063
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=11.701000
========= Finished 'rm -rf ...' (results: 0, elapsed: 13 secs) (at 2015-10-22 00:00:28.934963) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-22 00:00:28.935331) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 2708f83e4c15836f7925e69a4227778ae9a1809e --destination scripts --debug'
in dir C:\slave\test-pgo\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 2708f83e4c15836f7925e69a4227778ae9a1809e --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-063
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-063
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-063
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
2015-10-22 00:00:29,607 truncating revision to first 12 chars
2015-10-22 00:00:29,607 Setting DEBUG logging.
2015-10-22 00:00:29,607 attempt 1/10
2015-10-22 00:00:29,607 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/2708f83e4c15?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2015-10-22 00:00:30,608 unpacking tar archive at: mozilla-inbound-2708f83e4c15/testing/mozharness/
program finished with exit code 0
elapsedTime=1.674000
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-22 00:00:30.669785) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-22 00:00:30.670085) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-22 00:00:30.686702) =========
========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-22 00:00:30.686980) =========
TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production
========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-22 00:00:30.687433) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 6 secs) (at 2015-10-22 00:00:30.687856) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Mozilla-Inbound' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Mozilla-Inbound'
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', 'Mozilla-Inbound', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-063
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-063
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-063
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
00:00:31 INFO - MultiFileLogger online at 20151022 00:00:31 in C:\slave\test-pgo
00:00:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound
00:00:31 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json.
00:00:31 INFO - {'append_to_log': False,
00:00:31 INFO - 'base_work_dir': 'C:\\slave\\test-pgo',
00:00:31 INFO - 'blob_upload_branch': 'Mozilla-Inbound',
00:00:31 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt',
00:00:31 INFO - 'branch': 'Mozilla-Inbound',
00:00:31 INFO - 'buildbot_json_path': 'buildprops.json',
00:00:31 INFO - 'config_files': ('talos/windows_config.py',),
00:00:31 INFO - 'default_actions': ('clobber',
00:00:31 INFO - 'read-buildbot-config',
00:00:31 INFO - 'download-and-extract',
00:00:31 INFO - 'populate-webroot',
00:00:31 INFO - 'create-virtualenv',
00:00:31 INFO - 'install',
00:00:31 INFO - 'run-tests'),
00:00:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
00:00:31 INFO - 'download_minidump_stackwalk': True,
00:00:31 INFO - 'download_symbols': 'ondemand',
00:00:31 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python',
00:00:31 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'),
00:00:31 INFO - 'hg': 'c:/mozilla-build/hg/hg',
00:00:31 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python',
00:00:31 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'),
00:00:31 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
00:00:31 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
00:00:31 INFO - 'C:/mozilla-build/tooltool.py'),
00:00:31 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
00:00:31 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
00:00:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
00:00:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
00:00:31 INFO - 'installer_path': 'installer.exe',
00:00:31 INFO - 'log_level': 'info',
00:00:31 INFO - 'log_name': 'talos',
00:00:31 INFO - 'log_to_console': True,
00:00:31 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
00:00:31 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
00:00:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
00:00:31 INFO - 'opt_config_files': (),
00:00:31 INFO - 'pip_index': False,
00:00:31 INFO - 'sps_profile': False,
00:00:31 INFO - 'sps_profile_interval': 0,
00:00:31 INFO - 'suite': 'dromaeojs',
00:00:31 INFO - 'system_bits': '32',
00:00:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
00:00:31 INFO - 'title': 'T-W732-IX-063',
00:00:31 INFO - 'use_talos_json': True,
00:00:31 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
00:00:31 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv',
00:00:31 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
00:00:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
00:00:31 INFO - 'work_dir': 'build'}
00:00:31 INFO - #####
00:00:31 INFO - ##### Running clobber step.
00:00:31 INFO - #####
00:00:31 INFO - Running pre-action listener: _resource_record_pre_action
00:00:31 INFO - Running main action method: clobber
00:00:31 INFO - rmtree: C:\slave\test-pgo\build
00:00:31 INFO - Using _rmtree_windows ...
00:00:31 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1
00:02:07 INFO - Running post-action listener: _resource_record_post_action
00:02:07 INFO - #####
00:02:07 INFO - ##### Running read-buildbot-config step.
00:02:07 INFO - #####
00:02:07 INFO - Running pre-action listener: _resource_record_pre_action
00:02:07 INFO - Running main action method: read_buildbot_config
00:02:07 INFO - Using buildbot properties:
00:02:07 INFO - {
00:02:07 INFO - "properties": {
00:02:07 INFO - "buildnumber": 462,
00:02:07 INFO - "product": "firefox",
00:02:07 INFO - "script_repo_revision": "production",
00:02:07 INFO - "builddir": "mozilla-inbound_win7-ix_test-dromaeojs",
00:02:07 INFO - "repository": "",
00:02:07 INFO - "buildername": "Windows 7 32-bit mozilla-inbound pgo talos dromaeojs",
00:02:07 INFO - "buildid": "20151021193145",
00:02:07 INFO - "slavename": "t-w732-ix-063",
00:02:07 INFO - "pgo_build": "True",
00:02:07 INFO - "basedir": "C:\\slave\\test-pgo",
00:02:07 INFO - "project": "",
00:02:07 INFO - "platform": "win7-ix",
00:02:07 INFO - "master": "http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/",
00:02:07 INFO - "slavebuilddir": "test",
00:02:07 INFO - "scheduler": "tests-mozilla-inbound-win32-pgo-talos",
00:02:07 INFO - "branch": "mozilla-inbound",
00:02:07 INFO - "repo_path": "integration/mozilla-inbound",
00:02:07 INFO - "stage_platform": "win32-pgo",
00:02:07 INFO - "builduid": "1e8271267dd140e78a555e1d96db5fe9",
00:02:07 INFO - "revision": "2708f83e4c15836f7925e69a4227778ae9a1809e"
00:02:07 INFO - },
00:02:07 INFO - "sourcestamp": {
00:02:07 INFO - "repository": "",
00:02:07 INFO - "hasPatch": false,
00:02:07 INFO - "project": "",
00:02:07 INFO - "branch": "mozilla-inbound-win32-pgo-talos",
00:02:07 INFO - "changes": [
00:02:07 INFO - {
00:02:07 INFO - "category": null,
00:02:07 INFO - "files": [
00:02:07 INFO - {
00:02:07 INFO - "url": null,
00:02:07 INFO - "name": "https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip"
00:02:07 INFO - }
00:02:07 INFO - ],
00:02:07 INFO - "repository": "",
00:02:07 INFO - "rev": "2708f83e4c15836f7925e69a4227778ae9a1809e",
00:02:07 INFO - "who": "sendchange",
00:02:07 INFO - "when": 1445497125,
00:02:07 INFO - "number": 6557838,
00:02:07 INFO - "comments": "",
00:02:07 INFO - "project": "",
00:02:07 INFO - "at": "Wed 21 Oct 2015 23:58:45",
00:02:07 INFO - "branch": "mozilla-inbound-win32-pgo-talos",
00:02:07 INFO - "revlink": "",
00:02:07 INFO - "properties": [
00:02:07 INFO - [
00:02:07 INFO - "buildid",
00:02:07 INFO - "20151021193145",
00:02:07 INFO - "Change"
00:02:07 INFO - ],
00:02:07 INFO - [
00:02:07 INFO - "builduid",
00:02:07 INFO - "1e8271267dd140e78a555e1d96db5fe9",
00:02:07 INFO - "Change"
00:02:07 INFO - ],
00:02:07 INFO - [
00:02:07 INFO - "pgo_build",
00:02:07 INFO - "True",
00:02:07 INFO - "Change"
00:02:07 INFO - ]
00:02:07 INFO - ],
00:02:07 INFO - "revision": "2708f83e4c15836f7925e69a4227778ae9a1809e"
00:02:07 INFO - }
00:02:07 INFO - ],
00:02:07 INFO - "revision": "2708f83e4c15836f7925e69a4227778ae9a1809e"
00:02:07 INFO - }
00:02:07 INFO - }
00:02:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip.
00:02:07 INFO - Running post-action listener: _resource_record_post_action
00:02:07 INFO - #####
00:02:07 INFO - ##### Running download-and-extract step.
00:02:07 INFO - #####
00:02:07 INFO - Running pre-action listener: _resource_record_pre_action
00:02:07 INFO - Running main action method: download_and_extract
00:02:07 INFO - mkdir: C:\slave\test-pgo\build\tests
00:02: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')]}
00:02:07 INFO - https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net
00:02:07 INFO - trying https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/test_packages.json
00:02:07 INFO - Downloading https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json
00:02:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1
00:02:08 INFO - Downloaded 1221 bytes.
00:02:08 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json
00:02:08 INFO - Using the following test package requirements:
00:02:08 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'],
00:02:08 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
00:02:08 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'],
00:02:08 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
00:02:08 INFO - u'jsshell-win32.zip'],
00:02:08 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
00:02:08 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'],
00:02:08 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'],
00:02:08 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
00:02:08 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'],
00:02:08 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
00:02:08 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'],
00:02:08 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
00:02:08 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'],
00:02:08 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'],
00:02:08 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
00:02:08 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']}
00:02:08 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common
00:02:08 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')]}
00:02:08 INFO - https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
00:02:08 INFO - trying https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip
00:02:08 INFO - Downloading https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/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
00:02:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/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
00:02:09 INFO - Downloaded 18005601 bytes.
00:02:09 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
00:02:09 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip
00:02:09 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760
00:02:41 INFO - Return code: 0
00:02:41 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
00:02:41 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')]}
00:02:41 INFO - https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
00:02:41 INFO - trying https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip
00:02:41 INFO - Downloading https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/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
00:02:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/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
00:02:42 INFO - Downloaded 18005601 bytes.
00:02:42 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
00:02:42 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip
00:02:42 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760
00:03:06 INFO - Return code: 0
00:03:06 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')]}
00:03:06 INFO - https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net
00:03:06 INFO - trying https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip
00:03:06 INFO - Downloading https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/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
00:03:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/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
00:03:08 INFO - Downloaded 11128611 bytes.
00:03:08 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
00:03:08 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip
00:03:08 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760
00:03:12 INFO - Return code: 0
00:03:12 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')]}
00:03:12 INFO - https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net
00:03:12 INFO - trying https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
00:03:12 INFO - Downloading https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip
00:03:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/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
00:03:15 INFO - Downloaded 53253677 bytes.
00:03:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
00:03:15 INFO - mkdir: C:\slave\test-pgo\properties
00:03:15 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url
00:03:15 INFO - Writing to file C:\slave\test-pgo\properties\build_url
00:03:15 INFO - Contents:
00:03:15 INFO - build_url:https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
00:03:15 INFO - Running post-action listener: _resource_record_post_action
00:03:15 INFO - Running post-action listener: set_extra_try_arguments
00:03:15 INFO - #####
00:03:15 INFO - ##### Running populate-webroot step.
00:03:15 INFO - #####
00:03:15 INFO - Running pre-action listener: _resource_record_pre_action
00:03:15 INFO - Running main action method: populate_webroot
00:03:15 INFO - Guessing talos json url...
00:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
00:03:15 INFO - https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net
00:03:15 INFO - trying https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.txt
00:03:15 INFO - Downloading https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt
00:03:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/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
00:03:15 INFO - Downloaded 111 bytes.
00:03:15 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt
00:03:15 INFO - Contents:
00:03:15 INFO - 20151021193145
00:03:15 INFO - https://hg.mozilla.org/integration/mozilla-inbound/rev/2708f83e4c15836f7925e69a4227778ae9a1809e
00:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
00:03:15 INFO - trying https://hg.mozilla.org/integration/mozilla-inbound/raw-file/2708f83e4c/testing/talos/talos.json
00:03:15 INFO - Downloading https://hg.mozilla.org/integration/mozilla-inbound/raw-file/2708f83e4c/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json
00:03:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/mozilla-inbound/raw-file/2708f83e4c/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1
00:03:16 INFO - Downloaded 4264 bytes.
00:03:16 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
00:03:16 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
00:03:16 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs',
00:03:16 INFO - u'../fennec_ids.txt'],
00:03:16 INFO - u'tests': [u'tcheck2']},
00:03:16 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
00:03:16 INFO - u'--tppagecycles',
00:03:16 INFO - u'7'],
00:03:16 INFO - u'tests': [u'tsvgm']}},
00:03:16 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']},
00:03:16 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'],
00:03:16 INFO - u'tests': [u'tresize', u'tcanvasmark']},
00:03:16 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']},
00:03:16 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'],
00:03:16 INFO - u'tests': [u'dromaeo_css',
00:03:16 INFO - u'kraken',
00:03:16 INFO - u'v8_7']},
00:03:16 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
00:03:16 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
00:03:16 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
00:03:16 INFO - u'talos_options': [u'--e10s'],
00:03:16 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
00:03:16 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
00:03:16 INFO - u'tests': [u'damp', u'tps']},
00:03:16 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
00:03:16 INFO - u'talos_options': [u'--e10s'],
00:03:16 INFO - u'tests': [u'damp', u'tps']},
00:03:16 INFO - u'g3': {u'tests': [u'dromaeo_dom']},
00:03:16 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
00:03:16 INFO - u'other': {u'tests': [u'a11yr',
00:03:16 INFO - u'ts_paint',
00:03:16 INFO - u'tpaint',
00:03:16 INFO - u'sessionrestore',
00:03:16 INFO - u'sessionrestore_no_auto_restore']},
00:03:16 INFO - u'other-e10s': {u'talos_options': [u'--e10s'],
00:03:16 INFO - u'tests': [u'a11yr',
00:03:16 INFO - u'ts_paint',
00:03:16 INFO - u'tpaint',
00:03:16 INFO - u'sessionrestore',
00:03:16 INFO - u'sessionrestore_no_auto_restore']},
00:03:16 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'],
00:03:16 INFO - u'tests': [u'a11yr',
00:03:16 INFO - u'ts_paint',
00:03:16 INFO - u'tpaint',
00:03:16 INFO - u'sessionrestore',
00:03:16 INFO - u'sessionrestore_no_auto_restore']},
00:03:16 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'],
00:03:16 INFO - u'tests': [u'a11yr',
00:03:16 INFO - u'ts_paint',
00:03:16 INFO - u'tpaint',
00:03:16 INFO - u'sessionrestore',
00:03:16 INFO - u'sessionrestore_no_auto_restore']},
00:03:16 INFO - u'other_l64': {u'tests': [u'a11yr',
00:03:16 INFO - u'ts_paint',
00:03:16 INFO - u'tpaint',
00:03:16 INFO - u'sessionrestore',
00:03:16 INFO - u'sessionrestore_no_auto_restore']},
00:03:16 INFO - u'other_nol64': {u'tests': [u'a11yr',
00:03:16 INFO - u'ts_paint',
00:03:16 INFO - u'tpaint',
00:03:16 INFO - u'sessionrestore',
00:03:16 INFO - u'sessionrestore_no_auto_restore']},
00:03:16 INFO - u'svgr': {u'tests': [u'tsvgx',
00:03:16 INFO - u'tsvgr_opacity',
00:03:16 INFO - u'tart',
00:03:16 INFO - u'tscrollx',
00:03:16 INFO - u'cart']},
00:03:16 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'],
00:03:16 INFO - u'tests': [u'tsvgx',
00:03:16 INFO - u'tsvgr_opacity',
00:03:16 INFO - u'tart',
00:03:16 INFO - u'tscrollx',
00:03:16 INFO - u'cart']},
00:03:16 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
00:03:16 INFO - u'tests': [u'tp5o']},
00:03:16 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
00:03:16 INFO - u'talos_options': [u'--e10s'],
00:03:16 INFO - u'tests': [u'tp5o']},
00:03:16 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
00:03:16 INFO - u'talos_options': [u'--xperf_path',
00:03:16 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
00:03:16 INFO - u'tests': [u'tp5n']},
00:03:16 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
00:03:16 INFO - u'talos_options': [u'--e10s',
00:03:16 INFO - u'--xperf_path',
00:03:16 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"',
00:03:16 INFO - u'C:/slave/talos-data/talos/xperf.config'],
00:03:16 INFO - u'tests': [u'tp5n']}},
00:03:16 INFO - u'talos.zip': {u'path': u'',
00:03:16 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
00:03:16 INFO - Running post-action listener: _resource_record_post_action
00:03:16 INFO - #####
00:03:16 INFO - ##### Running create-virtualenv step.
00:03:16 INFO - #####
00:03:16 INFO - Running pre-action listener: _resource_record_pre_action
00:03:16 INFO - Running main action method: create_virtualenv
00:03:16 INFO - Creating virtualenv C:\slave\test-pgo\build/venv
00:03:16 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts
00:03:16 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll
00:03:16 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'!
00:03:16 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
00:03:16 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
00:03:23 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe
00:03:28 INFO - Installing distribute......................................................................................................................................................................................done.
00:03:30 INFO - Return code: 0
00:03:30 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv
00:03:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
00:03:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:03:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
00:03:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:03:30 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 0x018D8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01898EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012EE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01208B48>, '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 0x0122A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0195D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-063', 'USERDOMAIN': 'T-W732-IX-063', '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-063', '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
00:03:30 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
00:03:30 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
00:03:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:03:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:03:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:03:30 INFO - 'COMPUTERNAME': 'T-W732-IX-063',
00:03:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:03:30 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:03:30 INFO - 'DCLOCATION': 'SCL3',
00:03:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:03:30 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:03:30 INFO - 'HOMEDRIVE': 'C:',
00:03:30 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:03:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:03:30 INFO - 'KTS_VERSION': '1.19c',
00:03:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:03:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-063',
00:03:30 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:03:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:03:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:03:30 INFO - 'MOZ_NO_REMOTE': '1',
00:03:30 INFO - 'NO_EM_RESTART': '1',
00:03:30 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:03:30 INFO - 'OS': 'Windows_NT',
00:03:30 INFO - 'OURDRIVE': 'C:',
00:03:30 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;',
00:03:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:03:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:03:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:03:30 INFO - 'PROCESSOR_LEVEL': '6',
00:03:30 INFO - 'PROCESSOR_REVISION': '1e05',
00:03:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:03:30 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:03:30 INFO - 'PROMPT': '$P$G',
00:03:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
00:03:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:03:30 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:03:30 INFO - 'PWD': 'C:\\slave\\test-pgo',
00:03:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:03:30 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:03:30 INFO - 'SYSTEMDRIVE': 'C:',
00:03:30 INFO - 'SYSTEMROOT': 'C:\\windows',
00:03:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:03:30 INFO - 'TEST1': 'testie',
00:03:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:03:30 INFO - 'USERDOMAIN': 'T-W732-IX-063',
00:03:30 INFO - 'USERNAME': 'cltbld',
00:03:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:03:30 INFO - 'WINDIR': 'C:\\windows',
00:03:30 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:03:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:03:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:03:31 INFO - Ignoring indexes: http://pypi.python.org/simple/
00:03:31 INFO - Downloading/unpacking pip>=1.5
00:03:31 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache
00:03:37 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpip-1.5.5.tar.gz
00:03:37 INFO - Running setup.py egg_info for package pip
00:03:37 INFO - warning: no files found matching 'pip\cacert.pem'
00:03:37 INFO - warning: no files found matching '*.html' under directory 'docs'
00:03:37 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
00:03:37 INFO - no previously-included directories found matching 'docs\_build\_sources'
00:03:37 INFO - Installing collected packages: pip
00:03:37 INFO - Found existing installation: pip 0.8.2
00:03:37 INFO - Uninstalling pip:
00:03:37 INFO - Successfully uninstalled pip
00:03:37 INFO - Running setup.py install for pip
00:03:37 INFO - warning: no files found matching 'pip\cacert.pem'
00:03:37 INFO - warning: no files found matching '*.html' under directory 'docs'
00:03:37 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
00:03:37 INFO - no previously-included directories found matching 'docs\_build\_sources'
00:03:37 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:37 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:37 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:37 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:37 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:37 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:37 INFO - Successfully installed pip
00:03:37 INFO - Cleaning up...
00:03:37 INFO - Return code: 0
00:03:37 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv
00:03:37 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')]}
00:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:03:37 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')]}
00:03:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:03:37 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 0x018D8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01898EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012EE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01208B48>, '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 0x0122A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0195D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-063', 'USERDOMAIN': 'T-W732-IX-063', '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-063', '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
00:03:38 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
00:03:38 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
00:03:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:03:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:03:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:03:38 INFO - 'COMPUTERNAME': 'T-W732-IX-063',
00:03:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:03:38 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:03:38 INFO - 'DCLOCATION': 'SCL3',
00:03:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:03:38 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:03:38 INFO - 'HOMEDRIVE': 'C:',
00:03:38 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:03:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:03:38 INFO - 'KTS_VERSION': '1.19c',
00:03:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:03:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-063',
00:03:38 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:03:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:03:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:03:38 INFO - 'MOZ_NO_REMOTE': '1',
00:03:38 INFO - 'NO_EM_RESTART': '1',
00:03:38 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:03:38 INFO - 'OS': 'Windows_NT',
00:03:38 INFO - 'OURDRIVE': 'C:',
00:03:38 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;',
00:03:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:03:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:03:38 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:03:38 INFO - 'PROCESSOR_LEVEL': '6',
00:03:38 INFO - 'PROCESSOR_REVISION': '1e05',
00:03:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:03:38 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:03:38 INFO - 'PROMPT': '$P$G',
00:03:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
00:03:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:03:38 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:03:38 INFO - 'PWD': 'C:\\slave\\test-pgo',
00:03:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:03:38 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:03:38 INFO - 'SYSTEMDRIVE': 'C:',
00:03:38 INFO - 'SYSTEMROOT': 'C:\\windows',
00:03:38 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:03:38 INFO - 'TEST1': 'testie',
00:03:38 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:03:38 INFO - 'USERDOMAIN': 'T-W732-IX-063',
00:03:38 INFO - 'USERNAME': 'cltbld',
00:03:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:03:38 INFO - 'WINDIR': 'C:\\windows',
00:03:38 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:03:38 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:03:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:03:40 INFO - Ignoring indexes: https://pypi.python.org/simple/
00:03:40 INFO - Downloading/unpacking psutil>=0.7.1
00:03:40 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
00:03:40 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
00:03:41 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
00:03:41 INFO - Installing collected packages: psutil
00:03:41 INFO - Successfully installed psutil
00:03:41 INFO - Cleaning up...
00:03:41 INFO - Return code: 0
00:03:41 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv
00:03:41 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')]}
00:03:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:03:41 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')]}
00:03:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:03:41 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 0x018D8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01898EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012EE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01208B48>, '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 0x0122A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0195D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-063', 'USERDOMAIN': 'T-W732-IX-063', '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-063', '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
00:03:41 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
00:03:41 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
00:03:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:03:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:03:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:03:41 INFO - 'COMPUTERNAME': 'T-W732-IX-063',
00:03:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:03:41 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:03:41 INFO - 'DCLOCATION': 'SCL3',
00:03:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:03:41 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:03:41 INFO - 'HOMEDRIVE': 'C:',
00:03:41 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:03:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:03:41 INFO - 'KTS_VERSION': '1.19c',
00:03:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:03:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-063',
00:03:41 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:03:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:03:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:03:41 INFO - 'MOZ_NO_REMOTE': '1',
00:03:41 INFO - 'NO_EM_RESTART': '1',
00:03:41 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:03:41 INFO - 'OS': 'Windows_NT',
00:03:41 INFO - 'OURDRIVE': 'C:',
00:03:41 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;',
00:03:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:03:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:03:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:03:41 INFO - 'PROCESSOR_LEVEL': '6',
00:03:41 INFO - 'PROCESSOR_REVISION': '1e05',
00:03:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:03:41 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:03:41 INFO - 'PROMPT': '$P$G',
00:03:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
00:03:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:03:41 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:03:41 INFO - 'PWD': 'C:\\slave\\test-pgo',
00:03:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:03:41 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:03:41 INFO - 'SYSTEMDRIVE': 'C:',
00:03:41 INFO - 'SYSTEMROOT': 'C:\\windows',
00:03:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:03:41 INFO - 'TEST1': 'testie',
00:03:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:03:41 INFO - 'USERDOMAIN': 'T-W732-IX-063',
00:03:41 INFO - 'USERNAME': 'cltbld',
00:03:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:03:41 INFO - 'WINDIR': 'C:\\windows',
00:03:41 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:03:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:03:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:03:41 INFO - Ignoring indexes: https://pypi.python.org/simple/
00:03:41 INFO - Downloading/unpacking mozsystemmonitor==0.0.0
00:03:41 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
00:03:41 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
00:03:42 INFO - Downloading mozsystemmonitor-0.0.tar.gz
00:03:42 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
00:03:42 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
00:03:42 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)
00:03:42 INFO - Installing collected packages: mozsystemmonitor
00:03:42 INFO - Running setup.py install for mozsystemmonitor
00:03:42 INFO - Successfully installed mozsystemmonitor
00:03:42 INFO - Cleaning up...
00:03:43 INFO - Return code: 0
00:03:43 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv
00:03:43 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')]}
00:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:03:43 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')]}
00:03:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:03:43 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 0x018D8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01898EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012EE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01208B48>, '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 0x0122A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0195D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-063', 'USERDOMAIN': 'T-W732-IX-063', '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-063', '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
00:03:43 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test-pgo\build
00:03:43 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
00:03:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:03:43 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:03:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:03:43 INFO - 'COMPUTERNAME': 'T-W732-IX-063',
00:03:43 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:03:43 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:03:43 INFO - 'DCLOCATION': 'SCL3',
00:03:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:03:43 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:03:43 INFO - 'HOMEDRIVE': 'C:',
00:03:43 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:03:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:03:43 INFO - 'KTS_VERSION': '1.19c',
00:03:43 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:03:43 INFO - 'LOGONSERVER': '\\\\T-W732-IX-063',
00:03:43 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:03:43 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:03:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:03:43 INFO - 'MOZ_NO_REMOTE': '1',
00:03:43 INFO - 'NO_EM_RESTART': '1',
00:03:43 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:03:43 INFO - 'OS': 'Windows_NT',
00:03:43 INFO - 'OURDRIVE': 'C:',
00:03:43 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
00:03:43 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:03:43 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:03:43 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:03:43 INFO - 'PROCESSOR_LEVEL': '6',
00:03:43 INFO - 'PROCESSOR_REVISION': '1e05',
00:03:43 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:03:43 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:03:43 INFO - 'PROMPT': '$P$G',
00:03:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
00:03:43 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:03:43 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:03:43 INFO - 'PWD': 'C:\\slave\\test-pgo',
00:03:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:03:43 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:03:43 INFO - 'SYSTEMDRIVE': 'C:',
00:03:43 INFO - 'SYSTEMROOT': 'C:\\windows',
00:03:43 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:03:43 INFO - 'TEST1': 'testie',
00:03:43 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:03:43 INFO - 'USERDOMAIN': 'T-W732-IX-063',
00:03:43 INFO - 'USERNAME': 'cltbld',
00:03:43 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:03:43 INFO - 'WINDIR': 'C:\\windows',
00:03:43 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:03:43 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:03:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:03:43 INFO - Ignoring indexes: https://pypi.python.org/simple/
00:03:43 INFO - Downloading/unpacking blobuploader==1.2.4
00:03:43 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
00:03:43 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
00:03:44 INFO - Downloading blobuploader-1.2.4.tar.gz
00:03:44 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
00:03:44 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
00:03:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
00:03:44 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
00:03:44 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
00:03:44 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
00:03:44 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests
00:03:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
00:03:44 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
00:03:44 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
00:03:44 INFO - Downloading docopt-0.6.1.tar.gz
00:03:44 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
00:03:44 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt
00:03:45 INFO - Installing collected packages: blobuploader, requests, docopt
00:03:45 INFO - Running setup.py install for blobuploader
00:03:45 INFO - Running setup.py install for requests
00:03:46 INFO - Running setup.py install for docopt
00:03:46 INFO - Successfully installed blobuploader requests docopt
00:03:46 INFO - Cleaning up...
00:03:47 INFO - Return code: 0
00:03:47 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv
00:03:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
00:03:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:03:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
00:03:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:03:47 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 0x018D8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01898EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012EE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01208B48>, '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 0x0122A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0195D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-063', 'USERDOMAIN': 'T-W732-IX-063', '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-063', '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
00:03:47 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
00:03:47 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
00:03:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:03:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:03:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:03:47 INFO - 'COMPUTERNAME': 'T-W732-IX-063',
00:03:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:03:47 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:03:47 INFO - 'DCLOCATION': 'SCL3',
00:03:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:03:47 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:03:47 INFO - 'HOMEDRIVE': 'C:',
00:03:47 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:03:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:03:47 INFO - 'KTS_VERSION': '1.19c',
00:03:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:03:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-063',
00:03:47 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:03:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:03:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:03:47 INFO - 'MOZ_NO_REMOTE': '1',
00:03:47 INFO - 'NO_EM_RESTART': '1',
00:03:47 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:03:47 INFO - 'OS': 'Windows_NT',
00:03:47 INFO - 'OURDRIVE': 'C:',
00:03:47 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;',
00:03:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:03:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:03:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:03:47 INFO - 'PROCESSOR_LEVEL': '6',
00:03:47 INFO - 'PROCESSOR_REVISION': '1e05',
00:03:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:03:47 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:03:47 INFO - 'PROMPT': '$P$G',
00:03:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
00:03:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:03:47 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:03:47 INFO - 'PWD': 'C:\\slave\\test-pgo',
00:03:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:03:47 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:03:47 INFO - 'SYSTEMDRIVE': 'C:',
00:03:47 INFO - 'SYSTEMROOT': 'C:\\windows',
00:03:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:03:47 INFO - 'TEST1': 'testie',
00:03:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:03:47 INFO - 'USERDOMAIN': 'T-W732-IX-063',
00:03:47 INFO - 'USERNAME': 'cltbld',
00:03:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:03:47 INFO - 'WINDIR': 'C:\\windows',
00:03:47 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:03:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:03:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:03:47 INFO - Ignoring indexes: https://pypi.python.org/simple/
00:03:47 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser
00:03:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-puxqn9-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser
00:03:47 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash
00:03:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eif6bz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash
00:03:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug
00:03:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0iop63-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug
00:03:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice
00:03:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-geyvwo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice
00:03:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile
00:03:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e1zl4i-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile
00:03:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd
00:03:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qtqcla-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd
00:03:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo
00:03:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qstj8y-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo
00:03:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall
00:03:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b07uw4-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall
00:03:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak
00:03:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4fitgb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak
00:03:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog
00:03:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6nmv4u-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog
00:03:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork
00:03:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fsjdzn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork
00:03:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess
00:03:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hws8ub-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess
00:03:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile
00:03:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ci8q8y-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile
00:03:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner
00:03:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5knnvo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner
00:03:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot
00:03:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-chgxdv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot
00:03:50 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest
00:03:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cpeszh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest
00:03:50 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion
00:03:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dh54ox-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion
00:03:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
00:03:50 INFO - Running setup.py install for manifestparser
00:03:50 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:50 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:50 INFO - Running setup.py install for mozcrash
00:03:51 INFO - Running setup.py install for mozdebug
00:03:51 INFO - Running setup.py install for mozdevice
00:03:51 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:51 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:51 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:51 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:52 INFO - Running setup.py install for mozfile
00:03:52 INFO - Running setup.py install for mozhttpd
00:03:52 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:52 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:52 INFO - Running setup.py install for mozinfo
00:03:53 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:53 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:53 INFO - Running setup.py install for mozInstall
00:03:53 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:53 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:53 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:53 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:53 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:53 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:53 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:53 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:53 INFO - Running setup.py install for mozleak
00:03:53 INFO - Running setup.py install for mozlog
00:03:54 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:54 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:54 INFO - Running setup.py install for moznetwork
00:03:54 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:54 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:55 INFO - Running setup.py install for mozprocess
00:03:55 INFO - Running setup.py install for mozprofile
00:03:55 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:55 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:55 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:55 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:55 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:55 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:56 INFO - Running setup.py install for mozrunner
00:03:56 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:56 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:57 INFO - Running setup.py install for mozscreenshot
00:03:57 INFO - Running setup.py install for moztest
00:03:57 INFO - Running setup.py install for mozversion
00:03:57 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts
00:03:57 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts
00:03:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
00:03:58 INFO - Cleaning up...
00:03:58 INFO - Return code: 0
00:03:58 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv
00:03:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
00:03:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:03:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
00:03:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:03:58 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 0x018D8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01898EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012EE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01208B48>, '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 0x0122A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0195D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-063', 'USERDOMAIN': 'T-W732-IX-063', '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-063', '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
00:03:58 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
00:03:58 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
00:03:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:03:58 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:03:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:03:58 INFO - 'COMPUTERNAME': 'T-W732-IX-063',
00:03:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:03:58 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:03:58 INFO - 'DCLOCATION': 'SCL3',
00:03:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:03:58 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:03:58 INFO - 'HOMEDRIVE': 'C:',
00:03:58 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:03:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:03:58 INFO - 'KTS_VERSION': '1.19c',
00:03:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:03:58 INFO - 'LOGONSERVER': '\\\\T-W732-IX-063',
00:03:58 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:03:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:03:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:03:58 INFO - 'MOZ_NO_REMOTE': '1',
00:03:58 INFO - 'NO_EM_RESTART': '1',
00:03:58 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:03:58 INFO - 'OS': 'Windows_NT',
00:03:58 INFO - 'OURDRIVE': 'C:',
00:03:58 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;',
00:03:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:03:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:03:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:03:58 INFO - 'PROCESSOR_LEVEL': '6',
00:03:58 INFO - 'PROCESSOR_REVISION': '1e05',
00:03:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:03:58 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:03:58 INFO - 'PROMPT': '$P$G',
00:03:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
00:03:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:03:58 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:03:58 INFO - 'PWD': 'C:\\slave\\test-pgo',
00:03:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:03:58 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:03:58 INFO - 'SYSTEMDRIVE': 'C:',
00:03:58 INFO - 'SYSTEMROOT': 'C:\\windows',
00:03:58 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:03:58 INFO - 'TEST1': 'testie',
00:03:58 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:03:58 INFO - 'USERDOMAIN': 'T-W732-IX-063',
00:03:58 INFO - 'USERNAME': 'cltbld',
00:03:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:03:58 INFO - 'WINDIR': 'C:\\windows',
00:03:58 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:03:58 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:03:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:03:58 INFO - Ignoring indexes: https://pypi.python.org/simple/
00:03:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser
00:03:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cbfbqy-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser
00:03:58 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))
00:03:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash
00:03:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fs1cq_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash
00:03:58 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))
00:03:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug
00:03:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cu5wnc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug
00:03:58 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))
00:03:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice
00:03:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ybtz2k-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice
00:03:59 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))
00:03:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile
00:03:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ukvbbm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile
00:03:59 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))
00:03:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd
00:03:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ooaplc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd
00:03:59 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))
00:03:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo
00:03:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bawxnl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo
00:03:59 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))
00:03:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall
00:03:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-baa4dt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall
00:03:59 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))
00:03:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak
00:03:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ltecby-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak
00:03:59 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))
00:03:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog
00:03:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iioty8-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog
00:04:00 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))
00:04:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork
00:04:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xa10xl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork
00:04:00 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))
00:04:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess
00:04:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h8sxia-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess
00:04:00 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))
00:04:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile
00:04:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lknqw9-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile
00:04:00 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))
00:04:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner
00:04:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yf6isc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner
00:04:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14))
00:04:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot
00:04:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vcnmll-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot
00:04:00 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))
00:04:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest
00:04:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_njwjs-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest
00:04:00 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))
00:04:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion
00:04:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u0w9p2-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion
00:04:00 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))
00:04:00 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))
00:04:00 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))
00:04:00 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))
00:04:00 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))
00:04:00 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))
00:04:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10))
00:04:00 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
00:04:00 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
00:04:01 INFO - Downloading blessings-1.5.1.tar.gz
00:04:01 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
00:04:01 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings
00:04:02 INFO - Installing collected packages: blessings
00:04:02 INFO - Running setup.py install for blessings
00:04:02 INFO - Successfully installed blessings
00:04:02 INFO - Cleaning up...
00:04:05 INFO - Return code: 0
00:04:05 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv.
00:04:05 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze']
00:04:05 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze
00:04:05 INFO - Reading from file tmpfile_stdout
00:04:05 INFO - Using _rmtree_windows ...
00:04:05 INFO - Using _rmtree_windows ...
00:04:05 INFO - Current package versions:
00:04:05 INFO - blessings == 1.5.1
00:04:05 INFO - blobuploader == 1.2.4
00:04:05 INFO - docopt == 0.6.1
00:04:05 INFO - manifestparser == 1.1
00:04:05 INFO - mozInstall == 1.12
00:04:05 INFO - mozcrash == 0.16
00:04:05 INFO - mozdebug == 0.1
00:04:05 INFO - mozdevice == 0.46
00:04:05 INFO - mozfile == 1.2
00:04:05 INFO - mozhttpd == 0.7
00:04:05 INFO - mozinfo == 0.8
00:04:05 INFO - mozleak == 0.1
00:04:05 INFO - mozlog == 3.0
00:04:05 INFO - moznetwork == 0.27
00:04:05 INFO - mozprocess == 0.22
00:04:05 INFO - mozprofile == 0.27
00:04:05 INFO - mozrunner == 6.10
00:04:05 INFO - mozscreenshot == 0.1
00:04:05 INFO - mozsystemmonitor == 0.0
00:04:05 INFO - moztest == 0.7
00:04:05 INFO - mozversion == 1.4
00:04:05 INFO - psutil == 3.1.1
00:04:05 INFO - requests == 1.2.3
00:04:05 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv
00:04:05 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')]}
00:04:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:04:05 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')]}
00:04:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:04:05 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 0x018D8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01898EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012EE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01208B48>, '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 0x0122A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0195D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-063', 'USERDOMAIN': 'T-W732-IX-063', '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-063', '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
00:04:05 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
00:04:05 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
00:04:05 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:04:05 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:04:05 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:04:05 INFO - 'COMPUTERNAME': 'T-W732-IX-063',
00:04:05 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:04:05 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:04:05 INFO - 'DCLOCATION': 'SCL3',
00:04:05 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:04:05 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:04:05 INFO - 'HOMEDRIVE': 'C:',
00:04:05 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:04:05 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:04:05 INFO - 'KTS_VERSION': '1.19c',
00:04:05 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:04:05 INFO - 'LOGONSERVER': '\\\\T-W732-IX-063',
00:04:05 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:04:05 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:04:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:04:05 INFO - 'MOZ_NO_REMOTE': '1',
00:04:05 INFO - 'NO_EM_RESTART': '1',
00:04:05 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:04:05 INFO - 'OS': 'Windows_NT',
00:04:05 INFO - 'OURDRIVE': 'C:',
00:04:05 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;',
00:04:05 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:04:05 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:04:05 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:04:05 INFO - 'PROCESSOR_LEVEL': '6',
00:04:05 INFO - 'PROCESSOR_REVISION': '1e05',
00:04:05 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:04:05 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:04:05 INFO - 'PROMPT': '$P$G',
00:04:05 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
00:04:05 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:04:05 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:04:05 INFO - 'PWD': 'C:\\slave\\test-pgo',
00:04:05 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:04:05 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:04:05 INFO - 'SYSTEMDRIVE': 'C:',
00:04:05 INFO - 'SYSTEMROOT': 'C:\\windows',
00:04:05 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:04:05 INFO - 'TEST1': 'testie',
00:04:05 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:04:05 INFO - 'USERDOMAIN': 'T-W732-IX-063',
00:04:05 INFO - 'USERNAME': 'cltbld',
00:04:05 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:04:05 INFO - 'WINDIR': 'C:\\windows',
00:04:05 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:04:05 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:04:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:04:05 INFO - Ignoring indexes: https://pypi.python.org/simple/
00:04:05 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))
00:04:05 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))
00:04:05 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))
00:04:05 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))
00:04:05 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))
00:04:05 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))
00:04:05 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))
00:04:05 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))
00:04:05 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))
00:04:05 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))
00:04:05 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))
00:04:05 INFO - Cleaning up...
00:04:05 INFO - Return code: 0
00:04:05 INFO - Running post-action listener: _resource_record_post_action
00:04:05 INFO - Running post-action listener: _start_resource_monitoring
00:04:05 INFO - Starting resource monitoring.
00:04:06 INFO - #####
00:04:06 INFO - ##### Running install step.
00:04:06 INFO - #####
00:04:06 INFO - Running pre-action listener: _resource_record_pre_action
00:04:06 INFO - Running main action method: install
00:04:06 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze']
00:04:06 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze
00:04:06 INFO - Reading from file tmpfile_stdout
00:04:06 INFO - Using _rmtree_windows ...
00:04:06 INFO - Using _rmtree_windows ...
00:04:06 INFO - Detecting whether we're running mozinstall >=1.0...
00:04:06 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h']
00:04:06 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h
00:04:06 INFO - Reading from file tmpfile_stdout
00:04:06 INFO - Output received:
00:04:06 INFO - Usage: mozinstall-script.py [options] installer
00:04:06 INFO - Options:
00:04:06 INFO - -h, --help show this help message and exit
00:04:06 INFO - -d DEST, --destination=DEST
00:04:06 INFO - Directory to install application into. [default:
00:04:06 INFO - "C:\slave\test-pgo"]
00:04:06 INFO - --app=APP Application being installed. [default: firefox]
00:04:06 INFO - Using _rmtree_windows ...
00:04:06 INFO - Using _rmtree_windows ...
00:04:06 INFO - mkdir: C:\slave\test-pgo\build\application
00:04:06 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']
00:04:06 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
00:04:08 INFO - Reading from file tmpfile_stdout
00:04:08 INFO - Output received:
00:04:08 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe
00:04:08 INFO - Using _rmtree_windows ...
00:04:08 INFO - Using _rmtree_windows ...
00:04:08 INFO - Running post-action listener: _resource_record_post_action
00:04:08 INFO - #####
00:04:08 INFO - ##### Running run-tests step.
00:04:08 INFO - #####
00:04:08 INFO - Running pre-action listener: _resource_record_pre_action
00:04:08 INFO - Running main action method: run_tests
00:04:08 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version']
00:04:08 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version
00:04:08 INFO - Python 2.7.3
00:04:08 INFO - Return code: 0
00:04:08 INFO - grabbing minidump binary from tooltool
00:04:08 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')]}
00:04:08 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 0x01208B48>, '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 0x0122A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0195D660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1
00:04:08 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
00:04:08 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
00:04:08 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
00:04:09 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmp1hfesl
00:04:09 INFO - INFO - File integrity verified, renaming tmp1hfesl to win32-minidump_stackwalk.exe
00:04:09 INFO - Return code: 0
00:04:09 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755
00:04:09 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir
00:04:09 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir
00:04:09 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe
00:04:09 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir
00:04:09 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-063', '--webServer', 'localhost'] in C:\slave\test-pgo\build
00:04:09 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName Mozilla-Inbound --suite dromaeojs --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-063 --webServer localhost
00:04:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:04:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:04:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:04:09 INFO - 'COMPUTERNAME': 'T-W732-IX-063',
00:04:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:04:09 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:04:09 INFO - 'DCLOCATION': 'SCL3',
00:04:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:04:09 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:04:09 INFO - 'HOMEDRIVE': 'C:',
00:04:09 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:04:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:04:09 INFO - 'KTS_VERSION': '1.19c',
00:04:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:04:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-063',
00:04:09 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir',
00:04:09 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe',
00:04:09 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:04:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:04:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:04:09 INFO - 'MOZ_NO_REMOTE': '1',
00:04:09 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir',
00:04:09 INFO - 'NO_EM_RESTART': '1',
00:04:09 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:04:09 INFO - 'OS': 'Windows_NT',
00:04:09 INFO - 'OURDRIVE': 'C:',
00:04:09 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;',
00:04:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:04:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:04:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:04:09 INFO - 'PROCESSOR_LEVEL': '6',
00:04:09 INFO - 'PROCESSOR_REVISION': '1e05',
00:04:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:04:09 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:04:09 INFO - 'PROMPT': '$P$G',
00:04:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
00:04:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:04:09 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:04:09 INFO - 'PWD': 'C:\\slave\\test-pgo',
00:04:09 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos',
00:04:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:04:09 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:04:09 INFO - 'SYSTEMDRIVE': 'C:',
00:04:09 INFO - 'SYSTEMROOT': 'C:\\windows',
00:04:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:04:09 INFO - 'TEST1': 'testie',
00:04:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:04:09 INFO - 'USERDOMAIN': 'T-W732-IX-063',
00:04:09 INFO - 'USERNAME': 'cltbld',
00:04:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:04:09 INFO - 'WINDIR': 'C:\\windows',
00:04:09 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:04:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:04:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:04:09 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-063', '--webServer', 'localhost'] with output_timeout 3600
00:04:10 INFO - mozversion INFO | application_buildid: 20151021193145
00:04:10 INFO - mozversion INFO | application_changeset: 2708f83e4c15836f7925e69a4227778ae9a1809e
00:04:10 INFO - mozversion INFO | application_display_name: Nightly
00:04:10 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
00:04:10 INFO - mozversion INFO | application_name: Firefox
00:04:10 INFO - mozversion INFO | application_remotingname: firefox
00:04:10 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound
00:04:10 INFO - mozversion INFO | application_vendor: Mozilla
00:04:10 INFO - mozversion INFO | application_version: 44.0a1
00:04:10 INFO - mozversion INFO | platform_buildid: 20151021193145
00:04:10 INFO - mozversion INFO | platform_changeset: 2708f83e4c15836f7925e69a4227778ae9a1809e
00:04:10 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound
00:04:10 INFO - mozversion INFO | platform_version: 44.0a1
00:04:10 INFO - 2015-10-22 00:04:10,579 DEBUG : using testdate: 1445497450
00:04:10 INFO - 2015-10-22 00:04:10,579 DEBUG : actual date: 1445497450
00:04:10 INFO - 2015-10-22 00:04:10,664 INFO : starting webserver on 'localhost:49695'
00:04:10 INFO - 2015-10-22 00:04:10,665 INFO : Starting test suite T-W732-IX-063
00:04:10 INFO - 2015-10-22 00:04:10,665 INFO : Starting test dromaeo_css
00:04:10 INFO - 2015-10-22 00:04:10,665 DEBUG : operating with platform_type : w7_
00:04:10 INFO - 2015-10-22 00:04:10,667 INFO : Initialising browser for dromaeo_css test...
00:04:10 INFO - 2015-10-22 00:04:10,763 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpin9yip\profile http://localhost:49695/getInfo.html
00:04:18 INFO - 2015-10-22 00:04:18,888 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
00:04:18 INFO - 2015-10-22 00:04:18,888 DEBUG : BROWSER_OUTPUT: colorDepth:24
00:04:18 INFO - 2015-10-22 00:04:18,888 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
00:04:18 INFO - 2015-10-22 00:04:18,888 DEBUG : BROWSER_OUTPUT: __metrics
00:04:18 INFO - 2015-10-22 00:04:18,903 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
00:04:20 INFO - 2015-10-22 00:04:20,157 DEBUG : BROWSER_OUTPUT: console.error:
00:04:20 INFO - 2015-10-22 00:04:20,157 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]
00:04:20 INFO - 2015-10-22 00:04:20,157 DEBUG : BROWSER_OUTPUT: Stack:
00:04:20 INFO - 2015-10-22 00:04:20,157 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19
00:04:20 INFO - 2015-10-22 00:04:20,157 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9
00:04:20 INFO - 2015-10-22 00:04:20,157 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1
00:04:20 INFO - 2015-10-22 00:04:20,157 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51
00:04:20 INFO - 2015-10-22 00:04:20,157 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
00:04:20 INFO - 2015-10-22 00:04:20,157 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3
00:04:20 INFO - 2015-10-22 00:04:20,157 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14
00:04:20 INFO - 2015-10-22 00:04:20,158 DEBUG : BROWSER_OUTPUT: setAncestorsLastModified<@resource://gre/modules/Bookmarks.jsm:1347:9
00:04:20 INFO - 2015-10-22 00:04:20,158 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
00:04:20 INFO - 2015-10-22 00:04:20,158 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3
00:04:20 INFO - 2015-10-22 00:04:20,158 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14
00:04:20 INFO - 2015-10-22 00:04:20,158 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:808:13
00:04:20 INFO - 2015-10-22 00:04:20,158 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
00:04:20 INFO - 2015-10-22 00:04:20,158 DEBUG : BROWSER_OUTPUT: Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:934:23
00:04:20 INFO - 2015-10-22 00:04:20,158 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7
00:04:20 INFO - 2015-10-22 00:04:20,158 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11
00:04:20 INFO - 2015-10-22 00:04:20,158 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7
00:04:20 INFO - 2015-10-22 00:04:20,158 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7
00:04:20 INFO - 2015-10-22 00:04:20,158 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13
00:04:20 INFO - 2015-10-22 00:04:20,158 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9
00:04:20 INFO - 2015-10-22 00:04:20,158 DEBUG : BROWSER_OUTPUT:
00:04:20 INFO - 2015-10-22 00:04:20,779 INFO : Browser initialized.
00:04:20 INFO - 2015-10-22 00:04:20,779 INFO : Running cycle 1/1 for dromaeo_css test...
00:04:20 INFO - 2015-10-22 00:04:20,779 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpin9yip\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1
00:04:32 INFO - 2015-10-22 00:04:32,181 DEBUG : BROWSER_OUTPUT: RSS: Main: 130449408
00:04:32 INFO - 2015-10-22 00:04:32,181 DEBUG : BROWSER_OUTPUT:
00:05:24 INFO - 2015-10-22 00:05:24,555 DEBUG : BROWSER_OUTPUT: 1445497524576 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]
00:07:32 INFO - 2015-10-22 00:07:32,470 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49695/tests/dromaeo/cssquery-ext.html)
00:07:33 INFO - 2015-10-22 00:07:33,484 DEBUG : BROWSER_OUTPUT: RSS: Main: 186187776
00:07:33 INFO - 2015-10-22 00:07:33,484 DEBUG : BROWSER_OUTPUT:
00:10:35 INFO - 2015-10-22 00:10:35,747 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/dromaeo/cssquery-ext.html (next: http://localhost:49695/tests/dromaeo/cssquery-jquery.html)
00:10:36 INFO - 2015-10-22 00:10:36,302 DEBUG : BROWSER_OUTPUT: RSS: Main: 182620160
00:10:36 INFO - 2015-10-22 00:10:36,302 DEBUG : BROWSER_OUTPUT:
00:13:38 INFO - 2015-10-22 00:13:38,329 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49695/tests/dromaeo/cssquery-mootools.html)
00:13:38 INFO - 2015-10-22 00:13:38,897 DEBUG : BROWSER_OUTPUT: RSS: Main: 147951616
00:13:38 INFO - 2015-10-22 00:13:38,897 DEBUG : BROWSER_OUTPUT:
00:16:40 INFO - 2015-10-22 00:16:40,763 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49695/tests/dromaeo/cssquery-prototype.html)
00:16:41 INFO - 2015-10-22 00:16:41,342 DEBUG : BROWSER_OUTPUT: RSS: Main: 135258112
00:16:41 INFO - 2015-10-22 00:16:41,342 DEBUG : BROWSER_OUTPUT:
00:19:44 INFO - 2015-10-22 00:19:44,062 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49695/tests/dromaeo/cssquery-yui.html)
00:19:44 INFO - 2015-10-22 00:19:44,641 DEBUG : BROWSER_OUTPUT: RSS: Main: 129110016
00:19:44 INFO - 2015-10-22 00:19:44,641 DEBUG : BROWSER_OUTPUT:
00:22:47 INFO - 2015-10-22 00:22:47,039 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/dromaeo/cssquery-yui.html (next: http://localhost:49695/tests/dromaeo/cssquery-dojo.html)
00:22:47 INFO - 2015-10-22 00:22:47,295 DEBUG : BROWSER_OUTPUT: RSS: Main: 191746048
00:22:47 INFO - 2015-10-22 00:22:47,295 DEBUG : BROWSER_OUTPUT:
00:22:47 INFO - 2015-10-22 00:22:47,295 DEBUG : BROWSER_OUTPUT: __start_tp_report
00:22:47 INFO - 2015-10-22 00:22:47,295 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
00:22:47 INFO - 2015-10-22 00:22:47,295 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
00:22:47 INFO - 2015-10-22 00:22:47,295 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
00:22:47 INFO - 2015-10-22 00:22:47,295 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2657.8803953822076;2698.3119304577335;2739.5890616407523;2748.862556872156;2766.9308267293313;4946.925796113062;5030.673006254596;5093.185090385539;5177.999999999995;5187.844364669057;5179.14544100224;5185.844424667256;5218.895622087556;5273.630845840793;5276.630635855492;4334.739915605058;4376.102898905725;4411.338299255105;4483.3723278741;4503.954960450386;4185.979070104645;4396.450443694538;4419.138268037734;4432.69235575506;4448.243798554237;4359.888228501728;4443.311286750559;4456.799444025027;4459.777011149446;4461.330800379953;4280.250956082704;4341.088371442001;4341.261985462464;4348.434703488542;4452.243118669801;4186.853460128881;4337.132573485315;4350.282203436429;4397.384366188736;4425.557444255581;5084.79660813567;5122.743862806882;5200.193969934666;5225.477452254782;5249.973750131225;5163.870903227412;5276.076686579871;5297;5343.839684809462;5367.677939323653;5393;5436.075867102602;5438.211459338374;5518.972405137989;5543.722813859331;4865.5864251538305;5041.092603331399;5132.974335128375;5145.4597267287145;5147.716875571842;5457.536109430664;5496.587755918322;5519.917201241985;5583.804566840141;5675.77296908119;5317.7341132943975;5331.973340133274;5421.972890135603;5444.210589464506;5447.210154527571;5294.364676238876;5408.513233808897;5435.293411856513;5544.944550554524;5548.250986116904;5258.7370631468275;5336.573074154058;5340.946590534123;5349.866253343697;5496.945030549723;5373.892522149535;5383.81156659515;5387.299651045339;5492.615516913777;5495.560355171577;3391.1861153323025;3426.434638284655;3436.381451338733;3492.8078955657684;3499.4225952717516;3034.059441573075;3082.830444325584;3105.8757649693757;3183.18828698685;3183.824889631046;5177.818776342885;5198.038362902799;5232.215167724872;5272.420033796279;5302.363716353985;5374.731263436813;5406.243125962368;5484.396716361197;5583.469570390806;5592.273004509388;5035.370578677665;5035.949640503548;5074.416442109131;5181.35233095863;5201.193814958688;4264.957350426518;4328.805203765838;4419.0940857124215;4425.402570652985;4432.601065904084;5614.410486898817;5688.488036076773;5693.572982026365;5799.971000144972;5808.244928159397;5371.704556249444;5389.434109418455;5471.890562188813;5472.562195024469;5493.642913210629;5365.59758018158;5574.693391863487;5575.553955683617;5750.827475175752;5829.213056237354;5228.790848366023;5311.734413279398;5415.323084614423;5418.512333889968;5442.619016668795;5351.652142610717;5503.3120859892515;5504.889902202013;5508.283923089973;5529.115341545334;5348.9465105348445;5351.411344752152;5360.70516121617;5445.428230035947;5475.75359108849;5227.843164705065;5280.76236569363;5386.057439948072;5404.864878378072;5410.269613602113;5163.328767260232;5255.842324730264;5279.762410691451;5295.7616907240035;5326.920096198638;5138.97430512845;5336.119540275811;5382.461753824587;5486.478784515624;5496.642718223303;5151.25306830515;5153.4073581538605;5292.232626269247;5382.219578161066;5382.784688612412
00:22:47 INFO - 2015-10-22 00:22:47,295 DEBUG : BROWSER_OUTPUT: |1;ext.html;491.44466752569593;510.71144803186206;513.022691772174;514.7400562715828;523.1707743226985;30932.226694332625;31358;38091.23817523651;38360.80819595905;38694.806525967404;39977.40033899486;40372.59627403725;40380.39429408554;40388.394174087414;40444;6982.02251684762;7239.131304243499;8313.91686083139;8475.110113438062;8478.66085356585;9697.612095516171;10103.19174466041;10233.590656373735;10316.12312953396;10469.424181670005;9795.314327997043;10063.19494440443;10127.683401157876;10264.230182736288;10269.28115031948;10191.59233630654;10248.846267305997;10263.538140783683;10271.126954208901;10299.691009269696;10054.095131438133;10152.39085654863;10173.491325433699;10181.38911665295;10200.438975856325;37313;37707.43438848392;37868.43197352042;38031.8098409509;38063.809680951694;3095.1021522726796;3231.563738895266;3262.706356427933;3522.607781282843;3625.1299688074937;29200;30253;30263.848680756677;30643.2339191522;31369.215769605715;2547.5287071891757;2688.3951111000065;2728.9044883428983;2742.369255071325;2802.6216460777737;308706;309372;309613.45193274115;310984;313319.99999999773;44910.77544612256;45243.547564523935;51132.23301650478;51926.74036629793;51953;53971.73014134983;55592.72203639037;56465.71767141138;56483.71758141265;56598.43401565932;13115.540956066683;13855.653608659864;14014.929925350512;14044.789328160086;14056.85943140576;9872.210223182128;10084.042016008467;10342.172626189888;10423.791524169474;10552.894471055344;16610.418635347705;16830.15849207535;16844;16911.294192929072;16923;7226.421886249087;7456.105267367842;7484.363829074481;7497.962510187414;7529.73645922401;844.4933040175969;897.4480694372913;904.624580798961;963.9469829159324;1051.542578978147;27843;28264;28459.57310640342;28534.857325713238;28560.571591426145;985.6895078050392;993.2897977945803;1006.5118417567473;1011.185995273797;1080.5515710979855;15101.169435681142;15101.3204405802;15179.341736000186;15241;15294.15875139146;374040;391687;391970;398034;398614;47254;48132.966758310424;48143.9999999993;48484;48640.78543505833;841.3437518735396;1113.704868209925;1194.9581764638197;1198.0774803401507;1299.5321684193682;27643.170704878885;28114.437711246068;28378.43243135167;28420.85789571039;28508.287292817015;38740.61259387483;40161;42253.78873105615;42463.3630495549;42683.57316426796;342691;342888;346647.40086695657;347744;347975;26556.601650975637;26890.462190756465;26904.32739181536;26910;26913;38549.42175867308;39425;39580.40629390504;39694;39719.80140099281;32468.86358977519;33234.83382583072;34945.30109397849;35154.296914061066;35165;32273;33235.335293293516;33300.1674958128;34072;35022.64977350194;33321;33794.66205337915;33984.15039624029;34064.65935340615;34140.14649633779;29264.41471170607;29664.851675741484;31140.844295778377;31225.37549249048;31439;23761.881190593936;24094.277171684877;24332.39169020718;24430.26709198727;25046
00:22:47 INFO - 2015-10-22 00:22:47,296 DEBUG : BROWSER_OUTPUT: |2;jquery.html;327.62977835046394;327.98032118072905;330.8693066238835;335.12365165093286;337.3438661802794;9574.569144388503;9746.805063898717;17131.657366852654;17452.38916637918;17536.73694894576;10829.783404331909;10880.12958963283;10947.781044379128;11108.16688748345;11134.721631959204;4234.110836724276;4329.978350108253;4419.9337009944875;4421.292593185091;4422.314541246096;3553.1650062235485;3633.200695846923;4387.210302145612;4804.471508134103;4900.58345040672;4641.141388843075;4852.708837469745;4853.538913803183;4856.106118613726;4856.854294371175;4710.081534100852;4837.39532558432;4837.9032419351415;4838.516148385169;4845.660803743739;4013.4581831452674;4021.577734337882;4024.7786371749544;4026.6577340925764;4027.9597204027878;12588.937055314665;12707.682307942283;12750.29873356965;12780.169288996185;13216.471341146342;1914.2630087416444;1944.2611807513053;2040.1533363654066;2148.0333849767667;2149.9462513437134;9652.613895444174;9693.612255509772;9809.754756131082;9957.80084398308;9999.75000624983;1577.826439091699;1608.9541797831387;1614.2413065859027;1636.959076023109;1659.6846599146245;69864.30135698679;70210.64894675494;70757.29242707559;71506.64246678786;71536.284637154;6054.273487181478;6079.361667024988;6088.482478989247;6128.479079278222;6179.567430279837;6893.379595836399;6949.93050069503;6955.756548520778;6981.581105133709;6990.510664253454;4919.0899683558655;4920.360353154067;4922.360093187935;4949.678270912379;4971.875703107451;5192.92210616841;5275.472452754694;5285.127953887565;5325.520703136736;5381.838544843661;2691.5020721166647;2714.877830497632;2719.4561087782126;2724.8773805178816;2736.808423410342;985.9507024648739;989.8366769483099;997.1474389397106;1002.8044531316469;1050.1021626509378;345.4231433506051;396.23131125616214;396.3717507750248;396.59745358461186;402.6919406653912;11184.720381990515;11309.151813614011;11489.71275718097;11542.307461552333;11616.651500455;410.21443934865;417.7618757308305;425.67010566810967;426.0775421213039;427.8673611180544;6011.849703757441;6262.15460912771;6263.812085637438;6292.559520833498;6355.841103972438;396562;396636.57178279763;399252;399501;403940;79991.60004199942;81080.59459702781;81192;82441;82545.58727206326;478.7749757613916;482.27899290560833;482.3945947835444;482.91548978928546;483.74361588358227;10746.08658264025;10932.672019839418;11146.888531114586;11228.55085796559;11310.264832785842;36406;37702.62297377101;38687.22625547417;38699.2260154801;39419.60580394274;49119.26321105258;49453.25820112773;49734.50265497299;51071.93377953438;53315;5919.852003699942;6025.276966764016;6070.362611925861;6071.241094863142;6071.696415179135;11624.244424112405;11863.110266730202;12081.093917956012;12184.451699673358;12235.69410764738;10098.091171794575;10474.476276186313;10527.526261318106;10527.894721052691;10555.472226388803;10142.340747851365;10149.594016239267;10160.949195253976;10256.43589602579;10471.528781205016;9928.950355248178;10611.779645340885;10723.356598604109;10927.289726167774;10967.890321097006;5309.814156504503;5510.283663123768;5535.059039963136;6076.908846367396;6104.17593624855;10234.16655487495;10466.116099102235;10533.473326333806;10557.155427565624;10566.26036177491
00:22:47 INFO - 2015-10-22 00:22:47,298 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1274.8406449193849;1352.6550729563967;1424.0601203205886;1597.5047735202088;1601.6396310830057;7870.2916737493615;8008.719694810683;8476.406651534387;8732.08313127122;8973.730788076351;8536.48781073136;8731.126887311282;8754.518501482416;8794.428362156455;8794.428362156455;2254.549090181962;2279.361778701966;2282.7032485776826;2282.942926426836;2284.2804516577307;1881.915313810875;1994.3518356534112;2252.3918541993653;2254.549090181962;2268.5576312618964;2194.572058448603;2255.6616507523922;2260.977390226093;2265.1166045242367;2268.7617800130997;1925.9711104333446;2209.237812954528;2218.0462401167492;2227.732672079345;2229.2754854672216;1832.660957722812;2124.352072617851;2192.7478339990944;2240.787125223101;2243.25972429098;8676.956615216883;8726.52004139772;8768.605412756442;8768.956155219246;8773.07882672323;5046.142155833517;5060.418051924039;5096.974515127437;5120.948790512084;5122.948770512285;3271.9836400817844;3301.96698033019;3305.289362787012;3310.9503357449657;3352.195473086466;946.9573869175904;958.108958668438;996.2727209137298;997.2022382094368;997.4364484066551;12309.44607492656;12567.811482827765;12598.55905043319;12628.494860205581;12659.493620255178;4327.199468098412;4371.475422949266;4417;4456.844010459683;4468.218061839165;10044.447555384524;10274.383536987805;10349.586016559253;10354.223433242538;10362.689119326433;4922.803087876517;4948.65359424844;4974.403071631427;4978.377702787152;4987.251912213124;4478.350639157304;4515.07440974606;4524.7511386873375;4539.84110556129;4579.244424669959;3682.797446140488;3697.630236976335;3712.925741485155;3723.646253605903;3748.83130259139;1123.7977164110375;1126.3354620773619;1143.4854315558032;1146.6674664347413;1151.6717735445525;919.7516670498928;940.4827344960381;942.5475771629519;945.9952700236593;946.2808265718104;7820.765377038698;7859.331956783737;8066.516009039477;8222.218889205516;8267.503949763033;1001.3191030099457;1003.1122456625939;1017.6336518853207;1027.9074883260541;1037.444966942682;5848.736806843703;5935.465808077293;5962.25471816023;5984.8803023940145;6333.493320534439;10716.089132423675;10750.838737418944;10753.408562528979;10777.78444431107;10792.676219713421;9920.851187232198;10601.151907847508;10608.734781630366;10615.097716694014;10634.680959571226;7788.532688038736;7923.564203968722;8027.117017128111;8032.63853126599;8038.799030024179;7699.114601820862;7699.730509432143;7717.729879454417;7738.690452381842;7747.961260193663;8791.340649451444;9438.244940404615;9542.475163866055;9591.616335346507;9707.951460242653;9124.27005839518;9531.428114313163;9934.751631209278;9934.801303974025;10010.749731256778;7130.2869713028285;7799.9220007799195;7871.173526779835;7878.212178782305;7878.803029924068;8549.957250213709;8554.358423118416;8578.056413794602;8582.871256931276;8624.698135565224;7497.700091996259;7838.882416763639;8009.639566219417;8033;8133.8373232536205;7695.57674327895;7757.224277572198;7889.3294070004695;7892.723754668559;7906.327962123054;7233.710651574089;7508.849823003618;7797.610119494116;7856.921430785619;7867.016622922135;6471.255805582418;6482.995135753966;6693.933060669331;6767.221769496374;6770.695318710768;6409.615423074444;6807.829804254735;6852.657367131723;6863.691133899087;6898.586084834926
00:22:47 INFO - 2015-10-22 00:22:47,335 DEBUG : BROWSER_OUTPUT: |4;prototype.html;309.01424455985403;316.91126484584316;320.90533292678646;327.5120071094071;329.52054760323733;5734.5125664318475;5822.475977162055;5902.173695682608;5971.253593300838;5973.940260597393;5748.741306641201;5820.476157145856;5872.559558033154;5941;5947.553933454998;4250.341197114437;4304.225239456897;4310.784460776964;4322.978385108086;4327.805248763798;4189.350650649154;4194.161167766443;4273.764942928137;4287.807048682801;4321.438213032317;4195.475565554306;4233.047564298039;4251.171021650779;4280.250956082688;4288.76411797351;4270;4275.551067137937;4276.935845962313;4282.036541778106;4286.614204721559;3543.557055368079;3725.7578257413184;3732.141607430306;3733.794641294755;3743.382341913581;5766.365699773021;5777.62445441045;5778.191053252548;5783.103618939072;5785.595008349375;5009.142739488312;5188;5195.454477279869;5209.47905209476;5248.527632513054;6135.417135372132;6252;6272.278687950853;6273.843153921142;6274.749010039593;4246.893827654302;4264.402983582301;4308.569143085698;4311.374850646639;4319.0713996490595;6309.4636955858805;6369.235691717024;6437.130987316699;6439.678016099177;6494.090827284184;4252.787360631956;4304.78476076195;4395.011122497476;4430.18041662287;4444.755538445352;4505.144022635689;4511.05267893746;4515.1195516873895;4516.322551617283;4518.186726389215;5064.569511591482;5127.97436012825;5154.14956532168;5184.429712731597;5186.481351864859;5882.294124704978;6008.098785182257;6139.47814435769;6269.843253918597;6297.527685423612;1578.1478001879086;1580.8735301175877;1609.0426196413116;1645.5930179696427;1645.9341626334813;1034.622362837574;1043.9791204175874;1057.5716834681966;1061.851340812287;1091.170710260192;5782.826515204551;6010.879782404328;6026.246719160105;6043.12374705674;6048.546359023091;6406.711697973602;6407;6423.325550817097;6429.357064293533;6438.098666186737;5958.672273025025;6000.729967151488;6005.759769609167;6028.306744724325;6033.276006879202;3468;3469.3581687387914;3549.6450354964295;3569.089882080053;3583.552055993001;6774.932250677529;6799.694013769392;6806.081179040865;6806.523543351917;6810.353016463428;6226.688665566794;6498.545101842826;6499.935000650027;6515.185601799775;6529.804105876831;6788.253292137861;6788.626625535643;6798.014287928223;6798.116244888133;6798.422134118655;6109.11417844419;6381.425671689571;6422.389872962061;6489.448396886318;6573.80278591643;6382.68086595659;6396.616203027833;6407.935920640734;6414.230292364759;6420.55056146084;6814.557053791686;6873;6897.793066208022;6899.103116594811;6936.930630693831;4083.550809410903;4331.5884990925815;4363.890902727458;4374.4531933508315;4384.978075109604;6479.611223326616;6536.248331441755;6557.704903279459;6587.736490540325;6656.735220308126;6450.387213214737;6467.547271690936;6473.061406096184;6474.805755827333;6481.157449531531;6360.459360954371;6361.141245931925;6380.553361264667;6387.904181437189;6388.776392826228;5967.761289548543;6054.3642917493025;6212.627242365473;6273;6274.215723034621;6277.435030847246;6314.652694101868;6403.975363941747;6424.775132870327;6426.132472116205;6259;6259.155014073224;6261.217347831521;6270.435660790551;6276.592021518587
00:22:47 INFO - 2015-10-22 00:22:47,338 DEBUG : BROWSER_OUTPUT: |5;yui.html;746.8767653337198;746.8804991201409;754.4454825703107;764.2243123229919;766.7393086350642;12942.317498725164;12969;13182.340882955838;13203.877670398027;13208.73582528349;11742.882571174287;11992.940035299833;12035.638930832067;12039.398030098482;12079.879201207985;500.7446202436747;505.4919805595371;589.0604485845091;589.4105894105894;598.1267349669488;590.0942053947192;594.2215697436351;594.3848117198701;594.9434803693642;596.0284735880531;620.1503939602751;622.3372108704239;628.3590737447801;628.7830698409042;629.7984644913629;531.2695044314069;532.1565318969438;618.6628287583255;623.6975067092457;626.0859145647315;438.58991842627245;454.23234733300944;454.2640921706823;454.8908262017125;455.4284373111757;12163.087768417405;12222;12257.080718946025;12267.57063502782;12270.263784172977;9807.852882206773;9873.506324683809;10068.04353586408;10382.948085259526;11800.46897889597;9773.853392199122;10002.89997100027;10032.297739158263;10058.698239052841;10217.292417509367;3137.4352616529018;3155.100796273051;3194.7923384980136;3212.1166679163184;3256.6906143916526;44956.775216124035;46069.78057962199;46573;47539.762301188275;47758.76120619375;6852.224848936189;7041.260667629927;7097.290270972965;7214.639268036577;7399.334059934632;10720;10722.410267435365;10724.356538607552;10753.032227099598;10768.084712799344;6556.147700798961;6648.368405001517;6664.733410663616;6707.6981535831;6796.762113326109;4236.3857240700545;4293.463317085364;4340.565943405634;4352.608265256142;4390.2317094508335;2524.3436706456087;2611.242739605531;2613.4773045391;2613.6732908386452;2618.6726659167603;417.5490470292077;420.3400660962321;420.97684627345177;424.98300067997553;426.20300038927405;597.0954004682909;640.4837442440966;665.9167604049494;692.941100006495;699.6606645776794;9544.414354095652;9785.559649815776;9845.458499782437;9862.408255504693;9950.601975920883;703.992960070403;736.7364969078056;740.7888751705738;751.2224847283036;761.3330285350764;4175.227582897113;4444.266695995125;4497.595216430472;4511.300748384006;4516.36770852081;50124.24813627799;52138.478615214124;52497;53063.204051939254;53403.73298133485;46030.76984615056;46648;46728.5327146731;46982.5301746985;47019;39112.80443597764;39314;39614.405783913266;39822.20355592872;40143.799281003405;8532.189442003;8545.316374690134;8606.483610983361;8928.776780580538;8994.370394072614;26670.46659066769;27834.86082569574;27955.301117472227;27995;28028.57957130604;38040.61959380481;38054;39791.403128953665;39856;39860.402093968034;7680.927628948465;7750.418718596015;7829.491083079582;7863.135055143815;7889.960550197212;12130.514779409077;12211.572594959134;12321.445534951128;12405.937970310091;12660.366981651065;11527.88472115268;11715.297082175097;11822.172447928562;11881.762364752483;12151.75696486083;11332.830007550057;11417.1437142213;11430.542778289107;11753;11979;11459.942700286445;11642.70893227676;11676;11969.64091077269;12045.879541204476;9773.218142548436;9830.262730295113;9919;9987.201023918215;10078;10111.191104711459;10185.388876667424;10203.515721713295;10229.693109206735;10460.476976151314
00:22:47 INFO - 2015-10-22 00:22:47,338 DEBUG : BROWSER_OUTPUT: __end_tp_report
00:22:47 INFO - 2015-10-22 00:22:47,338 DEBUG : BROWSER_OUTPUT: __start_cc_report
00:22:47 INFO - 2015-10-22 00:22:47,338 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,753
00:22:47 INFO - 2015-10-22 00:22:47,338 DEBUG : BROWSER_OUTPUT: __end_cc_report
00:22:47 INFO - 2015-10-22 00:22:47,338 DEBUG : BROWSER_OUTPUT: __startTimestamp1445498567311__endTimestamp
00:22:47 INFO - 2015-10-22 00:22:47,338 DEBUG : BROWSER_OUTPUT:
00:22:47 INFO - 2015-10-22 00:22:47,338 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
00:22:47 INFO - 2015-10-22 00:22:47,338 DEBUG : BROWSER_OUTPUT: Number of tests: 6
00:22:47 INFO - 2015-10-22 00:22:47,338 DEBUG : BROWSER_OUTPUT:
00:22:47 INFO - 2015-10-22 00:22:47,338 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4974.81 Median:5276.08 stddev:725.66 (13.8%) stddev-sans-first:704.75
00:22:47 INFO - 2015-10-22 00:22:47,338 DEBUG : BROWSER_OUTPUT: Values: 2657.9 2698.3 2739.6 2748.9 2766.9 4946.9 5030.7 5093.2 5178.0 5187.8 5179.1 5185.8 5218.9 5273.6 5276.6 4334.7 4376.1 4411.3 4483.4 4504.0 4186.0 4396.5 4419.1 4432.7 4448.2 4359.9 4443.3 4456.8 4459.8 4461.3 4280.3 4341.1 4341.3 4348.4 4452.2 4186.9 4337.1 4350.3 4397.4 4425.6 5084.8 5122.7 5200.2 5225.5 5250.0 5163.9 5276.1 5297.0 5343.8 5367.7 5393.0 5436.1 5438.2 5519.0 5543.7 4865.6 5041.1 5133.0 5145.5 5147.7 5457.5 5496.6 5519.9 5583.8 5675.8 5317.7 5332.0 5422.0 5444.2 5447.2 5294.4 5408.5 5435.3 5544.9 5548.3 5258.7 5336.6 5340.9 5349.9 5496.9 5373.9 5383.8 5387.3 5492.6 5495.6 3391.2 3426.4 3436.4 3492.8 3499.4 3034.1 3082.8 3105.9 3183.2 3183.8 5177.8 5198.0 5232.2 5272.4 5302.4 5374.7 5406.2 5484.4 5583.5 5592.3 5035.4 5035.9 5074.4 5181.4 5201.2 4265.0 4328.8 4419.1 4425.4 4432.6 5614.4 5688.5 5693.6 5800.0 5808.2 5371.7 5389.4 5471.9 5472.6 5493.6 5365.6 5574.7 5575.6 5750.8 5829.2 5228.8 5311.7 5415.3 5418.5 5442.6 5351.7 5503.3 5504.9 5508.3 5529.1 5348.9 5351.4 5360.7 5445.4 5475.8 5227.8 5280.8 5386.1 5404.9 5410.3 5163.3 5255.8 5279.8 5295.8 5326.9 5139.0 5336.1 5382.5 5486.5 5496.6 5151.3 5153.4 5292.2 5382.2 5382.8
00:22:47 INFO - 2015-10-22 00:22:47,338 DEBUG : BROWSER_OUTPUT:
00:22:47 INFO - 2015-10-22 00:22:47,339 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49612.93 Median:17865.78 stddev:92307.60 (516.7%) stddev-sans-first:92492.87
00:22:47 INFO - 2015-10-22 00:22:47,339 DEBUG : BROWSER_OUTPUT: Values: 491.4 510.7 513.0 514.7 523.2 30932.2 31358.0 38091.2 38360.8 38694.8 39977.4 40372.6 40380.4 40388.4 40444.0 6982.0 7239.1 8313.9 8475.1 8478.7 9697.6 10103.2 10233.6 10316.1 10469.4 9795.3 10063.2 10127.7 10264.2 10269.3 10191.6 10248.8 10263.5 10271.1 10299.7 10054.1 10152.4 10173.5 10181.4 10200.4 37313.0 37707.4 37868.4 38031.8 38063.8 3095.1 3231.6 3262.7 3522.6 3625.1 29200.0 30253.0 30263.8 30643.2 31369.2 2547.5 2688.4 2728.9 2742.4 2802.6 308706.0 309372.0 309613.5 310984.0 313320.0 44910.8 45243.5 51132.2 51926.7 51953.0 53971.7 55592.7 56465.7 56483.7 56598.4 13115.5 13855.7 14014.9 14044.8 14056.9 9872.2 10084.0 10342.2 10423.8 10552.9 16610.4 16830.2 16844.0 16911.3 16923.0 7226.4 7456.1 7484.4 7498.0 7529.7 844.5 897.4 904.6 963.9 1051.5 27843.0 28264.0 28459.6 28534.9 28560.6 985.7 993.3 1006.5 1011.2 1080.6 15101.2 15101.3 15179.3 15241.0 15294.2 374040.0 391687.0 391970.0 398034.0 398614.0 47254.0 48133.0 48144.0 48484.0 48640.8 841.3 1113.7 1195.0 1198.1 1299.5 27643.2 28114.4 28378.4 28420.9 28508.3 38740.6 40161.0 42253.8 42463.4 42683.6 342691.0 342888.0 346647.4 347744.0 347975.0 26556.6 26890.5 26904.3 26910.0 26913.0 38549.4 39425.0 39580.4 39694.0 39719.8 32468.9 33234.8 34945.3 35154.3 35165.0 32273.0 33235.3 33300.2 34072.0 35022.6 33321.0 33794.7 33984.2 34064.7 34140.1 29264.4 29664.9 31140.8 31225.4 31439.0 23761.9 24094.3 24332.4 24430.3 25046.0
00:22:47 INFO - 2015-10-22 00:22:47,339 DEBUG : BROWSER_OUTPUT:
00:22:47 INFO - 2015-10-22 00:22:47,339 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:23245.56 Median:202175.11 stddev:66297.38 (32.8%) stddev-sans-first:66461.03
00:22:47 INFO - 2015-10-22 00:22:47,339 DEBUG : BROWSER_OUTPUT: Values: 327.6 328.0 330.9 335.1 337.3 9574.6 9746.8 17131.7 17452.4 17536.7 10829.8 10880.1 10947.8 11108.2 11134.7 4234.1 4330.0 4419.9 4421.3 4422.3 3553.2 3633.2 4387.2 4804.5 4900.6 4641.1 4852.7 4853.5 4856.1 4856.9 4710.1 4837.4 4837.9 4838.5 4845.7 4013.5 4021.6 4024.8 4026.7 4028.0 12588.9 12707.7 12750.3 12780.2 13216.5 1914.3 1944.3 2040.2 2148.0 2149.9 9652.6 9693.6 9809.8 9957.8 9999.8 1577.8 1609.0 1614.2 1637.0 1659.7 69864.3 70210.6 70757.3 71506.6 71536.3 6054.3 6079.4 6088.5 6128.5 6179.6 6893.4 6949.9 6955.8 6981.6 6990.5 4919.1 4920.4 4922.4 4949.7 4971.9 5192.9 5275.5 5285.1 5325.5 5381.8 2691.5 2714.9 2719.5 2724.9 2736.8 986.0 989.8 997.1 1002.8 1050.1 345.4 396.2 396.4 396.6 402.7 11184.7 11309.2 11489.7 11542.3 11616.7 410.2 417.8 425.7 426.1 427.9 6011.8 6262.2 6263.8 6292.6 6355.8 396562.0 396636.6 399252.0 399501.0 403940.0 79991.6 81080.6 81192.0 82441.0 82545.6 478.8 482.3 482.4 482.9 483.7 10746.1 10932.7 11146.9 11228.6 11310.3 36406.0 37702.6 38687.2 38699.2 39419.6 49119.3 49453.3 49734.5 51071.9 53315.0 5919.9 6025.3 6070.4 6071.2 6071.7 11624.2 11863.1 12081.1 12184.5 12235.7 10098.1 10474.5 10527.5 10527.9 10555.5 10142.3 10149.6 10160.9 10256.4 10471.5 9929.0 10611.8 10723.4 10927.3 10967.9 5309.8 5510.3 5535.1 6076.9 6104.2 10234.2 10466.1 10533.5 10557.2 10566.3
00:22:47 INFO - 2015-10-22 00:22:47,342 DEBUG : BROWSER_OUTPUT:
00:22:47 INFO - 2015-10-22 00:22:47,342 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5954.48 Median:5892.10 stddev:3316.98 (56.3%) stddev-sans-first:3307.63
00:22:47 INFO - 2015-10-22 00:22:47,342 DEBUG : BROWSER_OUTPUT: Values: 1274.8 1352.7 1424.1 1597.5 1601.6 7870.3 8008.7 8476.4 8732.1 8973.7 8536.5 8731.1 8754.5 8794.4 8794.4 2254.5 2279.4 2282.7 2282.9 2284.3 1881.9 1994.4 2252.4 2254.5 2268.6 2194.6 2255.7 2261.0 2265.1 2268.8 1926.0 2209.2 2218.0 2227.7 2229.3 1832.7 2124.4 2192.7 2240.8 2243.3 8677.0 8726.5 8768.6 8769.0 8773.1 5046.1 5060.4 5097.0 5120.9 5122.9 3272.0 3302.0 3305.3 3311.0 3352.2 947.0 958.1 996.3 997.2 997.4 12309.4 12567.8 12598.6 12628.5 12659.5 4327.2 4371.5 4417.0 4456.8 4468.2 10044.4 10274.4 10349.6 10354.2 10362.7 4922.8 4948.7 4974.4 4978.4 4987.3 4478.4 4515.1 4524.8 4539.8 4579.2 3682.8 3697.6 3712.9 3723.6 3748.8 1123.8 1126.3 1143.5 1146.7 1151.7 919.8 940.5 942.5 946.0 946.3 7820.8 7859.3 8066.5 8222.2 8267.5 1001.3 1003.1 1017.6 1027.9 1037.4 5848.7 5935.5 5962.3 5984.9 6333.5 10716.1 10750.8 10753.4 10777.8 10792.7 9920.9 10601.2 10608.7 10615.1 10634.7 7788.5 7923.6 8027.1 8032.6 8038.8 7699.1 7699.7 7717.7 7738.7 7748.0 8791.3 9438.2 9542.5 9591.6 9708.0 9124.3 9531.4 9934.8 9934.8 10010.7 7130.3 7799.9 7871.2 7878.2 7878.8 8550.0 8554.4 8578.1 8582.9 8624.7 7497.7 7838.9 8009.6 8033.0 8133.8 7695.6 7757.2 7889.3 7892.7 7906.3 7233.7 7508.8 7797.6 7856.9 7867.0 6471.3 6483.0 6693.9 6767.2 6770.7 6409.6 6807.8 6852.7 6863.7 6898.6
00:22:47 INFO - 2015-10-22 00:22:47,342 DEBUG : BROWSER_OUTPUT:
00:22:47 INFO - 2015-10-22 00:22:47,344 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5214.58 Median:5963.22 stddev:1614.76 (27.1%) stddev-sans-first:1576.76
00:22:47 INFO - 2015-10-22 00:22:47,344 DEBUG : BROWSER_OUTPUT: Values: 309.0 316.9 320.9 327.5 329.5 5734.5 5822.5 5902.2 5971.3 5973.9 5748.7 5820.5 5872.6 5941.0 5947.6 4250.3 4304.2 4310.8 4323.0 4327.8 4189.4 4194.2 4273.8 4287.8 4321.4 4195.5 4233.0 4251.2 4280.3 4288.8 4270.0 4275.6 4276.9 4282.0 4286.6 3543.6 3725.8 3732.1 3733.8 3743.4 5766.4 5777.6 5778.2 5783.1 5785.6 5009.1 5188.0 5195.5 5209.5 5248.5 6135.4 6252.0 6272.3 6273.8 6274.7 4246.9 4264.4 4308.6 4311.4 4319.1 6309.5 6369.2 6437.1 6439.7 6494.1 4252.8 4304.8 4395.0 4430.2 4444.8 4505.1 4511.1 4515.1 4516.3 4518.2 5064.6 5128.0 5154.1 5184.4 5186.5 5882.3 6008.1 6139.5 6269.8 6297.5 1578.1 1580.9 1609.0 1645.6 1645.9 1034.6 1044.0 1057.6 1061.9 1091.2 5782.8 6010.9 6026.2 6043.1 6048.5 6406.7 6407.0 6423.3 6429.4 6438.1 5958.7 6000.7 6005.8 6028.3 6033.3 3468.0 3469.4 3549.6 3569.1 3583.6 6774.9 6799.7 6806.1 6806.5 6810.4 6226.7 6498.5 6499.9 6515.2 6529.8 6788.3 6788.6 6798.0 6798.1 6798.4 6109.1 6381.4 6422.4 6489.4 6573.8 6382.7 6396.6 6407.9 6414.2 6420.6 6814.6 6873.0 6897.8 6899.1 6936.9 4083.6 4331.6 4363.9 4374.5 4385.0 6479.6 6536.2 6557.7 6587.7 6656.7 6450.4 6467.5 6473.1 6474.8 6481.2 6360.5 6361.1 6380.6 6387.9 6388.8 5967.8 6054.4 6212.6 6273.0 6274.2 6277.4 6314.7 6404.0 6424.8 6426.1 6259.0 6259.2 6261.2 6270.4 6276.6
00:22:47 INFO - 2015-10-22 00:22:47,345 DEBUG : BROWSER_OUTPUT:
00:22:47 INFO - 2015-10-22 00:22:47,345 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12446.99 Median:24700.52 stddev:14293.67 (57.9%) stddev-sans-first:14306.76
00:22:47 INFO - 2015-10-22 00:22:47,345 DEBUG : BROWSER_OUTPUT: Values: 746.9 746.9 754.4 764.2 766.7 12942.3 12969.0 13182.3 13203.9 13208.7 11742.9 11992.9 12035.6 12039.4 12079.9 500.7 505.5 589.1 589.4 598.1 590.1 594.2 594.4 594.9 596.0 620.2 622.3 628.4 628.8 629.8 531.3 532.2 618.7 623.7 626.1 438.6 454.2 454.3 454.9 455.4 12163.1 12222.0 12257.1 12267.6 12270.3 9807.9 9873.5 10068.0 10382.9 11800.5 9773.9 10002.9 10032.3 10058.7 10217.3 3137.4 3155.1 3194.8 3212.1 3256.7 44956.8 46069.8 46573.0 47539.8 47758.8 6852.2 7041.3 7097.3 7214.6 7399.3 10720.0 10722.4 10724.4 10753.0 10768.1 6556.1 6648.4 6664.7 6707.7 6796.8 4236.4 4293.5 4340.6 4352.6 4390.2 2524.3 2611.2 2613.5 2613.7 2618.7 417.5 420.3 421.0 425.0 426.2 597.1 640.5 665.9 692.9 699.7 9544.4 9785.6 9845.5 9862.4 9950.6 704.0 736.7 740.8 751.2 761.3 4175.2 4444.3 4497.6 4511.3 4516.4 50124.2 52138.5 52497.0 53063.2 53403.7 46030.8 46648.0 46728.5 46982.5 47019.0 39112.8 39314.0 39614.4 39822.2 40143.8 8532.2 8545.3 8606.5 8928.8 8994.4 26670.5 27834.9 27955.3 27995.0 28028.6 38040.6 38054.0 39791.4 39856.0 39860.4 7680.9 7750.4 7829.5 7863.1 7890.0 12130.5 12211.6 12321.4 12405.9 12660.4 11527.9 11715.3 11822.2 11881.8 12151.8 11332.8 11417.1 11430.5 11753.0 11979.0 11459.9 11642.7 11676.0 11969.6 12045.9 9773.2 9830.3 9919.0 9987.2 10078.0 10111.2 10185.4 10203.5 10229.7 10460.5
00:22:47 INFO - 2015-10-22 00:22:47,345 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
00:22:47 INFO - 2015-10-22 00:22:47,345 DEBUG : BROWSER_OUTPUT:
00:22:47 INFO - 2015-10-22 00:22:47,769 INFO : Browser exited with error code: 0
00:22:48 INFO - 2015-10-22 00:22:48,530 INFO : Completed test dromaeo_css (00:18:37)
00:22:48 INFO - 2015-10-22 00:22:48,530 INFO : Starting test kraken
00:22:48 INFO - 2015-10-22 00:22:48,530 DEBUG : operating with platform_type : w7_
00:22:48 INFO - 2015-10-22 00:22:48,530 INFO : Initialising browser for kraken test...
00:22:48 INFO - 2015-10-22 00:22:48,555 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpw0_yvf\profile http://localhost:49695/getInfo.html
00:22:52 INFO - 2015-10-22 00:22:52,720 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
00:22:52 INFO - 2015-10-22 00:22:52,720 DEBUG : BROWSER_OUTPUT: colorDepth:24
00:22:52 INFO - 2015-10-22 00:22:52,720 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
00:22:52 INFO - 2015-10-22 00:22:52,720 DEBUG : BROWSER_OUTPUT: __metrics
00:22:52 INFO - 2015-10-22 00:22:52,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]
00:22:54 INFO - 2015-10-22 00:22:54,028 INFO : Browser initialized.
00:22:54 INFO - 2015-10-22 00:22:54,028 INFO : Running cycle 1/1 for kraken test...
00:22:54 INFO - 2015-10-22 00:22:54,028 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpw0_yvf\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1
00:23:05 INFO - 2015-10-22 00:23:05,430 DEBUG : BROWSER_OUTPUT: RSS: Main: 146944000
00:23:05 INFO - 2015-10-22 00:23:05,430 DEBUG : BROWSER_OUTPUT:
00:23:07 INFO - 2015-10-22 00:23:07,786 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49695/tests/kraken/driver.html?testName=audio-beat-detection)
00:23:08 INFO - 2015-10-22 00:23:08,361 DEBUG : BROWSER_OUTPUT: RSS: Main: 181329920
00:23:08 INFO - 2015-10-22 00:23:08,361 DEBUG : BROWSER_OUTPUT:
00:23:10 INFO - 2015-10-22 00:23:10,936 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49695/tests/kraken/driver.html?testName=audio-dft)
00:23:11 INFO - 2015-10-22 00:23:11,499 DEBUG : BROWSER_OUTPUT: RSS: Main: 184745984
00:23:11 INFO - 2015-10-22 00:23:11,499 DEBUG : BROWSER_OUTPUT:
00:23:14 INFO - 2015-10-22 00:23:14,259 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49695/tests/kraken/driver.html?testName=audio-fft)
00:23:14 INFO - 2015-10-22 00:23:14,825 DEBUG : BROWSER_OUTPUT: RSS: Main: 177278976
00:23:14 INFO - 2015-10-22 00:23:14,825 DEBUG : BROWSER_OUTPUT:
00:23:16 INFO - 2015-10-22 00:23:16,852 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49695/tests/kraken/driver.html?testName=audio-oscillator)
00:23:17 INFO - 2015-10-22 00:23:17,411 DEBUG : BROWSER_OUTPUT: RSS: Main: 173318144
00:23:17 INFO - 2015-10-22 00:23:17,413 DEBUG : BROWSER_OUTPUT:
00:23:19 INFO - 2015-10-22 00:23:19,540 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49695/tests/kraken/driver.html?testName=imaging-gaussian-blur)
00:23:20 INFO - 2015-10-22 00:23:20,095 DEBUG : BROWSER_OUTPUT: RSS: Main: 175095808
00:23:20 INFO - 2015-10-22 00:23:20,098 DEBUG : BROWSER_OUTPUT:
00:23:23 INFO - 2015-10-22 00:23:23,217 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49695/tests/kraken/driver.html?testName=imaging-darkroom)
00:23:23 INFO - 2015-10-22 00:23:23,792 DEBUG : BROWSER_OUTPUT: RSS: Main: 171872256
00:23:23 INFO - 2015-10-22 00:23:23,792 DEBUG : BROWSER_OUTPUT:
00:23:27 INFO - 2015-10-22 00:23:27,151 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49695/tests/kraken/driver.html?testName=imaging-desaturate)
00:23:27 INFO - 2015-10-22 00:23:27,717 DEBUG : BROWSER_OUTPUT: RSS: Main: 172331008
00:23:27 INFO - 2015-10-22 00:23:27,717 DEBUG : BROWSER_OUTPUT:
00:23:30 INFO - 2015-10-22 00:23:30,516 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49695/tests/kraken/driver.html?testName=json-parse-financial)
00:23:31 INFO - 2015-10-22 00:23:31,084 DEBUG : BROWSER_OUTPUT: RSS: Main: 172167168
00:23:31 INFO - 2015-10-22 00:23:31,085 DEBUG : BROWSER_OUTPUT:
00:23:32 INFO - 2015-10-22 00:23:32,859 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49695/tests/kraken/driver.html?testName=json-stringify-tinderbox)
00:23:33 INFO - 2015-10-22 00:23:33,414 DEBUG : BROWSER_OUTPUT: RSS: Main: 202452992
00:23:33 INFO - 2015-10-22 00:23:33,414 DEBUG : BROWSER_OUTPUT:
00:23:35 INFO - 2015-10-22 00:23:35,269 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49695/tests/kraken/driver.html?testName=stanford-crypto-aes)
00:23:35 INFO - 2015-10-22 00:23:35,836 DEBUG : BROWSER_OUTPUT: RSS: Main: 207499264
00:23:35 INFO - 2015-10-22 00:23:35,836 DEBUG : BROWSER_OUTPUT:
00:23:38 INFO - 2015-10-22 00:23:38,104 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49695/tests/kraken/driver.html?testName=stanford-crypto-ccm)
00:23:38 INFO - 2015-10-22 00:23:38,667 DEBUG : BROWSER_OUTPUT: RSS: Main: 192184320
00:23:38 INFO - 2015-10-22 00:23:38,667 DEBUG : BROWSER_OUTPUT:
00:23:41 INFO - 2015-10-22 00:23:41,114 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49695/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2)
00:23:41 INFO - 2015-10-22 00:23:41,668 DEBUG : BROWSER_OUTPUT: RSS: Main: 192532480
00:23:41 INFO - 2015-10-22 00:23:41,668 DEBUG : BROWSER_OUTPUT:
00:23:44 INFO - 2015-10-22 00:23:44,716 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49695/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative)
00:23:45 INFO - 2015-10-22 00:23:45,276 DEBUG : BROWSER_OUTPUT: RSS: Main: 193032192
00:23:45 INFO - 2015-10-22 00:23:45,276 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,115 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49695/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49695/tests/kraken/driver.html?testName=ai-astar)
00:23:47 INFO - 2015-10-22 00:23:47,371 DEBUG : BROWSER_OUTPUT: RSS: Main: 239480832
00:23:47 INFO - 2015-10-22 00:23:47,371 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,371 DEBUG : BROWSER_OUTPUT: __start_tp_report
00:23:47 INFO - 2015-10-22 00:23:47,371 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
00:23:47 INFO - 2015-10-22 00:23:47,371 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
00:23:47 INFO - 2015-10-22 00:23:47,371 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
00:23:47 INFO - 2015-10-22 00:23:47,371 DEBUG : BROWSER_OUTPUT: |0;ai-astar;96;87;97;98;92;96;96;95;95;102
00:23:47 INFO - 2015-10-22 00:23:47,371 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;131;132;142;161;137;132;137;144;155;131
00:23:47 INFO - 2015-10-22 00:23:47,371 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;158;156;154;155;153;156;164;154;153
00:23:47 INFO - 2015-10-22 00:23:47,371 DEBUG : BROWSER_OUTPUT: |3;audio-fft;99;104;83;99;76;77;79;84;99;74
00:23:47 INFO - 2015-10-22 00:23:47,371 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;96;113;95;95;95;119;95;95;118
00:23:47 INFO - 2015-10-22 00:23:47,371 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;116;116;116;122;117;124;117;124;117;117
00:23:47 INFO - 2015-10-22 00:23:47,372 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;139;139;141;140;139;140;140;148;139
00:23:47 INFO - 2015-10-22 00:23:47,372 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;92;91;92;92;93;91;91;91;94;92
00:23:47 INFO - 2015-10-22 00:23:47,372 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;79;79;83;79;78;79;79;78;79;90
00:23:47 INFO - 2015-10-22 00:23:47,372 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;75;74;74;74;74;74;74;74;74;76
00:23:47 INFO - 2015-10-22 00:23:47,372 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;97;96;96;97;97;97;97;100;98;102
00:23:47 INFO - 2015-10-22 00:23:47,372 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;126;119;122;116;125;124;121;125;123;119
00:23:47 INFO - 2015-10-22 00:23:47,372 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;185;194;194;196;197;188;203;196;193;187
00:23:47 INFO - 2015-10-22 00:23:47,374 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;86;86;85;84;86;84;85;86;85;88
00:23:47 INFO - 2015-10-22 00:23:47,374 DEBUG : BROWSER_OUTPUT: __end_tp_report
00:23:47 INFO - 2015-10-22 00:23:47,374 DEBUG : BROWSER_OUTPUT: __start_cc_report
00:23:47 INFO - 2015-10-22 00:23:47,374 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,815
00:23:47 INFO - 2015-10-22 00:23:47,374 DEBUG : BROWSER_OUTPUT: __end_cc_report
00:23:47 INFO - 2015-10-22 00:23:47,374 DEBUG : BROWSER_OUTPUT: __startTimestamp1445498627373__endTimestamp
00:23:47 INFO - 2015-10-22 00:23:47,374 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,374 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
00:23:47 INFO - 2015-10-22 00:23:47,374 DEBUG : BROWSER_OUTPUT: Number of tests: 14
00:23:47 INFO - 2015-10-22 00:23:47,374 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:95.40 Median:96.00 stddev:3.89 (4.1%) stddev-sans-first:4.12
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: Values: 96.0 87.0 97.0 98.0 92.0 96.0 96.0 95.0 95.0 102.0
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.20 Median:139.50 stddev:10.51 (7.5%) stddev-sans-first:10.60
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: Values: 131.0 132.0 142.0 161.0 137.0 132.0 137.0 144.0 155.0 131.0
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:156.20 Median:156.00 stddev:3.39 (2.2%) stddev-sans-first:3.44
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: Values: 159.0 158.0 156.0 154.0 155.0 153.0 156.0 164.0 154.0 153.0
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.40 Median:83.50 stddev:11.54 (13.8%) stddev-sans-first:11.45
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: Values: 99.0 104.0 83.0 99.0 76.0 77.0 79.0 84.0 99.0 74.0
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:101.60 Median:95.00 stddev:10.51 (11.1%) stddev-sans-first:10.87
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 113.0 95.0 95.0 95.0 119.0 95.0 95.0 118.0
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:118.60 Median:117.00 stddev:3.34 (2.9%) stddev-sans-first:3.41
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: Values: 116.0 116.0 116.0 122.0 117.0 124.0 117.0 124.0 117.0 117.0
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:141.10 Median:140.00 stddev:3.21 (2.3%) stddev-sans-first:2.88
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: Values: 146.0 139.0 139.0 141.0 140.0 139.0 140.0 140.0 148.0 139.0
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:91.90 Median:92.00 stddev:0.99 (1.1%) stddev-sans-first:1.05
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: Values: 92.0 91.0 92.0 92.0 93.0 91.0 91.0 91.0 94.0 92.0
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:80.30 Median:79.00 stddev:3.68 (4.7%) stddev-sans-first:3.88
00:23:47 INFO - 2015-10-22 00:23:47,375 DEBUG : BROWSER_OUTPUT: Values: 79.0 79.0 83.0 79.0 78.0 79.0 79.0 78.0 79.0 90.0
00:23:47 INFO - 2015-10-22 00:23:47,377 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,377 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:74.30 Median:74.00 stddev:0.67 (0.9%) stddev-sans-first:0.67
00:23:47 INFO - 2015-10-22 00:23:47,377 DEBUG : BROWSER_OUTPUT: Values: 75.0 74.0 74.0 74.0 74.0 74.0 74.0 74.0 74.0 76.0
00:23:47 INFO - 2015-10-22 00:23:47,377 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,377 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:97.70 Median:97.00 stddev:1.89 (1.9%) stddev-sans-first:1.99
00:23:47 INFO - 2015-10-22 00:23:47,377 DEBUG : BROWSER_OUTPUT: Values: 97.0 96.0 96.0 97.0 97.0 97.0 97.0 100.0 98.0 102.0
00:23:47 INFO - 2015-10-22 00:23:47,377 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,377 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:122.00 Median:123.50 stddev:3.23 (2.6%) stddev-sans-first:3.09
00:23:47 INFO - 2015-10-22 00:23:47,378 DEBUG : BROWSER_OUTPUT: Values: 126.0 119.0 122.0 116.0 125.0 124.0 121.0 125.0 123.0 119.0
00:23:47 INFO - 2015-10-22 00:23:47,378 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,378 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.30 Median:195.00 stddev:5.38 (2.8%) stddev-sans-first:4.79
00:23:47 INFO - 2015-10-22 00:23:47,378 DEBUG : BROWSER_OUTPUT: Values: 185.0 194.0 194.0 196.0 197.0 188.0 203.0 196.0 193.0 187.0
00:23:47 INFO - 2015-10-22 00:23:47,378 DEBUG : BROWSER_OUTPUT:
00:23:47 INFO - 2015-10-22 00:23:47,378 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:85.50 Median:86.00 stddev:1.18 (1.4%) stddev-sans-first:1.24
00:23:47 INFO - 2015-10-22 00:23:47,380 DEBUG : BROWSER_OUTPUT: Values: 86.0 86.0 85.0 84.0 86.0 84.0 85.0 86.0 85.0 88.0
00:23:47 INFO - 2015-10-22 00:23:47,380 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
00:23:47 INFO - 2015-10-22 00:23:47,380 DEBUG : BROWSER_OUTPUT:
00:23:48 INFO - 2015-10-22 00:23:48,012 INFO : Browser exited with error code: 0
00:23:48 INFO - 2015-10-22 00:23:48,802 INFO : Completed test kraken (00:01:00)
00:23:48 INFO - 2015-10-22 00:23:48,802 INFO : Starting test v8_7
00:23:48 INFO - 2015-10-22 00:23:48,802 DEBUG : operating with platform_type : w7_
00:23:48 INFO - 2015-10-22 00:23:48,802 INFO : Initialising browser for v8_7 test...
00:23:48 INFO - 2015-10-22 00:23:48,878 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpjbe8fn\profile http://localhost:49695/getInfo.html
00:23:53 INFO - 2015-10-22 00:23:53,487 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
00:23:53 INFO - 2015-10-22 00:23:53,487 DEBUG : BROWSER_OUTPUT: colorDepth:24
00:23:53 INFO - 2015-10-22 00:23:53,487 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
00:23:53 INFO - 2015-10-22 00:23:53,487 DEBUG : BROWSER_OUTPUT: __metrics
00:23:53 INFO - 2015-10-22 00:23:53,615 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
00:23:54 INFO - 2015-10-22 00:23:54,262 DEBUG : BROWSER_OUTPUT: console.error:
00:23:54 INFO - 2015-10-22 00:23:54,262 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]
00:23:54 INFO - 2015-10-22 00:23:54,262 DEBUG : BROWSER_OUTPUT: Stack:
00:23:54 INFO - 2015-10-22 00:23:54,262 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19
00:23:54 INFO - 2015-10-22 00:23:54,262 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9
00:23:54 INFO - 2015-10-22 00:23:54,262 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1
00:23:54 INFO - 2015-10-22 00:23:54,262 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51
00:23:54 INFO - 2015-10-22 00:23:54,262 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
00:23:54 INFO - 2015-10-22 00:23:54,262 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3
00:23:54 INFO - 2015-10-22 00:23:54,262 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14
00:23:54 INFO - 2015-10-22 00:23:54,262 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15
00:23:54 INFO - 2015-10-22 00:23:54,262 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
00:23:54 INFO - 2015-10-22 00:23:54,263 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3
00:23:54 INFO - 2015-10-22 00:23:54,263 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14
00:23:54 INFO - 2015-10-22 00:23:54,263 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12
00:23:54 INFO - 2015-10-22 00:23:54,263 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6421.13
00:24:30 INFO - RETURN: kraken: 1585.50
00:24:30 INFO - RETURN: v8_7: 20759.56
00:24:30 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,25]]", "result": "20759.56"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,25]]", "result": "1585.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,25]]", "result": "6421.13"}}}
00:24:30 INFO - Return code: 0
00:24:30 INFO - # TBPL SUCCESS #
00:24:30 INFO - Running post-action listener: _resource_record_post_action
00:24:30 INFO - Running post-run listener: _resource_record_post_run
00:24:31 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 105344512; Write bytes: 451094016; Read time: 1265730; Write time: 4956830
00:24:31 INFO - install - Wall time: 2s; CPU: 9.0%; Read bytes: 32768; Write bytes: 8754176; Read time: 10630; Write time: 473420
00:24:31 INFO - run-tests - Wall time: 1222s; CPU: 13.0%; Read bytes: 105311744; Write bytes: 424686592; Read time: 1255100; Write time: 4432680
00:24:31 INFO - Running post-run listener: _upload_blobber_files
00:24:31 INFO - Blob upload gear active.
00:24:31 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ...
00:24:31 INFO - Copying logs to upload dir...
00:24:31 INFO - mkdir: C:\slave\test-pgo\build\upload\logs
program finished with exit code 0
elapsedTime=1440.882000
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 6 secs) (at 2015-10-22 00:24:36.748461) =========
========= Started set props: build_url (results: 0, elapsed: 5 secs) (at 2015-10-22 00:24:36.753394) =========
'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-063
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-063
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-063
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/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://queue.taskcluster.net/v1/task/cXnt7tsQRzW83Te29Y3gyQ/artifacts/public/build/firefox-44.0a1.en-US.win32.zip'
========= Finished set props: build_url (results: 0, elapsed: 5 secs) (at 2015-10-22 00:24:42.344180) =========
========= Started 'rm -f ...' (results: 0, elapsed: 17 secs) (at 2015-10-22 00:24:42.344476) =========
'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-063
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-063
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-063
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: 17 secs) (at 2015-10-22 00:25:00.118006) =========
========= Started reboot slave lost (results: 0, elapsed: 28 secs) (at 2015-10-22 00:25:00.118294) =========
========= Finished reboot slave lost (results: 0, elapsed: 28 secs) (at 2015-10-22 00:25:28.784591) =========