builder: mozilla-central_win7_ix_test-dromaeojs
slave: t-w732-ix-124
starttime: 1480752651.06
results: success (0)
buildid: 20161202221643
builduid: ccfc05b7945447b9b51412dc9bfd7bdc
revision: a8e20bf36959426f0a6eb4b5df29ef3dd85ad4bd
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-03 00:10:51.058764) =========
master: http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-03 00:10:51.059246) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-03 00:10:51.059529) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-03 00:10:51.245166) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-03 00:10:51.245448) =========
'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-124
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-124
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-124
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
--00:10:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,179 (12K) [text/x-python]
0K .......... . 100% 6.23 MB/s
00:10:52 (6.23 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.166000
========= master_lag: 0.08 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-03 00:10:52.490485) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 8 secs) (at 2016-12-03 00:10:52.490931) =========
'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-124
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-124
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-124
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=8.901000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 8 secs) (at 2016-12-03 00:11:01.401842) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-03 00:11:01.402200) =========
'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev a8e20bf36959426f0a6eb4b5df29ef3dd85ad4bd --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 a8e20bf36959426f0a6eb4b5df29ef3dd85ad4bd --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-124
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-124
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-124
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-12-03 00:11:01,746 truncating revision to first 12 chars
2016-12-03 00:11:01,746 Setting DEBUG logging.
2016-12-03 00:11:01,746 attempt 1/10
2016-12-03 00:11:01,746 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a8e20bf36959?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-12-03 00:11:02,904 unpacking tar archive at: mozilla-central-a8e20bf36959/testing/mozharness/
2016-12-03 00:11:03,033 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.829000
========= master_lag: 0.05 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-03 00:11:03.277323) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-03 00:11:03.277643) =========
script_repo_revision: a8e20bf36959426f0a6eb4b5df29ef3dd85ad4bd
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-03 00:11:03.278062) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-03 00:11:03.278355) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-03 00:11:03.288312) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 52 secs) (at 2016-12-03 00:11:03.288714) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox-Non-PGO'
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-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-124
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-124
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-124
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
00:11:03 INFO - MultiFileLogger online at 20161203 00:11:03 in C:\slave\test
00:11:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
00:11:03 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
00:11:03 INFO - {'append_to_log': False,
00:11:03 INFO - 'base_work_dir': 'C:\\slave\\test',
00:11:03 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
00:11:03 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
00:11:03 INFO - 'branch': 'Firefox-Non-PGO',
00:11:03 INFO - 'buildbot_json_path': 'buildprops.json',
00:11:03 INFO - 'config_files': ('talos/windows_config.py',),
00:11:03 INFO - 'default_actions': ('clobber',
00:11:03 INFO - 'read-buildbot-config',
00:11:03 INFO - 'download-and-extract',
00:11:03 INFO - 'populate-webroot',
00:11:03 INFO - 'create-virtualenv',
00:11:03 INFO - 'install',
00:11:03 INFO - 'run-tests'),
00:11:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
00:11:03 INFO - 'download_minidump_stackwalk': True,
00:11:03 INFO - 'download_symbols': 'ondemand',
00:11:03 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
00:11:03 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
00:11:03 INFO - 'hg': 'c:/mozilla-build/hg/hg',
00:11:03 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
00:11:03 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
00:11:03 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
00:11:03 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
00:11:03 INFO - 'C:/mozilla-build/tooltool.py'),
00:11:03 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
00:11:03 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
00:11:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
00:11:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
00:11:03 INFO - 'installer_path': 'installer.exe',
00:11:03 INFO - 'log_level': 'info',
00:11:03 INFO - 'log_name': 'talos',
00:11:03 INFO - 'log_to_console': True,
00:11:03 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
00:11:03 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
00:11:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
00:11:03 INFO - 'opt_config_files': (),
00:11:03 INFO - 'pip_index': False,
00:11:03 INFO - 'sps_profile': False,
00:11:03 INFO - 'sps_profile_interval': 0,
00:11:03 INFO - 'suite': 'dromaeojs',
00:11:03 INFO - 'system_bits': '32',
00:11:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
00:11:03 INFO - 'title': 'T-W732-IX-124',
00:11:03 INFO - 'use_talos_json': True,
00:11:03 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
00:11:03 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
00:11:03 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
00:11:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
00:11:03 INFO - 'work_dir': 'build'}
00:11:03 INFO - [mozharness: 2016-12-03 08:11:03.886000Z] Running clobber step.
00:11:03 INFO - Running pre-action listener: _resource_record_pre_action
00:11:03 INFO - Running main action method: clobber
00:11:03 INFO - rmtree: C:\slave\test\build
00:11:03 INFO - Using _rmtree_windows ...
00:11:03 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
00:12:54 INFO - Running post-action listener: _resource_record_post_action
00:12:54 INFO - [mozharness: 2016-12-03 08:12:54.533000Z] Finished clobber step (success)
00:12:54 INFO - [mozharness: 2016-12-03 08:12:54.533000Z] Running read-buildbot-config step.
00:12:54 INFO - Running pre-action listener: _resource_record_pre_action
00:12:54 INFO - Running main action method: read_buildbot_config
00:12:54 INFO - Using buildbot properties:
00:12:54 INFO - {
00:12:54 INFO - "project": "",
00:12:54 INFO - "product": "firefox",
00:12:54 INFO - "script_repo_revision": "production",
00:12:54 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs",
00:12:54 INFO - "repository": "",
00:12:54 INFO - "buildername": "Windows 7 32-bit mozilla-central talos dromaeojs",
00:12:54 INFO - "buildid": "20161202221643",
00:12:54 INFO - "pgo_build": "False",
00:12:54 INFO - "basedir": "C:\\slave\\test",
00:12:54 INFO - "buildnumber": 40,
00:12:54 INFO - "slavename": "t-w732-ix-124",
00:12:54 INFO - "master": "http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/",
00:12:54 INFO - "platform": "win7_ix",
00:12:54 INFO - "scheduler": "tests-mozilla-central-win32-talos",
00:12:54 INFO - "branch": "mozilla-central",
00:12:54 INFO - "repo_path": "mozilla-central",
00:12:54 INFO - "revision": "a8e20bf36959426f0a6eb4b5df29ef3dd85ad4bd",
00:12:54 INFO - "stage_platform": "win32",
00:12:54 INFO - "builduid": "ccfc05b7945447b9b51412dc9bfd7bdc",
00:12:54 INFO - "slavebuilddir": "test"
00:12:54 INFO - }
00:12:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip.
00:12:54 INFO - Running post-action listener: _resource_record_post_action
00:12:54 INFO - [mozharness: 2016-12-03 08:12:54.537000Z] Finished read-buildbot-config step (success)
00:12:54 INFO - [mozharness: 2016-12-03 08:12:54.537000Z] Running download-and-extract step.
00:12:54 INFO - Running pre-action listener: _resource_record_pre_action
00:12:54 INFO - Running main action method: download_and_extract
00:12:54 INFO - mkdir: C:\slave\test\build\tests
00:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
00:12:54 INFO - trying https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json
00:12:54 INFO - Downloading https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/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
00:12:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/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
00:12:55 INFO - Downloaded 1274 bytes.
00:12:55 INFO - Reading from file C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json
00:12:55 INFO - Using the following test package requirements:
00:12:55 INFO - {u'common': [u'firefox-53.0a1.en-US.win32.common.tests.zip'],
00:12:55 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
00:12:55 INFO - u'firefox-53.0a1.en-US.win32.cppunittest.tests.zip'],
00:12:55 INFO - u'gtest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
00:12:55 INFO - u'firefox-53.0a1.en-US.win32.gtest.tests.zip'],
00:12:55 INFO - u'jittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
00:12:55 INFO - u'jsshell-win32.zip'],
00:12:55 INFO - u'mochitest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
00:12:55 INFO - u'firefox-53.0a1.en-US.win32.mochitest.tests.zip'],
00:12:55 INFO - u'mozbase': [u'firefox-53.0a1.en-US.win32.common.tests.zip'],
00:12:55 INFO - u'reftest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
00:12:55 INFO - u'firefox-53.0a1.en-US.win32.reftest.tests.zip'],
00:12:55 INFO - u'talos': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
00:12:55 INFO - u'firefox-53.0a1.en-US.win32.talos.tests.zip'],
00:12:55 INFO - u'web-platform': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
00:12:55 INFO - u'firefox-53.0a1.en-US.win32.web-platform.tests.zip'],
00:12:55 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
00:12:55 INFO - u'firefox-53.0a1.en-US.win32.xpcshell.tests.zip']}
00:12:55 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip'] for test suite category: common
00:12:55 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip
00:12:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1
00:12:55 INFO - Fetch https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory
00:12:57 INFO - Expected file size: 16889595
00:12:57 INFO - Obtained file size: 16889595
00:13:23 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
00:13:23 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip
00:13:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1
00:13:23 INFO - Fetch https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory
00:13:25 INFO - Expected file size: 16889595
00:13:25 INFO - Obtained file size: 16889595
00:13:35 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip
00:13:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip'}, attempt #1
00:13:35 INFO - Fetch https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip into memory
00:13:36 INFO - Expected file size: 14409734
00:13:36 INFO - Obtained file size: 14409734
00:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
00:13:48 INFO - trying https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
00:13:48 INFO - Downloading https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip to C:\slave\test\build\firefox-53.0a1.en-US.win32.zip
00:13:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/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
00:13:51 INFO - Downloaded 53378596 bytes.
00:13:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
00:13:51 INFO - mkdir: C:\slave\test\properties
00:13:51 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
00:13:51 INFO - Writing to file C:\slave\test\properties\build_url
00:13:51 INFO - Contents:
00:13:51 INFO - build_url:https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
00:13:52 INFO - Running post-action listener: _resource_record_post_action
00:13:52 INFO - Running post-action listener: set_extra_try_arguments
00:13:52 INFO - [mozharness: 2016-12-03 08:13:52.278000Z] Finished download-and-extract step (success)
00:13:52 INFO - [mozharness: 2016-12-03 08:13:52.278000Z] Running populate-webroot step.
00:13:52 INFO - Running pre-action listener: _resource_record_pre_action
00:13:52 INFO - Running main action method: populate_webroot
00:13:52 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
00:13:52 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
00:13:52 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
00:13:52 INFO - u'--tppagecycles',
00:13:52 INFO - u'7'],
00:13:52 INFO - u'tests': [u'tsvgm']}},
00:13:52 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
00:13:52 INFO - u'tests': [u'tresize', u'tcanvasmark']},
00:13:52 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
00:13:52 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
00:13:52 INFO - u'tests': [u'dromaeo_css', u'kraken']},
00:13:52 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
00:13:52 INFO - u'g1': {u'pagesets_name': u'tp5n.zip',
00:13:52 INFO - u'talos_options': [u'--disable-e10s'],
00:13:52 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
00:13:52 INFO - u'g1-e10s': {u'pagesets_name': u'tp5n.zip',
00:13:52 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
00:13:52 INFO - u'g2': {u'pagesets_name': u'tp5n.zip',
00:13:52 INFO - u'talos_options': [u'--disable-e10s'],
00:13:52 INFO - u'tests': [u'damp', u'tps']},
00:13:52 INFO - u'g2-e10s': {u'pagesets_name': u'tp5n.zip',
00:13:52 INFO - u'tests': [u'damp', u'tps']},
00:13:52 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
00:13:52 INFO - u'tests': [u'dromaeo_dom']},
00:13:52 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
00:13:52 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
00:13:52 INFO - u'tests': [u'basic_compositor_video']},
00:13:52 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
00:13:52 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
00:13:52 INFO - u'tests': [u'a11yr',
00:13:52 INFO - u'ts_paint',
00:13:52 INFO - u'tpaint',
00:13:52 INFO - u'sessionrestore',
00:13:52 INFO - u'sessionrestore_no_auto_restore',
00:13:52 INFO - u'tabpaint']},
00:13:52 INFO - u'other-e10s': {u'tests': [u'a11yr',
00:13:52 INFO - u'ts_paint',
00:13:52 INFO - u'tpaint',
00:13:52 INFO - u'sessionrestore',
00:13:52 INFO - u'sessionrestore_no_auto_restore',
00:13:52 INFO - u'tabpaint']},
00:13:52 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
00:13:52 INFO - u'ts_paint',
00:13:52 INFO - u'tpaint',
00:13:52 INFO - u'sessionrestore',
00:13:52 INFO - u'sessionrestore_no_auto_restore',
00:13:52 INFO - u'tabpaint']},
00:13:52 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
00:13:52 INFO - u'ts_paint',
00:13:52 INFO - u'tpaint',
00:13:52 INFO - u'sessionrestore',
00:13:52 INFO - u'sessionrestore_no_auto_restore',
00:13:52 INFO - u'tabpaint']},
00:13:52 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
00:13:52 INFO - u'tests': [u'a11yr',
00:13:52 INFO - u'ts_paint',
00:13:52 INFO - u'tpaint',
00:13:52 INFO - u'sessionrestore',
00:13:52 INFO - u'sessionrestore_no_auto_restore',
00:13:52 INFO - u'tabpaint']},
00:13:52 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
00:13:52 INFO - u'tests': [u'a11yr',
00:13:52 INFO - u'ts_paint',
00:13:52 INFO - u'tpaint',
00:13:52 INFO - u'sessionrestore',
00:13:52 INFO - u'sessionrestore_no_auto_restore',
00:13:52 INFO - u'tabpaint']},
00:13:52 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
00:13:52 INFO - u'tests': [u'tsvgx',
00:13:52 INFO - u'tsvgr_opacity',
00:13:52 INFO - u'tart',
00:13:52 INFO - u'tscrollx',
00:13:52 INFO - u'cart',
00:13:52 INFO - u'tsvg_static']},
00:13:52 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
00:13:52 INFO - u'tsvgr_opacity',
00:13:52 INFO - u'tart',
00:13:52 INFO - u'tscrollx',
00:13:52 INFO - u'cart',
00:13:52 INFO - u'tsvg_static']},
00:13:52 INFO - u'tp5o': {u'pagesets_name': u'tp5n.zip',
00:13:52 INFO - u'talos_options': [u'--disable-e10s'],
00:13:52 INFO - u'tests': [u'tp5o']},
00:13:52 INFO - u'tp5o-e10s': {u'pagesets_name': u'tp5n.zip',
00:13:52 INFO - u'tests': [u'tp5o']},
00:13:52 INFO - u'xperf': {u'pagesets_name': u'tp5n.zip',
00:13:52 INFO - u'talos_options': [u'--disable-e10s',
00:13:52 INFO - u'--xperf_path',
00:13:52 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
00:13:52 INFO - u'tests': [u'tp5n']},
00:13:52 INFO - u'xperf-e10s': {u'pagesets_name': u'tp5n.zip',
00:13:52 INFO - u'talos_options': [u'--xperf_path',
00:13:52 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
00:13:52 INFO - u'tests': [u'tp5n']}},
00:13:52 INFO - u'talos.zip': {u'path': u'',
00:13:52 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
00:13:52 INFO - Running post-action listener: _resource_record_post_action
00:13:52 INFO - [mozharness: 2016-12-03 08:13:52.360000Z] Finished populate-webroot step (success)
00:13:52 INFO - [mozharness: 2016-12-03 08:13:52.360000Z] Running create-virtualenv step.
00:13:52 INFO - Running pre-action listener: _resource_record_pre_action
00:13:52 INFO - Running main action method: create_virtualenv
00:13:52 INFO - Creating virtualenv C:\slave\test\build/venv
00:13:52 INFO - mkdir: C:\slave\test\build\venv\Scripts
00:13:52 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
00:13:52 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'!
00:13:52 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
00:13:52 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
00:14:00 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
00:14:04 INFO - Installing distribute......................................................................................................................................................................................done.
00:14:05 INFO - Return code: 0
00:14:05 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
00:14:05 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
00:14:05 INFO - Reading from file tmpfile_stdout
00:14:05 INFO - Output received:
00:14:05 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
00:14:05 INFO - Using _rmtree_windows ...
00:14:05 INFO - Using _rmtree_windows ...
00:14:05 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
00:14: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')]}
00:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:14: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')]}
00:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:14: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A5F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B4E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F515C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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
00:14:06 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
00:14:06 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
00:14:06 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:14:06 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:14:06 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:14:06 INFO - 'COMPUTERNAME': 'T-W732-IX-124',
00:14:06 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:14:06 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:14:06 INFO - 'DCLOCATION': 'SCL3',
00:14:06 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:14:06 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:14:06 INFO - 'HOMEDRIVE': 'C:',
00:14:06 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:14:06 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:14:06 INFO - 'KTS_VERSION': '1.19c',
00:14:06 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:14:06 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124',
00:14:06 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:14:06 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:14:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:14:06 INFO - 'MOZ_NO_REMOTE': '1',
00:14:06 INFO - 'NO_EM_RESTART': '1',
00:14:06 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:14:06 INFO - 'OS': 'Windows_NT',
00:14:06 INFO - 'OURDRIVE': 'C:',
00:14:06 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;',
00:14:06 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:14:06 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:14:06 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:14:06 INFO - 'PROCESSOR_LEVEL': '6',
00:14:06 INFO - 'PROCESSOR_REVISION': '1e05',
00:14:06 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:14:06 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:14:06 INFO - 'PROMPT': '$P$G',
00:14:06 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
00:14:06 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:14:06 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:14:06 INFO - 'PWD': 'C:\\slave\\test',
00:14:06 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:14:06 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:14:06 INFO - 'SYSTEMDRIVE': 'C:',
00:14:06 INFO - 'SYSTEMROOT': 'C:\\windows',
00:14:06 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:06 INFO - 'TEST1': 'testie',
00:14:06 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:06 INFO - 'USERDOMAIN': 'T-W732-IX-124',
00:14:06 INFO - 'USERNAME': 'cltbld',
00:14:06 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:14:06 INFO - 'WINDIR': 'C:\\windows',
00:14:06 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:14:06 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:14:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:14:06 INFO - Ignoring indexes: http://pypi.python.org/simple/
00:14:06 INFO - Downloading/unpacking pip>=1.5
00:14:11 INFO - Running setup.py egg_info for package pip
00:14:11 INFO - warning: no files found matching 'pip\cacert.pem'
00:14:11 INFO - warning: no files found matching '*.html' under directory 'docs'
00:14:11 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
00:14:11 INFO - no previously-included directories found matching 'docs\_build\_sources'
00:14:11 INFO - Installing collected packages: pip
00:14:11 INFO - Found existing installation: pip 0.8.2
00:14:11 INFO - Uninstalling pip:
00:14:11 INFO - Successfully uninstalled pip
00:14:11 INFO - Running setup.py install for pip
00:14:11 INFO - warning: no files found matching 'pip\cacert.pem'
00:14:11 INFO - warning: no files found matching '*.html' under directory 'docs'
00:14:11 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
00:14:11 INFO - no previously-included directories found matching 'docs\_build\_sources'
00:14:11 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
00:14:11 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
00:14:11 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
00:14:11 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
00:14:11 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
00:14:11 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
00:14:11 INFO - Successfully installed pip
00:14:11 INFO - Cleaning up...
00:14:11 INFO - Return code: 0
00:14:11 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
00:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
00:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
00:14:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:14:11 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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A5F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B4E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F515C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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
00:14:11 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
00:14:11 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
00:14:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:14:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:14:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:14:11 INFO - 'COMPUTERNAME': 'T-W732-IX-124',
00:14:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:14:11 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:14:11 INFO - 'DCLOCATION': 'SCL3',
00:14:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:14:11 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:14:11 INFO - 'HOMEDRIVE': 'C:',
00:14:11 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:14:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:14:11 INFO - 'KTS_VERSION': '1.19c',
00:14:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:14:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124',
00:14:11 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:14:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:14:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:14:11 INFO - 'MOZ_NO_REMOTE': '1',
00:14:11 INFO - 'NO_EM_RESTART': '1',
00:14:11 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:14:11 INFO - 'OS': 'Windows_NT',
00:14:11 INFO - 'OURDRIVE': 'C:',
00:14:11 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
00:14:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:14:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:14:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:14:11 INFO - 'PROCESSOR_LEVEL': '6',
00:14:11 INFO - 'PROCESSOR_REVISION': '1e05',
00:14:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:14:11 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:14:11 INFO - 'PROMPT': '$P$G',
00:14:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
00:14:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:14:11 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:14:11 INFO - 'PWD': 'C:\\slave\\test',
00:14:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:14:11 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:14:11 INFO - 'SYSTEMDRIVE': 'C:',
00:14:11 INFO - 'SYSTEMROOT': 'C:\\windows',
00:14:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:11 INFO - 'TEST1': 'testie',
00:14:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:11 INFO - 'USERDOMAIN': 'T-W732-IX-124',
00:14:11 INFO - 'USERNAME': 'cltbld',
00:14:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:14:11 INFO - 'WINDIR': 'C:\\windows',
00:14:11 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:14:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:14:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:14:13 INFO - Ignoring indexes: https://pypi.python.org/simple/
00:14:13 INFO - Downloading/unpacking psutil>=3.1.1
00:14:13 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
00:14:13 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
00:14:14 INFO - Installing collected packages: psutil
00:14:14 INFO - Successfully installed psutil
00:14:14 INFO - Cleaning up...
00:14:14 INFO - Return code: 0
00:14:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
00:14:14 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')]}
00:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:14:14 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')]}
00:14:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:14:14 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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A5F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B4E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F515C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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
00:14:14 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
00:14:14 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
00:14:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:14:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:14:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:14:14 INFO - 'COMPUTERNAME': 'T-W732-IX-124',
00:14:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:14:14 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:14:14 INFO - 'DCLOCATION': 'SCL3',
00:14:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:14:14 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:14:14 INFO - 'HOMEDRIVE': 'C:',
00:14:14 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:14:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:14:14 INFO - 'KTS_VERSION': '1.19c',
00:14:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:14:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124',
00:14:14 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:14:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:14:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:14:14 INFO - 'MOZ_NO_REMOTE': '1',
00:14:14 INFO - 'NO_EM_RESTART': '1',
00:14:14 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:14:14 INFO - 'OS': 'Windows_NT',
00:14:14 INFO - 'OURDRIVE': 'C:',
00:14:14 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;',
00:14:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:14:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:14:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:14:14 INFO - 'PROCESSOR_LEVEL': '6',
00:14:14 INFO - 'PROCESSOR_REVISION': '1e05',
00:14:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:14:14 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:14:14 INFO - 'PROMPT': '$P$G',
00:14:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
00:14:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:14:14 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:14:14 INFO - 'PWD': 'C:\\slave\\test',
00:14:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:14:14 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:14:14 INFO - 'SYSTEMDRIVE': 'C:',
00:14:14 INFO - 'SYSTEMROOT': 'C:\\windows',
00:14:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:14 INFO - 'TEST1': 'testie',
00:14:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:14 INFO - 'USERDOMAIN': 'T-W732-IX-124',
00:14:14 INFO - 'USERNAME': 'cltbld',
00:14:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:14:14 INFO - 'WINDIR': 'C:\\windows',
00:14:14 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:14:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:14:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:14:14 INFO - Ignoring indexes: https://pypi.python.org/simple/
00:14:14 INFO - Downloading/unpacking mozsystemmonitor==0.3
00:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
00:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
00:14:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz
00:14:16 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
00:14:16 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)
00:14:16 INFO - Installing collected packages: mozsystemmonitor
00:14:16 INFO - Running setup.py install for mozsystemmonitor
00:14:16 INFO - Successfully installed mozsystemmonitor
00:14:16 INFO - Cleaning up...
00:14:16 INFO - Return code: 0
00:14:16 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
00:14:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
00:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:14:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
00:14:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:14:16 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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A5F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B4E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F515C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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
00:14:16 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
00:14:16 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
00:14:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:14:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:14:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:14:16 INFO - 'COMPUTERNAME': 'T-W732-IX-124',
00:14:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:14:16 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:14:16 INFO - 'DCLOCATION': 'SCL3',
00:14:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:14:16 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:14:16 INFO - 'HOMEDRIVE': 'C:',
00:14:16 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:14:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:14:16 INFO - 'KTS_VERSION': '1.19c',
00:14:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:14:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124',
00:14:16 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:14:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:14:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:14:16 INFO - 'MOZ_NO_REMOTE': '1',
00:14:16 INFO - 'NO_EM_RESTART': '1',
00:14:16 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:14:16 INFO - 'OS': 'Windows_NT',
00:14:16 INFO - 'OURDRIVE': 'C:',
00:14:16 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;',
00:14:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:14:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:14:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:14:16 INFO - 'PROCESSOR_LEVEL': '6',
00:14:16 INFO - 'PROCESSOR_REVISION': '1e05',
00:14:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:14:16 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:14:16 INFO - 'PROMPT': '$P$G',
00:14:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
00:14:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:14:16 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:14:16 INFO - 'PWD': 'C:\\slave\\test',
00:14:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:14:16 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:14:16 INFO - 'SYSTEMDRIVE': 'C:',
00:14:16 INFO - 'SYSTEMROOT': 'C:\\windows',
00:14:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:16 INFO - 'TEST1': 'testie',
00:14:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:16 INFO - 'USERDOMAIN': 'T-W732-IX-124',
00:14:16 INFO - 'USERNAME': 'cltbld',
00:14:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:14:16 INFO - 'WINDIR': 'C:\\windows',
00:14:16 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:14:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:14:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:14:16 INFO - Ignoring indexes: https://pypi.python.org/simple/
00:14:16 INFO - Downloading/unpacking jsonschema==2.5.1
00:14:16 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
00:14:16 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
00:14:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
00:14:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
00:14:17 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
00:14:17 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
00:14:18 INFO - Downloading functools32-3.2.3-2.tar.gz
00:14:18 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
00:14:18 INFO - warning: no files found matching '*.txt'
00:14:18 INFO - no previously-included directories found matching 'build'
00:14:18 INFO - no previously-included directories found matching 'dist'
00:14:18 INFO - no previously-included directories found matching '.git*'
00:14:18 INFO - Installing collected packages: jsonschema, functools32
00:14:18 INFO - Running setup.py install for functools32
00:14:18 INFO - warning: no files found matching '*.txt'
00:14:18 INFO - no previously-included directories found matching 'build'
00:14:18 INFO - no previously-included directories found matching 'dist'
00:14:18 INFO - no previously-included directories found matching '.git*'
00:14:18 INFO - Successfully installed jsonschema functools32
00:14:18 INFO - Cleaning up...
00:14:18 INFO - Return code: 0
00:14:18 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
00:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
00:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
00:14:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:14:18 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A5F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B4E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F515C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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
00:14:18 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
00:14:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
00:14:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:14:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:14:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:14:18 INFO - 'COMPUTERNAME': 'T-W732-IX-124',
00:14:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:14:18 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:14:18 INFO - 'DCLOCATION': 'SCL3',
00:14:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:14:18 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:14:18 INFO - 'HOMEDRIVE': 'C:',
00:14:18 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:14:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:14:18 INFO - 'KTS_VERSION': '1.19c',
00:14:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:14:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124',
00:14:18 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:14:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:14:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:14:18 INFO - 'MOZ_NO_REMOTE': '1',
00:14:18 INFO - 'NO_EM_RESTART': '1',
00:14:18 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:14:18 INFO - 'OS': 'Windows_NT',
00:14:18 INFO - 'OURDRIVE': 'C:',
00:14:18 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
00:14:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:14:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:14:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:14:18 INFO - 'PROCESSOR_LEVEL': '6',
00:14:18 INFO - 'PROCESSOR_REVISION': '1e05',
00:14:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:14:18 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:14:18 INFO - 'PROMPT': '$P$G',
00:14:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
00:14:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:14:18 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:14:18 INFO - 'PWD': 'C:\\slave\\test',
00:14:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:14:18 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:14:18 INFO - 'SYSTEMDRIVE': 'C:',
00:14:18 INFO - 'SYSTEMROOT': 'C:\\windows',
00:14:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:18 INFO - 'TEST1': 'testie',
00:14:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:18 INFO - 'USERDOMAIN': 'T-W732-IX-124',
00:14:18 INFO - 'USERNAME': 'cltbld',
00:14:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:14:18 INFO - 'WINDIR': 'C:\\windows',
00:14:18 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:14:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:14:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/
00:14:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
00:14:19 INFO - Cleaning up...
00:14:19 INFO - Return code: 0
00:14:19 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
00:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
00:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
00:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:14:19 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A5F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B4E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F515C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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
00:14:19 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build
00:14:19 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
00:14:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:14:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:14:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:14:19 INFO - 'COMPUTERNAME': 'T-W732-IX-124',
00:14:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:14:19 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:14:19 INFO - 'DCLOCATION': 'SCL3',
00:14:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:14:19 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:14:19 INFO - 'HOMEDRIVE': 'C:',
00:14:19 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:14:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:14:19 INFO - 'KTS_VERSION': '1.19c',
00:14:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:14:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124',
00:14:19 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:14:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:14:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:14:19 INFO - 'MOZ_NO_REMOTE': '1',
00:14:19 INFO - 'NO_EM_RESTART': '1',
00:14:19 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:14:19 INFO - 'OS': 'Windows_NT',
00:14:19 INFO - 'OURDRIVE': 'C:',
00:14:19 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
00:14:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:14:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:14:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:14:19 INFO - 'PROCESSOR_LEVEL': '6',
00:14:19 INFO - 'PROCESSOR_REVISION': '1e05',
00:14:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:14:19 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:14:19 INFO - 'PROMPT': '$P$G',
00:14:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
00:14:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:14:19 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:14:19 INFO - 'PWD': 'C:\\slave\\test',
00:14:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:14:19 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:14:19 INFO - 'SYSTEMDRIVE': 'C:',
00:14:19 INFO - 'SYSTEMROOT': 'C:\\windows',
00:14:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:19 INFO - 'TEST1': 'testie',
00:14:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:19 INFO - 'USERDOMAIN': 'T-W732-IX-124',
00:14:19 INFO - 'USERNAME': 'cltbld',
00:14:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:14:19 INFO - 'WINDIR': 'C:\\windows',
00:14:19 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:14:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:14:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/
00:14:19 INFO - Downloading/unpacking blobuploader==1.2.4
00:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
00:14:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
00:14:23 INFO - Downloading blobuploader-1.2.4.tar.gz
00:14:23 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
00:14:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
00:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
00:14:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
00:14:23 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
00:14:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
00:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
00:14:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
00:14:24 INFO - Downloading docopt-0.6.1.tar.gz
00:14:24 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
00:14:24 INFO - Installing collected packages: blobuploader, requests, docopt
00:14:24 INFO - Running setup.py install for blobuploader
00:14:24 INFO - Running setup.py install for requests
00:14:25 INFO - Running setup.py install for docopt
00:14:25 INFO - Successfully installed blobuploader requests docopt
00:14:25 INFO - Cleaning up...
00:14:26 INFO - Return code: 0
00:14:26 INFO - Installing None into virtualenv C:\slave\test\build/venv
00:14: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')]}
00:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:14: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')]}
00:14:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:14:26 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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A5F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B4E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F515C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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
00:14:26 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
00:14:26 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
00:14:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:14:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:14:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:14:26 INFO - 'COMPUTERNAME': 'T-W732-IX-124',
00:14:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:14:26 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:14:26 INFO - 'DCLOCATION': 'SCL3',
00:14:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:14:26 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:14:26 INFO - 'HOMEDRIVE': 'C:',
00:14:26 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:14:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:14:26 INFO - 'KTS_VERSION': '1.19c',
00:14:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:14:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124',
00:14:26 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:14:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:14:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:14:26 INFO - 'MOZ_NO_REMOTE': '1',
00:14:26 INFO - 'NO_EM_RESTART': '1',
00:14:26 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:14:26 INFO - 'OS': 'Windows_NT',
00:14:26 INFO - 'OURDRIVE': 'C:',
00:14: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;',
00:14:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:14:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:14:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:14:26 INFO - 'PROCESSOR_LEVEL': '6',
00:14:26 INFO - 'PROCESSOR_REVISION': '1e05',
00:14:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:14:26 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:14:26 INFO - 'PROMPT': '$P$G',
00:14:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
00:14:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:14:26 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:14:26 INFO - 'PWD': 'C:\\slave\\test',
00:14:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:14:26 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:14:26 INFO - 'SYSTEMDRIVE': 'C:',
00:14:26 INFO - 'SYSTEMROOT': 'C:\\windows',
00:14:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:26 INFO - 'TEST1': 'testie',
00:14:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:26 INFO - 'USERDOMAIN': 'T-W732-IX-124',
00:14:26 INFO - 'USERNAME': 'cltbld',
00:14:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:14:26 INFO - 'WINDIR': 'C:\\windows',
00:14:26 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:14:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:14:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:14:27 INFO - Ignoring indexes: https://pypi.python.org/simple/
00:14:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
00:14:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dqjomr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
00:14:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
00:14:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a4apgp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
00:14:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
00:14:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3ij8dk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
00:14:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
00:14:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9jie15-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
00:14:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
00:14:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6vsged-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
00:14:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
00:14:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_tajiy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
00:14:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
00:14:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jf00wb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
00:14:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
00:14:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ixojln-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
00:14:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
00:14:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_kiurm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
00:14:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
00:14:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-geijh1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
00:14:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
00:14:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yekzdp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
00:14:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
00:14:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sjhlbq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
00:14:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
00:14:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zzep_e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
00:14:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
00:14:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ic1mia-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
00:14:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
00:14:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ql9ztt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
00:14:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
00:14:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u_esx7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
00:14:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
00:14:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i32ch3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
00:14:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
00:14:29 INFO - Running setup.py install for manifestparser
00:14:29 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
00:14:29 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
00:14:30 INFO - Running setup.py install for mozcrash
00:14:30 INFO - Running setup.py install for mozdebug
00:14:30 INFO - Running setup.py install for mozdevice
00:14:31 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
00:14:31 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
00:14:31 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
00:14:31 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
00:14:31 INFO - Running setup.py install for mozfile
00:14:31 INFO - Running setup.py install for mozhttpd
00:14:32 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
00:14:32 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
00:14:32 INFO - Running setup.py install for mozinfo
00:14:32 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
00:14:32 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
00:14:32 INFO - Running setup.py install for mozInstall
00:14:32 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
00:14:32 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
00:14:32 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
00:14:32 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
00:14:32 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
00:14:32 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
00:14:32 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
00:14:32 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
00:14:33 INFO - Running setup.py install for mozleak
00:14:33 INFO - Running setup.py install for mozlog
00:14:33 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
00:14:33 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
00:14:34 INFO - Running setup.py install for moznetwork
00:14:34 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
00:14:34 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
00:14:34 INFO - Running setup.py install for mozprocess
00:14:35 INFO - Running setup.py install for mozprofile
00:14:35 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
00:14:35 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
00:14:35 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
00:14:35 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
00:14:35 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
00:14:35 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
00:14:35 INFO - Running setup.py install for mozrunner
00:14:36 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
00:14:36 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
00:14:36 INFO - Running setup.py install for mozscreenshot
00:14:36 INFO - Running setup.py install for moztest
00:14:37 INFO - Running setup.py install for mozversion
00:14:37 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
00:14:37 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
00:14:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
00:14:37 INFO - Cleaning up...
00:14:37 INFO - Return code: 0
00:14:37 INFO - Installing None into virtualenv C:\slave\test\build/venv
00:14:37 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')]}
00:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:14:37 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')]}
00:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:14:37 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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A5F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B4E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F515C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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
00:14:37 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
00:14:37 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
00:14:37 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:14:37 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:14:37 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:14:37 INFO - 'COMPUTERNAME': 'T-W732-IX-124',
00:14:37 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:14:37 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:14:37 INFO - 'DCLOCATION': 'SCL3',
00:14:37 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:14:37 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:14:37 INFO - 'HOMEDRIVE': 'C:',
00:14:37 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:14:37 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:14:37 INFO - 'KTS_VERSION': '1.19c',
00:14:37 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:14:37 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124',
00:14:37 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:14:37 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:14:37 INFO - 'MOZ_NO_REMOTE': '1',
00:14:37 INFO - 'NO_EM_RESTART': '1',
00:14:37 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:14:37 INFO - 'OS': 'Windows_NT',
00:14:37 INFO - 'OURDRIVE': 'C:',
00:14:37 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;',
00:14:37 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:14:37 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:14:37 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:14:37 INFO - 'PROCESSOR_LEVEL': '6',
00:14:37 INFO - 'PROCESSOR_REVISION': '1e05',
00:14:37 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:14:37 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:14:37 INFO - 'PROMPT': '$P$G',
00:14:37 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
00:14:37 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:14:37 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:14:37 INFO - 'PWD': 'C:\\slave\\test',
00:14:37 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:14:37 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:14:37 INFO - 'SYSTEMDRIVE': 'C:',
00:14:37 INFO - 'SYSTEMROOT': 'C:\\windows',
00:14:37 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:37 INFO - 'TEST1': 'testie',
00:14:37 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:37 INFO - 'USERDOMAIN': 'T-W732-IX-124',
00:14:37 INFO - 'USERNAME': 'cltbld',
00:14:37 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:14:37 INFO - 'WINDIR': 'C:\\windows',
00:14:37 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:14:37 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:14:37 INFO - Ignoring indexes: https://pypi.python.org/simple/
00:14:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
00:14:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hueteb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
00:14:38 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))
00:14:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
00:14:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dxjoze-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
00:14:38 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))
00:14:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
00:14:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d1ardg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
00:14:38 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))
00:14:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
00:14:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yui6yr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
00:14:38 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))
00:14:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
00:14:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wgfgbw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
00:14:38 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))
00:14:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
00:14:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-s9cnu6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
00:14:38 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))
00:14:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
00:14:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fqijyg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
00:14:38 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))
00:14:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
00:14:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-05z50c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
00:14:39 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))
00:14:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
00:14:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hko8mg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
00:14:39 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))
00:14:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
00:14:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8q5zk5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
00:14:39 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))
00:14:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
00:14:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-swxhjt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
00:14:39 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))
00:14:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
00:14:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-me8o0w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
00:14:39 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))
00:14:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
00:14:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d8nkzi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
00:14:39 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))
00:14:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
00:14:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lp1kw8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
00:14:39 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))
00:14:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
00:14:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-67qnao-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
00:14:40 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))
00:14:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
00:14:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eeqkxm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
00:14:40 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))
00:14:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
00:14:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qmxlpu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
00:14:40 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))
00:14:40 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))
00:14:40 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))
00:14:40 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))
00:14:40 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))
00:14:40 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))
00:14:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
00:14:40 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
00:14:40 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
00:14:41 INFO - Downloading blessings-1.6.tar.gz
00:14:41 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
00:14:41 INFO - Installing collected packages: blessings
00:14:41 INFO - Running setup.py install for blessings
00:14:42 INFO - Successfully installed blessings
00:14:42 INFO - Cleaning up...
00:14:44 INFO - Return code: 0
00:14:44 INFO - Done creating virtualenv C:\slave\test\build/venv.
00:14:44 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
00:14:44 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
00:14:45 INFO - Reading from file tmpfile_stdout
00:14:45 INFO - Using _rmtree_windows ...
00:14:45 INFO - Using _rmtree_windows ...
00:14:45 INFO - Current package versions:
00:14:45 INFO - blessings == 1.6
00:14:45 INFO - blobuploader == 1.2.4
00:14:45 INFO - docopt == 0.6.1
00:14:45 INFO - functools32 == 3.2.3-2
00:14:45 INFO - jsonschema == 2.5.1
00:14:45 INFO - manifestparser == 1.1
00:14:45 INFO - mozInstall == 1.12
00:14:45 INFO - mozcrash == 1.0
00:14:45 INFO - mozdebug == 0.1
00:14:45 INFO - mozdevice == 0.49
00:14:45 INFO - mozfile == 1.2
00:14:45 INFO - mozhttpd == 0.7
00:14:45 INFO - mozinfo == 0.9
00:14:45 INFO - mozleak == 0.1
00:14:45 INFO - mozlog == 3.4
00:14:45 INFO - moznetwork == 0.27
00:14:45 INFO - mozprocess == 0.23
00:14:45 INFO - mozprofile == 0.28
00:14:45 INFO - mozrunner == 6.13
00:14:45 INFO - mozscreenshot == 0.1
00:14:45 INFO - mozsystemmonitor == 0.3
00:14:45 INFO - moztest == 0.7
00:14:45 INFO - mozversion == 1.4
00:14:45 INFO - psutil == 3.1.1
00:14:45 INFO - requests == 1.2.3
00:14:45 INFO - Installing None into virtualenv C:\slave\test\build/venv
00:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
00:14:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
00:14:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:14:45 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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A5F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B4E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F515C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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
00:14:45 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
00:14:45 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
00:14:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:14:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:14:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:14:45 INFO - 'COMPUTERNAME': 'T-W732-IX-124',
00:14:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:14:45 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:14:45 INFO - 'DCLOCATION': 'SCL3',
00:14:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:14:45 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:14:45 INFO - 'HOMEDRIVE': 'C:',
00:14:45 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:14:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:14:45 INFO - 'KTS_VERSION': '1.19c',
00:14:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:14:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124',
00:14:45 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:14:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:14:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:14:45 INFO - 'MOZ_NO_REMOTE': '1',
00:14:45 INFO - 'NO_EM_RESTART': '1',
00:14:45 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:14:45 INFO - 'OS': 'Windows_NT',
00:14:45 INFO - 'OURDRIVE': 'C:',
00:14:45 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;',
00:14:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:14:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:14:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:14:45 INFO - 'PROCESSOR_LEVEL': '6',
00:14:45 INFO - 'PROCESSOR_REVISION': '1e05',
00:14:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:14:45 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:14:45 INFO - 'PROMPT': '$P$G',
00:14:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
00:14:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:14:45 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:14:45 INFO - 'PWD': 'C:\\slave\\test',
00:14:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:14:45 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:14:45 INFO - 'SYSTEMDRIVE': 'C:',
00:14:45 INFO - 'SYSTEMROOT': 'C:\\windows',
00:14:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:45 INFO - 'TEST1': 'testie',
00:14:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:45 INFO - 'USERDOMAIN': 'T-W732-IX-124',
00:14:45 INFO - 'USERNAME': 'cltbld',
00:14:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:14:45 INFO - 'WINDIR': 'C:\\windows',
00:14:45 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:14:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:14:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:14:45 INFO - Ignoring indexes: https://pypi.python.org/simple/
00:14:45 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))
00:14:45 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))
00:14:45 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))
00:14:45 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))
00:14:45 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))
00:14:45 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))
00:14:45 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))
00:14:45 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))
00:14:45 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))
00:14:45 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))
00:14:45 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))
00:14:45 INFO - Cleaning up...
00:14:45 INFO - Return code: 0
00:14:45 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
00:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
00:14:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
00:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
00:14:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
00:14:45 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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A5F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B4E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F515C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-124', 'USERDOMAIN': 'T-W732-IX-124', '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-124', '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
00:14:45 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
00:14:45 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
00:14:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:14:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:14:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:14:45 INFO - 'COMPUTERNAME': 'T-W732-IX-124',
00:14:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:14:45 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:14:45 INFO - 'DCLOCATION': 'SCL3',
00:14:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:14:45 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:14:45 INFO - 'HOMEDRIVE': 'C:',
00:14:45 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:14:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:14:45 INFO - 'KTS_VERSION': '1.19c',
00:14:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:14:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124',
00:14:45 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:14:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:14:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:14:45 INFO - 'MOZ_NO_REMOTE': '1',
00:14:45 INFO - 'NO_EM_RESTART': '1',
00:14:45 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:14:45 INFO - 'OS': 'Windows_NT',
00:14:45 INFO - 'OURDRIVE': 'C:',
00:14:45 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;',
00:14:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:14:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:14:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:14:45 INFO - 'PROCESSOR_LEVEL': '6',
00:14:45 INFO - 'PROCESSOR_REVISION': '1e05',
00:14:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:14:45 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:14:45 INFO - 'PROMPT': '$P$G',
00:14:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
00:14:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:14:45 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:14:45 INFO - 'PWD': 'C:\\slave\\test',
00:14:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:14:45 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:14:45 INFO - 'SYSTEMDRIVE': 'C:',
00:14:45 INFO - 'SYSTEMROOT': 'C:\\windows',
00:14:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:45 INFO - 'TEST1': 'testie',
00:14:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:45 INFO - 'USERDOMAIN': 'T-W732-IX-124',
00:14:45 INFO - 'USERNAME': 'cltbld',
00:14:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:14:45 INFO - 'WINDIR': 'C:\\windows',
00:14:45 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:14:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:14:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:14:45 INFO - Ignoring indexes: https://pypi.python.org/simple/
00:14:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
00:14:45 INFO - Cleaning up...
00:14:45 INFO - Return code: 0
00:14:45 INFO - Running post-action listener: _resource_record_post_action
00:14:45 INFO - Running post-action listener: _start_resource_monitoring
00:14:46 INFO - Starting resource monitoring.
00:14:46 INFO - [mozharness: 2016-12-03 08:14:46.100000Z] Finished create-virtualenv step (success)
00:14:46 INFO - [mozharness: 2016-12-03 08:14:46.100000Z] Running install step.
00:14:46 INFO - Running pre-action listener: _resource_record_pre_action
00:14:46 INFO - Running main action method: install
00:14:46 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
00:14:46 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
00:14:46 INFO - Reading from file tmpfile_stdout
00:14:46 INFO - Using _rmtree_windows ...
00:14:46 INFO - Using _rmtree_windows ...
00:14:46 INFO - Detecting whether we're running mozinstall >=1.0...
00:14:46 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
00:14:46 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
00:14:46 INFO - Reading from file tmpfile_stdout
00:14:46 INFO - Output received:
00:14:46 INFO - Usage: mozinstall-script.py [options] installer
00:14:46 INFO - Options:
00:14:46 INFO - -h, --help show this help message and exit
00:14:46 INFO - -d DEST, --destination=DEST
00:14:46 INFO - Directory to install application into. [default:
00:14:46 INFO - "C:\slave\test"]
00:14:46 INFO - --app=APP Application being installed. [default: firefox]
00:14:46 INFO - Using _rmtree_windows ...
00:14:46 INFO - Using _rmtree_windows ...
00:14:46 INFO - mkdir: C:\slave\test\build\application
00:14:46 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']
00:14:46 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
00:14:48 INFO - Reading from file tmpfile_stdout
00:14:48 INFO - Output received:
00:14:48 INFO - C:\slave\test\build\application\firefox\firefox.exe
00:14:48 INFO - Using _rmtree_windows ...
00:14:48 INFO - Using _rmtree_windows ...
00:14:48 INFO - Running post-action listener: _resource_record_post_action
00:14:48 INFO - [mozharness: 2016-12-03 08:14:48.560000Z] Finished install step (success)
00:14:48 INFO - [mozharness: 2016-12-03 08:14:48.560000Z] Running run-tests step.
00:14:48 INFO - Running pre-action listener: _resource_record_pre_action
00:14:48 INFO - Running main action method: run_tests
00:14:48 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
00:14:48 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
00:14:48 INFO - Python 2.7.3
00:14:48 INFO - Return code: 0
00:14:48 INFO - grabbing minidump binary from tooltool
00:14:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
00:14:48 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\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 0x018B4E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F515C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
00:14:48 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build
00:14:48 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o
00:14:48 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
00:14:48 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
00:14:49 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpjo66vj
00:14:49 INFO - INFO - File integrity verified, renaming tmpjo66vj to win32-minidump_stackwalk.exe
00:14:49 INFO - Return code: 0
00:14:49 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
00:14:49 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
00:14:49 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
00:14:49 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
00:14:49 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
00:14:49 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-124', '--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
00:14:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-124 --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
00:14:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
00:14:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
00:14:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
00:14:49 INFO - 'COMPUTERNAME': 'T-W732-IX-124',
00:14:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
00:14:49 INFO - 'CYGWINBASE': 'C:\\cygwin',
00:14:49 INFO - 'DCLOCATION': 'SCL3',
00:14:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
00:14:49 INFO - 'FP_NO_HOST_CHECK': 'NO',
00:14:49 INFO - 'HOMEDRIVE': 'C:',
00:14:49 INFO - 'HOMEPATH': '\\Users\\cltbld',
00:14:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
00:14:49 INFO - 'KTS_VERSION': '1.19c',
00:14:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
00:14:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-124',
00:14:49 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
00:14:49 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
00:14:49 INFO - 'MONDIR': 'C:\\Monitor_config\\',
00:14:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
00:14:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
00:14:49 INFO - 'MOZ_NO_REMOTE': '1',
00:14:49 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
00:14:49 INFO - 'NO_EM_RESTART': '1',
00:14:49 INFO - 'NUMBER_OF_PROCESSORS': '8',
00:14:49 INFO - 'OS': 'Windows_NT',
00:14:49 INFO - 'OURDRIVE': 'C:',
00:14:49 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
00:14:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
00:14:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
00:14:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
00:14:49 INFO - 'PROCESSOR_LEVEL': '6',
00:14:49 INFO - 'PROCESSOR_REVISION': '1e05',
00:14:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
00:14:49 INFO - 'PROGRAMFILES': 'C:\\Program Files',
00:14:49 INFO - 'PROMPT': '$P$G',
00:14:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
00:14:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
00:14:49 INFO - 'PUBLIC': 'C:\\Users\\Public',
00:14:49 INFO - 'PWD': 'C:\\slave\\test',
00:14:49 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
00:14:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
00:14:49 INFO - 'SLAVEDIR': 'C:\\slave\\',
00:14:49 INFO - 'SYSTEMDRIVE': 'C:',
00:14:49 INFO - 'SYSTEMROOT': 'C:\\windows',
00:14:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:49 INFO - 'TEST1': 'testie',
00:14:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
00:14:49 INFO - 'USERDOMAIN': 'T-W732-IX-124',
00:14:49 INFO - 'USERNAME': 'cltbld',
00:14:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
00:14:49 INFO - 'WINDIR': 'C:\\windows',
00:14:49 INFO - 'WINDOWS_TRACING_FLAGS': '3',
00:14:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
00:14:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
00:14:49 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-124', '--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
00:14:50 INFO - mozversion application_buildid: 20161202221643
00:14:50 INFO - mozversion application_changeset: a8e20bf36959426f0a6eb4b5df29ef3dd85ad4bd
00:14:50 INFO - mozversion application_display_name: Nightly
00:14:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
00:14:50 INFO - mozversion application_name: Firefox
00:14:50 INFO - mozversion application_remotingname: firefox
00:14:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
00:14:50 INFO - mozversion application_vendor: Mozilla
00:14:50 INFO - mozversion application_version: 53.0a1
00:14:50 INFO - mozversion platform_buildid: 20161202221643
00:14:50 INFO - mozversion platform_changeset: a8e20bf36959426f0a6eb4b5df29ef3dd85ad4bd
00:14:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
00:14:50 INFO - mozversion platform_version: 53.0a1
00:14:50 INFO - using testdate: 1480752890
00:14:50 INFO - actual date: 1480752890
00:14:50 INFO - starting webserver on 'localhost:50006'
00:14:50 INFO - SUITE-START | Running 2 tests
00:14:50 INFO - TEST-START | dromaeo_css
00:14:50 INFO - operating with platform_type : w7_
00:14:50 INFO - Initialising browser for dromaeo_css test...
00:14:50 INFO - TEST-INFO | started process 3404 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpt9brj8\profile http://localhost:50006/getInfo.html)
00:14:58 INFO - PROCESS | 3404 | __metrics Screen width/height:1600/1200
00:14:58 INFO - PROCESS | 3404 | colorDepth:24
00:14:58 INFO - PROCESS | 3404 | Browser inner width/height: 1010/674
00:14:58 INFO - PROCESS | 3404 | __metrics
00:14:59 INFO - PROCESS | 3404 | 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"
00:14:59 INFO - PROCESS | 3404 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
00:15:00 INFO - TEST-INFO | 3404: exit 0
00:15:00 INFO - Browser initialized.
00:15:00 INFO - Running cycle 1/1 for dromaeo_css test...
00:15:00 INFO - TEST-INFO | started process 2668 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpt9brj8\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
00:15:01 INFO - PROCESS | 2668 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
00:15:12 INFO - PROCESS | 2668 | RSS: Main: 138395648
00:15:12 INFO - PROCESS | 2668 |
00:16:03 INFO - PROCESS | 2668 | 1480752963681 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]
00:18:18 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:50006/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50006/tests/dromaeo/cssquery-ext.html)
00:18:20 INFO - PROCESS | 2668 | RSS: Main: 173953024
00:18:20 INFO - PROCESS | 2668 |
00:21:23 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:50006/tests/dromaeo/cssquery-ext.html (next: http://localhost:50006/tests/dromaeo/cssquery-jquery.html)
00:21:23 INFO - PROCESS | 2668 | RSS: Main: 202014720
00:21:23 INFO - PROCESS | 2668 |
00:24:25 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:50006/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50006/tests/dromaeo/cssquery-mootools.html)
00:24:26 INFO - PROCESS | 2668 | RSS: Main: 147693568
00:24:26 INFO - PROCESS | 2668 |
00:27:28 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:50006/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50006/tests/dromaeo/cssquery-prototype.html)
00:27:29 INFO - PROCESS | 2668 | RSS: Main: 135905280
00:27:29 INFO - PROCESS | 2668 |
00:30:32 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:50006/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50006/tests/dromaeo/cssquery-yui.html)
00:30:32 INFO - PROCESS | 2668 | RSS: Main: 142139392
00:30:32 INFO - PROCESS | 2668 |
00:33:35 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:50006/tests/dromaeo/cssquery-yui.html (next: http://localhost:50006/tests/dromaeo/cssquery-dojo.html)
00:33:35 INFO - PROCESS | 2668 | RSS: Main: 187138048
00:33:35 INFO - PROCESS | 2668 |
00:33:35 INFO - PROCESS | 2668 | __start_tp_report
00:33:35 INFO - PROCESS | 2668 | _x_x_mozilla_page_load
00:33:35 INFO - PROCESS | 2668 | _x_x_mozilla_page_load_details
00:33:35 INFO - PROCESS | 2668 | |i|pagename|runs|
00:33:35 INFO - PROCESS | 2668 | |0;dojo.html;2327.7206735191776;2349.5888219561584;2353.7057867766534;2357.1396440299295;2360;4006.0385507478213;4013.538443079043;4127.484064491939;4129.256733787921;4132.772697501636;3931.882043538691;4018.7990600469934;4045.0494133878515;4097.774622395767;4123.773192474413;3483.4426491761324;3573.8749143779974;3579.6957258633047;3585.8565657373674;3596.0650230940014;3450.0684815100035;3548.3790336690977;3552.7335449841235;3586.6234045425244;3594.92810143797;3597.676209141177;3603.513525674027;3615.2588719312607;3619.1856832212675;3630.0198946284486;3536;3544.769589976669;3549.0772399176144;3600.0999750062615;3603.7117030637487;3506.947395789065;3525.6121826599056;3589.533360663097;3597.784132952005;3612.963870361289;4004.3993400989793;4004.4994375703036;4011.679065674739;4039.596040395966;4089;4129.752214867088;4208.431861698662;4282.8715138545895;4284.036091879333;4289.2922667759785;4237.745735255895;4313.892152696177;4314.525402205786;4325.221460137174;4332.1985432695055;3926.3128952433494;3928.5482169550296;3941.901452463654;3946.605339466088;3947.486826712509;4230.873073807791;4345.413369195118;4346.543612920598;4365.454318210223;4366.563343665672;4082.9387559186634;4137.710360274812;4173.624373806372;4196.790160491963;4206.6634669227;4163.375493675972;4191.161767646483;4206.894827629334;4234.809433575497;4251.29853574163;4107.527634322091;4135.503739551273;4171.186618609403;4239.0674051708575;4250.192463432;4162.542120366757;4223.239816832999;4244.193603215379;4252.978735106305;4255.127698821734;2948.7788415868895;3053.328267781085;3072.984635076855;3075.2465645916895;3088.05814226663;2676.4513274778624;2695.595660650918;2740.1916434651826;2766.8893244270002;2787.6515435570554;3956.5647778744315;3972.106276087915;3995.220931918248;4010.498687664042;4016.678665706795;4227.894302642397;4260.254455470343;4280.72175308604;4319.589638984291;4353.455818022747;3872.5933776953016;3927.430522574211;3934.075492259317;4040.878773636796;4045.5752146024242;3593.8742144025355;3614;3642.4718415830084;3651.3792655249204;3690.7231957603826;4288.506821715418;4289.82840686369;4406.823727050882;4408.316710909814;4421.071574969293;4219.831206751818;4328.675349348749;4332.06860524976;4335.848245311399;4337.956620433755;4176.310908700152;4255.638270746897;4282.850100246476;4283.850065247701;4285.057287396768;4077.734947228421;4111.958880411157;4136.090060186754;4137.8758637240935;4138.710290279651;4156.58434156594;4218.135282267067;4224.176285624274;4236.131593023361;4244.066305412928;4055.371417430303;4091.6726661867574;4096.16028714107;4157.251694694863;4164.500259968823;4032.2338755636333;4113.8971525712095;4124.8143833526965;4140.606642369091;4160.105577300895;3934.626210509997;3963.5243770747693;3976.2445135425332;3979.840806367713;4031.254217969685;4071.572484889044;4139.958600413957;4144.046869220036;4145.564715704808;4151.958480415157;4022.939655905105;4023.557408684984;4026.67786577079;4106.733062350938;4106.77412742302
00:33:35 INFO - PROCESS | 2668 | |1;ext.html;532.1034057612923;535.3335097803235;537.3256563013417;557.1670352822529;561.8005608009154;24633.137840175597;33766.66233337666;34564.963051108476;34811.47782783254;37950;34686;34703.305933881304;34867.82566087172;35037.474437883386;35096.59613615452;7068.328508791669;7132.2867713228525;7178.2821717828065;7178.712851485934;7194.4963852530345;6343.6193828370215;6493.253275873288;8939.865902011443;9166.816663666723;9167.358284920057;9231.6307347706;9274.768130796716;9307.581158847834;9312.953435232814;9336.066393360677;9144.771380715469;9240.122188392126;9296.116868897443;9315.673951411669;9316.114969077928;6175.7529698811995;6361.713722882481;8585.441946273471;8764.474131552064;8941.239994600466;31682.683173168203;31928.84035579807;31965.680343196735;32074.198145046328;32074.518882216784;2496.1014034947584;2603.8307510011787;2607.6870775506873;2794.5668421394716;3174.666660000713;24838.875805621035;25134.622980655306;25175.87412062946;25225.62161567578;25412.745872541407;2512.459821138464;2609.830361026527;2619.742523588688;2620.9475810483873;2667.5731882898835;195719;204665;204758;205373;205660;45659.09647618682;45693.543064569596;46091.164502034735;46668;46706.29940550827;48287.5171248283;48961.510384896406;49001.26498102531;49111.50888491141;49191.26213106806;9416.717498475056;9661;10176.592936282466;10189.490525473697;10224.795504089876;7784.455088143775;7819.139894611588;7837.6081195941115;7877.606119693993;7888.802779930547;13038.80441793374;13049.804252936214;15175.392984280505;16030.75953860693;16218.59453513648;6190.164327815777;6260.7478504298415;6296.748130074746;6311.747530098836;6343.350728810543;724.7427163356932;776.6932061835544;857.1942374168262;911.3074063711495;933.6078846884325;22645.20741774063;24824.875875620866;25624.23127306184;25740.485190296462;27148;795.9124496305402;827.5862068965517;841.1420351241699;867.197841996151;948.6821914658667;12486.375681215903;12798.16811907223;13037.674058148623;13051.869481305255;13133.737325253442;291696;294536;294967;297036;297512;41464;42435.24170928942;43076.569234307884;43249.78375108104;43293.78353108277;922.114769820966;942.1332374215738;1001.9329414173882;1044.9111825494917;1057.038095333243;24562;24975.87512062428;25281.241562753148;25535;25725.871370643028;36466.45300320444;36981.260374792895;37016;37051.258974819815;37052.62947370492;246552;251229;252216;255415;255419;21203.15187392487;21896.562068758853;21905.56188876182;21952.560948781254;22032.559348812614;36078.09804754902;36298;36387.090322742144;36416.81791591025;36594.45108323324;29781.861191653457;30726.23184420318;30962;31347.529787053667;31383.529247060855;29887.252818679706;30441.695583043886;30904.300263485726;32088;32165.35669286648;28755.5686664696;29857.70142298636;30395.696043039286;30461.847690761406;30505.54241686421;27233.86383068072;27734.722652773213;28112.43775124445;28387.858060709565;28401.573976390784;21492.570148597253;22172;23506.177283794987;23645.261279937422;23657.64513532264
00:33:36 INFO - PROCESS | 2668 | |2;jquery.html;277.8819001924182;280.34259661094734;283.5987078284228;284.5958738591201;290.4336543739708;14763.704725905489;14987.47543835966;15765.60585985351;15969.760453593191;15975.440859569917;14199.78700319496;14380;14937.17845518496;14963.925180374083;14984.85015149851;4639.304104384344;4844.394450693663;4855.198892182786;4855.902881942359;4861.927071093936;4749.881252968687;4776;4797.11253418117;4815.434983630316;4840.878978025542;4601.102784956935;4712.0811441768865;4724.252026750108;4754.881127971794;4767.737774422405;4625.930611040837;4648.930266045995;4669.929951050737;4693.78877950493;4724.76376181193;4128.60778226068;4167.812448439797;4167.812448439828;4171.707980441371;4180.895477613054;18255.178516966767;18896.149673264597;18898.90550547252;18964.24143034277;18978.62042759137;1633.3058450158635;1649.3237772513212;1659.4192032788428;1676.8155502894672;1689.569159864239;11845.763084738343;11920.821187682192;11971.341576213305;12082.637520874388;12363.93818030904;1544.320498980445;1571.9528414147594;1607.6061364965658;1611.7340638794585;1628.4951664983894;59702.70148649229;59731.70134149345;60055.39944600585;60702.08946865756;61141.6942915287;7994.280514753584;8034.47775894565;8184.263416292562;8280.356643077026;8404.705835295736;10840.186985976083;10859.457027148612;10871.782564348669;10872;10872.151734205205;5587.1339942309005;5678.261825962681;5777;5811.389804070596;5844.824655260349;5472.452754724575;5490.560755139659;5581.246531718247;5584.46947539983;5587.106063029895;5358.651687640368;5362.142057270896;5366.010644881792;5369.24830523727;5444.50999410055;1505.4580351073605;1524.329295110148;1525.4965861265755;1530.2425299476831;1544.5057581574013;672.1363048482774;734.772220611612;786.7167819584945;853.88899443072;879.2833840420037;15451.459198927983;15666.764998525032;15757.527274181793;15775.211239437982;16060;790.9723159689383;795.8050277682005;804.8470790549777;858.5192292316198;894.9910500895038;8678.913210867937;8870.290376769972;8874.378793484524;9093.227075698507;9206.26349892007;261047;265311;266083;267526;267793.8140206844;56967.43032569704;58945.705271473365;59268.11097833536;60489.69755151196;60596;790.4861839804173;794.8370584030375;814.7759366174231;817.5217497763756;827.9586020698941;13792.55178206274;13825.30873456303;14046.578602641937;14113.153210807384;14371;32559.34881302408;32661.34677306488;32690.182745431553;33680.66319336775;35047.299054019284;41061.38407923943;41497.37753933754;42068.36897446479;42398.576014239465;42451;5163.509466600667;5300.655457395257;5385.299911011543;5555.833325000256;5913.319968203712;9325.953370233105;9517.476538790434;9737.07497787709;9843.950780246052;9850.704478865646;8002.319802816825;8196.754097377088;8332.291755200877;8336.874946875923;8371.66513339484;8357.623906924082;8432.95783521103;8433.746987590383;8460.450070745383;8535.189157030072;8436.746897593082;8451.28164106058;8527.403081784216;8616.913830861611;8777.385583009129;5973.163757073926;6007.8197654070445;6011.218541589566;6057.909131363122;6174.536909731698;8051.637676304697;8236.464629799044;8281.627326770189;8563.400561960603;8675.30597552207
00:33:36 INFO - PROCESS | 2668 | |3;mootools.html;1036.5387402605834;1112.0547534595592;1129.4973736687177;1191.6842036860235;1315.8355205599305;4798.73606951618;5149.2791009258735;5307.20391941209;5309.150535914255;5446.782128714846;5229.7385130743405;5248.21276808478;5378.300820893288;5379.977804217196;5387.353517577896;1411.8376386715506;1456.8178977627797;1604.6790641871614;1640.0733585524201;1644.1532610705497;1456.7450696128187;1472.6686495538524;1475.498330567607;1599.4801689450917;1641.5731909703504;1578.289769603677;1601.2714215032133;1609.4769200009985;1621.8297078806731;1624.894381865181;1556.5952852258438;1556.9766453503207;1558.8752899767992;1562.8280889102189;1568.8744900407996;1504.7818066380423;1542.3599206329359;1544.0812716433704;1544.1507171055987;1546.5128484527338;5009.273655319958;5347.25138480613;5347.59893008022;5349.037173308841;5384.91922621161;3096.2723759916403;3259.5273685315733;3282.0810173151553;3283.9835800821083;3285.737141028712;2117.2166298469665;2154.1814110637856;2190.8466407351493;2192.5943700415473;2215.1139544182297;689.3830022130165;703.0298188499888;709.4678990756933;713.0908092182457;722.3318430451864;6764.3573860483175;6772.390484856386;6777.457803375718;6780.457563394916;6781.186257649114;2744.40995186036;2851.9429611408073;2893.580430837517;2900.5794159846705;2905.5932169496286;5761.827145185567;6025.849353766103;6034.879302414015;6042.637441753509;6068.726907289182;3166.98416507916;3244.221386867182;3248.9440931697295;3263.543103965399;3264.3144939562485;3007.8796848126267;3058.2813038935833;3063.0963865659237;3063.326068264978;3075.4156710224984;2321.593721098801;2399.36416849535;2478.3804048987754;2493.0651005872796;2585.2502774195273;846.665567101004;855.6620135046746;856.8029353248787;857.4340934982881;865.2818160926416;501.6237821633775;673.8705690019515;696.5054811084187;704.630069213667;717.4583189691854;3690.151265208963;3759.4548790425233;3845.5000849889893;3857.4599556062176;3863.092173339263;734.2327268004948;735.8822588385833;746.3320328306136;764.2243123229927;781.2539025230891;2662.3876508403177;2716.524608193598;2727.5226835303743;2732.153032559913;2798.034678036074;6028.457438830526;6166.321704612489;6231.532635052389;6233.875322493615;6236.314005459396;6215.627062376181;6226.875462490725;6227.221597300338;6238.937610623926;6260.530460215502;4868.367112275381;5013.824516141917;5026.045051440215;5071.2139618359215;5133.897322053538;3796.215778584061;3847.095932455815;3859.749116307431;3889.747166434173;3930.8427662893164;5401;5618.297712785902;5694.202811606296;5787.855303617275;5793.536517078539;5685.943140568541;5842.503387211965;5848.239728835224;5853.824385268449;5857.648541087548;4713.434387873477;4798.95201047985;4821.252705830602;4825.5174482551465;4826.107170173529;4619.815207391666;4751.691140075884;5187.844364669066;5198.142306519382;5241.213817927342;4614.192516309699;4754.239321708648;4772.76136193182;4782.330473733749;4797.208460604101;4696.976515117402;4721;4750.548697873696;4769.880752981203;4776.880577985439;4368.213721530028;4377.912441751084;4400.427944367212;4403.75779332127;4410.779461027;3404.6254911959168;3456.75802693809;3469.670381313771;3491.633378495221;3495.2310491691787;3405.9318813623095;3420.5211270421664;3426.297608990201;3455.568053993251;3462.4633181856852
00:33:36 INFO - PROCESS | 2668 | |4;prototype.html;217.18122677505795;224.7235899843193;226.58647967459385;227.6778358622549;229.49969067432988;4356.237658409777;4463.821447142119;4478.731276123431;4487.349334346518;4523.434570678665;4223.7888105594675;4280.122574872152;4295.162443323553;4309.82760689572;4356.65146788257;3180.4275230458506;3236.8543415546237;3239.7732158748895;3247.1232767152856;3255.137388592025;3210.5184222366597;3252.9349413011723;3253.5933008373954;3255.96744032559;3256.0720194744504;3088.4440800655875;3117.2986078132467;3174.904752857418;3188.2029492626843;3193.3613277344507;3197.648258691542;3198.328351046283;3198.8080715157166;3204.1669166016886;3208.1338038729646;2737.397772490049;2778.7776977841722;2782.902598409046;2785.512535306313;2786.1084452975056;4315.266404711207;4316.532378991158;4321.395004699345;4329.181743667636;4332.588404101637;3770.6606405423645;3771.434284857266;3779.130799916035;3780.584135745066;3785.6782173515285;4306.5262821089655;4500.682148693426;4521.706089104197;4561.087782443525;4581.740021494083;3005.8046226995175;3010.202296391458;3035.0136205733224;3044.2389402649337;3046.0709483607493;4732.810687572527;4746.620270378361;4750.311204875308;4757.1437141314545;4787.329773831666;3070.495457225943;3071.7235448809715;3079.3687294104657;3079.9538006929915;3089.165925200183;3095.9845200773852;3116.766242531774;3121.453745594512;3121.953170702441;3127;3525.1892064825233;3562.6437356264164;3626.655467730561;3657.3416784978426;3687.2625474905126;4523.208438523246;4564.950061485877;4567.383403240587;4577.793999270041;4580.434956424347;1013.9442330671736;1056.9694547815818;1057.899784224398;1063.6649455421518;1067.4609322292192;660.798456470781;695.1033167214338;703.1351437731619;705.9858802823915;706.6431452116648;4147.460830092069;4223.72545784523;4245.598952345718;4251.914961700811;4270.48754149504;4568.794404251817;4571.177188106173;4582.175208462442;4585.656075794329;4603.056373443436;4242.787860606957;4253.936190957138;4269.569694152432;4270.380794784801;4271.209826182167;2347.3651874137563;2386.6658667786523;2403.230966090834;2454.5827209374092;2459.274514018369;4643.512431194744;4648.83729069481;4660.207764680013;4677.251639737626;4690.81236750533;4655.906881862412;4662.790174442158;4666.953330466652;4677.3919390479705;4699.295105734167;4664.95335046652;4678;4681.50844161365;4683.929741053818;4728.574428301469;4412.889677758082;4562.771861406983;4563.863084107482;4578.954210457853;4586.312053192048;4425.845095421645;4447.510773814813;4480.596746292849;4484.304932735431;4504.954950450454;4686.953130468652;4690.827293176706;4694.882627934329;4699.17764391237;4712.269598212282;2996.3108485048124;2999.595054667679;3000.984995075011;3004.8798048078706;3011.7590592752967;4281.155133494162;4368.6723495737315;4418.933715994327;4419.9116017678825;4447.377367168664;4142.337226043818;4156.667466602725;4224.852130175429;4291.3348430992;4298.720583162084;4271.1244194939345;4278.315469524861;4299.6560275178535;4312.9137417252105;4319.179355922364;4180.289350810431;4216.494020717533;4226.598473145046;4242.830286788494;4245.174574932808;4267.103908179193;4323.891902702458;4361.8255269790125;4389.890252743579;4392.450943632046;4118.279301122351;4286.571342865688;4294.463192100988;4368.410264614236;4374.846880359172
00:33:36 INFO - PROCESS | 2668 | |5;yui.html;513.9254808052834;528.9682619042858;568.1846550200461;579.4090028171264;586.232036032797;11982.940085299571;12920;12947.676308092303;13048.217106973576;13176.670583235424;11811.232269902472;11968.880311196885;11972.940135299334;12016.519339226421;12281.95603373712;486.55237181792836;612.1491127333011;623.1680706257152;623.7317953280087;624.8469125064365;493.6692416081234;614.5636598015415;617.6386813713983;623.3019018699064;627.7269387816292;657.9835504112411;659.4164164714239;664.1631544254226;664.8869692152346;667.4726965697093;647.97732079377;653.0758976048885;657.3689258312027;658.700291367429;664.2261765043718;365.7092611373964;378.03223747206965;458.5529109118616;473.90284991576647;479.08494774979624;11476.254043487146;11563;11619.883801161965;11641.35972521511;11680.123990700731;11206.495707693173;11258.605948791754;11530.538778448852;11692.590759323382;11788.292702437795;10767.511920675148;10925.726856828644;10939.343639381583;11026.779464410667;11108.944455277753;3454.602720687128;3529.5235143255845;3576.0702217423395;3578.7315951303494;3632.7093832493338;60954;62709.05936410958;62967.99999999954;64208.678956605385;65564.3443565563;8798.692045778367;8810.207081362709;8969.50667713282;9151.496667683341;9185.402948808307;16518.83481165173;16645.83354166467;16942.322307107577;16953.406630767862;17157.91421042887;5622.887542249132;5992.67040312787;6077.696115194222;6156.969215153985;6167.537434692416;3272.4600440927456;3339.7996120232865;3372.9325413491592;3399.2521645238007;3483.1814523586936;2060.9072591733407;2091.8326533877253;2096.4863608416035;2133.3919832847564;2137;355.8274236995055;368.28368822640243;375.26073634939127;376.8355780637842;377.0404321002999;499.857540600927;513.1120393484807;536.1876756713505;549.950504454593;553.2558708537011;7167.175774785862;7225.349718525253;7477.289657482531;7514.774556763306;7666.118396384376;566.9064604340238;576.4034224577542;576.6712973605005;592.2715060475639;600;3357.815320157366;3401.4557670772556;3438.862445502202;3526.1360966562843;3557.3774589446743;55274;68034.65982670055;68388.6580567104;68455.3154468459;69424.6528767363;59645;60588.394116058276;61079.38920610737;61465;62598;54295.72852135714;54517.454825452034;54930;56410.43589564052;56596.43403565994;6905.274946130584;7062.470314726519;7076.610786406797;7094;7116.395106416013;37081.258374831814;37325.4401183977;37649.4352584706;38033;38337.04157395976;44691.55308446874;50977.490225097274;51005.489945100075;51591;51795.66919488707;8854.513001784964;8896.377253592187;8997.589931268243;9093.227075698374;9261.259099272176;10272.30506966372;10454.738631534272;10540.94729526378;10686;10694.786104278026;7243.45674074436;9499.240060795259;9542.570584323583;9882.802343953224;10029.147522460673;10054.497275136067;10551;10574.365538067741;10880.401577913291;10979.560817567208;9927.503624818875;10178.796424071328;10225.079742823182;10227.84658230142;10593.470326483799;8976.910230897869;8995.910040899507;9095.727128186165;9285.35002549815;9549.809003820024;9227.815443691223;9274.860877086983;9508.524573771145;9575.856362154434;9667.46828924416
00:33:36 INFO - PROCESS | 2668 | __end_tp_report
00:33:36 INFO - PROCESS | 2668 | __start_cc_report
00:33:36 INFO - PROCESS | 2668 | _x_x_mozilla_cycle_collect,985
00:33:36 INFO - PROCESS | 2668 | __end_cc_report
00:33:36 INFO - PROCESS | 2668 | __startTimestamp1480754015975__endTimestamp
00:33:36 INFO - PROCESS | 2668 |
00:33:36 INFO - PROCESS | 2668 | ------- Summary: start -------
00:33:36 INFO - PROCESS | 2668 | Number of tests: 6
00:33:36 INFO - PROCESS | 2668 |
00:33:36 INFO - PROCESS | 2668 | [#0] dojo.html Cycles:165 Average:3917.36 Median:4089.00 stddev:463.11 (11.3%) stddev-sans-first:447.42
00:33:36 INFO - PROCESS | 2668 | Values: 2327.7 2349.6 2353.7 2357.1 2360.0 4006.0 4013.5 4127.5 4129.3 4132.8 3931.9 4018.8 4045.0 4097.8 4123.8 3483.4 3573.9 3579.7 3585.9 3596.1 3450.1 3548.4 3552.7 3586.6 3594.9 3597.7 3603.5 3615.3 3619.2 3630.0 3536.0 3544.8 3549.1 3600.1 3603.7 3506.9 3525.6 3589.5 3597.8 3613.0 4004.4 4004.5 4011.7 4039.6 4089.0 4129.8 4208.4 4282.9 4284.0 4289.3 4237.7 4313.9 4314.5 4325.2 4332.2 3926.3 3928.5 3941.9 3946.6 3947.5 4230.9 4345.4 4346.5 4365.5 4366.6 4082.9 4137.7 4173.6 4196.8 4206.7 4163.4 4191.2 4206.9 4234.8 4251.3 4107.5 4135.5 4171.2 4239.1 4250.2 4162.5 4223.2 4244.2 4253.0 4255.1 2948.8 3053.3 3073.0 3075.2 3088.1 2676.5 2695.6 2740.2 2766.9 2787.7 3956.6 3972.1 3995.2 4010.5 4016.7 4227.9 4260.3 4280.7 4319.6 4353.5 3872.6 3927.4 3934.1 4040.9 4045.6 3593.9 3614.0 3642.5 3651.4 3690.7 4288.5 4289.8 4406.8 4408.3 4421.1 4219.8 4328.7 4332.1 4335.8 4338.0 4176.3 4255.6 4282.9 4283.9 4285.1 4077.7 4112.0 4136.1 4137.9 4138.7 4156.6 4218.1 4224.2 4236.1 4244.1 4055.4 4091.7 4096.2 4157.3 4164.5 4032.2 4113.9 4124.8 4140.6 4160.1 3934.6 3963.5 3976.2 3979.8 4031.3 4071.6 4140.0 4144.0 4145.6 4152.0 4022.9 4023.6 4026.7 4106.7 4106.8
00:33:36 INFO - PROCESS | 2668 |
00:33:36 INFO - PROCESS | 2668 | [#1] ext.html Cycles:180 Average:38985.16 Median:32126.68 stddev:66258.61 (206.2%) stddev-sans-first:66381.58
00:33:36 INFO - PROCESS | 2668 | Values: 532.1 535.3 537.3 557.2 561.8 24633.1 33766.7 34565.0 34811.5 37950.0 34686.0 34703.3 34867.8 35037.5 35096.6 7068.3 7132.3 7178.3 7178.7 7194.5 6343.6 6493.3 8939.9 9166.8 9167.4 9231.6 9274.8 9307.6 9313.0 9336.1 9144.8 9240.1 9296.1 9315.7 9316.1 6175.8 6361.7 8585.4 8764.5 8941.2 31682.7 31928.8 31965.7 32074.2 32074.5 2496.1 2603.8 2607.7 2794.6 3174.7 24838.9 25134.6 25175.9 25225.6 25412.7 2512.5 2609.8 2619.7 2620.9 2667.6 195719.0 204665.0 204758.0 205373.0 205660.0 45659.1 45693.5 46091.2 46668.0 46706.3 48287.5 48961.5 49001.3 49111.5 49191.3 9416.7 9661.0 10176.6 10189.5 10224.8 7784.5 7819.1 7837.6 7877.6 7888.8 13038.8 13049.8 15175.4 16030.8 16218.6 6190.2 6260.7 6296.7 6311.7 6343.4 724.7 776.7 857.2 911.3 933.6 22645.2 24824.9 25624.2 25740.5 27148.0 795.9 827.6 841.1 867.2 948.7 12486.4 12798.2 13037.7 13051.9 13133.7 291696.0 294536.0 294967.0 297036.0 297512.0 41464.0 42435.2 43076.6 43249.8 43293.8 922.1 942.1 1001.9 1044.9 1057.0 24562.0 24975.9 25281.2 25535.0 25725.9 36466.5 36981.3 37016.0 37051.3 37052.6 246552.0 251229.0 252216.0 255415.0 255419.0 21203.2 21896.6 21905.6 21952.6 22032.6 36078.1 36298.0 36387.1 36416.8 36594.5 29781.9 30726.2 30962.0 31347.5 31383.5 29887.3 30441.7 30904.3 32088.0 32165.4 28755.6 29857.7 30395.7 30461.8 30505.5 27233.9 27734.7 28112.4 28387.9 28401.6 21492.6 22172.0 23506.2 23645.3 23657.6
00:33:36 INFO - PROCESS | 2668 |
00:33:36 INFO - PROCESS | 2668 | [#2] jquery.html Cycles:180 Average:18921.13 Median:4842.64 stddev:44207.60 (912.9%) stddev-sans-first:44309.45
00:33:36 INFO - PROCESS | 2668 | Values: 277.9 280.3 283.6 284.6 290.4 14763.7 14987.5 15765.6 15969.8 15975.4 14199.8 14380.0 14937.2 14963.9 14984.9 4639.3 4844.4 4855.2 4855.9 4861.9 4749.9 4776.0 4797.1 4815.4 4840.9 4601.1 4712.1 4724.3 4754.9 4767.7 4625.9 4648.9 4669.9 4693.8 4724.8 4128.6 4167.8 4167.8 4171.7 4180.9 18255.2 18896.1 18898.9 18964.2 18978.6 1633.3 1649.3 1659.4 1676.8 1689.6 11845.8 11920.8 11971.3 12082.6 12363.9 1544.3 1572.0 1607.6 1611.7 1628.5 59702.7 59731.7 60055.4 60702.1 61141.7 7994.3 8034.5 8184.3 8280.4 8404.7 10840.2 10859.5 10871.8 10872.0 10872.2 5587.1 5678.3 5777.0 5811.4 5844.8 5472.5 5490.6 5581.2 5584.5 5587.1 5358.7 5362.1 5366.0 5369.2 5444.5 1505.5 1524.3 1525.5 1530.2 1544.5 672.1 734.8 786.7 853.9 879.3 15451.5 15666.8 15757.5 15775.2 16060.0 791.0 795.8 804.8 858.5 895.0 8678.9 8870.3 8874.4 9093.2 9206.3 261047.0 265311.0 266083.0 267526.0 267793.8 56967.4 58945.7 59268.1 60489.7 60596.0 790.5 794.8 814.8 817.5 828.0 13792.6 13825.3 14046.6 14113.2 14371.0 32559.3 32661.3 32690.2 33680.7 35047.3 41061.4 41497.4 42068.4 42398.6 42451.0 5163.5 5300.7 5385.3 5555.8 5913.3 9326.0 9517.5 9737.1 9844.0 9850.7 8002.3 8196.8 8332.3 8336.9 8371.7 8357.6 8433.0 8433.7 8460.5 8535.2 8436.7 8451.3 8527.4 8616.9 8777.4 5973.2 6007.8 6011.2 6057.9 6174.5 8051.6 8236.5 8281.6 8563.4 8675.3
00:33:36 INFO - PROCESS | 2668 |
00:33:36 INFO - PROCESS | 2668 | [#3] mootools.html Cycles:180 Average:3538.80 Median:4617.00 stddev:1835.15 (39.7%) stddev-sans-first:1830.67
00:33:36 INFO - PROCESS | 2668 | Values: 1036.5 1112.1 1129.5 1191.7 1315.8 4798.7 5149.3 5307.2 5309.2 5446.8 5229.7 5248.2 5378.3 5380.0 5387.4 1411.8 1456.8 1604.7 1640.1 1644.2 1456.7 1472.7 1475.5 1599.5 1641.6 1578.3 1601.3 1609.5 1621.8 1624.9 1556.6 1557.0 1558.9 1562.8 1568.9 1504.8 1542.4 1544.1 1544.2 1546.5 5009.3 5347.3 5347.6 5349.0 5384.9 3096.3 3259.5 3282.1 3284.0 3285.7 2117.2 2154.2 2190.8 2192.6 2215.1 689.4 703.0 709.5 713.1 722.3 6764.4 6772.4 6777.5 6780.5 6781.2 2744.4 2851.9 2893.6 2900.6 2905.6 5761.8 6025.8 6034.9 6042.6 6068.7 3167.0 3244.2 3248.9 3263.5 3264.3 3007.9 3058.3 3063.1 3063.3 3075.4 2321.6 2399.4 2478.4 2493.1 2585.3 846.7 855.7 856.8 857.4 865.3 501.6 673.9 696.5 704.6 717.5 3690.2 3759.5 3845.5 3857.5 3863.1 734.2 735.9 746.3 764.2 781.3 2662.4 2716.5 2727.5 2732.2 2798.0 6028.5 6166.3 6231.5 6233.9 6236.3 6215.6 6226.9 6227.2 6238.9 6260.5 4868.4 5013.8 5026.0 5071.2 5133.9 3796.2 3847.1 3859.7 3889.7 3930.8 5401.0 5618.3 5694.2 5787.9 5793.5 5685.9 5842.5 5848.2 5853.8 5857.6 4713.4 4799.0 4821.3 4825.5 4826.1 4619.8 4751.7 5187.8 5198.1 5241.2 4614.2 4754.2 4772.8 4782.3 4797.2 4697.0 4721.0 4750.5 4769.9 4776.9 4368.2 4377.9 4400.4 4403.8 4410.8 3404.6 3456.8 3469.7 3491.6 3495.2 3405.9 3420.5 3426.3 3455.6 3462.5
00:33:36 INFO - PROCESS | 2668 |
00:33:36 INFO - PROCESS | 2668 | [#4] prototype.html Cycles:180 Average:3696.07 Median:4271.17 stddev:1131.16 (26.5%) stddev-sans-first:1103.79
00:33:36 INFO - PROCESS | 2668 | Values: 217.2 224.7 226.6 227.7 229.5 4356.2 4463.8 4478.7 4487.3 4523.4 4223.8 4280.1 4295.2 4309.8 4356.7 3180.4 3236.9 3239.8 3247.1 3255.1 3210.5 3252.9 3253.6 3256.0 3256.1 3088.4 3117.3 3174.9 3188.2 3193.4 3197.6 3198.3 3198.8 3204.2 3208.1 2737.4 2778.8 2782.9 2785.5 2786.1 4315.3 4316.5 4321.4 4329.2 4332.6 3770.7 3771.4 3779.1 3780.6 3785.7 4306.5 4500.7 4521.7 4561.1 4581.7 3005.8 3010.2 3035.0 3044.2 3046.1 4732.8 4746.6 4750.3 4757.1 4787.3 3070.5 3071.7 3079.4 3080.0 3089.2 3096.0 3116.8 3121.5 3122.0 3127.0 3525.2 3562.6 3626.7 3657.3 3687.3 4523.2 4565.0 4567.4 4577.8 4580.4 1013.9 1057.0 1057.9 1063.7 1067.5 660.8 695.1 703.1 706.0 706.6 4147.5 4223.7 4245.6 4251.9 4270.5 4568.8 4571.2 4582.2 4585.7 4603.1 4242.8 4253.9 4269.6 4270.4 4271.2 2347.4 2386.7 2403.2 2454.6 2459.3 4643.5 4648.8 4660.2 4677.3 4690.8 4655.9 4662.8 4667.0 4677.4 4699.3 4665.0 4678.0 4681.5 4683.9 4728.6 4412.9 4562.8 4563.9 4579.0 4586.3 4425.8 4447.5 4480.6 4484.3 4505.0 4687.0 4690.8 4694.9 4699.2 4712.3 2996.3 2999.6 3001.0 3004.9 3011.8 4281.2 4368.7 4418.9 4419.9 4447.4 4142.3 4156.7 4224.9 4291.3 4298.7 4271.1 4278.3 4299.7 4312.9 4319.2 4180.3 4216.5 4226.6 4242.8 4245.2 4267.1 4323.9 4361.8 4389.9 4392.5 4118.3 4286.6 4294.5 4368.4 4374.8
00:33:36 INFO - PROCESS | 2668 |
00:33:36 INFO - PROCESS | 2668 | [#5] yui.html Cycles:180 Average:14561.31 Median:532.58 stddev:19336.10 (3630.7%) stddev-sans-first:19361.57
00:33:36 INFO - PROCESS | 2668 | Values: 513.9 529.0 568.2 579.4 586.2 11982.9 12920.0 12947.7 13048.2 13176.7 11811.2 11968.9 11972.9 12016.5 12282.0 486.6 612.1 623.2 623.7 624.8 493.7 614.6 617.6 623.3 627.7 658.0 659.4 664.2 664.9 667.5 648.0 653.1 657.4 658.7 664.2 365.7 378.0 458.6 473.9 479.1 11476.3 11563.0 11619.9 11641.4 11680.1 11206.5 11258.6 11530.5 11692.6 11788.3 10767.5 10925.7 10939.3 11026.8 11108.9 3454.6 3529.5 3576.1 3578.7 3632.7 60954.0 62709.1 62968.0 64208.7 65564.3 8798.7 8810.2 8969.5 9151.5 9185.4 16518.8 16645.8 16942.3 16953.4 17157.9 5622.9 5992.7 6077.7 6157.0 6167.5 3272.5 3339.8 3372.9 3399.3 3483.2 2060.9 2091.8 2096.5 2133.4 2137.0 355.8 368.3 375.3 376.8 377.0 499.9 513.1 536.2 550.0 553.3 7167.2 7225.3 7477.3 7514.8 7666.1 566.9 576.4 576.7 592.3 600.0 3357.8 3401.5 3438.9 3526.1 3557.4 55274.0 68034.7 68388.7 68455.3 69424.7 59645.0 60588.4 61079.4 61465.0 62598.0 54295.7 54517.5 54930.0 56410.4 56596.4 6905.3 7062.5 7076.6 7094.0 7116.4 37081.3 37325.4 37649.4 38033.0 38337.0 44691.6 50977.5 51005.5 51591.0 51795.7 8854.5 8896.4 8997.6 9093.2 9261.3 10272.3 10454.7 10540.9 10686.0 10694.8 7243.5 9499.2 9542.6 9882.8 10029.1 10054.5 10551.0 10574.4 10880.4 10979.6 9927.5 10178.8 10225.1 10227.8 10593.5 8976.9 8995.9 9095.7 9285.4 9549.8 9227.8 9274.9 9508.5 9575.9 9667.5
00:33:36 INFO - PROCESS | 2668 | -------- Summary: end --------
00:33:36 INFO - PROCESS | 2668 |
00:33:36 INFO - TEST-INFO | 2668: exit 0
00:33:37 INFO - TEST-OK | dromaeo_css | took 1126731ms
00:33:37 INFO - TEST-START | kraken
00:33:37 INFO - operating with platform_type : w7_
00:33:37 INFO - Initialising browser for kraken test...
00:33:37 INFO - TEST-INFO | started process 3544 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpnm4gaz\profile http://localhost:50006/getInfo.html)
00:33:42 INFO - PROCESS | 3544 | __metrics Screen width/height:1600/1200
00:33:42 INFO - PROCESS | 3544 | colorDepth:24
00:33:42 INFO - PROCESS | 3544 | Browser inner width/height: 1010/674
00:33:42 INFO - PROCESS | 3544 | __metrics
00:33:42 INFO - PROCESS | 3544 | 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"
00:33:42 INFO - PROCESS | 3544 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
00:33:43 INFO - PROCESS | 3544 | console.error:
00:33:43 INFO - PROCESS | 3544 | Message: Error: Connection closed before committing the transaction.
00:33:43 INFO - PROCESS | 3544 | Stack:
00:33:43 INFO - PROCESS | 3544 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23
00:33:43 INFO - PROCESS | 3544 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7
00:33:43 INFO - PROCESS | 3544 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11
00:33:43 INFO - PROCESS | 3544 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7
00:33:43 INFO - PROCESS | 3544 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7
00:33:43 INFO - PROCESS | 3544 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15
00:33:43 INFO - PROCESS | 3544 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:550:9
00:33:43 INFO - PROCESS | 3544 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
00:33:43 INFO - PROCESS | 3544 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5
00:33:43 INFO - PROCESS | 3544 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
00:33:43 INFO - PROCESS | 3544 | Barrier/this.client.addBlocker/promise13.4%
00:34:44 INFO - TinderboxPrint: I/O read bytes / time
176,221,696 / 15,687,840
00:34:44 INFO - TinderboxPrint: I/O write bytes / time
483,318,272 / 3,267,950
00:34:44 INFO - TinderboxPrint: CPU idle
8,290.9 (86.6%)
00:34:44 INFO - TinderboxPrint: CPU user
1,240.6 (13.0%)
00:34:44 INFO - install - Wall time: 2s; CPU: 12.0%; Read bytes: 16384; Write bytes: 46653952; Read time: 530; Write time: 41900
00:34:44 INFO - run-tests - Wall time: 1196s; CPU: 13.0%; Read bytes: 176205312; Write bytes: 410462720; Read time: 15687310; Write time: 3161680
00:34:44 INFO - Running post-run listener: _upload_blobber_files
00:34:44 INFO - Blob upload gear active.
00:34:44 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
00:34:44 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
00:34:44 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-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
00:34:44 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-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
00:34:45 INFO - (blobuploader) - INFO - Open directory for files ...
00:34:45 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
00:34:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
00:34:45 INFO - (blobuploader) - INFO - Uploading, attempt #1.
00:34:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
00:34:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
00:34:46 INFO - (blobuploader) - INFO - Done attempting.
00:34:46 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
00:34:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
00:34:46 INFO - (blobuploader) - INFO - Uploading, attempt #1.
00:34:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
00:34:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
00:34:47 INFO - (blobuploader) - INFO - Done attempting.
00:34:47 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
00:34:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
00:34:47 INFO - (blobuploader) - INFO - Uploading, attempt #1.
00:34:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
00:34:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
00:34:55 INFO - (blobuploader) - INFO - Done attempting.
00:34:55 INFO - (blobuploader) - INFO - Iteration through files over.
00:34:55 INFO - Return code: 0
00:34:55 INFO - rmtree: C:\slave\test\build\uploaded_files.json
00:34:55 INFO - Using _rmtree_windows ...
00:34:55 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
00:34:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd487522ad8a6873fe3484e9f99f65f02a3af4832e1ac472e2ca9ee6773d896ce1222744259e85cb7d390d563dcdc9b43d0bdff572b1150c6bd19d6b58f2ecdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a9982c40a6b06a98b36948bdf9d59198286a7b7f87a9da1be65e5ea2efc19d29d332fd635ba7e8218e50af5f14f6aaab181f6501415aead2fb706648baa9eec8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2790fa0b54e6032ea64ec9ccedf435a9f8d543e44af8a8ee93d38b6fdee5bd1e1fb34fa6fffa2cbeeca585cf090cc55d96d296bdd703b343eea7bf5bfe92f302"}
00:34:55 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
00:34:55 INFO - Writing to file C:\slave\test\properties\blobber_files
00:34:55 INFO - Contents:
00:34:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd487522ad8a6873fe3484e9f99f65f02a3af4832e1ac472e2ca9ee6773d896ce1222744259e85cb7d390d563dcdc9b43d0bdff572b1150c6bd19d6b58f2ecdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a9982c40a6b06a98b36948bdf9d59198286a7b7f87a9da1be65e5ea2efc19d29d332fd635ba7e8218e50af5f14f6aaab181f6501415aead2fb706648baa9eec8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2790fa0b54e6032ea64ec9ccedf435a9f8d543e44af8a8ee93d38b6fdee5bd1e1fb34fa6fffa2cbeeca585cf090cc55d96d296bdd703b343eea7bf5bfe92f302"}
00:34:55 INFO - Running post-run listener: copy_logs_to_upload_dir
00:34:55 INFO - Copying logs to upload dir...
00:34:55 INFO - mkdir: C:\slave\test\build\upload\logs
00:34:55 INFO - Copying logs to upload dir...
00:34:55 INFO - Using _rmtree_windows ...
00:34:55 INFO - Using _rmtree_windows ...
00:34:55 INFO - Using _rmtree_windows ...
00:34:55 INFO - Using _rmtree_windows ...
00:34:55 INFO - Using _rmtree_windows ...
00:34:55 INFO - Using _rmtree_windows ...
00:34:55 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1432.476000
========= master_lag: 0.04 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 52 secs) (at 2016-12-03 00:34:55.803066) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-03 00:34:55.804019) =========
'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-124
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-124
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-124
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-Non-PGO/sha512/dd487522ad8a6873fe3484e9f99f65f02a3af4832e1ac472e2ca9ee6773d896ce1222744259e85cb7d390d563dcdc9b43d0bdff572b1150c6bd19d6b58f2ecdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a9982c40a6b06a98b36948bdf9d59198286a7b7f87a9da1be65e5ea2efc19d29d332fd635ba7e8218e50af5f14f6aaab181f6501415aead2fb706648baa9eec8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2790fa0b54e6032ea64ec9ccedf435a9f8d543e44af8a8ee93d38b6fdee5bd1e1fb34fa6fffa2cbeeca585cf090cc55d96d296bdd703b343eea7bf5bfe92f302"}
build_url:https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.102000
build_url: 'https://queue.taskcluster.net/v1/task/HIJSnDcgTA2lWMoCbBqGJg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd487522ad8a6873fe3484e9f99f65f02a3af4832e1ac472e2ca9ee6773d896ce1222744259e85cb7d390d563dcdc9b43d0bdff572b1150c6bd19d6b58f2ecdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a9982c40a6b06a98b36948bdf9d59198286a7b7f87a9da1be65e5ea2efc19d29d332fd635ba7e8218e50af5f14f6aaab181f6501415aead2fb706648baa9eec8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2790fa0b54e6032ea64ec9ccedf435a9f8d543e44af8a8ee93d38b6fdee5bd1e1fb34fa6fffa2cbeeca585cf090cc55d96d296bdd703b343eea7bf5bfe92f302"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-03 00:34:55.922999) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 00:34:55.923389) =========
'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-124
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-124
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-124
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.100000
========= master_lag: 0.01 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 00:34:56.033358) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-12-03 00:34:56.033681) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-12-03 00:34:56.491940) =========
========= Total master_lag: 0.20 =========