builder: mozilla-central_win7_ix_test-dromaeojs
slave: t-w732-ix-050
starttime: 1480674510.33
results: success (0)
buildid: 20161202002343
builduid: 5a55d89f2a0d4fc5926346ed8c785ba7
revision: b1e2d1fe6b070b71661047f94129a1ccd0a7f48f
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-02 02:28:30.328454) =========
master: http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-02 02:28:30.329300) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-02 02:28:30.329590) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-02 02:28:30.456044) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-02 02:28:30.456407) =========
'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-050
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-050
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-050
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
--02:28:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,179 (12K) [text/x-python]
0K .......... . 100% 8.45 MB/s
02:28:31 (8.45 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.149000
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-02 02:28:31.620631) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2016-12-02 02:28:31.620942) =========
'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-050
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-050
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-050
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=9.502000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2016-12-02 02:28:41.134144) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-02 02:28:41.134862) =========
'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev b1e2d1fe6b070b71661047f94129a1ccd0a7f48f --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 b1e2d1fe6b070b71661047f94129a1ccd0a7f48f --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-050
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-050
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-050
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-02 02:28:41,357 truncating revision to first 12 chars
2016-12-02 02:28:41,357 Setting DEBUG logging.
2016-12-02 02:28:41,357 attempt 1/10
2016-12-02 02:28:41,357 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b1e2d1fe6b07?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-12-02 02:28:41,890 unpacking tar archive at: mozilla-central-b1e2d1fe6b07/testing/mozharness/
2016-12-02 02:28:42,022 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.168000
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-02 02:28:42.336210) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-02 02:28:42.336558) =========
script_repo_revision: b1e2d1fe6b070b71661047f94129a1ccd0a7f48f
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-02 02:28:42.337012) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-02 02:28:42.337345) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-02 02:28:42.347037) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 13 secs) (at 2016-12-02 02:28:42.347488) =========
'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-050
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-050
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-050
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
02:28:42 INFO - MultiFileLogger online at 20161202 02:28:42 in C:\slave\test
02:28:42 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
02:28:42 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
02:28:42 INFO - {'append_to_log': False,
02:28:42 INFO - 'base_work_dir': 'C:\\slave\\test',
02:28:42 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
02:28:42 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
02:28:42 INFO - 'branch': 'Firefox-Non-PGO',
02:28:42 INFO - 'buildbot_json_path': 'buildprops.json',
02:28:42 INFO - 'config_files': ('talos/windows_config.py',),
02:28:42 INFO - 'default_actions': ('clobber',
02:28:42 INFO - 'read-buildbot-config',
02:28:42 INFO - 'download-and-extract',
02:28:42 INFO - 'populate-webroot',
02:28:42 INFO - 'create-virtualenv',
02:28:42 INFO - 'install',
02:28:42 INFO - 'run-tests'),
02:28:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
02:28:42 INFO - 'download_minidump_stackwalk': True,
02:28:42 INFO - 'download_symbols': 'ondemand',
02:28:42 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
02:28:42 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
02:28:42 INFO - 'hg': 'c:/mozilla-build/hg/hg',
02:28:42 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
02:28:42 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
02:28:42 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
02:28:42 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
02:28:42 INFO - 'C:/mozilla-build/tooltool.py'),
02:28:42 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
02:28:42 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
02:28:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
02:28:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
02:28:42 INFO - 'installer_path': 'installer.exe',
02:28:42 INFO - 'log_level': 'info',
02:28:42 INFO - 'log_name': 'talos',
02:28:42 INFO - 'log_to_console': True,
02:28:42 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
02:28:42 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
02:28:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
02:28:42 INFO - 'opt_config_files': (),
02:28:42 INFO - 'pip_index': False,
02:28:42 INFO - 'sps_profile': False,
02:28:42 INFO - 'sps_profile_interval': 0,
02:28:42 INFO - 'suite': 'dromaeojs',
02:28:42 INFO - 'system_bits': '32',
02:28:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
02:28:42 INFO - 'title': 'T-W732-IX-050',
02:28:42 INFO - 'use_talos_json': True,
02:28:42 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
02:28:42 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
02:28:42 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
02:28:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
02:28:42 INFO - 'work_dir': 'build'}
02:28:42 INFO - [mozharness: 2016-12-02 10:28:42.797000Z] Running clobber step.
02:28:42 INFO - Running pre-action listener: _resource_record_pre_action
02:28:42 INFO - Running main action method: clobber
02:28:42 INFO - rmtree: C:\slave\test\build
02:28:42 INFO - Using _rmtree_windows ...
02:28:42 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
02:29:53 INFO - Running post-action listener: _resource_record_post_action
02:29:53 INFO - [mozharness: 2016-12-02 10:29:53.098000Z] Finished clobber step (success)
02:29:53 INFO - [mozharness: 2016-12-02 10:29:53.098000Z] Running read-buildbot-config step.
02:29:53 INFO - Running pre-action listener: _resource_record_pre_action
02:29:53 INFO - Running main action method: read_buildbot_config
02:29:53 INFO - Using buildbot properties:
02:29:53 INFO - {
02:29:53 INFO - "project": "",
02:29:53 INFO - "product": "firefox",
02:29:53 INFO - "script_repo_revision": "production",
02:29:53 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs",
02:29:53 INFO - "repository": "",
02:29:53 INFO - "buildername": "Windows 7 32-bit mozilla-central talos dromaeojs",
02:29:53 INFO - "buildid": "20161202002343",
02:29:53 INFO - "pgo_build": "False",
02:29:53 INFO - "basedir": "C:\\slave\\test",
02:29:53 INFO - "buildnumber": 98,
02:29:53 INFO - "slavename": "t-w732-ix-050",
02:29:53 INFO - "master": "http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/",
02:29:53 INFO - "platform": "win7_ix",
02:29:53 INFO - "scheduler": "tests-mozilla-central-win32-talos",
02:29:53 INFO - "branch": "mozilla-central",
02:29:53 INFO - "repo_path": "mozilla-central",
02:29:53 INFO - "revision": "b1e2d1fe6b070b71661047f94129a1ccd0a7f48f",
02:29:53 INFO - "stage_platform": "win32",
02:29:53 INFO - "builduid": "5a55d89f2a0d4fc5926346ed8c785ba7",
02:29:53 INFO - "slavebuilddir": "test"
02:29:53 INFO - }
02:29:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip.
02:29:53 INFO - Running post-action listener: _resource_record_post_action
02:29:53 INFO - [mozharness: 2016-12-02 10:29:53.101000Z] Finished read-buildbot-config step (success)
02:29:53 INFO - [mozharness: 2016-12-02 10:29:53.102000Z] Running download-and-extract step.
02:29:53 INFO - Running pre-action listener: _resource_record_pre_action
02:29:53 INFO - Running main action method: download_and_extract
02:29:53 INFO - mkdir: C:\slave\test\build\tests
02:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:29:53 INFO - trying https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json
02:29:53 INFO - Downloading https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/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
02:29:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/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
02:29:53 INFO - Downloaded 1274 bytes.
02:29:53 INFO - Reading from file C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json
02:29:53 INFO - Using the following test package requirements:
02:29:53 INFO - {u'common': [u'firefox-53.0a1.en-US.win32.common.tests.zip'],
02:29:53 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
02:29:53 INFO - u'firefox-53.0a1.en-US.win32.cppunittest.tests.zip'],
02:29:53 INFO - u'gtest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
02:29:53 INFO - u'firefox-53.0a1.en-US.win32.gtest.tests.zip'],
02:29:53 INFO - u'jittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
02:29:53 INFO - u'jsshell-win32.zip'],
02:29:53 INFO - u'mochitest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
02:29:53 INFO - u'firefox-53.0a1.en-US.win32.mochitest.tests.zip'],
02:29:53 INFO - u'mozbase': [u'firefox-53.0a1.en-US.win32.common.tests.zip'],
02:29:53 INFO - u'reftest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
02:29:53 INFO - u'firefox-53.0a1.en-US.win32.reftest.tests.zip'],
02:29:53 INFO - u'talos': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
02:29:54 INFO - u'firefox-53.0a1.en-US.win32.talos.tests.zip'],
02:29:54 INFO - u'web-platform': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
02:29:54 INFO - u'firefox-53.0a1.en-US.win32.web-platform.tests.zip'],
02:29:54 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
02:29:54 INFO - u'firefox-53.0a1.en-US.win32.xpcshell.tests.zip']}
02:29:54 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip'] for test suite category: common
02:29:54 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip
02:29:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1
02:29:54 INFO - Fetch https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory
02:29:55 INFO - Expected file size: 16890326
02:29:55 INFO - Obtained file size: 16890326
02:30:29 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
02:30:29 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip
02:30:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1
02:30:29 INFO - Fetch https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory
02:30:31 INFO - Expected file size: 16890326
02:30:31 INFO - Obtained file size: 16890326
02:30:44 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip
02:30:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip'}, attempt #1
02:30:44 INFO - Fetch https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip into memory
02:30:46 INFO - Expected file size: 14409734
02:30:46 INFO - Obtained file size: 14409734
02:30:50 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')]}
02:30:50 INFO - trying https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
02:30:50 INFO - Downloading https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip to C:\slave\test\build\firefox-53.0a1.en-US.win32.zip
02:30:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/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
02:30:53 INFO - Downloaded 53384324 bytes.
02:30:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
02:30:53 INFO - mkdir: C:\slave\test\properties
02:30:53 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
02:30:53 INFO - Writing to file C:\slave\test\properties\build_url
02:30:53 INFO - Contents:
02:30:53 INFO - build_url:https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
02:30:54 INFO - Running post-action listener: _resource_record_post_action
02:30:54 INFO - Running post-action listener: set_extra_try_arguments
02:30:54 INFO - [mozharness: 2016-12-02 10:30:54.629000Z] Finished download-and-extract step (success)
02:30:54 INFO - [mozharness: 2016-12-02 10:30:54.629000Z] Running populate-webroot step.
02:30:54 INFO - Running pre-action listener: _resource_record_pre_action
02:30:54 INFO - Running main action method: populate_webroot
02:30:54 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
02:30:54 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
02:30:54 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
02:30:54 INFO - u'--tppagecycles',
02:30:54 INFO - u'7'],
02:30:54 INFO - u'tests': [u'tsvgm']}},
02:30:54 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
02:30:54 INFO - u'tests': [u'tresize', u'tcanvasmark']},
02:30:54 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
02:30:54 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
02:30:54 INFO - u'tests': [u'dromaeo_css', u'kraken']},
02:30:54 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
02:30:54 INFO - u'g1': {u'pagesets_name': u'tp5n.zip',
02:30:54 INFO - u'talos_options': [u'--disable-e10s'],
02:30:54 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
02:30:54 INFO - u'g1-e10s': {u'pagesets_name': u'tp5n.zip',
02:30:54 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
02:30:54 INFO - u'g2': {u'pagesets_name': u'tp5n.zip',
02:30:54 INFO - u'talos_options': [u'--disable-e10s'],
02:30:54 INFO - u'tests': [u'damp', u'tps']},
02:30:54 INFO - u'g2-e10s': {u'pagesets_name': u'tp5n.zip',
02:30:54 INFO - u'tests': [u'damp', u'tps']},
02:30:54 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
02:30:54 INFO - u'tests': [u'dromaeo_dom']},
02:30:54 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
02:30:54 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
02:30:54 INFO - u'tests': [u'basic_compositor_video']},
02:30:54 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
02:30:54 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
02:30:54 INFO - u'tests': [u'a11yr',
02:30:54 INFO - u'ts_paint',
02:30:54 INFO - u'tpaint',
02:30:54 INFO - u'sessionrestore',
02:30:54 INFO - u'sessionrestore_no_auto_restore',
02:30:54 INFO - u'tabpaint']},
02:30:54 INFO - u'other-e10s': {u'tests': [u'a11yr',
02:30:54 INFO - u'ts_paint',
02:30:54 INFO - u'tpaint',
02:30:54 INFO - u'sessionrestore',
02:30:54 INFO - u'sessionrestore_no_auto_restore',
02:30:54 INFO - u'tabpaint']},
02:30:54 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
02:30:54 INFO - u'ts_paint',
02:30:54 INFO - u'tpaint',
02:30:54 INFO - u'sessionrestore',
02:30:54 INFO - u'sessionrestore_no_auto_restore',
02:30:54 INFO - u'tabpaint']},
02:30:54 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
02:30:54 INFO - u'ts_paint',
02:30:54 INFO - u'tpaint',
02:30:54 INFO - u'sessionrestore',
02:30:54 INFO - u'sessionrestore_no_auto_restore',
02:30:54 INFO - u'tabpaint']},
02:30:54 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
02:30:54 INFO - u'tests': [u'a11yr',
02:30:54 INFO - u'ts_paint',
02:30:54 INFO - u'tpaint',
02:30:54 INFO - u'sessionrestore',
02:30:54 INFO - u'sessionrestore_no_auto_restore',
02:30:54 INFO - u'tabpaint']},
02:30:54 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
02:30:54 INFO - u'tests': [u'a11yr',
02:30:54 INFO - u'ts_paint',
02:30:54 INFO - u'tpaint',
02:30:54 INFO - u'sessionrestore',
02:30:54 INFO - u'sessionrestore_no_auto_restore',
02:30:54 INFO - u'tabpaint']},
02:30:54 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
02:30:54 INFO - u'tests': [u'tsvgx',
02:30:54 INFO - u'tsvgr_opacity',
02:30:54 INFO - u'tart',
02:30:54 INFO - u'tscrollx',
02:30:54 INFO - u'cart',
02:30:54 INFO - u'tsvg_static']},
02:30:54 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
02:30:54 INFO - u'tsvgr_opacity',
02:30:54 INFO - u'tart',
02:30:54 INFO - u'tscrollx',
02:30:54 INFO - u'cart',
02:30:54 INFO - u'tsvg_static']},
02:30:54 INFO - u'tp5o': {u'pagesets_name': u'tp5n.zip',
02:30:54 INFO - u'talos_options': [u'--disable-e10s'],
02:30:54 INFO - u'tests': [u'tp5o']},
02:30:54 INFO - u'tp5o-e10s': {u'pagesets_name': u'tp5n.zip',
02:30:54 INFO - u'tests': [u'tp5o']},
02:30:54 INFO - u'xperf': {u'pagesets_name': u'tp5n.zip',
02:30:54 INFO - u'talos_options': [u'--disable-e10s',
02:30:54 INFO - u'--xperf_path',
02:30:54 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:30:54 INFO - u'tests': [u'tp5n']},
02:30:54 INFO - u'xperf-e10s': {u'pagesets_name': u'tp5n.zip',
02:30:54 INFO - u'talos_options': [u'--xperf_path',
02:30:54 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:30:54 INFO - u'tests': [u'tp5n']}},
02:30:54 INFO - u'talos.zip': {u'path': u'',
02:30:54 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
02:30:54 INFO - Running post-action listener: _resource_record_post_action
02:30:54 INFO - [mozharness: 2016-12-02 10:30:54.712000Z] Finished populate-webroot step (success)
02:30:54 INFO - [mozharness: 2016-12-02 10:30:54.712000Z] Running create-virtualenv step.
02:30:54 INFO - Running pre-action listener: _resource_record_pre_action
02:30:54 INFO - Running main action method: create_virtualenv
02:30:54 INFO - Creating virtualenv C:\slave\test\build/venv
02:30:54 INFO - mkdir: C:\slave\test\build\venv\Scripts
02:30:54 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
02:30:54 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'!
02:30:54 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
02:30:54 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
02:31:04 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
02:31:06 INFO - Installing distribute......................................................................................................................................................................................done.
02:31:08 INFO - Return code: 0
02:31:08 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
02:31:08 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
02:31:08 INFO - Reading from file tmpfile_stdout
02:31:08 INFO - Output received:
02:31:08 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
02:31:08 INFO - Using _rmtree_windows ...
02:31:08 INFO - Using _rmtree_windows ...
02:31:08 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
02:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:31:08 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 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01965F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x012AA910>, '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-050', 'USERDOMAIN': 'T-W732-IX-050', '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-050', '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
02:31:08 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
02:31:08 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
02:31:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:31:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:31:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:31:08 INFO - 'COMPUTERNAME': 'T-W732-IX-050',
02:31:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:31:08 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:31:08 INFO - 'DCLOCATION': 'SCL3',
02:31:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:31:08 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:31:08 INFO - 'HOMEDRIVE': 'C:',
02:31:08 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:31:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:31:08 INFO - 'KTS_VERSION': '1.19c',
02:31:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:31:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-050',
02:31:08 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:31:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:31:08 INFO - 'MOZ_NO_REMOTE': '1',
02:31:08 INFO - 'NO_EM_RESTART': '1',
02:31:08 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:31:08 INFO - 'OS': 'Windows_NT',
02:31:08 INFO - 'OURDRIVE': 'C:',
02:31:08 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;',
02:31:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:31:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:31:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:31:08 INFO - 'PROCESSOR_LEVEL': '6',
02:31:08 INFO - 'PROCESSOR_REVISION': '1e05',
02:31:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:31:08 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:31:08 INFO - 'PROMPT': '$P$G',
02:31:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:31:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:31:08 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:31:08 INFO - 'PWD': 'C:\\slave\\test',
02:31:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:31:08 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:31:08 INFO - 'SYSTEMDRIVE': 'C:',
02:31:08 INFO - 'SYSTEMROOT': 'C:\\windows',
02:31:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:08 INFO - 'TEST1': 'testie',
02:31:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:08 INFO - 'USERDOMAIN': 'T-W732-IX-050',
02:31:08 INFO - 'USERNAME': 'cltbld',
02:31:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:31:08 INFO - 'WINDIR': 'C:\\windows',
02:31:08 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:31:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:31:09 INFO - Ignoring indexes: http://pypi.python.org/simple/
02:31:09 INFO - Downloading/unpacking pip>=1.5
02:31:13 INFO - Running setup.py egg_info for package pip
02:31:13 INFO - warning: no files found matching 'pip\cacert.pem'
02:31:13 INFO - warning: no files found matching '*.html' under directory 'docs'
02:31:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
02:31:13 INFO - no previously-included directories found matching 'docs\_build\_sources'
02:31:13 INFO - Installing collected packages: pip
02:31:13 INFO - Found existing installation: pip 0.8.2
02:31:13 INFO - Uninstalling pip:
02:31:13 INFO - Successfully uninstalled pip
02:31:13 INFO - Running setup.py install for pip
02:31:13 INFO - warning: no files found matching 'pip\cacert.pem'
02:31:13 INFO - warning: no files found matching '*.html' under directory 'docs'
02:31:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
02:31:13 INFO - no previously-included directories found matching 'docs\_build\_sources'
02:31:13 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
02:31:13 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
02:31:13 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
02:31:13 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
02:31:13 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
02:31:13 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
02:31:13 INFO - Successfully installed pip
02:31:13 INFO - Cleaning up...
02:31:13 INFO - Return code: 0
02:31:13 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
02:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:31:13 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01965F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x012AA910>, '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-050', 'USERDOMAIN': 'T-W732-IX-050', '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-050', '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
02:31:13 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build
02:31:13 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1
02:31:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:31:13 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:31:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:31:13 INFO - 'COMPUTERNAME': 'T-W732-IX-050',
02:31:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:31:13 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:31:13 INFO - 'DCLOCATION': 'SCL3',
02:31:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:31:13 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:31:13 INFO - 'HOMEDRIVE': 'C:',
02:31:13 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:31:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:31:13 INFO - 'KTS_VERSION': '1.19c',
02:31:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:31:13 INFO - 'LOGONSERVER': '\\\\T-W732-IX-050',
02:31:13 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:31:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:31:13 INFO - 'MOZ_NO_REMOTE': '1',
02:31:13 INFO - 'NO_EM_RESTART': '1',
02:31:13 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:31:13 INFO - 'OS': 'Windows_NT',
02:31:13 INFO - 'OURDRIVE': 'C:',
02:31:13 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
02:31:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:31:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:31:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:31:13 INFO - 'PROCESSOR_LEVEL': '6',
02:31:13 INFO - 'PROCESSOR_REVISION': '1e05',
02:31:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:31:13 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:31:13 INFO - 'PROMPT': '$P$G',
02:31:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:31:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:31:13 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:31:13 INFO - 'PWD': 'C:\\slave\\test',
02:31:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:31:13 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:31:13 INFO - 'SYSTEMDRIVE': 'C:',
02:31:13 INFO - 'SYSTEMROOT': 'C:\\windows',
02:31:13 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:13 INFO - 'TEST1': 'testie',
02:31:13 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:13 INFO - 'USERDOMAIN': 'T-W732-IX-050',
02:31:13 INFO - 'USERNAME': 'cltbld',
02:31:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:31:13 INFO - 'WINDIR': 'C:\\windows',
02:31:13 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:31:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:31:15 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:31:15 INFO - Downloading/unpacking psutil>=3.1.1
02:31:15 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
02:31:15 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
02:31:16 INFO - Installing collected packages: psutil
02:31:16 INFO - Successfully installed psutil
02:31:16 INFO - Cleaning up...
02:31:16 INFO - Return code: 0
02:31:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
02:31: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')]}
02:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:31: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')]}
02:31:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:31: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01965F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x012AA910>, '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-050', 'USERDOMAIN': 'T-W732-IX-050', '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-050', '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
02:31: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', 'mozsystemmonitor==0.3'] in C:\slave\test\build
02:31: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 mozsystemmonitor==0.3
02:31:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:31:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:31:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:31:16 INFO - 'COMPUTERNAME': 'T-W732-IX-050',
02:31:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:31:16 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:31:16 INFO - 'DCLOCATION': 'SCL3',
02:31:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:31:16 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:31:16 INFO - 'HOMEDRIVE': 'C:',
02:31:16 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:31:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:31:16 INFO - 'KTS_VERSION': '1.19c',
02:31:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:31:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-050',
02:31:16 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:31:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:31:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:31:16 INFO - 'MOZ_NO_REMOTE': '1',
02:31:16 INFO - 'NO_EM_RESTART': '1',
02:31:16 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:31:16 INFO - 'OS': 'Windows_NT',
02:31:16 INFO - 'OURDRIVE': 'C:',
02:31: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;',
02:31:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:31:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:31:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:31:16 INFO - 'PROCESSOR_LEVEL': '6',
02:31:16 INFO - 'PROCESSOR_REVISION': '1e05',
02:31:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:31:16 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:31:16 INFO - 'PROMPT': '$P$G',
02:31:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:31:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:31:16 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:31:16 INFO - 'PWD': 'C:\\slave\\test',
02:31:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:31:16 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:31:16 INFO - 'SYSTEMDRIVE': 'C:',
02:31:16 INFO - 'SYSTEMROOT': 'C:\\windows',
02:31:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:16 INFO - 'TEST1': 'testie',
02:31:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:16 INFO - 'USERDOMAIN': 'T-W732-IX-050',
02:31:16 INFO - 'USERNAME': 'cltbld',
02:31:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:31:16 INFO - 'WINDIR': 'C:\\windows',
02:31:16 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:31:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:31:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:31:17 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:31:17 INFO - Downloading/unpacking mozsystemmonitor==0.3
02:31: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
02:31: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
02:31:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz
02:31:18 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
02:31:18 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)
02:31:18 INFO - Installing collected packages: mozsystemmonitor
02:31:18 INFO - Running setup.py install for mozsystemmonitor
02:31:18 INFO - Successfully installed mozsystemmonitor
02:31:18 INFO - Cleaning up...
02:31:18 INFO - Return code: 0
02:31:18 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
02:31: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')]}
02:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:31: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')]}
02:31:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:31: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', '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 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01965F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x012AA910>, '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-050', 'USERDOMAIN': 'T-W732-IX-050', '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-050', '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
02:31: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', 'jsonschema==2.5.1'] in C:\slave\test\build
02:31: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 jsonschema==2.5.1
02:31:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:31:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:31:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:31:18 INFO - 'COMPUTERNAME': 'T-W732-IX-050',
02:31:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:31:18 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:31:18 INFO - 'DCLOCATION': 'SCL3',
02:31:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:31:18 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:31:18 INFO - 'HOMEDRIVE': 'C:',
02:31:18 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:31:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:31:18 INFO - 'KTS_VERSION': '1.19c',
02:31:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:31:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-050',
02:31:18 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:31:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:31:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:31:18 INFO - 'MOZ_NO_REMOTE': '1',
02:31:18 INFO - 'NO_EM_RESTART': '1',
02:31:18 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:31:18 INFO - 'OS': 'Windows_NT',
02:31:18 INFO - 'OURDRIVE': 'C:',
02:31: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;',
02:31:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:31:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:31:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:31:18 INFO - 'PROCESSOR_LEVEL': '6',
02:31:18 INFO - 'PROCESSOR_REVISION': '1e05',
02:31:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:31:18 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:31:18 INFO - 'PROMPT': '$P$G',
02:31:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:31:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:31:18 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:31:18 INFO - 'PWD': 'C:\\slave\\test',
02:31:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:31:18 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:31:18 INFO - 'SYSTEMDRIVE': 'C:',
02:31:18 INFO - 'SYSTEMROOT': 'C:\\windows',
02:31:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:18 INFO - 'TEST1': 'testie',
02:31:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:18 INFO - 'USERDOMAIN': 'T-W732-IX-050',
02:31:18 INFO - 'USERNAME': 'cltbld',
02:31:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:31:18 INFO - 'WINDIR': 'C:\\windows',
02:31:18 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:31:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:31:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:31:19 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:31:19 INFO - Downloading/unpacking jsonschema==2.5.1
02:31: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
02:31: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
02:31:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
02:31:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
02:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:31:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:31:20 INFO - Downloading functools32-3.2.3-2.tar.gz
02:31:20 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
02:31:20 INFO - warning: no files found matching '*.txt'
02:31:20 INFO - no previously-included directories found matching 'build'
02:31:20 INFO - no previously-included directories found matching 'dist'
02:31:20 INFO - no previously-included directories found matching '.git*'
02:31:20 INFO - Installing collected packages: jsonschema, functools32
02:31:20 INFO - Running setup.py install for functools32
02:31:20 INFO - warning: no files found matching '*.txt'
02:31:20 INFO - no previously-included directories found matching 'build'
02:31:20 INFO - no previously-included directories found matching 'dist'
02:31:20 INFO - no previously-included directories found matching '.git*'
02:31:20 INFO - Successfully installed jsonschema functools32
02:31:20 INFO - Cleaning up...
02:31:21 INFO - Return code: 0
02:31:21 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
02:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:31:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:31:21 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01965F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x012AA910>, '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-050', 'USERDOMAIN': 'T-W732-IX-050', '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-050', '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
02:31:21 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
02:31:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
02:31:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:31:21 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:31:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:31:21 INFO - 'COMPUTERNAME': 'T-W732-IX-050',
02:31:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:31:21 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:31:21 INFO - 'DCLOCATION': 'SCL3',
02:31:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:31:21 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:31:21 INFO - 'HOMEDRIVE': 'C:',
02:31:21 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:31:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:31:21 INFO - 'KTS_VERSION': '1.19c',
02:31:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:31:21 INFO - 'LOGONSERVER': '\\\\T-W732-IX-050',
02:31:21 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:31:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:31:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:31:21 INFO - 'MOZ_NO_REMOTE': '1',
02:31:21 INFO - 'NO_EM_RESTART': '1',
02:31:21 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:31:21 INFO - 'OS': 'Windows_NT',
02:31:21 INFO - 'OURDRIVE': 'C:',
02:31:21 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
02:31:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:31:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:31:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:31:21 INFO - 'PROCESSOR_LEVEL': '6',
02:31:21 INFO - 'PROCESSOR_REVISION': '1e05',
02:31:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:31:21 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:31:21 INFO - 'PROMPT': '$P$G',
02:31:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:31:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:31:21 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:31:21 INFO - 'PWD': 'C:\\slave\\test',
02:31:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:31:21 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:31:21 INFO - 'SYSTEMDRIVE': 'C:',
02:31:21 INFO - 'SYSTEMROOT': 'C:\\windows',
02:31:21 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:21 INFO - 'TEST1': 'testie',
02:31:21 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:21 INFO - 'USERDOMAIN': 'T-W732-IX-050',
02:31:21 INFO - 'USERNAME': 'cltbld',
02:31:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:31:21 INFO - 'WINDIR': 'C:\\windows',
02:31:21 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:31:21 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:31:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:31:21 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
02:31:21 INFO - Cleaning up...
02:31:21 INFO - Return code: 0
02:31:21 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
02:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:31:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:31:21 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01965F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x012AA910>, '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-050', 'USERDOMAIN': 'T-W732-IX-050', '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-050', '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
02:31:21 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build
02:31:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
02:31:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:31:21 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:31:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:31:21 INFO - 'COMPUTERNAME': 'T-W732-IX-050',
02:31:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:31:21 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:31:21 INFO - 'DCLOCATION': 'SCL3',
02:31:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:31:21 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:31:21 INFO - 'HOMEDRIVE': 'C:',
02:31:21 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:31:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:31:21 INFO - 'KTS_VERSION': '1.19c',
02:31:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:31:21 INFO - 'LOGONSERVER': '\\\\T-W732-IX-050',
02:31:21 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:31:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:31:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:31:21 INFO - 'MOZ_NO_REMOTE': '1',
02:31:21 INFO - 'NO_EM_RESTART': '1',
02:31:21 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:31:21 INFO - 'OS': 'Windows_NT',
02:31:21 INFO - 'OURDRIVE': 'C:',
02:31:21 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
02:31:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:31:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:31:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:31:21 INFO - 'PROCESSOR_LEVEL': '6',
02:31:21 INFO - 'PROCESSOR_REVISION': '1e05',
02:31:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:31:21 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:31:21 INFO - 'PROMPT': '$P$G',
02:31:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:31:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:31:21 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:31:21 INFO - 'PWD': 'C:\\slave\\test',
02:31:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:31:21 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:31:21 INFO - 'SYSTEMDRIVE': 'C:',
02:31:21 INFO - 'SYSTEMROOT': 'C:\\windows',
02:31:21 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:21 INFO - 'TEST1': 'testie',
02:31:21 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:21 INFO - 'USERDOMAIN': 'T-W732-IX-050',
02:31:21 INFO - 'USERNAME': 'cltbld',
02:31:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:31:21 INFO - 'WINDIR': 'C:\\windows',
02:31:21 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:31:21 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:31:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:31:21 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:31:21 INFO - Downloading/unpacking blobuploader==1.2.4
02:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:31:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:31:22 INFO - Downloading blobuploader-1.2.4.tar.gz
02:31:22 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
02:31:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
02:31: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
02:31: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
02:31:23 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
02:31:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
02:31: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
02:31: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
02:31:23 INFO - Downloading docopt-0.6.1.tar.gz
02:31:23 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
02:31:23 INFO - Installing collected packages: blobuploader, requests, docopt
02:31:23 INFO - Running setup.py install for blobuploader
02:31:24 INFO - Running setup.py install for requests
02:31:25 INFO - Running setup.py install for docopt
02:31:25 INFO - Successfully installed blobuploader requests docopt
02:31:25 INFO - Cleaning up...
02:31:26 INFO - Return code: 0
02:31:26 INFO - Installing None into virtualenv C:\slave\test\build/venv
02:31: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')]}
02:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:31: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')]}
02:31:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:31: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 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01965F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x012AA910>, '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-050', 'USERDOMAIN': 'T-W732-IX-050', '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-050', '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
02:31: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
02:31: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
02:31:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:31:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:31:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:31:26 INFO - 'COMPUTERNAME': 'T-W732-IX-050',
02:31:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:31:26 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:31:26 INFO - 'DCLOCATION': 'SCL3',
02:31:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:31:26 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:31:26 INFO - 'HOMEDRIVE': 'C:',
02:31:26 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:31:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:31:26 INFO - 'KTS_VERSION': '1.19c',
02:31:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:31:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-050',
02:31:26 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:31:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:31:26 INFO - 'MOZ_NO_REMOTE': '1',
02:31:26 INFO - 'NO_EM_RESTART': '1',
02:31:26 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:31:26 INFO - 'OS': 'Windows_NT',
02:31:26 INFO - 'OURDRIVE': 'C:',
02:31: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;',
02:31:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:31:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:31:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:31:26 INFO - 'PROCESSOR_LEVEL': '6',
02:31:26 INFO - 'PROCESSOR_REVISION': '1e05',
02:31:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:31:26 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:31:26 INFO - 'PROMPT': '$P$G',
02:31:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:31:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:31:26 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:31:26 INFO - 'PWD': 'C:\\slave\\test',
02:31:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:31:26 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:31:26 INFO - 'SYSTEMDRIVE': 'C:',
02:31:26 INFO - 'SYSTEMROOT': 'C:\\windows',
02:31:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:26 INFO - 'TEST1': 'testie',
02:31:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:26 INFO - 'USERDOMAIN': 'T-W732-IX-050',
02:31:26 INFO - 'USERNAME': 'cltbld',
02:31:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:31:26 INFO - 'WINDIR': 'C:\\windows',
02:31:26 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:31:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:31:26 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:31:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
02:31:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nvyl7h-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
02:31:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
02:31:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d_bmgj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
02:31:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
02:31:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4ptnbk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
02:31:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
02:31:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vxqfwd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
02:31:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
02:31:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ulszf4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
02:31:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
02:31:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g7zqty-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
02:31:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
02:31:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5dbs3e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
02:31:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
02:31:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2b0m7l-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
02:31:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
02:31:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5jzcmw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
02:31:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
02:31:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5q63_e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
02:31:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
02:31:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-crhe9o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
02:31:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
02:31:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rpjshk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
02:31:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
02:31:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nbfmt2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
02:31:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
02:31:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-apgcwl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
02:31:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
02:31:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1rxa9t-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
02:31:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
02:31:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vhue_z-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
02:31:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
02:31:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pem9sv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
02:31:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
02:31:29 INFO - Running setup.py install for manifestparser
02:31:29 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
02:31:29 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
02:31:30 INFO - Running setup.py install for mozcrash
02:31:30 INFO - Running setup.py install for mozdebug
02:31:30 INFO - Running setup.py install for mozdevice
02:31:31 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
02:31:31 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
02:31:31 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
02:31:31 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
02:31:31 INFO - Running setup.py install for mozfile
02:31:31 INFO - Running setup.py install for mozhttpd
02:31:31 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
02:31:31 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
02:31:32 INFO - Running setup.py install for mozinfo
02:31:32 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
02:31:32 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
02:31:32 INFO - Running setup.py install for mozInstall
02:31:32 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
02:31:32 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
02:31:32 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
02:31:32 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
02:31:32 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
02:31:32 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
02:31:32 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
02:31:32 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
02:31:32 INFO - Running setup.py install for mozleak
02:31:33 INFO - Running setup.py install for mozlog
02:31:33 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
02:31:33 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
02:31:34 INFO - Running setup.py install for moznetwork
02:31:34 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
02:31:34 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
02:31:34 INFO - Running setup.py install for mozprocess
02:31:35 INFO - Running setup.py install for mozprofile
02:31:35 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
02:31:35 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
02:31:35 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
02:31:35 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
02:31:35 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
02:31:35 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
02:31:35 INFO - Running setup.py install for mozrunner
02:31:35 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
02:31:35 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
02:31:36 INFO - Running setup.py install for mozscreenshot
02:31:36 INFO - Running setup.py install for moztest
02:31:37 INFO - Running setup.py install for mozversion
02:31:37 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
02:31:37 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
02:31:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
02:31:37 INFO - Cleaning up...
02:31:37 INFO - Return code: 0
02:31:37 INFO - Installing None into virtualenv C:\slave\test\build/venv
02:31: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')]}
02:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:31: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')]}
02:31:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:31: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 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01965F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x012AA910>, '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-050', 'USERDOMAIN': 'T-W732-IX-050', '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-050', '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
02:31: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
02:31: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
02:31:37 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:31:37 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:31:37 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:31:37 INFO - 'COMPUTERNAME': 'T-W732-IX-050',
02:31:37 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:31:37 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:31:37 INFO - 'DCLOCATION': 'SCL3',
02:31:37 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:31:37 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:31:37 INFO - 'HOMEDRIVE': 'C:',
02:31:37 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:31:37 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:31:37 INFO - 'KTS_VERSION': '1.19c',
02:31:37 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:31:37 INFO - 'LOGONSERVER': '\\\\T-W732-IX-050',
02:31:37 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:31:37 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:31:37 INFO - 'MOZ_NO_REMOTE': '1',
02:31:37 INFO - 'NO_EM_RESTART': '1',
02:31:37 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:31:37 INFO - 'OS': 'Windows_NT',
02:31:37 INFO - 'OURDRIVE': 'C:',
02:31: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;',
02:31:37 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:31:37 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:31:37 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:31:37 INFO - 'PROCESSOR_LEVEL': '6',
02:31:37 INFO - 'PROCESSOR_REVISION': '1e05',
02:31:37 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:31:37 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:31:37 INFO - 'PROMPT': '$P$G',
02:31:37 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:31:37 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:31:37 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:31:37 INFO - 'PWD': 'C:\\slave\\test',
02:31:37 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:31:37 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:31:37 INFO - 'SYSTEMDRIVE': 'C:',
02:31:37 INFO - 'SYSTEMROOT': 'C:\\windows',
02:31:37 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:37 INFO - 'TEST1': 'testie',
02:31:37 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:37 INFO - 'USERDOMAIN': 'T-W732-IX-050',
02:31:37 INFO - 'USERNAME': 'cltbld',
02:31:37 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:31:37 INFO - 'WINDIR': 'C:\\windows',
02:31:37 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:31:37 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:31:37 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:31:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
02:31:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-khuoib-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
02:31: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))
02:31:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
02:31:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n4b0zw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
02:31: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))
02:31:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
02:31:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ec1mrc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
02:31: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))
02:31:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
02:31:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ywc3hl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
02:31: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))
02:31:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
02:31:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ukmahk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
02:31: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))
02:31:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
02:31:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wiwsxz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
02:31: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))
02:31:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
02:31:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yixrhp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
02:31: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))
02:31:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
02:31:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cgsq4c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
02:31: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))
02:31:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
02:31:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hxpa3j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
02:31: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))
02:31:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
02:31:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sfjmks-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
02:31: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))
02:31:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
02:31:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xnn5hc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
02:31: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))
02:31:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
02:31:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ibkz0f-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
02:31: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))
02:31:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
02:31:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rk8api-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
02:31: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))
02:31:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
02:31:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-goqqwm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
02:31: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))
02:31:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
02:31:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vhaucd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
02:31: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))
02:31:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
02:31:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-08jiln-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
02:31: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))
02:31:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
02:31:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pv8u3t-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
02:31: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))
02:31: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))
02:31: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))
02:31: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))
02:31: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))
02:31: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))
02:31:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
02:31: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
02:31: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
02:31:41 INFO - Downloading blessings-1.6.tar.gz
02:31:41 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
02:31:41 INFO - Installing collected packages: blessings
02:31:41 INFO - Running setup.py install for blessings
02:31:41 INFO - Successfully installed blessings
02:31:41 INFO - Cleaning up...
02:31:44 INFO - Return code: 0
02:31:44 INFO - Done creating virtualenv C:\slave\test\build/venv.
02:31:44 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
02:31:44 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
02:31:45 INFO - Reading from file tmpfile_stdout
02:31:45 INFO - Using _rmtree_windows ...
02:31:45 INFO - Using _rmtree_windows ...
02:31:45 INFO - Current package versions:
02:31:45 INFO - blessings == 1.6
02:31:45 INFO - blobuploader == 1.2.4
02:31:45 INFO - docopt == 0.6.1
02:31:45 INFO - functools32 == 3.2.3-2
02:31:45 INFO - jsonschema == 2.5.1
02:31:45 INFO - manifestparser == 1.1
02:31:45 INFO - mozInstall == 1.12
02:31:45 INFO - mozcrash == 1.0
02:31:45 INFO - mozdebug == 0.1
02:31:45 INFO - mozdevice == 0.49
02:31:45 INFO - mozfile == 1.2
02:31:45 INFO - mozhttpd == 0.7
02:31:45 INFO - mozinfo == 0.9
02:31:45 INFO - mozleak == 0.1
02:31:45 INFO - mozlog == 3.4
02:31:45 INFO - moznetwork == 0.27
02:31:45 INFO - mozprocess == 0.23
02:31:45 INFO - mozprofile == 0.28
02:31:45 INFO - mozrunner == 6.13
02:31:45 INFO - mozscreenshot == 0.1
02:31:45 INFO - mozsystemmonitor == 0.3
02:31:45 INFO - moztest == 0.7
02:31:45 INFO - mozversion == 1.4
02:31:45 INFO - psutil == 3.1.1
02:31:45 INFO - requests == 1.2.3
02:31:45 INFO - Installing None into virtualenv C:\slave\test\build/venv
02:31: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')]}
02:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:31: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')]}
02:31:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:31: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 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01965F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x012AA910>, '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-050', 'USERDOMAIN': 'T-W732-IX-050', '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-050', '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
02:31: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
02:31: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
02:31:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:31:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:31:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:31:45 INFO - 'COMPUTERNAME': 'T-W732-IX-050',
02:31:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:31:45 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:31:45 INFO - 'DCLOCATION': 'SCL3',
02:31:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:31:45 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:31:45 INFO - 'HOMEDRIVE': 'C:',
02:31:45 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:31:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:31:45 INFO - 'KTS_VERSION': '1.19c',
02:31:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:31:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-050',
02:31:45 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:31:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:31:45 INFO - 'MOZ_NO_REMOTE': '1',
02:31:45 INFO - 'NO_EM_RESTART': '1',
02:31:45 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:31:45 INFO - 'OS': 'Windows_NT',
02:31:45 INFO - 'OURDRIVE': 'C:',
02:31: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;',
02:31:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:31:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:31:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:31:45 INFO - 'PROCESSOR_LEVEL': '6',
02:31:45 INFO - 'PROCESSOR_REVISION': '1e05',
02:31:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:31:45 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:31:45 INFO - 'PROMPT': '$P$G',
02:31:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:31:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:31:45 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:31:45 INFO - 'PWD': 'C:\\slave\\test',
02:31:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:31:45 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:31:45 INFO - 'SYSTEMDRIVE': 'C:',
02:31:45 INFO - 'SYSTEMROOT': 'C:\\windows',
02:31:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:45 INFO - 'TEST1': 'testie',
02:31:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:45 INFO - 'USERDOMAIN': 'T-W732-IX-050',
02:31:45 INFO - 'USERNAME': 'cltbld',
02:31:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:31:45 INFO - 'WINDIR': 'C:\\windows',
02:31:45 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:31:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:31:45 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:31: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))
02:31: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))
02:31: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))
02:31: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))
02:31: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))
02:31: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))
02:31: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))
02:31: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))
02:31: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))
02:31: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))
02:31: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))
02:31:45 INFO - Cleaning up...
02:31:45 INFO - Return code: 0
02:31:45 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
02:31: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')]}
02:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:31: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')]}
02:31:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:31: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 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01965F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x012AA910>, '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-050', 'USERDOMAIN': 'T-W732-IX-050', '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-050', '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
02:31: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
02:31: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
02:31:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:31:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:31:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:31:45 INFO - 'COMPUTERNAME': 'T-W732-IX-050',
02:31:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:31:45 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:31:45 INFO - 'DCLOCATION': 'SCL3',
02:31:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:31:45 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:31:45 INFO - 'HOMEDRIVE': 'C:',
02:31:45 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:31:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:31:45 INFO - 'KTS_VERSION': '1.19c',
02:31:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:31:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-050',
02:31:45 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:31:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:31:45 INFO - 'MOZ_NO_REMOTE': '1',
02:31:45 INFO - 'NO_EM_RESTART': '1',
02:31:45 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:31:45 INFO - 'OS': 'Windows_NT',
02:31:45 INFO - 'OURDRIVE': 'C:',
02:31: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;',
02:31:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:31:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:31:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:31:45 INFO - 'PROCESSOR_LEVEL': '6',
02:31:45 INFO - 'PROCESSOR_REVISION': '1e05',
02:31:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:31:45 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:31:45 INFO - 'PROMPT': '$P$G',
02:31:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:31:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:31:45 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:31:45 INFO - 'PWD': 'C:\\slave\\test',
02:31:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:31:45 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:31:45 INFO - 'SYSTEMDRIVE': 'C:',
02:31:45 INFO - 'SYSTEMROOT': 'C:\\windows',
02:31:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:45 INFO - 'TEST1': 'testie',
02:31:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:45 INFO - 'USERDOMAIN': 'T-W732-IX-050',
02:31:45 INFO - 'USERNAME': 'cltbld',
02:31:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:31:45 INFO - 'WINDIR': 'C:\\windows',
02:31:45 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:31:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:31:45 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:31:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
02:31:45 INFO - Cleaning up...
02:31:45 INFO - Return code: 0
02:31:45 INFO - Running post-action listener: _resource_record_post_action
02:31:45 INFO - Running post-action listener: _start_resource_monitoring
02:31:45 INFO - Starting resource monitoring.
02:31:46 INFO - [mozharness: 2016-12-02 10:31:46.003000Z] Finished create-virtualenv step (success)
02:31:46 INFO - [mozharness: 2016-12-02 10:31:46.003000Z] Running install step.
02:31:46 INFO - Running pre-action listener: _resource_record_pre_action
02:31:46 INFO - Running main action method: install
02:31:46 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
02:31:46 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
02:31:46 INFO - Reading from file tmpfile_stdout
02:31:46 INFO - Using _rmtree_windows ...
02:31:46 INFO - Using _rmtree_windows ...
02:31:46 INFO - Detecting whether we're running mozinstall >=1.0...
02:31:46 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
02:31:46 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
02:31:46 INFO - Reading from file tmpfile_stdout
02:31:46 INFO - Output received:
02:31:46 INFO - Usage: mozinstall-script.py [options] installer
02:31:46 INFO - Options:
02:31:46 INFO - -h, --help show this help message and exit
02:31:46 INFO - -d DEST, --destination=DEST
02:31:46 INFO - Directory to install application into. [default:
02:31:46 INFO - "C:\slave\test"]
02:31:46 INFO - --app=APP Application being installed. [default: firefox]
02:31:46 INFO - Using _rmtree_windows ...
02:31:46 INFO - Using _rmtree_windows ...
02:31:46 INFO - mkdir: C:\slave\test\build\application
02:31: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']
02:31: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
02:31:49 INFO - Reading from file tmpfile_stdout
02:31:49 INFO - Output received:
02:31:49 INFO - C:\slave\test\build\application\firefox\firefox.exe
02:31:49 INFO - Using _rmtree_windows ...
02:31:49 INFO - Using _rmtree_windows ...
02:31:49 INFO - Running post-action listener: _resource_record_post_action
02:31:49 INFO - [mozharness: 2016-12-02 10:31:49.620000Z] Finished install step (success)
02:31:49 INFO - [mozharness: 2016-12-02 10:31:49.620000Z] Running run-tests step.
02:31:49 INFO - Running pre-action listener: _resource_record_pre_action
02:31:49 INFO - Running main action method: run_tests
02:31:49 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
02:31:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
02:31:49 INFO - Python 2.7.3
02:31:49 INFO - Return code: 0
02:31:49 INFO - grabbing minidump binary from tooltool
02:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:31:49 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 0x01974E90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F515C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
02:31:49 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
02:31:49 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
02:31:49 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
02:31:49 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
02:31:50 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpsia0ty
02:31:50 INFO - INFO - File integrity verified, renaming tmpsia0ty to win32-minidump_stackwalk.exe
02:31:50 INFO - Return code: 0
02:31:50 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
02:31:50 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
02:31:50 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
02:31:50 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
02:31:50 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
02:31:50 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/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-050', '--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
02:31:50 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/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-050 --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
02:31:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:31:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:31:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:31:50 INFO - 'COMPUTERNAME': 'T-W732-IX-050',
02:31:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:31:50 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:31:50 INFO - 'DCLOCATION': 'SCL3',
02:31:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:31:50 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:31:50 INFO - 'HOMEDRIVE': 'C:',
02:31:50 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:31:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:31:50 INFO - 'KTS_VERSION': '1.19c',
02:31:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:31:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-050',
02:31:50 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
02:31:50 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
02:31:50 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:31:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:31:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:31:50 INFO - 'MOZ_NO_REMOTE': '1',
02:31:50 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
02:31:50 INFO - 'NO_EM_RESTART': '1',
02:31:50 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:31:50 INFO - 'OS': 'Windows_NT',
02:31:50 INFO - 'OURDRIVE': 'C:',
02:31:50 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;',
02:31:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:31:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:31:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:31:50 INFO - 'PROCESSOR_LEVEL': '6',
02:31:50 INFO - 'PROCESSOR_REVISION': '1e05',
02:31:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:31:50 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:31:50 INFO - 'PROMPT': '$P$G',
02:31:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:31:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:31:50 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:31:50 INFO - 'PWD': 'C:\\slave\\test',
02:31:50 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
02:31:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:31:50 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:31:50 INFO - 'SYSTEMDRIVE': 'C:',
02:31:50 INFO - 'SYSTEMROOT': 'C:\\windows',
02:31:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:50 INFO - 'TEST1': 'testie',
02:31:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:31:50 INFO - 'USERDOMAIN': 'T-W732-IX-050',
02:31:50 INFO - 'USERNAME': 'cltbld',
02:31:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:31:50 INFO - 'WINDIR': 'C:\\windows',
02:31:50 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:31:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:31:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:31:50 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/NjhbFF5pT5m2lPvlf5qqBg/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-050', '--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
02:31:51 INFO - mozversion application_buildid: 20161202002343
02:31:51 INFO - mozversion application_changeset: b1e2d1fe6b070b71661047f94129a1ccd0a7f48f
02:31:51 INFO - mozversion application_display_name: Nightly
02:31:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
02:31:51 INFO - mozversion application_name: Firefox
02:31:51 INFO - mozversion application_remotingname: firefox
02:31:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
02:31:51 INFO - mozversion application_vendor: Mozilla
02:31:51 INFO - mozversion application_version: 53.0a1
02:31:51 INFO - mozversion platform_buildid: 20161202002343
02:31:51 INFO - mozversion platform_changeset: b1e2d1fe6b070b71661047f94129a1ccd0a7f48f
02:31:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
02:31:51 INFO - mozversion platform_version: 53.0a1
02:31:51 INFO - using testdate: 1480674711
02:31:51 INFO - actual date: 1480674711
02:31:51 INFO - starting webserver on 'localhost:49990'
02:31:51 INFO - SUITE-START | Running 2 tests
02:31:51 INFO - TEST-START | dromaeo_css
02:31:51 INFO - operating with platform_type : w7_
02:31:51 INFO - Initialising browser for dromaeo_css test...
02:31:51 INFO - TEST-INFO | started process 1704 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpqpp_k1\profile http://localhost:49990/getInfo.html)
02:31:58 INFO - PROCESS | 1704 | __metrics Screen width/height:1600/1200
02:31:58 INFO - PROCESS | 1704 | colorDepth:24
02:31:58 INFO - PROCESS | 1704 | Browser inner width/height: 1010/674
02:31:58 INFO - PROCESS | 1704 | __metrics
02:31:59 INFO - PROCESS | 1704 | 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"
02:31:59 INFO - PROCESS | 1704 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
02:32:00 INFO - TEST-INFO | 1704: exit 0
02:32:00 INFO - Browser initialized.
02:32:00 INFO - Running cycle 1/1 for dromaeo_css test...
02:32:00 INFO - TEST-INFO | started process 3012 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpqpp_k1\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
02:32:01 INFO - PROCESS | 3012 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
02:32:12 INFO - PROCESS | 3012 | RSS: Main: 138207232
02:32:12 INFO - PROCESS | 3012 |
02:33:04 INFO - PROCESS | 3012 | 1480674784162 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]
02:35:17 INFO - PROCESS | 3012 | Cycle 1(1): loaded http://localhost:49990/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49990/tests/dromaeo/cssquery-ext.html)
02:35:20 INFO - PROCESS | 3012 | RSS: Main: 288169984
02:35:20 INFO - PROCESS | 3012 |
02:38:23 INFO - PROCESS | 3012 | Cycle 1(1): loaded http://localhost:49990/tests/dromaeo/cssquery-ext.html (next: http://localhost:49990/tests/dromaeo/cssquery-jquery.html)
02:38:24 INFO - PROCESS | 3012 | RSS: Main: 208326656
02:38:24 INFO - PROCESS | 3012 |
02:41:26 INFO - PROCESS | 3012 | Cycle 1(1): loaded http://localhost:49990/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49990/tests/dromaeo/cssquery-mootools.html)
02:41:27 INFO - PROCESS | 3012 | RSS: Main: 161001472
02:41:27 INFO - PROCESS | 3012 |
02:44:29 INFO - PROCESS | 3012 | Cycle 1(1): loaded http://localhost:49990/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49990/tests/dromaeo/cssquery-prototype.html)
02:44:30 INFO - PROCESS | 3012 | RSS: Main: 142577664
02:44:30 INFO - PROCESS | 3012 |
02:47:33 INFO - PROCESS | 3012 | Cycle 1(1): loaded http://localhost:49990/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49990/tests/dromaeo/cssquery-yui.html)
02:47:33 INFO - PROCESS | 3012 | RSS: Main: 146489344
02:47:33 INFO - PROCESS | 3012 |
02:50:36 INFO - PROCESS | 3012 | Cycle 1(1): loaded http://localhost:49990/tests/dromaeo/cssquery-yui.html (next: http://localhost:49990/tests/dromaeo/cssquery-dojo.html)
02:50:37 INFO - PROCESS | 3012 | RSS: Main: 194228224
02:50:37 INFO - PROCESS | 3012 |
02:50:37 INFO - PROCESS | 3012 | __start_tp_report
02:50:37 INFO - PROCESS | 3012 | _x_x_mozilla_page_load
02:50:37 INFO - PROCESS | 3012 | _x_x_mozilla_page_load_details
02:50:37 INFO - PROCESS | 3012 | |i|pagename|runs|
02:50:37 INFO - PROCESS | 3012 | |0;dojo.html;2357.4224315042816;2373.608354621488;2390.9641355379663;2395.508920671263;2399.256230568525;4087.1008378156794;4119.196756632457;4191.7694526801015;4194.140201258746;4197.097624010837;3978.4629075074854;4081.244969680604;4095.344744840826;4125.381192821071;4146.730462519934;3561.9287614247764;3573.839177237018;3576.678098971092;3595.9280814383696;3596.4245720684694;3464.9307013859707;3554.92890142197;3583.6416358364218;3593.5867375251787;3594.568651761779;3528.6647768462085;3577.6422357764145;3585.336712708144;3595.9280814383696;3614.2410093880317;3549.6627820357025;3597.37046016946;3634.1278093257692;3645.0887278180453;3657.140571965586;3497.667721566447;3499.772514786531;3566.5720113586535;3610.3862343401684;3611.512445819807;4062.350023996176;4063.756174629532;4069.5320038195396;4091.2022155679656;4103.994521342261;4318.740875547477;4324.935125973081;4387.473503179607;4392.648588112976;4402.5597440255715;4237.788110594458;4350.608445239944;4396.626286765597;4425.734455932622;4434.224010798098;3857.61423857612;3964.286428442908;4005.679545636313;4032.7781971991235;4033.3143365627916;4395.25280702275;4402.493713222956;4406.933895991563;4415.779211039499;4432.180046691373;4253.63844073251;4254.340577210537;4281.100968796588;4332.67504937131;4336.566343365638;4235.470566179228;4324.956750432455;4384.714993525411;4385.3641222022625;4403.559644035635;4215.873523794291;4268.829246830154;4289.249381358312;4293.227219100592;4363.563643635674;4193.182329445729;4289.1636130954785;4289.978550107229;4295.591918767712;4320.935185972213;3021.3353062326255;3052.6184226971627;3070.8618112185004;3086.3210093779335;3092.922676933095;2680.584509401046;2737.5893615957766;2759.8758055887533;2791.1486996466274;2825.27955371379;3897.707671924617;3929.1945151243926;3965.464662270614;4039.6162364575316;4064.0246340878566;4281.850135245251;4306.310990241546;4348.173846969065;4357.629601483846;4376.124775045004;4028.317779633108;4105.507339119265;4106.035081755785;4109.609587089221;4112.527059388149;3655.83548740302;3674.4488326750666;3720.1071742781537;3739.270842185748;3747.1756213633503;4379.4525684289465;4382.452193475816;4409.801558929792;4465.866024019285;4483.237849565647;4398.120375924764;4399.098184872029;4401.185780630593;4403.515613282472;4404.361367601744;4421.6683748719615;4439.311906654474;4446.621547320341;4471.0610771737;4471.686981911235;4272.4445822042935;4301.935470968001;4301.956980430281;4302.290122129939;4305.74165550068;4288.31386978082;4311.913761724811;4351.260285751493;4351.934720979125;4354.56454354562;4127.112670775798;4223.493180818322;4239.321708526621;4240.067185219247;4240.13077319155;4181.728187667792;4197.076643138505;4221.598948099926;4235.364695295607;4251;4106.178764247222;4165.4168416422335;4183.937240941449;4185.83256669742;4195.958040419679;4146.771927544014;4161.750294982311;4163.146554956287;4255.468066491689;4295.269804133367;4142.5857414258335;4149.626533611869;4169.10364271683;4206.642435393026;4219.64133048682
02:50:37 INFO - PROCESS | 3012 | |1;ext.html;550.5568017745716;553.5765139668155;558.2435799491689;559.1584665079056;561.1386521689208;24489.387765305855;33099.172520686996;33930.83034584827;34045.31909361811;37143.81428092862;33425.66574334256;33449.66550334496;33802.83098584504;33828.66171338286;33877.491837622394;7024.508284420092;7166.892496612529;7171.677274522633;7176.784696459088;7180.0665913431185;6259.311475737688;6458.612483250997;8948.73153805387;9180.219681327062;9188;9207.125323094295;9279.536023198847;9300.302477314195;9300.534973251344;9301.348905576613;6321.399467050623;8663.133686631349;9114.58984345706;9124.726258212231;9141.222996045344;5960.493358064569;6399.456046236076;6400.615963042233;8566.657333706644;8991.370604057718;31941.680583194102;32276.838615807006;32288.838555807306;32356.514652280235;32390.514142287884;2585.9870700646375;2654.283343497264;2675.3846615278594;2857.22854829197;3190.952135717966;24610.384740381454;24696.629550556758;25141.37146571332;25214.495710085877;25560.360990975376;2848.31640406301;2976.4642364374404;3010.5634682970845;3042.9695703042908;3047.207725991236;208401;209092;210276;210542;212664;45056.3241551377;45298.320525192146;45483.77258113688;45487.77256113698;45499.77250113728;46976.825579360106;47057.823554411414;47293.76353118212;47389.393583353245;47434.52565474301;9855.655052073143;9861.802763944825;9977.551010204557;9983.15143212835;9983.550740216708;6703.22912865017;6899.517033807686;7514.47398682087;7625.351845093229;7631.885521717179;14637.780433293508;14959.700805983819;15176.772348414783;15405.768913466307;15544.922275388551;6157.4458298753325;6192.256929168439;6238.688065596702;6279.529035322369;6289.685515724287;744.6053591596354;784.5175217241356;784.8626490364163;785.8349746553173;807.7374853172624;23194.65208021881;23871.88064059703;23962.88018559896;24060.759392405853;24123.51752964966;843.4939036578004;845.8773477845677;860.1570460948305;863;883.2315885179935;12218.755624887452;12785.744285114431;12875.806862897063;12878.871211287767;12918.806217906738;297861;300623;300775;301463;302185;34104.48843267353;35594.466083008774;41369.79315103405;41611.17443557904;41623;1000.1948431512702;1019.1388276906002;1040.2770074798093;1048.7797562511805;1055.211416648773;23749.525009499368;24562.50874982526;24763.50472990566;24890.253292401256;24962.50074998526;34999.65000350066;35155.64844351524;35274.823625881705;35382.46926296159;35425.291494169454;236355;240465;313831;405539;431429;31918;32145;32633.34733305273;32644.183895402806;32649.836750816095;34321;34644.133896652784;35561.46657800187;36019.639803601625;36148;30310.54534181945;30813.22966925845;30907;31581.526277105404;32046.67953320437;29901.551476728302;30493.23766905845;30537;31047.53428698526;31237.219069523442;29025.7097429023;29707.851460742557;30088;30389.39221215519;30449.543256851608;27688.307792305353;27853.075427079864;28259.858700706365;28525.28686782764;28717.13848584546;21994.89002554977;23067.653985190573;23576.76423235746;23646.29061128169;23859.164929227394
02:50:37 INFO - PROCESS | 3012 | |2;jquery.html;285.7085772512039;294.51405181450605;299.77367087848677;312.54524666610087;313.76624414810954;10949;11799.823002654966;14507.564773056798;15325.616859578517;15670.294836732346;14615.123092614424;15014.6997060059;15168.056305360631;15220.847791522081;15227.695446091071;4211.389348544467;4275.508316543606;4343.565643435647;4346.587074227943;4348.434703488542;3201.951970720441;3355.1947532592126;4178.749275043492;4305.870823875289;4341.196878577473;5393.568514518829;5638.605297629168;5642.238297829802;5663.037283661767;5713.285839270091;5400.162974738922;5678.233438485813;5680.233168522238;5688.11834165705;5698.202251684767;4898.461169271377;4917.188663870457;4930.901381972376;4932.136876046713;4934.087193869145;10457.477126143738;10555.155587552978;11652.825207621892;18265.360712375135;18351.61429014233;1533.1720870729905;1546.7989161409057;1549.5041586692264;1551.6431220819275;1563.6403627165816;10670.893291067068;11554.826677599844;11657.242279251821;11960;12354.135210535265;1552.285948463708;1553.1147246069745;1561.5705680937833;1572.088188850464;1574.4410734189312;61754.69122654358;63354;63380.68309658468;63969.68015159941;64634.67682661651;8381.49711017341;8453.619587118594;8523.105073967268;8591.828163436821;8598.140185981352;9815.11663950248;10595.947020265003;10612.151027917747;10649.680509584725;11221.66335009951;5042.369703787027;5108.795648174106;5117.692938423706;5126.2054381570915;5212.139996900545;5155.432902380735;5239.371275446894;5259.631825772236;5313.973430132824;5360.973195134077;3536.29274145172;3601;3630.5280313559597;3670.2659468106485;3689.2252626948643;1381.7443772902045;1530.5484881959626;1577.9921100394654;1580.9841901580837;1626.162526298957;616.1024045938499;785.2814674572702;871.372611719561;875.6497401039635;883.7702197428587;14622.634434139232;15003.774943375858;15065.322060507304;15326.540203793904;15372;744.140517702055;754.7433872483272;755.2070326157512;793.7658390774735;836.3184005035877;8212.219839115274;8305.584720763938;8313.916860831436;8377.036640786266;8529.872051919227;252225;258366;271890;280231;280680;73295;77134;77793.6110319456;78768;79576;721.6644260418931;729.2816575672958;752.9623518824037;753.3220101908327;755.1391413788285;10524.579016839547;13492.460301587827;13916.791248131083;14306.642333941734;14340.928295358457;36238.8188059058;37385.626143739304;37525.62474375221;38194.618053819104;38836.61163388443;49078.5092149074;49448.50551494439;49556.50443495663;49710;50228.748856255486;6479.838004049748;6513.185851768529;6604;6604.768833091011;6630.602163870183;10102.797944041225;10382.3251488653;10694.57221711154;10910.672679819618;10939.45302734876;8737.344699147463;8748.65005399777;8915.73252802417;9090.409123406956;9185.724428267162;7770.650320735467;8654.350923680624;8892.688755893512;8912.242459390765;9400.576974036048;7813.492123011986;8476.110008449024;8718.302535796995;8772;8916.509591972244;6469.77355792545;6592.17597800275;6624.973129164987;6653.434458071119;6818.079559259635;8183.018037835498;8326.376356610403;8440.366972476966;8459.365547584079;8556.18716221958
02:50:37 INFO - PROCESS | 3012 | |3;mootools.html;1145.7250259937612;1147.9081673466121;1192.4097571702007;1367.3368416318085;1408.2536255784437;5390.757415916283;5573.5819813514045;5728.054870946299;5735.541156707463;5741.425857414257;5080.415752188499;5252.185911183763;5493.285872836535;5569.554435645149;5571.1086226203815;1676.555712736126;1703.6933351996697;1710.9657806843857;1711.4865540337912;1711.6148866505;1672.1137796967625;1694.9237284322173;1697.6774412861578;1697.8217287184852;1698.4564939219454;1467.6697743007844;1518.6431188670708;1521.277393238214;1521.5283262188702;1691.129068529708;1663.717168081429;1707.5816424975835;1711.6234428425726;1713.5459103337564;1715.2195750933295;1626.715324818164;1662.8586570141551;1669.3239238108586;1674.188018810888;1675.1289329548688;5388.73056347185;5483.396826349099;5494.725263736837;5518.061929471959;5521.365043020064;3298.703116719507;3353.3628610563915;3371.713404360608;3380.188754698879;3381.3575420669995;2126.2345555599973;2217.800397964175;2257.5033492631596;2258.9661155082686;2261.502469456717;720.6769111067855;726.5096060159372;738.1695592458484;748.8389996150854;749.2694622742837;6705.564138330993;6856.897146542806;6859.794206173823;6863.622500762506;6868.072810170613;2887.393647334083;3087.799293045945;3187.10760986924;3200.807951522916;3201.2477067889026;6116.17431646722;6129.662868542273;6131.110988906584;6134.478569321657;6138.631682099089;3333.0834020644033;3356.412627790127;3365.74756893234;3379.172102834821;3382.458806590934;2991.461536923331;3088.073577926613;3094.4430002599747;3103.3017571046785;3113;2757.5587905934954;2775.431036637525;2830.2075418882746;2837.120492647286;2868.1108856254627;969.5152423788106;989.312427862643;1000.474750755859;1002.8545860850136;1003.5132960514145;555.8993193476938;557.5288880895637;567.1436131441553;595.7259660556193;615.8983767678372;3636.7272454566014;3696.983329584386;3751.2685026419585;3753.0617345663586;3757.7745335279424;710.6944014073895;724.4566575068699;727.5961841178091;728.7412968396249;741.4957828676444;2590.4819036192835;2607.8826452809667;2716.809823312349;2738.6439762830705;2798.062649012563;6105.603135796247;6212.06818977157;6362.681865906686;6363.172787537591;6373.362663733589;6447.871042579216;6550.967245163839;6565.868682626321;6571.605703657796;6571.80284591463;5191.6884986900905;5325.600579956441;5333.6799792011825;5336.91994620081;5342.332208474018;3685.6867166291167;3743.644353786386;3758.2295629396453;3784.394496880486;3883.8252278646964;5756.942430575808;5920.703964801656;5965.582409231312;6041.848953776015;6070.757169713162;5968.76124954997;6126.448619624255;6127.417895299939;6153.938460615336;6156.569040167325;5149.433562308218;5244.239585260039;5332.706701131475;5334.573234141337;5340.519353258225;5203.765830537711;5264.815731449381;5268.394134674561;5284.9471505284455;5404.351477822686;4850;4863.416390033219;4961.305417241517;4963.70217786934;4999.125153098266;4842.080004799077;4904.264360345834;4912.140375434356;4912.729799860899;4916.139675556692;4390.934135987899;4414.293713005904;4425.623821975039;4432.29083346663;4437.400950871591;3219.1791093271067;3282.950755738618;3288.2437039480574;3289.6545862684075;3312.089175476763;3203.215212273008;3251.821149836782;3267.8366081696295;3270.5584746058976;3282.425575524226
02:50:37 INFO - PROCESS | 3012 | |4;prototype.html;222.1336786532524;235.59948088249973;240.23724674159527;244.76502557544757;249.48730359112022;4165.916681666368;4316.633086187674;4362.236608593491;4432.667549933752;4451.287793952968;4345.60889519944;4356.9564304356945;4370.366296886949;4374.60628543431;4384.824607015724;3067.6165479315086;3116.6727493613184;3194.8242846643425;3240.141362538929;3244.1403028197547;3170.112368536802;3242.5622540956906;3249.4313495138376;3258.299465614892;3270.1007223013744;3171.587693599829;3175.2379428937006;3223.178089587152;3234.579504664402;3236.546883436321;3060.3726236121543;3155.4793459079224;3233.062411900546;3235.304409551946;3246.642869284377;2794.18968499135;2853.516171590761;2866.025551312543;2868.913932582026;2878.093400578811;4223.880671622022;4243.299855523835;4246.278132717445;4247.532771395144;4249.978750106261;3821.4332123829095;3830.7510011849417;3843.8846834595006;3845.769253844778;3846.9230615387537;4437.0460351024685;4505.504394516601;4509.977450112761;4520.530827481049;4542.500324964251;3123.1099136746147;3162.288485090859;3163.9050828475183;3210.2776875203126;3241.481362981935;4619.475573060916;4634.582887540138;4676.859694209179;4680.695754775963;4718.363020992191;3203.615566132079;3241.7730758846656;3247.9188020299207;3250.7074363306942;3254.7070763630913;3255.8046517209045;3287.2603664175367;3292.42382583047;3294.4399452093203;3306.2560923792435;3783.3894612717136;3786.1670432504807;3790.3935370341164;3792.810359482015;3794.6015668354426;4225.704200705921;4313.460817397825;4466.441694788152;4514.300283456069;4523.185826551186;1117.4133579870634;1136.3522792008475;1137.2380505061628;1138.9715257118473;1142.8457258169308;760.8630446519569;768.9307962283422;778.1945686214854;779.9649015794303;782.4092809928582;4211.136716973013;4230.302000169938;4239.703220774578;4242.703010789215;4259.9787001065415;4576.290674945389;4576.924422760649;4590.173768721662;4594.805350608799;4601.332806743004;4239.97880010598;4255.489341279004;4256.233877901946;4256.787160641956;4261.296886013835;2401.711794584626;2409.204962362416;2414.9275521734376;2428.3564855313357;2429.0648100481444;4333.674974376934;4553.385292985471;4586.587207151306;4598.678092533558;4618.65360097994;4610.976945115253;4612.169809434334;4633.652476064309;4641.721496710208;4645.674802763841;4620.976895115503;4628.861134165981;4628.95371046292;4635.698679585748;4640.770195898022;4552.135094332067;4555.316702494652;4566.543345665407;4572.771361431982;4578.51925547826;4328.978355108205;4638.814447422065;4644.883877903079;4649.977005058881;4661.766911654471;4754.263089221176;4814.8796280093275;4816.831410900601;4821.373221481185;4823.324734537237;3168.28713539461;3185.2036990752313;3187.8565464554613;3188.792728472642;3190.3778763140963;4491.48347939978;4686.437627484724;4688.413948256468;4707.7175369477945;4713.599344055657;4431.933520997123;4456.910861782681;4519.661025423041;4522.954770452254;4557.5442455754155;4524.16302983949;4533.954660453485;4535.455745310584;4535.909281814411;4539.114872599811;4426.778661066869;4459.754713490659;4463.924275212042;4471.441069866267;4473.306637471197;4372.584604462571;4487.573680500347;4542.909141817211;4584.2665173573405;4591.747453890068;4480.775961201992;4511.571400716926;4516.864494065183;4517.277235642281;4520.954790452053
02:50:37 INFO - PROCESS | 3012 | |5;yui.html;590.2150140313383;603.6830663901451;609.6250805754462;616.1835567872569;624.9718762655683;12222.266664000166;13079.803802942963;13092;13109.60671179865;13133.146345487548;11978;12172.878271217285;12176.512939482433;12180.573679921205;12210.572629957953;488.0653548454705;606.3966353478285;612.5008118383525;616.6639181645992;619.2135987296131;492.1363007921111;623.3548277532732;632.0424556796457;636.2206297285834;640.8365866703984;655.8360409897525;662.5097427903365;669.0967194287721;670.9295523969962;671.6775947545182;516.2024671881951;665.1286814273269;669.0065253099144;670.3095811314379;670.7250027488731;383.50527819113313;473.17903437535847;477.4127822777968;483.76779146010114;487.2569331769045;12348.814767778491;12368.319742414164;12403.503859845596;12477.50089996399;12526;11373.488193031251;11413.315201087962;11517.481713322837;11857.169998100137;12222.388880555936;9503.857442138373;10347.79304413915;11084.168687348401;11462.369569673665;11940.820887686692;3954.8418063277436;3978.3435733104;3988.4017397390335;4053.993273329691;4129.793510324472;43263;61732;64198.358016419705;64969.67515162394;65275.3472465274;9650.565724542412;9691;9711.174550163174;9771.9022809771;9773;16487.587810304838;17308.567285817702;17326.133693315285;17423.73864392035;18319.175637096363;6526.28210896801;6655.767048153388;6686.7993960181275;6826.351496607815;6859.382655560924;3228.612566492036;3270.591176102987;3284.687954644353;3381.898543043713;3472.114610774287;2242.9102835886706;2316.096722278313;2320.2575175943875;2327.51122264323;2338.205010296507;375.8759609328915;377.2059814091353;383.8752405468234;385.81673704990465;386.1061642298071;451.80798160782194;494.61667207914155;512.7590032684707;534.4655344655345;549.2173652545107;7045.7533986311255;7066.611336376446;7152.606606636685;7208;7227.024351712452;468.44957175319547;479.966402351832;487.7439344344248;519.366373024909;530.178223753181;3509.017475106974;3559.2347645255877;3609.639036096369;3664.6152154023976;3738.3271011218244;60981;64541.35458645447;64800.67599661971;65429;65846.67076664585;58635.70682146562;58704.07494988122;59210;59356.406435935955;59654.70172649109;52632.47367526276;52967;53381;53573.83227890733;53910;6740.325967403221;6753.1220941277315;6809.761658341934;6879.7248110077035;6891.793246202622;36385.818070910536;36930.26139477249;37036.81481592575;37107;37597.06007349838;45933;47583.76208118937;48729.75635121802;49797.75101124471;50507;8645.438046526955;8658;8664.35017373687;8762.605682744419;8772.91227087721;10020.198384129399;10027.749306267402;10183.898161018295;10282.434466104436;10558.31370960885;8880.600372983361;9042.638294468148;9080.137386948229;9109.90890091117;9392.624295028396;9664.71005869825;9772.511374431107;9786.60853565878;9918.603255869684;10402.895971040192;9396.76508087276;9478.194353480032;9537.423466200175;9602.423854568748;9652.855207172039;8820.647174112964;8832.425892317237;8932.821343573221;9044.14080662336;9192.862107068533;8456.534890581077;8475.364347674073;8519.787005324668;8556.27271681914;8645.740627781177
02:50:37 INFO - PROCESS | 3012 | __end_tp_report
02:50:37 INFO - PROCESS | 3012 | __start_cc_report
02:50:37 INFO - PROCESS | 3012 | _x_x_mozilla_cycle_collect,2607
02:50:37 INFO - PROCESS | 3012 | __end_cc_report
02:50:37 INFO - PROCESS | 3012 | __startTimestamp1480675837142__endTimestamp
02:50:37 INFO - PROCESS | 3012 |
02:50:37 INFO - PROCESS | 3012 | ------- Summary: start -------
02:50:37 INFO - PROCESS | 3012 | Number of tests: 6
02:50:37 INFO - PROCESS | 3012 |
02:50:37 INFO - PROCESS | 3012 | [#0] dojo.html Cycles:165 Average:3992.30 Median:4183.94 stddev:492.48 (11.8%) stddev-sans-first:477.00
02:50:37 INFO - PROCESS | 3012 | Values: 2357.4 2373.6 2391.0 2395.5 2399.3 4087.1 4119.2 4191.8 4194.1 4197.1 3978.5 4081.2 4095.3 4125.4 4146.7 3561.9 3573.8 3576.7 3595.9 3596.4 3464.9 3554.9 3583.6 3593.6 3594.6 3528.7 3577.6 3585.3 3595.9 3614.2 3549.7 3597.4 3634.1 3645.1 3657.1 3497.7 3499.8 3566.6 3610.4 3611.5 4062.4 4063.8 4069.5 4091.2 4104.0 4318.7 4324.9 4387.5 4392.6 4402.6 4237.8 4350.6 4396.6 4425.7 4434.2 3857.6 3964.3 4005.7 4032.8 4033.3 4395.3 4402.5 4406.9 4415.8 4432.2 4253.6 4254.3 4281.1 4332.7 4336.6 4235.5 4325.0 4384.7 4385.4 4403.6 4215.9 4268.8 4289.2 4293.2 4363.6 4193.2 4289.2 4290.0 4295.6 4320.9 3021.3 3052.6 3070.9 3086.3 3092.9 2680.6 2737.6 2759.9 2791.1 2825.3 3897.7 3929.2 3965.5 4039.6 4064.0 4281.9 4306.3 4348.2 4357.6 4376.1 4028.3 4105.5 4106.0 4109.6 4112.5 3655.8 3674.4 3720.1 3739.3 3747.2 4379.5 4382.5 4409.8 4465.9 4483.2 4398.1 4399.1 4401.2 4403.5 4404.4 4421.7 4439.3 4446.6 4471.1 4471.7 4272.4 4301.9 4302.0 4302.3 4305.7 4288.3 4311.9 4351.3 4351.9 4354.6 4127.1 4223.5 4239.3 4240.1 4240.1 4181.7 4197.1 4221.6 4235.4 4251.0 4106.2 4165.4 4183.9 4185.8 4196.0 4146.8 4161.8 4163.1 4255.5 4295.3 4142.6 4149.6 4169.1 4206.6 4219.6
02:50:37 INFO - PROCESS | 3012 |
02:50:37 INFO - PROCESS | 3012 | [#1] ext.html Cycles:180 Average:41286.69 Median:33437.67 stddev:75709.04 (226.4%) stddev-sans-first:75859.65
02:50:37 INFO - PROCESS | 3012 | Values: 550.6 553.6 558.2 559.2 561.1 24489.4 33099.2 33930.8 34045.3 37143.8 33425.7 33449.7 33802.8 33828.7 33877.5 7024.5 7166.9 7171.7 7176.8 7180.1 6259.3 6458.6 8948.7 9180.2 9188.0 9207.1 9279.5 9300.3 9300.5 9301.3 6321.4 8663.1 9114.6 9124.7 9141.2 5960.5 6399.5 6400.6 8566.7 8991.4 31941.7 32276.8 32288.8 32356.5 32390.5 2586.0 2654.3 2675.4 2857.2 3191.0 24610.4 24696.6 25141.4 25214.5 25560.4 2848.3 2976.5 3010.6 3043.0 3047.2 208401.0 209092.0 210276.0 210542.0 212664.0 45056.3 45298.3 45483.8 45487.8 45499.8 46976.8 47057.8 47293.8 47389.4 47434.5 9855.7 9861.8 9977.6 9983.2 9983.6 6703.2 6899.5 7514.5 7625.4 7631.9 14637.8 14959.7 15176.8 15405.8 15544.9 6157.4 6192.3 6238.7 6279.5 6289.7 744.6 784.5 784.9 785.8 807.7 23194.7 23871.9 23962.9 24060.8 24123.5 843.5 845.9 860.2 863.0 883.2 12218.8 12785.7 12875.8 12878.9 12918.8 297861.0 300623.0 300775.0 301463.0 302185.0 34104.5 35594.5 41369.8 41611.2 41623.0 1000.2 1019.1 1040.3 1048.8 1055.2 23749.5 24562.5 24763.5 24890.3 24962.5 34999.7 35155.6 35274.8 35382.5 35425.3 236355.0 240465.0 313831.0 405539.0 431429.0 31918.0 32145.0 32633.3 32644.2 32649.8 34321.0 34644.1 35561.5 36019.6 36148.0 30310.5 30813.2 30907.0 31581.5 32046.7 29901.6 30493.2 30537.0 31047.5 31237.2 29025.7 29707.9 30088.0 30389.4 30449.5 27688.3 27853.1 28259.9 28525.3 28717.1 21994.9 23067.7 23576.8 23646.3 23859.2
02:50:37 INFO - PROCESS | 3012 |
02:50:37 INFO - PROCESS | 3012 | [#2] jquery.html Cycles:180 Average:19741.94 Median:49502.50 stddev:45553.37 (92.0%) stddev-sans-first:45657.74
02:50:37 INFO - PROCESS | 3012 | Values: 285.7 294.5 299.8 312.5 313.8 10949.0 11799.8 14507.6 15325.6 15670.3 14615.1 15014.7 15168.1 15220.8 15227.7 4211.4 4275.5 4343.6 4346.6 4348.4 3202.0 3355.2 4178.7 4305.9 4341.2 5393.6 5638.6 5642.2 5663.0 5713.3 5400.2 5678.2 5680.2 5688.1 5698.2 4898.5 4917.2 4930.9 4932.1 4934.1 10457.5 10555.2 11652.8 18265.4 18351.6 1533.2 1546.8 1549.5 1551.6 1563.6 10670.9 11554.8 11657.2 11960.0 12354.1 1552.3 1553.1 1561.6 1572.1 1574.4 61754.7 63354.0 63380.7 63969.7 64634.7 8381.5 8453.6 8523.1 8591.8 8598.1 9815.1 10595.9 10612.2 10649.7 11221.7 5042.4 5108.8 5117.7 5126.2 5212.1 5155.4 5239.4 5259.6 5314.0 5361.0 3536.3 3601.0 3630.5 3670.3 3689.2 1381.7 1530.5 1578.0 1581.0 1626.2 616.1 785.3 871.4 875.6 883.8 14622.6 15003.8 15065.3 15326.5 15372.0 744.1 754.7 755.2 793.8 836.3 8212.2 8305.6 8313.9 8377.0 8529.9 252225.0 258366.0 271890.0 280231.0 280680.0 73295.0 77134.0 77793.6 78768.0 79576.0 721.7 729.3 753.0 753.3 755.1 10524.6 13492.5 13916.8 14306.6 14340.9 36238.8 37385.6 37525.6 38194.6 38836.6 49078.5 49448.5 49556.5 49710.0 50228.7 6479.8 6513.2 6604.0 6604.8 6630.6 10102.8 10382.3 10694.6 10910.7 10939.5 8737.3 8748.7 8915.7 9090.4 9185.7 7770.7 8654.4 8892.7 8912.2 9400.6 7813.5 8476.1 8718.3 8772.0 8916.5 6469.8 6592.2 6625.0 6653.4 6818.1 8183.0 8326.4 8440.4 8459.4 8556.2
02:50:37 INFO - PROCESS | 3012 |
02:50:37 INFO - PROCESS | 3012 | [#3] mootools.html Cycles:180 Average:3658.97 Median:4428.96 stddev:1898.81 (42.9%) stddev-sans-first:1894.74
02:50:37 INFO - PROCESS | 3012 | Values: 1145.7 1147.9 1192.4 1367.3 1408.3 5390.8 5573.6 5728.1 5735.5 5741.4 5080.4 5252.2 5493.3 5569.6 5571.1 1676.6 1703.7 1711.0 1711.5 1711.6 1672.1 1694.9 1697.7 1697.8 1698.5 1467.7 1518.6 1521.3 1521.5 1691.1 1663.7 1707.6 1711.6 1713.5 1715.2 1626.7 1662.9 1669.3 1674.2 1675.1 5388.7 5483.4 5494.7 5518.1 5521.4 3298.7 3353.4 3371.7 3380.2 3381.4 2126.2 2217.8 2257.5 2259.0 2261.5 720.7 726.5 738.2 748.8 749.3 6705.6 6856.9 6859.8 6863.6 6868.1 2887.4 3087.8 3187.1 3200.8 3201.2 6116.2 6129.7 6131.1 6134.5 6138.6 3333.1 3356.4 3365.7 3379.2 3382.5 2991.5 3088.1 3094.4 3103.3 3113.0 2757.6 2775.4 2830.2 2837.1 2868.1 969.5 989.3 1000.5 1002.9 1003.5 555.9 557.5 567.1 595.7 615.9 3636.7 3697.0 3751.3 3753.1 3757.8 710.7 724.5 727.6 728.7 741.5 2590.5 2607.9 2716.8 2738.6 2798.1 6105.6 6212.1 6362.7 6363.2 6373.4 6447.9 6551.0 6565.9 6571.6 6571.8 5191.7 5325.6 5333.7 5336.9 5342.3 3685.7 3743.6 3758.2 3784.4 3883.8 5756.9 5920.7 5965.6 6041.8 6070.8 5968.8 6126.4 6127.4 6153.9 6156.6 5149.4 5244.2 5332.7 5334.6 5340.5 5203.8 5264.8 5268.4 5284.9 5404.4 4850.0 4863.4 4961.3 4963.7 4999.1 4842.1 4904.3 4912.1 4912.7 4916.1 4390.9 4414.3 4425.6 4432.3 4437.4 3219.2 3283.0 3288.2 3289.7 3312.1 3203.2 3251.8 3267.8 3270.6 3282.4
02:50:37 INFO - PROCESS | 3012 |
02:50:37 INFO - PROCESS | 3012 | [#4] prototype.html Cycles:180 Average:3757.72 Median:4339.64 stddev:1125.54 (25.9%) stddev-sans-first:1096.97
02:50:37 INFO - PROCESS | 3012 | Values: 222.1 235.6 240.2 244.8 249.5 4165.9 4316.6 4362.2 4432.7 4451.3 4345.6 4357.0 4370.4 4374.6 4384.8 3067.6 3116.7 3194.8 3240.1 3244.1 3170.1 3242.6 3249.4 3258.3 3270.1 3171.6 3175.2 3223.2 3234.6 3236.5 3060.4 3155.5 3233.1 3235.3 3246.6 2794.2 2853.5 2866.0 2868.9 2878.1 4223.9 4243.3 4246.3 4247.5 4250.0 3821.4 3830.8 3843.9 3845.8 3846.9 4437.0 4505.5 4510.0 4520.5 4542.5 3123.1 3162.3 3163.9 3210.3 3241.5 4619.5 4634.6 4676.9 4680.7 4718.4 3203.6 3241.8 3247.9 3250.7 3254.7 3255.8 3287.3 3292.4 3294.4 3306.3 3783.4 3786.2 3790.4 3792.8 3794.6 4225.7 4313.5 4466.4 4514.3 4523.2 1117.4 1136.4 1137.2 1139.0 1142.8 760.9 768.9 778.2 780.0 782.4 4211.1 4230.3 4239.7 4242.7 4260.0 4576.3 4576.9 4590.2 4594.8 4601.3 4240.0 4255.5 4256.2 4256.8 4261.3 2401.7 2409.2 2414.9 2428.4 2429.1 4333.7 4553.4 4586.6 4598.7 4618.7 4611.0 4612.2 4633.7 4641.7 4645.7 4621.0 4628.9 4629.0 4635.7 4640.8 4552.1 4555.3 4566.5 4572.8 4578.5 4329.0 4638.8 4644.9 4650.0 4661.8 4754.3 4814.9 4816.8 4821.4 4823.3 3168.3 3185.2 3187.9 3188.8 3190.4 4491.5 4686.4 4688.4 4707.7 4713.6 4431.9 4456.9 4519.7 4523.0 4557.5 4524.2 4534.0 4535.5 4535.9 4539.1 4426.8 4459.8 4463.9 4471.4 4473.3 4372.6 4487.6 4542.9 4584.3 4591.7 4480.8 4511.6 4516.9 4517.3 4521.0
02:50:37 INFO - PROCESS | 3012 |
02:50:37 INFO - PROCESS | 3012 | [#5] yui.html Cycles:180 Average:14253.23 Median:27054.15 stddev:18657.07 (69.0%) stddev-sans-first:18681.20
02:50:37 INFO - PROCESS | 3012 | Values: 590.2 603.7 609.6 616.2 625.0 12222.3 13079.8 13092.0 13109.6 13133.1 11978.0 12172.9 12176.5 12180.6 12210.6 488.1 606.4 612.5 616.7 619.2 492.1 623.4 632.0 636.2 640.8 655.8 662.5 669.1 670.9 671.7 516.2 665.1 669.0 670.3 670.7 383.5 473.2 477.4 483.8 487.3 12348.8 12368.3 12403.5 12477.5 12526.0 11373.5 11413.3 11517.5 11857.2 12222.4 9503.9 10347.8 11084.2 11462.4 11940.8 3954.8 3978.3 3988.4 4054.0 4129.8 43263.0 61732.0 64198.4 64969.7 65275.3 9650.6 9691.0 9711.2 9771.9 9773.0 16487.6 17308.6 17326.1 17423.7 18319.2 6526.3 6655.8 6686.8 6826.4 6859.4 3228.6 3270.6 3284.7 3381.9 3472.1 2242.9 2316.1 2320.3 2327.5 2338.2 375.9 377.2 383.9 385.8 386.1 451.8 494.6 512.8 534.5 549.2 7045.8 7066.6 7152.6 7208.0 7227.0 468.4 480.0 487.7 519.4 530.2 3509.0 3559.2 3609.6 3664.6 3738.3 60981.0 64541.4 64800.7 65429.0 65846.7 58635.7 58704.1 59210.0 59356.4 59654.7 52632.5 52967.0 53381.0 53573.8 53910.0 6740.3 6753.1 6809.8 6879.7 6891.8 36385.8 36930.3 37036.8 37107.0 37597.1 45933.0 47583.8 48729.8 49797.8 50507.0 8645.4 8658.0 8664.4 8762.6 8772.9 10020.2 10027.7 10183.9 10282.4 10558.3 8880.6 9042.6 9080.1 9109.9 9392.6 9664.7 9772.5 9786.6 9918.6 10402.9 9396.8 9478.2 9537.4 9602.4 9652.9 8820.6 8832.4 8932.8 9044.1 9192.9 8456.5 8475.4 8519.8 8556.3 8645.7
02:50:37 INFO - PROCESS | 3012 | -------- Summary: end --------
02:50:37 INFO - PROCESS | 3012 |
02:50:37 INFO - TEST-INFO | 3012: exit 0
02:50:38 INFO - TEST-OK | dromaeo_css | took 1126997ms
02:50:38 INFO - TEST-START | kraken
02:50:38 INFO - operating with platform_type : w7_
02:50:38 INFO - Initialising browser for kraken test...
02:50:38 INFO - TEST-INFO | started process 4068 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmphldr4u\profile http://localhost:49990/getInfo.html)
02:50:43 INFO - PROCESS | 4068 | __metrics Screen width/height:1600/1200
02:50:43 INFO - PROCESS | 4068 | colorDepth:24
02:50:43 INFO - PROCESS | 4068 | Browser inner width/height: 1010/674
02:50:43 INFO - PROCESS | 4068 | __metrics
02:50:43 INFO - PROCESS | 4068 | 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"
02:50:43 INFO - PROCESS | 4068 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
02:50:44 INFO - PROCESS | 4068 | console.error:
02:50:44 INFO - PROCESS | 4068 | Message: Error: Connection closed before committing the transaction.
02:50:44 INFO - PROCESS | 4068 | Stack:
02:50:44 INFO - PROCESS | 4068 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23
02:50:44 INFO - PROCESS | 4068 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7
02:50:44 INFO - PROCESS | 4068 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11
02:50:44 INFO - PROCESS | 4068 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7
02:50:44 INFO - PROCESS | 4068 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7
02:50:44 INFO - PROCESS | 4068 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15
02:50:44 INFO - PROCESS | 4068 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:550:9
02:50:44 INFO - PROCESS | 4068 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
02:50:44 INFO - PROCESS | 4068 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5
02:50:44 INFO - PROCESS | 4068 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
02:50:44 INFO - PROCESS | 4068 | Barrier/this.client.addBlocker/promise13.2%
02:51:45 INFO - TinderboxPrint: I/O read bytes / time
79,918,592 / 658,890
02:51:45 INFO - TinderboxPrint: I/O write bytes / time
505,528,320 / 3,994,390
02:51:45 INFO - TinderboxPrint: CPU idle
8,319.4 (86.8%)
02:51:45 INFO - TinderboxPrint: CPU user
1,229.8 (12.8%)
02:51:45 INFO - install - Wall time: 4s; CPU: 8.0%; Read bytes: 0; Write bytes: 52958208; Read time: 0; Write time: 738000
02:51:45 INFO - run-tests - Wall time: 1196s; CPU: 13.0%; Read bytes: 79885824; Write bytes: 426286080; Read time: 632590; Write time: 3051500
02:51:45 INFO - Running post-run listener: _upload_blobber_files
02:51:45 INFO - Blob upload gear active.
02:51:45 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
02:51:45 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
02:51:45 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']
02:51:45 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
02:51:46 INFO - (blobuploader) - INFO - Open directory for files ...
02:51:46 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
02:51:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:51:46 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:51:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
02:51:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:51:47 INFO - (blobuploader) - INFO - Done attempting.
02:51:47 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
02:51:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:51:47 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:51:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
02:51:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:51:48 INFO - (blobuploader) - INFO - Done attempting.
02:51:48 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
02:51:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:51:48 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:51:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
02:51:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:51:56 INFO - (blobuploader) - INFO - Done attempting.
02:51:56 INFO - (blobuploader) - INFO - Iteration through files over.
02:51:56 INFO - Return code: 0
02:51:56 INFO - rmtree: C:\slave\test\build\uploaded_files.json
02:51:56 INFO - Using _rmtree_windows ...
02:51:56 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
02:51:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8dc24544017fc3d6397dc38a38a37fb4c4a6ca02cf93f7ff0fb0b2a91c93b3252146479251dc67ca6a3b24c67f1b70035688804a90814e5421f7c5345e6f03da", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7e43e67a38467571bcc61fff53424b08b327d17061915ad67bcf2329a3e8475cca69cb41c2c3c04274625a7570b9d8b95f23da803e6a4b4d856097040ea2bb4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8bf87bc7105f79b90ba6add456afca721e919cde281e6a08b705037bc8080094dd7670d24126cccc9e817e806d1597603d74e749fe962b2b9203cffe23ffcb3f"}
02:51:56 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
02:51:56 INFO - Writing to file C:\slave\test\properties\blobber_files
02:51:56 INFO - Contents:
02:51:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8dc24544017fc3d6397dc38a38a37fb4c4a6ca02cf93f7ff0fb0b2a91c93b3252146479251dc67ca6a3b24c67f1b70035688804a90814e5421f7c5345e6f03da", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7e43e67a38467571bcc61fff53424b08b327d17061915ad67bcf2329a3e8475cca69cb41c2c3c04274625a7570b9d8b95f23da803e6a4b4d856097040ea2bb4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8bf87bc7105f79b90ba6add456afca721e919cde281e6a08b705037bc8080094dd7670d24126cccc9e817e806d1597603d74e749fe962b2b9203cffe23ffcb3f"}
02:51:56 INFO - Running post-run listener: copy_logs_to_upload_dir
02:51:56 INFO - Copying logs to upload dir...
02:51:56 INFO - mkdir: C:\slave\test\build\upload\logs
02:51:56 INFO - Copying logs to upload dir...
02:51:56 INFO - Using _rmtree_windows ...
02:51:56 INFO - Using _rmtree_windows ...
02:51:56 INFO - Using _rmtree_windows ...
02:51:56 INFO - Using _rmtree_windows ...
02:51:56 INFO - Using _rmtree_windows ...
02:51:56 INFO - Using _rmtree_windows ...
02:51:56 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1393.934000
========= master_lag: 0.06 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 13 secs) (at 2016-12-02 02:51:56.344277) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-02 02:51:56.345056) =========
'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-050
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-050
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-050
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/8dc24544017fc3d6397dc38a38a37fb4c4a6ca02cf93f7ff0fb0b2a91c93b3252146479251dc67ca6a3b24c67f1b70035688804a90814e5421f7c5345e6f03da", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7e43e67a38467571bcc61fff53424b08b327d17061915ad67bcf2329a3e8475cca69cb41c2c3c04274625a7570b9d8b95f23da803e6a4b4d856097040ea2bb4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8bf87bc7105f79b90ba6add456afca721e919cde281e6a08b705037bc8080094dd7670d24126cccc9e817e806d1597603d74e749fe962b2b9203cffe23ffcb3f"}
build_url:https://queue.taskcluster.net/v1/task/NjhbFF5pT5m2lPvlf5qqBg/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/NjhbFF5pT5m2lPvlf5qqBg/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/8dc24544017fc3d6397dc38a38a37fb4c4a6ca02cf93f7ff0fb0b2a91c93b3252146479251dc67ca6a3b24c67f1b70035688804a90814e5421f7c5345e6f03da", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7e43e67a38467571bcc61fff53424b08b327d17061915ad67bcf2329a3e8475cca69cb41c2c3c04274625a7570b9d8b95f23da803e6a4b4d856097040ea2bb4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8bf87bc7105f79b90ba6add456afca721e919cde281e6a08b705037bc8080094dd7670d24126cccc9e817e806d1597603d74e749fe962b2b9203cffe23ffcb3f"}'
========= master_lag: 0.01 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-02 02:51:56.460157) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 02:51:56.460508) =========
'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-050
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-050
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-050
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.101000
========= master_lag: 0.01 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 02:51:56.571388) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-12-02 02:51:56.571803) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-12-02 02:51:57.025026) =========
========= Total master_lag: 0.15 =========