builder: fx-team_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-017
starttime: 1477260107.97
results: success (0)
buildid: 20161023123006
builduid: e56cd4dbefe34d1d82ee5740e6d07fee
revision: a9a41b69f3f9be494ae0653601c08aa388d9afe4
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-23 15:01:47.973498) =========
master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-23 15:01:47.973964) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-23 15:01:47.974243) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-23 15:01:48.109279) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-23 15:01:48.109667) =========
'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-017
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-017
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-017
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:01:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
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% 34.14 MB/s
15:01:49 (34.14 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.137000
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-23 15:01:49.271400) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-10-23 15:01:49.271732) =========
'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-017
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-017
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-017
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=9.401000
========= master_lag: 4.77 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-10-23 15:02:03.444905) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-23 15:02:03.445221) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev a9a41b69f3f9be494ae0653601c08aa388d9afe4 --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 a9a41b69f3f9be494ae0653601c08aa388d9afe4 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-017
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-017
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-017
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-23 15:02:03,838 truncating revision to first 12 chars
2016-10-23 15:02:03,838 Setting DEBUG logging.
2016-10-23 15:02:03,838 attempt 1/10
2016-10-23 15:02:03,838 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a9a41b69f3f9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-10-23 15:02:04,336 unpacking tar archive at: fx-team-a9a41b69f3f9/testing/mozharness/
2016-10-23 15:02:04,509 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.298000
========= master_lag: 0.04 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-23 15:02:04.779799) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-23 15:02:04.780120) =========
script_repo_revision: a9a41b69f3f9be494ae0653601c08aa388d9afe4
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-23 15:02:04.780510) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-23 15:02:04.780776) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-23 15:02:04.817192) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 20 secs) (at 2016-10-23 15:02:04.817599) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--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', '--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-017
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-017
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-017
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:02:05 INFO - MultiFileLogger online at 20161023 15:02:05 in C:\slave\test
15:02:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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:02:05 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
15:02:05 INFO - {'append_to_log': False,
15:02:05 INFO - 'base_work_dir': 'C:\\slave\\test',
15:02:05 INFO - 'blob_upload_branch': 'Fx-Team',
15:02:05 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
15:02:05 INFO - 'branch': 'Fx-Team',
15:02:05 INFO - 'buildbot_json_path': 'buildprops.json',
15:02:05 INFO - 'config_files': ('talos/windows_config.py',),
15:02:05 INFO - 'default_actions': ('clobber',
15:02:05 INFO - 'read-buildbot-config',
15:02:05 INFO - 'download-and-extract',
15:02:05 INFO - 'populate-webroot',
15:02:05 INFO - 'create-virtualenv',
15:02:05 INFO - 'install',
15:02:05 INFO - 'run-tests'),
15:02:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
15:02:05 INFO - 'download_minidump_stackwalk': True,
15:02:05 INFO - 'download_symbols': 'ondemand',
15:02:05 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
15:02:05 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
15:02:05 INFO - 'hg': 'c:/mozilla-build/hg/hg',
15:02:05 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
15:02:05 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
15:02:05 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
15:02:05 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
15:02:05 INFO - 'C:/mozilla-build/tooltool.py'),
15:02:05 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
15:02:05 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
15:02:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
15:02:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
15:02:05 INFO - 'installer_path': 'installer.exe',
15:02:05 INFO - 'log_level': 'info',
15:02:05 INFO - 'log_name': 'talos',
15:02:05 INFO - 'log_to_console': True,
15:02:05 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
15:02:05 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
15:02:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
15:02:05 INFO - 'opt_config_files': (),
15:02:05 INFO - 'pip_index': False,
15:02:05 INFO - 'sps_profile': False,
15:02:05 INFO - 'sps_profile_interval': 0,
15:02:05 INFO - 'suite': 'dromaeojs',
15:02:05 INFO - 'system_bits': '32',
15:02:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
15:02:05 INFO - 'title': 'T-W732-IX-017',
15:02:05 INFO - 'use_talos_json': True,
15:02:05 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
15:02:05 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
15:02:05 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
15:02:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
15:02:05 INFO - 'work_dir': 'build'}
15:02:05 INFO - [mozharness: 2016-10-23 22:02:05.207000Z] Running clobber step.
15:02:05 INFO - Running pre-action listener: _resource_record_pre_action
15:02:05 INFO - Running main action method: clobber
15:02:05 INFO - rmtree: C:\slave\test\build
15:02:05 INFO - Using _rmtree_windows ...
15:02:05 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
15:04:10 INFO - Running post-action listener: _resource_record_post_action
15:04:10 INFO - [mozharness: 2016-10-23 22:04:10.029000Z] Finished clobber step (success)
15:04:10 INFO - [mozharness: 2016-10-23 22:04:10.029000Z] Running read-buildbot-config step.
15:04:10 INFO - Running pre-action listener: _resource_record_pre_action
15:04:10 INFO - Running main action method: read_buildbot_config
15:04:10 INFO - Using buildbot properties:
15:04:10 INFO - {
15:04:10 INFO - "project": "",
15:04:10 INFO - "product": "firefox",
15:04:10 INFO - "script_repo_revision": "production",
15:04:10 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
15:04:10 INFO - "repository": "",
15:04:10 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs",
15:04:10 INFO - "buildid": "20161023123006",
15:04:10 INFO - "pgo_build": "True",
15:04:10 INFO - "basedir": "C:\\slave\\test",
15:04:10 INFO - "buildnumber": 47,
15:04:10 INFO - "slavename": "t-w732-ix-017",
15:04:10 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/",
15:04:10 INFO - "platform": "win7_ix",
15:04:10 INFO - "scheduler": "tests-fx-team-win32-pgo-talos",
15:04:10 INFO - "branch": "fx-team",
15:04:10 INFO - "repo_path": "integration/fx-team",
15:04:10 INFO - "revision": "a9a41b69f3f9be494ae0653601c08aa388d9afe4",
15:04:10 INFO - "stage_platform": "win32-pgo",
15:04:10 INFO - "builduid": "e56cd4dbefe34d1d82ee5740e6d07fee",
15:04:10 INFO - "slavebuilddir": "test"
15:04:10 INFO - }
15:04:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
15:04:10 INFO - Running post-action listener: _resource_record_post_action
15:04:10 INFO - [mozharness: 2016-10-23 22:04:10.033000Z] Finished read-buildbot-config step (success)
15:04:10 INFO - [mozharness: 2016-10-23 22:04:10.033000Z] Running download-and-extract step.
15:04:10 INFO - Running pre-action listener: _resource_record_pre_action
15:04:10 INFO - Running main action method: download_and_extract
15:04:10 INFO - mkdir: C:\slave\test\build\tests
15:04:10 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:04:10 INFO - trying https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
15:04:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
15:04:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/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:04:10 INFO - Downloaded 1218 bytes.
15:04:10 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
15:04:10 INFO - Using the following test package requirements:
15:04:10 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
15:04:10 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:04:10 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
15:04:10 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
15:04:10 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:04:10 INFO - u'jsshell-win32.zip'],
15:04:10 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:04:10 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
15:04:10 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
15:04:10 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:04:10 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
15:04:10 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:04:10 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
15:04:10 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:04:10 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
15:04:10 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:04:10 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
15:04:10 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
15:04:10 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
15:04:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
15:04:10 INFO - Fetch https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
15:04:11 INFO - Http code: 200
15:04:11 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront)
15:04:11 INFO - x-amz-cf-id: cAN9OOVVyU53anxs903K3RNxNPALnxcWwWaI9PcvnOv66UXilDDRmA==
15:04:11 INFO - x-amz-version-id: 98kggVBu638SYjo3J2RUVFFExy9MbYv4
15:04:11 INFO - Expected file size: 17184116
15:04:11 INFO - Obtained file size: 17184116
15:04:48 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:04:48 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
15:04:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
15:04:48 INFO - Fetch https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
15:04:48 INFO - Http code: 200
15:04:48 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront)
15:04:48 INFO - x-amz-cf-id: QdWDA7XP8uqoO7M2uoHFdNww4gJ-D9F_aLgi12NPLIsE3gbCm155BQ==
15:04:48 INFO - x-amz-version-id: 98kggVBu638SYjo3J2RUVFFExy9MbYv4
15:04:49 INFO - Expected file size: 17184116
15:04:49 INFO - Obtained file size: 17184116
15:05:05 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
15:05:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
15:05:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
15:05:06 INFO - Http code: 200
15:05:06 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront)
15:05:06 INFO - x-amz-cf-id: OKtjDLZq6qKSfftbRyRw-am0CX_t7fyq212UggJxXSxj8BD8prGAqg==
15:05:06 INFO - x-amz-version-id: dbjYe64e0VY4m0wyRSlNm_IC4TtlJ60d
15:05:07 INFO - Expected file size: 14409028
15:05:07 INFO - Obtained file size: 14409028
15:05:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:05:15 INFO - trying https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
15:05:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
15:05:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
15:05:18 INFO - Downloaded 54275487 bytes.
15:05:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
15:05:18 INFO - mkdir: C:\slave\test\properties
15:05:18 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
15:05:18 INFO - Writing to file C:\slave\test\properties\build_url
15:05:18 INFO - Contents:
15:05:18 INFO - build_url:https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
15:05:18 INFO - Running post-action listener: _resource_record_post_action
15:05:18 INFO - Running post-action listener: set_extra_try_arguments
15:05:18 INFO - [mozharness: 2016-10-23 22:05:18.824000Z] Finished download-and-extract step (success)
15:05:18 INFO - [mozharness: 2016-10-23 22:05:18.824000Z] Running populate-webroot step.
15:05:18 INFO - Running pre-action listener: _resource_record_pre_action
15:05:18 INFO - Running main action method: populate_webroot
15:05:18 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
15:05:18 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
15:05:18 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
15:05:18 INFO - u'--tppagecycles',
15:05:18 INFO - u'7'],
15:05:18 INFO - u'tests': [u'tsvgm']}},
15:05:18 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
15:05:18 INFO - u'tests': [u'tresize', u'tcanvasmark']},
15:05:18 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
15:05:18 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
15:05:18 INFO - u'tests': [u'dromaeo_css', u'kraken']},
15:05:18 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
15:05:18 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:05:18 INFO - u'talos_options': [u'--disable-e10s'],
15:05:18 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
15:05:18 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:05:18 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
15:05:18 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:05:18 INFO - u'talos_options': [u'--disable-e10s'],
15:05:18 INFO - u'tests': [u'damp', u'tps']},
15:05:18 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:05:18 INFO - u'tests': [u'damp', u'tps']},
15:05:18 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
15:05:18 INFO - u'tests': [u'dromaeo_dom']},
15:05:18 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
15:05:18 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
15:05:18 INFO - u'tests': [u'basic_compositor_video']},
15:05:18 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
15:05:18 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
15:05:18 INFO - u'tests': [u'a11yr',
15:05:18 INFO - u'ts_paint',
15:05:18 INFO - u'tpaint',
15:05:18 INFO - u'sessionrestore',
15:05:18 INFO - u'sessionrestore_no_auto_restore',
15:05:18 INFO - u'tabpaint']},
15:05:18 INFO - u'other-e10s': {u'tests': [u'a11yr',
15:05:18 INFO - u'ts_paint',
15:05:18 INFO - u'tpaint',
15:05:18 INFO - u'sessionrestore',
15:05:18 INFO - u'sessionrestore_no_auto_restore',
15:05:18 INFO - u'tabpaint']},
15:05:18 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
15:05:18 INFO - u'ts_paint',
15:05:18 INFO - u'tpaint',
15:05:18 INFO - u'sessionrestore',
15:05:18 INFO - u'sessionrestore_no_auto_restore',
15:05:18 INFO - u'tabpaint']},
15:05:18 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
15:05:18 INFO - u'ts_paint',
15:05:18 INFO - u'tpaint',
15:05:18 INFO - u'sessionrestore',
15:05:18 INFO - u'sessionrestore_no_auto_restore',
15:05:18 INFO - u'tabpaint']},
15:05:18 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
15:05:18 INFO - u'tests': [u'a11yr',
15:05:18 INFO - u'ts_paint',
15:05:18 INFO - u'tpaint',
15:05:18 INFO - u'sessionrestore',
15:05:18 INFO - u'sessionrestore_no_auto_restore',
15:05:18 INFO - u'tabpaint']},
15:05:18 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
15:05:18 INFO - u'tests': [u'a11yr',
15:05:18 INFO - u'ts_paint',
15:05:18 INFO - u'tpaint',
15:05:18 INFO - u'sessionrestore',
15:05:18 INFO - u'sessionrestore_no_auto_restore',
15:05:18 INFO - u'tabpaint']},
15:05:18 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
15:05:18 INFO - u'tests': [u'tsvgx',
15:05:18 INFO - u'tsvgr_opacity',
15:05:18 INFO - u'tart',
15:05:18 INFO - u'tscrollx',
15:05:18 INFO - u'cart']},
15:05:18 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
15:05:18 INFO - u'tsvgr_opacity',
15:05:18 INFO - u'tart',
15:05:18 INFO - u'tscrollx',
15:05:18 INFO - u'cart']},
15:05:18 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:05:18 INFO - u'talos_options': [u'--disable-e10s'],
15:05:18 INFO - u'tests': [u'tp5o']},
15:05:18 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:05:18 INFO - u'tests': [u'tp5o']},
15:05:18 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:05:18 INFO - u'talos_options': [u'--disable-e10s',
15:05:18 INFO - u'--xperf_path',
15:05:18 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
15:05:18 INFO - u'tests': [u'tp5n']},
15:05:18 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:05:18 INFO - u'talos_options': [u'--xperf_path',
15:05:18 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
15:05:18 INFO - u'tests': [u'tp5n']}},
15:05:18 INFO - u'talos.zip': {u'path': u'',
15:05:18 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
15:05:18 INFO - Running post-action listener: _resource_record_post_action
15:05:18 INFO - [mozharness: 2016-10-23 22:05:18.897000Z] Finished populate-webroot step (success)
15:05:18 INFO - [mozharness: 2016-10-23 22:05:18.897000Z] Running create-virtualenv step.
15:05:18 INFO - Running pre-action listener: _resource_record_pre_action
15:05:18 INFO - Running main action method: create_virtualenv
15:05:18 INFO - Creating virtualenv C:\slave\test\build/venv
15:05:18 INFO - mkdir: C:\slave\test\build\venv\Scripts
15:05:18 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
15:05:18 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:05:18 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:05:18 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:05:33 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
15:05:36 INFO - Installing distribute......................................................................................................................................................................................done.
15:05:38 INFO - Return code: 0
15:05:38 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
15:05:38 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
15:05:38 INFO - Reading from file tmpfile_stdout
15:05:38 INFO - Output received:
15:05:38 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:05:38 INFO - Using _rmtree_windows ...
15:05:38 INFO - Using _rmtree_windows ...
15:05:38 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
15:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:05:38 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-017', 'USERDOMAIN': 'T-W732-IX-017', '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-017', '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:05:38 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:05:38 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:05:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:05:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:05:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:05:38 INFO - 'COMPUTERNAME': 'T-W732-IX-017',
15:05:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:05:38 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:05:38 INFO - 'DCLOCATION': 'SCL3',
15:05:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:05:38 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:05:38 INFO - 'HOMEDRIVE': 'C:',
15:05:38 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:05:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:05:38 INFO - 'KTS_VERSION': '1.19c',
15:05:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:05:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-017',
15:05:38 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:05:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:05:38 INFO - 'MOZ_NO_REMOTE': '1',
15:05:38 INFO - 'NO_EM_RESTART': '1',
15:05:38 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:05:38 INFO - 'OS': 'Windows_NT',
15:05:38 INFO - 'OURDRIVE': 'C:',
15:05:38 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
15:05:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:05:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:05:38 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:05:38 INFO - 'PROCESSOR_LEVEL': '6',
15:05:38 INFO - 'PROCESSOR_REVISION': '1e05',
15:05:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:05:38 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:05:38 INFO - 'PROMPT': '$P$G',
15:05:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:05:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:05:38 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:05:38 INFO - 'PWD': 'C:\\slave\\test',
15:05:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:05:38 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:05:38 INFO - 'SYSTEMDRIVE': 'C:',
15:05:38 INFO - 'SYSTEMROOT': 'C:\\windows',
15:05:38 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:05:38 INFO - 'TEST1': 'testie',
15:05:38 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:05:38 INFO - 'USERDOMAIN': 'T-W732-IX-017',
15:05:38 INFO - 'USERNAME': 'cltbld',
15:05:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:05:38 INFO - 'WINDIR': 'C:\\windows',
15:05:38 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:05:38 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:05:42 INFO - Ignoring indexes: http://pypi.python.org/simple/
15:05:42 INFO - Downloading/unpacking pip>=1.5
15:05:46 INFO - Running setup.py egg_info for package pip
15:05:46 INFO - warning: no files found matching 'pip\cacert.pem'
15:05:46 INFO - warning: no files found matching '*.html' under directory 'docs'
15:05:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
15:05:46 INFO - no previously-included directories found matching 'docs\_build\_sources'
15:05:46 INFO - Installing collected packages: pip
15:05:46 INFO - Found existing installation: pip 0.8.2
15:05:46 INFO - Uninstalling pip:
15:05:46 INFO - Successfully uninstalled pip
15:05:46 INFO - Running setup.py install for pip
15:05:46 INFO - warning: no files found matching 'pip\cacert.pem'
15:05:46 INFO - warning: no files found matching '*.html' under directory 'docs'
15:05:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
15:05:46 INFO - no previously-included directories found matching 'docs\_build\_sources'
15:05:46 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
15:05:46 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
15:05:46 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
15:05:46 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
15:05:46 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
15:05:46 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
15:05:46 INFO - Successfully installed pip
15:05:46 INFO - Cleaning up...
15:05:46 INFO - Return code: 0
15:05:46 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
15:05:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:05:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:05:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:05:46 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-017', 'USERDOMAIN': 'T-W732-IX-017', '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-017', '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:05:46 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:05:46 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:05:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:05:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:05:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:05:46 INFO - 'COMPUTERNAME': 'T-W732-IX-017',
15:05:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:05:46 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:05:46 INFO - 'DCLOCATION': 'SCL3',
15:05:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:05:46 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:05:46 INFO - 'HOMEDRIVE': 'C:',
15:05:46 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:05:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:05:46 INFO - 'KTS_VERSION': '1.19c',
15:05:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:05:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-017',
15:05:46 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:05:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:05:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:05:46 INFO - 'MOZ_NO_REMOTE': '1',
15:05:46 INFO - 'NO_EM_RESTART': '1',
15:05:46 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:05:46 INFO - 'OS': 'Windows_NT',
15:05:46 INFO - 'OURDRIVE': 'C:',
15:05:46 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
15:05:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:05:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:05:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:05:46 INFO - 'PROCESSOR_LEVEL': '6',
15:05:46 INFO - 'PROCESSOR_REVISION': '1e05',
15:05:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:05:46 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:05:46 INFO - 'PROMPT': '$P$G',
15:05:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:05:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:05:46 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:05:46 INFO - 'PWD': 'C:\\slave\\test',
15:05:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:05:46 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:05:46 INFO - 'SYSTEMDRIVE': 'C:',
15:05:46 INFO - 'SYSTEMROOT': 'C:\\windows',
15:05:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:05:46 INFO - 'TEST1': 'testie',
15:05:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:05:46 INFO - 'USERDOMAIN': 'T-W732-IX-017',
15:05:46 INFO - 'USERNAME': 'cltbld',
15:05:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:05:46 INFO - 'WINDIR': 'C:\\windows',
15:05:46 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:05:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:05:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:05:49 INFO - Downloading/unpacking psutil>=3.1.1
15:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:05:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:05:50 INFO - Installing collected packages: psutil
15:05:51 INFO - Successfully installed psutil
15:05:51 INFO - Cleaning up...
15:05:51 INFO - Return code: 0
15:05:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
15:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:05:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:05:51 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-017', 'USERDOMAIN': 'T-W732-IX-017', '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-017', '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:05:51 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:05:51 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:05:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:05:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:05:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:05:51 INFO - 'COMPUTERNAME': 'T-W732-IX-017',
15:05:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:05:51 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:05:51 INFO - 'DCLOCATION': 'SCL3',
15:05:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:05:51 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:05:51 INFO - 'HOMEDRIVE': 'C:',
15:05:51 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:05:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:05:51 INFO - 'KTS_VERSION': '1.19c',
15:05:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:05:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-017',
15:05:51 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:05:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:05:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:05:51 INFO - 'MOZ_NO_REMOTE': '1',
15:05:51 INFO - 'NO_EM_RESTART': '1',
15:05:51 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:05:51 INFO - 'OS': 'Windows_NT',
15:05:51 INFO - 'OURDRIVE': 'C:',
15:05:51 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:05:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:05:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:05:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:05:51 INFO - 'PROCESSOR_LEVEL': '6',
15:05:51 INFO - 'PROCESSOR_REVISION': '1e05',
15:05:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:05:51 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:05:51 INFO - 'PROMPT': '$P$G',
15:05:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:05:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:05:51 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:05:51 INFO - 'PWD': 'C:\\slave\\test',
15:05:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:05:51 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:05:51 INFO - 'SYSTEMDRIVE': 'C:',
15:05:51 INFO - 'SYSTEMROOT': 'C:\\windows',
15:05:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:05:51 INFO - 'TEST1': 'testie',
15:05:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:05:51 INFO - 'USERDOMAIN': 'T-W732-IX-017',
15:05:51 INFO - 'USERNAME': 'cltbld',
15:05:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:05:51 INFO - 'WINDIR': 'C:\\windows',
15:05:51 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:05:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:05:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:05:51 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:05:51 INFO - Downloading/unpacking mozsystemmonitor==0.3
15:05:51 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:05:51 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:05:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz
15:05:53 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
15:05:53 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:05:53 INFO - Installing collected packages: mozsystemmonitor
15:05:53 INFO - Running setup.py install for mozsystemmonitor
15:05:53 INFO - Successfully installed mozsystemmonitor
15:05:53 INFO - Cleaning up...
15:05:53 INFO - Return code: 0
15:05:53 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
15:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:05:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:05:53 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-017', 'USERDOMAIN': 'T-W732-IX-017', '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-017', '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:05:53 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:05:53 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:05:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:05:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:05:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:05:53 INFO - 'COMPUTERNAME': 'T-W732-IX-017',
15:05:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:05:53 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:05:53 INFO - 'DCLOCATION': 'SCL3',
15:05:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:05:53 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:05:53 INFO - 'HOMEDRIVE': 'C:',
15:05:53 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:05:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:05:53 INFO - 'KTS_VERSION': '1.19c',
15:05:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:05:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-017',
15:05:53 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:05:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:05:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:05:53 INFO - 'MOZ_NO_REMOTE': '1',
15:05:53 INFO - 'NO_EM_RESTART': '1',
15:05:53 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:05:53 INFO - 'OS': 'Windows_NT',
15:05:53 INFO - 'OURDRIVE': 'C:',
15:05: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:05:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:05:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:05:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:05:53 INFO - 'PROCESSOR_LEVEL': '6',
15:05:53 INFO - 'PROCESSOR_REVISION': '1e05',
15:05:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:05:53 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:05:53 INFO - 'PROMPT': '$P$G',
15:05:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:05:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:05:53 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:05:53 INFO - 'PWD': 'C:\\slave\\test',
15:05:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:05:53 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:05:53 INFO - 'SYSTEMDRIVE': 'C:',
15:05:53 INFO - 'SYSTEMROOT': 'C:\\windows',
15:05:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:05:53 INFO - 'TEST1': 'testie',
15:05:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:05:53 INFO - 'USERDOMAIN': 'T-W732-IX-017',
15:05:53 INFO - 'USERNAME': 'cltbld',
15:05:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:05:53 INFO - 'WINDIR': 'C:\\windows',
15:05:53 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:05:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:05:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:05:53 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:05:53 INFO - Downloading/unpacking jsonschema==2.5.1
15:05:53 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:05:53 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:05:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
15:05:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
15:05:54 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:05:54 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:05:55 INFO - Downloading functools32-3.2.3-2.tar.gz
15:05:55 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
15:05:55 INFO - warning: no files found matching '*.txt'
15:05:55 INFO - no previously-included directories found matching 'build'
15:05:55 INFO - no previously-included directories found matching 'dist'
15:05:55 INFO - no previously-included directories found matching '.git*'
15:05:55 INFO - Installing collected packages: jsonschema, functools32
15:05:55 INFO - Running setup.py install for functools32
15:05:55 INFO - warning: no files found matching '*.txt'
15:05:55 INFO - no previously-included directories found matching 'build'
15:05:55 INFO - no previously-included directories found matching 'dist'
15:05:55 INFO - no previously-included directories found matching '.git*'
15:05:55 INFO - Successfully installed jsonschema functools32
15:05:55 INFO - Cleaning up...
15:05:55 INFO - Return code: 0
15:05:55 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
15:05:55 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:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:05:55 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:05:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:05:55 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-017', 'USERDOMAIN': 'T-W732-IX-017', '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-017', '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:05:56 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:05:56 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:05:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:05:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:05:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:05:56 INFO - 'COMPUTERNAME': 'T-W732-IX-017',
15:05:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:05:56 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:05:56 INFO - 'DCLOCATION': 'SCL3',
15:05:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:05:56 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:05:56 INFO - 'HOMEDRIVE': 'C:',
15:05:56 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:05:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:05:56 INFO - 'KTS_VERSION': '1.19c',
15:05:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:05:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-017',
15:05:56 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:05:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:05:56 INFO - 'MOZ_NO_REMOTE': '1',
15:05:56 INFO - 'NO_EM_RESTART': '1',
15:05:56 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:05:56 INFO - 'OS': 'Windows_NT',
15:05:56 INFO - 'OURDRIVE': 'C:',
15:05:56 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:05:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:05:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:05:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:05:56 INFO - 'PROCESSOR_LEVEL': '6',
15:05:56 INFO - 'PROCESSOR_REVISION': '1e05',
15:05:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:05:56 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:05:56 INFO - 'PROMPT': '$P$G',
15:05:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:05:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:05:56 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:05:56 INFO - 'PWD': 'C:\\slave\\test',
15:05:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:05:56 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:05:56 INFO - 'SYSTEMDRIVE': 'C:',
15:05:56 INFO - 'SYSTEMROOT': 'C:\\windows',
15:05:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:05:56 INFO - 'TEST1': 'testie',
15:05:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:05:56 INFO - 'USERDOMAIN': 'T-W732-IX-017',
15:05:56 INFO - 'USERNAME': 'cltbld',
15:05:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:05:56 INFO - 'WINDIR': 'C:\\windows',
15:05:56 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:05:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:05:56 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:05:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
15:05:56 INFO - Cleaning up...
15:05:56 INFO - Return code: 0
15:05:56 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
15:05:56 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:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:05:56 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:05:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:05:56 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-017', 'USERDOMAIN': 'T-W732-IX-017', '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-017', '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:05:56 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:05:56 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:05:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:05:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:05:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:05:56 INFO - 'COMPUTERNAME': 'T-W732-IX-017',
15:05:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:05:56 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:05:56 INFO - 'DCLOCATION': 'SCL3',
15:05:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:05:56 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:05:56 INFO - 'HOMEDRIVE': 'C:',
15:05:56 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:05:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:05:56 INFO - 'KTS_VERSION': '1.19c',
15:05:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:05:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-017',
15:05:56 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:05:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:05:56 INFO - 'MOZ_NO_REMOTE': '1',
15:05:56 INFO - 'NO_EM_RESTART': '1',
15:05:56 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:05:56 INFO - 'OS': 'Windows_NT',
15:05:56 INFO - 'OURDRIVE': 'C:',
15:05:56 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:05:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:05:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:05:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:05:56 INFO - 'PROCESSOR_LEVEL': '6',
15:05:56 INFO - 'PROCESSOR_REVISION': '1e05',
15:05:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:05:56 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:05:56 INFO - 'PROMPT': '$P$G',
15:05:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:05:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:05:56 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:05:56 INFO - 'PWD': 'C:\\slave\\test',
15:05:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:05:56 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:05:56 INFO - 'SYSTEMDRIVE': 'C:',
15:05:56 INFO - 'SYSTEMROOT': 'C:\\windows',
15:05:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:05:56 INFO - 'TEST1': 'testie',
15:05:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:05:56 INFO - 'USERDOMAIN': 'T-W732-IX-017',
15:05:56 INFO - 'USERNAME': 'cltbld',
15:05:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:05:56 INFO - 'WINDIR': 'C:\\windows',
15:05:56 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:05:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:05:56 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:05:56 INFO - Downloading/unpacking blobuploader==1.2.4
15:05:56 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:05:56 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:05:57 INFO - Downloading blobuploader-1.2.4.tar.gz
15:05:57 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
15:05:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
15:05:57 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:05:57 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:05:58 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
15:05:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
15:05:58 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:05:58 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:05:58 INFO - Downloading docopt-0.6.1.tar.gz
15:05:58 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
15:05:58 INFO - Installing collected packages: blobuploader, requests, docopt
15:05:58 INFO - Running setup.py install for blobuploader
15:05:59 INFO - Running setup.py install for requests
15:05:59 INFO - Running setup.py install for docopt
15:05:59 INFO - Successfully installed blobuploader requests docopt
15:05:59 INFO - Cleaning up...
15:06:01 INFO - Return code: 0
15:06:01 INFO - Installing None into virtualenv C:\slave\test\build/venv
15:06:01 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:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:06:01 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:06:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:06:01 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-017', 'USERDOMAIN': 'T-W732-IX-017', '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-017', '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:06:01 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:06:01 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:06:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:06:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:06:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:06:01 INFO - 'COMPUTERNAME': 'T-W732-IX-017',
15:06:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:06:01 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:06:01 INFO - 'DCLOCATION': 'SCL3',
15:06:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:06:01 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:06:01 INFO - 'HOMEDRIVE': 'C:',
15:06:01 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:06:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:06:01 INFO - 'KTS_VERSION': '1.19c',
15:06:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:06:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-017',
15:06:01 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:06:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:06:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:06:01 INFO - 'MOZ_NO_REMOTE': '1',
15:06:01 INFO - 'NO_EM_RESTART': '1',
15:06:01 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:06:01 INFO - 'OS': 'Windows_NT',
15:06:01 INFO - 'OURDRIVE': 'C:',
15:06:01 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:06:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:06:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:06:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:06:01 INFO - 'PROCESSOR_LEVEL': '6',
15:06:01 INFO - 'PROCESSOR_REVISION': '1e05',
15:06:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:06:01 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:06:01 INFO - 'PROMPT': '$P$G',
15:06:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:06:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:06:01 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:06:01 INFO - 'PWD': 'C:\\slave\\test',
15:06:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:06:01 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:06:01 INFO - 'SYSTEMDRIVE': 'C:',
15:06:01 INFO - 'SYSTEMROOT': 'C:\\windows',
15:06:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:06:01 INFO - 'TEST1': 'testie',
15:06:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:06:01 INFO - 'USERDOMAIN': 'T-W732-IX-017',
15:06:01 INFO - 'USERNAME': 'cltbld',
15:06:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:06:01 INFO - 'WINDIR': 'C:\\windows',
15:06:01 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:06:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:06:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:06:01 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:06:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
15:06:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qhv1dr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
15:06:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
15:06:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-np4rhi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
15:06:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
15:06:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yif_ew-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
15:06:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
15:06:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nuvvmu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
15:06:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
15:06:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-adn_dh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
15:06:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
15:06:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ahdnrf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
15:06:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
15:06:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1vjobi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
15:06:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
15:06:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bi1elj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
15:06:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
15:06:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rdpcap-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
15:06:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
15:06:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bm7at0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
15:06:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
15:06:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lof3zs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
15:06:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
15:06:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-31r6ye-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
15:06:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
15:06:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gbwew5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
15:06:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
15:06:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y0kl4i-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
15:06:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
15:06:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fzw7ca-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
15:06:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
15:06:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rcidps-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
15:06:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
15:06:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rynwsa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
15:06:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
15:06:03 INFO - Running setup.py install for manifestparser
15:06:03 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
15:06:03 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
15:06:04 INFO - Running setup.py install for mozcrash
15:06:04 INFO - Running setup.py install for mozdebug
15:06:04 INFO - Running setup.py install for mozdevice
15:06:05 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
15:06:05 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
15:06:05 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
15:06:05 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
15:06:05 INFO - Running setup.py install for mozfile
15:06:05 INFO - Running setup.py install for mozhttpd
15:06:06 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
15:06:06 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
15:06:06 INFO - Running setup.py install for mozinfo
15:06:06 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
15:06:06 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
15:06:06 INFO - Running setup.py install for mozInstall
15:06:06 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
15:06:06 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
15:06:06 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
15:06:06 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
15:06:06 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
15:06:06 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
15:06:06 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
15:06:06 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
15:06:07 INFO - Running setup.py install for mozleak
15:06:07 INFO - Running setup.py install for mozlog
15:06:07 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
15:06:07 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
15:06:08 INFO - Running setup.py install for moznetwork
15:06:08 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
15:06:08 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
15:06:08 INFO - Running setup.py install for mozprocess
15:06:09 INFO - Running setup.py install for mozprofile
15:06:09 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
15:06:09 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
15:06:09 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
15:06:09 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
15:06:09 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
15:06:09 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
15:06:09 INFO - Running setup.py install for mozrunner
15:06:10 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
15:06:10 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
15:06:10 INFO - Running setup.py install for mozscreenshot
15:06:11 INFO - Running setup.py install for moztest
15:06:11 INFO - Running setup.py install for mozversion
15:06:11 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
15:06:11 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
15:06:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
15:06:11 INFO - Cleaning up...
15:06:11 INFO - Return code: 0
15:06:11 INFO - Installing None into virtualenv C:\slave\test\build/venv
15:06:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:06:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:06:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:06:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:06:11 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-017', 'USERDOMAIN': 'T-W732-IX-017', '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-017', '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:06:12 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:06:12 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:06:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:06:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:06:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:06:12 INFO - 'COMPUTERNAME': 'T-W732-IX-017',
15:06:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:06:12 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:06:12 INFO - 'DCLOCATION': 'SCL3',
15:06:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:06:12 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:06:12 INFO - 'HOMEDRIVE': 'C:',
15:06:12 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:06:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:06:12 INFO - 'KTS_VERSION': '1.19c',
15:06:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:06:12 INFO - 'LOGONSERVER': '\\\\T-W732-IX-017',
15:06:12 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:06:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:06:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:06:12 INFO - 'MOZ_NO_REMOTE': '1',
15:06:12 INFO - 'NO_EM_RESTART': '1',
15:06:12 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:06:12 INFO - 'OS': 'Windows_NT',
15:06:12 INFO - 'OURDRIVE': 'C:',
15:06:12 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:06:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:06:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:06:12 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:06:12 INFO - 'PROCESSOR_LEVEL': '6',
15:06:12 INFO - 'PROCESSOR_REVISION': '1e05',
15:06:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:06:12 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:06:12 INFO - 'PROMPT': '$P$G',
15:06:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:06:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:06:12 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:06:12 INFO - 'PWD': 'C:\\slave\\test',
15:06:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:06:12 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:06:12 INFO - 'SYSTEMDRIVE': 'C:',
15:06:12 INFO - 'SYSTEMROOT': 'C:\\windows',
15:06:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:06:12 INFO - 'TEST1': 'testie',
15:06:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:06:12 INFO - 'USERDOMAIN': 'T-W732-IX-017',
15:06:12 INFO - 'USERNAME': 'cltbld',
15:06:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:06:12 INFO - 'WINDIR': 'C:\\windows',
15:06:12 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:06:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:06:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:06:12 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:06:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
15:06:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6qnr8v-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
15:06:12 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:06:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
15:06:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gicfs5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
15:06:12 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:06:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
15:06:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cwiyj0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
15:06:12 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:06:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
15:06:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qycxsw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
15:06:13 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:06:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
15:06:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w6qdfx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
15:06:13 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:06:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
15:06:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f3bfxl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
15:06:13 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:06:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
15:06:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8txneu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
15:06:13 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:06:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
15:06:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ydqpbh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
15:06:13 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:06:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
15:06:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-21kfrt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
15:06:13 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:06:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
15:06:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-huijkr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
15:06:13 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:06:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
15:06:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0jujsb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
15:06:13 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:06:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
15:06:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mww7h8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
15:06:14 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:06:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
15:06:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cbeusc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
15:06:14 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:06:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
15:06:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gpthca-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
15:06:14 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:06:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
15:06:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dhb7ra-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
15:06:14 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:06:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
15:06:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kalegh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
15:06:14 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:06:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
15:06:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wlzjmj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
15:06:14 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:06:14 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:06:14 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:06:14 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:06:14 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:06:14 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:06:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
15:06:14 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:06:14 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:06:15 INFO - Downloading blessings-1.6.tar.gz
15:06:15 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
15:06:16 INFO - Installing collected packages: blessings
15:06:16 INFO - Running setup.py install for blessings
15:06:16 INFO - Successfully installed blessings
15:06:16 INFO - Cleaning up...
15:06:19 INFO - Return code: 0
15:06:19 INFO - Done creating virtualenv C:\slave\test\build/venv.
15:06:19 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
15:06:19 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
15:06:19 INFO - Reading from file tmpfile_stdout
15:06:19 INFO - Using _rmtree_windows ...
15:06:19 INFO - Using _rmtree_windows ...
15:06:19 INFO - Current package versions:
15:06:19 INFO - blessings == 1.6
15:06:19 INFO - blobuploader == 1.2.4
15:06:19 INFO - docopt == 0.6.1
15:06:19 INFO - functools32 == 3.2.3-2
15:06:19 INFO - jsonschema == 2.5.1
15:06:19 INFO - manifestparser == 1.1
15:06:19 INFO - mozInstall == 1.12
15:06:19 INFO - mozcrash == 0.17
15:06:19 INFO - mozdebug == 0.1
15:06:19 INFO - mozdevice == 0.48
15:06:19 INFO - mozfile == 1.2
15:06:19 INFO - mozhttpd == 0.7
15:06:19 INFO - mozinfo == 0.9
15:06:19 INFO - mozleak == 0.1
15:06:19 INFO - mozlog == 3.4
15:06:19 INFO - moznetwork == 0.27
15:06:19 INFO - mozprocess == 0.23
15:06:19 INFO - mozprofile == 0.28
15:06:19 INFO - mozrunner == 6.12
15:06:19 INFO - mozscreenshot == 0.1
15:06:19 INFO - mozsystemmonitor == 0.3
15:06:19 INFO - moztest == 0.7
15:06:19 INFO - mozversion == 1.4
15:06:19 INFO - psutil == 3.1.1
15:06:19 INFO - requests == 1.2.3
15:06:19 INFO - Installing None into virtualenv C:\slave\test\build/venv
15:06:19 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:06:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:06:19 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:06:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:06:19 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-017', 'USERDOMAIN': 'T-W732-IX-017', '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-017', '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:06:19 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:06:19 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:06:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:06:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:06:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:06:19 INFO - 'COMPUTERNAME': 'T-W732-IX-017',
15:06:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:06:19 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:06:19 INFO - 'DCLOCATION': 'SCL3',
15:06:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:06:19 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:06:19 INFO - 'HOMEDRIVE': 'C:',
15:06:19 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:06:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:06:19 INFO - 'KTS_VERSION': '1.19c',
15:06:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:06:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-017',
15:06:19 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:06:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:06:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:06:19 INFO - 'MOZ_NO_REMOTE': '1',
15:06:19 INFO - 'NO_EM_RESTART': '1',
15:06:19 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:06:19 INFO - 'OS': 'Windows_NT',
15:06:19 INFO - 'OURDRIVE': 'C:',
15:06:19 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:06:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:06:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:06:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:06:19 INFO - 'PROCESSOR_LEVEL': '6',
15:06:19 INFO - 'PROCESSOR_REVISION': '1e05',
15:06:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:06:19 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:06:19 INFO - 'PROMPT': '$P$G',
15:06:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:06:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:06:19 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:06:19 INFO - 'PWD': 'C:\\slave\\test',
15:06:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:06:19 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:06:19 INFO - 'SYSTEMDRIVE': 'C:',
15:06:19 INFO - 'SYSTEMROOT': 'C:\\windows',
15:06:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:06:19 INFO - 'TEST1': 'testie',
15:06:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:06:19 INFO - 'USERDOMAIN': 'T-W732-IX-017',
15:06:19 INFO - 'USERNAME': 'cltbld',
15:06:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:06:19 INFO - 'WINDIR': 'C:\\windows',
15:06:19 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:06:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:06:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:06:19 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:06:19 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:06:19 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:06:19 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:06:19 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:06:19 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:06:19 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:06:19 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:06:19 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:06:19 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:06:19 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:06:19 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:06:19 INFO - Cleaning up...
15:06:19 INFO - Return code: 0
15:06:19 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
15:06:19 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:06:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:06:19 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:06:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:06:19 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-017', 'USERDOMAIN': 'T-W732-IX-017', '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-017', '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:06:19 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:06:19 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:06:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:06:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:06:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:06:19 INFO - 'COMPUTERNAME': 'T-W732-IX-017',
15:06:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:06:19 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:06:19 INFO - 'DCLOCATION': 'SCL3',
15:06:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:06:19 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:06:19 INFO - 'HOMEDRIVE': 'C:',
15:06:19 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:06:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:06:19 INFO - 'KTS_VERSION': '1.19c',
15:06:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:06:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-017',
15:06:19 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:06:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:06:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:06:19 INFO - 'MOZ_NO_REMOTE': '1',
15:06:19 INFO - 'NO_EM_RESTART': '1',
15:06:19 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:06:19 INFO - 'OS': 'Windows_NT',
15:06:19 INFO - 'OURDRIVE': 'C:',
15:06:19 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:06:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:06:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:06:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:06:19 INFO - 'PROCESSOR_LEVEL': '6',
15:06:19 INFO - 'PROCESSOR_REVISION': '1e05',
15:06:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:06:19 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:06:19 INFO - 'PROMPT': '$P$G',
15:06:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:06:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:06:19 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:06:19 INFO - 'PWD': 'C:\\slave\\test',
15:06:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:06:19 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:06:19 INFO - 'SYSTEMDRIVE': 'C:',
15:06:19 INFO - 'SYSTEMROOT': 'C:\\windows',
15:06:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:06:19 INFO - 'TEST1': 'testie',
15:06:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:06:19 INFO - 'USERDOMAIN': 'T-W732-IX-017',
15:06:19 INFO - 'USERNAME': 'cltbld',
15:06:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:06:19 INFO - 'WINDIR': 'C:\\windows',
15:06:19 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:06:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:06:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:06:20 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:06:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
15:06:20 INFO - Cleaning up...
15:06:20 INFO - Return code: 0
15:06:20 INFO - Running post-action listener: _resource_record_post_action
15:06:20 INFO - Running post-action listener: _start_resource_monitoring
15:06:20 INFO - Starting resource monitoring.
15:06:20 INFO - [mozharness: 2016-10-23 22:06:20.523000Z] Finished create-virtualenv step (success)
15:06:20 INFO - [mozharness: 2016-10-23 22:06:20.523000Z] Running install step.
15:06:20 INFO - Running pre-action listener: _resource_record_pre_action
15:06:20 INFO - Running main action method: install
15:06:20 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
15:06:20 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
15:06:20 INFO - Reading from file tmpfile_stdout
15:06:20 INFO - Using _rmtree_windows ...
15:06:20 INFO - Using _rmtree_windows ...
15:06:20 INFO - Detecting whether we're running mozinstall >=1.0...
15:06:20 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
15:06:20 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
15:06:20 INFO - Reading from file tmpfile_stdout
15:06:20 INFO - Output received:
15:06:20 INFO - Usage: mozinstall-script.py [options] installer
15:06:20 INFO - Options:
15:06:20 INFO - -h, --help show this help message and exit
15:06:20 INFO - -d DEST, --destination=DEST
15:06:20 INFO - Directory to install application into. [default:
15:06:20 INFO - "C:\slave\test"]
15:06:20 INFO - --app=APP Application being installed. [default: firefox]
15:06:20 INFO - Using _rmtree_windows ...
15:06:20 INFO - Using _rmtree_windows ...
15:06:20 INFO - mkdir: C:\slave\test\build\application
15:06:20 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:06:20 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:06:23 INFO - Reading from file tmpfile_stdout
15:06:23 INFO - Output received:
15:06:23 INFO - C:\slave\test\build\application\firefox\firefox.exe
15:06:23 INFO - Using _rmtree_windows ...
15:06:23 INFO - Using _rmtree_windows ...
15:06:23 INFO - Running post-action listener: _resource_record_post_action
15:06:23 INFO - [mozharness: 2016-10-23 22:06:23.342000Z] Finished install step (success)
15:06:23 INFO - [mozharness: 2016-10-23 22:06:23.342000Z] Running run-tests step.
15:06:23 INFO - Running pre-action listener: _resource_record_pre_action
15:06:23 INFO - Running main action method: run_tests
15:06:23 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
15:06:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
15:06:23 INFO - Python 2.7.3
15:06:23 INFO - Return code: 0
15:06:23 INFO - grabbing minidump binary from tooltool
15:06: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:06:23 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 0x01912E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
15:06:23 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:06:23 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:06:23 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:06:23 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
15:06:24 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpxpitxz
15:06:24 INFO - INFO - File integrity verified, renaming tmpxpitxz to win32-minidump_stackwalk.exe
15:06:24 INFO - Return code: 0
15:06:24 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
15:06:24 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
15:06:24 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
15:06:24 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
15:06:24 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
15:06:24 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', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-017', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] in C:\slave\test\build
15:06:24 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 --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-017 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log
15:06:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:06:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:06:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:06:24 INFO - 'COMPUTERNAME': 'T-W732-IX-017',
15:06:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:06:24 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:06:24 INFO - 'DCLOCATION': 'SCL3',
15:06:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:06:24 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:06:24 INFO - 'HOMEDRIVE': 'C:',
15:06:24 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:06:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:06:24 INFO - 'KTS_VERSION': '1.19c',
15:06:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:06:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-017',
15:06:24 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
15:06:24 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
15:06:24 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:06:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:06:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:06:24 INFO - 'MOZ_NO_REMOTE': '1',
15:06:24 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
15:06:24 INFO - 'NO_EM_RESTART': '1',
15:06:24 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:06:24 INFO - 'OS': 'Windows_NT',
15:06:24 INFO - 'OURDRIVE': 'C:',
15:06:24 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:06:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:06:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:06:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:06:24 INFO - 'PROCESSOR_LEVEL': '6',
15:06:24 INFO - 'PROCESSOR_REVISION': '1e05',
15:06:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:06:24 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:06:24 INFO - 'PROMPT': '$P$G',
15:06:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:06:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:06:24 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:06:24 INFO - 'PWD': 'C:\\slave\\test',
15:06:24 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
15:06:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:06:24 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:06:24 INFO - 'SYSTEMDRIVE': 'C:',
15:06:24 INFO - 'SYSTEMROOT': 'C:\\windows',
15:06:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:06:24 INFO - 'TEST1': 'testie',
15:06:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:06:24 INFO - 'USERDOMAIN': 'T-W732-IX-017',
15:06:24 INFO - 'USERNAME': 'cltbld',
15:06:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:06:24 INFO - 'WINDIR': 'C:\\windows',
15:06:24 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:06:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:06:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:06:24 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-017', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] with output_timeout 3600
15:06:25 INFO - mozversion application_buildid: 20161023123006
15:06:25 INFO - mozversion application_changeset: a9a41b69f3f9be494ae0653601c08aa388d9afe4
15:06:25 INFO - mozversion application_display_name: Nightly
15:06:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
15:06:25 INFO - mozversion application_name: Firefox
15:06:25 INFO - mozversion application_remotingname: firefox
15:06:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
15:06:25 INFO - mozversion application_vendor: Mozilla
15:06:25 INFO - mozversion application_version: 52.0a1
15:06:25 INFO - mozversion platform_buildid: 20161023123006
15:06:25 INFO - mozversion platform_changeset: a9a41b69f3f9be494ae0653601c08aa388d9afe4
15:06:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
15:06:25 INFO - mozversion platform_version: 52.0a1
15:06:25 INFO - using testdate: 1477260385
15:06:25 INFO - actual date: 1477260385
15:06:25 INFO - starting webserver on 'localhost:49896'
15:06:25 INFO - SUITE-START | Running 2 tests
15:06:25 INFO - TEST-START | dromaeo_css
15:06:25 INFO - operating with platform_type : w7_
15:06:25 INFO - Initialising browser for dromaeo_css test...
15:06:25 INFO - TEST-INFO | started process 3064 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp_fprpu\profile http://localhost:49896/getInfo.html)
15:06:34 INFO - PROCESS | 3064 | __metrics Screen width/height:1600/1200
15:06:34 INFO - PROCESS | 3064 | colorDepth:24
15:06:34 INFO - PROCESS | 3064 | Browser inner width/height: 1010/674
15:06:34 INFO - PROCESS | 3064 | __metrics
15:06:34 INFO - PROCESS | 3064 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
15:06:34 INFO - PROCESS | 3064 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
15:06:36 INFO - TEST-INFO | 3064: exit 0
15:06:36 INFO - Browser initialized.
15:06:36 INFO - Running cycle 1/1 for dromaeo_css test...
15:06:36 INFO - TEST-INFO | started process 3328 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp_fprpu\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
15:06:36 INFO - PROCESS | 3328 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
15:06:47 INFO - PROCESS | 3328 | RSS: Main: 123224064
15:06:47 INFO - PROCESS | 3328 |
15:07:38 INFO - PROCESS | 3328 | 1477260458475 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:09:49 INFO - PROCESS | 3328 | Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49896/tests/dromaeo/cssquery-ext.html)
15:09:51 INFO - PROCESS | 3328 | RSS: Main: 257441792
15:09:51 INFO - PROCESS | 3328 |
15:12:54 INFO - PROCESS | 3328 | Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-ext.html (next: http://localhost:49896/tests/dromaeo/cssquery-jquery.html)
15:12:54 INFO - PROCESS | 3328 | RSS: Main: 200097792
15:12:54 INFO - PROCESS | 3328 |
15:15:56 INFO - PROCESS | 3328 | Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49896/tests/dromaeo/cssquery-mootools.html)
15:15:57 INFO - PROCESS | 3328 | RSS: Main: 139190272
15:15:57 INFO - PROCESS | 3328 |
15:18:59 INFO - PROCESS | 3328 | Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49896/tests/dromaeo/cssquery-prototype.html)
15:19:00 INFO - PROCESS | 3328 | RSS: Main: 139296768
15:19:00 INFO - PROCESS | 3328 |
15:22:02 INFO - PROCESS | 3328 | Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49896/tests/dromaeo/cssquery-yui.html)
15:22:03 INFO - PROCESS | 3328 | RSS: Main: 135041024
15:22:03 INFO - PROCESS | 3328 |
15:25:06 INFO - PROCESS | 3328 | Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-yui.html (next: http://localhost:49896/tests/dromaeo/cssquery-dojo.html)
15:25:06 INFO - PROCESS | 3328 | RSS: Main: 184131584
15:25:06 INFO - PROCESS | 3328 |
15:25:06 INFO - PROCESS | 3328 | __start_tp_report
15:25:06 INFO - PROCESS | 3328 | _x_x_mozilla_page_load
15:25:06 INFO - PROCESS | 3328 | _x_x_mozilla_page_load_details
15:25:06 INFO - PROCESS | 3328 | |i|pagename|runs|
15:25:06 INFO - PROCESS | 3328 | |0;dojo.html;2745.7254274572547;2752.6283951666524;2777.0974433309166;2788.3865549579086;2799.3561480859403;4776.068666610008;4788.59296959758;4866.537678920506;4871.975640121804;4893.3638626978445;4742.90514189716;4748.810047598092;4753.738544380058;4827.324174615556;4852.708837469754;4192.308269135589;4246.808893599796;4260.424842646242;4282.486101667804;4285.271503844354;3928.489296391465;4218.346156345771;4221.936670949938;4277.807498662568;4288.506821715511;4218.430511880888;4240.639545638609;4259.957400426003;4282.807273672693;4291.871243862689;4035.1727895781296;4074.30736774749;4182.079942412664;4265.488141423018;4324.070324880166;4115.485564304462;4155.97922010388;4162.250794856925;4224.9155016899795;4261.4673165854265;4702.13010593038;4766.213574760161;4780.665353425262;4788.186008378584;4817.7591120444185;4937.851864444036;5018.197088465864;5020.171671674169;5075.086484432801;5126;4963.4292056413615;5037.992401519711;5064.873378165538;5102.489751024905;5164.14791559396;4597.563231493003;4677.204875171195;4677.2750223715375;4683.765811709401;4712.64655150865;5087.796488140433;5099.847004589868;5173.042987047408;5198.908229271895;5201.3238279024245;4841.225403935424;4862.708237505761;4877.609791216765;4980.302757613937;4988.800447982113;4992.176290912032;5036.345275114211;5040.84877453677;5042.672226305279;5044.965782014679;4978.626603004789;4992.375953005874;4995.525425084611;5003.749812509433;5023.547880690755;5005.599552035828;5013.473585273567;5014.971930754186;5018.422881368616;5021.72380519075;3370.9325813484083;3371.443711787577;3374.0383990563064;3378.5101160331615;3382.746294027958;3026.273694313349;3046.603941487637;3103.115612050555;3104.9379012419627;3110.206897241189;4768.237082066853;4905.141600219948;4907.362042934467;4913.336699545587;4917.9918116785975;4981;5031.270465782441;5046.091703493406;5138.794448222029;5187.377514698261;4568.771561421916;4791.0178413425165;4929.75351232437;4936.160852654985;4947.208446648519;4096.201240758083;4178.247915375262;4193.706440549132;4215.641670458045;4249.702520823513;5169.663971841818;5272.2355258487305;5454.972725136349;5472.2338872557075;5473.644213126134;5010.473900240568;5063.696178229171;5108.978204359218;5170.819021334235;5187.92218116721;5251.291075704731;5327.094393953114;5350.759215835375;5460.453954604508;5467.043267428105;4842.661013729005;4951.579115775199;4965.280034395065;5018.4730603286125;5075.87310317245;4904.558589726942;5067.037262920034;5070.949290507196;5174.870628234325;5176.4047134580005;4927.531884470969;4928.482509336612;5001.399832020181;5025.271335656383;5026.095302845377;4900.534449227461;4916.483769204325;4931.457539670561;4949.430815456269;4950.579200767975;4698.92951605733;4776.188048031773;4815.277708343777;4856.223004319292;4861.076395484846;4943.802247910187;4979.228219625964;4984.02811451778;4996.175631020987;4997.650164488451;4919.557239848431;4921.753912304299;4929.260610908393;4931.580815630711;4940.456549779593
15:25:06 INFO - PROCESS | 3328 | |1;ext.html;540.7701726766124;569.606971189879;584.1938125386963;586.1120402590077;593.8099808061422;28431.573526397096;30018.399632007375;37452.8764137076;40436;41097.79451102748;38579.421308680394;39583.60416395842;39760.40359394612;40069.198616027665;40102.39846402306;7724.073111226647;7812.414068944823;7815.335696465807;7842;7855.450118491708;6440.4847612190915;6556.114924485181;7718.421118416113;9384.671536496224;9646.180074693659;9598.520073996307;9752.17106545941;9814.263930205227;9826.950865245664;9840.212782977345;9733.610655573768;9802.068803463659;9843.163331116864;9848.162906152986;9866.45734484603;6266.185395898549;6446.419822215976;6644.3389152711825;9152.679656212067;9483.288753343459;36370.8181459091;36611;36966.07584810374;37009.81495092534;37086.629133708855;2983.8209707417623;3028.21266470717;3053.190904410333;3094.9381012379627;3634.4003239465455;29160;29162.270943226587;29410.705892941012;30048.399032019457;30106;3445.6735447738793;3502.6497350265026;3503.842327095261;3517.402041652925;3558.092686364986;291521;316888;317174;317350;320165.9999999977;48491.75754121207;51704.48295517072;51811.48188518142;51817.7409112952;52067;54190.45809541933;54271.18593221105;54327;54366.72816635892;54399.45600544023;13682.52111176104;14067.859321406655;14072;14087.22520261375;14111.082779619292;9166.17504424605;10546.26176167661;10729.356238625707;10736.731581710363;10772.9765672261;13817.861821381859;15428;15701;15924.68150637004;16418.917905410635;6041.72812223451;6121.112438696364;6137.846553836101;6150.630962142196;6154.169187159766;769.9730509432143;796.2077732655929;806.1253539909264;809.6235250608378;935.9470595579972;26924.326891827863;27259.591106133423;27286.197295147413;27882.16353509398;28033.159005229874;867.0895559662455;876.2945828608032;887.933404994628;895.1227796759082;973.1096047116808;13323.267220302783;13650.522231721841;14041.648958775908;14262.786058209134;14364.928175359266;418664.06679665897;425319;426856;431823;434696;47365.76317118392;47856.76071619619;48102.27846582304;48173.75913120412;48176.03647927022;983.2822039910905;1002.7142264454595;1084.3006260961793;1114.6878873915316;1117.083991127284;27305.863470682518;28281.43437131287;28300.71699282981;28788;28949.51282551208;40190.1961960765;41754.79122604368;41771.582284176766;42148.94627634334;42254.366184507875;323853;393278;394430;395158;465031;22364.552708946056;23305.76694233036;23373.181938632068;23414.180503682972;23441.476304040865;42242.78878605587;42442.36336454894;42557.57442425536;42711.57288427076;43169.568304317814;35257.82371088128;36023.459648105825;36691.26617467689;36920.81539592285;37785.1986642407;34924.47613285854;35570.822145889106;35736.28527429489;36622.26755464929;37597.436038458894;33721.49417758785;34140.487892681136;34515.654843451244;34755.30489390148;34768.130796730286;31490.212744681565;32405;32764.67235327616;33247.66752332446;33502.66497334996;24670.876645616656;24732;25210.74789252084;25341.493170136124;25757.871210643825
15:25:06 INFO - PROCESS | 3328 | |2;jquery.html;297.49722968183784;316.6137312478776;317.2845233997336;321.5498302376673;321.6028205166619;12633.178843375174;16262.51212463625;16896;17207.56981075472;17525.474235772916;16657.750133748003;16725.247363868624;16764.06628839951;17198.74201886973;17265.30938762448;5108.106081435743;5312.521873031426;5315.681059136445;5321.148616221405;5326.067938110835;4300.333448315516;5183.844484665466;6042.335343112254;6061.727222274986;6148.477379422754;5995.4304341087745;6068.180795592605;6083.117947897552;6122.0816877468515;6122.142899994004;5081.872953176163;5315.44187860273;5333.306670132897;5401.567874570025;6018.097285407181;5220.425753167148;5220.634555581111;5221.843344699665;5224.242484839677;5240.187770895517;11327.320360778298;18621.69648124632;18931.90534047321;21435.24976625826;21489.78510214909;1771.8671099667577;1822.9908850455663;1928.3829174663972;2028.7768345482132;2240.2943072931976;8912.8663070054;8967.282617390592;9168;9451.054894510562;9535.618575256982;1648.5713714434214;1697.2532085882171;1711.5977745229861;1751.2732216130291;1781.145050375814;77785.61107194485;80701.59649201775;80808.19191808066;84509;85563.57218213931;9206.26349892007;9447.716568502956;9565.90434095664;9729.173020293354;9799.951000244953;10931.196352601732;11283.887161128448;11727.4136293185;12458.626241212778;12535.623931282076;7114.466415018895;7271.490995630255;7327;7340.522866013692;7420.220876807857;7303.561786292839;7332.963335183396;7358.595277259802;7399.7410090647645;7468.551886886804;3876.5541962674088;3927.4108883667677;3950.288947989332;4027.436158937751;4031.657309128757;1586.1831156954179;1616.0465325458035;1618.902865828054;1635.0925236493622;1649.2495914358938;757.4205732614554;787.2560430393338;792.4532072869702;817.8159914019298;880.8326417980563;17899.463016109537;17929.910350448165;18558.44324670262;18673.253069877053;18714.064296785105;800.6877317846045;831.4844796226379;835.8161204534993;853.6542700206427;921.5438358012826;9643.746312979409;10161.644342447978;10177.745556361151;10194.592216311414;10237.078662920372;388293;388895;393379;397457;400576;92915.53542232381;95251;95870;96043;96380.51809740906;874.1433395272668;890.123228619809;906.4063038709655;908.0556221529536;908.6320040383655;16082.67834643324;16141.59646008859;16192.433264835674;16264;16343.182840857671;43469.34795978126;43853;44315;45201.54798451973;45221;56809.31906809286;59395.40604593899;60482;66304.396730571;67605.66197168983;6205.28639206479;6586.70359833799;6639.767608133692;6778.423833974168;6788.830279243059;10391.740206494898;12358.146278058362;12545.686357841127;12675;12747.490100396251;10420.635277765545;10483.842742359024;10585.364878107339;10640.946795266284;10663.146948243966;10391.37651740898;10498.160147188359;10595.009596928798;10635.787284254426;10809.13526917829;10181.33821301613;10419.895801042196;10645.361278323016;10792;11094.278871873303;6447.548671592944;6482.935170648233;6664.300248473841;6755.87190035157;6760.966195168992;9216.354855160344;10478.738031549272;10549.630762923261;10654.467276635982;10696.14430845515
15:25:06 INFO - PROCESS | 3328 | |3;mootools.html;1094.2340361746778;1168;1200.249843847595;1246.8690787467315;1480.9555713328598;6454.419102280794;7017.473689473284;7113.075300210965;7190.964045179773;7408.111026676793;7049.788506344805;7221.638918054103;7268.164161121473;7307.817304567388;7337.26627337266;1887.197940875126;1927.3832373640441;1928.315448015955;1930.054273406035;1937.4575118966711;1878.2205384765307;1900.2873922279146;1909.1408866010281;1929.556202073524;1932.0243277145091;1898.1173754204292;1914.2056046740656;1923.3556758485925;1928.951776205592;1929.2283086765267;1792.2920446423655;1812.3475548802421;1830.6155707301482;1833.1475863723433;1834.6973648709431;1844.6126313474188;1866.5986812835306;1868.9532761681066;1870.4762666453414;1872.092035362848;6966.3730264276455;6981.581105133658;7047.64761761915;7151.642417879085;7323.157836848777;4357.520672725997;4435.401220835178;4456.955430445686;4465.240909045438;4467.843625473125;2839.9716002839914;2951.74910132639;2994.9101526954228;3002.9699703003125;3009.593704849839;902.4224496322358;912.4434095201922;920.696170263818;930.0467021303151;936.3913456253422;9474.005229450877;9490.762730931712;9494.24046076319;9499.810003799885;9585.472798996052;3515.4199557072798;3531.9823400882833;3672.59601443841;3679.3745063339297;3708.2398108387715;7393.667284972189;7475.0656167979005;7555.24447555251;7564.886526702104;7567.962160189274;4219.113986062961;4254.213230443222;4264.125854199943;4287.442632457823;4289.6139347458875;3630.6006339302658;3746.7939263340777;4038.353863381845;4060.4112403701297;4061.878143655695;2889.061055157082;2979.9702002980125;2984.686607906182;2991.341904780945;3023.727864492206;895.3016647015338;902.6479672927563;912.7946212102224;914.5335878701777;915.8214148241029;764.2853931573997;776.97669069928;784.262792974602;785.9821531117217;804.6379129391797;4700.9059818803435;4878.463369029404;4928.8767780805765;4933.703977761345;4938.25926111079;786.4298383671907;796.4902462424053;799.1050023973187;805.0701439836997;807.1524898856178;3314.105191598255;3337.098983274502;3384.9492257616157;3401.9964110587434;3406.693397594179;8619.956900215459;8806.603702833389;8859.291256699578;8880.600372983232;8961.507117108622;8733.301335893113;8760.25537829279;8818.77953051166;8822.86765698515;8843.0714774949;6917.827054323682;6958.930410695828;6974.546654467444;6983.266756990544;6996.965015174993;4616.792244348945;4711.1519926413575;4748.928766068442;4876;4944;8287;8337.499750015018;8400.789980250544;8437.451565648213;8439;8369.372297077622;8412.87380689278;8492.830143396974;8501.95749021251;8548.615312311085;5848.64908105515;5895.469407753323;5907.232059832194;5936.940630593639;5957.136215248851;6384.6169229846355;6456.774012909714;6768.593884366954;6878.621675807678;6885.655717214219;6277.12120303167;6341.175647165839;6370.235571731422;6377.1390862233;6383.616982981036;6173.259208894781;6215.335153835828;6406.775762848278;6411.455026322628;6468.8059358219325;5692.971535142297;5713.342965559014;5751.079827227623;5846.94153058481;5891.58758886891;4270.124624451918;4274.722143060691;4338.479382473997;4354.499232588166;4366.672499562482;4182.386008744535;4196.223698615766;4252.596003379674;4325.2430824604935;4419.403380543714
15:25:06 INFO - PROCESS | 3328 | |4;prototype.html;251.67156860297314;254.66257209197818;263.794240492416;265.18720122823544;265.6971052999581;5106.310648062515;5211.5309622134;5222.138347172712;5234.8691282717955;5260.921511090222;4881.902361952759;5002.474740152277;5039.218921067231;5097.235414687799;5158.819441319554;3714.628537146291;3798.5821559628557;3813.8855834325013;3817.1602247505502;3826.349520581508;3791.241751649667;3798.601146879579;3815.6184381561898;3819.9045023874346;3820.923581528368;3600.2979419013304;3693.076730817296;3694.3350196964534;3755.380362240227;3796.620337966209;3721.348763966295;3780.319542482352;3785.4700341952284;3787.3182827091114;3792.1846802937503;3283.917902052444;3288.6053673559086;3293.2590167212425;3297.9340413191603;3300.9174770630684;5057.848264552069;5066.214736715815;5072.112380333427;5073.7209453480045;5074.543291103819;4111.280525907984;4370.431843860278;4405.163019026375;4429.563739943019;4441.511433742286;5308.75244317585;5324.520793128599;5333.679979201222;5340.25236466895;5349.893002139975;3378.45944648855;3480.7737497062863;3512.4906888501278;3515.1739341254784;3517.4899639552377;5576.386597474275;5590.692511911843;5622.522085622727;5629.9437005629825;5632.859178520529;3569.821508924543;3587.1749497615697;3590.3716849551224;3593.6765691087926;3598.334308153;3391.236971681352;3542.856571774091;3593.6945359644724;3627.6553727395853;3629.129009037812;4300.505441874225;4303.956960430418;4309.741415515079;4309.784510774449;4316.460442444694;5351.839444816662;5369.3556773187465;5418.701971391612;5420.214068959979;5435.157550579666;1219.1039585904352;1243.6704273367566;1246.4515613130193;1259.8425196850394;1260.577706468325;764.5985857424896;767.7619937819182;775.7711475114854;779.9571023593757;782.6047845837929;4939.456659767423;5244.370675518962;5309.787608495694;5312.203169524602;5358.973205134027;5527.309086364205;5539.750711218005;5548.889022219533;5557.665780372254;5593.049181639131;5258.684478931276;5315.681059136464;5324.547413469821;5331.280277162611;5341.5192632662465;2888.884444622234;2932.677405485395;2933.237358286818;2948.425057113885;2982.030839977016;5791.623544469596;5850.327212370632;5897.2038774765715;5905.173275741399;5916.7337469813965;5563.388027316992;5652.349979752381;5657.151427285858;5661.7735290588735;5671;5666.886662266732;5762.423757624204;5819.108789643383;5931.406859314034;5932.644041357532;5508.476694713996;5577.358603760619;5579.721013949205;5595.377340571189;5601.467860553241;5370.489803468664;5409.0804563223555;5505.174223866452;5520.503154716095;5522.30971128609;5549.472800083986;5907.91138132937;5917.964356237727;5922.466977972003;5923.8152369525405;3622.51476894472;3630.6006339302126;3636.3272794533095;3636.6908812751212;3644.21649345392;5614.157876318585;5687.630304030225;5687.800926967537;5688.3742788294085;5701.173329867229;5350.464953504774;5406.5945054121885;5493.615446918678;5495.340559132929;5504.80733174337;5248.107821670249;5386.48828361305;5462.153366228241;5470.468268884719;5472.972635136799;5246.42289348179;5283.815066472809;5348.197770334481;5348.893022139458;5355.625106242525;5192.350956130484;5390.811321603725;5494.148406996922;5501.559875209893;5516.641418307797;5087.3386459759995;5322.787088516416;5322.920156197738;5326.36083669962;5379.757910894097
15:25:06 INFO - PROCESS | 3328 | |5;yui.html;628.8616504369037;672.6031641331614;688.5662032919262;693.9097917270755;694.6839188169382;13567.932160339196;14436.494722684682;14706.632334191638;14779.408823647069;15095.169765662884;13468.124571902845;13600.727985440286;13657.590272291822;13746.381412836421;13833;501.59120316941755;510.9080365534221;513.9794408223667;601.5608605717829;613.8802933427984;512.8564002079423;515.7782153673919;610.9053096769985;623.3797371615226;631.3339426904614;634.4924060751404;653.1769969838015;659.8251463362213;660.8248814064277;661.8279247395687;616.5992105131633;642.7621779941405;650.9251436084863;657.4904449051975;658.4271683635244;375.8271195250188;386.4454507781343;466.7316293131463;470.9740964246965;472.1477732692489;13387.665308367272;13732.45070197191;13742.381592828347;13764.242966636832;13833.308334583331;12882.291473968928;13058.34708264583;13129.540466083738;13546.661333466742;13659.658508537266;12740.36298185087;13041.543545975941;13191.538296159684;13242.139260948008;13402.46390144393;4227.577242275778;4239.342901850218;4285.2072366611965;4339.023719663082;4432.49026361966;75668.62165689192;76935.23064769393;78954.21045789469;78959.60520197419;79558.60220698918;10246;10300.639477618248;10316.587336506605;10370.792584148276;10471.68584942453;18095;18313.175907084213;18408.17163227658;18520.073996300132;18528.536786580444;7054.012438258648;7136.857262854714;7226.602536860418;7234.16807067194;7620.504667196726;3882.6893848492055;4009.599040095967;4036.555978842325;4074.246264441088;4126.216018956388;3020.5771192033135;3046.314579219702;3092.582501362332;3128.46816041269;3133.060081975398;554.1881144123878;558.480613029878;564.912438572022;572.7565784541553;575.3412342867393;590.9349971503132;653.5033374635217;717.2146499582948;719.679742514576;720.1358369956074;9098.363114581916;9467.952660236793;9630.951845240868;9779.706608801747;9992.65025724096;576.9394213607511;631.0628716356207;708.2810946888912;708.6952610377484;709.7941596936934;4487.237169681162;4607.470140933769;4669.673122881366;4719.787609557578;4750.572448479585;106317;108739.45630271798;111985;113052;114644;83588.58205708933;83931.58034209789;92589.53705231566;92818;97634;73810.630946846;74490.25509744942;76372.61813691007;77227;79396.030198491;9026.684066057656;9237.58430870599;9400.34197606161;9990;10124.13944814699;48324.27513587229;48523.5147648519;48663.513364865896;49120.2631960528;49709.75145124251;62567.374326256155;65948.67025664842;66062.33937660563;66616.66691666511;67920.32079679337;12335;12742.10805243624;12807.48770049225;13194.604161875159;13221.537246196336;15125;15129.924350378178;15170.393184272505;15207.467738629095;15531.767023494882;13786.793198101836;14064.917298843427;14084.436622534984;14184.219867906952;14582.4896128635;11737.941310293394;13526.25605591702;13607.455701771818;13748.793768093286;14181.787273190705;13744.518941836988;13906.58280251594;14263.144211347353;14535.709285814013;14584.343704533534;11981.940090299493;12064.75870482568;12158.331291779037;12212.32832194238;12522.436490358137;11426.43391521197;11977.281363118242;12027.879721203026;12028.398580070787;12091.153619246568
15:25:06 INFO - PROCESS | 3328 | __end_tp_report
15:25:06 INFO - PROCESS | 3328 | __start_cc_report
15:25:06 INFO - PROCESS | 3328 | _x_x_mozilla_cycle_collect,2123
15:25:06 INFO - PROCESS | 3328 | __end_cc_report
15:25:06 INFO - PROCESS | 3328 | __startTimestamp1477261506339__endTimestamp
15:25:06 INFO - PROCESS | 3328 |
15:25:06 INFO - PROCESS | 3328 | ------- Summary: start -------
15:25:06 INFO - PROCESS | 3328 | Number of tests: 6
15:25:06 INFO - PROCESS | 3328 |
15:25:06 INFO - PROCESS | 3328 | [#0] dojo.html Cycles:165 Average:4668.50 Median:4916.48 stddev:610.92 (12.4%) stddev-sans-first:593.88
15:25:06 INFO - PROCESS | 3328 | Values: 2745.7 2752.6 2777.1 2788.4 2799.4 4776.1 4788.6 4866.5 4872.0 4893.4 4742.9 4748.8 4753.7 4827.3 4852.7 4192.3 4246.8 4260.4 4282.5 4285.3 3928.5 4218.3 4221.9 4277.8 4288.5 4218.4 4240.6 4260.0 4282.8 4291.9 4035.2 4074.3 4182.1 4265.5 4324.1 4115.5 4156.0 4162.3 4224.9 4261.5 4702.1 4766.2 4780.7 4788.2 4817.8 4937.9 5018.2 5020.2 5075.1 5126.0 4963.4 5038.0 5064.9 5102.5 5164.1 4597.6 4677.2 4677.3 4683.8 4712.6 5087.8 5099.8 5173.0 5198.9 5201.3 4841.2 4862.7 4877.6 4980.3 4988.8 4992.2 5036.3 5040.8 5042.7 5045.0 4978.6 4992.4 4995.5 5003.7 5023.5 5005.6 5013.5 5015.0 5018.4 5021.7 3370.9 3371.4 3374.0 3378.5 3382.7 3026.3 3046.6 3103.1 3104.9 3110.2 4768.2 4905.1 4907.4 4913.3 4918.0 4981.0 5031.3 5046.1 5138.8 5187.4 4568.8 4791.0 4929.8 4936.2 4947.2 4096.2 4178.2 4193.7 4215.6 4249.7 5169.7 5272.2 5455.0 5472.2 5473.6 5010.5 5063.7 5109.0 5170.8 5187.9 5251.3 5327.1 5350.8 5460.5 5467.0 4842.7 4951.6 4965.3 5018.5 5075.9 4904.6 5067.0 5070.9 5174.9 5176.4 4927.5 4928.5 5001.4 5025.3 5026.1 4900.5 4916.5 4931.5 4949.4 4950.6 4698.9 4776.2 4815.3 4856.2 4861.1 4943.8 4979.2 4984.0 4996.2 4997.7 4919.6 4921.8 4929.3 4931.6 4940.5
15:25:06 INFO - PROCESS | 3328 |
15:25:06 INFO - PROCESS | 3328 | [#1] ext.html Cycles:180 Average:51952.45 Median:37691.32 stddev:101145.36 (268.4%) stddev-sans-first:101355.45
15:25:06 INFO - PROCESS | 3328 | Values: 540.8 569.6 584.2 586.1 593.8 28431.6 30018.4 37452.9 40436.0 41097.8 38579.4 39583.6 39760.4 40069.2 40102.4 7724.1 7812.4 7815.3 7842.0 7855.5 6440.5 6556.1 7718.4 9384.7 9646.2 9598.5 9752.2 9814.3 9827.0 9840.2 9733.6 9802.1 9843.2 9848.2 9866.5 6266.2 6446.4 6644.3 9152.7 9483.3 36370.8 36611.0 36966.1 37009.8 37086.6 2983.8 3028.2 3053.2 3094.9 3634.4 29160.0 29162.3 29410.7 30048.4 30106.0 3445.7 3502.6 3503.8 3517.4 3558.1 291521.0 316888.0 317174.0 317350.0 320166.0 48491.8 51704.5 51811.5 51817.7 52067.0 54190.5 54271.2 54327.0 54366.7 54399.5 13682.5 14067.9 14072.0 14087.2 14111.1 9166.2 10546.3 10729.4 10736.7 10773.0 13817.9 15428.0 15701.0 15924.7 16418.9 6041.7 6121.1 6137.8 6150.6 6154.2 770.0 796.2 806.1 809.6 935.9 26924.3 27259.6 27286.2 27882.2 28033.2 867.1 876.3 887.9 895.1 973.1 13323.3 13650.5 14041.6 14262.8 14364.9 418664.1 425319.0 426856.0 431823.0 434696.0 47365.8 47856.8 48102.3 48173.8 48176.0 983.3 1002.7 1084.3 1114.7 1117.1 27305.9 28281.4 28300.7 28788.0 28949.5 40190.2 41754.8 41771.6 42148.9 42254.4 323853.0 393278.0 394430.0 395158.0 465031.0 22364.6 23305.8 23373.2 23414.2 23441.5 42242.8 42442.4 42557.6 42711.6 43169.6 35257.8 36023.5 36691.3 36920.8 37785.2 34924.5 35570.8 35736.3 36622.3 37597.4 33721.5 34140.5 34515.7 34755.3 34768.1 31490.2 32405.0 32764.7 33247.7 33502.7 24670.9 24732.0 25210.7 25341.5 25757.9
15:25:06 INFO - PROCESS | 3328 |
15:25:06 INFO - PROCESS | 3328 | [#2] jquery.html Cycles:180 Average:25716.72 Median:44084.00 stddev:65959.97 (149.6%) stddev-sans-first:66117.40
15:25:06 INFO - PROCESS | 3328 | Values: 297.5 316.6 317.3 321.5 321.6 12633.2 16262.5 16896.0 17207.6 17525.5 16657.8 16725.2 16764.1 17198.7 17265.3 5108.1 5312.5 5315.7 5321.1 5326.1 4300.3 5183.8 6042.3 6061.7 6148.5 5995.4 6068.2 6083.1 6122.1 6122.1 5081.9 5315.4 5333.3 5401.6 6018.1 5220.4 5220.6 5221.8 5224.2 5240.2 11327.3 18621.7 18931.9 21435.2 21489.8 1771.9 1823.0 1928.4 2028.8 2240.3 8912.9 8967.3 9168.0 9451.1 9535.6 1648.6 1697.3 1711.6 1751.3 1781.1 77785.6 80701.6 80808.2 84509.0 85563.6 9206.3 9447.7 9565.9 9729.2 9800.0 10931.2 11283.9 11727.4 12458.6 12535.6 7114.5 7271.5 7327.0 7340.5 7420.2 7303.6 7333.0 7358.6 7399.7 7468.6 3876.6 3927.4 3950.3 4027.4 4031.7 1586.2 1616.0 1618.9 1635.1 1649.2 757.4 787.3 792.5 817.8 880.8 17899.5 17929.9 18558.4 18673.3 18714.1 800.7 831.5 835.8 853.7 921.5 9643.7 10161.6 10177.7 10194.6 10237.1 388293.0 388895.0 393379.0 397457.0 400576.0 92915.5 95251.0 95870.0 96043.0 96380.5 874.1 890.1 906.4 908.1 908.6 16082.7 16141.6 16192.4 16264.0 16343.2 43469.3 43853.0 44315.0 45201.5 45221.0 56809.3 59395.4 60482.0 66304.4 67605.7 6205.3 6586.7 6639.8 6778.4 6788.8 10391.7 12358.1 12545.7 12675.0 12747.5 10420.6 10483.8 10585.4 10640.9 10663.1 10391.4 10498.2 10595.0 10635.8 10809.1 10181.3 10419.9 10645.4 10792.0 11094.3 6447.5 6482.9 6664.3 6755.9 6761.0 9216.4 10478.7 10549.6 10654.5 10696.1
15:25:06 INFO - PROCESS | 3328 |
15:25:06 INFO - PROCESS | 3328 | [#3] mootools.html Cycles:180 Average:4687.12 Median:5893.53 stddev:2634.75 (44.7%) stddev-sans-first:2628.30
15:25:06 INFO - PROCESS | 3328 | Values: 1094.2 1168.0 1200.2 1246.9 1481.0 6454.4 7017.5 7113.1 7191.0 7408.1 7049.8 7221.6 7268.2 7307.8 7337.3 1887.2 1927.4 1928.3 1930.1 1937.5 1878.2 1900.3 1909.1 1929.6 1932.0 1898.1 1914.2 1923.4 1929.0 1929.2 1792.3 1812.3 1830.6 1833.1 1834.7 1844.6 1866.6 1869.0 1870.5 1872.1 6966.4 6981.6 7047.6 7151.6 7323.2 4357.5 4435.4 4457.0 4465.2 4467.8 2840.0 2951.7 2994.9 3003.0 3009.6 902.4 912.4 920.7 930.0 936.4 9474.0 9490.8 9494.2 9499.8 9585.5 3515.4 3532.0 3672.6 3679.4 3708.2 7393.7 7475.1 7555.2 7564.9 7568.0 4219.1 4254.2 4264.1 4287.4 4289.6 3630.6 3746.8 4038.4 4060.4 4061.9 2889.1 2980.0 2984.7 2991.3 3023.7 895.3 902.6 912.8 914.5 915.8 764.3 777.0 784.3 786.0 804.6 4700.9 4878.5 4928.9 4933.7 4938.3 786.4 796.5 799.1 805.1 807.2 3314.1 3337.1 3384.9 3402.0 3406.7 8620.0 8806.6 8859.3 8880.6 8961.5 8733.3 8760.3 8818.8 8822.9 8843.1 6917.8 6958.9 6974.5 6983.3 6997.0 4616.8 4711.2 4748.9 4876.0 4944.0 8287.0 8337.5 8400.8 8437.5 8439.0 8369.4 8412.9 8492.8 8502.0 8548.6 5848.6 5895.5 5907.2 5936.9 5957.1 6384.6 6456.8 6768.6 6878.6 6885.7 6277.1 6341.2 6370.2 6377.1 6383.6 6173.3 6215.3 6406.8 6411.5 6468.8 5693.0 5713.3 5751.1 5846.9 5891.6 4270.1 4274.7 4338.5 4354.5 4366.7 4182.4 4196.2 4252.6 4325.2 4419.4
15:25:06 INFO - PROCESS | 3328 |
15:25:06 INFO - PROCESS | 3328 | [#4] prototype.html Cycles:180 Average:4473.43 Median:5259.80 stddev:1422.02 (27.0%) stddev-sans-first:1390.25
15:25:06 INFO - PROCESS | 3328 | Values: 251.7 254.7 263.8 265.2 265.7 5106.3 5211.5 5222.1 5234.9 5260.9 4881.9 5002.5 5039.2 5097.2 5158.8 3714.6 3798.6 3813.9 3817.2 3826.3 3791.2 3798.6 3815.6 3819.9 3820.9 3600.3 3693.1 3694.3 3755.4 3796.6 3721.3 3780.3 3785.5 3787.3 3792.2 3283.9 3288.6 3293.3 3297.9 3300.9 5057.8 5066.2 5072.1 5073.7 5074.5 4111.3 4370.4 4405.2 4429.6 4441.5 5308.8 5324.5 5333.7 5340.3 5349.9 3378.5 3480.8 3512.5 3515.2 3517.5 5576.4 5590.7 5622.5 5629.9 5632.9 3569.8 3587.2 3590.4 3593.7 3598.3 3391.2 3542.9 3593.7 3627.7 3629.1 4300.5 4304.0 4309.7 4309.8 4316.5 5351.8 5369.4 5418.7 5420.2 5435.2 1219.1 1243.7 1246.5 1259.8 1260.6 764.6 767.8 775.8 780.0 782.6 4939.5 5244.4 5309.8 5312.2 5359.0 5527.3 5539.8 5548.9 5557.7 5593.0 5258.7 5315.7 5324.5 5331.3 5341.5 2888.9 2932.7 2933.2 2948.4 2982.0 5791.6 5850.3 5897.2 5905.2 5916.7 5563.4 5652.3 5657.2 5661.8 5671.0 5666.9 5762.4 5819.1 5931.4 5932.6 5508.5 5577.4 5579.7 5595.4 5601.5 5370.5 5409.1 5505.2 5520.5 5522.3 5549.5 5907.9 5918.0 5922.5 5923.8 3622.5 3630.6 3636.3 3636.7 3644.2 5614.2 5687.6 5687.8 5688.4 5701.2 5350.5 5406.6 5493.6 5495.3 5504.8 5248.1 5386.5 5462.2 5470.5 5473.0 5246.4 5283.8 5348.2 5348.9 5355.6 5192.4 5390.8 5494.1 5501.6 5516.6 5087.3 5322.8 5322.9 5326.4 5379.8
15:25:06 INFO - PROCESS | 3328 |
15:25:06 INFO - PROCESS | 3328 | [#5] yui.html Cycles:180 Average:19688.97 Median:4312.12 stddev:28015.32 (649.7%) stddev-sans-first:28057.36
15:25:06 INFO - PROCESS | 3328 | Values: 628.9 672.6 688.6 693.9 694.7 13567.9 14436.5 14706.6 14779.4 15095.2 13468.1 13600.7 13657.6 13746.4 13833.0 501.6 510.9 514.0 601.6 613.9 512.9 515.8 610.9 623.4 631.3 634.5 653.2 659.8 660.8 661.8 616.6 642.8 650.9 657.5 658.4 375.8 386.4 466.7 471.0 472.1 13387.7 13732.5 13742.4 13764.2 13833.3 12882.3 13058.3 13129.5 13546.7 13659.7 12740.4 13041.5 13191.5 13242.1 13402.5 4227.6 4239.3 4285.2 4339.0 4432.5 75668.6 76935.2 78954.2 78959.6 79558.6 10246.0 10300.6 10316.6 10370.8 10471.7 18095.0 18313.2 18408.2 18520.1 18528.5 7054.0 7136.9 7226.6 7234.2 7620.5 3882.7 4009.6 4036.6 4074.2 4126.2 3020.6 3046.3 3092.6 3128.5 3133.1 554.2 558.5 564.9 572.8 575.3 590.9 653.5 717.2 719.7 720.1 9098.4 9468.0 9631.0 9779.7 9992.7 576.9 631.1 708.3 708.7 709.8 4487.2 4607.5 4669.7 4719.8 4750.6 106317.0 108739.5 111985.0 113052.0 114644.0 83588.6 83931.6 92589.5 92818.0 97634.0 73810.6 74490.3 76372.6 77227.0 79396.0 9026.7 9237.6 9400.3 9990.0 10124.1 48324.3 48523.5 48663.5 49120.3 49709.8 62567.4 65948.7 66062.3 66616.7 67920.3 12335.0 12742.1 12807.5 13194.6 13221.5 15125.0 15129.9 15170.4 15207.5 15531.8 13786.8 14064.9 14084.4 14184.2 14582.5 11737.9 13526.3 13607.5 13748.8 14181.8 13744.5 13906.6 14263.1 14535.7 14584.3 11981.9 12064.8 12158.3 12212.3 12522.4 11426.4 11977.3 12027.9 12028.4 12091.2
15:25:06 INFO - PROCESS | 3328 | -------- Summary: end --------
15:25:06 INFO - PROCESS | 3328 |
15:25:07 INFO - TEST-INFO | 3328: exit 0
15:25:07 INFO - TEST-OK | dromaeo_css | took 1122285ms
15:25:07 INFO - TEST-START | kraken
15:25:07 INFO - operating with platform_type : w7_
15:25:07 INFO - Initialising browser for kraken test...
15:25:07 INFO - TEST-INFO | started process 1540 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp4ueo_o\profile http://localhost:49896/getInfo.html)
15:25:12 INFO - PROCESS | 1540 | __metrics Screen width/height:1600/1200
15:25:12 INFO - PROCESS | 1540 | colorDepth:24
15:25:12 INFO - PROCESS | 1540 | Browser inner width/height: 1010/674
15:25:12 INFO - PROCESS | 1540 | __metrics
15:25:12 INFO - PROCESS | 1540 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
15:25:12 INFO - PROCESS | 1540 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
15:25:13 INFO - PROCESS | 1540 | console.error:
15:25:13 INFO - PROCESS | 1540 | Message: Error: Connection closed before committing the transaction.
15:25:13 INFO - PROCESS | 1540 | Stack:
15:25:13 INFO - PROCESS | 1540 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
15:25:13 INFO - PROCESS | 1540 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
15:25:13 INFO - PROCESS | 1540 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
15:25:13 INFO - PROCESS | 1540 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
15:25:13 INFO - PROCESS | 1540 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
15:25:13 INFO - PROCESS | 1540 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15
15:25:13 INFO - PROCESS | 1540 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
15:25:13 INFO - PROCESS | 1540 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
15:25:13 INFO - PROCESS | 1540 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
15:25:13 INFO - PROCESS | 1540 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
15:25:13 INFO - PROCESS | 1540 | Barrier/this.client.addBlocker/promise13.1%
15:26:10 INFO - TinderboxPrint: I/O read bytes / time
84,685,824 / 1,029,700
15:26:10 INFO - TinderboxPrint: I/O write bytes / time
466,813,440 / 3,258,150
15:26:10 INFO - TinderboxPrint: CPU idle
8,268.3 (86.9%)
15:26:10 INFO - TinderboxPrint: CPU user
1,211.0 (12.7%)
15:26:10 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 37955584; Read time: 0; Write time: 88650
15:26:10 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 84685824; Write bytes: 394156544; Read time: 1029700; Write time: 3115230
15:26:10 INFO - Running post-run listener: _upload_blobber_files
15:26:10 INFO - Blob upload gear active.
15:26:10 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
15:26:11 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:26:11 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:26:11 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:26:11 INFO - (blobuploader) - INFO - Open directory for files ...
15:26:11 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
15:26:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
15:26:11 INFO - (blobuploader) - INFO - Uploading, attempt #1.
15:26:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
15:26:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
15:26:12 INFO - (blobuploader) - INFO - Done attempting.
15:26:12 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
15:26:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
15:26:12 INFO - (blobuploader) - INFO - Uploading, attempt #1.
15:26:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
15:26:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
15:26:13 INFO - (blobuploader) - INFO - Done attempting.
15:26:13 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
15:26:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
15:26:13 INFO - (blobuploader) - INFO - Uploading, attempt #1.
15:26:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
15:26:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
15:26:21 INFO - (blobuploader) - INFO - Done attempting.
15:26:21 INFO - (blobuploader) - INFO - Iteration through files over.
15:26:21 INFO - Return code: 0
15:26:21 INFO - rmtree: C:\slave\test\build\uploaded_files.json
15:26:21 INFO - Using _rmtree_windows ...
15:26:21 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
15:26:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8d36d0a0ccb1910d5732382d6c19b96a52185455713586ba5ed63f21dd19f78839c5f346caf286d214b107b02c04063a4afbfb04a28c62c8f6d56798f3a5a9f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3d7bcc75dd8ce548ef3fcdd93fd41d650b671b8374804388ccef4b0d03aae38b7a21b7d4bc8522924199553e543c81bf6a8a8b3daf72ee88afe1438cd227d76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce5e8941042abf05c399f095aaf844333a2ac93cf1beccb0748dbddb236855fa7e8bd7ab0a9a8e3fa40b9a887ab2f04c6e30efe9dcde2aa0e33384f4be08ee19"}
15:26:21 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
15:26:21 INFO - Writing to file C:\slave\test\properties\blobber_files
15:26:21 INFO - Contents:
15:26:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8d36d0a0ccb1910d5732382d6c19b96a52185455713586ba5ed63f21dd19f78839c5f346caf286d214b107b02c04063a4afbfb04a28c62c8f6d56798f3a5a9f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3d7bcc75dd8ce548ef3fcdd93fd41d650b671b8374804388ccef4b0d03aae38b7a21b7d4bc8522924199553e543c81bf6a8a8b3daf72ee88afe1438cd227d76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce5e8941042abf05c399f095aaf844333a2ac93cf1beccb0748dbddb236855fa7e8bd7ab0a9a8e3fa40b9a887ab2f04c6e30efe9dcde2aa0e33384f4be08ee19"}
15:26:21 INFO - Running post-run listener: copy_logs_to_upload_dir
15:26:21 INFO - Copying logs to upload dir...
15:26:21 INFO - mkdir: C:\slave\test\build\upload\logs
15:26:21 INFO - Copying logs to upload dir...
15:26:21 INFO - Using _rmtree_windows ...
15:26:21 INFO - Using _rmtree_windows ...
15:26:21 INFO - Using _rmtree_windows ...
15:26:21 INFO - Using _rmtree_windows ...
15:26:21 INFO - Using _rmtree_windows ...
15:26:21 INFO - Using _rmtree_windows ...
15:26:21 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1456.343000
========= master_lag: 3.88 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 20 secs) (at 2016-10-23 15:26:25.041991) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-23 15:26:25.047638) =========
'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-017
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-017
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-017
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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8d36d0a0ccb1910d5732382d6c19b96a52185455713586ba5ed63f21dd19f78839c5f346caf286d214b107b02c04063a4afbfb04a28c62c8f6d56798f3a5a9f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3d7bcc75dd8ce548ef3fcdd93fd41d650b671b8374804388ccef4b0d03aae38b7a21b7d4bc8522924199553e543c81bf6a8a8b3daf72ee88afe1438cd227d76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce5e8941042abf05c399f095aaf844333a2ac93cf1beccb0748dbddb236855fa7e8bd7ab0a9a8e3fa40b9a887ab2f04c6e30efe9dcde2aa0e33384f4be08ee19"}
build_url:https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://queue.taskcluster.net/v1/task/Q66b297YRQy8IFaMbvcFKg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8d36d0a0ccb1910d5732382d6c19b96a52185455713586ba5ed63f21dd19f78839c5f346caf286d214b107b02c04063a4afbfb04a28c62c8f6d56798f3a5a9f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3d7bcc75dd8ce548ef3fcdd93fd41d650b671b8374804388ccef4b0d03aae38b7a21b7d4bc8522924199553e543c81bf6a8a8b3daf72ee88afe1438cd227d76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce5e8941042abf05c399f095aaf844333a2ac93cf1beccb0748dbddb236855fa7e8bd7ab0a9a8e3fa40b9a887ab2f04c6e30efe9dcde2aa0e33384f4be08ee19"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-23 15:26:25.163702) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 15:26:25.164043) =========
'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-017
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-017
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-017
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: 0.01 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 15:26:25.278576) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-23 15:26:25.278943) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-23 15:26:25.756744) =========
========= Total master_lag: 8.74 =========