builder: mozilla-central_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-137
starttime: 1479564811.38
results: success (0)
buildid: 20161119030204
builduid: 02292f9e3509430fa804597553cb229d
revision: f09e137ead39230eaa94f47988ccce2cfcda4195
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-19 06:13:31.375689) =========
master: http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-19 06:13:31.376165) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-19 06:13:31.376456) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-19 06:13:31.474384) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-19 06:13:31.474750) =========
'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-137
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-137
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-137
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
--06:13:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,179 (12K) [text/x-python]
0K .......... . 100% 11.82 MB/s
06:13:32 (11.82 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.019000
========= master_lag: 0.07 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-19 06:13:32.562763) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2016-11-19 06:13:32.563352) =========
'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-137
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-137
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-137
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=11.101000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2016-11-19 06:13:43.678241) =========
========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-11-19 06:13:43.678822) =========
'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev f09e137ead39230eaa94f47988ccce2cfcda4195 --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f09e137ead39230eaa94f47988ccce2cfcda4195 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-137
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-137
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-137
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-11-19 06:13:44,223 truncating revision to first 12 chars
2016-11-19 06:13:44,223 Setting DEBUG logging.
2016-11-19 06:13:44,223 attempt 1/10
2016-11-19 06:13:44,223 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f09e137ead39?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-11-19 06:13:47,739 unpacking tar archive at: mozilla-central-f09e137ead39/testing/mozharness/
2016-11-19 06:13:47,871 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=4.368000
========= master_lag: 0.13 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-11-19 06:13:48.179309) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-19 06:13:48.179775) =========
script_repo_revision: f09e137ead39230eaa94f47988ccce2cfcda4195
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-19 06:13:48.180252) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-19 06:13:48.180553) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-19 06:13:48.191513) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 23 secs) (at 2016-11-19 06:13:48.192008) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox'
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', 'Firefox', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-137
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-137
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-137
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
06:13:48 INFO - MultiFileLogger online at 20161119 06:13:48 in C:\slave\test
06:13:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
06:13:48 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
06:13:48 INFO - {'append_to_log': False,
06:13:48 INFO - 'base_work_dir': 'C:\\slave\\test',
06:13:48 INFO - 'blob_upload_branch': 'Firefox',
06:13:48 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
06:13:48 INFO - 'branch': 'Firefox',
06:13:48 INFO - 'buildbot_json_path': 'buildprops.json',
06:13:48 INFO - 'config_files': ('talos/windows_config.py',),
06:13:48 INFO - 'default_actions': ('clobber',
06:13:48 INFO - 'read-buildbot-config',
06:13:48 INFO - 'download-and-extract',
06:13:48 INFO - 'populate-webroot',
06:13:48 INFO - 'create-virtualenv',
06:13:48 INFO - 'install',
06:13:48 INFO - 'run-tests'),
06:13:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
06:13:48 INFO - 'download_minidump_stackwalk': True,
06:13:48 INFO - 'download_symbols': 'ondemand',
06:13:48 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
06:13:48 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
06:13:48 INFO - 'hg': 'c:/mozilla-build/hg/hg',
06:13:48 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
06:13:48 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
06:13:48 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
06:13:48 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
06:13:48 INFO - 'C:/mozilla-build/tooltool.py'),
06:13:48 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
06:13:48 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
06:13:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
06:13:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
06:13:48 INFO - 'installer_path': 'installer.exe',
06:13:48 INFO - 'log_level': 'info',
06:13:48 INFO - 'log_name': 'talos',
06:13:48 INFO - 'log_to_console': True,
06:13:48 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
06:13:48 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
06:13:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
06:13:48 INFO - 'opt_config_files': (),
06:13:48 INFO - 'pip_index': False,
06:13:48 INFO - 'sps_profile': False,
06:13:48 INFO - 'sps_profile_interval': 0,
06:13:48 INFO - 'suite': 'dromaeojs',
06:13:48 INFO - 'system_bits': '32',
06:13:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
06:13:48 INFO - 'title': 'T-W732-IX-137',
06:13:48 INFO - 'use_talos_json': True,
06:13:48 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
06:13:48 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
06:13:48 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
06:13:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
06:13:48 INFO - 'work_dir': 'build'}
06:13:48 INFO - [mozharness: 2016-11-19 14:13:48.730000Z] Running clobber step.
06:13:48 INFO - Running pre-action listener: _resource_record_pre_action
06:13:48 INFO - Running main action method: clobber
06:13:48 INFO - rmtree: C:\slave\test\build
06:13:48 INFO - Using _rmtree_windows ...
06:13:48 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
06:16:29 INFO - Running post-action listener: _resource_record_post_action
06:16:29 INFO - [mozharness: 2016-11-19 14:16:29.476000Z] Finished clobber step (success)
06:16:29 INFO - [mozharness: 2016-11-19 14:16:29.476000Z] Running read-buildbot-config step.
06:16:29 INFO - Running pre-action listener: _resource_record_pre_action
06:16:29 INFO - Running main action method: read_buildbot_config
06:16:29 INFO - Using buildbot properties:
06:16:29 INFO - {
06:16:29 INFO - "project": "",
06:16:29 INFO - "product": "firefox",
06:16:29 INFO - "script_repo_revision": "production",
06:16:29 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs",
06:16:29 INFO - "repository": "",
06:16:29 INFO - "buildername": "Windows 7 32-bit mozilla-central pgo talos dromaeojs",
06:16:29 INFO - "buildid": "20161119030204",
06:16:29 INFO - "stage_platform": "win32-pgo",
06:16:29 INFO - "basedir": "C:\\slave\\test",
06:16:29 INFO - "nightly_build": "True",
06:16:29 INFO - "buildnumber": 12,
06:16:29 INFO - "slavename": "t-w732-ix-137",
06:16:29 INFO - "master": "http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/",
06:16:29 INFO - "platform": "win7_ix",
06:16:29 INFO - "scheduler": "tests-mozilla-central-win32-pgo-talos",
06:16:29 INFO - "branch": "mozilla-central",
06:16:29 INFO - "repo_path": "mozilla-central",
06:16:29 INFO - "revision": "f09e137ead39230eaa94f47988ccce2cfcda4195",
06:16:29 INFO - "pgo_build": "True",
06:16:29 INFO - "builduid": "02292f9e3509430fa804597553cb229d",
06:16:29 INFO - "slavebuilddir": "test"
06:16:29 INFO - }
06:16:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.zip.
06:16:29 INFO - Running post-action listener: _resource_record_post_action
06:16:29 INFO - [mozharness: 2016-11-19 14:16:29.480000Z] Finished read-buildbot-config step (success)
06:16:29 INFO - [mozharness: 2016-11-19 14:16:29.480000Z] Running download-and-extract step.
06:16:29 INFO - Running pre-action listener: _resource_record_pre_action
06:16:29 INFO - Running main action method: download_and_extract
06:16:29 INFO - mkdir: C:\slave\test\build\tests
06:16:29 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')]}
06:16:29 INFO - trying https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json
06:16:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json
06:16:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.test_packages.json'}, attempt #1
06:16:30 INFO - Downloaded 1218 bytes.
06:16:30 INFO - Reading from file C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json
06:16:30 INFO - Using the following test package requirements:
06:16:30 INFO - {u'common': [u'firefox-53.0a1.en-US.win32.common.tests.zip'],
06:16:30 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
06:16:30 INFO - u'firefox-53.0a1.en-US.win32.cppunittest.tests.zip'],
06:16:30 INFO - u'gtest': [u'firefox-53.0a1.en-US.win32.common.tests.zip'],
06:16:30 INFO - u'jittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
06:16:30 INFO - u'jsshell-win32.zip'],
06:16:30 INFO - u'mochitest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
06:16:30 INFO - u'firefox-53.0a1.en-US.win32.mochitest.tests.zip'],
06:16:30 INFO - u'mozbase': [u'firefox-53.0a1.en-US.win32.common.tests.zip'],
06:16:30 INFO - u'reftest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
06:16:30 INFO - u'firefox-53.0a1.en-US.win32.reftest.tests.zip'],
06:16:30 INFO - u'talos': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
06:16:30 INFO - u'firefox-53.0a1.en-US.win32.talos.tests.zip'],
06:16:30 INFO - u'web-platform': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
06:16:30 INFO - u'firefox-53.0a1.en-US.win32.web-platform.tests.zip'],
06:16:30 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
06:16:30 INFO - u'firefox-53.0a1.en-US.win32.xpcshell.tests.zip']}
06:16:30 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip'] for test suite category: common
06:16:30 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip
06:16:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1
06:16:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory
06:16:31 INFO - Http code: 200
06:16:31 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront)
06:16:31 INFO - x-amz-cf-id: hVwuUfXNVDW4Di8jOPSVLJ7Wrpv6SNbJDEH6pE54o81I21v4iysCGQ==
06:16:31 INFO - x-amz-version-id: he0iUiJ3iEICSBoV5_qsNDhFQBxYv_fM
06:16:32 INFO - Expected file size: 16979425
06:16:32 INFO - Obtained file size: 16979425
06:16:56 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip', u'firefox-53.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos
06:16:56 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip
06:16:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1
06:16:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory
06:16:56 INFO - Http code: 200
06:16:56 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront)
06:16:56 INFO - x-amz-cf-id: W12ECqQpJCVia84fCCXhC2CcPLxpJKP2kQQxK9UTVhWvEVefVNDA7A==
06:16:56 INFO - x-amz-version-id: he0iUiJ3iEICSBoV5_qsNDhFQBxYv_fM
06:16:57 INFO - Expected file size: 16979425
06:16:57 INFO - Obtained file size: 16979425
06:17:04 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip
06:17:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip'}, attempt #1
06:17:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip into memory
06:17:05 INFO - Http code: 200
06:17:05 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront)
06:17:05 INFO - x-amz-cf-id: Q3xuQwqxvDRZVL3SBG8Ob-hnu-z_5fwYy22A0O07t7_yqZeID6b8jQ==
06:17:05 INFO - x-amz-version-id: m2wyWBdqJR5b8ZoDwms_JjAgxt8ZpzGM
06:17:05 INFO - Expected file size: 14409392
06:17:05 INFO - Obtained file size: 14409392
06:17:09 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')]}
06:17:09 INFO - trying https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
06:17:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.zip to C:\slave\test\build\firefox-53.0a1.en-US.win32.zip
06:17:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.zip'}, attempt #1
06:17:12 INFO - Downloaded 54663423 bytes.
06:17:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
06:17:12 INFO - mkdir: C:\slave\test\properties
06:17:12 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
06:17:12 INFO - Writing to file C:\slave\test\properties\build_url
06:17:12 INFO - Contents:
06:17:12 INFO - build_url:https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
06:17:13 INFO - Running post-action listener: _resource_record_post_action
06:17:13 INFO - Running post-action listener: set_extra_try_arguments
06:17:13 INFO - [mozharness: 2016-11-19 14:17:13.480000Z] Finished download-and-extract step (success)
06:17:13 INFO - [mozharness: 2016-11-19 14:17:13.480000Z] Running populate-webroot step.
06:17:13 INFO - Running pre-action listener: _resource_record_pre_action
06:17:13 INFO - Running main action method: populate_webroot
06:17:13 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
06:17:13 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
06:17:13 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
06:17:13 INFO - u'--tppagecycles',
06:17:13 INFO - u'7'],
06:17:13 INFO - u'tests': [u'tsvgm']}},
06:17:13 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
06:17:13 INFO - u'tests': [u'tresize', u'tcanvasmark']},
06:17:13 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
06:17:13 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
06:17:13 INFO - u'tests': [u'dromaeo_css', u'kraken']},
06:17:13 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
06:17:13 INFO - u'g1': {u'pagesets_name': u'tp5n.zip',
06:17:13 INFO - u'talos_options': [u'--disable-e10s'],
06:17:13 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
06:17:13 INFO - u'g1-e10s': {u'pagesets_name': u'tp5n.zip',
06:17:13 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
06:17:13 INFO - u'g2': {u'pagesets_name': u'tp5n.zip',
06:17:13 INFO - u'talos_options': [u'--disable-e10s'],
06:17:13 INFO - u'tests': [u'damp', u'tps']},
06:17:13 INFO - u'g2-e10s': {u'pagesets_name': u'tp5n.zip',
06:17:13 INFO - u'tests': [u'damp', u'tps']},
06:17:13 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
06:17:13 INFO - u'tests': [u'dromaeo_dom']},
06:17:13 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
06:17:13 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
06:17:13 INFO - u'tests': [u'basic_compositor_video']},
06:17:13 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
06:17:13 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
06:17:13 INFO - u'tests': [u'a11yr',
06:17:13 INFO - u'ts_paint',
06:17:13 INFO - u'tpaint',
06:17:13 INFO - u'sessionrestore',
06:17:13 INFO - u'sessionrestore_no_auto_restore',
06:17:13 INFO - u'tabpaint']},
06:17:13 INFO - u'other-e10s': {u'tests': [u'a11yr',
06:17:13 INFO - u'ts_paint',
06:17:13 INFO - u'tpaint',
06:17:13 INFO - u'sessionrestore',
06:17:13 INFO - u'sessionrestore_no_auto_restore',
06:17:13 INFO - u'tabpaint']},
06:17:13 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
06:17:13 INFO - u'ts_paint',
06:17:13 INFO - u'tpaint',
06:17:13 INFO - u'sessionrestore',
06:17:13 INFO - u'sessionrestore_no_auto_restore',
06:17:13 INFO - u'tabpaint']},
06:17:13 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
06:17:13 INFO - u'ts_paint',
06:17:13 INFO - u'tpaint',
06:17:13 INFO - u'sessionrestore',
06:17:13 INFO - u'sessionrestore_no_auto_restore',
06:17:13 INFO - u'tabpaint']},
06:17:13 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
06:17:13 INFO - u'tests': [u'a11yr',
06:17:13 INFO - u'ts_paint',
06:17:13 INFO - u'tpaint',
06:17:13 INFO - u'sessionrestore',
06:17:13 INFO - u'sessionrestore_no_auto_restore',
06:17:13 INFO - u'tabpaint']},
06:17:13 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
06:17:13 INFO - u'tests': [u'a11yr',
06:17:13 INFO - u'ts_paint',
06:17:13 INFO - u'tpaint',
06:17:13 INFO - u'sessionrestore',
06:17:13 INFO - u'sessionrestore_no_auto_restore',
06:17:13 INFO - u'tabpaint']},
06:17:13 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
06:17:13 INFO - u'tests': [u'tsvgx',
06:17:13 INFO - u'tsvgr_opacity',
06:17:13 INFO - u'tart',
06:17:13 INFO - u'tscrollx',
06:17:13 INFO - u'cart']},
06:17:13 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
06:17:13 INFO - u'tsvgr_opacity',
06:17:13 INFO - u'tart',
06:17:13 INFO - u'tscrollx',
06:17:13 INFO - u'cart']},
06:17:13 INFO - u'tp5o': {u'pagesets_name': u'tp5n.zip',
06:17:13 INFO - u'talos_options': [u'--disable-e10s'],
06:17:13 INFO - u'tests': [u'tp5o']},
06:17:13 INFO - u'tp5o-e10s': {u'pagesets_name': u'tp5n.zip',
06:17:13 INFO - u'tests': [u'tp5o']},
06:17:13 INFO - u'xperf': {u'pagesets_name': u'tp5n.zip',
06:17:13 INFO - u'talos_options': [u'--disable-e10s',
06:17:13 INFO - u'--xperf_path',
06:17:13 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
06:17:13 INFO - u'tests': [u'tp5n']},
06:17:13 INFO - u'xperf-e10s': {u'pagesets_name': u'tp5n.zip',
06:17:13 INFO - u'talos_options': [u'--xperf_path',
06:17:13 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
06:17:13 INFO - u'tests': [u'tp5n']}},
06:17:13 INFO - u'talos.zip': {u'path': u'',
06:17:13 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
06:17:13 INFO - Running post-action listener: _resource_record_post_action
06:17:13 INFO - [mozharness: 2016-11-19 14:17:13.537000Z] Finished populate-webroot step (success)
06:17:13 INFO - [mozharness: 2016-11-19 14:17:13.537000Z] Running create-virtualenv step.
06:17:13 INFO - Running pre-action listener: _resource_record_pre_action
06:17:13 INFO - Running main action method: create_virtualenv
06:17:13 INFO - Creating virtualenv C:\slave\test\build/venv
06:17:13 INFO - mkdir: C:\slave\test\build\venv\Scripts
06:17:14 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
06:17:14 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'!
06:17:14 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
06:17:14 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
06:17:20 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
06:17:23 INFO - Installing distribute......................................................................................................................................................................................done.
06:17:26 INFO - Return code: 0
06:17:26 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
06:17:26 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
06:17:26 INFO - Reading from file tmpfile_stdout
06:17:26 INFO - Output received:
06:17:26 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
06:17:26 INFO - Using _rmtree_windows ...
06:17:26 INFO - Using _rmtree_windows ...
06:17:26 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
06:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:17:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:17:26 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018F1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FA9020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01964E90>, '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 0x012BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A655C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-137', 'USERDOMAIN': 'T-W732-IX-137', '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-137', '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
06:17:26 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build
06:17:26 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
06:17:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:17:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:17:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:17:26 INFO - 'COMPUTERNAME': 'T-W732-IX-137',
06:17:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:17:26 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:17:26 INFO - 'DCLOCATION': 'SCL3',
06:17:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:17:26 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:17:26 INFO - 'HOMEDRIVE': 'C:',
06:17:26 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:17:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:17:26 INFO - 'KTS_VERSION': '1.19c',
06:17:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:17:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-137',
06:17:26 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:17:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:17:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:17:26 INFO - 'MOZ_NO_REMOTE': '1',
06:17:26 INFO - 'NO_EM_RESTART': '1',
06:17:26 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:17:26 INFO - 'OS': 'Windows_NT',
06:17:26 INFO - 'OURDRIVE': 'C:',
06:17:26 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:17:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:17:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:17:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:17:26 INFO - 'PROCESSOR_LEVEL': '6',
06:17:26 INFO - 'PROCESSOR_REVISION': '1e05',
06:17:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:17:26 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:17:26 INFO - 'PROMPT': '$P$G',
06:17:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:17:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:17:26 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:17:26 INFO - 'PWD': 'C:\\slave\\test',
06:17:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:17:26 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:17:26 INFO - 'SYSTEMDRIVE': 'C:',
06:17:26 INFO - 'SYSTEMROOT': 'C:\\windows',
06:17:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:17:26 INFO - 'TEST1': 'testie',
06:17:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:17:26 INFO - 'USERDOMAIN': 'T-W732-IX-137',
06:17:26 INFO - 'USERNAME': 'cltbld',
06:17:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:17:26 INFO - 'WINDIR': 'C:\\windows',
06:17:26 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:17:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:17:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:17:27 INFO - Ignoring indexes: http://pypi.python.org/simple/
06:17:27 INFO - Downloading/unpacking pip>=1.5
06:17:31 INFO - Running setup.py egg_info for package pip
06:17:31 INFO - warning: no files found matching 'pip\cacert.pem'
06:17:31 INFO - warning: no files found matching '*.html' under directory 'docs'
06:17:31 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
06:17:31 INFO - no previously-included directories found matching 'docs\_build\_sources'
06:17:31 INFO - Installing collected packages: pip
06:17:31 INFO - Found existing installation: pip 0.8.2
06:17:31 INFO - Uninstalling pip:
06:17:31 INFO - Successfully uninstalled pip
06:17:31 INFO - Running setup.py install for pip
06:17:31 INFO - warning: no files found matching 'pip\cacert.pem'
06:17:31 INFO - warning: no files found matching '*.html' under directory 'docs'
06:17:31 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
06:17:31 INFO - no previously-included directories found matching 'docs\_build\_sources'
06:17:31 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
06:17:31 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
06:17:31 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
06:17:31 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
06:17:31 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
06:17:31 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
06:17:31 INFO - Successfully installed pip
06:17:31 INFO - Cleaning up...
06:17:31 INFO - Return code: 0
06:17:31 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
06:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:17:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:17:31 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 0x018F1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FA9020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01964E90>, '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 0x012BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A655C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-137', 'USERDOMAIN': 'T-W732-IX-137', '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-137', '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
06:17:31 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
06:17:31 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
06:17:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:17:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:17:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:17:31 INFO - 'COMPUTERNAME': 'T-W732-IX-137',
06:17:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:17:31 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:17:31 INFO - 'DCLOCATION': 'SCL3',
06:17:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:17:31 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:17:31 INFO - 'HOMEDRIVE': 'C:',
06:17:31 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:17:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:17:31 INFO - 'KTS_VERSION': '1.19c',
06:17:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:17:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-137',
06:17:31 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:17:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:17:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:17:31 INFO - 'MOZ_NO_REMOTE': '1',
06:17:31 INFO - 'NO_EM_RESTART': '1',
06:17:31 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:17:31 INFO - 'OS': 'Windows_NT',
06:17:31 INFO - 'OURDRIVE': 'C:',
06:17:31 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:17:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:17:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:17:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:17:31 INFO - 'PROCESSOR_LEVEL': '6',
06:17:31 INFO - 'PROCESSOR_REVISION': '1e05',
06:17:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:17:31 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:17:31 INFO - 'PROMPT': '$P$G',
06:17:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:17:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:17:31 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:17:31 INFO - 'PWD': 'C:\\slave\\test',
06:17:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:17:31 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:17:31 INFO - 'SYSTEMDRIVE': 'C:',
06:17:31 INFO - 'SYSTEMROOT': 'C:\\windows',
06:17:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:17:31 INFO - 'TEST1': 'testie',
06:17:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:17:31 INFO - 'USERDOMAIN': 'T-W732-IX-137',
06:17:31 INFO - 'USERNAME': 'cltbld',
06:17:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:17:31 INFO - 'WINDIR': 'C:\\windows',
06:17:31 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:17:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:17:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:17:33 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:17:33 INFO - Downloading/unpacking psutil>=3.1.1
06:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
06:17:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
06:17:34 INFO - Installing collected packages: psutil
06:17:34 INFO - Successfully installed psutil
06:17:34 INFO - Cleaning up...
06:17:34 INFO - Return code: 0
06:17:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
06:17:34 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')]}
06:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:17:34 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')]}
06:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:17:34 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 0x018F1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FA9020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01964E90>, '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 0x012BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A655C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-137', 'USERDOMAIN': 'T-W732-IX-137', '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-137', '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
06:17:34 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
06:17:34 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
06:17:34 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:17:34 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:17:34 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:17:34 INFO - 'COMPUTERNAME': 'T-W732-IX-137',
06:17:34 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:17:34 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:17:34 INFO - 'DCLOCATION': 'SCL3',
06:17:34 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:17:34 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:17:34 INFO - 'HOMEDRIVE': 'C:',
06:17:34 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:17:34 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:17:34 INFO - 'KTS_VERSION': '1.19c',
06:17:34 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:17:34 INFO - 'LOGONSERVER': '\\\\T-W732-IX-137',
06:17:34 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:17:34 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:17:34 INFO - 'MOZ_NO_REMOTE': '1',
06:17:34 INFO - 'NO_EM_RESTART': '1',
06:17:34 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:17:34 INFO - 'OS': 'Windows_NT',
06:17:34 INFO - 'OURDRIVE': 'C:',
06:17:34 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:17:34 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:17:34 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:17:34 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:17:34 INFO - 'PROCESSOR_LEVEL': '6',
06:17:34 INFO - 'PROCESSOR_REVISION': '1e05',
06:17:34 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:17:34 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:17:34 INFO - 'PROMPT': '$P$G',
06:17:34 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:17:34 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:17:34 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:17:34 INFO - 'PWD': 'C:\\slave\\test',
06:17:34 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:17:34 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:17:34 INFO - 'SYSTEMDRIVE': 'C:',
06:17:34 INFO - 'SYSTEMROOT': 'C:\\windows',
06:17:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:17:35 INFO - 'TEST1': 'testie',
06:17:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:17:35 INFO - 'USERDOMAIN': 'T-W732-IX-137',
06:17:35 INFO - 'USERNAME': 'cltbld',
06:17:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:17:35 INFO - 'WINDIR': 'C:\\windows',
06:17:35 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:17:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:17:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:17:35 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:17:35 INFO - Downloading/unpacking mozsystemmonitor==0.3
06:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
06:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
06:17:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz
06:17:36 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
06:17:36 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)
06:17:36 INFO - Installing collected packages: mozsystemmonitor
06:17:36 INFO - Running setup.py install for mozsystemmonitor
06:17:36 INFO - Successfully installed mozsystemmonitor
06:17:36 INFO - Cleaning up...
06:17:36 INFO - Return code: 0
06:17:36 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
06:17:36 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')]}
06:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:17:36 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')]}
06:17:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:17:36 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 0x018F1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FA9020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01964E90>, '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 0x012BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A655C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-137', 'USERDOMAIN': 'T-W732-IX-137', '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-137', '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
06:17:36 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
06:17:36 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
06:17:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:17:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:17:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:17:36 INFO - 'COMPUTERNAME': 'T-W732-IX-137',
06:17:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:17:36 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:17:36 INFO - 'DCLOCATION': 'SCL3',
06:17:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:17:36 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:17:36 INFO - 'HOMEDRIVE': 'C:',
06:17:36 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:17:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:17:36 INFO - 'KTS_VERSION': '1.19c',
06:17:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:17:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-137',
06:17:36 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:17:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:17:36 INFO - 'MOZ_NO_REMOTE': '1',
06:17:36 INFO - 'NO_EM_RESTART': '1',
06:17:36 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:17:36 INFO - 'OS': 'Windows_NT',
06:17:36 INFO - 'OURDRIVE': 'C:',
06:17:36 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:17:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:17:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:17:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:17:36 INFO - 'PROCESSOR_LEVEL': '6',
06:17:36 INFO - 'PROCESSOR_REVISION': '1e05',
06:17:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:17:36 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:17:36 INFO - 'PROMPT': '$P$G',
06:17:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:17:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:17:36 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:17:36 INFO - 'PWD': 'C:\\slave\\test',
06:17:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:17:36 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:17:36 INFO - 'SYSTEMDRIVE': 'C:',
06:17:36 INFO - 'SYSTEMROOT': 'C:\\windows',
06:17:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:17:36 INFO - 'TEST1': 'testie',
06:17:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:17:36 INFO - 'USERDOMAIN': 'T-W732-IX-137',
06:17:36 INFO - 'USERNAME': 'cltbld',
06:17:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:17:36 INFO - 'WINDIR': 'C:\\windows',
06:17:36 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:17:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:17:37 INFO - Downloading/unpacking jsonschema==2.5.1
06:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
06:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
06:17:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
06:17:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
06:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
06:17:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
06:17:38 INFO - Downloading functools32-3.2.3-2.tar.gz
06:17:38 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
06:17:38 INFO - warning: no files found matching '*.txt'
06:17:38 INFO - no previously-included directories found matching 'build'
06:17:38 INFO - no previously-included directories found matching 'dist'
06:17:38 INFO - no previously-included directories found matching '.git*'
06:17:38 INFO - Installing collected packages: jsonschema, functools32
06:17:38 INFO - Running setup.py install for functools32
06:17:38 INFO - warning: no files found matching '*.txt'
06:17:38 INFO - no previously-included directories found matching 'build'
06:17:38 INFO - no previously-included directories found matching 'dist'
06:17:38 INFO - no previously-included directories found matching '.git*'
06:17:38 INFO - Successfully installed jsonschema functools32
06:17:38 INFO - Cleaning up...
06:17:39 INFO - Return code: 0
06:17:39 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
06:17:39 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')]}
06:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:17:39 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')]}
06:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:17:39 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 0x018F1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FA9020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01964E90>, '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 0x012BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A655C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-137', 'USERDOMAIN': 'T-W732-IX-137', '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-137', '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
06:17:39 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
06:17:39 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
06:17:39 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:17:39 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:17:39 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:17:39 INFO - 'COMPUTERNAME': 'T-W732-IX-137',
06:17:39 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:17:39 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:17:39 INFO - 'DCLOCATION': 'SCL3',
06:17:39 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:17:39 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:17:39 INFO - 'HOMEDRIVE': 'C:',
06:17:39 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:17:39 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:17:39 INFO - 'KTS_VERSION': '1.19c',
06:17:39 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:17:39 INFO - 'LOGONSERVER': '\\\\T-W732-IX-137',
06:17:39 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:17:39 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:17:39 INFO - 'MOZ_NO_REMOTE': '1',
06:17:39 INFO - 'NO_EM_RESTART': '1',
06:17:39 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:17:39 INFO - 'OS': 'Windows_NT',
06:17:39 INFO - 'OURDRIVE': 'C:',
06:17:39 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:17:39 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:17:39 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:17:39 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:17:39 INFO - 'PROCESSOR_LEVEL': '6',
06:17:39 INFO - 'PROCESSOR_REVISION': '1e05',
06:17:39 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:17:39 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:17:39 INFO - 'PROMPT': '$P$G',
06:17:39 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:17:39 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:17:39 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:17:39 INFO - 'PWD': 'C:\\slave\\test',
06:17:39 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:17:39 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:17:39 INFO - 'SYSTEMDRIVE': 'C:',
06:17:39 INFO - 'SYSTEMROOT': 'C:\\windows',
06:17:39 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:17:39 INFO - 'TEST1': 'testie',
06:17:39 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:17:39 INFO - 'USERDOMAIN': 'T-W732-IX-137',
06:17:39 INFO - 'USERNAME': 'cltbld',
06:17:39 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:17:39 INFO - 'WINDIR': 'C:\\windows',
06:17:39 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:17:39 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:17:39 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
06:17:39 INFO - Cleaning up...
06:17:39 INFO - Return code: 0
06:17:39 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
06:17:39 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')]}
06:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:17:39 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')]}
06:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:17:39 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 0x018F1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FA9020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01964E90>, '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 0x012BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A655C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-137', 'USERDOMAIN': 'T-W732-IX-137', '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-137', '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
06:17:39 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
06:17:39 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
06:17:39 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:17:39 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:17:39 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:17:39 INFO - 'COMPUTERNAME': 'T-W732-IX-137',
06:17:39 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:17:39 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:17:39 INFO - 'DCLOCATION': 'SCL3',
06:17:39 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:17:39 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:17:39 INFO - 'HOMEDRIVE': 'C:',
06:17:39 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:17:39 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:17:39 INFO - 'KTS_VERSION': '1.19c',
06:17:39 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:17:39 INFO - 'LOGONSERVER': '\\\\T-W732-IX-137',
06:17:39 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:17:39 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:17:39 INFO - 'MOZ_NO_REMOTE': '1',
06:17:39 INFO - 'NO_EM_RESTART': '1',
06:17:39 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:17:39 INFO - 'OS': 'Windows_NT',
06:17:39 INFO - 'OURDRIVE': 'C:',
06:17:39 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:17:39 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:17:39 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:17:39 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:17:39 INFO - 'PROCESSOR_LEVEL': '6',
06:17:39 INFO - 'PROCESSOR_REVISION': '1e05',
06:17:39 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:17:39 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:17:39 INFO - 'PROMPT': '$P$G',
06:17:39 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:17:39 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:17:39 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:17:39 INFO - 'PWD': 'C:\\slave\\test',
06:17:39 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:17:39 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:17:39 INFO - 'SYSTEMDRIVE': 'C:',
06:17:39 INFO - 'SYSTEMROOT': 'C:\\windows',
06:17:39 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:17:39 INFO - 'TEST1': 'testie',
06:17:39 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:17:39 INFO - 'USERDOMAIN': 'T-W732-IX-137',
06:17:39 INFO - 'USERNAME': 'cltbld',
06:17:39 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:17:39 INFO - 'WINDIR': 'C:\\windows',
06:17:39 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:17:39 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:17:39 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:17:39 INFO - Downloading/unpacking blobuploader==1.2.4
06:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
06:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
06:17:40 INFO - Downloading blobuploader-1.2.4.tar.gz
06:17:40 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
06:17:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
06:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
06:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
06:17:41 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
06:17:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
06:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
06:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
06:17:41 INFO - Downloading docopt-0.6.1.tar.gz
06:17:41 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
06:17:41 INFO - Installing collected packages: blobuploader, requests, docopt
06:17:41 INFO - Running setup.py install for blobuploader
06:17:41 INFO - Running setup.py install for requests
06:17:42 INFO - Running setup.py install for docopt
06:17:43 INFO - Successfully installed blobuploader requests docopt
06:17:43 INFO - Cleaning up...
06:17:44 INFO - Return code: 0
06:17:44 INFO - Installing None into virtualenv C:\slave\test\build/venv
06:17:44 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')]}
06:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:17:44 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')]}
06:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:17:44 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 0x018F1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FA9020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01964E90>, '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 0x012BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A655C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-137', 'USERDOMAIN': 'T-W732-IX-137', '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-137', '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
06:17:44 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
06:17:44 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
06:17:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:17:44 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:17:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:17:44 INFO - 'COMPUTERNAME': 'T-W732-IX-137',
06:17:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:17:44 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:17:44 INFO - 'DCLOCATION': 'SCL3',
06:17:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:17:44 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:17:44 INFO - 'HOMEDRIVE': 'C:',
06:17:44 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:17:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:17:44 INFO - 'KTS_VERSION': '1.19c',
06:17:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:17:44 INFO - 'LOGONSERVER': '\\\\T-W732-IX-137',
06:17:44 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:17:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:17:44 INFO - 'MOZ_NO_REMOTE': '1',
06:17:44 INFO - 'NO_EM_RESTART': '1',
06:17:44 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:17:44 INFO - 'OS': 'Windows_NT',
06:17:44 INFO - 'OURDRIVE': 'C:',
06:17:44 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:17:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:17:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:17:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:17:44 INFO - 'PROCESSOR_LEVEL': '6',
06:17:44 INFO - 'PROCESSOR_REVISION': '1e05',
06:17:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:17:44 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:17:44 INFO - 'PROMPT': '$P$G',
06:17:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:17:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:17:44 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:17:44 INFO - 'PWD': 'C:\\slave\\test',
06:17:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:17:44 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:17:44 INFO - 'SYSTEMDRIVE': 'C:',
06:17:44 INFO - 'SYSTEMROOT': 'C:\\windows',
06:17:44 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:17:44 INFO - 'TEST1': 'testie',
06:17:44 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:17:44 INFO - 'USERDOMAIN': 'T-W732-IX-137',
06:17:44 INFO - 'USERNAME': 'cltbld',
06:17:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:17:44 INFO - 'WINDIR': 'C:\\windows',
06:17:44 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:17:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:17:44 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:17:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
06:17:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-udm48x-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
06:17:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
06:17:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-car26w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
06:17:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
06:17:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uit2w2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
06:17:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
06:17:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kfms3a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
06:17:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
06:17:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bejtpz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
06:17:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
06:17:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h3zykm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
06:17:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
06:17:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ei_hry-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
06:17:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
06:17:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-r4iobr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
06:17:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
06:17:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h9dwda-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
06:17:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
06:17:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o1cwwx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
06:17:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
06:17:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-op9ew0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
06:17:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
06:17:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0lovpr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
06:17:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
06:17:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2hsxgx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
06:17:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
06:17:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cezqyo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
06:17:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
06:17:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qls6gk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
06:17:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
06:17:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tlotfu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
06:17:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
06:17:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rcvk3q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
06:17:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
06:17:47 INFO - Running setup.py install for manifestparser
06:17:47 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
06:17:47 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
06:17:48 INFO - Running setup.py install for mozcrash
06:17:48 INFO - Running setup.py install for mozdebug
06:17:49 INFO - Running setup.py install for mozdevice
06:17:49 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
06:17:49 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
06:17:49 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
06:17:49 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
06:17:49 INFO - Running setup.py install for mozfile
06:17:50 INFO - Running setup.py install for mozhttpd
06:17:50 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
06:17:50 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
06:17:50 INFO - Running setup.py install for mozinfo
06:17:50 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
06:17:50 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
06:17:50 INFO - Running setup.py install for mozInstall
06:17:51 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
06:17:51 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
06:17:51 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
06:17:51 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
06:17:51 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
06:17:51 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
06:17:51 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
06:17:51 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
06:17:51 INFO - Running setup.py install for mozleak
06:17:51 INFO - Running setup.py install for mozlog
06:17:51 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
06:17:51 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
06:17:52 INFO - Running setup.py install for moznetwork
06:17:52 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
06:17:52 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
06:17:52 INFO - Running setup.py install for mozprocess
06:17:53 INFO - Running setup.py install for mozprofile
06:17:53 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
06:17:53 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
06:17:53 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
06:17:53 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
06:17:53 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
06:17:53 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
06:17:53 INFO - Running setup.py install for mozrunner
06:17:54 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
06:17:54 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
06:17:54 INFO - Running setup.py install for mozscreenshot
06:17:54 INFO - Running setup.py install for moztest
06:17:57 INFO - Running setup.py install for mozversion
06:17:57 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
06:17:57 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
06:17:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
06:17:57 INFO - Cleaning up...
06:17:57 INFO - Return code: 0
06:17:57 INFO - Installing None into virtualenv C:\slave\test\build/venv
06:17:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:17:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:17:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:17:57 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 0x018F1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FA9020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01964E90>, '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 0x012BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A655C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-137', 'USERDOMAIN': 'T-W732-IX-137', '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-137', '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
06:17:57 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
06:17:57 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
06:17:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:17:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:17:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:17:57 INFO - 'COMPUTERNAME': 'T-W732-IX-137',
06:17:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:17:57 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:17:57 INFO - 'DCLOCATION': 'SCL3',
06:17:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:17:57 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:17:57 INFO - 'HOMEDRIVE': 'C:',
06:17:57 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:17:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:17:57 INFO - 'KTS_VERSION': '1.19c',
06:17:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:17:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-137',
06:17:57 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:17:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:17:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:17:57 INFO - 'MOZ_NO_REMOTE': '1',
06:17:57 INFO - 'NO_EM_RESTART': '1',
06:17:57 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:17:57 INFO - 'OS': 'Windows_NT',
06:17:57 INFO - 'OURDRIVE': 'C:',
06:17:57 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:17:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:17:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:17:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:17:57 INFO - 'PROCESSOR_LEVEL': '6',
06:17:57 INFO - 'PROCESSOR_REVISION': '1e05',
06:17:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:17:57 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:17:57 INFO - 'PROMPT': '$P$G',
06:17:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:17:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:17:57 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:17:57 INFO - 'PWD': 'C:\\slave\\test',
06:17:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:17:57 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:17:57 INFO - 'SYSTEMDRIVE': 'C:',
06:17:57 INFO - 'SYSTEMROOT': 'C:\\windows',
06:17:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:17:57 INFO - 'TEST1': 'testie',
06:17:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:17:57 INFO - 'USERDOMAIN': 'T-W732-IX-137',
06:17:57 INFO - 'USERNAME': 'cltbld',
06:17:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:17:57 INFO - 'WINDIR': 'C:\\windows',
06:17:57 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:17:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:17:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:17:57 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:17:57 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
06:17:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_2pki9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
06:17:58 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))
06:17:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
06:17:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9dzbox-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
06:17:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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))
06:17:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
06:17:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_iq9en-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
06:17:58 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))
06:17:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
06:17:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aqn7yu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
06:17:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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))
06:17:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
06:17:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oxwgxy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
06:17:58 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))
06:17:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
06:17:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-r6rorv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
06:17:58 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))
06:17:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
06:17:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-r1hvo8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
06:17:58 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))
06:17:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
06:17:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3aigmp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
06:17:59 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))
06:17:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
06:17:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-j_uwzx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
06:17:59 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))
06:17:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
06:17:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iynaoe-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
06:17:59 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))
06:17:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
06:17:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-05yflh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
06:17:59 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))
06:17:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
06:17:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cgbrgh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
06:17:59 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))
06:17:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
06:17:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1sqkql-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
06:17:59 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))
06:17:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
06:17:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b82akr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
06:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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))
06:17:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
06:17:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fgsikm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
06:18:00 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))
06:18:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
06:18:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mi1fzg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
06:18:00 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))
06:18:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
06:18:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-noyokn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
06:18:00 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))
06:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
06:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
06:18:00 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))
06:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.49->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
06:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.49->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
06:18:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
06:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
06:18:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
06:18:01 INFO - Downloading blessings-1.6.tar.gz
06:18:01 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
06:18:01 INFO - Installing collected packages: blessings
06:18:01 INFO - Running setup.py install for blessings
06:18:02 INFO - Successfully installed blessings
06:18:02 INFO - Cleaning up...
06:18:04 INFO - Return code: 0
06:18:04 INFO - Done creating virtualenv C:\slave\test\build/venv.
06:18:04 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
06:18:04 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
06:18:05 INFO - Reading from file tmpfile_stdout
06:18:05 INFO - Using _rmtree_windows ...
06:18:05 INFO - Using _rmtree_windows ...
06:18:05 INFO - Current package versions:
06:18:05 INFO - blessings == 1.6
06:18:05 INFO - blobuploader == 1.2.4
06:18:05 INFO - docopt == 0.6.1
06:18:05 INFO - functools32 == 3.2.3-2
06:18:05 INFO - jsonschema == 2.5.1
06:18:05 INFO - manifestparser == 1.1
06:18:05 INFO - mozInstall == 1.12
06:18:05 INFO - mozcrash == 1.0
06:18:05 INFO - mozdebug == 0.1
06:18:05 INFO - mozdevice == 0.49
06:18:05 INFO - mozfile == 1.2
06:18:05 INFO - mozhttpd == 0.7
06:18:05 INFO - mozinfo == 0.9
06:18:05 INFO - mozleak == 0.1
06:18:05 INFO - mozlog == 3.4
06:18:05 INFO - moznetwork == 0.27
06:18:05 INFO - mozprocess == 0.23
06:18:05 INFO - mozprofile == 0.28
06:18:05 INFO - mozrunner == 6.13
06:18:05 INFO - mozscreenshot == 0.1
06:18:05 INFO - mozsystemmonitor == 0.3
06:18:05 INFO - moztest == 0.7
06:18:05 INFO - mozversion == 1.4
06:18:05 INFO - psutil == 3.1.1
06:18:05 INFO - requests == 1.2.3
06:18:05 INFO - Installing None into virtualenv C:\slave\test\build/venv
06:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:18:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:18:05 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 0x018F1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FA9020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01964E90>, '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 0x012BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A655C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-137', 'USERDOMAIN': 'T-W732-IX-137', '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-137', '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
06:18:05 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
06:18:05 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
06:18:05 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:18:05 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:18:05 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:18:05 INFO - 'COMPUTERNAME': 'T-W732-IX-137',
06:18:05 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:18:05 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:18:05 INFO - 'DCLOCATION': 'SCL3',
06:18:05 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:18:05 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:18:05 INFO - 'HOMEDRIVE': 'C:',
06:18:05 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:18:05 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:18:05 INFO - 'KTS_VERSION': '1.19c',
06:18:05 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:18:05 INFO - 'LOGONSERVER': '\\\\T-W732-IX-137',
06:18:05 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:18:05 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:18:05 INFO - 'MOZ_NO_REMOTE': '1',
06:18:05 INFO - 'NO_EM_RESTART': '1',
06:18:05 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:18:05 INFO - 'OS': 'Windows_NT',
06:18:05 INFO - 'OURDRIVE': 'C:',
06:18:05 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:18:05 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:18:05 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:18:05 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:18:05 INFO - 'PROCESSOR_LEVEL': '6',
06:18:05 INFO - 'PROCESSOR_REVISION': '1e05',
06:18:05 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:18:05 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:18:05 INFO - 'PROMPT': '$P$G',
06:18:05 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:18:05 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:18:05 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:18:05 INFO - 'PWD': 'C:\\slave\\test',
06:18:05 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:18:05 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:18:05 INFO - 'SYSTEMDRIVE': 'C:',
06:18:05 INFO - 'SYSTEMROOT': 'C:\\windows',
06:18:05 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:18:05 INFO - 'TEST1': 'testie',
06:18:05 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:18:05 INFO - 'USERDOMAIN': 'T-W732-IX-137',
06:18:05 INFO - 'USERNAME': 'cltbld',
06:18:05 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:18:05 INFO - 'WINDIR': 'C:\\windows',
06:18:05 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:18:05 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:18:05 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:18:05 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))
06:18:05 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))
06:18:05 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))
06:18:05 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))
06:18:05 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))
06:18:05 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))
06:18:05 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))
06:18:05 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))
06:18:05 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))
06:18:05 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))
06:18:05 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))
06:18:05 INFO - Cleaning up...
06:18:05 INFO - Return code: 0
06:18:05 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
06:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:18:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:18:05 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 0x018F1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FA9020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01964E90>, '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 0x012BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A655C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-137', 'USERDOMAIN': 'T-W732-IX-137', '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-137', '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
06:18:05 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
06:18:05 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
06:18:05 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:18:05 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:18:05 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:18:05 INFO - 'COMPUTERNAME': 'T-W732-IX-137',
06:18:05 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:18:05 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:18:05 INFO - 'DCLOCATION': 'SCL3',
06:18:05 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:18:05 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:18:05 INFO - 'HOMEDRIVE': 'C:',
06:18:05 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:18:05 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:18:05 INFO - 'KTS_VERSION': '1.19c',
06:18:05 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:18:05 INFO - 'LOGONSERVER': '\\\\T-W732-IX-137',
06:18:05 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:18:05 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:18:05 INFO - 'MOZ_NO_REMOTE': '1',
06:18:05 INFO - 'NO_EM_RESTART': '1',
06:18:05 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:18:05 INFO - 'OS': 'Windows_NT',
06:18:05 INFO - 'OURDRIVE': 'C:',
06:18:05 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:18:05 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:18:05 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:18:05 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:18:05 INFO - 'PROCESSOR_LEVEL': '6',
06:18:05 INFO - 'PROCESSOR_REVISION': '1e05',
06:18:05 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:18:05 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:18:05 INFO - 'PROMPT': '$P$G',
06:18:05 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:18:05 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:18:05 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:18:05 INFO - 'PWD': 'C:\\slave\\test',
06:18:05 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:18:05 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:18:05 INFO - 'SYSTEMDRIVE': 'C:',
06:18:05 INFO - 'SYSTEMROOT': 'C:\\windows',
06:18:05 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:18:05 INFO - 'TEST1': 'testie',
06:18:05 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:18:05 INFO - 'USERDOMAIN': 'T-W732-IX-137',
06:18:05 INFO - 'USERNAME': 'cltbld',
06:18:05 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:18:05 INFO - 'WINDIR': 'C:\\windows',
06:18:05 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:18:05 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:18:05 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:18:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
06:18:05 INFO - Cleaning up...
06:18:05 INFO - Return code: 0
06:18:05 INFO - Running post-action listener: _resource_record_post_action
06:18:05 INFO - Running post-action listener: _start_resource_monitoring
06:18:05 INFO - Starting resource monitoring.
06:18:05 INFO - [mozharness: 2016-11-19 14:18:05.957000Z] Finished create-virtualenv step (success)
06:18:05 INFO - [mozharness: 2016-11-19 14:18:05.957000Z] Running install step.
06:18:05 INFO - Running pre-action listener: _resource_record_pre_action
06:18:05 INFO - Running main action method: install
06:18:05 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
06:18:05 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
06:18:06 INFO - Reading from file tmpfile_stdout
06:18:06 INFO - Using _rmtree_windows ...
06:18:06 INFO - Using _rmtree_windows ...
06:18:06 INFO - Detecting whether we're running mozinstall >=1.0...
06:18:06 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
06:18:06 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
06:18:06 INFO - Reading from file tmpfile_stdout
06:18:06 INFO - Output received:
06:18:06 INFO - Usage: mozinstall-script.py [options] installer
06:18:06 INFO - Options:
06:18:06 INFO - -h, --help show this help message and exit
06:18:06 INFO - -d DEST, --destination=DEST
06:18:06 INFO - Directory to install application into. [default:
06:18:06 INFO - "C:\slave\test"]
06:18:06 INFO - --app=APP Application being installed. [default: firefox]
06:18:06 INFO - Using _rmtree_windows ...
06:18:06 INFO - Using _rmtree_windows ...
06:18:06 INFO - mkdir: C:\slave\test\build\application
06:18:06 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-53.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
06:18:06 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-53.0a1.en-US.win32.zip --destination C:\slave\test\build\application
06:18:08 INFO - Reading from file tmpfile_stdout
06:18:08 INFO - Output received:
06:18:08 INFO - C:\slave\test\build\application\firefox\firefox.exe
06:18:08 INFO - Using _rmtree_windows ...
06:18:08 INFO - Using _rmtree_windows ...
06:18:08 INFO - Running post-action listener: _resource_record_post_action
06:18:08 INFO - [mozharness: 2016-11-19 14:18:08.882000Z] Finished install step (success)
06:18:08 INFO - [mozharness: 2016-11-19 14:18:08.883000Z] Running run-tests step.
06:18:08 INFO - Running pre-action listener: _resource_record_pre_action
06:18:08 INFO - Running main action method: run_tests
06:18:08 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
06:18:08 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
06:18:08 INFO - Python 2.7.3
06:18:08 INFO - Return code: 0
06:18:08 INFO - grabbing minidump binary from tooltool
06:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:18:08 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\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 0x01964E90>, '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 0x012BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A655C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
06:18:08 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build
06:18:08 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o
06:18:08 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
06:18:08 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
06:18:09 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp2skcoz
06:18:09 INFO - INFO - File integrity verified, renaming tmp2skcoz to win32-minidump_stackwalk.exe
06:18:09 INFO - Return code: 0
06:18:09 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
06:18:09 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
06:18:09 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
06:18:09 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
06:18:09 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
06:18:09 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-137', '--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
06:18:09 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-137 --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
06:18:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:18:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:18:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:18:09 INFO - 'COMPUTERNAME': 'T-W732-IX-137',
06:18:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:18:09 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:18:09 INFO - 'DCLOCATION': 'SCL3',
06:18:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:18:09 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:18:09 INFO - 'HOMEDRIVE': 'C:',
06:18:09 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:18:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:18:09 INFO - 'KTS_VERSION': '1.19c',
06:18:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:18:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-137',
06:18:09 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
06:18:09 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
06:18:09 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:18:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:18:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:18:09 INFO - 'MOZ_NO_REMOTE': '1',
06:18:09 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
06:18:09 INFO - 'NO_EM_RESTART': '1',
06:18:09 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:18:09 INFO - 'OS': 'Windows_NT',
06:18:09 INFO - 'OURDRIVE': 'C:',
06:18:09 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:18:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:18:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:18:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:18:09 INFO - 'PROCESSOR_LEVEL': '6',
06:18:09 INFO - 'PROCESSOR_REVISION': '1e05',
06:18:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:18:09 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:18:09 INFO - 'PROMPT': '$P$G',
06:18:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:18:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:18:09 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:18:09 INFO - 'PWD': 'C:\\slave\\test',
06:18:09 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
06:18:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:18:09 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:18:09 INFO - 'SYSTEMDRIVE': 'C:',
06:18:09 INFO - 'SYSTEMROOT': 'C:\\windows',
06:18:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:18:09 INFO - 'TEST1': 'testie',
06:18:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:18:09 INFO - 'USERDOMAIN': 'T-W732-IX-137',
06:18:09 INFO - 'USERNAME': 'cltbld',
06:18:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:18:09 INFO - 'WINDIR': 'C:\\windows',
06:18:09 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:18:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:18:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:18:09 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-137', '--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
06:18:11 INFO - mozversion application_buildid: 20161119030204
06:18:11 INFO - mozversion application_changeset: f09e137ead39230eaa94f47988ccce2cfcda4195
06:18:11 INFO - mozversion application_display_name: Nightly
06:18:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
06:18:11 INFO - mozversion application_name: Firefox
06:18:11 INFO - mozversion application_remotingname: firefox
06:18:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
06:18:11 INFO - mozversion application_vendor: Mozilla
06:18:11 INFO - mozversion application_version: 53.0a1
06:18:11 INFO - mozversion platform_buildid: 20161119030204
06:18:11 INFO - mozversion platform_changeset: f09e137ead39230eaa94f47988ccce2cfcda4195
06:18:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
06:18:11 INFO - mozversion platform_version: 53.0a1
06:18:11 INFO - using testdate: 1479565091
06:18:11 INFO - actual date: 1479565091
06:18:11 INFO - starting webserver on 'localhost:49893'
06:18:11 INFO - SUITE-START | Running 2 tests
06:18:11 INFO - TEST-START | dromaeo_css
06:18:11 INFO - operating with platform_type : w7_
06:18:11 INFO - Initialising browser for dromaeo_css test...
06:18:11 INFO - TEST-INFO | started process 2872 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpusq3my\profile http://localhost:49893/getInfo.html)
06:18:18 INFO - PROCESS | 2872 | __metrics Screen width/height:1600/1200
06:18:18 INFO - PROCESS | 2872 | colorDepth:24
06:18:18 INFO - PROCESS | 2872 | Browser inner width/height: 1010/674
06:18:18 INFO - PROCESS | 2872 | __metrics
06:18:19 INFO - PROCESS | 2872 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
06:18:19 INFO - PROCESS | 2872 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
06:18:20 INFO - TEST-INFO | 2872: exit 0
06:18:20 INFO - Browser initialized.
06:18:20 INFO - Running cycle 1/1 for dromaeo_css test...
06:18:20 INFO - TEST-INFO | started process 3540 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpusq3my\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
06:18:21 INFO - PROCESS | 3540 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
06:18:31 INFO - PROCESS | 3540 | RSS: Main: 123514880
06:18:31 INFO - PROCESS | 3540 |
06:19:23 INFO - PROCESS | 3540 | 1479565163277 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]
06:21:34 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49893/tests/dromaeo/cssquery-ext.html)
06:21:37 INFO - PROCESS | 3540 | RSS: Main: 179372032
06:21:37 INFO - PROCESS | 3540 |
06:24:39 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-ext.html (next: http://localhost:49893/tests/dromaeo/cssquery-jquery.html)
06:24:40 INFO - PROCESS | 3540 | RSS: Main: 198721536
06:24:40 INFO - PROCESS | 3540 |
06:27:42 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49893/tests/dromaeo/cssquery-mootools.html)
06:27:42 INFO - PROCESS | 3540 | RSS: Main: 179781632
06:27:42 INFO - PROCESS | 3540 |
06:30:44 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49893/tests/dromaeo/cssquery-prototype.html)
06:30:45 INFO - PROCESS | 3540 | RSS: Main: 140034048
06:30:45 INFO - PROCESS | 3540 |
06:33:48 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49893/tests/dromaeo/cssquery-yui.html)
06:33:48 INFO - PROCESS | 3540 | RSS: Main: 134807552
06:33:48 INFO - PROCESS | 3540 |
06:36:51 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-yui.html (next: http://localhost:49893/tests/dromaeo/cssquery-dojo.html)
06:36:51 INFO - PROCESS | 3540 | RSS: Main: 184356864
06:36:51 INFO - PROCESS | 3540 |
06:36:51 INFO - PROCESS | 3540 | __start_tp_report
06:36:51 INFO - PROCESS | 3540 | _x_x_mozilla_page_load
06:36:51 INFO - PROCESS | 3540 | _x_x_mozilla_page_load_details
06:36:51 INFO - PROCESS | 3540 | |i|pagename|runs|
06:36:51 INFO - PROCESS | 3540 | |0;dojo.html;2770.418212175443;2809.0449247255942;2837.2339468343544;2849.52982757845;2858.6569611646605;4830.082284365968;4852.902941941168;4864.318995340655;4934.97532512337;4950.381202349711;4788.377510923584;4841.927371089437;4871.829485967983;4909.705417674942;5018.272350509174;4205.179989901971;4269.359596060593;4289.592488713567;4306.20335237982;4312.008238105238;4243.469566304212;4261.595148460891;4294.806733696975;4297.989972356494;4301.075268817203;4277.716684994489;4298.011457364808;4308.612224899758;4312.374705667677;4322.675799315049;4181.456410666624;4265.616094551489;4360.476742790854;4362.192994296065;4383.978080109611;4100.897477563055;4225.3873188387815;4233.1322078973735;4251.256030194703;4318.589733975267;4807.014562014813;4853.417589889201;4871.488493708145;4900.117978763821;4903.411590609114;5049.924251136236;5089.949100508985;5094.949050509485;5145.845624631267;5191.506806853343;5059.392872855281;5082.339295891547;5148.124818780816;5152.716600587004;5246.396664383568;4535.773211339486;4715.811367545294;4772.284157376386;4783.115123702126;4792.185328494164;5225.399079105876;5228.55557277628;5239.214117882349;5241.633085684041;5255.264263003182;4921.557059864629;4947.208446648519;4958.851234462972;5044.520770526794;5045.949540504622;5060.746962651852;5072.873178170576;5074.3657042869645;5078.415982162025;5081.695098294114;5065.594752419797;5129.12804823181;5157.767900444489;5181.145111056709;5184.274201611702;5102.387713474407;5167.302414174113;5176.068307704574;5179.2749015137915;5194.194899790538;3371.3088816792497;3375.3586818504896;3385.0521853880955;3423.9486407703903;3431.5024321473247;3130.6712795156636;3174.920626984344;3175.6348019978;3185.028566287305;3195.1692559934118;4639.443266808004;4695.81216751333;4749.097671442415;4757.548032936866;4852.2479015752615;5053.191489361684;5098.107831129611;5175.689458632494;5260.552853007461;5291.365036195682;4794.113089078462;4799.232122860326;4801.135795556834;4904;4909.091818013679;4019.638232559084;4131.97934010334;4168.8332466701595;4185.769782661982;4356.19410409069;5081.314022606901;5284.07528682479;5286.074936886106;5292.417834038176;5295.4969277918535;4931.753412329441;4943.2585112233455;5075.746212689424;5076.593872490266;5077.619178561697;5085.389753229636;5227.607929405386;5276.973615131899;5283.260343551982;5292.4442933492965;4975.253711943237;4992.201247800334;4996.775145118551;4999.65002449825;5001.674891132065;5176.792928282826;5210.27056212123;5222.869428264324;5238.5547228486;5245.868853278546;5027.723475208899;5070.366204224472;5076.060928728197;5089.949100508948;5118.129917913889;5018.673786203885;5062.240663900445;5134.409542902465;5149.201873709581;5149.356330458692;4889.608831293417;4891.730954797378;4932.136876046749;4970.826021089245;5047.570956468741;5041.773120209673;5045.7981680732355;5073.59411247092;5111.642185047011;5113.488651135006;4885.706857588556;4955.454899961073;5024.648274620741;5036.899262014813;5040.798368065236
06:36:51 INFO - PROCESS | 3540 | |1;ext.html;506.6099103690159;515.9474671669792;560.4143669864991;573.7188777499026;582.5747204540688;37160.99999999996;39429.60570394295;40651;40683.186336273255;40781.796091019576;38578.228435431345;39146;39227;39366.60633393665;39555;6591.967040164793;7726.381889448845;7891.60541972902;7914.3668506519625;7918.6436610352675;6624.503162262849;9505.334626576143;9733.610655573804;9746.415215087081;10034.548445319942;9850.162736167435;9857.112859842613;9861.70414887551;9880.308378413514;9881.703548893509;6644.833879153011;6775.983602459622;9708.271879609023;9749.561269742879;9780.364276322016;9510.572024258925;9891.30760846741;9899.52511872032;9906.05892440217;9927.404355738607;35076.6492335076;35435.645643543496;36665.81667091674;36691.4496282556;36771.81614091912;2668.2929023808706;2714.24001279642;2754.4215714699935;3093.2112311360684;3173.254285242966;28923.276918077005;29096.563551546744;29302.560461593093;29342.853285733647;29479.852600737075;3171.952420713691;3387.9153021174666;3425.160835595295;3436.329915666446;3508.2632647144133;323060;325633;325736;325939;326692.36653816816;49623.751881241085;50055.74972125116;51093.23360149601;51125.488745112816;51314.23028654573;27354.45291094167;28021.439571208462;28097.297567560974;28751.281217969718;28766.42467150645;12078.939605301917;12539.24764514132;12596;12608.49566017349;12630.747385052247;8085.716999905093;8258.132896045949;9046.864297035418;9325.626974920928;9457.952710236405;15798.526044218463;16482.83517164813;16614.83385166157;16760.329586816635;16783.2447539861;6477.676116194266;6513.446357059608;6600.669966501656;6611.966940165268;6625.536212465081;744;789.1122487201898;794.8171920458327;804.5896592737629;863.1411745313328;25845.741542584332;26917.905765419528;27132.32169195786;27160.320991975357;27302;835.4193835284419;855.6791203298762;886.5788750343535;942.1803031362758;1009.4448053570507;13521.526746564019;13966;14138.434462621586;14239.715205696035;14256;336885;434147;437641;438433;439210;46222.30666540004;47374;47540.049199015826;47551.28673069907;47642.99999999931;981.3228872078244;998.1017084623896;1068.1348108032519;1087.3910610058392;1089.8637670291373;26239;27256.591151133147;27473.115322890808;27765;27822.860885696253;41190.79404602958;41665.58334416617;41787.79106104572;41826.79086604548;41834.58165418307;355898;356284;357966;436356;612472.6302002606;32290.192745181557;32517;33142.834285828416;33313.83343083269;33321.50017749687;41352.79323603363;41373.586264138175;41918.37122443227;42259;42345.788271058445;34482.31035379228;35458.822705886305;35838.46242306419;36356.81821590981;36447.27105457929;34998.30003399969;35079.12302192466;35784.284314314085;36272.4559131608;37135.25729485449;33670;33971.83014084914;33977.66022339844;34093.65906341004;34194.82902585471;30554.84722576373;31054.534181986837;31548.684513154574;32525.18687032843;32632;22927.54144917059;23965.16121935724;24407;24678.629820553066;24791.380215494755
06:36:51 INFO - PROCESS | 3540 | |2;jquery.html;267.3010078644343;284.76364617367574;285.6800383570401;293.35315629037973;293.69015688448684;12834.294113823737;16479.340826366944;16705.749413758775;17023.40418085367;18217.635647287047;15762.10854697435;15895.28471218795;16526.504204873843;17131.31474741012;17146;4354.825806967718;4459.375687403766;4474.7762611869275;4475.932861007088;4480.574345437179;3307.2724000719804;3452.7583069185166;4272.65818734502;4303.7848107594655;4810.278458231258;4956.355673762406;5046.772895219706;5065.088284108859;5066.620003499734;5068.26510156029;4795.856124316276;5038.319826823387;5041.873953151164;5045;5051.166557518005;4769.666123371366;4771.904561908743;4809.975950120227;4820.85537433874;4824.107540105092;10138.391696498235;10181.592736290539;10208.336458130198;10379.89620103797;20924.58150836975;1690.805557360907;1695.626962068343;1739.82601739825;1910.1977169588806;1918.5491409518752;8160.469569478024;8167.63245653947;8180.263776260105;10422.426766527838;13299;1658.7926509186352;1697.4313604942358;1738.1830539646346;1817.282173541457;1830.3136323878546;80765;81255;85644.71532927074;88536;88821.55589222013;7917.168697286817;8045.557494337868;8286.212809783061;8336.791580210544;8337.541435221;9980.600775969026;10380.844287335696;10385.376877387382;10394.844077338847;10650.520726567323;6069.332373438918;6276.403741644628;6293.716782744787;6357.682115894187;6460.12788273577;6545.672716364258;6562.245341785756;6583.374579414948;6634.867302654016;6667.966660166668;3134.326119884236;3213.212762873064;3217.9034628961167;3223.8710451581674;3273;1434.4333988074861;1449.8767604753502;1449.9202543859979;1454.7963285140088;1463.7631201634601;751.2787723785227;775.1202385292734;784.6116172494652;819.647551552826;888.3825741109866;15933.681326373408;16036.358545658042;17179.141042947802;17412.296265937704;17518.91240543789;774.6359211170741;785.1481142959839;804.6178065418997;808.454293351985;814.4380377539597;9292.931312899103;9828;9831.459269740233;9850.261230407605;9950.651727189514;387479.00167986494;400290;406942;408713;411497;97156.51421742987;97249;98389.50805245928;99197;100227.49886250522;939.8355287824603;955.3455882720359;996.9102780749622;1001.3090967232587;1002.7142264454741;15743.68512629764;15817.706432567444;16057.839421605635;16117;16129.176722314643;43033;43565.346519802864;43876;44465.555344447435;46976.53023469722;60476.39523604883;60766.69616651889;62686.686566566874;63308.683456582425;63588.68205658942;6502.122213501117;6708.362705542965;6715.160604924385;6744.359285867835;6749.797506074826;7938.920610793818;11835.230710003822;12054.457549410474;12106.636800895987;12416.192947458747;9859.30984831055;10014.248931330034;10025.197984161396;10110.8483372748;10249.897501025192;8795.164459376349;10020.64927727526;10173.491325433552;10358.378497290187;10515.526801294112;10657.946710266398;10761.977612126835;10889.836652450378;11131.443427828739;11238.887611123784;6896.827579310357;6961.234264231032;6964.373206411447;7005;7097.893531596927;7482.96258518704;10257.640982565574;10302.742431439274;10442.895571044191;10773.89226107729
06:36:51 INFO - PROCESS | 3540 | |3;mootools.html;1028.8096702110115;1096.429856474633;1098.1215027977617;1156.0347110163013;1367.3026756354257;5677.347105082911;5743.339515955667;6357.936420635798;6449.8710025799455;6473.8705225895455;6503.967480162604;6514.739410423577;6543.738250469975;6544.901826472595;6546.345365463451;1824.5256233379318;1846.4368367647862;1852.0924746874002;1856;1856.6008308213732;1839.8988055656935;1873.363056560769;1877.5963167918894;1878.0703551741906;1881.5390229393818;1811.918463669138;1822.0252165091679;1827.798942116366;1843.8893666379993;1851.6019055902977;1737.4613869700306;1744;1754.9385771498;1758.2527425843964;1763.8324359185856;1717.3645751072183;1740.947771566855;1756.2184827751655;1778.6976214043643;1786.7230579260236;5747.252857128588;6093.116498107794;6127.142200091991;6141.017437210025;6292.402221788922;3840.886143018521;3916.7454115482687;3918.3338832398563;3933.5083114610675;3938.7636741795586;2665.120510231619;2767.1421859223706;2802.677692065398;2802.7757779377603;2803.523401021831;894.217559635319;913.2237598041678;935.3732998890691;937.9155875971195;940.1538615246264;7866.685332586747;7987.56068416231;8208.05607355156;8208.09710931797;8224.300934420582;3227.354529094144;3281.425433243837;3282.6553211912883;3285.83570821458;3302.7449569163564;6402.743890244338;6445.903311450332;6448.9355106449275;6458.870822583522;6478.416942475105;3738.9626103738615;3743.1390780120723;3744.045268456526;3748.3627783276906;3758.079270578671;3253.886113985999;3368.006438100766;3573.6247693991754;3579.158897659048;3643.5627724672677;2737.657792775903;2786.7770578353684;2837.616921715542;2861.7424431801237;2875.712428757108;880.9207171354481;882.1487264789381;882.770479675289;891.7681402835309;902.3007169443733;661.1933441201726;664.1233571685425;669.7321071571411;671.2180309938964;688.1810645332139;4192.098698779777;4243.618074373321;4377.212101821703;4559.133764584718;4577.22187228172;762.2225330163202;785.850688369208;786.6381464526268;790.8576456237818;801.8773716796416;2909.4908391031486;2937.4272016956916;2964.1107667700035;3062.066069848673;3072.5391191321482;6950.8609827804175;7725.343345815669;7747.18654541265;7757.68969241224;7762.7671169865;7090.149182098181;7533.698652053966;7620.733274335372;7648.961755191189;7659.731909383145;5991.490723288569;6122.326544080148;6124.265088189446;6132.141500189977;6132.601380910227;4212.936805947852;4235.068284977423;4266.765327906995;4300.376445415334;4410.272305069628;6924.792256232321;7012.6844292008;7071.398931090915;7088.681009354488;7094.326039026284;6704.363085506869;7160.749373771893;7162.677679504539;7190.352868241884;7198.928010719826;4818.879528011687;5068.518490743373;5103.821366252163;5132.204508301219;5137.614678899023;5831.912521312099;5963.254593175853;5969.253843269591;5977.701114944322;5985.221921150222;5212;5498.890022199614;5560.66636001825;5619.550435965031;5635.633683810539;5170.1727723564045;5256.421793602777;5386.946130538645;5466.72666366688;5469.753861076181;4620.376249206314;4994.900101998012;5001.24981252815;5012.298278241122;5015.172496538176;3719.9256014880093;3761.247750449866;3778.677462887967;3790.639889210521;3838.5777564467326;3777.320082385197;3780;3783.905402364963;3826.5790763016603;3840.135969406861
06:36:51 INFO - PROCESS | 3540 | |4;prototype.html;220.21932250173134;228.95937961962886;230.03843932362722;234.0497579825906;235.39620872462135;4952.257161425788;5103.974480127594;5141.866125557894;5169.431362550116;5179.223116532517;4941.234108713146;5079.263506791523;5083.720395378262;5087.592992560595;5092.287079808821;3677.7793332400006;3685.410334346505;3691.704663626917;3694;3696.5933747287777;3510.5611798525183;3623.184783423722;3694.150345420555;3702.463142844286;3704.8885334399706;3525.8413371398346;3569.5538057742783;3569.7501174917766;3578;3579.1767893384535;3631.1285291530107;3647.6352364763575;3649.923272634579;3654.2326111516613;3660.1764602964386;3081.6764239754725;3122.3911337289237;3125.3280544683003;3125.4374212641715;3125.6092988376454;4842.612590992748;4930.693366257945;4945.703257804544;4956.529129732669;4985.850424487271;4152.418661387408;4248.426462427564;4267.551907049746;4268.487781466213;4270.893227669302;5140.460251673595;5184.844454666366;5240.606954478391;5259.5529380002745;5266.499682530153;3455.4471284594597;3463.8268086595567;3474.2009337852437;3484.3553942520716;3488.3023395320783;5439.700816455093;5506.614536982413;5512.39363669996;5514.393416724157;5523.696196709179;3417.8974630761118;3443.2252743133095;3452.430348992388;3516.505485168793;3536.169000284931;3559.056849934769;3562.2697347044314;3585.1395665040723;3591.712662986955;3595.94606080909;4204.495460544754;4237.8769626049125;4241.724287921275;4247.171801498678;4262.041040765803;5162.277281180638;5306.787728490817;5308.681479111266;5318.9202161967605;5331.413544510101;1206.1858245684127;1208.8186771984097;1216.4708351866966;1217.227060816388;1218.6405010521917;748.584535582746;770.1797585571348;774.5081873010566;777.4635501503944;778.8169780101672;4856.635752318591;4864.8783780405065;4866.756662166948;4896.5837903777865;4903.774056485879;5193.506616871391;5208.713520756395;5234.502722241457;5360.812371566976;5367.677939323653;4851.7008434265035;4860;4900.142475066849;4918.286848407032;4923.87690307738;2720.1159623122167;2823.0683874321426;2823.675277343091;2827.844468554208;2837.758790502789;5558.333000040021;5572.609917305831;5592.496675299242;5594.636348637407;5609.691466969274;5395.244665746799;5467.4532546745;5478.45215478457;5480.342358916876;5490.56075513958;5286.788528458818;5401.540869026177;5556.91664625031;5584.91622625661;5617.859553511195;5260.605454590845;5301.4168441472175;5312.123499622674;5334.973325133349;5358.151437753969;5216.556592689512;5241.854887067729;5277.393099793573;5293.967676303084;5299.4700529946695;5575.358833734173;5586.469285417878;5599.188117722989;5609.9804070533755;5610.326760788732;3357.0432426758257;3486.9302613948084;3493.510908472765;3508.9649103508636;3518.3666939951054;5219.32148820666;5219.4780521947505;5241.816536421207;5295.099833028317;5319.38827034896;5205.609579281493;5235.947640523698;5241.449647786927;5242.868928276824;5243.868903277296;5084.542391184811;5233.39815921174;5334.386545547157;5340.196396891508;5360.463953604608;5217.50433708797;5220.738963051908;5241.475852414728;5257.342832145981;5260.552853007537;5145.279660847559;5326.387465441524;5354.464553544614;5355.518003379714;5389.8113566025;5261.474492243668;5287.735613219246;5293.9205911910585;5300.416954134965;5301.072312345401
06:36:51 INFO - PROCESS | 3540 | |5;yui.html;506.8251453248628;530.6418167736776;582.0512564519834;597.4592993341026;616.5221952986437;11662.533498660054;11914.463849126789;12089.697757556043;12132.332721700302;12145.08911831613;10846.674599762;11100.61147859825;11148.498317575708;11356.545738170464;11380.260283081594;512.4670342843448;521.8617066477368;522.1723568144496;523.3458177278402;523.5262087810519;517.4153206876225;524.7009204753292;525.3669328459198;525.4115390762352;525.779172747447;540.0737734784843;540.0818608365774;548.3611592494739;548.5173047718002;549.1049589169647;547.4060644201047;547.6248769592821;547.6659237864939;547.8712502561895;547.9397266300704;395.9941747960178;397.0985862093061;397.49121124960095;397.5547386926632;398.04866369377214;10198.235132365027;10370.740731481697;10385.792284154275;10418;10442.843357349646;9723.270754693427;9830.90169098307;9945.055219754111;10027.247956403226;10239.18086553074;8994.86507702385;9248.881984186419;9325;9355.251579873593;9583.185429238523;3128.2023084113634;3145.8741650334186;3222.7744057915957;3243.902073519783;3323.302106557626;62820.37179628191;64292.35707642911;64613;64674;65278.34721652818;8829.64681412749;8911.197992180734;8990.820183596292;8999.37004409685;9200.079992000747;14702.926485367505;15088.396464141531;15135.394584216509;15761.68476630461;15776.053436793829;6122.632642041492;6139.785107521216;6144.293406258248;6145.539084568675;6192.164057852222;3578.534790477221;3594.0655429588496;3599.1002249437643;3607;3693.7968411737074;2535.8098142639005;2674.652295201611;2678.116221646852;2679.102500662261;2681.865906704657;474.45437746591693;481.5208867177181;486.5620941152991;489.52760586033935;495.38819557846034;552.8977139229175;560.5879678436346;593.5370411079365;609.0438012320702;619.3094699410216;7747.418943579254;7916.208379162038;8817.11828817126;8969.923609166812;9118.36171468004;578.7453520450988;594.4531031451075;607.7174114036906;631.8831016262006;652.7193306878088;3823.904402389907;3968.444417781513;3978.224246271949;4103.589641035873;4270.807813648394;76290.23709762942;79509.20490795134;79954.20045799468;80636.59681701554;80822;67484;69044.6547767258;69408.65295673489;70015.2998470019;70157;60887.69556152191;61886.38113618896;62810.37189628045;63530;63840.36159638344;8054.247993683754;8319.251267385964;8489.533075680896;8666.306695464475;8734.781630459289;43751.343729843444;44636;44637;44643;44699.10601788011;56858.14712779394;56924.43075569357;58118.2564523065;58683;59513.70243148757;10652.786944261226;10712.464376781285;10792.190585705946;10841.132709383073;10862.619808306672;12534.22211641477;12708.491660333482;12713.491460341853;12835.358232088545;13060.934695326463;11307.434628268718;11713.590024349107;11720;11921.761564768483;11960.76078478443;11611.129165312805;11829.822552661544;11856.22934509254;11993.100517461051;12003.219790713576;11579.768404632028;11633.302001879914;11717.765644686888;11928.642140735792;11930.463129159036;10269.948650256702;10507.211959102944;10519.684409467729;10589.894101058891;10692.572297108029;10125.949370253102;10224.284300098921;10289.9485502572;10313.9484302578;10372.533236004247
06:36:51 INFO - PROCESS | 3540 | __end_tp_report
06:36:51 INFO - PROCESS | 3540 | __start_cc_report
06:36:51 INFO - PROCESS | 3540 | _x_x_mozilla_cycle_collect,2168
06:36:51 INFO - PROCESS | 3540 | __end_cc_report
06:36:51 INFO - PROCESS | 3540 | __startTimestamp1479566211371__endTimestamp
06:36:51 INFO - PROCESS | 3540 |
06:36:51 INFO - PROCESS | 3540 | ------- Summary: start -------
06:36:51 INFO - PROCESS | 3540 | Number of tests: 6
06:36:51 INFO - PROCESS | 3540 |
06:36:51 INFO - PROCESS | 3540 | [#0] dojo.html Cycles:165 Average:4722.42 Median:4955.45 stddev:606.49 (12.2%) stddev-sans-first:588.70
06:36:51 INFO - PROCESS | 3540 | Values: 2770.4 2809.0 2837.2 2849.5 2858.7 4830.1 4852.9 4864.3 4935.0 4950.4 4788.4 4841.9 4871.8 4909.7 5018.3 4205.2 4269.4 4289.6 4306.2 4312.0 4243.5 4261.6 4294.8 4298.0 4301.1 4277.7 4298.0 4308.6 4312.4 4322.7 4181.5 4265.6 4360.5 4362.2 4384.0 4100.9 4225.4 4233.1 4251.3 4318.6 4807.0 4853.4 4871.5 4900.1 4903.4 5049.9 5089.9 5094.9 5145.8 5191.5 5059.4 5082.3 5148.1 5152.7 5246.4 4535.8 4715.8 4772.3 4783.1 4792.2 5225.4 5228.6 5239.2 5241.6 5255.3 4921.6 4947.2 4958.9 5044.5 5045.9 5060.7 5072.9 5074.4 5078.4 5081.7 5065.6 5129.1 5157.8 5181.1 5184.3 5102.4 5167.3 5176.1 5179.3 5194.2 3371.3 3375.4 3385.1 3423.9 3431.5 3130.7 3174.9 3175.6 3185.0 3195.2 4639.4 4695.8 4749.1 4757.5 4852.2 5053.2 5098.1 5175.7 5260.6 5291.4 4794.1 4799.2 4801.1 4904.0 4909.1 4019.6 4132.0 4168.8 4185.8 4356.2 5081.3 5284.1 5286.1 5292.4 5295.5 4931.8 4943.3 5075.7 5076.6 5077.6 5085.4 5227.6 5277.0 5283.3 5292.4 4975.3 4992.2 4996.8 4999.7 5001.7 5176.8 5210.3 5222.9 5238.6 5245.9 5027.7 5070.4 5076.1 5089.9 5118.1 5018.7 5062.2 5134.4 5149.2 5149.4 4889.6 4891.7 4932.1 4970.8 5047.6 5041.8 5045.8 5073.6 5111.6 5113.5 4885.7 4955.5 5024.6 5036.9 5040.8
06:36:51 INFO - PROCESS | 3540 |
06:36:51 INFO - PROCESS | 3540 | [#1] ext.html Cycles:180 Average:52271.15 Median:36678.63 stddev:105187.97 (286.8%) stddev-sans-first:105411.25
06:36:51 INFO - PROCESS | 3540 | Values: 506.6 515.9 560.4 573.7 582.6 37161.0 39429.6 40651.0 40683.2 40781.8 38578.2 39146.0 39227.0 39366.6 39555.0 6592.0 7726.4 7891.6 7914.4 7918.6 6624.5 9505.3 9733.6 9746.4 10034.5 9850.2 9857.1 9861.7 9880.3 9881.7 6644.8 6776.0 9708.3 9749.6 9780.4 9510.6 9891.3 9899.5 9906.1 9927.4 35076.6 35435.6 36665.8 36691.4 36771.8 2668.3 2714.2 2754.4 3093.2 3173.3 28923.3 29096.6 29302.6 29342.9 29479.9 3172.0 3387.9 3425.2 3436.3 3508.3 323060.0 325633.0 325736.0 325939.0 326692.4 49623.8 50055.7 51093.2 51125.5 51314.2 27354.5 28021.4 28097.3 28751.3 28766.4 12078.9 12539.2 12596.0 12608.5 12630.7 8085.7 8258.1 9046.9 9325.6 9458.0 15798.5 16482.8 16614.8 16760.3 16783.2 6477.7 6513.4 6600.7 6612.0 6625.5 744.0 789.1 794.8 804.6 863.1 25845.7 26917.9 27132.3 27160.3 27302.0 835.4 855.7 886.6 942.2 1009.4 13521.5 13966.0 14138.4 14239.7 14256.0 336885.0 434147.0 437641.0 438433.0 439210.0 46222.3 47374.0 47540.0 47551.3 47643.0 981.3 998.1 1068.1 1087.4 1089.9 26239.0 27256.6 27473.1 27765.0 27822.9 41190.8 41665.6 41787.8 41826.8 41834.6 355898.0 356284.0 357966.0 436356.0 612472.6 32290.2 32517.0 33142.8 33313.8 33321.5 41352.8 41373.6 41918.4 42259.0 42345.8 34482.3 35458.8 35838.5 36356.8 36447.3 34998.3 35079.1 35784.3 36272.5 37135.3 33670.0 33971.8 33977.7 34093.7 34194.8 30554.8 31054.5 31548.7 32525.2 32632.0 22927.5 23965.2 24407.0 24678.6 24791.4
06:36:51 INFO - PROCESS | 3540 |
06:36:51 INFO - PROCESS | 3540 | [#2] jquery.html Cycles:180 Average:25606.77 Median:23960.09 stddev:67704.80 (282.6%) stddev-sans-first:67868.00
06:36:51 INFO - PROCESS | 3540 | Values: 267.3 284.8 285.7 293.4 293.7 12834.3 16479.3 16705.7 17023.4 18217.6 15762.1 15895.3 16526.5 17131.3 17146.0 4354.8 4459.4 4474.8 4475.9 4480.6 3307.3 3452.8 4272.7 4303.8 4810.3 4956.4 5046.8 5065.1 5066.6 5068.3 4795.9 5038.3 5041.9 5045.0 5051.2 4769.7 4771.9 4810.0 4820.9 4824.1 10138.4 10181.6 10208.3 10379.9 20924.6 1690.8 1695.6 1739.8 1910.2 1918.5 8160.5 8167.6 8180.3 10422.4 13299.0 1658.8 1697.4 1738.2 1817.3 1830.3 80765.0 81255.0 85644.7 88536.0 88821.6 7917.2 8045.6 8286.2 8336.8 8337.5 9980.6 10380.8 10385.4 10394.8 10650.5 6069.3 6276.4 6293.7 6357.7 6460.1 6545.7 6562.2 6583.4 6634.9 6668.0 3134.3 3213.2 3217.9 3223.9 3273.0 1434.4 1449.9 1449.9 1454.8 1463.8 751.3 775.1 784.6 819.6 888.4 15933.7 16036.4 17179.1 17412.3 17518.9 774.6 785.1 804.6 808.5 814.4 9292.9 9828.0 9831.5 9850.3 9950.7 387479.0 400290.0 406942.0 408713.0 411497.0 97156.5 97249.0 98389.5 99197.0 100227.5 939.8 955.3 996.9 1001.3 1002.7 15743.7 15817.7 16057.8 16117.0 16129.2 43033.0 43565.3 43876.0 44465.6 46976.5 60476.4 60766.7 62686.7 63308.7 63588.7 6502.1 6708.4 6715.2 6744.4 6749.8 7938.9 11835.2 12054.5 12106.6 12416.2 9859.3 10014.2 10025.2 10110.8 10249.9 8795.2 10020.6 10173.5 10358.4 10515.5 10657.9 10762.0 10889.8 11131.4 11238.9 6896.8 6961.2 6964.4 7005.0 7097.9 7483.0 10257.6 10302.7 10442.9 10773.9
06:36:51 INFO - PROCESS | 3540 |
06:36:51 INFO - PROCESS | 3540 | [#3] mootools.html Cycles:180 Average:4114.52 Median:5041.85 stddev:2205.44 (43.7%) stddev-sans-first:2199.43
06:36:51 INFO - PROCESS | 3540 | Values: 1028.8 1096.4 1098.1 1156.0 1367.3 5677.3 5743.3 6357.9 6449.9 6473.9 6504.0 6514.7 6543.7 6544.9 6546.3 1824.5 1846.4 1852.1 1856.0 1856.6 1839.9 1873.4 1877.6 1878.1 1881.5 1811.9 1822.0 1827.8 1843.9 1851.6 1737.5 1744.0 1754.9 1758.3 1763.8 1717.4 1740.9 1756.2 1778.7 1786.7 5747.3 6093.1 6127.1 6141.0 6292.4 3840.9 3916.7 3918.3 3933.5 3938.8 2665.1 2767.1 2802.7 2802.8 2803.5 894.2 913.2 935.4 937.9 940.2 7866.7 7987.6 8208.1 8208.1 8224.3 3227.4 3281.4 3282.7 3285.8 3302.7 6402.7 6445.9 6448.9 6458.9 6478.4 3739.0 3743.1 3744.0 3748.4 3758.1 3253.9 3368.0 3573.6 3579.2 3643.6 2737.7 2786.8 2837.6 2861.7 2875.7 880.9 882.1 882.8 891.8 902.3 661.2 664.1 669.7 671.2 688.2 4192.1 4243.6 4377.2 4559.1 4577.2 762.2 785.9 786.6 790.9 801.9 2909.5 2937.4 2964.1 3062.1 3072.5 6950.9 7725.3 7747.2 7757.7 7762.8 7090.1 7533.7 7620.7 7649.0 7659.7 5991.5 6122.3 6124.3 6132.1 6132.6 4212.9 4235.1 4266.8 4300.4 4410.3 6924.8 7012.7 7071.4 7088.7 7094.3 6704.4 7160.7 7162.7 7190.4 7198.9 4818.9 5068.5 5103.8 5132.2 5137.6 5831.9 5963.3 5969.3 5977.7 5985.2 5212.0 5498.9 5560.7 5619.6 5635.6 5170.2 5256.4 5386.9 5466.7 5469.8 4620.4 4994.9 5001.2 5012.3 5015.2 3719.9 3761.2 3778.7 3790.6 3838.6 3777.3 3780.0 3783.9 3826.6 3840.1
06:36:51 INFO - PROCESS | 3540 |
06:36:51 INFO - PROCESS | 3540 | [#4] prototype.html Cycles:180 Average:4322.95 Median:5089.94 stddev:1365.87 (26.8%) stddev-sans-first:1334.54
06:36:51 INFO - PROCESS | 3540 | Values: 220.2 229.0 230.0 234.0 235.4 4952.3 5104.0 5141.9 5169.4 5179.2 4941.2 5079.3 5083.7 5087.6 5092.3 3677.8 3685.4 3691.7 3694.0 3696.6 3510.6 3623.2 3694.2 3702.5 3704.9 3525.8 3569.6 3569.8 3578.0 3579.2 3631.1 3647.6 3649.9 3654.2 3660.2 3081.7 3122.4 3125.3 3125.4 3125.6 4842.6 4930.7 4945.7 4956.5 4985.9 4152.4 4248.4 4267.6 4268.5 4270.9 5140.5 5184.8 5240.6 5259.6 5266.5 3455.4 3463.8 3474.2 3484.4 3488.3 5439.7 5506.6 5512.4 5514.4 5523.7 3417.9 3443.2 3452.4 3516.5 3536.2 3559.1 3562.3 3585.1 3591.7 3595.9 4204.5 4237.9 4241.7 4247.2 4262.0 5162.3 5306.8 5308.7 5318.9 5331.4 1206.2 1208.8 1216.5 1217.2 1218.6 748.6 770.2 774.5 777.5 778.8 4856.6 4864.9 4866.8 4896.6 4903.8 5193.5 5208.7 5234.5 5360.8 5367.7 4851.7 4860.0 4900.1 4918.3 4923.9 2720.1 2823.1 2823.7 2827.8 2837.8 5558.3 5572.6 5592.5 5594.6 5609.7 5395.2 5467.5 5478.5 5480.3 5490.6 5286.8 5401.5 5556.9 5584.9 5617.9 5260.6 5301.4 5312.1 5335.0 5358.2 5216.6 5241.9 5277.4 5294.0 5299.5 5575.4 5586.5 5599.2 5610.0 5610.3 3357.0 3486.9 3493.5 3509.0 3518.4 5219.3 5219.5 5241.8 5295.1 5319.4 5205.6 5235.9 5241.4 5242.9 5243.9 5084.5 5233.4 5334.4 5340.2 5360.5 5217.5 5220.7 5241.5 5257.3 5260.6 5145.3 5326.4 5354.5 5355.5 5389.8 5261.5 5287.7 5293.9 5300.4 5301.1
06:36:51 INFO - PROCESS | 3540 |
06:36:51 INFO - PROCESS | 3540 | [#5] yui.html Cycles:180 Average:16005.83 Median:492.46 stddev:21987.10 (4464.8%) stddev-sans-first:22017.98
06:36:51 INFO - PROCESS | 3540 | Values: 506.8 530.6 582.1 597.5 616.5 11662.5 11914.5 12089.7 12132.3 12145.1 10846.7 11100.6 11148.5 11356.5 11380.3 512.5 521.9 522.2 523.3 523.5 517.4 524.7 525.4 525.4 525.8 540.1 540.1 548.4 548.5 549.1 547.4 547.6 547.7 547.9 547.9 396.0 397.1 397.5 397.6 398.0 10198.2 10370.7 10385.8 10418.0 10442.8 9723.3 9830.9 9945.1 10027.2 10239.2 8994.9 9248.9 9325.0 9355.3 9583.2 3128.2 3145.9 3222.8 3243.9 3323.3 62820.4 64292.4 64613.0 64674.0 65278.3 8829.6 8911.2 8990.8 8999.4 9200.1 14702.9 15088.4 15135.4 15761.7 15776.1 6122.6 6139.8 6144.3 6145.5 6192.2 3578.5 3594.1 3599.1 3607.0 3693.8 2535.8 2674.7 2678.1 2679.1 2681.9 474.5 481.5 486.6 489.5 495.4 552.9 560.6 593.5 609.0 619.3 7747.4 7916.2 8817.1 8969.9 9118.4 578.7 594.5 607.7 631.9 652.7 3823.9 3968.4 3978.2 4103.6 4270.8 76290.2 79509.2 79954.2 80636.6 80822.0 67484.0 69044.7 69408.7 70015.3 70157.0 60887.7 61886.4 62810.4 63530.0 63840.4 8054.2 8319.3 8489.5 8666.3 8734.8 43751.3 44636.0 44637.0 44643.0 44699.1 56858.1 56924.4 58118.3 58683.0 59513.7 10652.8 10712.5 10792.2 10841.1 10862.6 12534.2 12708.5 12713.5 12835.4 13060.9 11307.4 11713.6 11720.0 11921.8 11960.8 11611.1 11829.8 11856.2 11993.1 12003.2 11579.8 11633.3 11717.8 11928.6 11930.5 10269.9 10507.2 10519.7 10589.9 10692.6 10125.9 10224.3 10289.9 10313.9 10372.5
06:36:51 INFO - PROCESS | 3540 | -------- Summary: end --------
06:36:51 INFO - PROCESS | 3540 |
06:36:52 INFO - TEST-INFO | 3540: exit 0
06:36:52 INFO - TEST-OK | dromaeo_css | took 1121422ms
06:36:52 INFO - TEST-START | kraken
06:36:52 INFO - operating with platform_type : w7_
06:36:52 INFO - Initialising browser for kraken test...
06:36:52 INFO - TEST-INFO | started process 1496 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp4psj6x\profile http://localhost:49893/getInfo.html)
06:36:57 INFO - PROCESS | 1496 | __metrics Screen width/height:1600/1200
06:36:57 INFO - PROCESS | 1496 | colorDepth:24
06:36:57 INFO - PROCESS | 1496 | Browser inner width/height: 1010/674
06:36:57 INFO - PROCESS | 1496 | __metrics
06:36:57 INFO - PROCESS | 1496 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
06:36:57 INFO - PROCESS | 1496 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
06:36:58 INFO - PROCESS | 1496 | console.error:
06:36:58 INFO - PROCESS | 1496 | Message: Error: Connection closed before committing the transaction.
06:36:58 INFO - PROCESS | 1496 | Stack:
06:36:58 INFO - PROCESS | 1496 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23
06:36:58 INFO - PROCESS | 1496 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7
06:36:58 INFO - PROCESS | 1496 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11
06:36:58 INFO - PROCESS | 1496 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7
06:36:58 INFO - PROCESS | 1496 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7
06:36:58 INFO - PROCESS | 1496 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15
06:36:58 INFO - PROCESS | 1496 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:550:9
06:36:58 INFO - PROCESS | 1496 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
06:36:58 INFO - PROCESS | 1496 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5
06:36:58 INFO - PROCESS | 1496 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
06:36:58 INFO - PROCESS | 1496 | Barrier/this.client.addBlocker/promise13.2%
06:37:56 INFO - TinderboxPrint: I/O read bytes / time
79,496,192 / 932,680
06:37:56 INFO - TinderboxPrint: I/O write bytes / time
461,259,776 / 3,255,240
06:37:56 INFO - TinderboxPrint: CPU idle
8,261.5 (86.8%)
06:37:56 INFO - TinderboxPrint: CPU user
1,220.9 (12.8%)
06:37:56 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 35639808; Read time: 0; Write time: 125940
06:37:56 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 79496192; Write bytes: 388508672; Read time: 932680; Write time: 2922240
06:37:57 INFO - Running post-run listener: _upload_blobber_files
06:37:57 INFO - Blob upload gear active.
06:37:57 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
06:37:57 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
06:37:57 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', 'Firefox', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
06:37:57 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 Firefox -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
06:37:58 INFO - (blobuploader) - INFO - Open directory for files ...
06:37:58 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
06:37:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
06:37:58 INFO - (blobuploader) - INFO - Uploading, attempt #1.
06:37:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
06:37:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
06:37:58 INFO - (blobuploader) - INFO - Done attempting.
06:37:58 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
06:37:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
06:37:58 INFO - (blobuploader) - INFO - Uploading, attempt #1.
06:38:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
06:38:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
06:38:00 INFO - (blobuploader) - INFO - Done attempting.
06:38:00 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
06:38:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
06:38:00 INFO - (blobuploader) - INFO - Uploading, attempt #1.
06:38:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
06:38:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
06:38:08 INFO - (blobuploader) - INFO - Done attempting.
06:38:08 INFO - (blobuploader) - INFO - Iteration through files over.
06:38:08 INFO - Return code: 0
06:38:08 INFO - rmtree: C:\slave\test\build\uploaded_files.json
06:38:08 INFO - Using _rmtree_windows ...
06:38:08 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
06:38:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20be7868c4fc90fb57385de6720eb17572fd797592f0b68b23add0f768b5deae3a5f5994eff81e255cea45dd0428cb2548f517f163ea6b3febad9a9e27b9decf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/904300f3821862f940d8903a37c10b4d50ed029d4174ae9f871725f33392bc91de0b3eecee9b01c361f671ccdaf823bdca0fe4bf80b1006e4218c8ee5ff1435d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2b23b1c42a5162bba0463bb5ee9de915648beffeab81dda9a37eafaee8605135d409ac17ff7d1b3b46678259da9f8e990423f4296ae46981be3cc44db29eda9"}
06:38:08 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
06:38:08 INFO - Writing to file C:\slave\test\properties\blobber_files
06:38:08 INFO - Contents:
06:38:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20be7868c4fc90fb57385de6720eb17572fd797592f0b68b23add0f768b5deae3a5f5994eff81e255cea45dd0428cb2548f517f163ea6b3febad9a9e27b9decf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/904300f3821862f940d8903a37c10b4d50ed029d4174ae9f871725f33392bc91de0b3eecee9b01c361f671ccdaf823bdca0fe4bf80b1006e4218c8ee5ff1435d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2b23b1c42a5162bba0463bb5ee9de915648beffeab81dda9a37eafaee8605135d409ac17ff7d1b3b46678259da9f8e990423f4296ae46981be3cc44db29eda9"}
06:38:08 INFO - Running post-run listener: copy_logs_to_upload_dir
06:38:08 INFO - Copying logs to upload dir...
06:38:08 INFO - mkdir: C:\slave\test\build\upload\logs
06:38:08 INFO - Copying logs to upload dir...
06:38:08 INFO - Using _rmtree_windows ...
06:38:08 INFO - Using _rmtree_windows ...
06:38:08 INFO - Using _rmtree_windows ...
06:38:08 INFO - Using _rmtree_windows ...
06:38:08 INFO - Using _rmtree_windows ...
06:38:08 INFO - Using _rmtree_windows ...
06:38:08 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1459.994000
========= master_lag: 3.58 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 23 secs) (at 2016-11-19 06:38:11.762707) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-19 06:38:11.763740) =========
'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-137
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-137
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-137
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/Firefox/sha512/20be7868c4fc90fb57385de6720eb17572fd797592f0b68b23add0f768b5deae3a5f5994eff81e255cea45dd0428cb2548f517f163ea6b3febad9a9e27b9decf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/904300f3821862f940d8903a37c10b4d50ed029d4174ae9f871725f33392bc91de0b3eecee9b01c361f671ccdaf823bdca0fe4bf80b1006e4218c8ee5ff1435d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2b23b1c42a5162bba0463bb5ee9de915648beffeab81dda9a37eafaee8605135d409ac17ff7d1b3b46678259da9f8e990423f4296ae46981be3cc44db29eda9"}
build_url:https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://queue.taskcluster.net/v1/task/Wet4GTVhROWCAVAch5O5_w/artifacts/public/build/firefox-53.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20be7868c4fc90fb57385de6720eb17572fd797592f0b68b23add0f768b5deae3a5f5994eff81e255cea45dd0428cb2548f517f163ea6b3febad9a9e27b9decf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/904300f3821862f940d8903a37c10b4d50ed029d4174ae9f871725f33392bc91de0b3eecee9b01c361f671ccdaf823bdca0fe4bf80b1006e4218c8ee5ff1435d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2b23b1c42a5162bba0463bb5ee9de915648beffeab81dda9a37eafaee8605135d409ac17ff7d1b3b46678259da9f8e990423f4296ae46981be3cc44db29eda9"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-19 06:38:11.880140) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 06:38:11.880481) =========
'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-137
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-137
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-137
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-11-19 06:38:11.993053) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-19 06:38:11.993395) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-19 06:38:12.578254) =========
========= Total master_lag: 3.82 =========