builder: fx-team_win7_ix_test-dromaeojs-e10s-pgo
slave: t-w732-ix-100
starttime: 1477347395.11
results: success (0)
buildid: 20161024144922
builduid: 24853bc936714a19b44a4729024e9741
revision: 9cdd8ed1733a941de56c4d022400c3e4b2ce0d12
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 15:16:35.109970) =========
master: http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 15:16:35.110331) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 15:16:35.110614) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 15:16:35.252771) =========
========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-10-24 15:16:35.253166) =========
'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\. (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-100
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-100
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
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-100
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
--15:16:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,179 (12K) [text/x-python]
0K .......... . 100% 2.50 GB/s
15:16:36 (2.50 GB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.131000
========= master_lag: 4.44 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-10-24 15:16:40.819686) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 29 secs) (at 2016-10-24 15:16:40.820040) =========
'rm' '-rf' 'scripts' 'properties'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'scripts', 'properties']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-100
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-100
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
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-100
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=29.514000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 29 secs) (at 2016-10-24 15:17:10.340778) =========
========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-10-24 15:17:10.341145) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 9cdd8ed1733a941de56c4d022400c3e4b2ce0d12 --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 9cdd8ed1733a941de56c4d022400c3e4b2ce0d12 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-100
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-100
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
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-100
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
2016-10-24 15:17:11,697 truncating revision to first 12 chars
2016-10-24 15:17:11,697 Setting DEBUG logging.
2016-10-24 15:17:11,697 attempt 1/10
2016-10-24 15:17:11,697 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9cdd8ed1733a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-10-24 15:17:12,278 unpacking tar archive at: fx-team-9cdd8ed1733a/testing/mozharness/
2016-10-24 15:17:13,801 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=4.843000
========= master_lag: 0.14 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-10-24 15:17:15.328280) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 15:17:15.328658) =========
script_repo_revision: 9cdd8ed1733a941de56c4d022400c3e4b2ce0d12
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 15:17:15.329051) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 15:17:15.329336) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 15:17:15.339345) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 30 secs) (at 2016-10-24 15:17:15.339640) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs-e10s' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-100
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-100
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/buildprops.json
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
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-100
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
15:17:16 INFO - MultiFileLogger online at 20161024 15:17:16 in C:\slave\test
15:17:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team
15:17:16 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
15:17:16 INFO - {'append_to_log': False,
15:17:16 INFO - 'base_work_dir': 'C:\\slave\\test',
15:17:16 INFO - 'blob_upload_branch': 'Fx-Team',
15:17:16 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
15:17:16 INFO - 'branch': 'Fx-Team',
15:17:16 INFO - 'buildbot_json_path': 'buildprops.json',
15:17:16 INFO - 'config_files': ('talos/windows_config.py',),
15:17:16 INFO - 'default_actions': ('clobber',
15:17:16 INFO - 'read-buildbot-config',
15:17:16 INFO - 'download-and-extract',
15:17:16 INFO - 'populate-webroot',
15:17:16 INFO - 'create-virtualenv',
15:17:16 INFO - 'install',
15:17:16 INFO - 'run-tests'),
15:17:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
15:17:16 INFO - 'download_minidump_stackwalk': True,
15:17:16 INFO - 'download_symbols': 'ondemand',
15:17:16 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
15:17:16 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
15:17:16 INFO - 'hg': 'c:/mozilla-build/hg/hg',
15:17:16 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
15:17:16 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
15:17:16 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
15:17:16 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
15:17:16 INFO - 'C:/mozilla-build/tooltool.py'),
15:17:16 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
15:17:16 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
15:17:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
15:17:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
15:17:16 INFO - 'installer_path': 'installer.exe',
15:17:16 INFO - 'log_level': 'info',
15:17:16 INFO - 'log_name': 'talos',
15:17:16 INFO - 'log_to_console': True,
15:17:16 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
15:17:16 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
15:17:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
15:17:16 INFO - 'opt_config_files': (),
15:17:16 INFO - 'pip_index': False,
15:17:16 INFO - 'sps_profile': False,
15:17:16 INFO - 'sps_profile_interval': 0,
15:17:16 INFO - 'suite': 'dromaeojs-e10s',
15:17:16 INFO - 'system_bits': '32',
15:17:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
15:17:16 INFO - 'title': 'T-W732-IX-100',
15:17:16 INFO - 'use_talos_json': True,
15:17:16 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
15:17:16 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
15:17:16 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
15:17:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
15:17:16 INFO - 'work_dir': 'build'}
15:17:16 INFO - [mozharness: 2016-10-24 22:17:16.361000Z] Running clobber step.
15:17:16 INFO - Running pre-action listener: _resource_record_pre_action
15:17:16 INFO - Running main action method: clobber
15:17:16 INFO - rmtree: C:\slave\test\build
15:17:16 INFO - Using _rmtree_windows ...
15:17:16 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
15:19:18 INFO - Running post-action listener: _resource_record_post_action
15:19:18 INFO - [mozharness: 2016-10-24 22:19:18.578000Z] Finished clobber step (success)
15:19:18 INFO - [mozharness: 2016-10-24 22:19:18.578000Z] Running read-buildbot-config step.
15:19:18 INFO - Running pre-action listener: _resource_record_pre_action
15:19:18 INFO - Running main action method: read_buildbot_config
15:19:18 INFO - Using buildbot properties:
15:19:18 INFO - {
15:19:18 INFO - "project": "",
15:19:18 INFO - "product": "firefox",
15:19:18 INFO - "script_repo_revision": "production",
15:19:18 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs-e10s",
15:19:18 INFO - "repository": "",
15:19:18 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs-e10s",
15:19:18 INFO - "buildid": "20161024144922",
15:19:18 INFO - "stage_platform": "win32-pgo",
15:19:18 INFO - "basedir": "C:\\slave\\test",
15:19:18 INFO - "buildnumber": 241,
15:19:18 INFO - "platform": "win7_ix",
15:19:18 INFO - "master": "http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/",
15:19:18 INFO - "slavebuilddir": "test",
15:19:18 INFO - "branch": "fx-team",
15:19:18 INFO - "revision": "9cdd8ed1733a941de56c4d022400c3e4b2ce0d12",
15:19:18 INFO - "slavename": "t-w732-ix-100",
15:19:18 INFO - "builduid": "24853bc936714a19b44a4729024e9741",
15:19:18 INFO - "repo_path": "integration/fx-team"
15:19:18 INFO - }
15:19:18 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.test_packages.json.
15:19:18 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.zip.
15:19:18 INFO - Running post-action listener: _resource_record_post_action
15:19:18 INFO - [mozharness: 2016-10-24 22:19:18.581000Z] Finished read-buildbot-config step (success)
15:19:18 INFO - [mozharness: 2016-10-24 22:19:18.581000Z] Running download-and-extract step.
15:19:18 INFO - Running pre-action listener: _resource_record_pre_action
15:19:18 INFO - Running main action method: download_and_extract
15:19:18 INFO - mkdir: C:\slave\test\build\tests
15:19: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:19:18 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.test_packages.json
15:19:18 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
15:19:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1
15:19:18 INFO - Downloaded 1218 bytes.
15:19:18 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
15:19:18 INFO - Using the following test package requirements:
15:19:18 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
15:19:18 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:19:18 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
15:19:18 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
15:19:18 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:19:18 INFO - u'jsshell-win32.zip'],
15:19:18 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:19:18 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
15:19:18 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
15:19:18 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:19:18 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
15:19:18 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:19:18 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
15:19:18 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:19:18 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
15:19:18 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:19:18 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
15:19:18 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
15:19:18 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.common.tests.zip
15:19:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
15:19:18 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.common.tests.zip into memory
15:19:19 INFO - Http code: 200
15:19:19 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront)
15:19:19 INFO - x-amz-cf-id: 6Snh4Xj_HlsU6cXGKEDYSQu4k53vXFVVLIzXjNx_sye437NPjqPLUg==
15:19:19 INFO - x-amz-replication-status: COMPLETED
15:19:19 INFO - x-amz-version-id: mMAfgXZ4egHCJATlD3IBF6oXxBz.hLJW
15:19:20 INFO - Expected file size: 17167667
15:19:20 INFO - Obtained file size: 17167667
15:19:42 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos
15:19:42 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.common.tests.zip
15:19:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
15:19:42 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.common.tests.zip into memory
15:19:42 INFO - Http code: 200
15:19:42 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront)
15:19:42 INFO - x-amz-cf-id: h1WEIqh9hc231t1b6I-hZzV1MfFCmmnap9EKuQwKvqE41Q7cHBbOMw==
15:19:42 INFO - x-amz-replication-status: COMPLETED
15:19:42 INFO - x-amz-version-id: mMAfgXZ4egHCJATlD3IBF6oXxBz.hLJW
15:19:43 INFO - Expected file size: 17167667
15:19:43 INFO - Obtained file size: 17167667
15:19:52 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.talos.tests.zip
15:19:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
15:19:52 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
15:19:52 INFO - Http code: 200
15:19:52 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront)
15:19:52 INFO - x-amz-cf-id: uR4b8GhLTHGyG3TfXUIaJkfc0lvzq8QI3jztj_Dzn0PRHWrAfuv64Q==
15:19:52 INFO - x-amz-replication-status: COMPLETED
15:19:52 INFO - x-amz-version-id: l5bEyfMODBPn4eVr3AVRP.FUd_a2Nphd
15:19:53 INFO - Expected file size: 14409028
15:19:53 INFO - Obtained file size: 14409028
15:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:19:54 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.zip
15:19:54 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
15:19:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
15:19:56 INFO - Downloaded 54295363 bytes.
15:19:56 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.zip
15:19:56 INFO - mkdir: C:\slave\test\properties
15:19:56 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
15:19:56 INFO - Writing to file C:\slave\test\properties\build_url
15:19:56 INFO - Contents:
15:19:56 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.zip
15:19:57 INFO - Running post-action listener: _resource_record_post_action
15:19:57 INFO - Running post-action listener: set_extra_try_arguments
15:19:57 INFO - [mozharness: 2016-10-24 22:19:57.245000Z] Finished download-and-extract step (success)
15:19:57 INFO - [mozharness: 2016-10-24 22:19:57.245000Z] Running populate-webroot step.
15:19:57 INFO - Running pre-action listener: _resource_record_pre_action
15:19:57 INFO - Running main action method: populate_webroot
15:19:57 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
15:19:57 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
15:19:57 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
15:19:57 INFO - u'--tppagecycles',
15:19:57 INFO - u'7'],
15:19:57 INFO - u'tests': [u'tsvgm']}},
15:19:57 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
15:19:57 INFO - u'tests': [u'tresize', u'tcanvasmark']},
15:19:57 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
15:19:57 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
15:19:57 INFO - u'tests': [u'dromaeo_css', u'kraken']},
15:19:57 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
15:19:57 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:19:57 INFO - u'talos_options': [u'--disable-e10s'],
15:19:57 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
15:19:57 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:19:57 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
15:19:57 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:19:57 INFO - u'talos_options': [u'--disable-e10s'],
15:19:57 INFO - u'tests': [u'damp', u'tps']},
15:19:57 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:19:57 INFO - u'tests': [u'damp', u'tps']},
15:19:57 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
15:19:57 INFO - u'tests': [u'dromaeo_dom']},
15:19:57 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
15:19:57 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
15:19:57 INFO - u'tests': [u'basic_compositor_video']},
15:19:57 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
15:19:57 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
15:19:57 INFO - u'tests': [u'a11yr',
15:19:57 INFO - u'ts_paint',
15:19:57 INFO - u'tpaint',
15:19:57 INFO - u'sessionrestore',
15:19:57 INFO - u'sessionrestore_no_auto_restore',
15:19:57 INFO - u'tabpaint']},
15:19:57 INFO - u'other-e10s': {u'tests': [u'a11yr',
15:19:57 INFO - u'ts_paint',
15:19:57 INFO - u'tpaint',
15:19:57 INFO - u'sessionrestore',
15:19:57 INFO - u'sessionrestore_no_auto_restore',
15:19:57 INFO - u'tabpaint']},
15:19:57 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
15:19:57 INFO - u'ts_paint',
15:19:57 INFO - u'tpaint',
15:19:57 INFO - u'sessionrestore',
15:19:57 INFO - u'sessionrestore_no_auto_restore',
15:19:57 INFO - u'tabpaint']},
15:19:57 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
15:19:57 INFO - u'ts_paint',
15:19:57 INFO - u'tpaint',
15:19:57 INFO - u'sessionrestore',
15:19:57 INFO - u'sessionrestore_no_auto_restore',
15:19:57 INFO - u'tabpaint']},
15:19:57 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
15:19:57 INFO - u'tests': [u'a11yr',
15:19:57 INFO - u'ts_paint',
15:19:57 INFO - u'tpaint',
15:19:57 INFO - u'sessionrestore',
15:19:57 INFO - u'sessionrestore_no_auto_restore',
15:19:57 INFO - u'tabpaint']},
15:19:57 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
15:19:57 INFO - u'tests': [u'a11yr',
15:19:57 INFO - u'ts_paint',
15:19:57 INFO - u'tpaint',
15:19:57 INFO - u'sessionrestore',
15:19:57 INFO - u'sessionrestore_no_auto_restore',
15:19:57 INFO - u'tabpaint']},
15:19:57 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
15:19:57 INFO - u'tests': [u'tsvgx',
15:19:57 INFO - u'tsvgr_opacity',
15:19:57 INFO - u'tart',
15:19:57 INFO - u'tscrollx',
15:19:57 INFO - u'cart']},
15:19:57 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
15:19:57 INFO - u'tsvgr_opacity',
15:19:57 INFO - u'tart',
15:19:57 INFO - u'tscrollx',
15:19:57 INFO - u'cart']},
15:19:57 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:19:57 INFO - u'talos_options': [u'--disable-e10s'],
15:19:57 INFO - u'tests': [u'tp5o']},
15:19:57 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:19:57 INFO - u'tests': [u'tp5o']},
15:19:57 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:19:57 INFO - u'talos_options': [u'--disable-e10s',
15:19:57 INFO - u'--xperf_path',
15:19:57 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
15:19:57 INFO - u'tests': [u'tp5n']},
15:19:57 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:19:57 INFO - u'talos_options': [u'--xperf_path',
15:19:57 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
15:19:57 INFO - u'tests': [u'tp5n']}},
15:19:57 INFO - u'talos.zip': {u'path': u'',
15:19:57 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
15:19:57 INFO - Running post-action listener: _resource_record_post_action
15:19:57 INFO - [mozharness: 2016-10-24 22:19:57.327000Z] Finished populate-webroot step (success)
15:19:57 INFO - [mozharness: 2016-10-24 22:19:57.327000Z] Running create-virtualenv step.
15:19:57 INFO - Running pre-action listener: _resource_record_pre_action
15:19:57 INFO - Running main action method: create_virtualenv
15:19:57 INFO - Creating virtualenv C:\slave\test\build/venv
15:19:57 INFO - mkdir: C:\slave\test\build\venv\Scripts
15:19:57 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
15:19:57 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'!
15:19:57 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build/venv'] in C:\slave\test\build
15:19:57 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
15:20:08 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
15:20:12 INFO - Installing distribute......................................................................................................................................................................................done.
15:20:13 INFO - Return code: 0
15:20:13 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
15:20:13 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
15:20:13 INFO - Reading from file tmpfile_stdout
15:20:13 INFO - Output received:
15:20:13 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
15:20:13 INFO - Using _rmtree_windows ...
15:20:13 INFO - Using _rmtree_windows ...
15:20:13 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
15:20: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:20: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:20:13 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--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 0x01931640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E2E90>, '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 0x013FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
15:20:13 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--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\build
15:20:13 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --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
15:20:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:20:13 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:20:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:20:13 INFO - 'COMPUTERNAME': 'T-W732-IX-100',
15:20:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:20:13 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:20:13 INFO - 'DCLOCATION': 'SCL3',
15:20:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:20:13 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:20:13 INFO - 'HOMEDRIVE': 'C:',
15:20:13 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:20:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:20:13 INFO - 'KTS_VERSION': '1.19c',
15:20:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:20:13 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100',
15:20:13 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:20:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:20:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:20:13 INFO - 'MOZ_NO_REMOTE': '1',
15:20:13 INFO - 'NO_EM_RESTART': '1',
15:20:13 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:20:13 INFO - 'OS': 'Windows_NT',
15:20:13 INFO - 'OURDRIVE': 'C:',
15:20: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;',
15:20:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:20:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:20:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:20:13 INFO - 'PROCESSOR_LEVEL': '6',
15:20:13 INFO - 'PROCESSOR_REVISION': '1e05',
15:20:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:20:13 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:20:13 INFO - 'PROMPT': '$P$G',
15:20:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:20:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:20:13 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:20:13 INFO - 'PWD': 'C:\\slave\\test',
15:20:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:20:13 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:20:13 INFO - 'SYSTEMDRIVE': 'C:',
15:20:13 INFO - 'SYSTEMROOT': 'C:\\windows',
15:20:13 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:13 INFO - 'TEST1': 'testie',
15:20:13 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:13 INFO - 'USERDOMAIN': 'T-W732-IX-100',
15:20:13 INFO - 'USERNAME': 'cltbld',
15:20:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:20:13 INFO - 'WINDIR': 'C:\\windows',
15:20:13 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:20:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:20:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:20:14 INFO - Ignoring indexes: http://pypi.python.org/simple/
15:20:14 INFO - Downloading/unpacking pip>=1.5
15:20:18 INFO - Running setup.py egg_info for package pip
15:20:18 INFO - warning: no files found matching 'pip\cacert.pem'
15:20:18 INFO - warning: no files found matching '*.html' under directory 'docs'
15:20:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
15:20:18 INFO - no previously-included directories found matching 'docs\_build\_sources'
15:20:18 INFO - Installing collected packages: pip
15:20:18 INFO - Found existing installation: pip 0.8.2
15:20:18 INFO - Uninstalling pip:
15:20:18 INFO - Successfully uninstalled pip
15:20:18 INFO - Running setup.py install for pip
15:20:18 INFO - warning: no files found matching 'pip\cacert.pem'
15:20:18 INFO - warning: no files found matching '*.html' under directory 'docs'
15:20:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
15:20:18 INFO - no previously-included directories found matching 'docs\_build\_sources'
15:20:18 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
15:20:18 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
15:20:18 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
15:20:18 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
15:20:18 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
15:20:18 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
15:20:18 INFO - Successfully installed pip
15:20:18 INFO - Cleaning up...
15:20:18 INFO - Return code: 0
15:20:18 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
15:20: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:20: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:20:18 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01931640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E2E90>, '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 0x013FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
15:20:18 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build
15:20:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1
15:20:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:20:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:20:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:20:18 INFO - 'COMPUTERNAME': 'T-W732-IX-100',
15:20:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:20:18 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:20:18 INFO - 'DCLOCATION': 'SCL3',
15:20:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:20:18 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:20:18 INFO - 'HOMEDRIVE': 'C:',
15:20:18 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:20:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:20:18 INFO - 'KTS_VERSION': '1.19c',
15:20:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:20:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100',
15:20:18 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:20:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:20:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:20:18 INFO - 'MOZ_NO_REMOTE': '1',
15:20:18 INFO - 'NO_EM_RESTART': '1',
15:20:18 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:20:18 INFO - 'OS': 'Windows_NT',
15:20:18 INFO - 'OURDRIVE': 'C:',
15:20:18 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
15:20:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:20:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:20:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:20:18 INFO - 'PROCESSOR_LEVEL': '6',
15:20:18 INFO - 'PROCESSOR_REVISION': '1e05',
15:20:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:20:18 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:20:18 INFO - 'PROMPT': '$P$G',
15:20:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:20:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:20:18 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:20:18 INFO - 'PWD': 'C:\\slave\\test',
15:20:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:20:18 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:20:18 INFO - 'SYSTEMDRIVE': 'C:',
15:20:18 INFO - 'SYSTEMROOT': 'C:\\windows',
15:20:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:18 INFO - 'TEST1': 'testie',
15:20:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:18 INFO - 'USERDOMAIN': 'T-W732-IX-100',
15:20:18 INFO - 'USERNAME': 'cltbld',
15:20:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:20:18 INFO - 'WINDIR': 'C:\\windows',
15:20:18 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:20:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:20:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:20:20 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:20:20 INFO - Downloading/unpacking psutil>=3.1.1
15:20:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:20:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:20:21 INFO - Installing collected packages: psutil
15:20:21 INFO - Successfully installed psutil
15:20:21 INFO - Cleaning up...
15:20:21 INFO - Return code: 0
15:20:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
15:20:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:20:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:20:21 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01931640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E2E90>, '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 0x013FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
15:20:21 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build
15:20:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
15:20:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:20:21 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:20:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:20:21 INFO - 'COMPUTERNAME': 'T-W732-IX-100',
15:20:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:20:21 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:20:21 INFO - 'DCLOCATION': 'SCL3',
15:20:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:20:21 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:20:21 INFO - 'HOMEDRIVE': 'C:',
15:20:21 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:20:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:20:21 INFO - 'KTS_VERSION': '1.19c',
15:20:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:20:21 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100',
15:20:21 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:20:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:20:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:20:21 INFO - 'MOZ_NO_REMOTE': '1',
15:20:21 INFO - 'NO_EM_RESTART': '1',
15:20:21 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:20:21 INFO - 'OS': 'Windows_NT',
15:20:21 INFO - 'OURDRIVE': 'C:',
15:20:21 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;',
15:20:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:20:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:20:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:20:21 INFO - 'PROCESSOR_LEVEL': '6',
15:20:21 INFO - 'PROCESSOR_REVISION': '1e05',
15:20:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:20:21 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:20:21 INFO - 'PROMPT': '$P$G',
15:20:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:20:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:20:21 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:20:21 INFO - 'PWD': 'C:\\slave\\test',
15:20:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:20:21 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:20:21 INFO - 'SYSTEMDRIVE': 'C:',
15:20:21 INFO - 'SYSTEMROOT': 'C:\\windows',
15:20:21 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:21 INFO - 'TEST1': 'testie',
15:20:21 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:21 INFO - 'USERDOMAIN': 'T-W732-IX-100',
15:20:21 INFO - 'USERNAME': 'cltbld',
15:20:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:20:21 INFO - 'WINDIR': 'C:\\windows',
15:20:21 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:20:21 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:20:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:20:21 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:20:21 INFO - Downloading/unpacking mozsystemmonitor==0.3
15:20:21 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
15:20:21 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
15:20:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz
15:20:22 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
15:20:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3)
15:20:22 INFO - Installing collected packages: mozsystemmonitor
15:20:22 INFO - Running setup.py install for mozsystemmonitor
15:20:22 INFO - Successfully installed mozsystemmonitor
15:20:22 INFO - Cleaning up...
15:20:23 INFO - Return code: 0
15:20:23 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
15:20:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:20:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:20:23 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01931640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E2E90>, '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 0x013FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
15:20:23 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build
15:20:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1
15:20:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:20:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:20:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:20:23 INFO - 'COMPUTERNAME': 'T-W732-IX-100',
15:20:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:20:23 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:20:23 INFO - 'DCLOCATION': 'SCL3',
15:20:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:20:23 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:20:23 INFO - 'HOMEDRIVE': 'C:',
15:20:23 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:20:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:20:23 INFO - 'KTS_VERSION': '1.19c',
15:20:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:20:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100',
15:20:23 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:20:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:20:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:20:23 INFO - 'MOZ_NO_REMOTE': '1',
15:20:23 INFO - 'NO_EM_RESTART': '1',
15:20:23 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:20:23 INFO - 'OS': 'Windows_NT',
15:20:23 INFO - 'OURDRIVE': 'C:',
15:20:23 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
15:20:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:20:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:20:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:20:23 INFO - 'PROCESSOR_LEVEL': '6',
15:20:23 INFO - 'PROCESSOR_REVISION': '1e05',
15:20:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:20:23 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:20:23 INFO - 'PROMPT': '$P$G',
15:20:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:20:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:20:23 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:20:23 INFO - 'PWD': 'C:\\slave\\test',
15:20:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:20:23 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:20:23 INFO - 'SYSTEMDRIVE': 'C:',
15:20:23 INFO - 'SYSTEMROOT': 'C:\\windows',
15:20:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:23 INFO - 'TEST1': 'testie',
15:20:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:23 INFO - 'USERDOMAIN': 'T-W732-IX-100',
15:20:23 INFO - 'USERNAME': 'cltbld',
15:20:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:20:23 INFO - 'WINDIR': 'C:\\windows',
15:20:23 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:20:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:20:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:20:23 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:20:23 INFO - Downloading/unpacking jsonschema==2.5.1
15:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:20:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:20:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
15:20:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
15:20:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:20:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:20:25 INFO - Downloading functools32-3.2.3-2.tar.gz
15:20:26 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
15:20:26 INFO - warning: no files found matching '*.txt'
15:20:26 INFO - no previously-included directories found matching 'build'
15:20:26 INFO - no previously-included directories found matching 'dist'
15:20:26 INFO - no previously-included directories found matching '.git*'
15:20:26 INFO - Installing collected packages: jsonschema, functools32
15:20:26 INFO - Running setup.py install for functools32
15:20:26 INFO - warning: no files found matching '*.txt'
15:20:26 INFO - no previously-included directories found matching 'build'
15:20:26 INFO - no previously-included directories found matching 'dist'
15:20:26 INFO - no previously-included directories found matching '.git*'
15:20:26 INFO - Successfully installed jsonschema functools32
15:20:26 INFO - Cleaning up...
15:20:26 INFO - Return code: 0
15:20:26 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
15:20:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:20:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:20:26 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01931640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E2E90>, '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 0x013FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
15:20:26 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
15:20:26 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
15:20:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:20:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:20:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:20:26 INFO - 'COMPUTERNAME': 'T-W732-IX-100',
15:20:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:20:26 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:20:26 INFO - 'DCLOCATION': 'SCL3',
15:20:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:20:26 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:20:26 INFO - 'HOMEDRIVE': 'C:',
15:20:26 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:20:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:20:26 INFO - 'KTS_VERSION': '1.19c',
15:20:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:20:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100',
15:20:26 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:20:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:20:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:20:26 INFO - 'MOZ_NO_REMOTE': '1',
15:20:26 INFO - 'NO_EM_RESTART': '1',
15:20:26 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:20:26 INFO - 'OS': 'Windows_NT',
15:20:26 INFO - 'OURDRIVE': 'C:',
15:20:26 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
15:20:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:20:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:20:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:20:26 INFO - 'PROCESSOR_LEVEL': '6',
15:20:26 INFO - 'PROCESSOR_REVISION': '1e05',
15:20:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:20:26 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:20:26 INFO - 'PROMPT': '$P$G',
15:20:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:20:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:20:26 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:20:26 INFO - 'PWD': 'C:\\slave\\test',
15:20:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:20:26 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:20:26 INFO - 'SYSTEMDRIVE': 'C:',
15:20:26 INFO - 'SYSTEMROOT': 'C:\\windows',
15:20:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:26 INFO - 'TEST1': 'testie',
15:20:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:26 INFO - 'USERDOMAIN': 'T-W732-IX-100',
15:20:26 INFO - 'USERNAME': 'cltbld',
15:20:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:20:26 INFO - 'WINDIR': 'C:\\windows',
15:20:26 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:20:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:20:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:20:27 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:20:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
15:20:27 INFO - Cleaning up...
15:20:27 INFO - Return code: 0
15:20:27 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
15:20:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:20:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:20:27 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--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 0x01931640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E2E90>, '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 0x013FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
15:20:27 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--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\build
15:20:27 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --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
15:20:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:20:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:20:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:20:27 INFO - 'COMPUTERNAME': 'T-W732-IX-100',
15:20:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:20:27 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:20:27 INFO - 'DCLOCATION': 'SCL3',
15:20:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:20:27 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:20:27 INFO - 'HOMEDRIVE': 'C:',
15:20:27 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:20:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:20:27 INFO - 'KTS_VERSION': '1.19c',
15:20:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:20:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100',
15:20:27 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:20:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:20:27 INFO - 'MOZ_NO_REMOTE': '1',
15:20:27 INFO - 'NO_EM_RESTART': '1',
15:20:27 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:20:27 INFO - 'OS': 'Windows_NT',
15:20:27 INFO - 'OURDRIVE': 'C:',
15:20:27 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;',
15:20:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:20:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:20:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:20:27 INFO - 'PROCESSOR_LEVEL': '6',
15:20:27 INFO - 'PROCESSOR_REVISION': '1e05',
15:20:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:20:27 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:20:27 INFO - 'PROMPT': '$P$G',
15:20:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:20:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:20:27 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:20:27 INFO - 'PWD': 'C:\\slave\\test',
15:20:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:20:27 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:20:27 INFO - 'SYSTEMDRIVE': 'C:',
15:20:27 INFO - 'SYSTEMROOT': 'C:\\windows',
15:20:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:27 INFO - 'TEST1': 'testie',
15:20:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:27 INFO - 'USERDOMAIN': 'T-W732-IX-100',
15:20:27 INFO - 'USERNAME': 'cltbld',
15:20:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:20:27 INFO - 'WINDIR': 'C:\\windows',
15:20:27 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:20:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:20:27 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:20:27 INFO - Downloading/unpacking blobuploader==1.2.4
15:20:27 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
15:20:27 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
15:20:28 INFO - Downloading blobuploader-1.2.4.tar.gz
15:20:28 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
15:20:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
15:20: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
15:20: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
15:20:28 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
15:20:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
15:20: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
15:20: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
15:20:29 INFO - Downloading docopt-0.6.1.tar.gz
15:20:29 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
15:20:29 INFO - Installing collected packages: blobuploader, requests, docopt
15:20:29 INFO - Running setup.py install for blobuploader
15:20:29 INFO - Running setup.py install for requests
15:20:30 INFO - Running setup.py install for docopt
15:20:30 INFO - Successfully installed blobuploader requests docopt
15:20:30 INFO - Cleaning up...
15:20:31 INFO - Return code: 0
15:20:31 INFO - Installing None into virtualenv C:\slave\test\build/venv
15:20:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:20:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:20:31 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\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 0x01931640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E2E90>, '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 0x013FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
15:20:31 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\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\build\tests\config
15:20:31 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test\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
15:20:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:20:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:20:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:20:31 INFO - 'COMPUTERNAME': 'T-W732-IX-100',
15:20:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:20:31 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:20:31 INFO - 'DCLOCATION': 'SCL3',
15:20:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:20:31 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:20:31 INFO - 'HOMEDRIVE': 'C:',
15:20:31 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:20:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:20:31 INFO - 'KTS_VERSION': '1.19c',
15:20:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:20:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100',
15:20:31 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:20:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:20:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:20:31 INFO - 'MOZ_NO_REMOTE': '1',
15:20:31 INFO - 'NO_EM_RESTART': '1',
15:20:31 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:20:31 INFO - 'OS': 'Windows_NT',
15:20:31 INFO - 'OURDRIVE': 'C:',
15:20:31 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;',
15:20:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:20:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:20:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:20:31 INFO - 'PROCESSOR_LEVEL': '6',
15:20:31 INFO - 'PROCESSOR_REVISION': '1e05',
15:20:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:20:31 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:20:31 INFO - 'PROMPT': '$P$G',
15:20:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:20:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:20:31 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:20:31 INFO - 'PWD': 'C:\\slave\\test',
15:20:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:20:31 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:20:31 INFO - 'SYSTEMDRIVE': 'C:',
15:20:31 INFO - 'SYSTEMROOT': 'C:\\windows',
15:20:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:31 INFO - 'TEST1': 'testie',
15:20:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:31 INFO - 'USERDOMAIN': 'T-W732-IX-100',
15:20:31 INFO - 'USERNAME': 'cltbld',
15:20:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:20:31 INFO - 'WINDIR': 'C:\\windows',
15:20:31 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:20:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:20:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:20:31 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:20:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
15:20:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fo0kfg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
15:20:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
15:20:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lauwbw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
15:20:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
15:20:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2smpl8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
15:20:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
15:20:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yxgr1w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
15:20:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
15:20:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0i5uma-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
15:20:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
15:20:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1jzs2q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
15:20:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
15:20:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kkar0d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
15:20:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
15:20:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i5cxti-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
15:20:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
15:20:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9xn9n9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
15:20:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
15:20:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rzv2vz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
15:20:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
15:20:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eyoqsa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
15:20:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
15:20:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b3nvdx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
15:20:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
15:20:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ft6ef7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
15:20:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
15:20:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mndfpm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
15:20:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
15:20:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d9cmve-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
15:20:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
15:20:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cidayx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
15:20:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
15:20:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-m8ntnf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
15:20:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
15:20:34 INFO - Running setup.py install for manifestparser
15:20:34 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
15:20:34 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
15:20:35 INFO - Running setup.py install for mozcrash
15:20:35 INFO - Running setup.py install for mozdebug
15:20:36 INFO - Running setup.py install for mozdevice
15:20:36 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
15:20:36 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
15:20:36 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
15:20:36 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
15:20:36 INFO - Running setup.py install for mozfile
15:20:36 INFO - Running setup.py install for mozhttpd
15:20:37 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
15:20:37 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
15:20:37 INFO - Running setup.py install for mozinfo
15:20:37 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
15:20:37 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
15:20:37 INFO - Running setup.py install for mozInstall
15:20:37 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
15:20:37 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
15:20:37 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
15:20:37 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
15:20:37 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
15:20:37 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
15:20:37 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
15:20:37 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
15:20:37 INFO - Running setup.py install for mozleak
15:20:38 INFO - Running setup.py install for mozlog
15:20:38 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
15:20:38 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
15:20:39 INFO - Running setup.py install for moznetwork
15:20:39 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
15:20:39 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
15:20:39 INFO - Running setup.py install for mozprocess
15:20:40 INFO - Running setup.py install for mozprofile
15:20:40 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
15:20:40 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
15:20:40 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
15:20:40 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
15:20:40 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
15:20:40 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
15:20:40 INFO - Running setup.py install for mozrunner
15:20:40 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
15:20:40 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
15:20:41 INFO - Running setup.py install for mozscreenshot
15:20:41 INFO - Running setup.py install for moztest
15:20:41 INFO - Running setup.py install for mozversion
15:20:42 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
15:20:42 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
15:20:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
15:20:42 INFO - Cleaning up...
15:20:42 INFO - Return code: 0
15:20:42 INFO - Installing None into virtualenv C:\slave\test\build/venv
15:20:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:20:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:20:42 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\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 0x01931640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E2E90>, '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 0x013FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
15:20:42 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\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\build\tests\config
15:20:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\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
15:20:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:20:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:20:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:20:42 INFO - 'COMPUTERNAME': 'T-W732-IX-100',
15:20:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:20:42 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:20:42 INFO - 'DCLOCATION': 'SCL3',
15:20:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:20:42 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:20:42 INFO - 'HOMEDRIVE': 'C:',
15:20:42 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:20:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:20:42 INFO - 'KTS_VERSION': '1.19c',
15:20:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:20:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100',
15:20:42 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:20:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:20:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:20:42 INFO - 'MOZ_NO_REMOTE': '1',
15:20:42 INFO - 'NO_EM_RESTART': '1',
15:20:42 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:20:42 INFO - 'OS': 'Windows_NT',
15:20:42 INFO - 'OURDRIVE': 'C:',
15:20:42 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
15:20:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:20:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:20:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:20:42 INFO - 'PROCESSOR_LEVEL': '6',
15:20:42 INFO - 'PROCESSOR_REVISION': '1e05',
15:20:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:20:42 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:20:42 INFO - 'PROMPT': '$P$G',
15:20:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:20:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:20:42 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:20:42 INFO - 'PWD': 'C:\\slave\\test',
15:20:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:20:42 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:20:42 INFO - 'SYSTEMDRIVE': 'C:',
15:20:42 INFO - 'SYSTEMROOT': 'C:\\windows',
15:20:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:42 INFO - 'TEST1': 'testie',
15:20:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:42 INFO - 'USERDOMAIN': 'T-W732-IX-100',
15:20:42 INFO - 'USERNAME': 'cltbld',
15:20:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:20:42 INFO - 'WINDIR': 'C:\\windows',
15:20:42 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:20:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:20:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:20:42 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:20:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
15:20:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7krcvi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
15:20:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1))
15:20:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
15:20:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xgktyi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
15:20:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
15:20:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
15:20:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bgcfil-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
15:20:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
15:20:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
15:20:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gfrele-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
15:20:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
15:20:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
15:20:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fpvurz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
15:20:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5))
15:20:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
15:20:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wy13oz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
15:20:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6))
15:20:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
15:20:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-asbsl1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
15:20:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7))
15:20:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
15:20:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rd892k-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
15:20:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8))
15:20:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
15:20:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e4m_jl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
15:20:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9))
15:20:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
15:20:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-biv_yp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
15:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
15:20:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
15:20:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a9pg4h-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
15:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11))
15:20:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
15:20:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fitnkc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
15:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12))
15:20:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
15:20:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-trdbje-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
15:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13))
15:20:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
15:20:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gcq7zd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
15:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14))
15:20:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
15:20:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ojz8fm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
15:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15))
15:20:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
15:20:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hlppgz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
15:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16))
15:20:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
15:20:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-znrlj1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
15:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17))
15:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
15:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
15:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
15:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
15:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
15:20:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
15:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:20:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:20:45 INFO - Downloading blessings-1.6.tar.gz
15:20:45 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
15:20:46 INFO - Installing collected packages: blessings
15:20:46 INFO - Running setup.py install for blessings
15:20:46 INFO - Successfully installed blessings
15:20:46 INFO - Cleaning up...
15:20:49 INFO - Return code: 0
15:20:49 INFO - Done creating virtualenv C:\slave\test\build/venv.
15:20:49 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
15:20:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
15:20:49 INFO - Reading from file tmpfile_stdout
15:20:49 INFO - Using _rmtree_windows ...
15:20:49 INFO - Using _rmtree_windows ...
15:20:49 INFO - Current package versions:
15:20:49 INFO - blessings == 1.6
15:20:49 INFO - blobuploader == 1.2.4
15:20:49 INFO - docopt == 0.6.1
15:20:49 INFO - functools32 == 3.2.3-2
15:20:49 INFO - jsonschema == 2.5.1
15:20:49 INFO - manifestparser == 1.1
15:20:49 INFO - mozInstall == 1.12
15:20:49 INFO - mozcrash == 0.17
15:20:49 INFO - mozdebug == 0.1
15:20:49 INFO - mozdevice == 0.48
15:20:49 INFO - mozfile == 1.2
15:20:49 INFO - mozhttpd == 0.7
15:20:49 INFO - mozinfo == 0.9
15:20:49 INFO - mozleak == 0.1
15:20:49 INFO - mozlog == 3.4
15:20:49 INFO - moznetwork == 0.27
15:20:49 INFO - mozprocess == 0.23
15:20:49 INFO - mozprofile == 0.28
15:20:49 INFO - mozrunner == 6.12
15:20:49 INFO - mozscreenshot == 0.1
15:20:49 INFO - mozsystemmonitor == 0.3
15:20:49 INFO - moztest == 0.7
15:20:49 INFO - mozversion == 1.4
15:20:49 INFO - psutil == 3.1.1
15:20:49 INFO - requests == 1.2.3
15:20:49 INFO - Installing None into virtualenv C:\slave\test\build/venv
15:20:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:20:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:20:49 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\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 0x01931640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E2E90>, '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 0x013FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
15:20:49 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\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\build\tests\talos
15:20:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\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
15:20:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:20:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:20:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:20:49 INFO - 'COMPUTERNAME': 'T-W732-IX-100',
15:20:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:20:49 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:20:49 INFO - 'DCLOCATION': 'SCL3',
15:20:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:20:49 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:20:49 INFO - 'HOMEDRIVE': 'C:',
15:20:49 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:20:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:20:49 INFO - 'KTS_VERSION': '1.19c',
15:20:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:20:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100',
15:20:49 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:20:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:20:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:20:49 INFO - 'MOZ_NO_REMOTE': '1',
15:20:49 INFO - 'NO_EM_RESTART': '1',
15:20:49 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:20:49 INFO - 'OS': 'Windows_NT',
15:20:49 INFO - 'OURDRIVE': 'C:',
15:20:49 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
15:20:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:20:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:20:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:20:49 INFO - 'PROCESSOR_LEVEL': '6',
15:20:49 INFO - 'PROCESSOR_REVISION': '1e05',
15:20:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:20:49 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:20:49 INFO - 'PROMPT': '$P$G',
15:20:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:20:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:20:49 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:20:49 INFO - 'PWD': 'C:\\slave\\test',
15:20:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:20:49 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:20:49 INFO - 'SYSTEMDRIVE': 'C:',
15:20:49 INFO - 'SYSTEMROOT': 'C:\\windows',
15:20:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:49 INFO - 'TEST1': 'testie',
15:20:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:49 INFO - 'USERDOMAIN': 'T-W732-IX-100',
15:20:49 INFO - 'USERNAME': 'cltbld',
15:20:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:20:49 INFO - 'WINDIR': 'C:\\windows',
15:20:49 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:20:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:20:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:20:49 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1))
15:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2))
15:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3))
15:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4))
15:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5))
15:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6))
15:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7))
15:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8))
15:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 9))
15:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test\build\tests\talos\requirements.txt (line 1))
15:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 4))
15:20:49 INFO - Cleaning up...
15:20:49 INFO - Return code: 0
15:20:49 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
15:20:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:20:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:20:49 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01931640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E2E90>, '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 0x013FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
15:20:49 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build
15:20:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
15:20:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:20:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:20:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:20:49 INFO - 'COMPUTERNAME': 'T-W732-IX-100',
15:20:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:20:49 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:20:49 INFO - 'DCLOCATION': 'SCL3',
15:20:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:20:49 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:20:49 INFO - 'HOMEDRIVE': 'C:',
15:20:49 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:20:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:20:49 INFO - 'KTS_VERSION': '1.19c',
15:20:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:20:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100',
15:20:49 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:20:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:20:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:20:49 INFO - 'MOZ_NO_REMOTE': '1',
15:20:49 INFO - 'NO_EM_RESTART': '1',
15:20:49 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:20:49 INFO - 'OS': 'Windows_NT',
15:20:49 INFO - 'OURDRIVE': 'C:',
15:20:49 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
15:20:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:20:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:20:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:20:49 INFO - 'PROCESSOR_LEVEL': '6',
15:20:49 INFO - 'PROCESSOR_REVISION': '1e05',
15:20:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:20:49 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:20:49 INFO - 'PROMPT': '$P$G',
15:20:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:20:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:20:49 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:20:49 INFO - 'PWD': 'C:\\slave\\test',
15:20:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:20:49 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:20:49 INFO - 'SYSTEMDRIVE': 'C:',
15:20:49 INFO - 'SYSTEMROOT': 'C:\\windows',
15:20:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:49 INFO - 'TEST1': 'testie',
15:20:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:49 INFO - 'USERDOMAIN': 'T-W732-IX-100',
15:20:49 INFO - 'USERNAME': 'cltbld',
15:20:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:20:49 INFO - 'WINDIR': 'C:\\windows',
15:20:49 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:20:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:20:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:20:50 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:20:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
15:20:50 INFO - Cleaning up...
15:20:50 INFO - Return code: 0
15:20:50 INFO - Running post-action listener: _resource_record_post_action
15:20:50 INFO - Running post-action listener: _start_resource_monitoring
15:20:50 INFO - Starting resource monitoring.
15:20:50 INFO - [mozharness: 2016-10-24 22:20:50.349000Z] Finished create-virtualenv step (success)
15:20:50 INFO - [mozharness: 2016-10-24 22:20:50.349000Z] Running install step.
15:20:50 INFO - Running pre-action listener: _resource_record_pre_action
15:20:50 INFO - Running main action method: install
15:20:50 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
15:20:50 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
15:20:50 INFO - Reading from file tmpfile_stdout
15:20:50 INFO - Using _rmtree_windows ...
15:20:50 INFO - Using _rmtree_windows ...
15:20:50 INFO - Detecting whether we're running mozinstall >=1.0...
15:20:50 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
15:20:50 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
15:20:50 INFO - Reading from file tmpfile_stdout
15:20:50 INFO - Output received:
15:20:50 INFO - Usage: mozinstall-script.py [options] installer
15:20:50 INFO - Options:
15:20:50 INFO - -h, --help show this help message and exit
15:20:50 INFO - -d DEST, --destination=DEST
15:20:50 INFO - Directory to install application into. [default:
15:20:50 INFO - "C:\slave\test"]
15:20:50 INFO - --app=APP Application being installed. [default: firefox]
15:20:50 INFO - Using _rmtree_windows ...
15:20:50 INFO - Using _rmtree_windows ...
15:20:50 INFO - mkdir: C:\slave\test\build\application
15:20:50 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
15:20:50 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application
15:20:52 INFO - Reading from file tmpfile_stdout
15:20:52 INFO - Output received:
15:20:52 INFO - C:\slave\test\build\application\firefox\firefox.exe
15:20:52 INFO - Using _rmtree_windows ...
15:20:52 INFO - Using _rmtree_windows ...
15:20:52 INFO - Running post-action listener: _resource_record_post_action
15:20:52 INFO - [mozharness: 2016-10-24 22:20:52.582000Z] Finished install step (success)
15:20:52 INFO - [mozharness: 2016-10-24 22:20:52.582000Z] Running run-tests step.
15:20:52 INFO - Running pre-action listener: _resource_record_pre_action
15:20:52 INFO - Running main action method: run_tests
15:20:52 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
15:20:52 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
15:20:52 INFO - Python 2.7.3
15:20:52 INFO - Return code: 0
15:20:52 INFO - grabbing minidump binary from tooltool
15:20:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:20:52 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\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E2E90>, '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 0x013FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA4520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
15:20:52 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\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build
15:20:52 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\build\tests\config/tooltool-manifests/win32/releng.manifest -o
15:20:52 INFO - Calling ['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\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] with output_timeout 600
15:20:52 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
15:20:52 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpilg3f5
15:20:53 INFO - INFO - File integrity verified, renaming tmpilg3f5 to win32-minidump_stackwalk.exe
15:20:53 INFO - Return code: 0
15:20:53 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
15:20:53 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
15:20:53 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
15:20:53 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
15:20:53 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
15:20:53 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-100', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_raw.log'] in C:\slave\test\build
15:20:53 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-100 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_raw.log
15:20:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:20:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:20:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:20:53 INFO - 'COMPUTERNAME': 'T-W732-IX-100',
15:20:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:20:53 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:20:53 INFO - 'DCLOCATION': 'SCL3',
15:20:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:20:53 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:20:53 INFO - 'HOMEDRIVE': 'C:',
15:20:53 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:20:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:20:53 INFO - 'KTS_VERSION': '1.19c',
15:20:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:20:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100',
15:20:53 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
15:20:53 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
15:20:53 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:20:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:20:53 INFO - 'MOZ_NO_REMOTE': '1',
15:20:53 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
15:20:53 INFO - 'NO_EM_RESTART': '1',
15:20:53 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:20:53 INFO - 'OS': 'Windows_NT',
15:20:53 INFO - 'OURDRIVE': 'C:',
15:20:53 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;',
15:20:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:20:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:20:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:20:53 INFO - 'PROCESSOR_LEVEL': '6',
15:20:53 INFO - 'PROCESSOR_REVISION': '1e05',
15:20:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:20:53 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:20:53 INFO - 'PROMPT': '$P$G',
15:20:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:20:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:20:53 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:20:53 INFO - 'PWD': 'C:\\slave\\test',
15:20:53 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
15:20:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:20:53 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:20:53 INFO - 'SYSTEMDRIVE': 'C:',
15:20:53 INFO - 'SYSTEMROOT': 'C:\\windows',
15:20:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:53 INFO - 'TEST1': 'testie',
15:20:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:20:53 INFO - 'USERDOMAIN': 'T-W732-IX-100',
15:20:53 INFO - 'USERNAME': 'cltbld',
15:20:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:20:53 INFO - 'WINDIR': 'C:\\windows',
15:20:53 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:20:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:20:53 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-100', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_raw.log'] with output_timeout 3600
15:20:54 INFO - mozversion application_buildid: 20161024123133
15:20:54 INFO - mozversion application_changeset: 9cdd8ed1733a941de56c4d022400c3e4b2ce0d12
15:20:54 INFO - mozversion application_display_name: Nightly
15:20:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
15:20:54 INFO - mozversion application_name: Firefox
15:20:54 INFO - mozversion application_remotingname: firefox
15:20:54 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
15:20:54 INFO - mozversion application_vendor: Mozilla
15:20:54 INFO - mozversion application_version: 52.0a1
15:20:54 INFO - mozversion platform_buildid: 20161024123133
15:20:54 INFO - mozversion platform_changeset: 9cdd8ed1733a941de56c4d022400c3e4b2ce0d12
15:20:54 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
15:20:54 INFO - mozversion platform_version: 52.0a1
15:20:54 INFO - using testdate: 1477347654
15:20:54 INFO - actual date: 1477347654
15:20:54 INFO - starting webserver on 'localhost:49617'
15:20:54 INFO - SUITE-START | Running 2 tests
15:20:54 INFO - TEST-START | dromaeo_css
15:20:54 INFO - operating with platform_type : w7_
15:20:54 INFO - Initialising browser for dromaeo_css test...
15:20:54 INFO - TEST-INFO | started process 816 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp0csapd\profile http://localhost:49617/getInfo.html)
15:21:04 INFO - PROCESS | 816 | __metrics Screen width/height:1600/1200
15:21:04 INFO - PROCESS | 816 | colorDepth:24
15:21:04 INFO - PROCESS | 816 | Browser inner width/height: 1008/673
15:21:04 INFO - PROCESS | 816 | __metrics
15:21:04 ERROR - PROCESS | 816 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined
15:21:04 INFO - PROCESS | 816 | [Child 3576] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345
15:21:04 INFO - PROCESS | 816 | [Child 3576] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345
15:21:05 INFO - TEST-INFO | 816: exit 0
15:21:05 INFO - Browser initialized.
15:21:05 INFO - Running cycle 1/1 for dromaeo_css test...
15:21:05 INFO - TEST-INFO | started process 3996 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp0csapd\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
15:21:05 INFO - PROCESS | 3996 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
15:21:16 INFO - PROCESS | 3996 | RSS: Main: 130273280
15:21:16 INFO - PROCESS | 3996 |
15:22:06 INFO - PROCESS | 3996 | 1477347726358 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]
15:24:15 INFO - PROCESS | 3996 | Cycle 1(1): loaded http://localhost:49617/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49617/tests/dromaeo/cssquery-ext.html)
15:24:16 INFO - PROCESS | 3996 | RSS: Main: 113586176
15:24:16 INFO - PROCESS | 3996 |
15:27:18 INFO - PROCESS | 3996 | Cycle 1(1): loaded http://localhost:49617/tests/dromaeo/cssquery-ext.html (next: http://localhost:49617/tests/dromaeo/cssquery-jquery.html)
15:27:18 INFO - PROCESS | 3996 | RSS: Main: 83828736
15:27:18 INFO - PROCESS | 3996 |
15:30:20 INFO - PROCESS | 3996 | Cycle 1(1): loaded http://localhost:49617/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49617/tests/dromaeo/cssquery-mootools.html)
15:30:20 INFO - PROCESS | 3996 | RSS: Main: 76578816
15:30:20 INFO - PROCESS | 3996 |
15:33:22 INFO - PROCESS | 3996 | Cycle 1(1): loaded http://localhost:49617/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49617/tests/dromaeo/cssquery-prototype.html)
15:33:23 INFO - PROCESS | 3996 | RSS: Main: 74452992
15:33:23 INFO - PROCESS | 3996 |
15:36:25 INFO - PROCESS | 3996 | Cycle 1(1): loaded http://localhost:49617/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49617/tests/dromaeo/cssquery-yui.html)
15:36:26 INFO - PROCESS | 3996 | RSS: Main: 78307328
15:36:26 INFO - PROCESS | 3996 |
15:39:28 INFO - PROCESS | 3996 | Cycle 1(1): loaded http://localhost:49617/tests/dromaeo/cssquery-yui.html (next: http://localhost:49617/tests/dromaeo/cssquery-dojo.html)
15:39:28 INFO - PROCESS | 3996 | RSS: Main: 71483392
15:39:28 INFO - PROCESS | 3996 |
15:39:28 INFO - PROCESS | 3996 | __start_tp_report
15:39:28 INFO - PROCESS | 3996 | _x_x_mozilla_page_load
15:39:28 INFO - PROCESS | 3996 | _x_x_mozilla_page_load_details
15:39:28 INFO - PROCESS | 3996 | |i|pagename|runs|
15:39:28 INFO - PROCESS | 3996 | |0;dojo.html;3140.576022236998;3165.4776961801304;3168.9841550792244;3185.4743967245404;3210;5405.081136206844;5572.916406253899;5591.38494765576;5644.576656750744;5674.716264186794;5550.944490555094;5584.274044374226;5600.439956004398;5655.3779084300695;5674.943250567493;4843.667991302403;4884.194107972198;4929.7288649124375;4930.778114984816;4989.750512474362;55.37154053146655;4444.088961762847;4897.681650692712;4947.233178857283;4989.451160372356;4797.592204662608;4805.91866829964;4874.902501949959;4890.193118135486;4893.975530122362;4849.054434385296;4858.149823780825;4884.5848102911295;4945.9258111128365;4951.702897826106;4786.7845946932475;4819.49395313494;4831.661783675144;4879.365682461293;4920.5571498565305;5377.704226267554;5456.345238571357;5463.235147079412;5482.2873026506295;5545.861353466155;5824.650520968756;5835.737391817378;5912.46787789101;5919.674417907014;5935.73289201987;5815.389384114692;5832.620879642852;5868.09044598088;5952.73212705425;5956.493698035672;5374.596905232084;5403.351597808288;5412.810551630713;5449.64577302474;5458.918116228299;5994.7602095916545;6027.186329845415;6027.457528822427;6074.301455332605;6099.634021958696;5648.322201335784;5685.914711279334;5720.77116915327;5809.390014048464;5833.67914764692;5748.281464816898;5753;5847.239858818411;5936.851578710567;5957.136215248851;5711.942880571141;5737.139429085671;5743.59794814359;5812.7674893003805;5882.794102206402;5715.799947001835;5779.826605201851;5877.265341832271;5882.3529411764675;5896.528277737771;3817.2365526894732;3827.1771569112216;3898.2398432305995;3910.765354078764;3916.7845768483007;3514.38498262803;3537.8231088445473;3539.734519911017;3547.609762926076;3565.2156525564333;5385.3268341457315;5441.401445840955;5521.3926468088475;5565.30433695788;5572.219889215432;5659.518940890071;5799.3040835100055;5836.387179346192;5945.732442040118;5951.553633477506;5498.230247765316;5635.605507614509;5639;5640.520555752807;5674.177244299553;4848.0788650156355;4850.587700045465;4893.143699852582;4900.362952816183;5016.749162541858;6114;6126.019836826086;6239;6264.749410023639;6281.057841323746;5901.3213480448585;5913.2608423947;6008.699565021819;6011.128386384037;6032.969835150796;6094.39056094387;6136.386361363828;6138.324784273816;6165.876682466415;6280.277768056732;5710.714464276853;5791.9131213031;5842.269716285465;5850;5857.209276747584;5806.129080637769;5813.651180929158;5956.047032474783;5970.641761494324;5983.551233657406;5627.634203776742;5662.320521537442;5688.345840228427;5691.8292451226525;5822.592418530662;5701.971490142689;5756.884862302814;5764.164196191445;5766.048601980626;5843.795467158629;5586.664800112007;5597.04850175463;5608.6073974823;5704.771809127588;5722.284714410699;5698.088305871041;5757.9424205757405;5759.971200143972;5783.797567085132;5856.560757943086;5553.555715542828;5638.9436105638415;5676.9716151420635;5692.857678558069;5698.088305871041
15:39:28 INFO - PROCESS | 3996 | |1;ext.html;570.0493519490585;577.0651544497914;619.9770378874857;702.4134847402415;704.9425861208188;41100.38349424761;42862.785686071526;42958.14083718327;42993.14013719724;43088.56911430885;42810;43811.78094109533;43815.12369752611;43877;43990.340144897775;8348.332133429341;8558.443701159436;8561.95719021404;8573.271271941892;8593.26957208638;8406.747797566082;8495.532745698983;8503.702370417037;8519.74440766775;8530.616122274481;8460.957695211515;8503.064662887078;8523.06246312905;8534.1039190885;8554.144585541428;8447.113053129426;8498.192671696179;8504;8513.616887240089;8529.189726975927;8446.366522510774;8592.699255526026;8606.354523410706;8617.310615150836;8627.22354988054;39444.60555394467;39890.20219595621;39915.800420998;39983.60016399828;39996.40005399921;3323.4682450807754;3400.4389275769468;3502.369573476773;3708.61059588742;3947.2697550953167;32373.352532949208;32429.351412971846;32679.346413071842;32830.507542386644;32900.835495822605;3566.268914872445;3601.603823579404;3643.908902277438;3657.487951686766;3866.3427217372832;341498;341769;341865;342511;342946;55689.44310556923;55946.440535594935;56179.43820561742;56190.719046404505;56224.15663765047;30071.248218794266;30572.694273056986;58046.7097664509;58256.41743582595;58431.70784146052;14389.64025899361;14499.057561258485;14548.854511454962;14571;14584;10942.17933654963;10961.780764384826;11004.669859904216;11027.44862756859;11042.834357484644;18487.075646217636;18496.81503184951;18530.980796056076;18595.34916277924;18627.254909803727;7250.891236631454;7370.815729606803;7386.409087273113;7418.629068546551;7443.367313778282;865.0873328638221;870.038607338894;914.5244472874201;963.9566219519999;972.5915115651443;29568;29695.703042969726;29756.702432975395;30090.69909300879;30167.547486787716;997.3766396002499;1017.2167431078028;1029.5264178477985;1073.238001019285;1096;15621.218939053002;15643.983141095792;15710.528684139494;15739.13434761099;15911.602209944613;524827;531695;534262;535916;535956;53315.73342133342;53699.46300537023;53866;54171.3748587543;54197.45802542003;1236.3014896583402;1249.6251124662747;1251.1304643272838;1252.154795513025;1259.6284096191646;28655.5701664471;28983.420331593672;29329.560056599596;29460.55809162907;29545.2613684651;45707.31439028351;46162.307565385876;46370.304445432674;46389.30416043694;46390;429951;517473.1895107807;517772;518712;519075;24338.87830560836;24522.67034631707;24994.50010999806;25029.123980660588;25613.103541375964;45306.773466133774;46035.76982115068;46081.539184607725;46187.3071903915;46435.53564464312;38660.22679546337;39987.40018899777;40056.599434005286;40065.799671001456;40552.391714123725;39665.639948895114;39838.40242396308;39871.60128398795;40432.1913561733;40681.59318406897;37067.62932370642;37338.43992340171;37472.81263593664;37561.24877502489;37575.624243757215;33729;35363.46954795731;35964.28071438609;36151;36986.260274793814;26070.86964565165;27648.44703105967;27869.72130278671;27938.72061279361;28227.71772282251
15:39:28 INFO - PROCESS | 3996 | |2;jquery.html;351.9947200791987;355.8576569372251;357.30682475996565;366.13409287036166;367.9117011917141;17991.46025619232;18705.4388368349;19436.902815485937;19784.802151978474;19841.908695021768;18383.26466941324;18668.53328666784;18690.71963920542;18717.625647487042;18718.438446846616;5665.631733937302;5716.628419152763;5754.539636829065;5786.276715410574;5801.419858014207;5712.742926568294;5823.242978412801;5831.533477321804;5833.766649334021;5836.5914385993;5579.330480342364;5693.914591281134;5743.597948143632;5750.338711048241;5847.707614619273;5831.912521312183;5848.181254624356;5878.088896221093;5882.941170588304;5909.704514774287;5626.071698169797;5719.97040532704;5742.97128514359;5769.04810706233;5813.302403711582;12912.483500659962;12975.156614820007;12998.805017924738;13096.541621043218;13122;2234.128689810975;2447.473793134485;2704.2968828104636;2784.039506370299;2807.0596350222495;10389.532471038745;10392.532336044897;11784.116191285688;15291.485852934366;16142.677146457005;1913.0817207740224;1954.2866853598475;1989.104902793734;1992.2927360786857;2025.813916374087;90649.54675226581;93969;95688.52155739177;96167.51916240514;97482.51258743732;10502.212334074822;10573.62992295266;10696.465176741132;10703;10705;13800.723985520233;13889.652758680912;13900.721985560232;13911.65220869466;13916.165030098227;7222.7472038479455;7433.368163706145;7435.665395057235;7467.066616672916;7493.437992150611;7289.854202915912;7309.8903516446235;7368.631568421664;7387.889181662279;7424.740134095281;4272.743635381887;4352.260115780325;4362.934555981663;4394.450693663292;4398.69209155356;1810.8551315894697;1853.4717605482642;1854.156358856744;1865.3378050792044;1874.9343772967882;825.1418524734329;857.8541647919868;863.572531596851;905.846006178938;913.9131782480654;18880.24479020851;19658;19825.702614460795;20050.298239561544;20051.799482005285;873.4497266721925;898.0660113481906;903.6340282185726;947.8009617980301;986.4623780039896;10746.892531074589;11106.111511079094;11217.270877392943;11257.774844503065;11266.54933802655;496182;496211;497727;498577;498744;104718;105409;105577;106065;107670;1029.8352263637933;1063.803196408667;1073.8603981482356;1089.967300980956;1090.7109615951779;17571.736423953906;17896.373626922996;18212.18045187943;18232.726509102107;18414.447566573024;48182;48834.75582622206;48965.510344896094;49253.26120108273;49265.014699706524;65191.348086520426;68268.6586567064;70143;70310;71792.64103679448;8472.44929079608;8627.43921645091;8634.222919937236;8651.091635378194;8799.47203167812;9902.455364954996;14620.561383158521;14768.261586920396;14894.851051489346;15004.924975375054;11819.409029548317;12038.337891416057;12252.571160009356;12511.186772859735;12548.309842958724;11998.160128790902;12160.695982600506;12210.57262995791;12346.197497162655;12375.195612285173;12243.326617035793;12466;12560.183588066746;12605.810912836132;12938.029647776266;8588.441751286146;8601.569921504026;8694.521801300736;8757.781055473664;8826.470411775314;8708.956455217683;10780.779886204771;12283.324417157142;12321.137520373488;12416.937915310365
15:39:28 INFO - PROCESS | 3996 | |3;mootools.html;1351.2162945491618;1401.0262867307222;1456.4028748213227;1652.454689952316;1680.0423758457684;7476.850462990737;7890.408219383555;7895.486793358435;7901.407394445418;8096.67613295469;8016.478928869634;8073.717419890305;8082.515049097058;8097.9595102024405;8110.391720620947;2168.4795649044236;2189.6715492676067;2191.1783081344497;2194.297824696098;2194.3087927302927;2118.5127420693248;2143.6462983607685;2146.2059038155903;2157.4714195022243;2166.068590506082;2140.3899888531773;2148.2695883399642;2152.203684636687;2155.8814265215406;2159.524904521003;2038.9184432622678;2048.150017742635;2050.2106688924723;2051.8974051297523;2052.692096185569;2058.341330774153;2074.9377518674464;2084.1455003448664;2086.5201003769216;2091.383042002613;7794.649240784178;7856.175101614305;7869.96065019677;7907.406944479187;7907.802304942365;4597.793099310539;4661.207594708873;4707.340972263886;4709;4747.810087596492;3094.102710214035;3139.3407384449292;3155.8106513609255;3157.921051973696;3165.699258570432;1016.3952448293254;1024.6618615856748;1054.093479607537;1055.904968552834;1058.190484279519;10372.585096596202;10393.324433911772;10459.790804183875;10460.058594726435;10483.266171368008;3905.3556163233316;3962.6829853611644;4004.1391100913443;4004.839806407769;4010.1578668479947;7962.243586859239;7971.163027882105;7977.162397948248;8005.9599702001115;8011.278984891271;4538.818447262139;4564.7717614119165;4584.862454126382;4590.104929538707;4603.033362993742;4252.617264446215;4369.191699535596;4369.71596846204;4394.384786129945;4401.383806267125;3354.9664503355143;3368.9326213476083;3383.154211447138;3395.3379091077004;3416.6241713411505;1025.2618114957368;1026.7484466305802;1029.7425643589104;1032.390889375272;1033.7674023344837;795.6181033104142;820.446198815797;844.4300097434208;861.6854847980568;897.7351681254046;4999.375078115236;5106.693598384109;5125.384953805493;5151.381834179922;5263.789448422097;882.9779255518663;884.2307192742355;889.017635512749;929.9628014879329;939.2157548447124;3562.9109272267883;3605.387084195693;3659.1035196376524;3662.5238718966366;3683.852645894188;9519.286053546011;9804.803903922024;9822.968588298269;9833.606655733834;9839.901600984042;9673.03269673027;9759.072888075621;9773.169280611086;9774.951125244328;9817.214622830157;7305.269473052652;7410.555366677909;7440.255974402625;7441.888371674429;7454.2545745426105;4967.726775027264;5084.694918304914;5126.820561280338;5202.687838729688;5285.418603953485;8913;9033;9117;9152.267818574364;9187.127222913812;9061;9140.897593553387;9168.129027742354;9296.023917488566;9331.673391431268;6754.18949726036;6869;6938.271481494572;7060.964695176492;7075.6815943281645;7641.42689298316;7723.034620672416;7729.1884352142215;7729.574873382018;7778.92221077782;6833.965830170818;6942.791716248521;6982.68577913985;7081.610511421715;7125.251848555827;6966.12923384577;6988.8951665724035;7004.579725216503;7093.07789987319;7110.431165506644;6177.135201071764;6221.626702397871;6316.273628532777;6323.114763932961;6324.083007963727;4640.141573808856;4683.78922948475;4727.409073865767;4780.259059845729;4820.060088282751;4692.389989301369;4748.121597504472;4797.880052998564;4811.133995880775;4814.903701926012
15:39:28 INFO - PROCESS | 3996 | |4;prototype.html;283.812683628805;290.53949490058255;290.9374484485835;292.3611907981063;293.74444233516846;6184.103305020769;6253.624782513047;6270.561060725751;6301.645146293546;6366.649834259114;6180.598261113025;6192.78325258616;6196.287426945903;6196.349383314743;6228.22147231596;4357.7385356878685;4405.713628614146;4406.735595864242;4421.270490369086;4422.380866678652;4263.765492897889;4387.451568553931;4396.626286765629;4402;4411.845585404511;4325.070109926365;4344.239758042346;4348.30427131659;4359.387026800088;4360.8691739247715;4327.805248763813;4336.544662810406;4338.674599405042;4338.913221735547;4350.8912277193;3700.3709369407266;3701.0747313171705;3702.648248416396;3707.1658876752786;3707.369747142965;5890.175375447441;5941.643501389887;5944.346121926585;6027.819165425044;6029.366916473751;5076.568491678212;5079.771410286546;5085.440601533828;5088.440271570124;5092.6180536459915;6340.239171299428;6346.270178929436;6353.332900045475;6364.172657554534;6378.489720822323;4090.872746171974;4095.795210239476;4097.344424892003;4099.815508302134;4113.9794301028605;6487.83780405489;6655.46756259498;6683.53215274931;6700.83247918806;6703.296153903819;4181.330987042059;4189.916201675949;4190.015346393595;4194.895127621834;4195.412642230091;4243.151369726067;4252.87241382759;4254.404598275647;4257.340112282601;4260.850870219528;5078.466760990117;5084.415292241366;5087.97456012725;5088.185890257615;5090.949090509048;6174.629522228681;6449.709763060673;6461.61230326182;6482.189726284215;6484.773032943825;1445.8771004464738;1466.0250933129562;1476.660368115318;1476.7415702252063;1481.3037872200048;888;900.320258205051;908.6592527802075;909.0318810466831;912.3339961827903;5486.067368547357;5506.64206826555;5531.861703457365;5531.944680553223;5536.197251398525;5941.257342832146;5950.761969521257;5950.7917222896995;5954.523638108941;5958.076498142795;5480.534154596824;5493.917591236055;5514.435787358897;5529.751161197756;5538.889222215533;3207.566978457925;3355.5973283205685;3581.426971684518;3590.982045089758;3596.723163276999;7043.260457651975;7054.188768291609;7059.5764254144915;7075.433965282765;7077.6461176941975;6711.865762684719;6781.694823732944;6877.140357455318;6899.275576064541;6915.688794004282;7005.474589405815;7020.78937631882;7040.6479676016;7042.330978557029;7043.506954513135;6694.364035416627;6708.865822683421;6739.932600674127;6743.629100399329;6761.661916904037;6460.644664543495;6586.571872828251;6610.768623098168;6627.701753421011;6637;6978.860422791414;7022.016917631638;7027.718891244497;7061.399781018671;7082.150141982995;4051.9797401012806;4129.690273229459;4133.359329303963;4152.6055024772595;4155.8753237402925;6360.681965901779;6637.369449902251;6641.369069938348;6645.501587380869;6679.432248258952;6393.392627700361;6411.076676996976;6415.582987105823;6427;6427.421532062137;6368.713407896563;6410.749903768907;6413.8717225656155;6422.743090276524;6439.8104644419245;6127.417895299939;6272.18461599989;6275.246970363403;6283.717232724447;6300;6372.713227904663;6432.324605916311;6433.678316084271;6451.645159516272;6464.159659244268;6353.52348573868;6357.745690172342;6367.968160159169;6368.299487056513;6377.489800815831
15:39:28 INFO - PROCESS | 3996 | |5;yui.html;688.5180373738384;689.3244620272135;694.3507820188124;763.797593637686;771.9035120609924;14126.434942602284;14214.218217998006;14310.928445357771;15766;16537.255823487943;15224.923875380608;15344.539663810103;15463.381464741396;15465.994710343819;15528.844711552882;673.1518286958443;676.3034074902846;679.796061181646;688.6453476459627;689.417442261291;682.9043933849265;687.3504538211392;687.7902239816854;689.5035574386459;697.285282585349;712.8324843661735;724.3517052238244;724.3697982754984;725.0501842586202;725.3471875312234;713.1157364867553;721.8881073433627;723.7720118162762;723.8950352198901;724.3878922310639;522.1879976636329;524.1116307858143;524.3576618642171;524.9370075590915;525.3538148077847;16003.519894403187;16018.119003454805;16041.197940103066;16047;16064.357425702958;15040.924795376062;15417.845821541754;15438.922805386013;15622.222111865414;15902.76145857813;13123.803142952864;13204.735905281937;13259.801102983463;13343.199408035549;13441.798373024412;3876.282887665791;3895.8831235062994;3915.7454765440157;3924.8037598120263;4023.959760402388;94798.52600736952;95050.0494995048;96350.51824740831;96395.51802241014;96879;10350.585976561004;10367.170626349875;10452.843207351743;10811.18916081297;10862.891371086187;18916.71624925627;18975.33586324472;19113.331033413764;19146.425607231806;19163.32928347501;8292.253697167283;8328.625211865481;8362.79093022667;8418.452800567944;8581.570921453902;4507.143642707875;4562.156001139733;4562.657800664963;4575.22221222393;4719.457262414866;3247.805131692106;3338.532605435241;3346.1300061983575;3347.832608369572;3353.2622822978906;585.7071464267866;595.330253464852;599.5233789137646;601.7472661482188;602.0397466041657;728.45002023473;739.9926000740031;742.4802638153315;760.2473551184398;762.622501861571;10274.178065754722;10317.123044541147;10428.63937220081;10487.528061237263;10532.526036328383;721.732958805235;734.6069852628818;766.5553978692345;766.6588368176108;766.6894907562446;4891.437484689235;5069.594432445396;5101;5120.02719483297;5190.688558686491;107495.46252268688;135012;137661;138471;139414.3029284847;98565;102288;106231;106237;107994;76640.61679691565;95770;97427.51286243524;100340.99659003463;101275;9409.153176214173;10088.747781305527;10123.7469063274;10164.491775411052;10293.897061029294;57262.7136864313;57265.427345726006;57424.42575574358;57933.71033144807;58130.4186958125;74424;75464.24535754572;79733.60133199296;80491.1950880507;83181.09779570931;14094.647633809236;14133.010689251654;14253.50112746049;14267.357968891218;14275.571732848031;17004.67863092647;17116.144192790063;17249.310027598756;17406.042667652895;17581.384651537133;13470.79793803074;13732.382042807898;13793.655158621115;13845.238511881944;14381.209033503257;14366.856331436551;14697;14774.48289309869;14878.702425951204;15385.461508847584;13631.659208519866;13737.587872363845;13825.170489770646;13919.443222270997;14284.928575357057;12087.818682719942;13198.868011319764;13400.530981415604;13817.79273310881;13888.583342499742;11727.413629318671;11871.169018168644;12035.097367697634;12127.57553485659;12155.635330940086
15:39:28 INFO - PROCESS | 3996 | __end_tp_report
15:39:28 INFO - PROCESS | 3996 | __start_cc_report
15:39:28 INFO - PROCESS | 3996 | _x_x_mozilla_cycle_collect,245
15:39:28 INFO - PROCESS | 3996 | __end_cc_report
15:39:28 INFO - PROCESS | 3996 | __startTimestamp1477348768423__endTimestamp
15:39:28 INFO - PROCESS | 3996 |
15:39:28 INFO - PROCESS | 3996 | ------- Summary: start -------
15:39:28 INFO - PROCESS | 3996 | Number of tests: 6
15:39:28 INFO - PROCESS | 3996 |
15:39:28 INFO - PROCESS | 3996 | [#0] dojo.html Cycles:165 Average:5378.85 Median:5674.72 stddev:828.79 (14.6%) stddev-sans-first:812.52
15:39:28 INFO - PROCESS | 3996 | Values: 3140.6 3165.5 3169.0 3185.5 3210.0 5405.1 5572.9 5591.4 5644.6 5674.7 5550.9 5584.3 5600.4 5655.4 5674.9 4843.7 4884.2 4929.7 4930.8 4989.8 55.4 4444.1 4897.7 4947.2 4989.5 4797.6 4805.9 4874.9 4890.2 4894.0 4849.1 4858.1 4884.6 4945.9 4951.7 4786.8 4819.5 4831.7 4879.4 4920.6 5377.7 5456.3 5463.2 5482.3 5545.9 5824.7 5835.7 5912.5 5919.7 5935.7 5815.4 5832.6 5868.1 5952.7 5956.5 5374.6 5403.4 5412.8 5449.6 5458.9 5994.8 6027.2 6027.5 6074.3 6099.6 5648.3 5685.9 5720.8 5809.4 5833.7 5748.3 5753.0 5847.2 5936.9 5957.1 5711.9 5737.1 5743.6 5812.8 5882.8 5715.8 5779.8 5877.3 5882.4 5896.5 3817.2 3827.2 3898.2 3910.8 3916.8 3514.4 3537.8 3539.7 3547.6 3565.2 5385.3 5441.4 5521.4 5565.3 5572.2 5659.5 5799.3 5836.4 5945.7 5951.6 5498.2 5635.6 5639.0 5640.5 5674.2 4848.1 4850.6 4893.1 4900.4 5016.7 6114.0 6126.0 6239.0 6264.7 6281.1 5901.3 5913.3 6008.7 6011.1 6033.0 6094.4 6136.4 6138.3 6165.9 6280.3 5710.7 5791.9 5842.3 5850.0 5857.2 5806.1 5813.7 5956.0 5970.6 5983.6 5627.6 5662.3 5688.3 5691.8 5822.6 5702.0 5756.9 5764.2 5766.0 5843.8 5586.7 5597.0 5608.6 5704.8 5722.3 5698.1 5757.9 5760.0 5783.8 5856.6 5553.6 5638.9 5677.0 5692.9 5698.1
15:39:28 INFO - PROCESS | 3996 |
15:39:28 INFO - PROCESS | 3996 | [#1] ext.html Cycles:180 Average:60237.38 Median:40026.50 stddev:124094.82 (310.0%) stddev-sans-first:124362.07
15:39:28 INFO - PROCESS | 3996 | Values: 570.0 577.1 620.0 702.4 704.9 41100.4 42862.8 42958.1 42993.1 43088.6 42810.0 43811.8 43815.1 43877.0 43990.3 8348.3 8558.4 8562.0 8573.3 8593.3 8406.7 8495.5 8503.7 8519.7 8530.6 8461.0 8503.1 8523.1 8534.1 8554.1 8447.1 8498.2 8504.0 8513.6 8529.2 8446.4 8592.7 8606.4 8617.3 8627.2 39444.6 39890.2 39915.8 39983.6 39996.4 3323.5 3400.4 3502.4 3708.6 3947.3 32373.4 32429.4 32679.3 32830.5 32900.8 3566.3 3601.6 3643.9 3657.5 3866.3 341498.0 341769.0 341865.0 342511.0 342946.0 55689.4 55946.4 56179.4 56190.7 56224.2 30071.2 30572.7 58046.7 58256.4 58431.7 14389.6 14499.1 14548.9 14571.0 14584.0 10942.2 10961.8 11004.7 11027.4 11042.8 18487.1 18496.8 18531.0 18595.3 18627.3 7250.9 7370.8 7386.4 7418.6 7443.4 865.1 870.0 914.5 964.0 972.6 29568.0 29695.7 29756.7 30090.7 30167.5 997.4 1017.2 1029.5 1073.2 1096.0 15621.2 15644.0 15710.5 15739.1 15911.6 524827.0 531695.0 534262.0 535916.0 535956.0 53315.7 53699.5 53866.0 54171.4 54197.5 1236.3 1249.6 1251.1 1252.2 1259.6 28655.6 28983.4 29329.6 29460.6 29545.3 45707.3 46162.3 46370.3 46389.3 46390.0 429951.0 517473.2 517772.0 518712.0 519075.0 24338.9 24522.7 24994.5 25029.1 25613.1 45306.8 46035.8 46081.5 46187.3 46435.5 38660.2 39987.4 40056.6 40065.8 40552.4 39665.6 39838.4 39871.6 40432.2 40681.6 37067.6 37338.4 37472.8 37561.2 37575.6 33729.0 35363.5 35964.3 36151.0 36986.3 26070.9 27648.4 27869.7 27938.7 28227.7
15:39:28 INFO - PROCESS | 3996 |
15:39:28 INFO - PROCESS | 3996 | [#2] jquery.html Cycles:180 Average:30287.51 Median:2744.17 stddev:82775.72 (3016.4%) stddev-sans-first:82977.41
15:39:28 INFO - PROCESS | 3996 | Values: 352.0 355.9 357.3 366.1 367.9 17991.5 18705.4 19436.9 19784.8 19841.9 18383.3 18668.5 18690.7 18717.6 18718.4 5665.6 5716.6 5754.5 5786.3 5801.4 5712.7 5823.2 5831.5 5833.8 5836.6 5579.3 5693.9 5743.6 5750.3 5847.7 5831.9 5848.2 5878.1 5882.9 5909.7 5626.1 5720.0 5743.0 5769.0 5813.3 12912.5 12975.2 12998.8 13096.5 13122.0 2234.1 2447.5 2704.3 2784.0 2807.1 10389.5 10392.5 11784.1 15291.5 16142.7 1913.1 1954.3 1989.1 1992.3 2025.8 90649.5 93969.0 95688.5 96167.5 97482.5 10502.2 10573.6 10696.5 10703.0 10705.0 13800.7 13889.7 13900.7 13911.7 13916.2 7222.7 7433.4 7435.7 7467.1 7493.4 7289.9 7309.9 7368.6 7387.9 7424.7 4272.7 4352.3 4362.9 4394.5 4398.7 1810.9 1853.5 1854.2 1865.3 1874.9 825.1 857.9 863.6 905.8 913.9 18880.2 19658.0 19825.7 20050.3 20051.8 873.4 898.1 903.6 947.8 986.5 10746.9 11106.1 11217.3 11257.8 11266.5 496182.0 496211.0 497727.0 498577.0 498744.0 104718.0 105409.0 105577.0 106065.0 107670.0 1029.8 1063.8 1073.9 1090.0 1090.7 17571.7 17896.4 18212.2 18232.7 18414.4 48182.0 48834.8 48965.5 49253.3 49265.0 65191.3 68268.7 70143.0 70310.0 71792.6 8472.4 8627.4 8634.2 8651.1 8799.5 9902.5 14620.6 14768.3 14894.9 15004.9 11819.4 12038.3 12252.6 12511.2 12548.3 11998.2 12160.7 12210.6 12346.2 12375.2 12243.3 12466.0 12560.2 12605.8 12938.0 8588.4 8601.6 8694.5 8757.8 8826.5 8709.0 10780.8 12283.3 12321.1 12416.9
15:39:28 INFO - PROCESS | 3996 |
15:39:28 INFO - PROCESS | 3996 | [#3] mootools.html Cycles:180 Average:5162.55 Median:4983.55 stddev:2888.60 (58.0%) stddev-sans-first:2882.51
15:39:28 INFO - PROCESS | 3996 | Values: 1351.2 1401.0 1456.4 1652.5 1680.0 7476.9 7890.4 7895.5 7901.4 8096.7 8016.5 8073.7 8082.5 8098.0 8110.4 2168.5 2189.7 2191.2 2194.3 2194.3 2118.5 2143.6 2146.2 2157.5 2166.1 2140.4 2148.3 2152.2 2155.9 2159.5 2038.9 2048.2 2050.2 2051.9 2052.7 2058.3 2074.9 2084.1 2086.5 2091.4 7794.6 7856.2 7870.0 7907.4 7907.8 4597.8 4661.2 4707.3 4709.0 4747.8 3094.1 3139.3 3155.8 3157.9 3165.7 1016.4 1024.7 1054.1 1055.9 1058.2 10372.6 10393.3 10459.8 10460.1 10483.3 3905.4 3962.7 4004.1 4004.8 4010.2 7962.2 7971.2 7977.2 8006.0 8011.3 4538.8 4564.8 4584.9 4590.1 4603.0 4252.6 4369.2 4369.7 4394.4 4401.4 3355.0 3368.9 3383.2 3395.3 3416.6 1025.3 1026.7 1029.7 1032.4 1033.8 795.6 820.4 844.4 861.7 897.7 4999.4 5106.7 5125.4 5151.4 5263.8 883.0 884.2 889.0 930.0 939.2 3562.9 3605.4 3659.1 3662.5 3683.9 9519.3 9804.8 9823.0 9833.6 9839.9 9673.0 9759.1 9773.2 9775.0 9817.2 7305.3 7410.6 7440.3 7441.9 7454.3 4967.7 5084.7 5126.8 5202.7 5285.4 8913.0 9033.0 9117.0 9152.3 9187.1 9061.0 9140.9 9168.1 9296.0 9331.7 6754.2 6869.0 6938.3 7061.0 7075.7 7641.4 7723.0 7729.2 7729.6 7778.9 6834.0 6942.8 6982.7 7081.6 7125.3 6966.1 6988.9 7004.6 7093.1 7110.4 6177.1 6221.6 6316.3 6323.1 6324.1 4640.1 4683.8 4727.4 4780.3 4820.1 4692.4 4748.1 4797.9 4811.1 4814.9
15:39:28 INFO - PROCESS | 3996 |
15:39:28 INFO - PROCESS | 3996 | [#4] prototype.html Cycles:180 Average:5242.05 Median:6151.02 stddev:1698.11 (27.6%) stddev-sans-first:1661.59
15:39:28 INFO - PROCESS | 3996 | Values: 283.8 290.5 290.9 292.4 293.7 6184.1 6253.6 6270.6 6301.6 6366.6 6180.6 6192.8 6196.3 6196.3 6228.2 4357.7 4405.7 4406.7 4421.3 4422.4 4263.8 4387.5 4396.6 4402.0 4411.8 4325.1 4344.2 4348.3 4359.4 4360.9 4327.8 4336.5 4338.7 4338.9 4350.9 3700.4 3701.1 3702.6 3707.2 3707.4 5890.2 5941.6 5944.3 6027.8 6029.4 5076.6 5079.8 5085.4 5088.4 5092.6 6340.2 6346.3 6353.3 6364.2 6378.5 4090.9 4095.8 4097.3 4099.8 4114.0 6487.8 6655.5 6683.5 6700.8 6703.3 4181.3 4189.9 4190.0 4194.9 4195.4 4243.2 4252.9 4254.4 4257.3 4260.9 5078.5 5084.4 5088.0 5088.2 5090.9 6174.6 6449.7 6461.6 6482.2 6484.8 1445.9 1466.0 1476.7 1476.7 1481.3 888.0 900.3 908.7 909.0 912.3 5486.1 5506.6 5531.9 5531.9 5536.2 5941.3 5950.8 5950.8 5954.5 5958.1 5480.5 5493.9 5514.4 5529.8 5538.9 3207.6 3355.6 3581.4 3591.0 3596.7 7043.3 7054.2 7059.6 7075.4 7077.6 6711.9 6781.7 6877.1 6899.3 6915.7 7005.5 7020.8 7040.6 7042.3 7043.5 6694.4 6708.9 6739.9 6743.6 6761.7 6460.6 6586.6 6610.8 6627.7 6637.0 6978.9 7022.0 7027.7 7061.4 7082.2 4052.0 4129.7 4133.4 4152.6 4155.9 6360.7 6637.4 6641.4 6645.5 6679.4 6393.4 6411.1 6415.6 6427.0 6427.4 6368.7 6410.7 6413.9 6422.7 6439.8 6127.4 6272.2 6275.2 6283.7 6300.0 6372.7 6432.3 6433.7 6451.6 6464.2 6353.5 6357.7 6368.0 6368.3 6377.5
15:39:28 INFO - PROCESS | 3996 |
15:39:28 INFO - PROCESS | 3996 | [#5] yui.html Cycles:180 Average:22731.02 Median:3293.17 stddev:33770.57 (1025.5%) stddev-sans-first:33824.75
15:39:28 INFO - PROCESS | 3996 | Values: 688.5 689.3 694.4 763.8 771.9 14126.4 14214.2 14310.9 15766.0 16537.3 15224.9 15344.5 15463.4 15466.0 15528.8 673.2 676.3 679.8 688.6 689.4 682.9 687.4 687.8 689.5 697.3 712.8 724.4 724.4 725.1 725.3 713.1 721.9 723.8 723.9 724.4 522.2 524.1 524.4 524.9 525.4 16003.5 16018.1 16041.2 16047.0 16064.4 15040.9 15417.8 15438.9 15622.2 15902.8 13123.8 13204.7 13259.8 13343.2 13441.8 3876.3 3895.9 3915.7 3924.8 4024.0 94798.5 95050.0 96350.5 96395.5 96879.0 10350.6 10367.2 10452.8 10811.2 10862.9 18916.7 18975.3 19113.3 19146.4 19163.3 8292.3 8328.6 8362.8 8418.5 8581.6 4507.1 4562.2 4562.7 4575.2 4719.5 3247.8 3338.5 3346.1 3347.8 3353.3 585.7 595.3 599.5 601.7 602.0 728.5 740.0 742.5 760.2 762.6 10274.2 10317.1 10428.6 10487.5 10532.5 721.7 734.6 766.6 766.7 766.7 4891.4 5069.6 5101.0 5120.0 5190.7 107495.5 135012.0 137661.0 138471.0 139414.3 98565.0 102288.0 106231.0 106237.0 107994.0 76640.6 95770.0 97427.5 100341.0 101275.0 9409.2 10088.7 10123.7 10164.5 10293.9 57262.7 57265.4 57424.4 57933.7 58130.4 74424.0 75464.2 79733.6 80491.2 83181.1 14094.6 14133.0 14253.5 14267.4 14275.6 17004.7 17116.1 17249.3 17406.0 17581.4 13470.8 13732.4 13793.7 13845.2 14381.2 14366.9 14697.0 14774.5 14878.7 15385.5 13631.7 13737.6 13825.2 13919.4 14284.9 12087.8 13198.9 13400.5 13817.8 13888.6 11727.4 11871.2 12035.1 12127.6 12155.6
15:39:28 INFO - PROCESS | 3996 | -------- Summary: end --------
15:39:28 INFO - PROCESS | 3996 |
15:39:28 INFO - PROCESS | 3996 | [Child 1524] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345
15:39:29 INFO - TEST-INFO | 3996: exit 0
15:39:29 INFO - TEST-OK | dromaeo_css | took 1114980ms
15:39:29 INFO - TEST-START | kraken
15:39:29 INFO - operating with platform_type : w7_
15:39:29 INFO - Initialising browser for kraken test...
15:39:29 INFO - TEST-INFO | started process 1256 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmptnxxsu\profile http://localhost:49617/getInfo.html)
15:39:35 INFO - PROCESS | 1256 | __metrics Screen width/height:1600/1200
15:39:35 INFO - PROCESS | 1256 | colorDepth:24
15:39:35 INFO - PROCESS | 1256 | Browser inner width/height: 1008/673
15:39:35 INFO - PROCESS | 1256 | __metrics
15:39:36 ERROR - PROCESS | 1256 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined
15:39:36 INFO - PROCESS | 1256 | [Child 3524] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345
15:39:36 INFO - PROCESS | 1256 | console.error:
15:39:36 INFO - PROCESS | 1256 | Message: Error: Connection closed before committing the transaction.
15:39:36 INFO - PROCESS | 1256 | Stack:
15:39:36 INFO - PROCESS | 1256 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
15:39:36 INFO - PROCESS | 1256 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
15:39:36 INFO - PROCESS | 1256 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
15:39:36 INFO - PROCESS | 1256 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
15:39:36 INFO - PROCESS | 1256 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
15:39:36 INFO - PROCESS | 1256 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15
15:39:36 INFO - PROCESS | 1256 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
15:39:36 INFO - PROCESS | 1256 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
15:39:36 INFO - PROCESS | 1256 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
15:39:36 INFO - PROCESS | 1256 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
15:39:36 INFO - PROCESS | 1256 | Barrier/this.client.addBlocker/promise13.2%
15:40:30 INFO - TinderboxPrint: I/O read bytes / time
141,860,352 / 2,148,260
15:40:30 INFO - TinderboxPrint: I/O write bytes / time
515,033,600 / 4,055,870
15:40:30 INFO - TinderboxPrint: CPU idle
8,193.0 (86.8%)
15:40:30 INFO - TinderboxPrint: CPU user
1,213.0 (12.9%)
15:40:30 INFO - install - Wall time: 2s; CPU: 11.0%; Read bytes: 0; Write bytes: 66634752; Read time: 0; Write time: 51400
15:40:30 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 141860352; Write bytes: 442152960; Read time: 2148260; Write time: 3623670
15:40:31 INFO - Running post-run listener: _upload_blobber_files
15:40:31 INFO - Blob upload gear active.
15:40:31 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
15:40:31 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
15:40:31 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Fx-Team', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
15:40:31 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Fx-Team -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
15:40:31 INFO - (blobuploader) - INFO - Open directory for files ...
15:40:31 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_errorsummary.log ...
15:40:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
15:40:31 INFO - (blobuploader) - INFO - Uploading, attempt #1.
15:40:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded
15:40:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
15:40:32 INFO - (blobuploader) - INFO - Done attempting.
15:40:32 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_raw.log ...
15:40:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
15:40:32 INFO - (blobuploader) - INFO - Uploading, attempt #1.
15:40:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded
15:40:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
15:40:33 INFO - (blobuploader) - INFO - Done attempting.
15:40:33 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
15:40:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
15:40:33 INFO - (blobuploader) - INFO - Uploading, attempt #1.
15:40:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
15:40:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
15:40:40 INFO - (blobuploader) - INFO - Done attempting.
15:40:40 INFO - (blobuploader) - INFO - Iteration through files over.
15:40:40 INFO - Return code: 0
15:40:40 INFO - rmtree: C:\slave\test\build\uploaded_files.json
15:40:40 INFO - Using _rmtree_windows ...
15:40:40 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
15:40:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d14d6a497d61703f450f0bc28f9b4970d6c35796962808a3c0dc525f763d457ef95c4baa3242e77b8cc3030fa215c75746070b6c53c6d9006181250bf2dcfee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f34f05edab6927c9820589a347640fd21e2fe12293677e916337edb43717f4be504bc6bfefb09c7212fd78bd692d2c3bf7d9b8ae993b2068b231b36669ff3c8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/efee71a596cc9993a536e27c5bb2f48a68ff79b0f85eb7cb010e30e32e60f5e85092b00dd1b1f5b648eb389f7221b30391f04fdc7077e77eb988c04e78117c54"}
15:40:40 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
15:40:40 INFO - Writing to file C:\slave\test\properties\blobber_files
15:40:40 INFO - Contents:
15:40:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d14d6a497d61703f450f0bc28f9b4970d6c35796962808a3c0dc525f763d457ef95c4baa3242e77b8cc3030fa215c75746070b6c53c6d9006181250bf2dcfee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f34f05edab6927c9820589a347640fd21e2fe12293677e916337edb43717f4be504bc6bfefb09c7212fd78bd692d2c3bf7d9b8ae993b2068b231b36669ff3c8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/efee71a596cc9993a536e27c5bb2f48a68ff79b0f85eb7cb010e30e32e60f5e85092b00dd1b1f5b648eb389f7221b30391f04fdc7077e77eb988c04e78117c54"}
15:40:40 INFO - Running post-run listener: copy_logs_to_upload_dir
15:40:40 INFO - Copying logs to upload dir...
15:40:40 INFO - mkdir: C:\slave\test\build\upload\logs
15:40:40 INFO - Copying logs to upload dir...
15:40:40 INFO - Using _rmtree_windows ...
15:40:40 INFO - Using _rmtree_windows ...
15:40:40 INFO - Using _rmtree_windows ...
15:40:40 INFO - Using _rmtree_windows ...
15:40:40 INFO - Using _rmtree_windows ...
15:40:40 INFO - Using _rmtree_windows ...
15:40:40 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1405.694000
========= master_lag: 4.48 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 30 secs) (at 2016-10-24 15:40:45.510182) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 15:40:45.510926) =========
'cmd' '/C' 'for' '%f' 'in' '(*)' 'do' '@type' '%f'
in dir C:\slave\test\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-100
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-100
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\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-100
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
blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d14d6a497d61703f450f0bc28f9b4970d6c35796962808a3c0dc525f763d457ef95c4baa3242e77b8cc3030fa215c75746070b6c53c6d9006181250bf2dcfee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f34f05edab6927c9820589a347640fd21e2fe12293677e916337edb43717f4be504bc6bfefb09c7212fd78bd692d2c3bf7d9b8ae993b2068b231b36669ff3c8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/efee71a596cc9993a536e27c5bb2f48a68ff79b0f85eb7cb010e30e32e60f5e85092b00dd1b1f5b648eb389f7221b30391f04fdc7077e77eb988c04e78117c54"}
build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477337493/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d14d6a497d61703f450f0bc28f9b4970d6c35796962808a3c0dc525f763d457ef95c4baa3242e77b8cc3030fa215c75746070b6c53c6d9006181250bf2dcfee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f34f05edab6927c9820589a347640fd21e2fe12293677e916337edb43717f4be504bc6bfefb09c7212fd78bd692d2c3bf7d9b8ae993b2068b231b36669ff3c8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/efee71a596cc9993a536e27c5bb2f48a68ff79b0f85eb7cb010e30e32e60f5e85092b00dd1b1f5b648eb389f7221b30391f04fdc7077e77eb988c04e78117c54"}'
========= master_lag: 0.01 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 15:40:45.626843) =========
========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-10-24 15:40:45.627179) =========
'rm' '-f' 'oauth.txt'
in dir C:\slave\test\. (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-100
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-100
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
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-100
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
========= master_lag: 4.84 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-10-24 15:40:50.568279) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-24 15:40:50.568615) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-24 15:40:51.103768) =========
========= Total master_lag: 13.92 =========