builder: mozilla-inbound_win7-ix_test-dromaeojs-pgo
slave: t-w732-ix-101
starttime: 1445563146.92
results: success (0)
buildid: 20151022133016
builduid: 1e445a849c274c09bcd3bc5d26272f0d
revision: ce72dd15cd715f53603333dbb992cd01997dd916
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-22 18:19:06.921454) =========
master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-22 18:19:06.922059) =========
========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-22 18:19:06.922403) =========
'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-101
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-101
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-101
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.597000
basedir: 'C:\\slave\\test-pgo'
========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-22 18:19:07.825071) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2015-10-22 18:19:07.825459) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2015-10-22 18:19:09.137497) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-22 18:19:09.137982) =========
'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-101
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-101
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-101
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: 0 secs) (at 2015-10-22 18:19:09.914637) =========
========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-22 18:19:09.915237) =========
script_repo_url: https://hg.mozilla.org/build/mozharness
========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-22 18:19:09.915956) =========
========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2015-10-22 18:19:09.916581) =========
'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-101
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-101
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-101
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
--18:19:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 2.10 GB/s
18:19:11 (2.10 GB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=1.989000
========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2015-10-22 18:19:16.510682) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 16 secs) (at 2015-10-22 18:19:16.511072) =========
'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-101
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-101
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-101
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=12.404000
========= Finished 'rm -rf ...' (results: 0, elapsed: 16 secs) (at 2015-10-22 18:19:33.446717) =========
========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2015-10-22 18:19:33.447299) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev ce72dd15cd715f53603333dbb992cd01997dd916 --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 ce72dd15cd715f53603333dbb992cd01997dd916 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-101
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-101
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-101
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
2015-10-22 18:19:34,102 truncating revision to first 12 chars
2015-10-22 18:19:34,102 Setting DEBUG logging.
2015-10-22 18:19:34,102 attempt 1/10
2015-10-22 18:19:34,102 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/ce72dd15cd71?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2015-10-22 18:19:36,846 unpacking tar archive at: mozilla-inbound-ce72dd15cd71/testing/mozharness/
program finished with exit code 0
elapsedTime=3.575000
========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2015-10-22 18:19:37.489397) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2015-10-22 18:19:37.489858) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2015-10-22 18:19:38.500753) =========
========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-22 18:19:38.501311) =========
TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production
========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-22 18:19:38.501893) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 10 secs) (at 2015-10-22 18:19:38.502287) =========
'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-101
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-101
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-101
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
18:19:39 INFO - MultiFileLogger online at 20151022 18:19:39 in C:\slave\test-pgo
18:19:39 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
18:19:39 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json.
18:19:39 INFO - {'append_to_log': False,
18:19:39 INFO - 'base_work_dir': 'C:\\slave\\test-pgo',
18:19:39 INFO - 'blob_upload_branch': 'Mozilla-Inbound',
18:19:39 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt',
18:19:39 INFO - 'branch': 'Mozilla-Inbound',
18:19:39 INFO - 'buildbot_json_path': 'buildprops.json',
18:19:39 INFO - 'config_files': ('talos/windows_config.py',),
18:19:39 INFO - 'default_actions': ('clobber',
18:19:39 INFO - 'read-buildbot-config',
18:19:39 INFO - 'download-and-extract',
18:19:39 INFO - 'populate-webroot',
18:19:39 INFO - 'create-virtualenv',
18:19:39 INFO - 'install',
18:19:39 INFO - 'run-tests'),
18:19:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
18:19:39 INFO - 'download_minidump_stackwalk': True,
18:19:39 INFO - 'download_symbols': 'ondemand',
18:19:39 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python',
18:19:39 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'),
18:19:39 INFO - 'hg': 'c:/mozilla-build/hg/hg',
18:19:39 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python',
18:19:39 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'),
18:19:39 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
18:19:39 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
18:19:39 INFO - 'C:/mozilla-build/tooltool.py'),
18:19:39 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
18:19:39 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
18:19:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
18:19:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
18:19:39 INFO - 'installer_path': 'installer.exe',
18:19:39 INFO - 'log_level': 'info',
18:19:39 INFO - 'log_name': 'talos',
18:19:39 INFO - 'log_to_console': True,
18:19:39 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
18:19:39 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
18:19:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
18:19:39 INFO - 'opt_config_files': (),
18:19:39 INFO - 'pip_index': False,
18:19:39 INFO - 'sps_profile': False,
18:19:39 INFO - 'sps_profile_interval': 0,
18:19:39 INFO - 'suite': 'dromaeojs',
18:19:39 INFO - 'system_bits': '32',
18:19:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
18:19:39 INFO - 'title': 'T-W732-IX-101',
18:19:39 INFO - 'use_talos_json': True,
18:19:39 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
18:19:39 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv',
18:19:39 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
18:19:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
18:19:39 INFO - 'work_dir': 'build'}
18:19:39 INFO - #####
18:19:39 INFO - ##### Running clobber step.
18:19:39 INFO - #####
18:19:39 INFO - Running pre-action listener: _resource_record_pre_action
18:19:39 INFO - Running main action method: clobber
18:19:39 INFO - rmtree: C:\slave\test-pgo\build
18:19:39 INFO - Using _rmtree_windows ...
18:19:39 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1
18:21:16 INFO - Running post-action listener: _resource_record_post_action
18:21:16 INFO - #####
18:21:16 INFO - ##### Running read-buildbot-config step.
18:21:16 INFO - #####
18:21:16 INFO - Running pre-action listener: _resource_record_pre_action
18:21:16 INFO - Running main action method: read_buildbot_config
18:21:16 INFO - Using buildbot properties:
18:21:16 INFO - {
18:21:16 INFO - "properties": {
18:21:16 INFO - "buildnumber": 383,
18:21:16 INFO - "product": "firefox",
18:21:16 INFO - "script_repo_revision": "production",
18:21:16 INFO - "builddir": "mozilla-inbound_win7-ix_test-dromaeojs",
18:21:16 INFO - "repository": "",
18:21:16 INFO - "buildername": "Windows 7 32-bit mozilla-inbound pgo talos dromaeojs",
18:21:16 INFO - "buildid": "20151022133016",
18:21:16 INFO - "slavename": "t-w732-ix-101",
18:21:16 INFO - "pgo_build": "True",
18:21:16 INFO - "basedir": "C:\\slave\\test-pgo",
18:21:16 INFO - "project": "",
18:21:16 INFO - "platform": "win7-ix",
18:21:16 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/",
18:21:16 INFO - "slavebuilddir": "test",
18:21:16 INFO - "scheduler": "tests-mozilla-inbound-win32-pgo-talos",
18:21:16 INFO - "branch": "mozilla-inbound",
18:21:16 INFO - "repo_path": "integration/mozilla-inbound",
18:21:16 INFO - "stage_platform": "win32-pgo",
18:21:16 INFO - "builduid": "1e445a849c274c09bcd3bc5d26272f0d",
18:21:16 INFO - "revision": "ce72dd15cd715f53603333dbb992cd01997dd916"
18:21:16 INFO - },
18:21:16 INFO - "sourcestamp": {
18:21:16 INFO - "repository": "",
18:21:16 INFO - "hasPatch": false,
18:21:16 INFO - "project": "",
18:21:16 INFO - "branch": "mozilla-inbound-win32-pgo-talos",
18:21:16 INFO - "changes": [
18:21:16 INFO - {
18:21:16 INFO - "category": null,
18:21:16 INFO - "files": [
18:21:16 INFO - {
18:21:16 INFO - "url": null,
18:21:16 INFO - "name": "https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip"
18:21:16 INFO - }
18:21:16 INFO - ],
18:21:16 INFO - "repository": "",
18:21:16 INFO - "rev": "ce72dd15cd715f53603333dbb992cd01997dd916",
18:21:16 INFO - "who": "sendchange",
18:21:16 INFO - "when": 1445562551,
18:21:16 INFO - "number": 6562782,
18:21:16 INFO - "comments": "",
18:21:16 INFO - "project": "",
18:21:16 INFO - "at": "Thu 22 Oct 2015 18:09:11",
18:21:16 INFO - "branch": "mozilla-inbound-win32-pgo-talos",
18:21:16 INFO - "revlink": "",
18:21:16 INFO - "properties": [
18:21:16 INFO - [
18:21:16 INFO - "buildid",
18:21:16 INFO - "20151022133016",
18:21:16 INFO - "Change"
18:21:16 INFO - ],
18:21:16 INFO - [
18:21:16 INFO - "builduid",
18:21:16 INFO - "1e445a849c274c09bcd3bc5d26272f0d",
18:21:16 INFO - "Change"
18:21:16 INFO - ],
18:21:16 INFO - [
18:21:16 INFO - "pgo_build",
18:21:16 INFO - "True",
18:21:16 INFO - "Change"
18:21:16 INFO - ]
18:21:16 INFO - ],
18:21:16 INFO - "revision": "ce72dd15cd715f53603333dbb992cd01997dd916"
18:21:16 INFO - }
18:21:16 INFO - ],
18:21:16 INFO - "revision": "ce72dd15cd715f53603333dbb992cd01997dd916"
18:21:16 INFO - }
18:21:16 INFO - }
18:21:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip.
18:21:16 INFO - Running post-action listener: _resource_record_post_action
18:21:16 INFO - #####
18:21:16 INFO - ##### Running download-and-extract step.
18:21:16 INFO - #####
18:21:16 INFO - Running pre-action listener: _resource_record_pre_action
18:21:16 INFO - Running main action method: download_and_extract
18:21:16 INFO - mkdir: C:\slave\test-pgo\build\tests
18:21:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:21:16 INFO - https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net
18:21:16 INFO - trying https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/test_packages.json
18:21:16 INFO - Downloading https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json
18:21:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1
18:21:18 INFO - Downloaded 1221 bytes.
18:21:18 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json
18:21:18 INFO - Using the following test package requirements:
18:21:18 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'],
18:21:18 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
18:21:18 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'],
18:21:18 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
18:21:18 INFO - u'jsshell-win32.zip'],
18:21:18 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
18:21:18 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'],
18:21:18 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'],
18:21:18 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
18:21:18 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'],
18:21:18 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
18:21:18 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'],
18:21:18 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
18:21:18 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'],
18:21:18 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'],
18:21:18 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
18:21:18 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']}
18:21:18 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common
18:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:21:18 INFO - https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
18:21:18 INFO - trying https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip
18:21:18 INFO - Downloading https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/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
18:21:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/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
18:21:27 INFO - Downloaded 18006688 bytes.
18:21:27 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
18:21:27 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip
18:21:27 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760
18:21:59 INFO - Return code: 0
18:21:59 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip', u'firefox-44.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos
18:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:21:59 INFO - https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
18:21:59 INFO - trying https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip
18:21:59 INFO - Downloading https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/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
18:21:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/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
18:22:12 INFO - Downloaded 18006688 bytes.
18:22:12 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
18:22:12 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip
18:22:12 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760
18:22:35 INFO - Return code: 0
18:22:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:22:35 INFO - https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net
18:22:35 INFO - trying https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip
18:22:35 INFO - Downloading https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/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
18:22:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/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
18:22:43 INFO - Downloaded 11131307 bytes.
18:22:43 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
18:22:43 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip
18:22:43 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760
18:22:45 INFO - Return code: 0
18:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:22:45 INFO - https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net
18:22:45 INFO - trying https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
18:22:45 INFO - Downloading https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip
18:22:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/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
18:23:03 INFO - Downloaded 53235049 bytes.
18:23:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
18:23:03 INFO - mkdir: C:\slave\test-pgo\properties
18:23:03 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url
18:23:03 INFO - Writing to file C:\slave\test-pgo\properties\build_url
18:23:03 INFO - Contents:
18:23:03 INFO - build_url:https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
18:23:03 INFO - Running post-action listener: _resource_record_post_action
18:23:03 INFO - Running post-action listener: set_extra_try_arguments
18:23:03 INFO - #####
18:23:03 INFO - ##### Running populate-webroot step.
18:23:03 INFO - #####
18:23:03 INFO - Running pre-action listener: _resource_record_pre_action
18:23:03 INFO - Running main action method: populate_webroot
18:23:03 INFO - Guessing talos json url...
18:23:03 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')]}
18:23:03 INFO - https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net
18:23:03 INFO - trying https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.txt
18:23:03 INFO - Downloading https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt
18:23:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/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
18:23:04 INFO - Downloaded 111 bytes.
18:23:04 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt
18:23:04 INFO - Contents:
18:23:04 INFO - 20151022133016
18:23:04 INFO - https://hg.mozilla.org/integration/mozilla-inbound/rev/ce72dd15cd715f53603333dbb992cd01997dd916
18:23: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')]}
18:23:04 INFO - trying https://hg.mozilla.org/integration/mozilla-inbound/raw-file/ce72dd15cd/testing/talos/talos.json
18:23:04 INFO - Downloading https://hg.mozilla.org/integration/mozilla-inbound/raw-file/ce72dd15cd/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json
18:23:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/mozilla-inbound/raw-file/ce72dd15cd/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1
18:23:05 INFO - Downloaded 4264 bytes.
18:23:05 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
18:23:05 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
18:23:05 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs',
18:23:05 INFO - u'../fennec_ids.txt'],
18:23:05 INFO - u'tests': [u'tcheck2']},
18:23:05 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
18:23:05 INFO - u'--tppagecycles',
18:23:05 INFO - u'7'],
18:23:05 INFO - u'tests': [u'tsvgm']}},
18:23:05 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']},
18:23:05 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'],
18:23:05 INFO - u'tests': [u'tresize', u'tcanvasmark']},
18:23:05 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']},
18:23:05 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'],
18:23:05 INFO - u'tests': [u'dromaeo_css',
18:23:05 INFO - u'kraken',
18:23:05 INFO - u'v8_7']},
18:23:05 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:23:05 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
18:23:05 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:23:05 INFO - u'talos_options': [u'--e10s'],
18:23:05 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
18:23:05 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:23:05 INFO - u'tests': [u'damp', u'tps']},
18:23:05 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:23:05 INFO - u'talos_options': [u'--e10s'],
18:23:05 INFO - u'tests': [u'damp', u'tps']},
18:23:05 INFO - u'g3': {u'tests': [u'dromaeo_dom']},
18:23:05 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
18:23:05 INFO - u'other': {u'tests': [u'a11yr',
18:23:05 INFO - u'ts_paint',
18:23:05 INFO - u'tpaint',
18:23:05 INFO - u'sessionrestore',
18:23:05 INFO - u'sessionrestore_no_auto_restore']},
18:23:05 INFO - u'other-e10s': {u'talos_options': [u'--e10s'],
18:23:05 INFO - u'tests': [u'a11yr',
18:23:05 INFO - u'ts_paint',
18:23:05 INFO - u'tpaint',
18:23:05 INFO - u'sessionrestore',
18:23:05 INFO - u'sessionrestore_no_auto_restore']},
18:23:05 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'],
18:23:05 INFO - u'tests': [u'a11yr',
18:23:05 INFO - u'ts_paint',
18:23:05 INFO - u'tpaint',
18:23:05 INFO - u'sessionrestore',
18:23:05 INFO - u'sessionrestore_no_auto_restore']},
18:23:05 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'],
18:23:05 INFO - u'tests': [u'a11yr',
18:23:05 INFO - u'ts_paint',
18:23:05 INFO - u'tpaint',
18:23:05 INFO - u'sessionrestore',
18:23:05 INFO - u'sessionrestore_no_auto_restore']},
18:23:05 INFO - u'other_l64': {u'tests': [u'a11yr',
18:23:05 INFO - u'ts_paint',
18:23:05 INFO - u'tpaint',
18:23:05 INFO - u'sessionrestore',
18:23:05 INFO - u'sessionrestore_no_auto_restore']},
18:23:05 INFO - u'other_nol64': {u'tests': [u'a11yr',
18:23:05 INFO - u'ts_paint',
18:23:05 INFO - u'tpaint',
18:23:05 INFO - u'sessionrestore',
18:23:05 INFO - u'sessionrestore_no_auto_restore']},
18:23:05 INFO - u'svgr': {u'tests': [u'tsvgx',
18:23:05 INFO - u'tsvgr_opacity',
18:23:05 INFO - u'tart',
18:23:05 INFO - u'tscrollx',
18:23:05 INFO - u'cart']},
18:23:05 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'],
18:23:05 INFO - u'tests': [u'tsvgx',
18:23:05 INFO - u'tsvgr_opacity',
18:23:05 INFO - u'tart',
18:23:05 INFO - u'tscrollx',
18:23:05 INFO - u'cart']},
18:23:05 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:23:05 INFO - u'tests': [u'tp5o']},
18:23:05 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:23:05 INFO - u'talos_options': [u'--e10s'],
18:23:05 INFO - u'tests': [u'tp5o']},
18:23:05 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:23:05 INFO - u'talos_options': [u'--xperf_path',
18:23:05 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
18:23:05 INFO - u'tests': [u'tp5n']},
18:23:05 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:23:05 INFO - u'talos_options': [u'--e10s',
18:23:05 INFO - u'--xperf_path',
18:23:05 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"',
18:23:05 INFO - u'C:/slave/talos-data/talos/xperf.config'],
18:23:05 INFO - u'tests': [u'tp5n']}},
18:23:05 INFO - u'talos.zip': {u'path': u'',
18:23:05 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
18:23:05 INFO - Running post-action listener: _resource_record_post_action
18:23:05 INFO - #####
18:23:05 INFO - ##### Running create-virtualenv step.
18:23:05 INFO - #####
18:23:05 INFO - Running pre-action listener: _resource_record_pre_action
18:23:05 INFO - Running main action method: create_virtualenv
18:23:05 INFO - Creating virtualenv C:\slave\test-pgo\build/venv
18:23:05 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts
18:23:05 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll
18:23: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'!
18:23: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
18:23: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
18:23:07 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe
18:23:11 INFO - Installing distribute......................................................................................................................................................................................done.
18:23:13 INFO - Return code: 0
18:23:13 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv
18:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:23:13 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01958B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01918EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DD660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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
18:23:13 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test-pgo\build
18:23:13 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
18:23:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
18:23:13 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
18:23:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
18:23:13 INFO - 'COMPUTERNAME': 'T-W732-IX-101',
18:23:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
18:23:13 INFO - 'CYGWINBASE': 'C:\\cygwin',
18:23:13 INFO - 'DCLOCATION': 'SCL3',
18:23:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:23:13 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:23:13 INFO - 'HOMEDRIVE': 'C:',
18:23:13 INFO - 'HOMEPATH': '\\Users\\cltbld',
18:23:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:23:13 INFO - 'KTS_VERSION': '1.19c',
18:23:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
18:23:13 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101',
18:23:13 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:23:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:23:13 INFO - 'MOZ_NO_REMOTE': '1',
18:23:13 INFO - 'NO_EM_RESTART': '1',
18:23:13 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:23:13 INFO - 'OS': 'Windows_NT',
18:23:13 INFO - 'OURDRIVE': 'C:',
18:23:13 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
18:23:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
18:23:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:23:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
18:23:13 INFO - 'PROCESSOR_LEVEL': '6',
18:23:13 INFO - 'PROCESSOR_REVISION': '1e05',
18:23:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
18:23:13 INFO - 'PROGRAMFILES': 'C:\\Program Files',
18:23:13 INFO - 'PROMPT': '$P$G',
18:23:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
18:23:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
18:23:13 INFO - 'PUBLIC': 'C:\\Users\\Public',
18:23:13 INFO - 'PWD': 'C:\\slave\\test-pgo',
18:23:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:23:13 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:23:13 INFO - 'SYSTEMDRIVE': 'C:',
18:23:13 INFO - 'SYSTEMROOT': 'C:\\windows',
18:23:13 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
18:23:13 INFO - 'TEST1': 'testie',
18:23:13 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
18:23:13 INFO - 'USERDOMAIN': 'T-W732-IX-101',
18:23:13 INFO - 'USERNAME': 'cltbld',
18:23:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
18:23:13 INFO - 'WINDIR': 'C:\\windows',
18:23:13 INFO - 'WINDOWS_TRACING_FLAGS': '3',
18:23:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
18:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:23:16 INFO - Ignoring indexes: http://pypi.python.org/simple/
18:23:16 INFO - Downloading/unpacking pip>=1.5
18:23:16 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache
18:23:20 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
18:23:20 INFO - Running setup.py egg_info for package pip
18:23:20 INFO - warning: no files found matching 'pip\cacert.pem'
18:23:20 INFO - warning: no files found matching '*.html' under directory 'docs'
18:23:20 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
18:23:20 INFO - no previously-included directories found matching 'docs\_build\_sources'
18:23:20 INFO - Installing collected packages: pip
18:23:20 INFO - Found existing installation: pip 0.8.2
18:23:20 INFO - Uninstalling pip:
18:23:20 INFO - Successfully uninstalled pip
18:23:20 INFO - Running setup.py install for pip
18:23:20 INFO - warning: no files found matching 'pip\cacert.pem'
18:23:20 INFO - warning: no files found matching '*.html' under directory 'docs'
18:23:20 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
18:23:20 INFO - no previously-included directories found matching 'docs\_build\_sources'
18:23:20 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:20 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:20 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:20 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:20 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:20 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:20 INFO - Successfully installed pip
18:23:20 INFO - Cleaning up...
18:23:20 INFO - Return code: 0
18:23:20 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv
18:23: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')]}
18:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:23: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')]}
18:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:23: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', '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 0x01958B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01918EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DD660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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
18:23: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', 'psutil>=0.7.1'] in C:\slave\test-pgo\build
18:23: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 psutil>=0.7.1
18:23:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
18:23:20 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
18:23:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
18:23:20 INFO - 'COMPUTERNAME': 'T-W732-IX-101',
18:23:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
18:23:20 INFO - 'CYGWINBASE': 'C:\\cygwin',
18:23:20 INFO - 'DCLOCATION': 'SCL3',
18:23:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:23:20 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:23:20 INFO - 'HOMEDRIVE': 'C:',
18:23:20 INFO - 'HOMEPATH': '\\Users\\cltbld',
18:23:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:23:20 INFO - 'KTS_VERSION': '1.19c',
18:23:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
18:23:20 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101',
18:23:20 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:23:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:23:20 INFO - 'MOZ_NO_REMOTE': '1',
18:23:20 INFO - 'NO_EM_RESTART': '1',
18:23:20 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:23:20 INFO - 'OS': 'Windows_NT',
18:23:20 INFO - 'OURDRIVE': 'C:',
18:23: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;',
18:23:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
18:23:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:23:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
18:23:20 INFO - 'PROCESSOR_LEVEL': '6',
18:23:20 INFO - 'PROCESSOR_REVISION': '1e05',
18:23:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
18:23:20 INFO - 'PROGRAMFILES': 'C:\\Program Files',
18:23:20 INFO - 'PROMPT': '$P$G',
18:23:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
18:23:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
18:23:20 INFO - 'PUBLIC': 'C:\\Users\\Public',
18:23:20 INFO - 'PWD': 'C:\\slave\\test-pgo',
18:23:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:23:20 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:23:20 INFO - 'SYSTEMDRIVE': 'C:',
18:23:20 INFO - 'SYSTEMROOT': 'C:\\windows',
18:23:20 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
18:23:20 INFO - 'TEST1': 'testie',
18:23:20 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
18:23:20 INFO - 'USERDOMAIN': 'T-W732-IX-101',
18:23:20 INFO - 'USERNAME': 'cltbld',
18:23:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
18:23:20 INFO - 'WINDIR': 'C:\\windows',
18:23:20 INFO - 'WINDOWS_TRACING_FLAGS': '3',
18:23:20 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
18:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:23:22 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:23:22 INFO - Downloading/unpacking psutil>=0.7.1
18:23: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
18:23: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
18:23:28 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
18:23:28 INFO - Installing collected packages: psutil
18:23:28 INFO - Successfully installed psutil
18:23:28 INFO - Cleaning up...
18:23:28 INFO - Return code: 0
18:23:28 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv
18:23:28 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')]}
18:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:23:28 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')]}
18:23:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:23:28 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 0x01958B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01918EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DD660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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
18:23:28 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
18:23:28 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
18:23:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
18:23:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
18:23:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
18:23:28 INFO - 'COMPUTERNAME': 'T-W732-IX-101',
18:23:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
18:23:28 INFO - 'CYGWINBASE': 'C:\\cygwin',
18:23:28 INFO - 'DCLOCATION': 'SCL3',
18:23:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:23:28 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:23:28 INFO - 'HOMEDRIVE': 'C:',
18:23:28 INFO - 'HOMEPATH': '\\Users\\cltbld',
18:23:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:23:28 INFO - 'KTS_VERSION': '1.19c',
18:23:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
18:23:28 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101',
18:23:28 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:23:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:23:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:23:28 INFO - 'MOZ_NO_REMOTE': '1',
18:23:28 INFO - 'NO_EM_RESTART': '1',
18:23:28 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:23:28 INFO - 'OS': 'Windows_NT',
18:23:28 INFO - 'OURDRIVE': 'C:',
18:23:28 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;',
18:23:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
18:23:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:23:28 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
18:23:28 INFO - 'PROCESSOR_LEVEL': '6',
18:23:28 INFO - 'PROCESSOR_REVISION': '1e05',
18:23:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
18:23:28 INFO - 'PROGRAMFILES': 'C:\\Program Files',
18:23:28 INFO - 'PROMPT': '$P$G',
18:23:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
18:23:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
18:23:28 INFO - 'PUBLIC': 'C:\\Users\\Public',
18:23:28 INFO - 'PWD': 'C:\\slave\\test-pgo',
18:23:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:23:28 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:23:28 INFO - 'SYSTEMDRIVE': 'C:',
18:23:28 INFO - 'SYSTEMROOT': 'C:\\windows',
18:23:28 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
18:23:28 INFO - 'TEST1': 'testie',
18:23:28 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
18:23:28 INFO - 'USERDOMAIN': 'T-W732-IX-101',
18:23:28 INFO - 'USERNAME': 'cltbld',
18:23:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
18:23:28 INFO - 'WINDIR': 'C:\\windows',
18:23:28 INFO - 'WINDOWS_TRACING_FLAGS': '3',
18:23:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
18:23:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:23:28 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:23:28 INFO - Downloading/unpacking mozsystemmonitor==0.0.0
18:23:28 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
18:23:28 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
18:23:29 INFO - Downloading mozsystemmonitor-0.0.tar.gz
18:23:29 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
18:23:29 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
18:23:29 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)
18:23:29 INFO - Installing collected packages: mozsystemmonitor
18:23:29 INFO - Running setup.py install for mozsystemmonitor
18:23:29 INFO - Successfully installed mozsystemmonitor
18:23:29 INFO - Cleaning up...
18:23:30 INFO - Return code: 0
18:23:30 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv
18:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:23:30 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01958B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01918EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DD660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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
18:23:30 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test-pgo\build
18:23:30 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
18:23:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
18:23:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
18:23:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
18:23:30 INFO - 'COMPUTERNAME': 'T-W732-IX-101',
18:23:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
18:23:30 INFO - 'CYGWINBASE': 'C:\\cygwin',
18:23:30 INFO - 'DCLOCATION': 'SCL3',
18:23:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:23:30 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:23:30 INFO - 'HOMEDRIVE': 'C:',
18:23:30 INFO - 'HOMEPATH': '\\Users\\cltbld',
18:23:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:23:30 INFO - 'KTS_VERSION': '1.19c',
18:23:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
18:23:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101',
18:23:30 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:23:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:23:30 INFO - 'MOZ_NO_REMOTE': '1',
18:23:30 INFO - 'NO_EM_RESTART': '1',
18:23:30 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:23:30 INFO - 'OS': 'Windows_NT',
18:23:30 INFO - 'OURDRIVE': 'C:',
18:23:30 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
18:23:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
18:23:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:23:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
18:23:30 INFO - 'PROCESSOR_LEVEL': '6',
18:23:30 INFO - 'PROCESSOR_REVISION': '1e05',
18:23:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
18:23:30 INFO - 'PROGRAMFILES': 'C:\\Program Files',
18:23:30 INFO - 'PROMPT': '$P$G',
18:23:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
18:23:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
18:23:30 INFO - 'PUBLIC': 'C:\\Users\\Public',
18:23:30 INFO - 'PWD': 'C:\\slave\\test-pgo',
18:23:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:23:30 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:23:30 INFO - 'SYSTEMDRIVE': 'C:',
18:23:30 INFO - 'SYSTEMROOT': 'C:\\windows',
18:23:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
18:23:30 INFO - 'TEST1': 'testie',
18:23:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
18:23:30 INFO - 'USERDOMAIN': 'T-W732-IX-101',
18:23:30 INFO - 'USERNAME': 'cltbld',
18:23:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
18:23:30 INFO - 'WINDIR': 'C:\\windows',
18:23:30 INFO - 'WINDOWS_TRACING_FLAGS': '3',
18:23:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
18:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:23:31 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:23:31 INFO - Downloading/unpacking blobuploader==1.2.4
18:23:31 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
18:23:31 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
18:23:31 INFO - Downloading blobuploader-1.2.4.tar.gz
18:23:32 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
18:23:32 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
18:23:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
18:23:32 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
18:23:32 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
18:23:32 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
18:23:32 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests
18:23:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
18:23:32 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
18:23:32 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
18:23:33 INFO - Downloading docopt-0.6.1.tar.gz
18:23:33 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
18:23:33 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt
18:23:33 INFO - Installing collected packages: blobuploader, requests, docopt
18:23:33 INFO - Running setup.py install for blobuploader
18:23:33 INFO - Running setup.py install for requests
18:23:34 INFO - Running setup.py install for docopt
18:23:34 INFO - Successfully installed blobuploader requests docopt
18:23:34 INFO - Cleaning up...
18:23:35 INFO - Return code: 0
18:23:35 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv
18:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:23:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:23:35 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 0x01958B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01918EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DD660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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
18:23:35 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
18:23:35 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
18:23:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
18:23:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
18:23:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
18:23:35 INFO - 'COMPUTERNAME': 'T-W732-IX-101',
18:23:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
18:23:35 INFO - 'CYGWINBASE': 'C:\\cygwin',
18:23:35 INFO - 'DCLOCATION': 'SCL3',
18:23:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:23:35 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:23:35 INFO - 'HOMEDRIVE': 'C:',
18:23:35 INFO - 'HOMEPATH': '\\Users\\cltbld',
18:23:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:23:35 INFO - 'KTS_VERSION': '1.19c',
18:23:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
18:23:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101',
18:23:35 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:23:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:23:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:23:35 INFO - 'MOZ_NO_REMOTE': '1',
18:23:35 INFO - 'NO_EM_RESTART': '1',
18:23:35 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:23:35 INFO - 'OS': 'Windows_NT',
18:23:35 INFO - 'OURDRIVE': 'C:',
18:23:35 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
18:23:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
18:23:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:23:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
18:23:35 INFO - 'PROCESSOR_LEVEL': '6',
18:23:35 INFO - 'PROCESSOR_REVISION': '1e05',
18:23:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
18:23:35 INFO - 'PROGRAMFILES': 'C:\\Program Files',
18:23:35 INFO - 'PROMPT': '$P$G',
18:23:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
18:23:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
18:23:35 INFO - 'PUBLIC': 'C:\\Users\\Public',
18:23:35 INFO - 'PWD': 'C:\\slave\\test-pgo',
18:23:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:23:35 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:23:35 INFO - 'SYSTEMDRIVE': 'C:',
18:23:35 INFO - 'SYSTEMROOT': 'C:\\windows',
18:23:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
18:23:35 INFO - 'TEST1': 'testie',
18:23:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
18:23:35 INFO - 'USERDOMAIN': 'T-W732-IX-101',
18:23:35 INFO - 'USERNAME': 'cltbld',
18:23:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
18:23:35 INFO - 'WINDIR': 'C:\\windows',
18:23:35 INFO - 'WINDOWS_TRACING_FLAGS': '3',
18:23:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
18:23:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:23:35 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:23:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser
18:23:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zhpkyj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser
18:23:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash
18:23:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kv68d8-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash
18:23:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug
18:23:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f7qqwf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug
18:23:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice
18:23:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f5xkkx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice
18:23:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile
18:23:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e01yhh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile
18:23:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd
18:23:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9lxswb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd
18:23:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo
18:23:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8albd2-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo
18:23:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall
18:23:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-inq2yb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall
18:23:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak
18:23:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o0hrnb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak
18:23:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog
18:23:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zulumw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog
18:23:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork
18:23:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d4t9f0-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork
18:23:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess
18:23:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4gy1yn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess
18:23:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile
18:23:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-z2yiyx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile
18:23:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner
18:23:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aiexu5-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner
18:23:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot
18:23:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-perew1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot
18:23:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest
18:23:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-idyzfx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest
18:23:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion
18:23:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aassb1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion
18:23:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
18:23:37 INFO - Running setup.py install for manifestparser
18:23:38 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:38 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:38 INFO - Running setup.py install for mozcrash
18:23:38 INFO - Running setup.py install for mozdebug
18:23:39 INFO - Running setup.py install for mozdevice
18:23:39 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:39 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:39 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:39 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:39 INFO - Running setup.py install for mozfile
18:23:40 INFO - Running setup.py install for mozhttpd
18:23:40 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:40 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:40 INFO - Running setup.py install for mozinfo
18:23:40 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:40 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:41 INFO - Running setup.py install for mozInstall
18:23:41 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:41 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:41 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:41 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:41 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:41 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:41 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:41 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:41 INFO - Running setup.py install for mozleak
18:23:41 INFO - Running setup.py install for mozlog
18:23:42 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:42 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:42 INFO - Running setup.py install for moznetwork
18:23:42 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:42 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:43 INFO - Running setup.py install for mozprocess
18:23:43 INFO - Running setup.py install for mozprofile
18:23:43 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:43 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:43 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:43 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:43 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:43 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:44 INFO - Running setup.py install for mozrunner
18:23:44 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:44 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:45 INFO - Running setup.py install for mozscreenshot
18:23:45 INFO - Running setup.py install for moztest
18:23:45 INFO - Running setup.py install for mozversion
18:23:46 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts
18:23:46 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts
18:23:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
18:23:46 INFO - Cleaning up...
18:23:46 INFO - Return code: 0
18:23:46 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv
18:23: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')]}
18:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:23: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')]}
18:23:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:23: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\\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 0x01958B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01918EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DD660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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
18:23: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\\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
18:23: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\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
18:23:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
18:23:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
18:23:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
18:23:46 INFO - 'COMPUTERNAME': 'T-W732-IX-101',
18:23:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
18:23:46 INFO - 'CYGWINBASE': 'C:\\cygwin',
18:23:46 INFO - 'DCLOCATION': 'SCL3',
18:23:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:23:46 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:23:46 INFO - 'HOMEDRIVE': 'C:',
18:23:46 INFO - 'HOMEPATH': '\\Users\\cltbld',
18:23:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:23:46 INFO - 'KTS_VERSION': '1.19c',
18:23:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
18:23:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101',
18:23:46 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:23:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:23:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:23:46 INFO - 'MOZ_NO_REMOTE': '1',
18:23:46 INFO - 'NO_EM_RESTART': '1',
18:23:46 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:23:46 INFO - 'OS': 'Windows_NT',
18:23:46 INFO - 'OURDRIVE': 'C:',
18:23: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;',
18:23:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
18:23:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:23:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
18:23:46 INFO - 'PROCESSOR_LEVEL': '6',
18:23:46 INFO - 'PROCESSOR_REVISION': '1e05',
18:23:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
18:23:46 INFO - 'PROGRAMFILES': 'C:\\Program Files',
18:23:46 INFO - 'PROMPT': '$P$G',
18:23:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
18:23:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
18:23:46 INFO - 'PUBLIC': 'C:\\Users\\Public',
18:23:46 INFO - 'PWD': 'C:\\slave\\test-pgo',
18:23:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:23:46 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:23:46 INFO - 'SYSTEMDRIVE': 'C:',
18:23:46 INFO - 'SYSTEMROOT': 'C:\\windows',
18:23:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
18:23:46 INFO - 'TEST1': 'testie',
18:23:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
18:23:46 INFO - 'USERDOMAIN': 'T-W732-IX-101',
18:23:46 INFO - 'USERNAME': 'cltbld',
18:23:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
18:23:46 INFO - 'WINDIR': 'C:\\windows',
18:23:46 INFO - 'WINDOWS_TRACING_FLAGS': '3',
18:23:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
18:23:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:23:46 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:23:46 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser
18:23:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cc1fj_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser
18:23:46 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))
18:23:46 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash
18:23:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bhtfvr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash
18:23:46 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))
18:23:46 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug
18:23:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jzy_f_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug
18:23:47 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))
18:23:47 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice
18:23:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gcibjx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice
18:23:47 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))
18:23:47 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile
18:23:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-shefx1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile
18:23:47 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))
18:23:47 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd
18:23:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3dv0dy-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd
18:23:47 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))
18:23:47 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo
18:23:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-om9ydm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo
18:23:47 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))
18:23:47 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall
18:23:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9ynj6u-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall
18:23:47 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))
18:23:47 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak
18:23:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x41iap-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak
18:23:48 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))
18:23:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog
18:23:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0gqit4-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog
18:23:48 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))
18:23:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork
18:23:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2q3zpx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork
18:23:48 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))
18:23:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess
18:23:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f6b9qh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess
18:23:48 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))
18:23:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile
18:23:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-17bd3c-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile
18:23:48 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))
18:23:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner
18:23:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ckvgpx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner
18:23:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14))
18:23:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot
18:23:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-85ul_s-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot
18:23:48 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))
18:23:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest
18:23:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-earuih-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest
18:23:49 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))
18:23:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion
18:23:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qpacgv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion
18:23:49 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))
18:23:49 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))
18:23:49 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))
18:23:49 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))
18:23:49 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))
18:23:49 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))
18:23:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10))
18:23:49 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
18:23:49 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
18:23:53 INFO - Downloading blessings-1.5.1.tar.gz
18:23:53 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
18:23:53 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings
18:23:53 INFO - Installing collected packages: blessings
18:23:53 INFO - Running setup.py install for blessings
18:23:54 INFO - Successfully installed blessings
18:23:54 INFO - Cleaning up...
18:23:56 INFO - Return code: 0
18:23:56 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv.
18:23:56 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze']
18:23:56 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze
18:23:57 INFO - Reading from file tmpfile_stdout
18:23:57 INFO - Using _rmtree_windows ...
18:23:57 INFO - Using _rmtree_windows ...
18:23:57 INFO - Current package versions:
18:23:57 INFO - blessings == 1.5.1
18:23:57 INFO - blobuploader == 1.2.4
18:23:57 INFO - docopt == 0.6.1
18:23:57 INFO - manifestparser == 1.1
18:23:57 INFO - mozInstall == 1.12
18:23:57 INFO - mozcrash == 0.16
18:23:57 INFO - mozdebug == 0.1
18:23:57 INFO - mozdevice == 0.46
18:23:57 INFO - mozfile == 1.2
18:23:57 INFO - mozhttpd == 0.7
18:23:57 INFO - mozinfo == 0.8
18:23:57 INFO - mozleak == 0.1
18:23:57 INFO - mozlog == 3.0
18:23:57 INFO - moznetwork == 0.27
18:23:57 INFO - mozprocess == 0.22
18:23:57 INFO - mozprofile == 0.27
18:23:57 INFO - mozrunner == 6.10
18:23:57 INFO - mozscreenshot == 0.1
18:23:57 INFO - mozsystemmonitor == 0.0
18:23:57 INFO - moztest == 0.7
18:23:57 INFO - mozversion == 1.4
18:23:57 INFO - psutil == 3.1.1
18:23:57 INFO - requests == 1.2.3
18:23:57 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv
18:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:23:57 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 0x01958B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01918EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0137E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DD660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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
18:23:57 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
18:23:57 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
18:23:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
18:23:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
18:23:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
18:23:57 INFO - 'COMPUTERNAME': 'T-W732-IX-101',
18:23:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
18:23:57 INFO - 'CYGWINBASE': 'C:\\cygwin',
18:23:57 INFO - 'DCLOCATION': 'SCL3',
18:23:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:23:57 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:23:57 INFO - 'HOMEDRIVE': 'C:',
18:23:57 INFO - 'HOMEPATH': '\\Users\\cltbld',
18:23:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:23:57 INFO - 'KTS_VERSION': '1.19c',
18:23:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
18:23:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101',
18:23:57 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:23:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:23:57 INFO - 'MOZ_NO_REMOTE': '1',
18:23:57 INFO - 'NO_EM_RESTART': '1',
18:23:57 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:23:57 INFO - 'OS': 'Windows_NT',
18:23:57 INFO - 'OURDRIVE': 'C:',
18:23:57 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;',
18:23:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
18:23:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:23:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
18:23:57 INFO - 'PROCESSOR_LEVEL': '6',
18:23:57 INFO - 'PROCESSOR_REVISION': '1e05',
18:23:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
18:23:57 INFO - 'PROGRAMFILES': 'C:\\Program Files',
18:23:57 INFO - 'PROMPT': '$P$G',
18:23:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
18:23:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
18:23:57 INFO - 'PUBLIC': 'C:\\Users\\Public',
18:23:57 INFO - 'PWD': 'C:\\slave\\test-pgo',
18:23:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:23:57 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:23:57 INFO - 'SYSTEMDRIVE': 'C:',
18:23:57 INFO - 'SYSTEMROOT': 'C:\\windows',
18:23:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
18:23:57 INFO - 'TEST1': 'testie',
18:23:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
18:23:57 INFO - 'USERDOMAIN': 'T-W732-IX-101',
18:23:57 INFO - 'USERNAME': 'cltbld',
18:23:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
18:23:57 INFO - 'WINDIR': 'C:\\windows',
18:23:57 INFO - 'WINDOWS_TRACING_FLAGS': '3',
18:23:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
18:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:23:57 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:23:57 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))
18:23:57 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))
18:23:57 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))
18:23:57 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))
18:23:57 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))
18:23:57 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))
18:23:57 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))
18:23:57 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))
18:23:57 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))
18:23:57 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))
18:23:57 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))
18:23:57 INFO - Cleaning up...
18:23:57 INFO - Return code: 0
18:23:57 INFO - Running post-action listener: _resource_record_post_action
18:23:57 INFO - Running post-action listener: _start_resource_monitoring
18:23:57 INFO - Starting resource monitoring.
18:23:57 INFO - #####
18:23:57 INFO - ##### Running install step.
18:23:57 INFO - #####
18:23:57 INFO - Running pre-action listener: _resource_record_pre_action
18:23:57 INFO - Running main action method: install
18:23:57 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze']
18:23:57 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze
18:23:58 INFO - Reading from file tmpfile_stdout
18:23:58 INFO - Using _rmtree_windows ...
18:23:58 INFO - Using _rmtree_windows ...
18:23:58 INFO - Detecting whether we're running mozinstall >=1.0...
18:23:58 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h']
18:23:58 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h
18:23:58 INFO - Reading from file tmpfile_stdout
18:23:58 INFO - Output received:
18:23:58 INFO - Usage: mozinstall-script.py [options] installer
18:23:58 INFO - Options:
18:23:58 INFO - -h, --help show this help message and exit
18:23:58 INFO - -d DEST, --destination=DEST
18:23:58 INFO - Directory to install application into. [default:
18:23:58 INFO - "C:\slave\test-pgo"]
18:23:58 INFO - --app=APP Application being installed. [default: firefox]
18:23:58 INFO - Using _rmtree_windows ...
18:23:58 INFO - Using _rmtree_windows ...
18:23:58 INFO - mkdir: C:\slave\test-pgo\build\application
18:23:58 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']
18:23:58 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
18:23:59 INFO - Reading from file tmpfile_stdout
18:23:59 INFO - Output received:
18:23:59 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe
18:23:59 INFO - Using _rmtree_windows ...
18:23:59 INFO - Using _rmtree_windows ...
18:23:59 INFO - Running post-action listener: _resource_record_post_action
18:23:59 INFO - #####
18:23:59 INFO - ##### Running run-tests step.
18:23:59 INFO - #####
18:23:59 INFO - Running pre-action listener: _resource_record_pre_action
18:23:59 INFO - Running main action method: run_tests
18:23:59 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version']
18:23:59 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version
18:23:59 INFO - Python 2.7.3
18:23:59 INFO - Return code: 0
18:23:59 INFO - grabbing minidump binary from tooltool
18:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:23:59 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01298B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DD660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1
18:23:59 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
18:23:59 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
18:23:59 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
18:24:02 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpis6vis
18:24:02 INFO - INFO - File integrity verified, renaming tmpis6vis to win32-minidump_stackwalk.exe
18:24:02 INFO - Return code: 0
18:24:02 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755
18:24:02 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir
18:24:02 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir
18:24:02 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe
18:24:02 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir
18:24:02 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/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-101', '--webServer', 'localhost'] in C:\slave\test-pgo\build
18:24:02 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/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-101 --webServer localhost
18:24:02 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
18:24:02 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
18:24:02 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
18:24:02 INFO - 'COMPUTERNAME': 'T-W732-IX-101',
18:24:02 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
18:24:02 INFO - 'CYGWINBASE': 'C:\\cygwin',
18:24:02 INFO - 'DCLOCATION': 'SCL3',
18:24:02 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:24:02 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:24:02 INFO - 'HOMEDRIVE': 'C:',
18:24:02 INFO - 'HOMEPATH': '\\Users\\cltbld',
18:24:02 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:24:02 INFO - 'KTS_VERSION': '1.19c',
18:24:02 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
18:24:02 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101',
18:24:02 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir',
18:24:02 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe',
18:24:02 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:24:02 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:24:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:24:02 INFO - 'MOZ_NO_REMOTE': '1',
18:24:02 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir',
18:24:02 INFO - 'NO_EM_RESTART': '1',
18:24:02 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:24:02 INFO - 'OS': 'Windows_NT',
18:24:02 INFO - 'OURDRIVE': 'C:',
18:24:02 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;',
18:24:02 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
18:24:02 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:24:02 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
18:24:02 INFO - 'PROCESSOR_LEVEL': '6',
18:24:02 INFO - 'PROCESSOR_REVISION': '1e05',
18:24:02 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
18:24:02 INFO - 'PROGRAMFILES': 'C:\\Program Files',
18:24:02 INFO - 'PROMPT': '$P$G',
18:24:02 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
18:24:02 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
18:24:02 INFO - 'PUBLIC': 'C:\\Users\\Public',
18:24:02 INFO - 'PWD': 'C:\\slave\\test-pgo',
18:24:02 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos',
18:24:02 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:24:02 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:24:02 INFO - 'SYSTEMDRIVE': 'C:',
18:24:02 INFO - 'SYSTEMROOT': 'C:\\windows',
18:24:02 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
18:24:02 INFO - 'TEST1': 'testie',
18:24:02 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
18:24:02 INFO - 'USERDOMAIN': 'T-W732-IX-101',
18:24:02 INFO - 'USERNAME': 'cltbld',
18:24:02 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
18:24:02 INFO - 'WINDIR': 'C:\\windows',
18:24:02 INFO - 'WINDOWS_TRACING_FLAGS': '3',
18:24:02 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
18:24:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:24:02 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/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-101', '--webServer', 'localhost'] with output_timeout 3600
18:24:03 INFO - mozversion INFO | application_buildid: 20151022133016
18:24:03 INFO - mozversion INFO | application_changeset: ce72dd15cd715f53603333dbb992cd01997dd916
18:24:03 INFO - mozversion INFO | application_display_name: Nightly
18:24:03 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
18:24:03 INFO - mozversion INFO | application_name: Firefox
18:24:03 INFO - mozversion INFO | application_remotingname: firefox
18:24:03 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound
18:24:03 INFO - mozversion INFO | application_vendor: Mozilla
18:24:03 INFO - mozversion INFO | application_version: 44.0a1
18:24:03 INFO - mozversion INFO | platform_buildid: 20151022133016
18:24:03 INFO - mozversion INFO | platform_changeset: ce72dd15cd715f53603333dbb992cd01997dd916
18:24:03 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound
18:24:03 INFO - mozversion INFO | platform_version: 44.0a1
18:24:03 INFO - 2015-10-22 18:24:03,342 DEBUG : using testdate: 1445563443
18:24:03 INFO - 2015-10-22 18:24:03,342 DEBUG : actual date: 1445563443
18:24:03 INFO - 2015-10-22 18:24:03,391 INFO : starting webserver on 'localhost:49747'
18:24:03 INFO - 2015-10-22 18:24:03,391 INFO : Starting test suite T-W732-IX-101
18:24:03 INFO - 2015-10-22 18:24:03,391 INFO : Starting test dromaeo_css
18:24:03 INFO - 2015-10-22 18:24:03,392 DEBUG : operating with platform_type : w7_
18:24:03 INFO - 2015-10-22 18:24:03,394 INFO : Initialising browser for dromaeo_css test...
18:24:03 INFO - 2015-10-22 18:24:03,490 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp2hp3df\profile http://localhost:49747/getInfo.html
18:24:12 INFO - 2015-10-22 18:24:12,667 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
18:24:12 INFO - 2015-10-22 18:24:12,667 DEBUG : BROWSER_OUTPUT: colorDepth:24
18:24:12 INFO - 2015-10-22 18:24:12,667 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
18:24:12 INFO - 2015-10-22 18:24:12,667 DEBUG : BROWSER_OUTPUT: __metrics
18:24:12 INFO - 2015-10-22 18:24:12,730 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
18:24:14 INFO - 2015-10-22 18:24:14,128 DEBUG : BROWSER_OUTPUT: console.error:
18:24:14 INFO - 2015-10-22 18:24:14,128 DEBUG : BROWSER_OUTPUT: Message: Error: Transaction canceled due to a closed connection.
18:24:14 INFO - 2015-10-22 18:24:14,128 DEBUG : BROWSER_OUTPUT: Stack:
18:24:14 INFO - 2015-10-22 18:24:14,128 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise<@resource://gre/modules/Sqlite.jsm:562:1
18:24:14 INFO - 2015-10-22 18:24:14,128 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9
18:24:14 INFO - 2015-10-22 18:24:14,128 DEBUG : BROWSER_OUTPUT:
18:24:14 INFO - 2015-10-22 18:24:14,730 INFO : Browser initialized.
18:24:14 INFO - 2015-10-22 18:24:14,730 INFO : Running cycle 1/1 for dromaeo_css test...
18:24:14 INFO - 2015-10-22 18:24:14,730 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp2hp3df\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1
18:24:26 INFO - 2015-10-22 18:24:26,154 DEBUG : BROWSER_OUTPUT: RSS: Main: 127504384
18:24:26 INFO - 2015-10-22 18:24:26,154 DEBUG : BROWSER_OUTPUT:
18:25:17 INFO - 2015-10-22 18:25:17,078 DEBUG : BROWSER_OUTPUT: 1445563517072 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no]
18:27:26 INFO - 2015-10-22 18:27:26,684 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49747/tests/dromaeo/cssquery-ext.html)
18:27:27 INFO - 2015-10-22 18:27:27,607 DEBUG : BROWSER_OUTPUT: RSS: Main: 176668672
18:27:27 INFO - 2015-10-22 18:27:27,607 DEBUG : BROWSER_OUTPUT:
18:30:29 INFO - 2015-10-22 18:30:29,867 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-ext.html (next: http://localhost:49747/tests/dromaeo/cssquery-jquery.html)
18:30:30 INFO - 2015-10-22 18:30:30,421 DEBUG : BROWSER_OUTPUT: RSS: Main: 191889408
18:30:30 INFO - 2015-10-22 18:30:30,421 DEBUG : BROWSER_OUTPUT:
18:33:32 INFO - 2015-10-22 18:33:32,536 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49747/tests/dromaeo/cssquery-mootools.html)
18:33:33 INFO - 2015-10-22 18:33:33,099 DEBUG : BROWSER_OUTPUT: RSS: Main: 154996736
18:33:33 INFO - 2015-10-22 18:33:33,099 DEBUG : BROWSER_OUTPUT:
18:36:35 INFO - 2015-10-22 18:36:35,055 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49747/tests/dromaeo/cssquery-prototype.html)
18:36:35 INFO - 2015-10-22 18:36:35,621 DEBUG : BROWSER_OUTPUT: RSS: Main: 135770112
18:36:35 INFO - 2015-10-22 18:36:35,621 DEBUG : BROWSER_OUTPUT:
18:39:38 INFO - 2015-10-22 18:39:38,417 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49747/tests/dromaeo/cssquery-yui.html)
18:39:38 INFO - 2015-10-22 18:39:38,980 DEBUG : BROWSER_OUTPUT: RSS: Main: 130801664
18:39:38 INFO - 2015-10-22 18:39:38,980 DEBUG : BROWSER_OUTPUT:
18:42:41 INFO - 2015-10-22 18:42:41,346 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-yui.html (next: http://localhost:49747/tests/dromaeo/cssquery-dojo.html)
18:42:41 INFO - 2015-10-22 18:42:41,601 DEBUG : BROWSER_OUTPUT: RSS: Main: 192094208
18:42:41 INFO - 2015-10-22 18:42:41,601 DEBUG : BROWSER_OUTPUT:
18:42:41 INFO - 2015-10-22 18:42:41,601 DEBUG : BROWSER_OUTPUT: __start_tp_report
18:42:41 INFO - 2015-10-22 18:42:41,601 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
18:42:41 INFO - 2015-10-22 18:42:41,601 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
18:42:41 INFO - 2015-10-22 18:42:41,601 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
18:42:41 INFO - 2015-10-22 18:42:41,601 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2676.665416822897;2716.9320766980836;2755.8346499210047;2760.061579063117;2774.3618967637444;5088.643794934356;5115.872103197422;5189.195674670423;5191.402988656301;5210.739463026843;5130.51260130287;5133.358330208724;5219.582433405328;5220.451852555484;5227.059129356714;4184.330507118846;4442.644588432918;4510.210713125206;4526.660500462462;4528.252838281688;4401.185780630578;4401.449818772653;4410.117976404716;4438.223310920576;4504.909901801962;4365.519792822803;4436.157130145279;4466.240739074366;4507.211238033347;4553.772311384434;4414.690971631987;4433.933490997638;4522.9547704522865;4526.0495295987885;4526.3210518422175;4267.231898258312;4339.848105316299;4341.761203133858;4397.758123303217;4435.866923992286;5003.949960500385;5031.748412579393;5046.4701206373165;5127.974360128175;5147.0992576298995;5122.743862806845;5310.867228319285;5339.652922560022;5428.565714742851;5438.483344082306;5314.335708036496;5322.760475778599;5339.439358867302;5396.947595218934;5399.946000539983;4936.6297527685565;4952.083864485082;4991.650584459089;5013.548780609763;5055.090083784918;5340.25236466895;5378;5480.91778623321;5482.068048431776;5560.193771903132;4975.477574854661;5163.122269214243;5175.53420192177;5251.4485978972425;5253.737313134328;5263.710495922761;5334;5370.758315875795;5386.084365657847;5444.128939369682;5016.347874776256;5210.947890521122;5235.685858848481;5334.413214546396;5342.412334643186;5148.768305426265;5170.1986192140275;5237.188235823453;5267.868303292448;5366.087765079946;3372.561566996275;3436.896893093211;3437.8281085945605;3440.535527703778;3454.240067185215;3057.5413687946984;3123.094302652251;3126.0934329044776;3159.77881548294;3180.268538236219;4981.651284410056;5014.749262536859;5068.467810879804;5073.518015788494;5089.796408143707;5252.133397989365;5329.200619907045;5357.919631205535;5362.973185134128;5485.533729632946;4820.638452116105;4889.731064791471;5004.149294619923;5045.924311135336;5145.15105007677;4215.810288537024;4238.512571054368;4276.422682937826;4293.699441039097;4319.654427645781;5576.163575463713;5606.887862242814;5690.9146362804595;5696.373398926032;5740.454656807597;5222.451642577475;5287.286216360896;5292.311999440049;5370.919436208538;5443.129099344086;5501.312335958005;5565.332160140809;5635.718214089361;5661.094224923992;5668.178114173339;5231.529162375405;5258.6581872178185;5266.97366513165;5294.49702278283;5352.170413585901;5224.686518808883;5297.073012222769;5300.443453437333;5424.159255315432;5437.157240627709;5063.443021267732;5241.397239317374;5323.840284791462;5335.386430560535;5336.546393556591;5031.723867617186;5152.407473140637;5356;5369.248305237348;5369.328833895763;5001.699898006132;5112.130937740519;5119.769610367468;5130.435652078343;5258.631895767259;5147.433782283871;5172.844814655567;5245.816396426107;5247.868803279948;5248.343957005374;5062;5109.259157422227;5126.026055049529;5162.547789799815;5286.312779338661
18:42:41 INFO - 2015-10-22 18:42:41,601 DEBUG : BROWSER_OUTPUT: |1;ext.html;480.8942032752793;488.72387101287774;498.72570086452464;501.5962150468872;504.41991709534057;30090.99999999997;30263.848680756593;30830.229244268903;31025.68974310256;31045.223869403275;31059.844700776466;31433.371332573333;38972.80513597428;39522.40716389257;39614.60385396145;8382.245597896188;8475.406721529496;8483.066862645104;8488.702895398663;8495.36284778641;7027.543209691379;8354.540500272482;8411.285040771541;8506.617202225914;8511.148885111501;7375.225601311866;10484.161267098652;10716.464176791169;10736.73158171044;10759.515821788;10386.844197337046;10483.73790655232;10512.737181570445;10519.579216831318;10520.842187367196;7300.56196628204;7390.482666213367;10265.640702575449;10285.537150828155;10418.791624167474;38789.41815872764;39085.21829563393;39115.41326880099;39119.80440097781;39136.21727565433;3156.873725051018;3228.6448490666007;3247.3018301065385;3252.7397808175288;3306;30243.092707218817;30375.696243037506;30418.239544011354;30503;30814;2685.8119931604797;2740.3423178437033;2747.4917140329103;2750.614913912054;2775.056480796539;332054.02487303596;334560;335716;335808;337009;45932.540674593496;47089.875676729665;53836.73081634567;54661.45338546563;55001.049695588634;51922.22116668253;52192;52256.73871630617;52321.7383913078;52375.47624523706;13507.189568625914;13635.318234088256;13708;13808.17150970945;13821.170729756046;10682.572697092184;10798.568057277778;10812.891871081345;10828.837567436494;10855.077318427864;17088.316467341167;17332.91333543324;17517.64964700724;17633;17656.470305890845;7167.784966451015;7278.96360518194;7397.963010184914;7429.582392099586;7435.739749108863;862.0259107208939;892.0989800301612;901.5041727050227;907.0385391485047;1029.984550231747;27469.450610987667;28067.43865122686;28078.43843123126;28195.17287663758;28558.857205713837;982.5578489679673;1011.4437059617181;1028.9639862604922;1059.7880423915249;1080.6649938519083;14380.85619143816;14985.250737463084;15101.109261787626;15274.389024438899;15810.130442825524;412151;413435;413778;416579;417788;48555.271670924965;48780.902302240174;48788;48836.12825831619;48865.755671221414;1102.0412241350077;1111.1666250312267;1212.345333519907;1218.256863313378;1328.0106320790878;26572.7342726578;26890;27069.458610827278;27093.45813083688;27187.59218611762;37965.81017094897;39406.408903867035;39460.40809387804;39473.40789888211;39548.802255989685;267589;462363;483462;488164;489870;27681.031163909167;27752.722472775014;28102.578461322686;28165.859170704014;28192.29519262035;40575.1884962305;41118.6084870293;41362.37956430596;41474.79262603668;41962.58037419709;34049.48925766165;35946.28107437889;36033.27933441369;36096.4585531212;36824.68577828008;34794.13014674552;34888.302233955685;35300.82349588236;35483.82258088693;36170.819145904105;35549;36040.63959360373;36148.81925590355;36189.63810361968;36260.63739362573;29745.70254297516;29924.85037574798;31859.68140318567;32291;32895.506567401986;24433.38916527101;24915.127970520945;24927.875360623082;24941;25013
18:42:41 INFO - 2015-10-22 18:42:41,608 DEBUG : BROWSER_OUTPUT: |2;jquery.html;319.16856588586717;322.8595560930995;326.78268951147487;331.19023986353375;331.3555135261916;17035.744463833038;17411.129443527803;17412.477625671236;17683.292668293252;18229.635407291848;16378.414044067651;16590.917045414786;16935.153242337896;16955.491335259932;16986;5354.330708661418;5370.865728356793;5437.401885792579;5451.618386712932;5472.945270547293;5420.674759514442;5441;5450.29146210992;5453.481919217687;5457.099578569551;4192;4314.956850431503;5394.406615272317;5402.810901618444;5403.8108666196695;5956.642601443906;5995.961373133134;6002.999999999956;6004.729787159588;6013.75944962201;5194.194899790538;5196.818111366122;5207.114790485589;5212.478752124795;5213.244079608474;16244.918775406166;16647.33410663572;19565.21739130433;20975.685364719542;21403.892980535154;2122.1617461102933;2169.372970272296;2218.8003079722903;2273.704418425594;2401.447667036574;13775.931120344434;13860.722785544232;14085.64785880351;14213;14393.352299146563;1641.9671606567802;1718.3899715600908;1778.9822101778946;1787.4012205910906;1841.8250266224686;70066.42575459971;71129;72929;72982;74256.62871685607;9407.576659050359;9471.90528094724;9745.707628771148;9758.756031099138;9831.950840245752;12189.390530473442;12215.511379544718;12219.266843989211;12235.816462753066;12238.632841014783;7673.846523069619;7722.3049925506975;7740.535567865946;7789.688412463438;7790.376769858398;7569.659365328574;7571.697132114764;7729.806754831175;7730.342920851678;7785.455018148788;5455.781768729206;5456.399796022434;5512.118061110203;5513.889722205614;5559.499645031967;1582.723023470911;1671.4400675773752;1694.3476761446684;1698.2357938927532;1709.4529750479976;412.53383676445424;416.7374554030941;419.10521037584977;439.1480527776104;862.784303924019;10965.780684386267;11136.888631113747;11300.434978251218;11391.373474458818;11511.654650360502;414.56470705758835;444.2203932099145;450.4459514796818;463.3767582601396;471.93864797576094;6234.906476402857;6290.087937249074;6296.5592408531875;6576.177977752781;6594.934050659528;418376;423038.6226616933;427524;428186;428234;69190.3080969194;72805.27194728091;72916.63541682258;76061.61969190119;86111.13888861031;472.0959362820161;483.42713884047515;484.4912841516323;484.5856792442481;485.85181519636143;10230.335028223142;10326.535305911102;10825.051490731745;10861.728456788644;10933.507992140214;37942.999999998894;38277.23445531129;38352.23295534018;38546.95859371497;40856;54392.18411723906;54491.72754136204;54710.72644636752;54969.135031114245;55349;5920.023196172756;6100.450959413674;6253.312135664983;6265.248170219603;6285;12256.938715306367;12379.69050773738;12511.249325040526;12564.183328083645;12628.621141365773;10362.637307694195;10481.580736770444;10634.30876992993;10638;10675.679729608124;10458.790824183321;10550.630727924487;10581.36511809294;10587.994140556635;10752.462376881282;9090.181883630505;10804.03131057241;10890.564377424815;10944.069754070993;11028;5699.601027928005;6167.4140956610845;6248.4064013918605;6260.937390626218;6262.405071518198;10010.899891000996;10212.438315892698;10521.842172367573;10525.421101839473;10773.138148948223
18:42:41 INFO - 2015-10-22 18:42:41,609 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1181.208590244536;1257.7107265328973;1333.2467156056832;1463.967902628647;1595.2103708664206;7832.608369581527;8581.742547723577;8886.244669203117;9004.234640055603;9011.594478248478;8624.310055195585;8637.784055398619;8714.694985675502;8749.475031498114;8756.430831995916;1936.6320399124122;1974.003128420146;2150.5376344086017;2157.8489505734606;2209.0942713487475;2199.417154454071;2203.911843526257;2207.8233741300737;2208.933731988035;2214.712087428632;2133.25336132354;2136.7435907691097;2138.347803919804;2139.272647299918;2140.1546389176265;1840.4754644926131;1848.6302739452096;2167.3389616167065;2170.804627583525;2173.7282839645045;2195;2207.4812419081504;2210.469487323031;2221.9777802221934;2228.3760547046854;8547.95726021366;8601.827963440695;8727.170918762708;8803.207711306013;8820.338474614366;4888.584470320027;4972.154733695281;4996.650234483587;5107.438181799999;5109.105906466353;3241.286916878283;3299.472084466498;3307.536944827726;3329.051220402174;3331.3170799985946;940.431039221275;950.1686024728363;953.9427634341962;959.764857609883;961.1589858873485;12594.937025314906;12642.810357844639;12695.873041269562;12714.491420343174;12726.10917235794;4125.906634741796;4305.9569404306185;4313.137372525444;4363.629091527192;4424.3142312941545;10573.101286390589;10671.3063650864;10671.626493072705;10687.519061642244;10694.358338499715;5004.824831130893;5016.398032236155;5017.949820501748;5018.698878067327;5019.67372120811;4454.26504626733;4541.954580454153;4548.340490628841;4564.657650676146;4566.383538222364;3401.795892246473;3521.542199514047;3536.6993805526818;3571;3574.1779390740276;1055.6358056470506;1063.3513556730388;1073.061071562383;1083.7236504691252;1084.7559299157626;894.932880034;1109.7225693576606;1129.7683974785314;1132.2753437799815;1134.5064896769932;7031;7636.732714354971;7998.600069996477;8163.22449367321;8167.346612271004;616.1927874484439;616.202018386182;620.8261686727724;639.1978067525226;642.5598465051455;5589.469000444951;5706.28671416073;5761.279840019997;5765.827025189251;5766.769329226784;11136.61021864247;11222.719432014102;11300.378479183724;11305.773884522428;11306.434678266218;11088.94655007773;11175.608853690082;11177;11178.105751539857;11180.161487888441;7933.484323518953;8005.239502247161;8107.756767296991;8154.429189956765;8167.305779008732;7621.618919053914;7656;7657.731979380695;7675.270849269311;7702.922970770221;9492.85760713576;9657.806843862943;9665.081817227352;9710.126088651772;9771.9022809771;9769.853452198366;10076.193904487476;10078.798424031625;10086.798264034825;10105.9494702526;7915.84168316642;7951.562664053533;7976.401769867167;7976.441649084509;7978.601069946596;8636.956815215884;8712.651493940424;8715.738527844174;8722.91277087221;8764.649414023368;7964.920350796418;7984.800379990546;8229.135940726137;8247.381446391424;8255.545944973084;6154.969225153845;6230.408111229426;6957.965210173917;7829.45193836426;7941.404394670539;7504.6247687614305;7876.448648594544;7911.485753426009;7921.960390198012;7928.603569821601;6774.729010839708;6775.62734049632;6794.8641027180165;6800.455963523005;6803;6768;6918.792436226921;6932.376086152069;6940.895886561605;6956.339147780953
18:42:41 INFO - 2015-10-22 18:42:41,632 DEBUG : BROWSER_OUTPUT: |4;prototype.html;315.5597940872483;321.02248652852063;326.83168168393274;327.1836767265672;330.36240056690576;5754.625949313297;5782.826515204545;5784.797532086383;5785.5082318002915;5802.245708057957;5494.983428065811;5596.4403559644015;5617.9438205617935;5687.033204355259;5711.05767548354;4131.07050913545;4153.169366126786;4225.852095176677;4235.089455767024;4242.236397448458;4126.216018956388;4224.345226489883;4234.280172370704;4234.809433575481;4235.512916014667;4053.797310134496;4139.7930103494855;4212.220739163249;4215.409842622035;4217.915641687165;4170.72890262132;4195.2238835815315;4206.705530612859;4206.894827629334;4209.158168366308;3674.6141655126094;3700;3712.925741485155;3717.3494638438165;3717.75834570752;5529.944700552983;5532.031894418501;5539.750711217965;5550;5556.666600004012;4784.712917224978;4868.902621947541;4918.729469879156;4935.3090567320605;4946.431160416562;5762.308522977228;5770.2498675171955;5848.853778655525;5861.970690146564;5888.882222355529;4073.7555746655;4134.111166099303;4154.626083652486;4164.7917604119675;4165.10450253197;6106.114613381079;6133.570650054498;6142.631442113443;6154.2307211599;6161.938380616226;4095.97952010244;4126;4248.575142485726;4249.2563801334645;4252.128313695685;3933.5476420212044;4179.540250572434;4186.413902053715;4296.89257768552;4298.376735373354;4867.975660121677;4894.26586012094;4902.362692849907;4960.975195124001;4966.428860680996;5804.9419505805245;5861.032929566659;5919.970400147971;5920.407959204132;5934.080217566284;1514.1293756090292;1526.3055309833999;1526.7251894659069;1527.9686211806986;1529.4493982166412;1006.8892421833592;1009.5255230041869;1029.9382037077798;1033.9483025848679;1038.0242571982312;5599.384067752544;5702.714864256771;5735.082386818089;5764.394738552475;5780.88438231233;5943.405659434022;6109.175261339662;6133.815985520441;6135.907961380493;6137.355577664371;5598.720063996784;5634.126710359901;5753.626014309057;5758.222639943638;5770.13447982806;3313.93372132556;3319.5684561006915;3341.6491268416953;3356.81537515439;3361.109306033903;5959;6256.468200203034;6338.556301058974;6408.935910640927;6510.804675859732;6224.657643829542;6292.842678933063;6297.527685423612;6303.747850085945;6304.306526282106;6323.43089121972;6479.870402591921;6483.61098334092;6500.512461565307;6501;6067.514598831995;6147.784827531015;6210.4100110489435;6224.408681175281;6225.595336303126;5825.592208545361;6011.248593925759;6035.456808887221;6042.214512113573;6046.881326954527;6322.209723784527;6458.160439142881;6458.451031662361;6468.676566171766;6469.514786390945;4164.146349998183;4187.7068605198565;4196.601322874322;4198.181354635817;4207.6633869291;6031.879362412639;6040.275166979991;6216.129741836056;6218.22272215973;6224.408681175281;5967.403259673998;6062.787802426894;6063.938810708197;6066.15073889647;6066.908996364969;5977.432143946318;5994.910076348771;6004.669743164168;6009.609375390585;6011.218541589566;5722.799702010409;5827.5337972961215;5876.118582212703;5880.735366908414;5884.941150588611;5812;6029.60807547508;6030.276366836008;6031.547633927561;6041.24484439445;5868.855573163192;5923.7926672566255;5931.288245410578;5944.3164036136395;5945.762169513344
18:42:41 INFO - 2015-10-22 18:42:41,634 DEBUG : BROWSER_OUTPUT: |5;yui.html;721.239092757141;739.1536690489388;748.2330611123598;751.1737089201882;757.201152783813;12599.55901543446;13257.602271931846;14201.786973195385;14671.413143474276;14800.555983320517;12851.100422970396;12904.161229520074;12956.222626642408;13006.609801705941;13033.93483032586;490.2450226650971;506.0056988018543;595.2826843653398;602.7197353230747;602.8432607522054;590.5629833922887;598.7365559153958;599.5024129972113;599.8110595162532;601.3114983344068;620.804446599322;622.8754249150165;624.9750009999594;625.449604348173;625.9561830671855;515.1525740157457;519.7323378460096;598.6228675934134;609.95425343099;621.7077973352517;447.5323287164888;448.549208045915;449.2811501597418;450.061621519133;450.0773414500294;12262.570810021607;12296.754064918652;12330.691732706664;12398.504059837596;12447.626571202878;9307.11582399671;9487.66793162236;9761.365511241747;9774.951125244328;9891.90108098917;10082.294239403245;10100.949495252476;10359.948200259027;10365.481725913749;10370.585176592927;3029.969700302991;3073.3085055862475;3124.7500199983947;3128.655847856734;3187.888423905152;47930.04139917217;48031.519684803054;48111.75944120257;48212.75893620544;48321.27518087231;7048.577085374894;7086.681099350541;7165.09813525523;7301.817454563678;7428.405727541892;9760.267979901535;9808.558614862348;9813.214942804558;10733.731656708645;10744.516496757664;6265.216847894013;6338.968305158444;6340.651264180422;6408.391202835724;6410.326915673881;4086.223617512663;4096.713230073866;4122.773247471418;4189.099343641132;4278.080212754273;2513.4973005398992;2555.3228394475477;2557.5779996300776;2564.3076369380537;2564.8076394270506;415.46405137373057;420.459709273582;423.7965776427332;425.08606496033156;425.179403750764;567.2455634006761;659.594349475079;674.8549061951704;689.8482333886537;702.2100137345486;9076.41003334781;9210.217131543894;9234.907650923538;9277.257819374568;9423.952880235554;625.5433533520464;638.1512588257606;698.8078278473461;701.1376007510688;714.7176865138241;4171.499420069611;4373.2565463871215;4403.647708183337;4458.5318541553315;4532.909341813145;59099.40900591025;59591.40408595859;59619;59721.70139149277;59920.400795992355;50485.74757126264;51019.23471147936;51303.97392052138;51372.74313628483;51515.48484515182;42394;42916;43001.35497967533;43215.9196020102;43441.78279108647;8629.611667474854;8744.431611945203;9035.638574456949;9120.908790912006;9501.762455938657;29594.55608165836;30440.391192176474;31123.821332506508;31277.530837037008;31363.68636313699;43587.34618980654;43847.34228986631;44346;44422.55577444184;44534.331985019606;7348.632568371453;7786.961065194864;7797.376209903081;7851.921480785348;8103.959480202561;12861.356932153169;12939.676508087374;12979.935100324816;13048.478060877458;13251.933740331237;12260.509579616715;12294.569690060805;12499.875001250235;12531.93734031324;12591.11862169676;12156.659214308373;12431.93784031074;12454.688632784253;12578.182418142791;12689.111762176928;11979.820302695642;12091.09316801261;12393.194442361219;12439.564615238423;12614.55849045279;9735.561899714388;10106.626806178376;10265.538050787882;10498.36926258857;10868.61959831402;9698.369605975871;9826.705198844045;9840.950795245977;9995.724923694987;10027.54876030595
18:42:41 INFO - 2015-10-22 18:42:41,634 DEBUG : BROWSER_OUTPUT: __end_tp_report
18:42:41 INFO - 2015-10-22 18:42:41,634 DEBUG : BROWSER_OUTPUT: __start_cc_report
18:42:41 INFO - 2015-10-22 18:42:41,634 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,652
18:42:41 INFO - 2015-10-22 18:42:41,634 DEBUG : BROWSER_OUTPUT: __end_cc_report
18:42:41 INFO - 2015-10-22 18:42:41,634 DEBUG : BROWSER_OUTPUT: __startTimestamp1445564561597__endTimestamp
18:42:41 INFO - 2015-10-22 18:42:41,635 DEBUG : BROWSER_OUTPUT:
18:42:41 INFO - 2015-10-22 18:42:41,635 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
18:42:41 INFO - 2015-10-22 18:42:41,635 DEBUG : BROWSER_OUTPUT: Number of tests: 6
18:42:41 INFO - 2015-10-22 18:42:41,635 DEBUG : BROWSER_OUTPUT:
18:42:41 INFO - 2015-10-22 18:42:41,635 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4904.93 Median:5148.77 stddev:683.64 (13.3%) stddev-sans-first:663.01
18:42:41 INFO - 2015-10-22 18:42:41,635 DEBUG : BROWSER_OUTPUT: Values: 2676.7 2716.9 2755.8 2760.1 2774.4 5088.6 5115.9 5189.2 5191.4 5210.7 5130.5 5133.4 5219.6 5220.5 5227.1 4184.3 4442.6 4510.2 4526.7 4528.3 4401.2 4401.4 4410.1 4438.2 4504.9 4365.5 4436.2 4466.2 4507.2 4553.8 4414.7 4433.9 4523.0 4526.0 4526.3 4267.2 4339.8 4341.8 4397.8 4435.9 5003.9 5031.7 5046.5 5128.0 5147.1 5122.7 5310.9 5339.7 5428.6 5438.5 5314.3 5322.8 5339.4 5396.9 5399.9 4936.6 4952.1 4991.7 5013.5 5055.1 5340.3 5378.0 5480.9 5482.1 5560.2 4975.5 5163.1 5175.5 5251.4 5253.7 5263.7 5334.0 5370.8 5386.1 5444.1 5016.3 5210.9 5235.7 5334.4 5342.4 5148.8 5170.2 5237.2 5267.9 5366.1 3372.6 3436.9 3437.8 3440.5 3454.2 3057.5 3123.1 3126.1 3159.8 3180.3 4981.7 5014.7 5068.5 5073.5 5089.8 5252.1 5329.2 5357.9 5363.0 5485.5 4820.6 4889.7 5004.1 5045.9 5145.2 4215.8 4238.5 4276.4 4293.7 4319.7 5576.2 5606.9 5690.9 5696.4 5740.5 5222.5 5287.3 5292.3 5370.9 5443.1 5501.3 5565.3 5635.7 5661.1 5668.2 5231.5 5258.7 5267.0 5294.5 5352.2 5224.7 5297.1 5300.4 5424.2 5437.2 5063.4 5241.4 5323.8 5335.4 5336.5 5031.7 5152.4 5356.0 5369.2 5369.3 5001.7 5112.1 5119.8 5130.4 5258.6 5147.4 5172.8 5245.8 5247.9 5248.3 5062.0 5109.3 5126.0 5162.5 5286.3
18:42:41 INFO - 2015-10-22 18:42:41,635 DEBUG : BROWSER_OUTPUT:
18:42:41 INFO - 2015-10-22 18:42:41,635 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:53437.13 Median:333307.01 stddev:106457.66 (31.9%) stddev-sans-first:106682.06
18:42:41 INFO - 2015-10-22 18:42:41,635 DEBUG : BROWSER_OUTPUT: Values: 480.9 488.7 498.7 501.6 504.4 30091.0 30263.8 30830.2 31025.7 31045.2 31059.8 31433.4 38972.8 39522.4 39614.6 8382.2 8475.4 8483.1 8488.7 8495.4 7027.5 8354.5 8411.3 8506.6 8511.1 7375.2 10484.2 10716.5 10736.7 10759.5 10386.8 10483.7 10512.7 10519.6 10520.8 7300.6 7390.5 10265.6 10285.5 10418.8 38789.4 39085.2 39115.4 39119.8 39136.2 3156.9 3228.6 3247.3 3252.7 3306.0 30243.1 30375.7 30418.2 30503.0 30814.0 2685.8 2740.3 2747.5 2750.6 2775.1 332054.0 334560.0 335716.0 335808.0 337009.0 45932.5 47089.9 53836.7 54661.5 55001.0 51922.2 52192.0 52256.7 52321.7 52375.5 13507.2 13635.3 13708.0 13808.2 13821.2 10682.6 10798.6 10812.9 10828.8 10855.1 17088.3 17332.9 17517.6 17633.0 17656.5 7167.8 7279.0 7398.0 7429.6 7435.7 862.0 892.1 901.5 907.0 1030.0 27469.5 28067.4 28078.4 28195.2 28558.9 982.6 1011.4 1029.0 1059.8 1080.7 14380.9 14985.3 15101.1 15274.4 15810.1 412151.0 413435.0 413778.0 416579.0 417788.0 48555.3 48780.9 48788.0 48836.1 48865.8 1102.0 1111.2 1212.3 1218.3 1328.0 26572.7 26890.0 27069.5 27093.5 27187.6 37965.8 39406.4 39460.4 39473.4 39548.8 267589.0 462363.0 483462.0 488164.0 489870.0 27681.0 27752.7 28102.6 28165.9 28192.3 40575.2 41118.6 41362.4 41474.8 41962.6 34049.5 35946.3 36033.3 36096.5 36824.7 34794.1 34888.3 35300.8 35483.8 36170.8 35549.0 36040.6 36148.8 36189.6 36260.6 29745.7 29924.9 31859.7 32291.0 32895.5 24433.4 24915.1 24927.9 24941.0 25013.0
18:42:41 INFO - 2015-10-22 18:42:41,637 DEBUG : BROWSER_OUTPUT:
18:42:41 INFO - 2015-10-22 18:42:41,637 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25168.67 Median:425281.31 stddev:70117.24 (16.5%) stddev-sans-first:70289.11
18:42:41 INFO - 2015-10-22 18:42:41,637 DEBUG : BROWSER_OUTPUT: Values: 319.2 322.9 326.8 331.2 331.4 17035.7 17411.1 17412.5 17683.3 18229.6 16378.4 16590.9 16935.2 16955.5 16986.0 5354.3 5370.9 5437.4 5451.6 5472.9 5420.7 5441.0 5450.3 5453.5 5457.1 4192.0 4315.0 5394.4 5402.8 5403.8 5956.6 5996.0 6003.0 6004.7 6013.8 5194.2 5196.8 5207.1 5212.5 5213.2 16244.9 16647.3 19565.2 20975.7 21403.9 2122.2 2169.4 2218.8 2273.7 2401.4 13775.9 13860.7 14085.6 14213.0 14393.4 1642.0 1718.4 1779.0 1787.4 1841.8 70066.4 71129.0 72929.0 72982.0 74256.6 9407.6 9471.9 9745.7 9758.8 9832.0 12189.4 12215.5 12219.3 12235.8 12238.6 7673.8 7722.3 7740.5 7789.7 7790.4 7569.7 7571.7 7729.8 7730.3 7785.5 5455.8 5456.4 5512.1 5513.9 5559.5 1582.7 1671.4 1694.3 1698.2 1709.5 412.5 416.7 419.1 439.1 862.8 10965.8 11136.9 11300.4 11391.4 11511.7 414.6 444.2 450.4 463.4 471.9 6234.9 6290.1 6296.6 6576.2 6594.9 418376.0 423038.6 427524.0 428186.0 428234.0 69190.3 72805.3 72916.6 76061.6 86111.1 472.1 483.4 484.5 484.6 485.9 10230.3 10326.5 10825.1 10861.7 10933.5 37943.0 38277.2 38352.2 38547.0 40856.0 54392.2 54491.7 54710.7 54969.1 55349.0 5920.0 6100.5 6253.3 6265.2 6285.0 12256.9 12379.7 12511.2 12564.2 12628.6 10362.6 10481.6 10634.3 10638.0 10675.7 10458.8 10550.6 10581.4 10588.0 10752.5 9090.2 10804.0 10890.6 10944.1 11028.0 5699.6 6167.4 6248.4 6260.9 6262.4 10010.9 10212.4 10521.8 10525.4 10773.1
18:42:41 INFO - 2015-10-22 18:42:41,640 DEBUG : BROWSER_OUTPUT:
18:42:41 INFO - 2015-10-22 18:42:41,640 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5988.14 Median:5063.56 stddev:3442.22 (68.0%) stddev-sans-first:3432.91
18:42:41 INFO - 2015-10-22 18:42:41,640 DEBUG : BROWSER_OUTPUT: Values: 1181.2 1257.7 1333.2 1464.0 1595.2 7832.6 8581.7 8886.2 9004.2 9011.6 8624.3 8637.8 8714.7 8749.5 8756.4 1936.6 1974.0 2150.5 2157.8 2209.1 2199.4 2203.9 2207.8 2208.9 2214.7 2133.3 2136.7 2138.3 2139.3 2140.2 1840.5 1848.6 2167.3 2170.8 2173.7 2195.0 2207.5 2210.5 2222.0 2228.4 8548.0 8601.8 8727.2 8803.2 8820.3 4888.6 4972.2 4996.7 5107.4 5109.1 3241.3 3299.5 3307.5 3329.1 3331.3 940.4 950.2 953.9 959.8 961.2 12594.9 12642.8 12695.9 12714.5 12726.1 4125.9 4306.0 4313.1 4363.6 4424.3 10573.1 10671.3 10671.6 10687.5 10694.4 5004.8 5016.4 5017.9 5018.7 5019.7 4454.3 4542.0 4548.3 4564.7 4566.4 3401.8 3521.5 3536.7 3571.0 3574.2 1055.6 1063.4 1073.1 1083.7 1084.8 894.9 1109.7 1129.8 1132.3 1134.5 7031.0 7636.7 7998.6 8163.2 8167.3 616.2 616.2 620.8 639.2 642.6 5589.5 5706.3 5761.3 5765.8 5766.8 11136.6 11222.7 11300.4 11305.8 11306.4 11088.9 11175.6 11177.0 11178.1 11180.2 7933.5 8005.2 8107.8 8154.4 8167.3 7621.6 7656.0 7657.7 7675.3 7702.9 9492.9 9657.8 9665.1 9710.1 9771.9 9769.9 10076.2 10078.8 10086.8 10105.9 7915.8 7951.6 7976.4 7976.4 7978.6 8637.0 8712.7 8715.7 8722.9 8764.6 7964.9 7984.8 8229.1 8247.4 8255.5 6155.0 6230.4 6958.0 7829.5 7941.4 7504.6 7876.4 7911.5 7922.0 7928.6 6774.7 6775.6 6794.9 6800.5 6803.0 6768.0 6918.8 6932.4 6940.9 6956.3
18:42:41 INFO - 2015-10-22 18:42:41,641 DEBUG : BROWSER_OUTPUT:
18:42:41 INFO - 2015-10-22 18:42:41,642 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4974.83 Median:5744.35 stddev:1510.91 (26.3%) stddev-sans-first:1474.12
18:42:41 INFO - 2015-10-22 18:42:41,642 DEBUG : BROWSER_OUTPUT: Values: 315.6 321.0 326.8 327.2 330.4 5754.6 5782.8 5784.8 5785.5 5802.2 5495.0 5596.4 5617.9 5687.0 5711.1 4131.1 4153.2 4225.9 4235.1 4242.2 4126.2 4224.3 4234.3 4234.8 4235.5 4053.8 4139.8 4212.2 4215.4 4217.9 4170.7 4195.2 4206.7 4206.9 4209.2 3674.6 3700.0 3712.9 3717.3 3717.8 5529.9 5532.0 5539.8 5550.0 5556.7 4784.7 4868.9 4918.7 4935.3 4946.4 5762.3 5770.2 5848.9 5862.0 5888.9 4073.8 4134.1 4154.6 4164.8 4165.1 6106.1 6133.6 6142.6 6154.2 6161.9 4096.0 4126.0 4248.6 4249.3 4252.1 3933.5 4179.5 4186.4 4296.9 4298.4 4868.0 4894.3 4902.4 4961.0 4966.4 5804.9 5861.0 5920.0 5920.4 5934.1 1514.1 1526.3 1526.7 1528.0 1529.4 1006.9 1009.5 1029.9 1033.9 1038.0 5599.4 5702.7 5735.1 5764.4 5780.9 5943.4 6109.2 6133.8 6135.9 6137.4 5598.7 5634.1 5753.6 5758.2 5770.1 3313.9 3319.6 3341.6 3356.8 3361.1 5959.0 6256.5 6338.6 6408.9 6510.8 6224.7 6292.8 6297.5 6303.7 6304.3 6323.4 6479.9 6483.6 6500.5 6501.0 6067.5 6147.8 6210.4 6224.4 6225.6 5825.6 6011.2 6035.5 6042.2 6046.9 6322.2 6458.2 6458.5 6468.7 6469.5 4164.1 4187.7 4196.6 4198.2 4207.7 6031.9 6040.3 6216.1 6218.2 6224.4 5967.4 6062.8 6063.9 6066.2 6066.9 5977.4 5994.9 6004.7 6009.6 6011.2 5722.8 5827.5 5876.1 5880.7 5884.9 5812.0 6029.6 6030.3 6031.5 6041.2 5868.9 5923.8 5931.3 5944.3 5945.8
18:42:41 INFO - 2015-10-22 18:42:41,642 DEBUG : BROWSER_OUTPUT:
18:42:41 INFO - 2015-10-22 18:42:41,642 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13208.69 Median:2491.49 stddev:15830.72 (635.4%) stddev-sans-first:15847.35
18:42:41 INFO - 2015-10-22 18:42:41,642 DEBUG : BROWSER_OUTPUT: Values: 721.2 739.2 748.2 751.2 757.2 12599.6 13257.6 14201.8 14671.4 14800.6 12851.1 12904.2 12956.2 13006.6 13033.9 490.2 506.0 595.3 602.7 602.8 590.6 598.7 599.5 599.8 601.3 620.8 622.9 625.0 625.4 626.0 515.2 519.7 598.6 610.0 621.7 447.5 448.5 449.3 450.1 450.1 12262.6 12296.8 12330.7 12398.5 12447.6 9307.1 9487.7 9761.4 9775.0 9891.9 10082.3 10100.9 10359.9 10365.5 10370.6 3030.0 3073.3 3124.8 3128.7 3187.9 47930.0 48031.5 48111.8 48212.8 48321.3 7048.6 7086.7 7165.1 7301.8 7428.4 9760.3 9808.6 9813.2 10733.7 10744.5 6265.2 6339.0 6340.7 6408.4 6410.3 4086.2 4096.7 4122.8 4189.1 4278.1 2513.5 2555.3 2557.6 2564.3 2564.8 415.5 420.5 423.8 425.1 425.2 567.2 659.6 674.9 689.8 702.2 9076.4 9210.2 9234.9 9277.3 9424.0 625.5 638.2 698.8 701.1 714.7 4171.5 4373.3 4403.6 4458.5 4532.9 59099.4 59591.4 59619.0 59721.7 59920.4 50485.7 51019.2 51304.0 51372.7 51515.5 42394.0 42916.0 43001.4 43215.9 43441.8 8629.6 8744.4 9035.6 9120.9 9501.8 29594.6 30440.4 31123.8 31277.5 31363.7 43587.3 43847.3 44346.0 44422.6 44534.3 7348.6 7787.0 7797.4 7851.9 8104.0 12861.4 12939.7 12979.9 13048.5 13251.9 12260.5 12294.6 12499.9 12531.9 12591.1 12156.7 12431.9 12454.7 12578.2 12689.1 11979.8 12091.1 12393.2 12439.6 12614.6 9735.6 10106.6 10265.5 10498.4 10868.6 9698.4 9826.7 9841.0 9995.7 10027.5
18:42:41 INFO - 2015-10-22 18:42:41,642 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
18:42:41 INFO - 2015-10-22 18:42:41,642 DEBUG : BROWSER_OUTPUT:
18:42:42 INFO - 2015-10-22 18:42:42,033 INFO : Browser exited with error code: 0
18:42:42 INFO - 2015-10-22 18:42:42,769 INFO : Completed test dromaeo_css (00:18:39)
18:42:42 INFO - 2015-10-22 18:42:42,769 INFO : Starting test kraken
18:42:42 INFO - 2015-10-22 18:42:42,769 DEBUG : operating with platform_type : w7_
18:42:42 INFO - 2015-10-22 18:42:42,769 INFO : Initialising browser for kraken test...
18:42:42 INFO - 2015-10-22 18:42:42,796 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpnkq1ki\profile http://localhost:49747/getInfo.html
18:42:47 INFO - 2015-10-22 18:42:47,259 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
18:42:47 INFO - 2015-10-22 18:42:47,259 DEBUG : BROWSER_OUTPUT: colorDepth:24
18:42:47 INFO - 2015-10-22 18:42:47,259 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
18:42:47 INFO - 2015-10-22 18:42:47,259 DEBUG : BROWSER_OUTPUT: __metrics
18:42:47 INFO - 2015-10-22 18:42:47,387 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
18:42:48 INFO - 2015-10-22 18:42:48,170 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 121: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code.
18:42:48 INFO - 2015-10-22 18:42:48,384 DEBUG : BROWSER_OUTPUT: console.error:
18:42:48 INFO - 2015-10-22 18:42:48,384 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]
18:42:48 INFO - 2015-10-22 18:42:48,384 DEBUG : BROWSER_OUTPUT: Stack:
18:42:48 INFO - 2015-10-22 18:42:48,384 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19
18:42:48 INFO - 2015-10-22 18:42:48,384 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9
18:42:48 INFO - 2015-10-22 18:42:48,384 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1
18:42:48 INFO - 2015-10-22 18:42:48,384 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51
18:42:48 INFO - 2015-10-22 18:42:48,384 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
18:42:48 INFO - 2015-10-22 18:42:48,384 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3
18:42:48 INFO - 2015-10-22 18:42:48,384 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14
18:42:48 INFO - 2015-10-22 18:42:48,385 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15
18:42:48 INFO - 2015-10-22 18:42:48,385 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
18:42:48 INFO - 2015-10-22 18:42:48,385 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3
18:42:48 INFO - 2015-10-22 18:42:48,385 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14
18:42:48 INFO - 2015-10-22 18:42:48,385 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12
18:42:48 INFO - 2015-10-22 18:42:48,385 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6549.98
18:44:25 INFO - RETURN: kraken: 1580.80
18:44:25 INFO - RETURN: v8_7: 19212.18
18:44:25 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,25]]", "result": "19212.18"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,25]]", "result": "1580.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,25]]", "result": "6549.98"}}}
18:44:25 INFO - Return code: 0
18:44:25 INFO - # TBPL SUCCESS #
18:44:25 INFO - Running post-action listener: _resource_record_post_action
18:44:25 INFO - Running post-run listener: _resource_record_post_run
18:44:26 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 217115648; Write bytes: 571732480; Read time: 3125490; Write time: 6890210
18:44:26 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 960000; Read time: 0; Write time: 2080
18:44:26 INFO - run-tests - Wall time: 1226s; CPU: 13.0%; Read bytes: 217115648; Write bytes: 548156416; Read time: 3125490; Write time: 6869090
18:44:26 INFO - Running post-run listener: _upload_blobber_files
18:44:26 INFO - Blob upload gear active.
18:44:26 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ...
18:44:26 INFO - Copying logs to upload dir...
18:44:26 INFO - mkdir: C:\slave\test-pgo\build\upload\logs
program finished with exit code 0
elapsedTime=1488.051000
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 10 secs) (at 2015-10-22 18:44:49.476480) =========
========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-22 18:44:49.481366) =========
'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-101
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-101
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-101
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/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://queue.taskcluster.net/v1/task/rh2KU2CFRsmuXJ6R4endBg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip'
========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-22 18:44:50.028081) =========
========= Started 'rm -f ...' (results: 0, elapsed: 13 secs) (at 2015-10-22 18:44:50.028474) =========
'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-101
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-101
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-101
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: 13 secs) (at 2015-10-22 18:45:03.884201) =========
========= Started reboot slave lost (results: 0, elapsed: 1 mins, 11 secs) (at 2015-10-22 18:45:03.884526) =========
========= Finished reboot slave lost (results: 0, elapsed: 1 mins, 11 secs) (at 2015-10-22 18:46:15.584481) =========