builder: mozilla-inbound_win7-ix_test-dromaeojs-pgo
slave: t-w732-ix-028
starttime: 1445951472.82
results: success (0)
buildid: 20151027013036
builduid: b6afc3d232bf4a17bf41d9570a877a77
revision: f1966d9bbf475e1ed4a3aeaaac0be37fdb61c1ab
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-27 06:11:12.818790) =========
master: http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-27 06:11:12.819958) =========
========= Started set props: basedir (results: 0, elapsed: 41 secs) (at 2015-10-27 06:11:12.820260) =========
'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-028
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-028
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-028
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.561000
basedir: 'C:\\slave\\test-pgo'
========= Finished set props: basedir (results: 0, elapsed: 41 secs) (at 2015-10-27 06:11:54.053678) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 2 mins, 16 secs) (at 2015-10-27 06:11:54.053966) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 2 mins, 16 secs) (at 2015-10-27 06:14:10.428684) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 37 secs) (at 2015-10-27 06:14:10.429075) =========
'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-028
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-028
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-028
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: 37 secs) (at 2015-10-27 06:14:48.400496) =========
========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-27 06:14:48.400819) =========
script_repo_url: https://hg.mozilla.org/build/mozharness
========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-27 06:14:48.401523) =========
========= Started 'bash -c ...' (results: 0, elapsed: 27 secs) (at 2015-10-27 06:14:48.401851) =========
'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-028
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-028
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-028
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
--06:14:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 12.53 MB/s
06:14:59 (12.53 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=0.711000
========= Finished 'bash -c ...' (results: 0, elapsed: 27 secs) (at 2015-10-27 06:15:16.351574) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 29 secs) (at 2015-10-27 06:15:16.351916) =========
'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-028
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-028
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-028
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=8.701000
========= Finished 'rm -rf ...' (results: 0, elapsed: 29 secs) (at 2015-10-27 06:15:45.842771) =========
========= Started 'bash -c ...' (results: 0, elapsed: 30 secs) (at 2015-10-27 06:15:45.843140) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev f1966d9bbf475e1ed4a3aeaaac0be37fdb61c1ab --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 f1966d9bbf475e1ed4a3aeaaac0be37fdb61c1ab --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-028
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-028
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-028
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
2015-10-27 06:16:12,125 truncating revision to first 12 chars
2015-10-27 06:16:12,125 Setting DEBUG logging.
2015-10-27 06:16:12,125 attempt 1/10
2015-10-27 06:16:12,125 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/f1966d9bbf47?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2015-10-27 06:16:13,424 unpacking tar archive at: mozilla-inbound-f1966d9bbf47/testing/mozharness/
program finished with exit code 0
elapsedTime=1.974000
========= Finished 'bash -c ...' (results: 0, elapsed: 30 secs) (at 2015-10-27 06:16:16.085040) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 54 secs) (at 2015-10-27 06:16:16.085406) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 54 secs) (at 2015-10-27 06:17:10.230654) =========
========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-27 06:17:10.230911) =========
TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production
========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-27 06:17:10.231278) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 31 secs) (at 2015-10-27 06:17:10.231579) =========
'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-028
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-028
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-028
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
06:17:10 INFO - MultiFileLogger online at 20151027 06:17:10 in C:\slave\test-pgo
06:17:10 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
06:17:10 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json.
06:17:10 INFO - {'append_to_log': False,
06:17:10 INFO - 'base_work_dir': 'C:\\slave\\test-pgo',
06:17:10 INFO - 'blob_upload_branch': 'Mozilla-Inbound',
06:17:10 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt',
06:17:10 INFO - 'branch': 'Mozilla-Inbound',
06:17:10 INFO - 'buildbot_json_path': 'buildprops.json',
06:17:10 INFO - 'config_files': ('talos/windows_config.py',),
06:17:10 INFO - 'default_actions': ('clobber',
06:17:10 INFO - 'read-buildbot-config',
06:17:10 INFO - 'download-and-extract',
06:17:10 INFO - 'populate-webroot',
06:17:10 INFO - 'create-virtualenv',
06:17:10 INFO - 'install',
06:17:10 INFO - 'run-tests'),
06:17:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
06:17:10 INFO - 'download_minidump_stackwalk': True,
06:17:10 INFO - 'download_symbols': 'ondemand',
06:17:10 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python',
06:17:10 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'),
06:17:10 INFO - 'hg': 'c:/mozilla-build/hg/hg',
06:17:10 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python',
06:17:10 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'),
06:17:10 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
06:17:10 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
06:17:10 INFO - 'C:/mozilla-build/tooltool.py'),
06:17:10 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
06:17:10 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
06:17:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
06:17:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
06:17:10 INFO - 'installer_path': 'installer.exe',
06:17:10 INFO - 'log_level': 'info',
06:17:10 INFO - 'log_name': 'talos',
06:17:10 INFO - 'log_to_console': True,
06:17:10 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
06:17:10 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
06:17:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
06:17:10 INFO - 'opt_config_files': (),
06:17:10 INFO - 'pip_index': False,
06:17:10 INFO - 'sps_profile': False,
06:17:10 INFO - 'sps_profile_interval': 0,
06:17:10 INFO - 'suite': 'dromaeojs',
06:17:10 INFO - 'system_bits': '32',
06:17:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
06:17:10 INFO - 'title': 'T-W732-IX-028',
06:17:10 INFO - 'use_talos_json': True,
06:17:10 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
06:17:10 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv',
06:17:10 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
06:17:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
06:17:10 INFO - 'work_dir': 'build'}
06:17:10 INFO - #####
06:17:10 INFO - ##### Running clobber step.
06:17:10 INFO - #####
06:17:10 INFO - Running pre-action listener: _resource_record_pre_action
06:17:10 INFO - Running main action method: clobber
06:17:10 INFO - rmtree: C:\slave\test-pgo\build
06:17:10 INFO - Using _rmtree_windows ...
06:17:10 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1
06:19:52 INFO - Running post-action listener: _resource_record_post_action
06:19:52 INFO - #####
06:19:52 INFO - ##### Running read-buildbot-config step.
06:19:52 INFO - #####
06:19:52 INFO - Running pre-action listener: _resource_record_pre_action
06:19:52 INFO - Running main action method: read_buildbot_config
06:19:52 INFO - Using buildbot properties:
06:19:52 INFO - {
06:19:52 INFO - "properties": {
06:19:52 INFO - "buildnumber": 365,
06:19:52 INFO - "product": "firefox",
06:19:52 INFO - "script_repo_revision": "production",
06:19:52 INFO - "builddir": "mozilla-inbound_win7-ix_test-dromaeojs",
06:19:52 INFO - "repository": "",
06:19:52 INFO - "buildername": "Windows 7 32-bit mozilla-inbound pgo talos dromaeojs",
06:19:52 INFO - "buildid": "20151027013036",
06:19:52 INFO - "slavename": "t-w732-ix-028",
06:19:52 INFO - "pgo_build": "True",
06:19:52 INFO - "basedir": "C:\\slave\\test-pgo",
06:19:52 INFO - "project": "",
06:19:52 INFO - "platform": "win7-ix",
06:19:52 INFO - "master": "http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/",
06:19:52 INFO - "slavebuilddir": "test",
06:19:52 INFO - "scheduler": "tests-mozilla-inbound-win32-pgo-talos",
06:19:52 INFO - "branch": "mozilla-inbound",
06:19:52 INFO - "repo_path": "integration/mozilla-inbound",
06:19:52 INFO - "stage_platform": "win32-pgo",
06:19:52 INFO - "builduid": "b6afc3d232bf4a17bf41d9570a877a77",
06:19:52 INFO - "revision": "f1966d9bbf475e1ed4a3aeaaac0be37fdb61c1ab"
06:19:52 INFO - },
06:19:52 INFO - "sourcestamp": {
06:19:52 INFO - "repository": "",
06:19:52 INFO - "hasPatch": false,
06:19:52 INFO - "project": "",
06:19:52 INFO - "branch": "mozilla-inbound-win32-pgo-talos",
06:19:52 INFO - "changes": [
06:19:52 INFO - {
06:19:52 INFO - "category": null,
06:19:52 INFO - "files": [
06:19:52 INFO - {
06:19:52 INFO - "url": null,
06:19:52 INFO - "name": "https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip"
06:19:52 INFO - }
06:19:52 INFO - ],
06:19:52 INFO - "repository": "",
06:19:52 INFO - "rev": "f1966d9bbf475e1ed4a3aeaaac0be37fdb61c1ab",
06:19:52 INFO - "who": "sendchange",
06:19:52 INFO - "when": 1445950550,
06:19:52 INFO - "number": 6579874,
06:19:52 INFO - "comments": "",
06:19:52 INFO - "project": "",
06:19:52 INFO - "at": "Tue 27 Oct 2015 05:55:50",
06:19:52 INFO - "branch": "mozilla-inbound-win32-pgo-talos",
06:19:52 INFO - "revlink": "",
06:19:52 INFO - "properties": [
06:19:52 INFO - [
06:19:52 INFO - "buildid",
06:19:52 INFO - "20151027013036",
06:19:52 INFO - "Change"
06:19:52 INFO - ],
06:19:52 INFO - [
06:19:52 INFO - "builduid",
06:19:52 INFO - "b6afc3d232bf4a17bf41d9570a877a77",
06:19:52 INFO - "Change"
06:19:52 INFO - ],
06:19:52 INFO - [
06:19:52 INFO - "pgo_build",
06:19:52 INFO - "True",
06:19:52 INFO - "Change"
06:19:52 INFO - ]
06:19:52 INFO - ],
06:19:52 INFO - "revision": "f1966d9bbf475e1ed4a3aeaaac0be37fdb61c1ab"
06:19:52 INFO - }
06:19:52 INFO - ],
06:19:52 INFO - "revision": "f1966d9bbf475e1ed4a3aeaaac0be37fdb61c1ab"
06:19:52 INFO - }
06:19:52 INFO - }
06:19:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip.
06:19:52 INFO - Running post-action listener: _resource_record_post_action
06:19:52 INFO - #####
06:19:52 INFO - ##### Running download-and-extract step.
06:19:52 INFO - #####
06:19:52 INFO - Running pre-action listener: _resource_record_pre_action
06:19:52 INFO - Running main action method: download_and_extract
06:19:52 INFO - mkdir: C:\slave\test-pgo\build\tests
06:19:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
06:19:52 INFO - https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net
06:19:52 INFO - trying https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/test_packages.json
06:19:52 INFO - Downloading https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json
06:19:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1
06:19:53 INFO - Downloaded 1221 bytes.
06:19:53 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json
06:19:53 INFO - Using the following test package requirements:
06:19:53 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'],
06:19:53 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
06:19:53 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'],
06:19:53 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
06:19:53 INFO - u'jsshell-win32.zip'],
06:19:53 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
06:19:53 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'],
06:19:53 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'],
06:19:53 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
06:19:53 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'],
06:19:53 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
06:19:53 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'],
06:19:53 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
06:19:53 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'],
06:19:53 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'],
06:19:53 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
06:19:53 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']}
06:19:53 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common
06:19:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
06:19:53 INFO - https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
06:19:53 INFO - trying https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip
06:19:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/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
06:19:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/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
06:19:55 INFO - Downloaded 17994013 bytes.
06:19:55 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
06:19:55 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip
06:19:55 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760
06:20:27 INFO - Return code: 0
06:20:27 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
06:20:27 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')]}
06:20:27 INFO - https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
06:20:27 INFO - trying https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip
06:20:27 INFO - Downloading https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/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
06:20:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/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
06:20:28 INFO - Downloaded 17994013 bytes.
06:20:28 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
06:20:28 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip
06:20:28 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760
06:20:53 INFO - Return code: 0
06:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
06:20:53 INFO - https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net
06:20:53 INFO - trying https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip
06:20:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/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
06:20:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/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
06:20:55 INFO - Downloaded 11131307 bytes.
06:20:55 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
06:20:55 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip
06:20:55 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760
06:21:00 INFO - Return code: 0
06:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
06:21:00 INFO - https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net
06:21:00 INFO - trying https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
06:21:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip
06:21:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/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
06:21:04 INFO - Downloaded 53208725 bytes.
06:21:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
06:21:04 INFO - mkdir: C:\slave\test-pgo\properties
06:21:04 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url
06:21:04 INFO - Writing to file C:\slave\test-pgo\properties\build_url
06:21:04 INFO - Contents:
06:21:04 INFO - build_url:https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
06:21:04 INFO - Running post-action listener: _resource_record_post_action
06:21:04 INFO - Running post-action listener: set_extra_try_arguments
06:21:04 INFO - #####
06:21:04 INFO - ##### Running populate-webroot step.
06:21:04 INFO - #####
06:21:04 INFO - Running pre-action listener: _resource_record_pre_action
06:21:04 INFO - Running main action method: populate_webroot
06:21:04 INFO - Guessing talos json url...
06:21:04 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')]}
06:21:04 INFO - https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net
06:21:04 INFO - trying https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt
06:21:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt
06:21:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/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
06:21:04 INFO - Downloaded 111 bytes.
06:21:04 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt
06:21:04 INFO - Contents:
06:21:04 INFO - 20151027013036
06:21:04 INFO - https://hg.mozilla.org/integration/mozilla-inbound/rev/f1966d9bbf475e1ed4a3aeaaac0be37fdb61c1ab
06:21:04 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')]}
06:21:04 INFO - trying https://hg.mozilla.org/integration/mozilla-inbound/raw-file/f1966d9bbf/testing/talos/talos.json
06:21:04 INFO - Downloading https://hg.mozilla.org/integration/mozilla-inbound/raw-file/f1966d9bbf/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json
06:21:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/mozilla-inbound/raw-file/f1966d9bbf/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1
06:21:05 INFO - Downloaded 4264 bytes.
06:21:05 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
06:21:05 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
06:21:05 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs',
06:21:05 INFO - u'../fennec_ids.txt'],
06:21:05 INFO - u'tests': [u'tcheck2']},
06:21:05 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
06:21:05 INFO - u'--tppagecycles',
06:21:05 INFO - u'7'],
06:21:05 INFO - u'tests': [u'tsvgm']}},
06:21:05 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']},
06:21:05 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'],
06:21:05 INFO - u'tests': [u'tresize', u'tcanvasmark']},
06:21:05 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']},
06:21:05 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'],
06:21:05 INFO - u'tests': [u'dromaeo_css',
06:21:05 INFO - u'kraken',
06:21:05 INFO - u'v8_7']},
06:21:05 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
06:21:05 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
06:21:05 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
06:21:05 INFO - u'talos_options': [u'--e10s'],
06:21:05 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
06:21:05 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
06:21:05 INFO - u'tests': [u'damp', u'tps']},
06:21:05 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
06:21:05 INFO - u'talos_options': [u'--e10s'],
06:21:05 INFO - u'tests': [u'damp', u'tps']},
06:21:05 INFO - u'g3': {u'tests': [u'dromaeo_dom']},
06:21:05 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
06:21:05 INFO - u'other': {u'tests': [u'a11yr',
06:21:05 INFO - u'ts_paint',
06:21:05 INFO - u'tpaint',
06:21:05 INFO - u'sessionrestore',
06:21:05 INFO - u'sessionrestore_no_auto_restore']},
06:21:05 INFO - u'other-e10s': {u'talos_options': [u'--e10s'],
06:21:05 INFO - u'tests': [u'a11yr',
06:21:05 INFO - u'ts_paint',
06:21:05 INFO - u'tpaint',
06:21:05 INFO - u'sessionrestore',
06:21:05 INFO - u'sessionrestore_no_auto_restore']},
06:21:05 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'],
06:21:05 INFO - u'tests': [u'a11yr',
06:21:05 INFO - u'ts_paint',
06:21:05 INFO - u'tpaint',
06:21:05 INFO - u'sessionrestore',
06:21:05 INFO - u'sessionrestore_no_auto_restore']},
06:21:05 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'],
06:21:05 INFO - u'tests': [u'a11yr',
06:21:05 INFO - u'ts_paint',
06:21:05 INFO - u'tpaint',
06:21:05 INFO - u'sessionrestore',
06:21:05 INFO - u'sessionrestore_no_auto_restore']},
06:21:05 INFO - u'other_l64': {u'tests': [u'a11yr',
06:21:05 INFO - u'ts_paint',
06:21:05 INFO - u'tpaint',
06:21:05 INFO - u'sessionrestore',
06:21:05 INFO - u'sessionrestore_no_auto_restore']},
06:21:05 INFO - u'other_nol64': {u'tests': [u'a11yr',
06:21:05 INFO - u'ts_paint',
06:21:05 INFO - u'tpaint',
06:21:05 INFO - u'sessionrestore',
06:21:05 INFO - u'sessionrestore_no_auto_restore']},
06:21:05 INFO - u'svgr': {u'tests': [u'tsvgx',
06:21:05 INFO - u'tsvgr_opacity',
06:21:05 INFO - u'tart',
06:21:05 INFO - u'tscrollx',
06:21:05 INFO - u'cart']},
06:21:05 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'],
06:21:05 INFO - u'tests': [u'tsvgx',
06:21:05 INFO - u'tsvgr_opacity',
06:21:05 INFO - u'tart',
06:21:05 INFO - u'tscrollx',
06:21:05 INFO - u'cart']},
06:21:05 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
06:21:05 INFO - u'tests': [u'tp5o']},
06:21:05 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
06:21:05 INFO - u'talos_options': [u'--e10s'],
06:21:05 INFO - u'tests': [u'tp5o']},
06:21:05 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
06:21:05 INFO - u'talos_options': [u'--xperf_path',
06:21:05 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
06:21:05 INFO - u'tests': [u'tp5n']},
06:21:05 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
06:21:05 INFO - u'talos_options': [u'--e10s',
06:21:05 INFO - u'--xperf_path',
06:21:05 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"',
06:21:05 INFO - u'C:/slave/talos-data/talos/xperf.config'],
06:21:05 INFO - u'tests': [u'tp5n']}},
06:21:05 INFO - u'talos.zip': {u'path': u'',
06:21:05 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
06:21:05 INFO - Running post-action listener: _resource_record_post_action
06:21:05 INFO - #####
06:21:05 INFO - ##### Running create-virtualenv step.
06:21:05 INFO - #####
06:21:05 INFO - Running pre-action listener: _resource_record_pre_action
06:21:05 INFO - Running main action method: create_virtualenv
06:21:05 INFO - Creating virtualenv C:\slave\test-pgo\build/venv
06:21:05 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts
06:21:05 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll
06:21:05 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'!
06:21:05 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
06:21:05 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
06:21:07 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe
06:21:10 INFO - Installing distribute......................................................................................................................................................................................done.
06:21:11 INFO - Return code: 0
06:21:11 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv
06:21:11 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')]}
06:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:21:11 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')]}
06:21:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:21:11 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 0x018B8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012FE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01218B48>, '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 0x0123A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0193D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-028', 'USERDOMAIN': 'T-W732-IX-028', '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-028', '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
06:21:11 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
06:21:11 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
06:21:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:21:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:21:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:21:11 INFO - 'COMPUTERNAME': 'T-W732-IX-028',
06:21:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:21:11 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:21:11 INFO - 'DCLOCATION': 'SCL3',
06:21:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:21:11 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:21:11 INFO - 'HOMEDRIVE': 'C:',
06:21:11 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:21:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:21:11 INFO - 'KTS_VERSION': '1.19c',
06:21:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:21:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-028',
06:21:11 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:21:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:21:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:21:11 INFO - 'MOZ_NO_REMOTE': '1',
06:21:11 INFO - 'NO_EM_RESTART': '1',
06:21:11 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:21:11 INFO - 'OS': 'Windows_NT',
06:21:11 INFO - 'OURDRIVE': 'C:',
06:21:11 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;',
06:21:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:21:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:21:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:21:11 INFO - 'PROCESSOR_LEVEL': '6',
06:21:11 INFO - 'PROCESSOR_REVISION': '1e05',
06:21:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:21:11 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:21:11 INFO - 'PROMPT': '$P$G',
06:21:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
06:21:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:21:11 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:21:11 INFO - 'PWD': 'C:\\slave\\test-pgo',
06:21:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:21:11 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:21:11 INFO - 'SYSTEMDRIVE': 'C:',
06:21:11 INFO - 'SYSTEMROOT': 'C:\\windows',
06:21:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:21:11 INFO - 'TEST1': 'testie',
06:21:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:21:11 INFO - 'USERDOMAIN': 'T-W732-IX-028',
06:21:11 INFO - 'USERNAME': 'cltbld',
06:21:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:21:11 INFO - 'WINDIR': 'C:\\windows',
06:21:11 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:21:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:21:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:21:13 INFO - Ignoring indexes: http://pypi.python.org/simple/
06:21:13 INFO - Downloading/unpacking pip>=1.5
06:21:13 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache
06:21:17 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
06:21:17 INFO - Running setup.py egg_info for package pip
06:21:17 INFO - warning: no files found matching 'pip\cacert.pem'
06:21:17 INFO - warning: no files found matching '*.html' under directory 'docs'
06:21:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
06:21:17 INFO - no previously-included directories found matching 'docs\_build\_sources'
06:21:17 INFO - Installing collected packages: pip
06:21:17 INFO - Found existing installation: pip 0.8.2
06:21:17 INFO - Uninstalling pip:
06:21:17 INFO - Successfully uninstalled pip
06:21:17 INFO - Running setup.py install for pip
06:21:17 INFO - warning: no files found matching 'pip\cacert.pem'
06:21:17 INFO - warning: no files found matching '*.html' under directory 'docs'
06:21:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
06:21:17 INFO - no previously-included directories found matching 'docs\_build\_sources'
06:21:17 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:17 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:17 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:17 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:17 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:17 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:17 INFO - Successfully installed pip
06:21:17 INFO - Cleaning up...
06:21:17 INFO - Return code: 0
06:21:17 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv
06:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
06:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
06:21:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:21:17 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x018B8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012FE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01218B48>, '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 0x0123A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0193D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-028', 'USERDOMAIN': 'T-W732-IX-028', '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-028', '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
06:21:17 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in C:\slave\test-pgo\build
06:21:17 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1
06:21:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:21:17 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:21:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:21:17 INFO - 'COMPUTERNAME': 'T-W732-IX-028',
06:21:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:21:17 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:21:17 INFO - 'DCLOCATION': 'SCL3',
06:21:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:21:17 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:21:17 INFO - 'HOMEDRIVE': 'C:',
06:21:17 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:21:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:21:17 INFO - 'KTS_VERSION': '1.19c',
06:21:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:21:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-028',
06:21:17 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:21:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:21:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:21:17 INFO - 'MOZ_NO_REMOTE': '1',
06:21:17 INFO - 'NO_EM_RESTART': '1',
06:21:17 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:21:17 INFO - 'OS': 'Windows_NT',
06:21:17 INFO - 'OURDRIVE': 'C:',
06:21:17 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:21:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:21:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:21:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:21:17 INFO - 'PROCESSOR_LEVEL': '6',
06:21:17 INFO - 'PROCESSOR_REVISION': '1e05',
06:21:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:21:17 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:21:17 INFO - 'PROMPT': '$P$G',
06:21:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
06:21:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:21:17 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:21:17 INFO - 'PWD': 'C:\\slave\\test-pgo',
06:21:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:21:17 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:21:17 INFO - 'SYSTEMDRIVE': 'C:',
06:21:17 INFO - 'SYSTEMROOT': 'C:\\windows',
06:21:17 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:21:17 INFO - 'TEST1': 'testie',
06:21:17 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:21:17 INFO - 'USERDOMAIN': 'T-W732-IX-028',
06:21:17 INFO - 'USERNAME': 'cltbld',
06:21:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:21:17 INFO - 'WINDIR': 'C:\\windows',
06:21:17 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:21:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:21:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:21:19 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:21:19 INFO - Downloading/unpacking psutil>=0.7.1
06:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
06:21:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
06:21:20 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
06:21:20 INFO - Installing collected packages: psutil
06:21:20 INFO - Successfully installed psutil
06:21:20 INFO - Cleaning up...
06:21:20 INFO - Return code: 0
06:21:20 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv
06:21:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
06:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:21:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
06:21:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:21:20 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x018B8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012FE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01218B48>, '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 0x0123A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0193D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-028', 'USERDOMAIN': 'T-W732-IX-028', '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-028', '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
06:21:20 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in C:\slave\test-pgo\build
06:21:20 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0
06:21:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:21:20 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:21:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:21:20 INFO - 'COMPUTERNAME': 'T-W732-IX-028',
06:21:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:21:20 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:21:20 INFO - 'DCLOCATION': 'SCL3',
06:21:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:21:20 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:21:20 INFO - 'HOMEDRIVE': 'C:',
06:21:20 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:21:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:21:20 INFO - 'KTS_VERSION': '1.19c',
06:21:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:21:20 INFO - 'LOGONSERVER': '\\\\T-W732-IX-028',
06:21:20 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:21:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:21:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:21:20 INFO - 'MOZ_NO_REMOTE': '1',
06:21:20 INFO - 'NO_EM_RESTART': '1',
06:21:20 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:21:20 INFO - 'OS': 'Windows_NT',
06:21:20 INFO - 'OURDRIVE': 'C:',
06:21:20 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:21:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:21:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:21:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:21:20 INFO - 'PROCESSOR_LEVEL': '6',
06:21:20 INFO - 'PROCESSOR_REVISION': '1e05',
06:21:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:21:20 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:21:20 INFO - 'PROMPT': '$P$G',
06:21:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
06:21:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:21:20 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:21:20 INFO - 'PWD': 'C:\\slave\\test-pgo',
06:21:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:21:20 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:21:20 INFO - 'SYSTEMDRIVE': 'C:',
06:21:20 INFO - 'SYSTEMROOT': 'C:\\windows',
06:21:20 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:21:20 INFO - 'TEST1': 'testie',
06:21:20 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:21:20 INFO - 'USERDOMAIN': 'T-W732-IX-028',
06:21:20 INFO - 'USERNAME': 'cltbld',
06:21:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:21:20 INFO - 'WINDIR': 'C:\\windows',
06:21:20 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:21:20 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:21:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:21:20 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:21:20 INFO - Downloading/unpacking mozsystemmonitor==0.0.0
06:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
06:21:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
06:21:21 INFO - Downloading mozsystemmonitor-0.0.tar.gz
06:21:21 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
06:21:21 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
06:21:21 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)
06:21:21 INFO - Installing collected packages: mozsystemmonitor
06:21:21 INFO - Running setup.py install for mozsystemmonitor
06:21:22 INFO - Successfully installed mozsystemmonitor
06:21:22 INFO - Cleaning up...
06:21:22 INFO - Return code: 0
06:21:22 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv
06:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
06:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
06:21:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:21:22 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 0x018B8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012FE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01218B48>, '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 0x0123A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0193D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-028', 'USERDOMAIN': 'T-W732-IX-028', '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-028', '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
06:21:22 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
06:21:22 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
06:21:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:21:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:21:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:21:22 INFO - 'COMPUTERNAME': 'T-W732-IX-028',
06:21:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:21:22 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:21:22 INFO - 'DCLOCATION': 'SCL3',
06:21:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:21:22 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:21:22 INFO - 'HOMEDRIVE': 'C:',
06:21:22 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:21:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:21:22 INFO - 'KTS_VERSION': '1.19c',
06:21:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:21:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-028',
06:21:22 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:21:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:21:22 INFO - 'MOZ_NO_REMOTE': '1',
06:21:22 INFO - 'NO_EM_RESTART': '1',
06:21:22 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:21:22 INFO - 'OS': 'Windows_NT',
06:21:22 INFO - 'OURDRIVE': 'C:',
06:21:22 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;',
06:21:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:21:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:21:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:21:22 INFO - 'PROCESSOR_LEVEL': '6',
06:21:22 INFO - 'PROCESSOR_REVISION': '1e05',
06:21:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:21:22 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:21:22 INFO - 'PROMPT': '$P$G',
06:21:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
06:21:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:21:22 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:21:22 INFO - 'PWD': 'C:\\slave\\test-pgo',
06:21:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:21:22 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:21:22 INFO - 'SYSTEMDRIVE': 'C:',
06:21:22 INFO - 'SYSTEMROOT': 'C:\\windows',
06:21:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:21:22 INFO - 'TEST1': 'testie',
06:21:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:21:22 INFO - 'USERDOMAIN': 'T-W732-IX-028',
06:21:22 INFO - 'USERNAME': 'cltbld',
06:21:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:21:22 INFO - 'WINDIR': 'C:\\windows',
06:21:22 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:21:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:21:22 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:21:22 INFO - Downloading/unpacking blobuploader==1.2.4
06:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
06:21:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
06:21:23 INFO - Downloading blobuploader-1.2.4.tar.gz
06:21:23 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
06:21:23 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
06:21:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
06:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
06:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
06:21:23 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2frequests-1.2.3.tar.gz
06:21:23 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests
06:21:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
06:21:24 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
06:21:24 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
06:21:24 INFO - Downloading docopt-0.6.1.tar.gz
06:21:24 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
06:21:24 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt
06:21:24 INFO - Installing collected packages: blobuploader, requests, docopt
06:21:24 INFO - Running setup.py install for blobuploader
06:21:24 INFO - Running setup.py install for requests
06:21:25 INFO - Running setup.py install for docopt
06:21:25 INFO - Successfully installed blobuploader requests docopt
06:21:25 INFO - Cleaning up...
06:21:26 INFO - Return code: 0
06:21:26 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv
06:21:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
06:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:21:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
06:21:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:21:26 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018B8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012FE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01218B48>, '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 0x0123A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0193D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-028', 'USERDOMAIN': 'T-W732-IX-028', '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-028', '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
06:21:26 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config
06:21:26 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --no-deps --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
06:21:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:21:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:21:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:21:26 INFO - 'COMPUTERNAME': 'T-W732-IX-028',
06:21:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:21:26 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:21:26 INFO - 'DCLOCATION': 'SCL3',
06:21:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:21:26 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:21:26 INFO - 'HOMEDRIVE': 'C:',
06:21:26 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:21:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:21:26 INFO - 'KTS_VERSION': '1.19c',
06:21:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:21:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-028',
06:21:26 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:21:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:21:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:21:26 INFO - 'MOZ_NO_REMOTE': '1',
06:21:26 INFO - 'NO_EM_RESTART': '1',
06:21:26 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:21:26 INFO - 'OS': 'Windows_NT',
06:21:26 INFO - 'OURDRIVE': 'C:',
06:21:26 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:21:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:21:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:21:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:21:26 INFO - 'PROCESSOR_LEVEL': '6',
06:21:26 INFO - 'PROCESSOR_REVISION': '1e05',
06:21:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:21:26 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:21:26 INFO - 'PROMPT': '$P$G',
06:21:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
06:21:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:21:26 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:21:26 INFO - 'PWD': 'C:\\slave\\test-pgo',
06:21:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:21:26 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:21:26 INFO - 'SYSTEMDRIVE': 'C:',
06:21:26 INFO - 'SYSTEMROOT': 'C:\\windows',
06:21:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:21:26 INFO - 'TEST1': 'testie',
06:21:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:21:26 INFO - 'USERDOMAIN': 'T-W732-IX-028',
06:21:26 INFO - 'USERNAME': 'cltbld',
06:21:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:21:26 INFO - 'WINDIR': 'C:\\windows',
06:21:26 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:21:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:21:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:21:26 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:21:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser
06:21:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hco7yk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser
06:21:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash
06:21:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o3zs1y-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash
06:21:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug
06:21:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3qsn3z-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug
06:21:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice
06:21:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-m3rit6-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice
06:21:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile
06:21:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6shmk5-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile
06:21:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd
06:21:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yq2nbb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd
06:21:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo
06:21:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dl66o_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo
06:21:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall
06:21:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7ywodr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall
06:21:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak
06:21:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3_yn6e-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak
06:21:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog
06:21:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y8gg9n-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog
06:21:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork
06:21:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ioteat-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork
06:21:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess
06:21:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pxh54x-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess
06:21:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile
06:21:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2yc65e-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile
06:21:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner
06:21:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2izhen-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner
06:21:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot
06:21:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e3sk5v-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot
06:21:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest
06:21:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i_cuso-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest
06:21:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion
06:21:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-819s6b-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion
06:21:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
06:21:30 INFO - Running setup.py install for manifestparser
06:21:30 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:30 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:30 INFO - Running setup.py install for mozcrash
06:21:31 INFO - Running setup.py install for mozdebug
06:21:31 INFO - Running setup.py install for mozdevice
06:21:31 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:31 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:31 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:31 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:32 INFO - Running setup.py install for mozfile
06:21:32 INFO - Running setup.py install for mozhttpd
06:21:32 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:32 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:32 INFO - Running setup.py install for mozinfo
06:21:33 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:33 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:33 INFO - Running setup.py install for mozInstall
06:21:33 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:33 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:33 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:33 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:33 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:33 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:33 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:33 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:33 INFO - Running setup.py install for mozleak
06:21:34 INFO - Running setup.py install for mozlog
06:21:34 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:34 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:35 INFO - Running setup.py install for moznetwork
06:21:35 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:35 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:35 INFO - Running setup.py install for mozprocess
06:21:36 INFO - Running setup.py install for mozprofile
06:21:36 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:36 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:36 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:36 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:36 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:36 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:36 INFO - Running setup.py install for mozrunner
06:21:36 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:36 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:37 INFO - Running setup.py install for mozscreenshot
06:21:37 INFO - Running setup.py install for moztest
06:21:38 INFO - Running setup.py install for mozversion
06:21:38 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts
06:21:38 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts
06:21:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
06:21:38 INFO - Cleaning up...
06:21:38 INFO - Return code: 0
06:21:38 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv
06:21:38 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')]}
06:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:21:38 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')]}
06:21:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:21:38 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 0x018B8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012FE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01218B48>, '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 0x0123A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0193D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-028', 'USERDOMAIN': 'T-W732-IX-028', '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-028', '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
06:21:38 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
06:21:38 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
06:21:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:21:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:21:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:21:38 INFO - 'COMPUTERNAME': 'T-W732-IX-028',
06:21:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:21:38 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:21:38 INFO - 'DCLOCATION': 'SCL3',
06:21:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:21:38 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:21:38 INFO - 'HOMEDRIVE': 'C:',
06:21:38 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:21:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:21:38 INFO - 'KTS_VERSION': '1.19c',
06:21:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:21:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-028',
06:21:38 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:21:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:21:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:21:38 INFO - 'MOZ_NO_REMOTE': '1',
06:21:38 INFO - 'NO_EM_RESTART': '1',
06:21:38 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:21:38 INFO - 'OS': 'Windows_NT',
06:21:38 INFO - 'OURDRIVE': 'C:',
06:21: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;',
06:21:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:21:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:21:38 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:21:38 INFO - 'PROCESSOR_LEVEL': '6',
06:21:38 INFO - 'PROCESSOR_REVISION': '1e05',
06:21:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:21:38 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:21:38 INFO - 'PROMPT': '$P$G',
06:21:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
06:21:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:21:38 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:21:38 INFO - 'PWD': 'C:\\slave\\test-pgo',
06:21:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:21:38 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:21:38 INFO - 'SYSTEMDRIVE': 'C:',
06:21:38 INFO - 'SYSTEMROOT': 'C:\\windows',
06:21:38 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:21:38 INFO - 'TEST1': 'testie',
06:21:38 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:21:38 INFO - 'USERDOMAIN': 'T-W732-IX-028',
06:21:38 INFO - 'USERNAME': 'cltbld',
06:21:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:21:38 INFO - 'WINDIR': 'C:\\windows',
06:21:38 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:21:38 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:21:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:21:38 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:21:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser
06:21:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-spjs6z-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser
06:21:39 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))
06:21:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash
06:21:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ahu3fo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash
06:21:39 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))
06:21:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug
06:21:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9qj6wm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug
06:21:39 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))
06:21:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice
06:21:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rld2r5-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice
06:21:39 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))
06:21:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile
06:21:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dnw0w4-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile
06:21:39 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))
06:21:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd
06:21:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nlzkpr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd
06:21:39 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))
06:21:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo
06:21:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1e_hdw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo
06:21:39 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))
06:21:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall
06:21:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k9ugkm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall
06:21:40 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))
06:21:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak
06:21:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eayb2d-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak
06:21:40 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))
06:21:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog
06:21:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2kmhqa-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog
06:21:40 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))
06:21:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork
06:21:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a1xzea-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork
06:21:40 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))
06:21:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess
06:21:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lmfhwj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess
06:21:40 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))
06:21:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile
06:21:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bnxlg5-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile
06:21:40 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))
06:21:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner
06:21:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3fkdzq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner
06:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14))
06:21:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot
06:21:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5uoork-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot
06:21:41 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))
06:21:41 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest
06:21:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ky280y-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest
06:21:41 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))
06:21:41 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion
06:21:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5mgs4q-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion
06:21:41 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))
06:21:41 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))
06:21:41 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))
06:21:41 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))
06:21:41 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))
06:21:41 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))
06:21:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10))
06:21: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
06:21: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
06:21:42 INFO - Downloading blessings-1.5.1.tar.gz
06:21:42 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
06:21:42 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings
06:21:42 INFO - Installing collected packages: blessings
06:21:42 INFO - Running setup.py install for blessings
06:21:42 INFO - Successfully installed blessings
06:21:42 INFO - Cleaning up...
06:21:45 INFO - Return code: 0
06:21:45 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv.
06:21:45 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze']
06:21:45 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze
06:21:46 INFO - Reading from file tmpfile_stdout
06:21:46 INFO - Using _rmtree_windows ...
06:21:46 INFO - Using _rmtree_windows ...
06:21:46 INFO - Current package versions:
06:21:46 INFO - blessings == 1.5.1
06:21:46 INFO - blobuploader == 1.2.4
06:21:46 INFO - docopt == 0.6.1
06:21:46 INFO - manifestparser == 1.1
06:21:46 INFO - mozInstall == 1.12
06:21:46 INFO - mozcrash == 0.16
06:21:46 INFO - mozdebug == 0.1
06:21:46 INFO - mozdevice == 0.46
06:21:46 INFO - mozfile == 1.2
06:21:46 INFO - mozhttpd == 0.7
06:21:46 INFO - mozinfo == 0.8
06:21:46 INFO - mozleak == 0.1
06:21:46 INFO - mozlog == 3.0
06:21:46 INFO - moznetwork == 0.27
06:21:46 INFO - mozprocess == 0.22
06:21:46 INFO - mozprofile == 0.27
06:21:46 INFO - mozrunner == 6.11
06:21:46 INFO - mozscreenshot == 0.1
06:21:46 INFO - mozsystemmonitor == 0.0
06:21:46 INFO - moztest == 0.7
06:21:46 INFO - mozversion == 1.4
06:21:46 INFO - psutil == 3.1.1
06:21:46 INFO - requests == 1.2.3
06:21:46 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv
06:21:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
06:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:21:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
06:21:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:21:46 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 0x018B8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x012FE8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01218B48>, '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 0x0123A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0193D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-028', 'USERDOMAIN': 'T-W732-IX-028', '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-028', '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
06:21:46 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
06:21:46 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
06:21:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:21:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:21:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:21:46 INFO - 'COMPUTERNAME': 'T-W732-IX-028',
06:21:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:21:46 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:21:46 INFO - 'DCLOCATION': 'SCL3',
06:21:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:21:46 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:21:46 INFO - 'HOMEDRIVE': 'C:',
06:21:46 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:21:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:21:46 INFO - 'KTS_VERSION': '1.19c',
06:21:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:21:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-028',
06:21:46 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:21:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:21:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:21:46 INFO - 'MOZ_NO_REMOTE': '1',
06:21:46 INFO - 'NO_EM_RESTART': '1',
06:21:46 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:21:46 INFO - 'OS': 'Windows_NT',
06:21:46 INFO - 'OURDRIVE': 'C:',
06:21:46 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:21:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:21:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:21:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:21:46 INFO - 'PROCESSOR_LEVEL': '6',
06:21:46 INFO - 'PROCESSOR_REVISION': '1e05',
06:21:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:21:46 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:21:46 INFO - 'PROMPT': '$P$G',
06:21:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
06:21:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:21:46 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:21:46 INFO - 'PWD': 'C:\\slave\\test-pgo',
06:21:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:21:46 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:21:46 INFO - 'SYSTEMDRIVE': 'C:',
06:21:46 INFO - 'SYSTEMROOT': 'C:\\windows',
06:21:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:21:46 INFO - 'TEST1': 'testie',
06:21:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:21:46 INFO - 'USERDOMAIN': 'T-W732-IX-028',
06:21:46 INFO - 'USERNAME': 'cltbld',
06:21:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:21:46 INFO - 'WINDIR': 'C:\\windows',
06:21:46 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:21:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:21:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:21:46 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:21:46 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))
06:21:46 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))
06:21:46 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))
06:21:46 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))
06:21:46 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))
06:21:46 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))
06:21:46 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))
06:21:46 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))
06:21:46 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))
06:21:46 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))
06:21:46 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))
06:21:46 INFO - Cleaning up...
06:21:46 INFO - Return code: 0
06:21:46 INFO - Running post-action listener: _resource_record_post_action
06:21:46 INFO - Running post-action listener: _start_resource_monitoring
06:21:46 INFO - Starting resource monitoring.
06:21:46 INFO - #####
06:21:46 INFO - ##### Running install step.
06:21:46 INFO - #####
06:21:46 INFO - Running pre-action listener: _resource_record_pre_action
06:21:46 INFO - Running main action method: install
06:21:46 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze']
06:21:46 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze
06:21:46 INFO - Reading from file tmpfile_stdout
06:21:46 INFO - Using _rmtree_windows ...
06:21:46 INFO - Using _rmtree_windows ...
06:21:46 INFO - Detecting whether we're running mozinstall >=1.0...
06:21:46 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h']
06:21:46 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h
06:21:47 INFO - Reading from file tmpfile_stdout
06:21:47 INFO - Output received:
06:21:47 INFO - Usage: mozinstall-script.py [options] installer
06:21:47 INFO - Options:
06:21:47 INFO - -h, --help show this help message and exit
06:21:47 INFO - -d DEST, --destination=DEST
06:21:47 INFO - Directory to install application into. [default:
06:21:47 INFO - "C:\slave\test-pgo"]
06:21:47 INFO - --app=APP Application being installed. [default: firefox]
06:21:47 INFO - Using _rmtree_windows ...
06:21:47 INFO - Using _rmtree_windows ...
06:21:47 INFO - mkdir: C:\slave\test-pgo\build\application
06:21:47 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']
06:21:47 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
06:21:48 INFO - Reading from file tmpfile_stdout
06:21:48 INFO - Output received:
06:21:48 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe
06:21:48 INFO - Using _rmtree_windows ...
06:21:48 INFO - Using _rmtree_windows ...
06:21:48 INFO - Running post-action listener: _resource_record_post_action
06:21:48 INFO - #####
06:21:48 INFO - ##### Running run-tests step.
06:21:48 INFO - #####
06:21:48 INFO - Running pre-action listener: _resource_record_pre_action
06:21:48 INFO - Running main action method: run_tests
06:21:48 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version']
06:21:48 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version
06:21:48 INFO - Python 2.7.3
06:21:48 INFO - Return code: 0
06:21:48 INFO - grabbing minidump binary from tooltool
06:21:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
06:21:48 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 0x01218B48>, '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 0x0123A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0193D660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1
06:21:48 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
06:21:48 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
06:21:48 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
06:21:49 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmptt7afc
06:21:49 INFO - INFO - File integrity verified, renaming tmptt7afc to win32-minidump_stackwalk.exe
06:21:49 INFO - Return code: 0
06:21:49 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755
06:21:49 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir
06:21:49 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir
06:21:49 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe
06:21:49 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir
06:21:49 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-028', '--webServer', 'localhost'] in C:\slave\test-pgo\build
06:21:49 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName Mozilla-Inbound --suite dromaeojs --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-028 --webServer localhost
06:21:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:21:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:21:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:21:49 INFO - 'COMPUTERNAME': 'T-W732-IX-028',
06:21:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:21:49 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:21:49 INFO - 'DCLOCATION': 'SCL3',
06:21:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:21:49 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:21:49 INFO - 'HOMEDRIVE': 'C:',
06:21:49 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:21:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:21:49 INFO - 'KTS_VERSION': '1.19c',
06:21:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:21:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-028',
06:21:49 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir',
06:21:49 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe',
06:21:49 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:21:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:21:49 INFO - 'MOZ_NO_REMOTE': '1',
06:21:49 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir',
06:21:49 INFO - 'NO_EM_RESTART': '1',
06:21:49 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:21:49 INFO - 'OS': 'Windows_NT',
06:21:49 INFO - 'OURDRIVE': 'C:',
06:21:49 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:21:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:21:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:21:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:21:49 INFO - 'PROCESSOR_LEVEL': '6',
06:21:49 INFO - 'PROCESSOR_REVISION': '1e05',
06:21:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:21:49 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:21:49 INFO - 'PROMPT': '$P$G',
06:21:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
06:21:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:21:49 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:21:49 INFO - 'PWD': 'C:\\slave\\test-pgo',
06:21:49 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos',
06:21:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:21:49 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:21:49 INFO - 'SYSTEMDRIVE': 'C:',
06:21:49 INFO - 'SYSTEMROOT': 'C:\\windows',
06:21:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:21:49 INFO - 'TEST1': 'testie',
06:21:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:21:49 INFO - 'USERDOMAIN': 'T-W732-IX-028',
06:21:49 INFO - 'USERNAME': 'cltbld',
06:21:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:21:49 INFO - 'WINDIR': 'C:\\windows',
06:21:49 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:21:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:21:49 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/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-028', '--webServer', 'localhost'] with output_timeout 3600
06:21:51 INFO - mozversion INFO | application_buildid: 20151027013036
06:21:51 INFO - mozversion INFO | application_changeset: f1966d9bbf475e1ed4a3aeaaac0be37fdb61c1ab
06:21:51 INFO - mozversion INFO | application_display_name: Nightly
06:21:51 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
06:21:51 INFO - mozversion INFO | application_name: Firefox
06:21:51 INFO - mozversion INFO | application_remotingname: firefox
06:21:51 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound
06:21:51 INFO - mozversion INFO | application_vendor: Mozilla
06:21:51 INFO - mozversion INFO | application_version: 44.0a1
06:21:51 INFO - mozversion INFO | platform_buildid: 20151027013036
06:21:51 INFO - mozversion INFO | platform_changeset: f1966d9bbf475e1ed4a3aeaaac0be37fdb61c1ab
06:21:51 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound
06:21:51 INFO - mozversion INFO | platform_version: 44.0a1
06:21:51 INFO - 2015-10-27 06:21:51,098 DEBUG : using testdate: 1445952111
06:21:51 INFO - 2015-10-27 06:21:51,098 DEBUG : actual date: 1445952111
06:21:51 INFO - 2015-10-27 06:21:51,109 INFO : starting webserver on 'localhost:49894'
06:21:51 INFO - 2015-10-27 06:21:51,111 INFO : Starting test suite T-W732-IX-028
06:21:51 INFO - 2015-10-27 06:21:51,111 INFO : Starting test dromaeo_css
06:21:51 INFO - 2015-10-27 06:21:51,111 DEBUG : operating with platform_type : w7_
06:21:51 INFO - 2015-10-27 06:21:51,111 INFO : Initialising browser for dromaeo_css test...
06:21:51 INFO - 2015-10-27 06:21:51,204 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpk4i_f6\profile http://localhost:49894/getInfo.html
06:21:58 INFO - 2015-10-27 06:21:58,427 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
06:21:58 INFO - 2015-10-27 06:21:58,427 DEBUG : BROWSER_OUTPUT: colorDepth:24
06:21:58 INFO - 2015-10-27 06:21:58,427 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
06:21:58 INFO - 2015-10-27 06:21:58,427 DEBUG : BROWSER_OUTPUT: __metrics
06:21:58 INFO - 2015-10-27 06:21:58,573 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
06:22:00 INFO - 2015-10-27 06:21:59,993 DEBUG : BROWSER_OUTPUT: console.error:
06:22:00 INFO - 2015-10-27 06:21:59,993 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]
06:22:00 INFO - 2015-10-27 06:21:59,993 DEBUG : BROWSER_OUTPUT: Stack:
06:22:00 INFO - 2015-10-27 06:21:59,993 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19
06:22:00 INFO - 2015-10-27 06:21:59,993 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9
06:22:00 INFO - 2015-10-27 06:21:59,993 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1
06:22:00 INFO - 2015-10-27 06:21:59,993 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51
06:22:00 INFO - 2015-10-27 06:21:59,993 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
06:22:00 INFO - 2015-10-27 06:21:59,993 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3
06:22:00 INFO - 2015-10-27 06:21:59,993 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14
06:22:00 INFO - 2015-10-27 06:21:59,993 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15
06:22:00 INFO - 2015-10-27 06:21:59,993 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
06:22:00 INFO - 2015-10-27 06:21:59,993 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3
06:22:00 INFO - 2015-10-27 06:21:59,993 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14
06:22:00 INFO - 2015-10-27 06:21:59,993 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12
06:22:00 INFO - 2015-10-27 06:21:59,993 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23
06:41:35 INFO - 2015-10-27 06:41:35,832 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7
06:41:35 INFO - 2015-10-27 06:41:35,832 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11
06:41:35 INFO - 2015-10-27 06:41:35,832 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7
06:41:35 INFO - 2015-10-27 06:41:35,832 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7
06:41:35 INFO - 2015-10-27 06:41:35,832 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13
06:41:35 INFO - 2015-10-27 06:41:35,832 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9
06:41:35 INFO - 2015-10-27 06:41:35,832 DEBUG : BROWSER_OUTPUT:
06:41:36 INFO - 2015-10-27 06:41:36,407 INFO : Browser initialized.
06:41:36 INFO - 2015-10-27 06:41:36,407 INFO : Running cycle 1/1 for v8_7 test...
06:41:36 INFO - 2015-10-27 06:41:36,407 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp_1qvbj\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\v8_7\v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1
06:41:47 INFO - 2015-10-27 06:41:47,818 DEBUG : BROWSER_OUTPUT: RSS: Main: 147128320
06:41:47 INFO - 2015-10-27 06:41:47,818 DEBUG : BROWSER_OUTPUT:
06:42:09 INFO - 2015-10-27 06:42:09,338 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49894/tests/v8_7/run.html (next: http://localhost:49894/tests/v8_7/run.html)
06:42:09 INFO - 2015-10-27 06:42:09,592 DEBUG : BROWSER_OUTPUT: RSS: Main: 503250944
06:42:09 INFO - 2015-10-27 06:42:09,592 DEBUG : BROWSER_OUTPUT:
06:42:09 INFO - 2015-10-27 06:42:09,592 DEBUG : BROWSER_OUTPUT: __start_tp_report
06:42:09 INFO - 2015-10-27 06:42:09,592 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
06:42:09 INFO - 2015-10-27 06:42:09,592 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
06:42:09 INFO - 2015-10-27 06:42:09,592 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
06:42:09 INFO - 2015-10-27 06:42:09,592 DEBUG : BROWSER_OUTPUT: |0;Richards;151.37753557372085
06:42:09 INFO - 2015-10-27 06:42:09,592 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;202.42914979757086
06:42:09 INFO - 2015-10-27 06:42:09,592 DEBUG : BROWSER_OUTPUT: |2;Encrypt;293.68575624082234
06:42:09 INFO - 2015-10-27 06:42:09,592 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5775.862068965517
06:42:09 INFO - 2015-10-27 06:42:09,592 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1412.4293785310736
06:42:09 INFO - 2015-10-27 06:42:09,592 DEBUG : BROWSER_OUTPUT: |5;Earley;675.6756756756756
06:42:09 INFO - 2015-10-27 06:42:09,592 DEBUG : BROWSER_OUTPUT: |6;Boyer;10416.666666666666
06:42:09 INFO - 2015-10-27 06:42:09,594 DEBUG : BROWSER_OUTPUT: |7;RegExp;30636.363636363636
06:42:09 INFO - 2015-10-27 06:42:09,594 DEBUG : BROWSER_OUTPUT: |8;Splay;580.046403712297
06:42:09 INFO - 2015-10-27 06:42:09,594 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;5427.027027027027
06:42:09 INFO - 2015-10-27 06:42:09,594 DEBUG : BROWSER_OUTPUT: __end_tp_report
06:42:09 INFO - 2015-10-27 06:42:09,594 DEBUG : BROWSER_OUTPUT: __startTimestamp1445953329589__endTimestamp
06:42:09 INFO - 2015-10-27 06:42:09,594 DEBUG : BROWSER_OUTPUT:
06:42:09 INFO - 2015-10-27 06:42:09,594 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
06:42:09 INFO - 2015-10-27 06:42:09,594 DEBUG : BROWSER_OUTPUT: Number of tests: 10
06:42:09 INFO - 2015-10-27 06:42:09,594 DEBUG : BROWSER_OUTPUT:
06:42:09 INFO - 2015-10-27 06:42:09,594 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:151.38 Median:151.38 stddev:0.00 (0.0%)
06:42:09 INFO - 2015-10-27 06:42:09,594 DEBUG : BROWSER_OUTPUT: Values: 151.4
06:42:09 INFO - 2015-10-27 06:42:09,594 DEBUG : BROWSER_OUTPUT:
06:42:09 INFO - 2015-10-27 06:42:09,594 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:202.43 Median:202.43 stddev:0.00 (0.0%)
06:42:09 INFO - 2015-10-27 06:42:09,594 DEBUG : BROWSER_OUTPUT: Values: 202.4
06:42:09 INFO - 2015-10-27 06:42:09,594 DEBUG : BROWSER_OUTPUT:
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:293.69 Median:293.69 stddev:0.00 (0.0%)
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: Values: 293.7
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT:
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5775.86 Median:5775.86 stddev:0.00 (0.0%)
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: Values: 5775.9
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT:
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1412.43 Median:1412.43 stddev:0.00 (0.0%)
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: Values: 1412.4
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT:
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:675.68 Median:675.68 stddev:0.00 (0.0%)
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: Values: 675.7
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT:
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10416.67 Median:10416.67 stddev:0.00 (0.0%)
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: Values: 10416.7
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT:
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:30636.36 Median:30636.36 stddev:0.00 (0.0%)
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: Values: 30636.4
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT:
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:580.05 Median:580.05 stddev:0.00 (0.0%)
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: Values: 580.0
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT:
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:5427.03 Median:5427.03 stddev:0.00 (0.0%)
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: Values: 5427.0
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
06:42:09 INFO - 2015-10-27 06:42:09,595 DEBUG : BROWSER_OUTPUT:
06:42:10 INFO - 2015-10-27 06:42:10,193 INFO : Browser exited with error code: 0
06:42:10 INFO - 2015-10-27 06:42:10,851 INFO : Completed test v8_7 (00:00:40)
06:42:11 INFO - 2015-10-27 06:42:10,997 INFO : Completed test suite (00:20:19)
06:42:11 INFO - 2015-10-27 06:42:10,997 DEBUG : Working with test: dromaeo_css
06:42:11 INFO - 2015-10-27 06:42:10,997 DEBUG : Generating results file: dromaeo_css
06:42:11 INFO - 2015-10-27 06:42:10,999 DEBUG : Working with test: kraken
06:42:11 INFO - 2015-10-27 06:42:10,999 DEBUG : Generating results file: kraken
06:42:11 INFO - 2015-10-27 06:42:10,999 INFO : javascript benchmark
06:42:11 INFO - 2015-10-27 06:42:10,999 DEBUG : Working with test: v8_7
06:42:11 INFO - 2015-10-27 06:42:10,999 DEBUG : Generating results file: v8_7
06:42:11 INFO - 2015-10-27 06:42:10,999 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
06:42:11 INFO - 2015-10-27 06:42:11,068 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,63,25]]
06:42:11 INFO - 2015-10-27 06:42:11,068 DEBUG : process_Request line: dromaeo_css 6178.26 graph.html#tests=[[72,63,25]]
06:42:11 INFO - 2015-10-27 06:42:11,068 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
06:42:11 INFO - 2015-10-27 06:42:11,190 DEBUG : process_Request line: kraken 1631.70 graph.html#tests=[[232,63,25]]
06:42:11 INFO - 2015-10-27 06:42:11,190 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
06:42:11 INFO - 2015-10-27 06:42:11,220 DEBUG : process_Request line: v8_7 20410.67 graph.html#tests=[[230,63,25]]
06:42:11 INFO - 2015-10-27 06:42:11,220 INFO : javascript benchmark
06:42:11 INFO - 2015-10-27 06:42:11,223 INFO : TALOSDATA: [{"talos_counters": {}, "results": {"jquery.html": [275.7325394367463, 277.53097265621096, 286.5987617335731, 289.73778730249126, 292.1119795820705, 16352.345906163739, 16353.264103115358, 17054.48836534903, 17302.913485432586, 17875.553111172227, 12396.93801530991, 15562.187264120277, 15660.608484787886, 15832.44586439475, 16994.235259413326, 5271.446498117691, 5376.032314183445, 5381.569474442055, 5384.461553844623, 5396.163594642817, 4347.934780978288, 4690.97654511727, 4703.035877645075, 4708.670393072486, 5313.468653134694, 3580.982095089521, 3638.108663377476, 4693.249080147177, 4805.135075686392, 4809.711417314972, 4682.859514214579, 4749.667523273355, 4777.402824646936, 4791.233402655594, 4796.808127674906, 3968.3849003404516, 4026.134381108076, 4038.353863381845, 4039.414284928698, 4045.5347635022054, 13048.217106973612, 13081.345932703422, 13211.40548675312, 13214.207147571176, 13276.867231327757, 1196.7427003194268, 1934.0619799397282, 1968.1635304995464, 2082.1994417508577, 2082.8437867160023, 9777.755556111155, 10015.298929074968, 10144.492775361203, 10180.23648226386, 10193.796124077477, 1602.6073611965026, 1633.3058450158635, 1655.9420420285232, 1693.7459381092935, 1699.17589968865, 69153.30846691519, 71913.28086719118, 71990.0, 72363.0, 73127.26872731258, 6015.669138197353, 6196.411340922605, 6245.28179259391, 6298.212723409574, 6341.143945567382, 6855.0, 7232.240614735482, 7262.20115787263, 7269.854602908018, 7274.636268186569, 5216.9739151304, 5237.188235823453, 5246.658967167122, 5246.973765131226, 5262.0, 5141.740273632984, 5270.7101109439345, 5588.9720551397795, 5591.41290164535, 5634.830955071355, 2677.290518012728, 2680.879360428746, 2725.4957832800997, 2827.067067867599, 2844.7155284471387, 940.3605548227271, 956.5121787888227, 982.4498280962683, 982.6364245229167, 1045.9958439897632, 392.1764294980518, 405.2502869691048, 405.7869618450278, 424.60511724096267, 450.5809597074752, 11143.275687080313, 11166.720831979266, 11211.495482703298, 11260.831087533694, 11627.59303424376, 435.1905455852112, 439.949405818335, 449.85379751580865, 454.4115370595087, 462.3319303606324, 6109.20580324555, 6156.846078848064, 6185.41238582334, 6319.33646967071, 6331.335209802996, 307473.0, 310299.0, 420514.0, 424842.0, 425835.0, 75877.24122758812, 77122.6143869277, 77312.61343693358, 78838.0, 79219.60390198012, 452.1093445911549, 471.7429001194357, 475.09731510130337, 479.2715073088955, 479.3983550643989, 10387.844182337421, 10887.673369798918, 10949.124070074535, 10993.725156871142, 11081.0, 38183.0, 39395.80302098471, 39680.801595991834, 40215.79892100521, 40610.18779624332, 53258.20112698235, 53808.46191538034, 54424.0, 54489.45510544844, 54794.17808732952, 5651.265335506358, 5965.164876917148, 5991.161237426851, 6037.939620603914, 6062.393760623902, 11849.763004739685, 12190.205735165326, 12318.260904345409, 12322.137450378388, 12335.629931102081, 10110.39337639744, 10326.432046237529, 10399.63601273982, 10423.68728938133, 10434.165266778487, 9446.574904129468, 9631.855522167314, 10158.6444474446, 10237.9488102559, 10270.127039201752, 10217.540210690686, 10866.0, 10871.945640271748, 10893.237473376788, 10908.61819836302, 6050.969745151246, 6076.756929722762, 6091.299500557316, 6094.939050609437, 6294.748210071546, 10586.735331616772, 10638.255322127377, 10818.0, 10847.45762711877, 10897.727556811144], "dojo.html": [2611.0208671748096, 2667.333166708322, 2700.257429206969, 2705.296622878051, 2713.0, 5025.170846810276, 5056.342675452186, 5065.670731402456, 5155.3040339554145, 5162.096633089203, 5098.286239926413, 5099.388073431193, 5166.095933211683, 5195.246689230059, 5203.167493201088, 4379.146066517031, 4418.734875907456, 4495.213337665912, 4515.232410490208, 4516.412866327381, 4391.187630288391, 4392.209402307583, 4413.668974826885, 4415.646748260131, 4427.180971520263, 4458.866234012985, 4475.910481790363, 4481.686281960264, 4495.3931219285305, 4499.550044995507, 4296.5273819879785, 4400.779961001953, 4479.484859241196, 4482.865514034552, 4492.573205545468, 4263.424437700902, 4354.085642015149, 4438.3564383164585, 4458.0415210729525, 4466.0, 4789.736564488951, 4827.8551643450755, 5095.464976177522, 5220.138677118271, 5228.189630607262, 4970.527799859007, 5103.974480127576, 5149.742512874379, 5195.0, 5199.896002079937, 5154.948450515484, 5313.840584782462, 5380.973095134538, 5392.0294347017525, 5504.064309067428, 5018.673786203922, 5023.472535383805, 5041.344625198737, 5054.545090941796, 5127.7948882044675, 5238.004779091961, 5343.278657381282, 5452.100403433389, 5543.8891222176135, 5634.098544232903, 5027.899442011212, 5171.741412929414, 5250.921236181384, 5410.810621628303, 5422.295101636762, 5370.167624018283, 5370.248165256867, 5430.864228394322, 5440.483154100354, 5463.453654634584, 5105.5149760772665, 5114.488551144856, 5179.533841954242, 5406.891862162814, 5453.64551304164, 5249.816256431007, 5336.573074154058, 5348.812791552277, 5387.461253874581, 5469.753861076261, 3212.8072315661134, 3390.0507857799857, 3439.9852043647184, 3440.208751987057, 3441.2257242120822, 2979.6275465566805, 3000.2799328161086, 3001.474741920199, 3006.2334104803135, 3142.0, 4757.0, 4843.4430040545785, 4858.514148585114, 4865.051314993613, 4945.604351651859, 5055.595552355802, 5064.164412871909, 5140.665856719302, 5446.183072539151, 5459.0, 5118.616103792156, 5216.739163041832, 5308.708021058879, 5314.441983591744, 5319.0957537218765, 3863.942040869389, 4089.795510224477, 4250.383694364362, 4307.569243075667, 4418.734875907456, 5223.921641175461, 5255.5532779713385, 5336.733163341817, 5350.384705758887, 5416.945830541802, 5169.689818610896, 5264.552513036435, 5387.353517577916, 5525.585581081516, 5528.336599608073, 5237.869053273698, 5511.53151982086, 5619.213310136666, 5624.943750562442, 5708.885822283614, 5238.0833354162105, 5287.0, 5359.919601206063, 5367.946320536901, 5371.785128594969, 5208.713520756395, 5238.68567885928, 5351.411344752152, 5441.83674489766, 5560.443955604407, 5212.582993360598, 5263.947360526346, 5296.841094767009, 5309.973450132725, 5405.189221616789, 5216.504432078947, 5221.321228240305, 5295.338082739658, 5311.867203319948, 5323.680579165262, 5185.118529849859, 5188.481151884781, 5301.072312345401, 5308.946910531, 5309.628326017141, 5214.608904332114, 5285.497877701612, 5289.153735402471, 5334.11987022137, 5421.0, 5207.635465517377, 5282.630215885005, 5285.048691235615, 5330.067238233371, 5373.086575282133], "mootools.html": [1092.9617463388784, 1099.373357186404, 1138.9886101138989, 1362.6116556781312, 1469.8456662050492, 5848.5321174305955, 6002.159697642333, 6686.799396018115, 6694.665266736662, 6808.6255255960905, 6388.361163883609, 6559.3112723164095, 6562.934370656292, 6944.930550694491, 6964.408025317842, 1916.3675986924281, 1920.4526709887684, 2037.8064083912004, 2040.6632905570564, 2040.928567500138, 1755.7103077992117, 1782.0376996421917, 2010.909509072088, 2033.613613413454, 2051.866628669139, 2026.0, 2041.3773798991301, 2044.4684382060623, 2045.1819272291127, 2045.642012647788, 1851.7037274036156, 1872.9438116856516, 1924.9711254331196, 1957.6280506703752, 1966.3117908731972, 1988.2146552111979, 1993.3123072540095, 1998.400479856052, 2017.243533674872, 2019.151956178394, 6358.87282254357, 6818.2159051708695, 6827.965860170717, 6832.6925288362145, 6839.316068393171, 4112.0, 4216.072464057902, 4226.830926762926, 4229.830806767725, 4238.618524332794, 2854.0, 2907.752841008517, 2917.1102813641833, 2926.4878646236825, 2938.0598208573265, 746.664001199457, 778.1479280188184, 851.2466467176531, 888.0320450708693, 889.4174315823146, 8328.458650187778, 8493.702720404817, 8564.357673174474, 8602.956985215096, 9015.233705135071, 3602.9279414411567, 3675.9264814703556, 3702.4631428443263, 3729.086373838427, 3733.663970242637, 7500.662470188855, 7855.7250496232355, 7989.800254993672, 7991.800204994805, 8006.039275286887, 4104.4664193654635, 4167.645750111214, 4329.502107257643, 4331.805068771912, 4331.935020974688, 3998.0404702871683, 4002.9799851000557, 4005.2590270799997, 4005.439238506611, 4010.218007488512, 2848.7151284871347, 2852.9286767830554, 2981.016264632666, 2992.6408830940427, 3025.2588115911744, 924.7318277699526, 943.3726571829657, 958.6644674364056, 961.7739831139677, 982.2142286171035, 1017.8778546574458, 1056.6196169379018, 1059.5920570580229, 1062.9742298681808, 1111.6831702964616, 6301.6534090625455, 6353.936460635427, 6359.618422894641, 6361.459275961595, 6362.840928976812, 371.1815446939492, 393.7243929249537, 397.35826639976233, 397.64609497547207, 402.50089888538, 4717.787699553528, 4870.0746858096845, 4897.681650692693, 4975.577075948585, 5011.198208286657, 7720.845583088307, 7756.146823849372, 7980.521168729895, 8037.196280371916, 8182.427230093956, 7966.800829979181, 8126.065502467174, 8127.674893004332, 8145.551994640233, 8146.389020823462, 5827.475527202572, 6045.969770151209, 6279.246490421087, 6462.676866156768, 6506.446952009038, 5523.972380138073, 5796.9420305798085, 5947.1079338099635, 6084.60450070744, 6162.476189523762, 6741.1910570731825, 6750.628715420503, 7189.604571748604, 7212.927870721225, 7215.567065976058, 6948.965255173692, 7271.127464704269, 7329.303716146957, 7347.853042939005, 7444.069491313586, 6293.811185664437, 6352.968235158794, 6354.809355719336, 6358.332375100733, 6361.586496877688, 6102.603330783485, 6413.134226879498, 6660.866782664417, 6733.192016957996, 6743.460523158234, 5932.940670593239, 5967.910481342697, 5995.430434108753, 6156.630602163885, 6351.0, 5471.206675032177, 5790.305163380422, 6122.204113465221, 6156.076588511759, 6260.843478913063, 5874.118882167709, 5924.437178468039, 6193.349698281616, 6281.560290779602, 6287.434130928055, 5263.394709608444, 5263.447338029605, 5527.8894422112135, 5578.60949733531, 5594.440555944373, 5672.347680016851, 5678.432156784289, 5687.317521897398, 5693.718913244486, 5703.0], "ext.html": [460.7673125071838, 475.5862399712251, 494.4709161197518, 501.78674063523016, 502.54770706364246, 29621.185987331195, 29733.10800675978, 29734.0, 37254.0, 38864.0, 39155.21689566214, 40879.79560102196, 41074.0, 41094.794526027326, 41113.58886411142, 8138.430309878311, 8431.747047588551, 8580.957095214515, 8593.656253749843, 8609.096044915288, 7528.51064680794, 7706.0, 10264.846027309597, 10643.467826608678, 10708.089812365959, 10512.0, 10621.84067238992, 10653.147748180127, 10661.78676426471, 10665.040146386824, 7578.583177925212, 7722.691092356299, 10637.468126593678, 10729.517171727291, 10766.623168189075, 10386.636467723594, 10554.841677374845, 10642.680719578426, 10648.893511064867, 10650.840237396445, 37904.620953790385, 37911.81044094789, 38226.80886595577, 38351.23297534034, 38352.80823595864, 3118.501039833616, 3202.8558714857886, 3231.579894613709, 3253.6258330292085, 3509.718682856164, 29015.709842901513, 29942.401151977057, 30191.0, 30487.23781905448, 31142.22144446407, 2535.492901419705, 2650.1387049208893, 2694.9326266843486, 2748.862556872168, 2772.7365900239447, 310293.0, 310972.0, 311897.0, 312190.43904780556, 312424.43787780916, 45202.0, 45933.77033114813, 53452.19821702677, 53720.462795372325, 53800.46199538033, 58184.0, 59104.408955909894, 59303.70348148232, 59325.40674593285, 59325.703371482865, 12762.872371276355, 13685.31573421345, 13712.794308085387, 13753.312334383241, 13791.793123103162, 10347.223958203165, 10358.067773900386, 10361.4301213434, 10372.740681483023, 10400.843987340197, 16495.670086598202, 16918.238679259463, 17312.134393280285, 17470.47588572345, 17476.650466990843, 7209.0, 7218.314260145278, 7408.481406301507, 7566.497933729423, 7656.540607563564, 826.8428998490267, 857.0957639690187, 890.7550423633553, 942.9434233945985, 981.455292312759, 26911.461770764865, 27042.864785675945, 27529.31176720598, 27538.724612754017, 28196.577051344248, 913.6345461815195, 978.1441238915949, 983.4246965525248, 988.3180605382263, 1072.3512275073624, 13976.720465590632, 14224.0, 14249.216293103771, 14272.000959932913, 14568.927155364367, 348279.0, 355158.0, 355631.0, 355835.0, 356451.0, 47303.5269647306, 47561.0, 48000.0, 48046.27930581044, 48083.519164807905, 1104.812181929074, 1120.260627985542, 1157.3692337675986, 1174.788538063157, 1183.7336599265268, 23014.884925575934, 26711.599326010513, 27050.323741905824, 27198.864005680636, 29240.0, 42469.575304247795, 44630.55369446264, 44893.55106448894, 45122.77438612786, 45213.32180017368, 295227.0, 295728.0, 300490.0, 313247.0, 371395.0, 22910.73990930448, 25374.372713277575, 27428.86285568639, 27672.861635691694, 27695.44609107766, 37059.0, 37388.439173411876, 37651.246975060894, 39301.0, 39405.605943941344, 31181.53227701632, 31208.21979450441, 31520.369592608477, 32280.414102686318, 34065.31869362549, 31094.53358199674, 31916.6808331923, 32804.67195328016, 32984.83507582447, 33783.0, 35103.82448087743, 35577.1105722359, 35639.28721425609, 35832.28335433329, 35843.82078089593, 28545.28636784097, 29880.402391952473, 30259.0, 30774.230644234074, 31204.843975779975, 23879.41808728635, 24436.26691199267, 24718.0, 24993.75006249914, 25389.0], "prototype.html": [274.872184434238, 278.1932396051451, 278.97489225969656, 281.24485755745826, 290.60187543065996, 5650.63270887392, 5658.91511627326, 5667.5749318801045, 5677.772889084432, 5681.488666020057, 5583.441655834415, 5590.105583106703, 5600.719964001804, 5603.943960560393, 5610.102383618621, 3804.1250512382167, 3822.311983842907, 3838.769673819566, 3839.7921195312597, 3852.287326844543, 3782.8486860525545, 3792.639699228569, 3856.402257650069, 3857.4599556062176, 3985.6612187964056, 3849.1724279279947, 3885.844566217362, 4042.130941847502, 4042.959570404302, 4058.6144316289906, 4068.7965601720093, 4070.043539768152, 4072.9185416291803, 4074.0425999890003, 4079.0, 3258.4460641690735, 3548.3790336690977, 3551.058969373118, 3554.2180720241504, 3561.8397172127316, 4994.750262486861, 5117.46266642001, 5133.640645154841, 5501.477359650827, 5528.5024347808485, 4811.975940120312, 4813.735244561548, 4814.422269327703, 4814.831480898187, 4817.855464336076, 5618.0, 5711.428857114297, 5822.563307751893, 5823.2138661280605, 5826.747249341412, 3976.681865450757, 3980.7014473914282, 3981.402789581557, 3981.5620281768984, 3985.1631157456677, 6024.939750602525, 6055.152278680988, 6077.361877002895, 6086.147939288503, 6115.143879856868, 3995.5604883462797, 4008.318585840414, 4010.1378203685763, 4012.378081397388, 4014.578469260686, 4043.4541336919137, 4058.411530328086, 4065.6137666921595, 4067.5322337930925, 4069.064115253449, 4459.977700111479, 4469.262571675637, 4477.440319960005, 4697.295405689174, 4766.928496072562, 5850.9414905851245, 5867.2959244890035, 5872.442117998783, 5874.412558744177, 5874.618149820248, 1368.4868174434587, 1410.0200360749182, 1463.817022872141, 1466.5013895275658, 1480.060161797245, 970.2771435280761, 998.8751406074241, 1010.3533738407425, 1014.4369874719454, 1016.6340117557673, 5215.6609820361555, 5362.115250983544, 5536.086545719992, 5680.573956953162, 5681.2330335404195, 5942.168096466498, 5978.760849566055, 6011.579189456696, 6012.158297838306, 6018.428249316308, 5543.334799824047, 5668.546516278688, 5672.5745569081655, 5679.346875109332, 5686.857828554319, 3157.1372889995014, 3272.5745653065424, 3315.8839440619463, 3352.2122301259183, 3353.782004169721, 6379.744810207632, 6394.392532709385, 6446.484281257488, 6451.612903225821, 6451.999940009306, 6090.994985827378, 6149.754009839557, 6194.876102478015, 6232.688365581703, 6233.3143354231, 5995.760169593254, 6223.533235007302, 6293.811185664437, 6424.164858568356, 6428.292887782433, 5865.090910908816, 6174.845628859314, 6201.875962480815, 6206.658633775186, 6212.534059945432, 6048.848778780566, 6076.210092687922, 6090.756369745161, 6095.238095238095, 6099.84750381244, 5962.582619216613, 6189.50483961273, 6458.838529036811, 6495.480361571157, 6502.057201705632, 3734.4958430612605, 3761.9623803762706, 3764.8705388383923, 3766.0, 3866.052817059838, 6148.815535533941, 6158.846028849314, 6169.413905678954, 6173.506119510339, 6176.845578860564, 5628.0, 5660.15097735343, 5882.352941176553, 6003.939960600338, 6005.969970150296, 5852.4147585241135, 5921.171036055042, 5945.881082378415, 5952.702364881825, 6014.308354539284, 5789.1895134680335, 5800.8259752207505, 5812.244408226903, 5813.040848260159, 5817.0983497557945, 5979.940200597938, 5981.491573216152, 5987.700614969147, 5988.041913293852, 5991.670458124845, 5848.678322692293, 5862.824115276549, 5877.324107727666, 5888.116782482662, 5896.14505896634], "yui.html": [634.4892361648871, 662.5958165519032, 674.861653361061, 744.7430636430431, 767.3055884424596, 13535.729285414285, 14029.579112626612, 14117.576472705823, 14180.290985450712, 14241.786373204397, 12213.389330533482, 12536.310502922335, 12600.180988235757, 12641.55754548591, 12766.489340426371, 470.67288234676914, 473.96919200252046, 563.8393057978467, 569.5870493891916, 597.2325561653277, 568.3038278109325, 596.2219303808528, 601.2724603230097, 606.1877084706492, 606.3481757111101, 592.602956019468, 606.8452544601121, 615.6398506873485, 629.8456878064881, 630.2940706408806, 511.69809812210974, 517.420489052262, 613.1691557939017, 622.9719662615194, 628.1770880147, 440.38346315158714, 449.7526360501744, 450.7115446114457, 450.79038247214874, 451.82830524400845, 12834.550790722373, 12860.871391286155, 12901.935490322583, 12945.417456214493, 12945.805812912813, 9817.0, 9825.656102036395, 10253.897461025368, 10481.056704896597, 10743.247972641919, 10089.243306751949, 10217.702208803385, 10306.175505959505, 10509.474526273656, 10897.891021089847, 3175.2061984503875, 3265.5264986576813, 3266.591676040495, 3321.2693207494312, 3372.2243883906594, 55237.44762552363, 56014.43985560133, 56839.93741658464, 56876.0, 57023.714881425745, 6994.755183568551, 7021.29787021304, 7039.472039597051, 7052.294770522906, 7193.89209161863, 10637.734056648645, 10640.78718425627, 10664.0, 10696.679099627023, 10753.892461075446, 6641.169853768279, 6700.765473208512, 6769.881911378578, 6795.762148324884, 6941.618210998343, 4010.1177740896956, 4156.189543039079, 4171.97914010428, 4193.412922190896, 4257.169851878916, 2606.244189185115, 2642.2337522118755, 2648.1658277642864, 2651.3901802585515, 2655.2034389683017, 429.27024059099654, 430.5715812766318, 431.24962565137065, 435.505701029334, 437.0777659139214, 568.9061304884731, 616.7255571270741, 660.8082959306633, 675.2976904019863, 703.0614130136223, 8819.691310804219, 9118.772030699285, 9237.168654821098, 9345.20565751905, 9431.811363772686, 567.3560508822516, 586.4370204603629, 623.7975286113832, 634.9777757778455, 669.9196096468455, 4298.41971333866, 4675.18184317743, 4794.760261986887, 4925.0, 4925.21196608541, 71684.2831571688, 72029.6398518004, 73108.0, 73934.0, 74150.21422750533, 63241.05138422927, 63449.68275158595, 63696.0, 63890.36109638937, 64249.35750642527, 53753.0, 55511.16733249005, 56181.43818561844, 57022.0, 57382.42617573855, 9453.33826632129, 9511.524423778921, 9826.472061456581, 9982.850257246002, 10059.748506287402, 39623.40564891587, 39966.40050399304, 40088.59911400849, 41925.79037104795, 42008.63930885461, 53206.137785177336, 53242.73378633082, 54551.18173227484, 55099.72450137723, 56204.43795561992, 8176.959115204386, 8218.301444377295, 8702.91297087021, 8774.473531588124, 8992.194103316315, 13089.345532723517, 13168.341582921008, 13322.400491978078, 13580.86419135796, 13992.860071399564, 12593.055520835791, 13210.867891320964, 13360.866391335961, 13414.195148291134, 13654.453821847412, 12948.805767913676, 12968.740625187633, 13155.802662960255, 13288.734225315246, 13371.866281337063, 12961.287129207984, 13081.411336489691, 13278.601641950758, 13656.795148072588, 13886.305684715522, 10058.597656093967, 10948.835767463335, 11008.39453830015, 11226.326420414802, 11965.0, 9775.657851975147, 10230.948845256024, 10427.63914012467, 10845.891541084487, 11162.152487769943]}, "summary": {"suite": 6178.256515399027, "subtests": {"jquery.html": {"filtered": 6617.396803845887, "value": 6617.396803845887}, "dojo.html": {"filtered": 4837.858069546202, "value": 4837.858069546202}, "mootools.html": {"filtered": 3773.058224953821, "value": 3773.058224953821}, "ext.html": {"filtered": 17567.707163642976, "value": 17567.707163642976}, "prototype.html": {"filtered": 4351.806380266667, "value": 4351.806380266667}, "yui.html": {"filtered": 6021.875834900571, "value": 6021.875834900571}}}, "test_machine": {"platform": "x86", "osversion": "6.1.7601", "os": "win", "name": "T-W732-IX-028"}, "testrun": {"date": 1445952111, "suite": "dromaeo_css", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "44.0a1", "id": "20151027013036", "branch": "Mozilla-Inbound", "revision": "f1966d9bbf475e1ed4a3aeaaac0be37fdb61c1ab"}}, {"talos_counters": {}, "results": {"audio-dft": [156.0, 163.0, 152.0, 152.0, 154.0, 154.0, 156.0, 153.0, 161.0, 161.0], "ai-astar": [90.0, 97.0, 100.0, 92.0, 94.0, 94.0, 101.0, 101.0, 95.0, 96.0], "imaging-gaussian-blur": [116.0, 117.0, 124.0, 124.0, 123.0, 124.0, 123.0, 124.0, 123.0, 117.0], "audio-beat-detection": [135.0, 140.0, 144.0, 152.0, 128.0, 134.0, 140.0, 130.0, 150.0, 146.0], "json-stringify-tinderbox": [81.0, 82.0, 79.0, 83.0, 78.0, 78.0, 83.0, 78.0, 80.0, 82.0], "stanford-crypto-pbkdf2": [197.0, 206.0, 206.0, 204.0, 204.0, 198.0, 204.0, 196.0, 209.0, 208.0], "json-parse-financial": [79.0, 80.0, 79.0, 82.0, 84.0, 79.0, 81.0, 80.0, 80.0, 94.0], "stanford-crypto-sha256-iterative": [85.0, 85.0, 91.0, 85.0, 85.0, 86.0, 88.0, 86.0, 87.0, 85.0], "audio-fft": [107.0, 89.0, 77.0, 103.0, 80.0, 83.0, 90.0, 83.0, 103.0, 74.0], "imaging-darkroom": [149.0, 158.0, 148.0, 158.0, 157.0, 156.0, 159.0, 158.0, 158.0, 147.0], "stanford-crypto-ccm": [122.0, 119.0, 123.0, 124.0, 130.0, 122.0, 126.0, 124.0, 120.0, 124.0], "audio-oscillator": [97.0, 95.0, 111.0, 100.0, 102.0, 102.0, 123.0, 101.0, 101.0, 118.0], "stanford-crypto-aes": [96.0, 97.0, 97.0, 102.0, 102.0, 102.0, 103.0, 102.0, 99.0, 98.0], "imaging-desaturate": [90.0, 91.0, 90.0, 97.0, 97.0, 91.0, 97.0, 98.0, 97.0, 97.0]}, "summary": {"suite": 1631.7, "subtests": {"audio-fft": {"filtered": 88.9, "value": 88.9}, "stanford-crypto-pbkdf2": {"filtered": 203.2, "value": 203.2}, "audio-beat-detection": {"filtered": 139.9, "value": 139.9}, "stanford-crypto-ccm": {"filtered": 123.4, "value": 123.4}, "imaging-darkroom": {"filtered": 154.8, "value": 154.8}, "json-parse-financial": {"filtered": 81.8, "value": 81.8}, "audio-oscillator": {"filtered": 105.0, "value": 105.0}, "ai-astar": {"filtered": 96.0, "value": 96.0}, "audio-dft": {"filtered": 156.2, "value": 156.2}, "stanford-crypto-sha256-iterative": {"filtered": 86.3, "value": 86.3}, "json-stringify-tinderbox": {"filtered": 80.4, "value": 80.4}, "imaging-gaussian-blur": {"filtered": 121.5, "value": 121.5}, "stanford-crypto-aes": {"filtered": 99.8, "value": 99.8}, "imaging-desaturate": {"filtered": 94.5, "value": 94.5}}}, "test_machine": {"platform": "x86", "osversion": "6.1.7601", "os": "win", "name": "T-W732-IX-028"}, "testrun": {"date": 1445952111, "suite": "kraken", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "44.0a1", "id": "20151027013036", "branch": "Mozilla-Inbound", "revision": "f1966d9bbf475e1ed4a3aeaaac0be37fdb61c1ab"}}, {"talos_counters": {}, "results": {"DeltaBlue": [202.42914979757086], "Encrypt": [293.68575624082234], "NavierStokes": [5427.027027027027], "Decrypt": [5775.862068965517], "Splay": [580.046403712297], "Richards": [151.37753557372085], "RegExp": [30636.363636363636], "Earley": [675.6756756756756], "Boyer": [10416.666666666666], "RayTrace": [1412.4293785310736]}, "summary": {"suite": 20410.66551140022, "subtests": {"DeltaBlue": {"filtered": 326.62292, "value": 326.62292}, "Encrypt": {"filtered": 906.3463049999999, "value": 906.3463049999999}, "NavierStokes": {"filtered": 273.4462151394421, "value": 273.4462151394421}, "Decrypt": {"filtered": 46.08506865671644, "value": 46.08506865671644}, "Splay": {"filtered": 140.490484, "value": 140.490484}, "RegExp": {"filtered": 29.735415430267068, "value": 29.735415430267068}, "Richards": {"filtered": 233.2050120000001, "value": 233.2050120000001}, "Earley": {"filtered": 986.3652400000001, "value": 986.3652400000001}, "Boyer": {"filtered": 63.980448000000024, "value": 63.980448000000024}, "RayTrace": {"filtered": 523.912212, "value": 523.912212}}}, "test_machine": {"platform": "x86", "osversion": "6.1.7601", "os": "win", "name": "T-W732-IX-028"}, "testrun": {"date": 1445952111, "suite": "v8_7", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "44.0a1", "id": "20151027013036", "branch": "Mozilla-Inbound", "revision": "f1966d9bbf475e1ed4a3aeaaac0be37fdb61c1ab"}}]
06:42:11 INFO - RETURN: dromaeo_css: 6178.26
06:42:11 INFO - RETURN: kraken: 1631.70
06:42:11 INFO - RETURN: v8_7: 20410.67
06:42:11 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,25]]", "result": "20410.67"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,25]]", "result": "1631.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,25]]", "result": "6178.26"}}}
06:42:11 INFO - Return code: 0
06:42:11 INFO - # TBPL SUCCESS #
06:42:11 INFO - Running post-action listener: _resource_record_post_action
06:42:11 INFO - Running post-run listener: _resource_record_post_run
06:42:12 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 84369920; Write bytes: 436580352; Read time: 1004510; Write time: 4529250
06:42:12 INFO - install - Wall time: 2s; CPU: 9.0%; Read bytes: 16384; Write bytes: 23588352; Read time: 23360; Write time: 379800
06:42:12 INFO - run-tests - Wall time: 1222s; CPU: 13.0%; Read bytes: 84346368; Write bytes: 411869696; Read time: 980200; Write time: 4148610
06:42:12 INFO - Running post-run listener: _upload_blobber_files
06:42:12 INFO - Blob upload gear active.
06:42:12 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ...
06:42:12 INFO - Copying logs to upload dir...
06:42:12 INFO - mkdir: C:\slave\test-pgo\build\upload\logs
program finished with exit code 0
elapsedTime=1501.850000
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 31 secs) (at 2015-10-27 06:42:41.317006) =========
========= Started set props: build_url (results: 0, elapsed: 1 secs) (at 2015-10-27 06:42:41.317669) =========
'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-028
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-028
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-028
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/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.102000
build_url: 'https://queue.taskcluster.net/v1/task/Du_rld6bSpy-eFpAA-elIA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip'
========= Finished set props: build_url (results: 0, elapsed: 1 secs) (at 2015-10-27 06:42:42.871722) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-27 06:42:42.872066) =========
'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-028
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-028
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-028
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.101000
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-27 06:42:43.782303) =========
========= Started reboot slave lost (results: 0, elapsed: 10 secs) (at 2015-10-27 06:42:43.782625) =========
========= Finished reboot slave lost (results: 0, elapsed: 10 secs) (at 2015-10-27 06:42:54.351704) =========