builder: fx-team_win7_ix_test-dromaeojs
slave: t-w732-ix-149
starttime: 1474657163.71
results: success (0)
buildid: 20160923101725
builduid: 4996e7026b864fd19f6eeb3186d92dcf
revision: 76fd7dbb20d5387fe43dd30337b12f8ccb0bd30c
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-23 11:59:23.711369) =========
master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-23 11:59:23.712028) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-23 11:59:23.712460) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-23 11:59:23.907455) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-23 11:59:23.908013) =========
'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-149
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-149
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-149
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
--11:59:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 13.76 MB/s
11:59:25 (13.76 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=1.848000
========= master_lag: 0.05 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-23 11:59:25.801839) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 20 secs) (at 2016-09-23 11:59:25.802234) =========
'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-149
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-149
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-149
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=15.901000
========= master_lag: 4.20 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 20 secs) (at 2016-09-23 11:59:45.906168) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-23 11:59:45.906551) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 76fd7dbb20d5387fe43dd30337b12f8ccb0bd30c --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 76fd7dbb20d5387fe43dd30337b12f8ccb0bd30c --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-149
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-149
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-149
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-09-23 11:59:46,319 truncating revision to first 12 chars
2016-09-23 11:59:46,319 Setting DEBUG logging.
2016-09-23 11:59:46,319 attempt 1/10
2016-09-23 11:59:46,319 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/76fd7dbb20d5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-09-23 11:59:47,088 unpacking tar archive at: fx-team-76fd7dbb20d5/testing/mozharness/
2016-09-23 11:59:47,234 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.529000
========= master_lag: 0.09 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-23 11:59:47.523116) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-23 11:59:47.523446) =========
script_repo_revision: 76fd7dbb20d5387fe43dd30337b12f8ccb0bd30c
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-23 11:59:47.523858) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-23 11:59:47.524135) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-23 11:59:47.535813) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 41 secs) (at 2016-09-23 11:59:47.536098) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team-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', 'Fx-Team-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-149
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-149
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-149
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
11:59:48 INFO - MultiFileLogger online at 20160923 11:59:48 in C:\slave\test
11:59:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO
11:59:48 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
11:59:48 INFO - {'append_to_log': False,
11:59:48 INFO - 'base_work_dir': 'C:\\slave\\test',
11:59:48 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO',
11:59:48 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
11:59:48 INFO - 'branch': 'Fx-Team-Non-PGO',
11:59:48 INFO - 'buildbot_json_path': 'buildprops.json',
11:59:48 INFO - 'config_files': ('talos/windows_config.py',),
11:59:48 INFO - 'default_actions': ('clobber',
11:59:48 INFO - 'read-buildbot-config',
11:59:48 INFO - 'download-and-extract',
11:59:48 INFO - 'populate-webroot',
11:59:48 INFO - 'create-virtualenv',
11:59:48 INFO - 'install',
11:59:48 INFO - 'run-tests'),
11:59:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
11:59:48 INFO - 'download_minidump_stackwalk': True,
11:59:48 INFO - 'download_symbols': 'ondemand',
11:59:48 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
11:59:48 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
11:59:48 INFO - 'hg': 'c:/mozilla-build/hg/hg',
11:59:48 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
11:59:48 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
11:59:48 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
11:59:48 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
11:59:48 INFO - 'C:/mozilla-build/tooltool.py'),
11:59:48 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
11:59:48 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
11:59:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
11:59:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
11:59:48 INFO - 'installer_path': 'installer.exe',
11:59:48 INFO - 'log_level': 'info',
11:59:48 INFO - 'log_name': 'talos',
11:59:48 INFO - 'log_to_console': True,
11:59:48 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
11:59:48 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
11:59:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
11:59:48 INFO - 'opt_config_files': (),
11:59:48 INFO - 'pip_index': False,
11:59:48 INFO - 'sps_profile': False,
11:59:48 INFO - 'sps_profile_interval': 0,
11:59:48 INFO - 'suite': 'dromaeojs',
11:59:48 INFO - 'system_bits': '32',
11:59:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
11:59:48 INFO - 'title': 'T-W732-IX-149',
11:59:48 INFO - 'use_talos_json': True,
11:59:48 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
11:59:48 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
11:59:48 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
11:59:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
11:59:48 INFO - 'work_dir': 'build'}
11:59:48 INFO - [mozharness: 2016-09-23 18:59:48.047000Z] Running clobber step.
11:59:48 INFO - Running pre-action listener: _resource_record_pre_action
11:59:48 INFO - Running main action method: clobber
11:59:48 INFO - rmtree: C:\slave\test\build
11:59:48 INFO - Using _rmtree_windows ...
11:59:48 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
12:02:36 INFO - Running post-action listener: _resource_record_post_action
12:02:36 INFO - [mozharness: 2016-09-23 19:02:36.948000Z] Finished clobber step (success)
12:02:36 INFO - [mozharness: 2016-09-23 19:02:36.948000Z] Running read-buildbot-config step.
12:02:36 INFO - Running pre-action listener: _resource_record_pre_action
12:02:36 INFO - Running main action method: read_buildbot_config
12:02:36 INFO - Using buildbot properties:
12:02:36 INFO - {
12:02:36 INFO - "project": "",
12:02:36 INFO - "product": "firefox",
12:02:36 INFO - "script_repo_revision": "production",
12:02:36 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
12:02:36 INFO - "repository": "",
12:02:36 INFO - "buildername": "Windows 7 32-bit fx-team talos dromaeojs",
12:02:36 INFO - "buildid": "20160923101725",
12:02:36 INFO - "pgo_build": "False",
12:02:36 INFO - "basedir": "C:\\slave\\test",
12:02:36 INFO - "buildnumber": 151,
12:02:36 INFO - "slavename": "t-w732-ix-149",
12:02:36 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/",
12:02:36 INFO - "platform": "win7_ix",
12:02:36 INFO - "scheduler": "tests-fx-team-win32-talos",
12:02:36 INFO - "branch": "fx-team",
12:02:36 INFO - "repo_path": "integration/fx-team",
12:02:36 INFO - "revision": "76fd7dbb20d5387fe43dd30337b12f8ccb0bd30c",
12:02:36 INFO - "stage_platform": "win32",
12:02:36 INFO - "builduid": "4996e7026b864fd19f6eeb3186d92dcf",
12:02:36 INFO - "slavebuilddir": "test"
12:02:36 INFO - }
12:02:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
12:02:36 INFO - Running post-action listener: _resource_record_post_action
12:02:36 INFO - [mozharness: 2016-09-23 19:02:36.952000Z] Finished read-buildbot-config step (success)
12:02:36 INFO - [mozharness: 2016-09-23 19:02:36.952000Z] Running download-and-extract step.
12:02:36 INFO - Running pre-action listener: _resource_record_pre_action
12:02:36 INFO - Running main action method: download_and_extract
12:02:36 INFO - mkdir: C:\slave\test\build\tests
12:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:02:36 INFO - trying https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
12:02:36 INFO - Downloading https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
12:02:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1
12:02:38 INFO - Downloaded 1274 bytes.
12:02:38 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
12:02:38 INFO - Using the following test package requirements:
12:02:38 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
12:02:38 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:02:38 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
12:02:38 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:02:38 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'],
12:02:38 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:02:38 INFO - u'jsshell-win32.zip'],
12:02:38 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:02:38 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
12:02:38 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
12:02:38 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:02:38 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
12:02:38 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:02:38 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
12:02:38 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:02:38 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
12:02:38 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:02:38 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
12:02:38 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
12:02:38 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
12:02:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
12:02:38 INFO - Fetch https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
12:02:38 INFO - Expected file size: 17290855
12:03:07 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos
12:03:07 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
12:03:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
12:03:07 INFO - Fetch https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
12:03:08 INFO - Expected file size: 17290855
12:03:21 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
12:03:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
12:03:21 INFO - Fetch https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
12:03:22 INFO - Expected file size: 14414059
12:03:24 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')]}
12:03:24 INFO - trying https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
12:03:24 INFO - Downloading https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
12:03:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
12:03:27 INFO - Downloaded 53502150 bytes.
12:03:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
12:03:27 INFO - mkdir: C:\slave\test\properties
12:03:27 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
12:03:27 INFO - Writing to file C:\slave\test\properties\build_url
12:03:27 INFO - Contents:
12:03:27 INFO - build_url:https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
12:03:28 INFO - Running post-action listener: _resource_record_post_action
12:03:28 INFO - Running post-action listener: set_extra_try_arguments
12:03:28 INFO - [mozharness: 2016-09-23 19:03:28.127000Z] Finished download-and-extract step (success)
12:03:28 INFO - [mozharness: 2016-09-23 19:03:28.127000Z] Running populate-webroot step.
12:03:28 INFO - Running pre-action listener: _resource_record_pre_action
12:03:28 INFO - Running main action method: populate_webroot
12:03:28 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
12:03:28 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
12:03:28 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
12:03:28 INFO - u'--tppagecycles',
12:03:28 INFO - u'7'],
12:03:28 INFO - u'tests': [u'tsvgm']}},
12:03:28 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
12:03:28 INFO - u'tests': [u'tresize', u'tcanvasmark']},
12:03:28 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
12:03:28 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
12:03:28 INFO - u'tests': [u'dromaeo_css', u'kraken']},
12:03:28 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
12:03:28 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:03:28 INFO - u'talos_options': [u'--disable-e10s'],
12:03:28 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
12:03:28 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:03:28 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
12:03:28 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:03:28 INFO - u'talos_options': [u'--disable-e10s'],
12:03:28 INFO - u'tests': [u'damp', u'tps']},
12:03:28 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:03:28 INFO - u'tests': [u'damp', u'tps']},
12:03:28 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
12:03:28 INFO - u'tests': [u'dromaeo_dom']},
12:03:28 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
12:03:28 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
12:03:28 INFO - u'tests': [u'basic_compositor_video']},
12:03:28 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
12:03:28 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
12:03:28 INFO - u'tests': [u'a11yr',
12:03:28 INFO - u'ts_paint',
12:03:28 INFO - u'tpaint',
12:03:28 INFO - u'sessionrestore',
12:03:28 INFO - u'sessionrestore_no_auto_restore',
12:03:28 INFO - u'tabpaint']},
12:03:28 INFO - u'other-e10s': {u'tests': [u'a11yr',
12:03:28 INFO - u'ts_paint',
12:03:28 INFO - u'tpaint',
12:03:28 INFO - u'sessionrestore',
12:03:28 INFO - u'sessionrestore_no_auto_restore',
12:03:28 INFO - u'tabpaint']},
12:03:28 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
12:03:28 INFO - u'ts_paint',
12:03:28 INFO - u'tpaint',
12:03:28 INFO - u'sessionrestore',
12:03:28 INFO - u'sessionrestore_no_auto_restore',
12:03:28 INFO - u'tabpaint']},
12:03:28 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
12:03:28 INFO - u'ts_paint',
12:03:28 INFO - u'tpaint',
12:03:28 INFO - u'sessionrestore',
12:03:28 INFO - u'sessionrestore_no_auto_restore',
12:03:28 INFO - u'tabpaint']},
12:03:28 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
12:03:28 INFO - u'tests': [u'a11yr',
12:03:28 INFO - u'ts_paint',
12:03:28 INFO - u'tpaint',
12:03:28 INFO - u'sessionrestore',
12:03:28 INFO - u'sessionrestore_no_auto_restore',
12:03:28 INFO - u'tabpaint']},
12:03:28 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
12:03:28 INFO - u'tests': [u'a11yr',
12:03:28 INFO - u'ts_paint',
12:03:28 INFO - u'tpaint',
12:03:28 INFO - u'sessionrestore',
12:03:28 INFO - u'sessionrestore_no_auto_restore',
12:03:28 INFO - u'tabpaint']},
12:03:28 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
12:03:28 INFO - u'tests': [u'tsvgx',
12:03:28 INFO - u'tsvgr_opacity',
12:03:28 INFO - u'tart',
12:03:28 INFO - u'tscrollx',
12:03:28 INFO - u'cart']},
12:03:28 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
12:03:28 INFO - u'tsvgr_opacity',
12:03:28 INFO - u'tart',
12:03:28 INFO - u'tscrollx',
12:03:28 INFO - u'cart']},
12:03:28 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:03:28 INFO - u'talos_options': [u'--disable-e10s'],
12:03:28 INFO - u'tests': [u'tp5o']},
12:03:28 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:03:28 INFO - u'tests': [u'tp5o']},
12:03:28 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:03:28 INFO - u'talos_options': [u'--disable-e10s',
12:03:28 INFO - u'--xperf_path',
12:03:28 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:03:28 INFO - u'tests': [u'tp5n']},
12:03:28 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:03:28 INFO - u'talos_options': [u'--xperf_path',
12:03:28 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:03:28 INFO - u'tests': [u'tp5n']}},
12:03:28 INFO - u'talos.zip': {u'path': u'',
12:03:28 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
12:03:28 INFO - Running post-action listener: _resource_record_post_action
12:03:28 INFO - [mozharness: 2016-09-23 19:03:28.144000Z] Finished populate-webroot step (success)
12:03:28 INFO - [mozharness: 2016-09-23 19:03:28.145000Z] Running create-virtualenv step.
12:03:28 INFO - Running pre-action listener: _resource_record_pre_action
12:03:28 INFO - Running main action method: create_virtualenv
12:03:28 INFO - Creating virtualenv C:\slave\test\build/venv
12:03:28 INFO - mkdir: C:\slave\test\build\venv\Scripts
12:03:28 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
12:03:28 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'!
12:03:28 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
12:03:28 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
12:03:31 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
12:03:38 INFO - Installing distribute......................................................................................................................................................................................done.
12:03:39 INFO - Return code: 0
12:03:39 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
12:03:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:03:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:03:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:03:39 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01790640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0183BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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
12:03:39 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build
12:03:39 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
12:03:39 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:03:39 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:03:39 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:03:39 INFO - 'COMPUTERNAME': 'T-W732-IX-149',
12:03:39 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:03:39 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:03:39 INFO - 'DCLOCATION': 'SCL3',
12:03:39 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:03:39 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:03:39 INFO - 'HOMEDRIVE': 'C:',
12:03:39 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:03:39 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:03:39 INFO - 'KTS_VERSION': '1.19c',
12:03:39 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:03:39 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149',
12:03:39 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:03:39 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:03:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:03:39 INFO - 'MOZ_NO_REMOTE': '1',
12:03:39 INFO - 'NO_EM_RESTART': '1',
12:03:39 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:03:39 INFO - 'OS': 'Windows_NT',
12:03:39 INFO - 'OURDRIVE': 'C:',
12:03:39 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:03:39 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:03:39 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:03:39 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:03:39 INFO - 'PROCESSOR_LEVEL': '6',
12:03:39 INFO - 'PROCESSOR_REVISION': '1e05',
12:03:39 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:03:39 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:03:39 INFO - 'PROMPT': '$P$G',
12:03:39 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:03:39 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:03:39 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:03:39 INFO - 'PWD': 'C:\\slave\\test',
12:03:39 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:03:39 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:03:39 INFO - 'SYSTEMDRIVE': 'C:',
12:03:39 INFO - 'SYSTEMROOT': 'C:\\windows',
12:03:39 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:03:39 INFO - 'TEST1': 'testie',
12:03:39 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:03:39 INFO - 'USERDOMAIN': 'T-W732-IX-149',
12:03:39 INFO - 'USERNAME': 'cltbld',
12:03:39 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:03:39 INFO - 'WINDIR': 'C:\\windows',
12:03:39 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:03:39 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:03:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:03:40 INFO - Ignoring indexes: http://pypi.python.org/simple/
12:03:40 INFO - Downloading/unpacking pip>=1.5
12:03:45 INFO - Running setup.py egg_info for package pip
12:03:45 INFO - warning: no files found matching 'pip\cacert.pem'
12:03:45 INFO - warning: no files found matching '*.html' under directory 'docs'
12:03:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
12:03:45 INFO - no previously-included directories found matching 'docs\_build\_sources'
12:03:45 INFO - Installing collected packages: pip
12:03:45 INFO - Found existing installation: pip 0.8.2
12:03:45 INFO - Uninstalling pip:
12:03:45 INFO - Successfully uninstalled pip
12:03:45 INFO - Running setup.py install for pip
12:03:45 INFO - warning: no files found matching 'pip\cacert.pem'
12:03:45 INFO - warning: no files found matching '*.html' under directory 'docs'
12:03:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
12:03:45 INFO - no previously-included directories found matching 'docs\_build\_sources'
12:03:45 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
12:03:45 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
12:03:45 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
12:03:45 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
12:03:45 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
12:03:45 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
12:03:45 INFO - Successfully installed pip
12:03:45 INFO - Cleaning up...
12:03:45 INFO - Return code: 0
12:03:45 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
12:03: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')]}
12:03:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:03: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')]}
12:03:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:03: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', '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 0x01790640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0183BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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
12:03: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', 'psutil>=3.1.1'] in C:\slave\test\build
12:03: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 psutil>=3.1.1
12:03:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:03:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:03:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:03:45 INFO - 'COMPUTERNAME': 'T-W732-IX-149',
12:03:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:03:45 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:03:45 INFO - 'DCLOCATION': 'SCL3',
12:03:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:03:45 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:03:45 INFO - 'HOMEDRIVE': 'C:',
12:03:45 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:03:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:03:45 INFO - 'KTS_VERSION': '1.19c',
12:03:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:03:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149',
12:03:45 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:03:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:03:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:03:45 INFO - 'MOZ_NO_REMOTE': '1',
12:03:45 INFO - 'NO_EM_RESTART': '1',
12:03:45 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:03:45 INFO - 'OS': 'Windows_NT',
12:03:45 INFO - 'OURDRIVE': 'C:',
12:03: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;',
12:03:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:03:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:03:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:03:45 INFO - 'PROCESSOR_LEVEL': '6',
12:03:45 INFO - 'PROCESSOR_REVISION': '1e05',
12:03:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:03:45 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:03:45 INFO - 'PROMPT': '$P$G',
12:03:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:03:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:03:45 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:03:45 INFO - 'PWD': 'C:\\slave\\test',
12:03:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:03:45 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:03:45 INFO - 'SYSTEMDRIVE': 'C:',
12:03:45 INFO - 'SYSTEMROOT': 'C:\\windows',
12:03:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:03:45 INFO - 'TEST1': 'testie',
12:03:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:03:45 INFO - 'USERDOMAIN': 'T-W732-IX-149',
12:03:45 INFO - 'USERNAME': 'cltbld',
12:03:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:03:45 INFO - 'WINDIR': 'C:\\windows',
12:03:45 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:03:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:03:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:03:47 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:03:47 INFO - Downloading/unpacking psutil>=3.1.1
12:03:47 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
12:03:47 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
12:03:48 INFO - Installing collected packages: psutil
12:03:48 INFO - Successfully installed psutil
12:03:48 INFO - Cleaning up...
12:03:48 INFO - Return code: 0
12:03:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
12:03:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:03:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:03:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:03:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:03:48 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 0x01790640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0183BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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
12:03:48 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
12:03:48 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
12:03:48 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:03:48 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:03:48 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:03:48 INFO - 'COMPUTERNAME': 'T-W732-IX-149',
12:03:48 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:03:48 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:03:48 INFO - 'DCLOCATION': 'SCL3',
12:03:48 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:03:48 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:03:48 INFO - 'HOMEDRIVE': 'C:',
12:03:48 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:03:48 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:03:48 INFO - 'KTS_VERSION': '1.19c',
12:03:48 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:03:48 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149',
12:03:48 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:03:48 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:03:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:03:48 INFO - 'MOZ_NO_REMOTE': '1',
12:03:48 INFO - 'NO_EM_RESTART': '1',
12:03:48 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:03:48 INFO - 'OS': 'Windows_NT',
12:03:48 INFO - 'OURDRIVE': 'C:',
12:03:48 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;',
12:03:48 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:03:48 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:03:48 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:03:48 INFO - 'PROCESSOR_LEVEL': '6',
12:03:48 INFO - 'PROCESSOR_REVISION': '1e05',
12:03:48 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:03:48 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:03:48 INFO - 'PROMPT': '$P$G',
12:03:48 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:03:48 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:03:48 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:03:48 INFO - 'PWD': 'C:\\slave\\test',
12:03:48 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:03:48 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:03:48 INFO - 'SYSTEMDRIVE': 'C:',
12:03:48 INFO - 'SYSTEMROOT': 'C:\\windows',
12:03:48 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:03:48 INFO - 'TEST1': 'testie',
12:03:48 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:03:48 INFO - 'USERDOMAIN': 'T-W732-IX-149',
12:03:48 INFO - 'USERNAME': 'cltbld',
12:03:48 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:03:48 INFO - 'WINDIR': 'C:\\windows',
12:03:48 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:03:48 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:03:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:03:48 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:03:48 INFO - Downloading/unpacking mozsystemmonitor==0.3
12:03:48 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
12:03:48 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
12:03:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz
12:03:49 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
12:03:49 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)
12:03:49 INFO - Installing collected packages: mozsystemmonitor
12:03:49 INFO - Running setup.py install for mozsystemmonitor
12:03:50 INFO - Successfully installed mozsystemmonitor
12:03:50 INFO - Cleaning up...
12:03:50 INFO - Return code: 0
12:03:50 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
12:03: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')]}
12:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:03: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')]}
12:03:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:03:50 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 0x01790640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0183BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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
12:03:50 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
12:03:50 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
12:03:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:03:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:03:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:03:50 INFO - 'COMPUTERNAME': 'T-W732-IX-149',
12:03:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:03:50 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:03:50 INFO - 'DCLOCATION': 'SCL3',
12:03:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:03:50 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:03:50 INFO - 'HOMEDRIVE': 'C:',
12:03:50 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:03:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:03:50 INFO - 'KTS_VERSION': '1.19c',
12:03:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:03:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149',
12:03:50 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:03:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:03:50 INFO - 'MOZ_NO_REMOTE': '1',
12:03:50 INFO - 'NO_EM_RESTART': '1',
12:03:50 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:03:50 INFO - 'OS': 'Windows_NT',
12:03:50 INFO - 'OURDRIVE': 'C:',
12:03: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;',
12:03:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:03:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:03:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:03:50 INFO - 'PROCESSOR_LEVEL': '6',
12:03:50 INFO - 'PROCESSOR_REVISION': '1e05',
12:03:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:03:50 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:03:50 INFO - 'PROMPT': '$P$G',
12:03:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:03:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:03:50 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:03:50 INFO - 'PWD': 'C:\\slave\\test',
12:03:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:03:50 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:03:50 INFO - 'SYSTEMDRIVE': 'C:',
12:03:50 INFO - 'SYSTEMROOT': 'C:\\windows',
12:03:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:03:50 INFO - 'TEST1': 'testie',
12:03:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:03:50 INFO - 'USERDOMAIN': 'T-W732-IX-149',
12:03:50 INFO - 'USERNAME': 'cltbld',
12:03:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:03:50 INFO - 'WINDIR': 'C:\\windows',
12:03:50 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:03:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:03:50 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:03:50 INFO - Downloading/unpacking jsonschema==2.5.1
12:03:50 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
12:03:50 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
12:03:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
12:03:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
12:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:03:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:03:51 INFO - Downloading functools32-3.2.3-2.tar.gz
12:03:51 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
12:03:51 INFO - warning: no files found matching '*.txt'
12:03:51 INFO - no previously-included directories found matching 'build'
12:03:51 INFO - no previously-included directories found matching 'dist'
12:03:51 INFO - no previously-included directories found matching '.git*'
12:03:51 INFO - Installing collected packages: jsonschema, functools32
12:03:51 INFO - Running setup.py install for functools32
12:03:52 INFO - warning: no files found matching '*.txt'
12:03:52 INFO - no previously-included directories found matching 'build'
12:03:52 INFO - no previously-included directories found matching 'dist'
12:03:52 INFO - no previously-included directories found matching '.git*'
12:03:52 INFO - Successfully installed jsonschema functools32
12:03:52 INFO - Cleaning up...
12:03:52 INFO - Return code: 0
12:03:52 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
12:03:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:03:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:03:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:03:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:03:52 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 0x01790640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0183BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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
12:03:52 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
12:03:52 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
12:03:52 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:03:52 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:03:52 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:03:52 INFO - 'COMPUTERNAME': 'T-W732-IX-149',
12:03:52 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:03:52 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:03:52 INFO - 'DCLOCATION': 'SCL3',
12:03:52 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:03:52 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:03:52 INFO - 'HOMEDRIVE': 'C:',
12:03:52 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:03:52 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:03:52 INFO - 'KTS_VERSION': '1.19c',
12:03:52 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:03:52 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149',
12:03:52 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:03:52 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:03:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:03:52 INFO - 'MOZ_NO_REMOTE': '1',
12:03:52 INFO - 'NO_EM_RESTART': '1',
12:03:52 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:03:52 INFO - 'OS': 'Windows_NT',
12:03:52 INFO - 'OURDRIVE': 'C:',
12:03:52 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;',
12:03:52 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:03:52 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:03:52 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:03:52 INFO - 'PROCESSOR_LEVEL': '6',
12:03:52 INFO - 'PROCESSOR_REVISION': '1e05',
12:03:52 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:03:52 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:03:52 INFO - 'PROMPT': '$P$G',
12:03:52 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:03:52 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:03:52 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:03:52 INFO - 'PWD': 'C:\\slave\\test',
12:03:52 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:03:52 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:03:52 INFO - 'SYSTEMDRIVE': 'C:',
12:03:52 INFO - 'SYSTEMROOT': 'C:\\windows',
12:03:52 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:03:52 INFO - 'TEST1': 'testie',
12:03:52 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:03:52 INFO - 'USERDOMAIN': 'T-W732-IX-149',
12:03:52 INFO - 'USERNAME': 'cltbld',
12:03:52 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:03:52 INFO - 'WINDIR': 'C:\\windows',
12:03:52 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:03:52 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:03:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:03:53 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:03:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
12:03:53 INFO - Cleaning up...
12:03:53 INFO - Return code: 0
12:03:53 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
12:03: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')]}
12:03:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:03: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')]}
12:03:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:03:53 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01790640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0183BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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
12:03:53 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build
12:03:53 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
12:03:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:03:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:03:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:03:53 INFO - 'COMPUTERNAME': 'T-W732-IX-149',
12:03:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:03:53 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:03:53 INFO - 'DCLOCATION': 'SCL3',
12:03:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:03:53 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:03:53 INFO - 'HOMEDRIVE': 'C:',
12:03:53 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:03:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:03:53 INFO - 'KTS_VERSION': '1.19c',
12:03:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:03:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149',
12:03:53 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:03:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:03:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:03:53 INFO - 'MOZ_NO_REMOTE': '1',
12:03:53 INFO - 'NO_EM_RESTART': '1',
12:03:53 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:03:53 INFO - 'OS': 'Windows_NT',
12:03:53 INFO - 'OURDRIVE': 'C:',
12:03:53 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:03:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:03:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:03:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:03:53 INFO - 'PROCESSOR_LEVEL': '6',
12:03:53 INFO - 'PROCESSOR_REVISION': '1e05',
12:03:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:03:53 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:03:53 INFO - 'PROMPT': '$P$G',
12:03:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:03:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:03:53 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:03:53 INFO - 'PWD': 'C:\\slave\\test',
12:03:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:03:53 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:03:53 INFO - 'SYSTEMDRIVE': 'C:',
12:03:53 INFO - 'SYSTEMROOT': 'C:\\windows',
12:03:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:03:53 INFO - 'TEST1': 'testie',
12:03:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:03:53 INFO - 'USERDOMAIN': 'T-W732-IX-149',
12:03:53 INFO - 'USERNAME': 'cltbld',
12:03:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:03:53 INFO - 'WINDIR': 'C:\\windows',
12:03:53 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:03:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:03:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:03:53 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:03:53 INFO - Downloading/unpacking blobuploader==1.2.4
12:03:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:03:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:03:54 INFO - Downloading blobuploader-1.2.4.tar.gz
12:03:54 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
12:03:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
12:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:03:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:03:54 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
12:03:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
12:03:55 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
12:03:55 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
12:03:55 INFO - Downloading docopt-0.6.1.tar.gz
12:03:55 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
12:03:55 INFO - Installing collected packages: blobuploader, requests, docopt
12:03:55 INFO - Running setup.py install for blobuploader
12:03:56 INFO - Running setup.py install for requests
12:03:56 INFO - Running setup.py install for docopt
12:03:56 INFO - Successfully installed blobuploader requests docopt
12:03:56 INFO - Cleaning up...
12:03:58 INFO - Return code: 0
12:03:58 INFO - Installing None into virtualenv C:\slave\test\build/venv
12:03:58 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')]}
12:03:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:03:58 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')]}
12:03:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:03:58 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 0x01790640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0183BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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
12:03:58 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
12:03:58 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
12:03:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:03:58 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:03:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:03:58 INFO - 'COMPUTERNAME': 'T-W732-IX-149',
12:03:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:03:58 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:03:58 INFO - 'DCLOCATION': 'SCL3',
12:03:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:03:58 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:03:58 INFO - 'HOMEDRIVE': 'C:',
12:03:58 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:03:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:03:58 INFO - 'KTS_VERSION': '1.19c',
12:03:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:03:58 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149',
12:03:58 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:03:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:03:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:03:58 INFO - 'MOZ_NO_REMOTE': '1',
12:03:58 INFO - 'NO_EM_RESTART': '1',
12:03:58 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:03:58 INFO - 'OS': 'Windows_NT',
12:03:58 INFO - 'OURDRIVE': 'C:',
12:03:58 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;',
12:03:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:03:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:03:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:03:58 INFO - 'PROCESSOR_LEVEL': '6',
12:03:58 INFO - 'PROCESSOR_REVISION': '1e05',
12:03:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:03:58 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:03:58 INFO - 'PROMPT': '$P$G',
12:03:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:03:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:03:58 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:03:58 INFO - 'PWD': 'C:\\slave\\test',
12:03:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:03:58 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:03:58 INFO - 'SYSTEMDRIVE': 'C:',
12:03:58 INFO - 'SYSTEMROOT': 'C:\\windows',
12:03:58 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:03:58 INFO - 'TEST1': 'testie',
12:03:58 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:03:58 INFO - 'USERDOMAIN': 'T-W732-IX-149',
12:03:58 INFO - 'USERNAME': 'cltbld',
12:03:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:03:58 INFO - 'WINDIR': 'C:\\windows',
12:03:58 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:03:58 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:03:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:03:58 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:03:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
12:03:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tqev26-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
12:03:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
12:03:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ctonce-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
12:03:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
12:03:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qduftr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
12:03:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
12:03:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cwc_t1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
12:03:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
12:03:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wjsjwy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
12:03:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
12:03:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i6somj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
12:03:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
12:03:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_jaqbw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
12:03:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
12:03:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ldktbq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
12:03:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
12:03:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hxodqv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
12:03:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
12:03:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qwfj60-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
12:03:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
12:03:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ztvyjc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
12:04:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
12:04:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-czwjwp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
12:04:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
12:04:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dik8s0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
12:04:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
12:04:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ln4vwl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
12:04:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
12:04:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kbhih_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
12:04:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
12:04:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5jg5xy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
12:04:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
12:04:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0qtb2b-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
12:04:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
12:04:01 INFO - Running setup.py install for manifestparser
12:04:01 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
12:04:01 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
12:04:02 INFO - Running setup.py install for mozcrash
12:04:02 INFO - Running setup.py install for mozdebug
12:04:02 INFO - Running setup.py install for mozdevice
12:04:03 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
12:04:03 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
12:04:03 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
12:04:03 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
12:04:03 INFO - Running setup.py install for mozfile
12:04:03 INFO - Running setup.py install for mozhttpd
12:04:04 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
12:04:04 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
12:04:04 INFO - Running setup.py install for mozinfo
12:04:04 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
12:04:04 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
12:04:04 INFO - Running setup.py install for mozInstall
12:04:04 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
12:04:04 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
12:04:04 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
12:04:04 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
12:04:04 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
12:04:04 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
12:04:04 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
12:04:04 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
12:04:04 INFO - Running setup.py install for mozleak
12:04:05 INFO - Running setup.py install for mozlog
12:04:05 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
12:04:05 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
12:04:06 INFO - Running setup.py install for moznetwork
12:04:06 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
12:04:06 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
12:04:06 INFO - Running setup.py install for mozprocess
12:04:07 INFO - Running setup.py install for mozprofile
12:04:07 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
12:04:07 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
12:04:07 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
12:04:07 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
12:04:07 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
12:04:07 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
12:04:07 INFO - Running setup.py install for mozrunner
12:04:07 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
12:04:07 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
12:04:08 INFO - Running setup.py install for mozscreenshot
12:04:08 INFO - Running setup.py install for moztest
12:04:09 INFO - Running setup.py install for mozversion
12:04:09 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
12:04:09 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
12:04:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
12:04:09 INFO - Cleaning up...
12:04:09 INFO - Return code: 0
12:04:09 INFO - Installing None into virtualenv C:\slave\test\build/venv
12:04:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:04:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:04:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:04:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:04:09 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 0x01790640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0183BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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
12:04:09 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
12:04:09 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
12:04:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:04:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:04:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:04:09 INFO - 'COMPUTERNAME': 'T-W732-IX-149',
12:04:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:04:09 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:04:09 INFO - 'DCLOCATION': 'SCL3',
12:04:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:04:09 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:04:09 INFO - 'HOMEDRIVE': 'C:',
12:04:09 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:04:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:04:09 INFO - 'KTS_VERSION': '1.19c',
12:04:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:04:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149',
12:04:09 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:04:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:04:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:04:09 INFO - 'MOZ_NO_REMOTE': '1',
12:04:09 INFO - 'NO_EM_RESTART': '1',
12:04:09 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:04:09 INFO - 'OS': 'Windows_NT',
12:04:09 INFO - 'OURDRIVE': 'C:',
12:04:09 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:04:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:04:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:04:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:04:09 INFO - 'PROCESSOR_LEVEL': '6',
12:04:09 INFO - 'PROCESSOR_REVISION': '1e05',
12:04:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:04:09 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:04:09 INFO - 'PROMPT': '$P$G',
12:04:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:04:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:04:09 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:04:09 INFO - 'PWD': 'C:\\slave\\test',
12:04:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:04:09 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:04:09 INFO - 'SYSTEMDRIVE': 'C:',
12:04:09 INFO - 'SYSTEMROOT': 'C:\\windows',
12:04:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:04:09 INFO - 'TEST1': 'testie',
12:04:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:04:09 INFO - 'USERDOMAIN': 'T-W732-IX-149',
12:04:09 INFO - 'USERNAME': 'cltbld',
12:04:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:04:09 INFO - 'WINDIR': 'C:\\windows',
12:04:09 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:04:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:04:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:04:09 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:04:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
12:04:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jebxwa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
12:04:10 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))
12:04:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
12:04:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-su4r0b-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
12:04:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
12:04:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
12:04:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gvbpuf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
12:04:10 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))
12:04:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
12:04:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xr7z4e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
12:04:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
12:04:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
12:04:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tgjwlj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
12:04:10 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))
12:04:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
12:04:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-odf3pz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
12:04:10 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))
12:04:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
12:04:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1beguy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
12:04:10 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))
12:04:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
12:04:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rsslua-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
12:04:11 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))
12:04:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
12:04:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pl8zat-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
12:04:11 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))
12:04:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
12:04:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-p8c5ol-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
12:04:11 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))
12:04:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
12:04:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-djuxms-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
12:04:11 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))
12:04:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
12:04:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dbmpbt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
12:04:11 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))
12:04:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
12:04:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-t1razq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
12:04:11 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))
12:04:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
12:04:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3ctxzz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
12:04:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14))
12:04:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
12:04:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zbttex-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
12:04:12 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))
12:04:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
12:04:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dl97z6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
12:04:12 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))
12:04:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
12:04:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zp8itn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
12:04:12 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))
12:04:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
12:04:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
12:04:12 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))
12:04:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
12:04:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
12:04:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
12:04:12 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
12:04:12 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
12:04:13 INFO - Downloading blessings-1.6.tar.gz
12:04:13 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
12:04:13 INFO - Installing collected packages: blessings
12:04:13 INFO - Running setup.py install for blessings
12:04:14 INFO - Successfully installed blessings
12:04:14 INFO - Cleaning up...
12:04:17 INFO - Return code: 0
12:04:17 INFO - Done creating virtualenv C:\slave\test\build/venv.
12:04:17 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
12:04:17 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
12:04:17 INFO - Reading from file tmpfile_stdout
12:04:17 INFO - Using _rmtree_windows ...
12:04:17 INFO - Using _rmtree_windows ...
12:04:17 INFO - Current package versions:
12:04:17 INFO - blessings == 1.6
12:04:17 INFO - blobuploader == 1.2.4
12:04:17 INFO - docopt == 0.6.1
12:04:17 INFO - functools32 == 3.2.3-2
12:04:17 INFO - jsonschema == 2.5.1
12:04:17 INFO - manifestparser == 1.1
12:04:17 INFO - mozInstall == 1.12
12:04:17 INFO - mozcrash == 0.17
12:04:17 INFO - mozdebug == 0.1
12:04:17 INFO - mozdevice == 0.48
12:04:17 INFO - mozfile == 1.2
12:04:17 INFO - mozhttpd == 0.7
12:04:17 INFO - mozinfo == 0.9
12:04:17 INFO - mozleak == 0.1
12:04:17 INFO - mozlog == 3.4
12:04:17 INFO - moznetwork == 0.27
12:04:17 INFO - mozprocess == 0.23
12:04:17 INFO - mozprofile == 0.28
12:04:17 INFO - mozrunner == 6.12
12:04:17 INFO - mozscreenshot == 0.1
12:04:17 INFO - mozsystemmonitor == 0.3
12:04:17 INFO - moztest == 0.7
12:04:17 INFO - mozversion == 1.4
12:04:17 INFO - psutil == 3.1.1
12:04:17 INFO - requests == 1.2.3
12:04:17 INFO - Installing None into virtualenv C:\slave\test\build/venv
12:04:17 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')]}
12:04:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:04:17 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')]}
12:04:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:04:17 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 0x01790640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0183BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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
12:04:17 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
12:04:17 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
12:04:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:04:17 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:04:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:04:17 INFO - 'COMPUTERNAME': 'T-W732-IX-149',
12:04:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:04:17 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:04:17 INFO - 'DCLOCATION': 'SCL3',
12:04:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:04:17 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:04:17 INFO - 'HOMEDRIVE': 'C:',
12:04:17 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:04:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:04:17 INFO - 'KTS_VERSION': '1.19c',
12:04:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:04:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149',
12:04:17 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:04:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:04:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:04:17 INFO - 'MOZ_NO_REMOTE': '1',
12:04:17 INFO - 'NO_EM_RESTART': '1',
12:04:17 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:04:17 INFO - 'OS': 'Windows_NT',
12:04:17 INFO - 'OURDRIVE': 'C:',
12:04:17 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;',
12:04:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:04:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:04:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:04:17 INFO - 'PROCESSOR_LEVEL': '6',
12:04:17 INFO - 'PROCESSOR_REVISION': '1e05',
12:04:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:04:17 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:04:17 INFO - 'PROMPT': '$P$G',
12:04:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:04:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:04:17 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:04:17 INFO - 'PWD': 'C:\\slave\\test',
12:04:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:04:17 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:04:17 INFO - 'SYSTEMDRIVE': 'C:',
12:04:17 INFO - 'SYSTEMROOT': 'C:\\windows',
12:04:17 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:04:17 INFO - 'TEST1': 'testie',
12:04:17 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:04:17 INFO - 'USERDOMAIN': 'T-W732-IX-149',
12:04:17 INFO - 'USERNAME': 'cltbld',
12:04:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:04:17 INFO - 'WINDIR': 'C:\\windows',
12:04:17 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:04:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:04:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:04:17 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:04:17 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))
12:04:17 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))
12:04:17 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))
12:04:17 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))
12:04:17 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))
12:04:17 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))
12:04:17 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))
12:04:17 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))
12:04:17 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))
12:04:17 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))
12:04:17 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))
12:04:17 INFO - Cleaning up...
12:04:17 INFO - Return code: 0
12:04:17 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
12:04:17 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')]}
12:04:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:04:17 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')]}
12:04:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:04:17 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 0x01790640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0183BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-149', 'USERDOMAIN': 'T-W732-IX-149', '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-149', '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
12:04:17 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
12:04:17 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
12:04:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:04:17 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:04:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:04:17 INFO - 'COMPUTERNAME': 'T-W732-IX-149',
12:04:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:04:17 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:04:17 INFO - 'DCLOCATION': 'SCL3',
12:04:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:04:17 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:04:17 INFO - 'HOMEDRIVE': 'C:',
12:04:17 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:04:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:04:17 INFO - 'KTS_VERSION': '1.19c',
12:04:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:04:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149',
12:04:17 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:04:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:04:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:04:17 INFO - 'MOZ_NO_REMOTE': '1',
12:04:17 INFO - 'NO_EM_RESTART': '1',
12:04:17 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:04:17 INFO - 'OS': 'Windows_NT',
12:04:17 INFO - 'OURDRIVE': 'C:',
12:04:17 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;',
12:04:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:04:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:04:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:04:17 INFO - 'PROCESSOR_LEVEL': '6',
12:04:17 INFO - 'PROCESSOR_REVISION': '1e05',
12:04:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:04:17 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:04:17 INFO - 'PROMPT': '$P$G',
12:04:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:04:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:04:17 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:04:17 INFO - 'PWD': 'C:\\slave\\test',
12:04:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:04:17 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:04:17 INFO - 'SYSTEMDRIVE': 'C:',
12:04:17 INFO - 'SYSTEMROOT': 'C:\\windows',
12:04:17 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:04:17 INFO - 'TEST1': 'testie',
12:04:17 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:04:17 INFO - 'USERDOMAIN': 'T-W732-IX-149',
12:04:17 INFO - 'USERNAME': 'cltbld',
12:04:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:04:17 INFO - 'WINDIR': 'C:\\windows',
12:04:17 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:04:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:04:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:04:18 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:04:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
12:04:18 INFO - Cleaning up...
12:04:18 INFO - Return code: 0
12:04:18 INFO - Running post-action listener: _resource_record_post_action
12:04:18 INFO - Running post-action listener: _start_resource_monitoring
12:04:18 INFO - Starting resource monitoring.
12:04:18 INFO - [mozharness: 2016-09-23 19:04:18.340000Z] Finished create-virtualenv step (success)
12:04:18 INFO - [mozharness: 2016-09-23 19:04:18.340000Z] Running install step.
12:04:18 INFO - Running pre-action listener: _resource_record_pre_action
12:04:18 INFO - Running main action method: install
12:04:18 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
12:04:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
12:04:18 INFO - Reading from file tmpfile_stdout
12:04:18 INFO - Using _rmtree_windows ...
12:04:18 INFO - Using _rmtree_windows ...
12:04:18 INFO - Detecting whether we're running mozinstall >=1.0...
12:04:18 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
12:04:18 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
12:04:18 INFO - Reading from file tmpfile_stdout
12:04:18 INFO - Output received:
12:04:18 INFO - Usage: mozinstall-script.py [options] installer
12:04:18 INFO - Options:
12:04:18 INFO - -h, --help show this help message and exit
12:04:18 INFO - -d DEST, --destination=DEST
12:04:18 INFO - Directory to install application into. [default:
12:04:18 INFO - "C:\slave\test"]
12:04:18 INFO - --app=APP Application being installed. [default: firefox]
12:04:18 INFO - Using _rmtree_windows ...
12:04:18 INFO - Using _rmtree_windows ...
12:04:18 INFO - mkdir: C:\slave\test\build\application
12:04:18 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
12:04:18 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application
12:04:23 INFO - Reading from file tmpfile_stdout
12:04:23 INFO - Output received:
12:04:23 INFO - C:\slave\test\build\application\firefox\firefox.exe
12:04:23 INFO - Using _rmtree_windows ...
12:04:23 INFO - Using _rmtree_windows ...
12:04:23 INFO - Running post-action listener: _resource_record_post_action
12:04:23 INFO - [mozharness: 2016-09-23 19:04:23.314000Z] Finished install step (success)
12:04:23 INFO - [mozharness: 2016-09-23 19:04:23.314000Z] Running run-tests step.
12:04:23 INFO - Running pre-action listener: _resource_record_pre_action
12:04:23 INFO - Running main action method: run_tests
12:04:23 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
12:04:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
12:04:23 INFO - Python 2.7.3
12:04:23 INFO - Return code: 0
12:04:23 INFO - grabbing minidump binary from tooltool
12:04:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:04:23 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF8520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
12:04:23 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build
12:04:23 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o
12:04:23 INFO - Calling ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] with output_timeout 600
12:04:23 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
12:04:23 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpdvj9yv
12:04:23 INFO - INFO - File integrity verified, renaming tmpdvj9yv to win32-minidump_stackwalk.exe
12:04:23 INFO - Return code: 0
12:04:23 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
12:04:23 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
12:04:23 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
12:04:23 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
12:04:23 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
12:04:23 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-149', '--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
12:04:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-149 --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
12:04:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:04:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:04:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:04:23 INFO - 'COMPUTERNAME': 'T-W732-IX-149',
12:04:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:04:23 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:04:23 INFO - 'DCLOCATION': 'SCL3',
12:04:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:04:23 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:04:23 INFO - 'HOMEDRIVE': 'C:',
12:04:23 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:04:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:04:23 INFO - 'KTS_VERSION': '1.19c',
12:04:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:04:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-149',
12:04:23 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
12:04:23 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
12:04:23 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:04:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:04:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:04:23 INFO - 'MOZ_NO_REMOTE': '1',
12:04:23 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
12:04:23 INFO - 'NO_EM_RESTART': '1',
12:04:23 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:04:23 INFO - 'OS': 'Windows_NT',
12:04:23 INFO - 'OURDRIVE': 'C:',
12:04:23 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:04:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:04:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:04:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:04:23 INFO - 'PROCESSOR_LEVEL': '6',
12:04:23 INFO - 'PROCESSOR_REVISION': '1e05',
12:04:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:04:23 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:04:23 INFO - 'PROMPT': '$P$G',
12:04:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:04:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:04:23 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:04:23 INFO - 'PWD': 'C:\\slave\\test',
12:04:23 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
12:04:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:04:23 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:04:23 INFO - 'SYSTEMDRIVE': 'C:',
12:04:23 INFO - 'SYSTEMROOT': 'C:\\windows',
12:04:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:04:23 INFO - 'TEST1': 'testie',
12:04:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:04:23 INFO - 'USERDOMAIN': 'T-W732-IX-149',
12:04:23 INFO - 'USERNAME': 'cltbld',
12:04:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:04:23 INFO - 'WINDIR': 'C:\\windows',
12:04:23 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:04:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:04:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:04:23 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-149', '--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
12:04:25 INFO - mozversion application_buildid: 20160923101725
12:04:25 INFO - mozversion application_changeset: 76fd7dbb20d5387fe43dd30337b12f8ccb0bd30c
12:04:25 INFO - mozversion application_display_name: Nightly
12:04:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
12:04:25 INFO - mozversion application_name: Firefox
12:04:25 INFO - mozversion application_remotingname: firefox
12:04:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
12:04:25 INFO - mozversion application_vendor: Mozilla
12:04:25 INFO - mozversion application_version: 52.0a1
12:04:25 INFO - mozversion platform_buildid: 20160923101725
12:04:25 INFO - mozversion platform_changeset: 76fd7dbb20d5387fe43dd30337b12f8ccb0bd30c
12:04:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
12:04:25 INFO - mozversion platform_version: 52.0a1
12:04:25 INFO - using testdate: 1474657465
12:04:25 INFO - actual date: 1474657465
12:04:25 INFO - starting webserver on 'localhost:49650'
12:04:25 INFO - SUITE-START | Running 2 tests
12:04:25 INFO - TEST-START | dromaeo_css
12:04:25 INFO - operating with platform_type : w7_
12:04:25 INFO - Initialising browser for dromaeo_css test...
12:04:25 INFO - TEST-INFO | started process 3544 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpzucrbd\profile http://localhost:49650/getInfo.html)
12:04:33 INFO - PROCESS | 3544 | __metrics Screen width/height:1600/1200
12:04:33 INFO - PROCESS | 3544 | colorDepth:24
12:04:33 INFO - PROCESS | 3544 | Browser inner width/height: 1010/674
12:04:33 INFO - PROCESS | 3544 | __metrics
12:04:34 INFO - PROCESS | 3544 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
12:04:34 INFO - PROCESS | 3544 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
12:04:35 INFO - TEST-INFO | 3544: exit 0
12:04:35 INFO - Browser initialized.
12:04:35 INFO - Running cycle 1/1 for dromaeo_css test...
12:04:35 INFO - TEST-INFO | started process 752 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpzucrbd\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
12:04:36 INFO - PROCESS | 752 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
12:04:47 INFO - PROCESS | 752 | RSS: Main: 139227136
12:04:47 INFO - PROCESS | 752 |
12:05:38 INFO - PROCESS | 752 | 1474657538906 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]
12:07:52 INFO - PROCESS | 752 | Cycle 1(1): loaded http://localhost:49650/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49650/tests/dromaeo/cssquery-ext.html)
12:07:53 INFO - PROCESS | 752 | RSS: Main: 171675648
12:07:53 INFO - PROCESS | 752 |
12:10:56 INFO - PROCESS | 752 | Cycle 1(1): loaded http://localhost:49650/tests/dromaeo/cssquery-ext.html (next: http://localhost:49650/tests/dromaeo/cssquery-jquery.html)
12:10:56 INFO - PROCESS | 752 | RSS: Main: 205160448
12:10:56 INFO - PROCESS | 752 |
12:13:59 INFO - PROCESS | 752 | Cycle 1(1): loaded http://localhost:49650/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49650/tests/dromaeo/cssquery-mootools.html)
12:13:59 INFO - PROCESS | 752 | RSS: Main: 152518656
12:13:59 INFO - PROCESS | 752 |
12:17:02 INFO - PROCESS | 752 | Cycle 1(1): loaded http://localhost:49650/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49650/tests/dromaeo/cssquery-prototype.html)
12:17:02 INFO - PROCESS | 752 | RSS: Main: 144343040
12:17:02 INFO - PROCESS | 752 |
12:20:05 INFO - PROCESS | 752 | Cycle 1(1): loaded http://localhost:49650/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49650/tests/dromaeo/cssquery-yui.html)
12:20:06 INFO - PROCESS | 752 | RSS: Main: 140292096
12:20:06 INFO - PROCESS | 752 |
12:23:09 INFO - PROCESS | 752 | Cycle 1(1): loaded http://localhost:49650/tests/dromaeo/cssquery-yui.html (next: http://localhost:49650/tests/dromaeo/cssquery-dojo.html)
12:23:09 INFO - PROCESS | 752 | RSS: Main: 188555264
12:23:09 INFO - PROCESS | 752 |
12:23:09 INFO - PROCESS | 752 | __start_tp_report
12:23:09 INFO - PROCESS | 752 | _x_x_mozilla_page_load
12:23:09 INFO - PROCESS | 752 | _x_x_mozilla_page_load_details
12:23:09 INFO - PROCESS | 752 | |i|pagename|runs|
12:23:09 INFO - PROCESS | 752 | |0;dojo.html;2341.461463863311;2355.1874603261867;2381.5356005578915;2397.436602398435;2404.3989002749304;4063.817128229229;4064.796760161995;4166.812493437795;4168.958310416895;4181.853635122771;3980.6815454763623;3991.7405368651016;4080.816363263653;4137.089840235151;4187.811548480318;3584.086058055192;3600.8739694110645;3630.3102410542047;3643.544556930384;3680.7975561344115;3603.045193023851;3610.4584312353095;3634.5275114235114;3642.963570364289;3668.4864119023223;3595.083253770298;3618.1678214010794;3646.6535679110443;3664.835082421284;3693.630636936312;3439.8452069656923;3566.572011358641;3612.584552776438;3664.285464334456;3681.355762741536;3555.6266592007723;3631.1648320886343;3642.817859107034;3645.0887278180453;3658.2866341063504;4033.657139143176;4047.6761859051207;4070.982254436391;4084.7549147051272;4098.4876890388705;4177.665786737054;4286.164197981395;4383.057642606823;4393.297072468422;4403.383526306319;4237.957620423787;4256.808443620044;4275.46556680415;4279.572042795696;4287.485501739811;4023.5574086850424;4058.3912413138264;4076.490438695163;4091.672666186698;4105.85629502966;4374.58441448062;4382.824687012548;4452.087322099027;4461.57615026572;4471.262241730143;4123.958760412417;4156.9792151039055;4171.0615111600255;4200.5169405518145;4293.3130699088;4202.075543380452;4231.830726770895;4292.5063617683745;4305.978470107692;4310;4182.1844740275965;4210.6841986850495;4282.464691913511;4291.721038132511;4294.592013758688;4091.5499295077516;4180.832766689298;4202.768847713344;4244.596763307481;4284.485861696617;2960.097170363017;2988.7310142087317;2989.0285657161508;3008.578798968146;3009.1724775686425;2740.260129764952;2795.7763378929635;2803.41128363042;2809.9578506322423;2810.437912417525;4045.5752146024242;4149.190907773016;4154.335306350969;4156.168766246763;4157.417961485395;4263.936040959388;4279.593438623326;4314.072474418015;4316.028893498938;4331.870043898688;4052.169305292467;4063.878083657495;4070.1249231414795;4141.337386018223;4145.751254924714;3681.2269423420844;3716.6655001050035;3743.3074881147077;3745.194783121642;3746.157114649182;4314.180305741899;4322.78386080701;4440.067585806887;4466.0844526871915;4514.751688657156;4297.72064815786;4301.397804307462;4317.568243175783;4350.73895566267;4410.514843367291;4275.144971005749;4384.824607015684;4407.272799987966;4447.7998490067375;4458.777061146994;4109.630133288018;4255.063885945087;4319.416878721332;4327.870163895088;4335.609795118455;4233.78831058452;4242.215190189825;4281.678874084394;4283.657307415461;4315.741055536678;4085.2646523624844;4147.108371700099;4160.583941605815;4209.852655157055;4228.090960443519;4165.875023749292;4253.659707223353;4261.126469073771;4262.275413179705;4265.253580623441;3956.8615098471414;4001.699872509632;4002.1795531915304;4047.433359329755;4083.3058380076054;4138.337865941435;4276.828926842892;4278.465191851019;4278.572142785696;4282.357646353072;3952.110775075585;3987.720859539804;4077.7553346799286;4144.295469770206;4166.895827604334
12:23:09 INFO - PROCESS | 752 | |1;ext.html;526.5682140644672;531.752734978235;537.6612733977595;552.439274136751;557.9916301255485;26221.737782622167;36137.457938130916;37379.81310093452;38909.22181556367;40715.796421017854;37811.621883781154;38183.04542386435;38269.80865095677;38277.23445531088;38300;6337.081123237128;7674.616269186547;7839.412044096687;7856.803579910491;7861.3317867981;6463.806085817433;6540.869182616345;6551.868962620769;6661.9000714989315;9587;9549.331546791727;9614.375065620747;9711;9718.173955213815;9719.61121555137;10048.944860789657;10071.143952764023;10140.442275674835;10164.745881353025;10173.491325433699;6407.295197528269;6519.674016299166;9577.760555986086;9777.266704997153;9926.354786938899;30433.69566304331;37876.81061594702;38364.23271534582;38379.616203838166;38479.80760096209;2586.2370600672657;2633.644457998184;2667.373167305682;2694.259078753339;2777.777777777773;26519.602205966927;27146.592801108;27325.86337068322;27343.72656273432;27718;3300.9174770630684;3301.2572171261513;3326.866925322984;3344.8494817733263;3411.403004474207;201274.9936250324;211076;212273;214261;214424;39383;40556.18887622231;40761.79619101885;44047;47215.291770623466;43712;47772.761136194094;49134.26298605524;49212.75393623009;54280.72859635676;10524.789504209873;10572.418516981486;10598.523066462029;10701.250912436055;10924.563017479371;7940.444168908121;7979.441439099207;8053.557054361949;8087.716929907425;8109.189081091844;13182.802257966137;13511.121777084654;14920.40318387274;15328.23358832054;15406.614834629225;5893.381194974551;5948.821535353946;5982.893164764446;6030.005049166926;6040.275166979991;777.19172061057;937.6952490440498;942.6747772018642;948.1940350701848;949.9787728192221;25352.11267605625;25726.48547029049;25742;25982.22053338403;26093.608595871076;861.2205953611991;871.6426265231224;875.1073904617255;899.3120262998812;953.609020301673;13655.86344136546;13717.588472345846;13959.441622334993;14015.929920350332;14033.649158771112;309447;313608;314673;316701;317393;38414.039649009;39314.60685393167;42085;47516.049679006224;48622;1051.9579216831241;1060.9045185933148;1076.1928553584812;1079.0881704959297;1081.4971038466992;25578.360540986625;26131.73868261369;26383.076592319176;26671.33321666974;26713.599296010965;36476.27047459089;39378.21243575055;39476.40785388279;39799.2040159201;39803.40294895637;290741;292206;292380;292849;294384;31184.376312474076;33146.83426582852;33504.664953350155;33741.83129084339;33884.32231355408;37482.625173749;38076.428853566664;38216.61783382131;38850.4172437408;39079;30893;32468.837655812516;32996.34007319792;33683;34439.31121377508;32297.192570185933;32581.18547036343;33014.66985330116;33160.834195828866;33867.830660846535;31615.525767113973;32073;32175.678243218204;32312.515312269865;32342.676573233966;28589.857050714614;28646.42707145887;29496;29630.407391852474;30622.693773061983;22416.551668966855;22791.430214244778;22886.282814560567;23417.41456463602;23515.41211469727
12:23:09 INFO - PROCESS | 752 | |2;jquery.html;289.249397812676;312.4001916320664;319.47924882441623;329.6538634433844;332.5327914280437;11773.411329433526;15738.606534836636;15900.840991590081;16133.919330403332;17340.913295433504;15192.392304307814;15300.846991530081;15493.225338733046;15658.373665053412;15797.60505987351;4790.784414701329;4880.87797805055;5139.25480805283;5139.331886854704;5140.997505486432;3567.161716996504;3699.3526132926763;3925.2542017016676;4991.800327986876;5061.595072394218;5636.0982242841155;5780.1907732917425;5822.446867547576;5885.293764748236;5904.7047647617665;4753.857384278477;5650.830475085754;5868.677222752747;5876.11858221266;5879.911801322962;4746.572808447222;4841.8063277468855;4931.65478416511;4954.975225123887;4956.058348913731;10517.684469465928;10679.35923844564;10723.678289651323;10874.891251087545;10912.236143469881;1567.8902476826627;1637.7052130616603;1653.2395098254817;1679.6808606364752;1683.3771504543397;7779.805504862367;8049.154838741906;8065.112837587861;10687.251892367538;13182.868171318356;1560.672258825648;1633.575270429685;1648.7856578644698;1673.983260167395;1716.9914150429292;65048.67475662639;65083;66305;67864.3213567863;68004.65997670029;8852.911470885338;8918.331125165509;9173.862392064124;9237.261019118454;9280.164785169367;10237.48812559369;10921.945390272998;10924.836127458095;11796.705082372837;11834.467448964817;5914.556408269397;5976.013957696931;6046;6088.178095957078;6094;5461.644993075437;5583.664980101207;5605.719714014364;5639.464250896158;5794.8551286217335;3752.981235093807;3858.7105967052585;3868.767873927517;3894.7273690841935;3916.823742931575;1576.3852097681913;1604.7592861070932;1638.8863951481628;1664;1664.9583760406088;770.3298130626279;785.8114052627328;826.7478419082117;833.9207775261341;901.6889173235223;16363.918180409022;16366.754498682529;16574.585635359213;16807.83192168087;16974.660506789794;777.782220978127;798.2576204130214;817.5789468423648;828.9171082891662;916.8258030974093;9052.81894362109;9301.116393942564;9417.246620270362;9475.76310592227;9546.904530954602;268797;277192;278825;280216;287947;70788;71605;74497.25502744903;74617.6269118651;74720.62639686876;782.2021538031177;792.1998781230998;806.9919300807035;809.5102463009912;810.606855675009;8267.793305167419;8788.516631585324;8852.822943540965;9285.257179425495;14560.635984100058;35531.644683553866;38556.228875422894;38905.80547097247;39476;40616.39075413812;45443.9561867911;48623.75688121537;51924.480755191966;52325;52451;9848.507574621097;10028.142728802462;10042;10063.949680251551;10064.647737329158;11140.832887506856;11297.322160670386;11971.521139154336;11991.64025079249;12035.638930832089;9330.253579713743;9529.428234305686;9593.664221752206;9802.215822734022;9996.800063998533;8813.91186088131;9436.245100391814;9468.053194680477;9852.901470985198;9907.554160062959;9742.171915387271;9941;10197.13324367437;10203.38779673222;10205.84691229647;9756.26827987918;10178.185745140223;10299.742506437398;10433.530491127765;10566.471676415995;9551.570179342085;9563.330566860253;9637.469939153414;9866.358686685342;9973.501324933579
12:23:09 INFO - PROCESS | 752 | |3;mootools.html;1095.5508241620937;1223.290491514921;1236.2953116723465;1265.405259528022;1534.6700459401234;5448.455154484554;6312.558120931537;6583.111279977202;6647.867042659144;6653;6482.870342593145;6507.804765857032;6568.507361947849;6572.441342485882;6584.637844918528;1819.0995457248648;1826.041328302645;1844.6402951424477;1847.8891266523985;1851.8518518518554;1812.9365472208476;1852.6943054396077;1861.2182883189025;1876.4183103237972;1879.6146789908105;1735.2885317020025;1743.7384392341123;1767.4079183473486;1768.2131451504085;1769.2480695704273;1748.3094177799762;1757.8857374270694;1774.1572752942377;1781.15395187285;1782.2069179215255;1754.6666133434735;1783.0728021428913;1788.6869797785334;1789.03392168229;1789.7852257729025;6107.4197951194565;6344.873102537924;6436.678166091677;6473.029045643144;6486.481081513515;3651.1967367179177;3686.299603075407;3807.8286477108595;3813.7139714521245;3814.8474061037527;2446.963295550568;2449.3998970252223;2499.250224932513;2499.4251322195996;2499.7750202481684;723.236984980845;729.905112335393;755.9206283340225;756.6493808551709;757.1179575794193;7792.9220707792765;8075.798105047362;8121.512709237465;8128.837423251561;8151.470154440001;3265.5264986576813;3276.541284220211;3331.700146986783;3337.9986004198645;3347.727863411888;6538.476921846241;6550.541462097703;6569.737210511622;6577.309382514863;6584.86830263392;3595.1371670799344;3642.2715456908722;3673.7061035117126;3713.9071523212137;3745.101175717808;2929.516629756109;3012.990648132856;3401.3197360527497;3499.3876071687355;3514.8594056237466;2709.187243826844;2830.3490197254746;2830.830150190995;2854;2864.427114577093;858.9742307730778;878.3500209844797;880.6389380354024;882.5895958379384;884.9557522123868;615.0558892100604;715.4955756191872;755.9773206803804;756.1531085184629;757.9848403031908;4010.21800748857;4191.266528357586;4197.769122698281;4249.086446414036;4498.460184777782;739.6819367671952;763.6716212028881;780.1223623423648;827.1067247372823;835.1314632781961;3096.002855103494;3147.1502694272417;3147.3547922676253;3190.441672707267;3239.060672405023;7331.963340183265;7397.297256760599;7406.666699998513;7721;7752.224777522316;7219;7538.509996850187;7544.735934242275;7668.233176682287;7685.538867667958;5870.14882841994;5904;5941.524678025748;6008.729607167688;6021.337652858182;4338.370936214168;4359.869203923888;4567.977160114178;4600.700954437951;4619.792109355154;6768.187817461936;6777.864442711019;6877.862442751217;6992.2308546058875;7064.823379415556;7088.893666595109;7237.421006319377;7264.927350726425;7299.306565876233;7322.450816188701;5371.167469042305;5426.67439953604;5527.474889885454;5537.446255374431;5546.168074788814;5309.522143007148;5493.615446918678;5728.4271572844045;6071.878562428815;6122.204113465221;5177.145770947752;5422.5119739223655;5518.089515230103;5564.916526252028;5568.303962004749;5332.813351532678;5335.973320133375;5450.018996580654;5521.668699878021;5584.134459158837;5146.459621739664;5215;5291.576673866159;5337.706426146444;5344.75948582307;3714.312852122366;3835.4055121456217;3982.4026396039667;4045.5954404559307;4055.6349928506575;3727.4595183698752;3867.8452861885207;3913.5695073542456;3956.5449973253444;3995.380715989026
12:23:09 INFO - PROCESS | 752 | |4;prototype.html;240.85067258299856;242.21280837278843;247.93511866533237;248.7537338035345;254.5837555596599;4436.6228870546;4438.624026551767;4479.820807167717;4504.234280172366;4519.819207231707;4109.013836679198;4348.21732088224;4352.325389564615;4356.934645980313;4474.619657329131;3177.4916013437855;3202.9839850800713;3234.9191270218257;3328.134684981898;3328.8335583220864;3313.0392186265954;3322.2857085726564;3332.483465062919;3333.116724068124;3334.71654909333;3221.6134063912364;3292.341531693659;3293.917652058694;3305.3224089061687;3312.4865645824934;3124.187711195083;3149.9055028349185;3167.113208301679;3246.2371342734436;3262.331222099464;2831.7309855563894;2833.3908209735005;2834.376437183816;2834.5323021701392;2836.5745138229227;4309.4397728295435;4317.085966972281;4323.589259020388;4326.6538676905775;4330.242207613686;3804.0489877530617;3813.8283777229813;3816.3512202925567;3819.52255968004;3827.7129215308964;4322.783860806947;4536.54634536547;4546.931796023062;4578.9542104579195;4581.152486789954;3081.7380522655603;3113.1283240692414;3115.018769087753;3116.6415862175686;3122.8282444465544;4751.216049351853;4775.188218002948;4779.091972525209;4789.185838407479;4800.423949126092;3187.7768556200845;3198.760092992988;3205.535197396411;3211.6627754085484;3214.180384002111;3240.8541615627355;3241.967580324214;3255.072304393284;3255.7139929727623;3261;3770.7360484765804;3779.4897688812207;3782.224643947984;3785.432185172246;3790.6019867913474;4469.346341853535;4584.908301833944;4604.838830640979;4627.560381763795;4629.8148074077335;1115.503600897609;1124.966251012471;1128.4752590045669;1132.9773404532027;1134;690.4441924250925;710.9146902371748;718.8490417012486;722.1550785580883;724.7644515532368;4259.893502662396;4350.369196466558;4381.6932814703305;4384.320430333303;4392.758398288125;4638.6752927294765;4694.0611877624615;4709.576138147583;4792.586187074751;4810.903781924342;4381.496127945264;4384.758838263862;4408.699433667076;4412.183746006999;4446.288593824973;2508.761667641571;2524.4320027993554;2526.772590466867;2532.2656429635567;2538.314655043128;4656.534346565317;4867.440244371943;4897.461279259279;4908.312836202935;4957.3059771632;4833.782479788418;4873.707577545359;4911.557959783565;4920.163572192736;4924.236743304794;4842.748122123159;4908.950910490849;4915.95084049155;4919.540229885129;4940.283658869515;4539.863804085882;4785.545373189541;4788.186008378653;4807.302941073455;4818.68678535894;4703.082898834831;4705.952940470688;4717.433907930933;4726.338312636165;4739.4075740532435;4909.239067944474;4910.312556242057;4916.975415122902;4918.2622606607865;4925.704457732548;2892.4070565534435;3177.936441271208;3196.5365022071205;3199.2961548459302;3208.3904058228864;4730.6925049873025;4776.471529110651;4786;4786.832460863853;4789.066132104345;4143.197708996596;4553.086024943972;4596.747178905059;4600.769961501978;4624.075184962953;4492.326151077365;4592.104539614743;4593;4597.66667666373;4611.792469338955;4472.731636101714;4487.573680500347;4489.685721999429;4495.662825288051;4499.910001800011;4621.584057434847;4653.208954477815;4656.231721765871;4666.556677115668;4672.32251323563;4334.566543345641;4506.715586058089;4515.9548404515535;4518.841840535565;4521.66087543429
12:23:09 INFO - PROCESS | 752 | |5;yui.html;569.7834822767347;591.553377200214;614.382545541731;630.4231628060325;655.862268923526;12683.873161268384;13661.65845853854;13823.23972181531;13840.723185536282;13847.861521384782;12448.81326780099;12736.681582960431;12841.678958026054;12876;12877.613671589866;515.9871003224931;651.1372431528232;666.9333066693316;667.2060248304526;668.6489592963684;521.6687403498787;524.4964833759576;529.7377797989981;529.8940211957605;643.2955913274957;691.2776148924386;694.2675477371371;697.8185671725363;701.2146396036446;705.3052743048119;672.3411057164006;690.6995456976232;693.0609024771412;696.7526528082508;701.2916953876596;393.7125898094379;400.6334204203151;508.4280184792109;509.02013623774087;512.0501469773553;11770.529178832836;12440.19138755978;12472.314022728746;12651.177673451197;13009.414576344087;12610.117291789578;12614.936925315315;12913.418896149602;13032;13142.86857131426;9813;10146.188304935587;10184.185265178769;10224.182065434747;10449.947750261275;3115.283484798509;3187.585613870182;3200.735709394776;3208.2941752814577;3303.5540202072652;62627.06059409113;62842.37157628411;63676;64132.3586764131;65349.01976470404;9934;9975.650852220282;10040.598376065022;10054.497275136215;10224.13094886928;18570.44288671342;18571.350002749838;18575.535611609546;18590.25638974426;18607.720884186747;6675.132232809704;6721.7311307548125;6830.077939478207;6853.828654283683;6927.411170050501;3642.579394036328;3702.5371828521434;3731.197792474631;3740.94388584166;3742.419924911643;2413.1071503543803;2553.70632377279;2555.5016771729706;2559.987200063988;2572.1769033908968;415.147431832594;417.05953075813846;417.42603919610536;421.9219444402795;423.71822737878966;517.7230181852691;565.7708628005664;571.8799052199001;594.1236675903009;619.755196697311;7909.918125408873;7983.72056978003;8113.107558168596;8119.594020298961;8489.83020339602;430.1740657936769;532.3079996005255;554.877926856091;568.0376834806422;578.9565782566324;3591.7731006775443;3849.191669749328;3948.763074215556;3955.4066889966734;4032.4757781488806;93828.53085734528;94652.52673736725;95997.52001240088;96122;96533.51733241288;62556.68721656453;81072.59463702644;83904.16095838964;84840.15159848446;85593.14406855976;65054.674726626065;66444.66777666178;67105.66447167733;67272.66363668151;70009.64995174992;7877;8105.148959359182;8151.470154439942;8169.223923727236;8218.465799722999;40029.999999998836;40871;40990.79504602458;42309;42639;57521.71239143777;57671.71164144152;58896.41103588909;60997.39002609917;61169.69415153074;10316.381017138996;10778.191635627516;10951.45242737876;11050.778984420427;11216.943915280697;12656;12972.610821675366;13007.154534955198;13143.671408214872;13600.65998350049;10966.451677416257;11405.543778248777;11634.825477617673;11677.591284305008;11731.530738770354;12055.397230138633;12114.575989840314;12117.394130293625;12315;12574.93712531468;10528.56178793999;10823.134149268197;11019;11195.160362972647;11203;9852.719146510908;10031.574825396703;10246.38521688701;10351.223658225512;10950.616728414467;9679.467629280458;9703.951480242553;9842.60629574809;10370.844437333597;10564.418956957134
12:23:09 INFO - PROCESS | 752 | __end_tp_report
12:23:09 INFO - PROCESS | 752 | __start_cc_report
12:23:09 INFO - PROCESS | 752 | _x_x_mozilla_cycle_collect,825
12:23:09 INFO - PROCESS | 752 | __end_cc_report
12:23:09 INFO - PROCESS | 752 | __startTimestamp1474658589715__endTimestamp
12:23:09 INFO - PROCESS | 752 |
12:23:09 INFO - PROCESS | 752 | ------- Summary: start -------
12:23:09 INFO - PROCESS | 752 | Number of tests: 6
12:23:09 INFO - PROCESS | 752 |
12:23:09 INFO - PROCESS | 752 | [#0] dojo.html Cycles:165 Average:3977.01 Median:4149.19 stddev:475.83 (11.5%) stddev-sans-first:459.67
12:23:09 INFO - PROCESS | 752 | Values: 2341.5 2355.2 2381.5 2397.4 2404.4 4063.8 4064.8 4166.8 4169.0 4181.9 3980.7 3991.7 4080.8 4137.1 4187.8 3584.1 3600.9 3630.3 3643.5 3680.8 3603.0 3610.5 3634.5 3643.0 3668.5 3595.1 3618.2 3646.7 3664.8 3693.6 3439.8 3566.6 3612.6 3664.3 3681.4 3555.6 3631.2 3642.8 3645.1 3658.3 4033.7 4047.7 4071.0 4084.8 4098.5 4177.7 4286.2 4383.1 4393.3 4403.4 4238.0 4256.8 4275.5 4279.6 4287.5 4023.6 4058.4 4076.5 4091.7 4105.9 4374.6 4382.8 4452.1 4461.6 4471.3 4124.0 4157.0 4171.1 4200.5 4293.3 4202.1 4231.8 4292.5 4306.0 4310.0 4182.2 4210.7 4282.5 4291.7 4294.6 4091.5 4180.8 4202.8 4244.6 4284.5 2960.1 2988.7 2989.0 3008.6 3009.2 2740.3 2795.8 2803.4 2810.0 2810.4 4045.6 4149.2 4154.3 4156.2 4157.4 4263.9 4279.6 4314.1 4316.0 4331.9 4052.2 4063.9 4070.1 4141.3 4145.8 3681.2 3716.7 3743.3 3745.2 3746.2 4314.2 4322.8 4440.1 4466.1 4514.8 4297.7 4301.4 4317.6 4350.7 4410.5 4275.1 4384.8 4407.3 4447.8 4458.8 4109.6 4255.1 4319.4 4327.9 4335.6 4233.8 4242.2 4281.7 4283.7 4315.7 4085.3 4147.1 4160.6 4209.9 4228.1 4165.9 4253.7 4261.1 4262.3 4265.3 3956.9 4001.7 4002.2 4047.4 4083.3 4138.3 4276.8 4278.5 4278.6 4282.4 3952.1 3987.7 4077.8 4144.3 4166.9
12:23:09 INFO - PROCESS | 752 |
12:23:09 INFO - PROCESS | 752 | [#1] ext.html Cycles:180 Average:41892.51 Median:33593.83 stddev:72395.01 (215.5%) stddev-sans-first:72531.47
12:23:09 INFO - PROCESS | 752 | Values: 526.6 531.8 537.7 552.4 558.0 26221.7 36137.5 37379.8 38909.2 40715.8 37811.6 38183.0 38269.8 38277.2 38300.0 6337.1 7674.6 7839.4 7856.8 7861.3 6463.8 6540.9 6551.9 6661.9 9587.0 9549.3 9614.4 9711.0 9718.2 9719.6 10048.9 10071.1 10140.4 10164.7 10173.5 6407.3 6519.7 9577.8 9777.3 9926.4 30433.7 37876.8 38364.2 38379.6 38479.8 2586.2 2633.6 2667.4 2694.3 2777.8 26519.6 27146.6 27325.9 27343.7 27718.0 3300.9 3301.3 3326.9 3344.8 3411.4 201275.0 211076.0 212273.0 214261.0 214424.0 39383.0 40556.2 40761.8 44047.0 47215.3 43712.0 47772.8 49134.3 49212.8 54280.7 10524.8 10572.4 10598.5 10701.3 10924.6 7940.4 7979.4 8053.6 8087.7 8109.2 13182.8 13511.1 14920.4 15328.2 15406.6 5893.4 5948.8 5982.9 6030.0 6040.3 777.2 937.7 942.7 948.2 950.0 25352.1 25726.5 25742.0 25982.2 26093.6 861.2 871.6 875.1 899.3 953.6 13655.9 13717.6 13959.4 14015.9 14033.6 309447.0 313608.0 314673.0 316701.0 317393.0 38414.0 39314.6 42085.0 47516.0 48622.0 1052.0 1060.9 1076.2 1079.1 1081.5 25578.4 26131.7 26383.1 26671.3 26713.6 36476.3 39378.2 39476.4 39799.2 39803.4 290741.0 292206.0 292380.0 292849.0 294384.0 31184.4 33146.8 33504.7 33741.8 33884.3 37482.6 38076.4 38216.6 38850.4 39079.0 30893.0 32468.8 32996.3 33683.0 34439.3 32297.2 32581.2 33014.7 33160.8 33867.8 31615.5 32073.0 32175.7 32312.5 32342.7 28589.9 28646.4 29496.0 29630.4 30622.7 22416.6 22791.4 22886.3 23417.4 23515.4
12:23:09 INFO - PROCESS | 752 |
12:23:09 INFO - PROCESS | 752 | [#2] jquery.html Cycles:180 Average:20389.35 Median:4955.52 stddev:46917.28 (946.8%) stddev-sans-first:47024.62
12:23:09 INFO - PROCESS | 752 | Values: 289.2 312.4 319.5 329.7 332.5 11773.4 15738.6 15900.8 16133.9 17340.9 15192.4 15300.8 15493.2 15658.4 15797.6 4790.8 4880.9 5139.3 5139.3 5141.0 3567.2 3699.4 3925.3 4991.8 5061.6 5636.1 5780.2 5822.4 5885.3 5904.7 4753.9 5650.8 5868.7 5876.1 5879.9 4746.6 4841.8 4931.7 4955.0 4956.1 10517.7 10679.4 10723.7 10874.9 10912.2 1567.9 1637.7 1653.2 1679.7 1683.4 7779.8 8049.2 8065.1 10687.3 13182.9 1560.7 1633.6 1648.8 1674.0 1717.0 65048.7 65083.0 66305.0 67864.3 68004.7 8852.9 8918.3 9173.9 9237.3 9280.2 10237.5 10921.9 10924.8 11796.7 11834.5 5914.6 5976.0 6046.0 6088.2 6094.0 5461.6 5583.7 5605.7 5639.5 5794.9 3753.0 3858.7 3868.8 3894.7 3916.8 1576.4 1604.8 1638.9 1664.0 1665.0 770.3 785.8 826.7 833.9 901.7 16363.9 16366.8 16574.6 16807.8 16974.7 777.8 798.3 817.6 828.9 916.8 9052.8 9301.1 9417.2 9475.8 9546.9 268797.0 277192.0 278825.0 280216.0 287947.0 70788.0 71605.0 74497.3 74617.6 74720.6 782.2 792.2 807.0 809.5 810.6 8267.8 8788.5 8852.8 9285.3 14560.6 35531.6 38556.2 38905.8 39476.0 40616.4 45444.0 48623.8 51924.5 52325.0 52451.0 9848.5 10028.1 10042.0 10063.9 10064.6 11140.8 11297.3 11971.5 11991.6 12035.6 9330.3 9529.4 9593.7 9802.2 9996.8 8813.9 9436.2 9468.1 9852.9 9907.6 9742.2 9941.0 10197.1 10203.4 10205.8 9756.3 10178.2 10299.7 10433.5 10566.5 9551.6 9563.3 9637.5 9866.4 9973.5
12:23:09 INFO - PROCESS | 752 |
12:23:09 INFO - PROCESS | 752 | [#3] mootools.html Cycles:180 Average:4133.67 Median:5334.39 stddev:2230.42 (41.8%) stddev-sans-first:2224.99
12:23:09 INFO - PROCESS | 752 | Values: 1095.6 1223.3 1236.3 1265.4 1534.7 5448.5 6312.6 6583.1 6647.9 6653.0 6482.9 6507.8 6568.5 6572.4 6584.6 1819.1 1826.0 1844.6 1847.9 1851.9 1812.9 1852.7 1861.2 1876.4 1879.6 1735.3 1743.7 1767.4 1768.2 1769.2 1748.3 1757.9 1774.2 1781.2 1782.2 1754.7 1783.1 1788.7 1789.0 1789.8 6107.4 6344.9 6436.7 6473.0 6486.5 3651.2 3686.3 3807.8 3813.7 3814.8 2447.0 2449.4 2499.3 2499.4 2499.8 723.2 729.9 755.9 756.6 757.1 7792.9 8075.8 8121.5 8128.8 8151.5 3265.5 3276.5 3331.7 3338.0 3347.7 6538.5 6550.5 6569.7 6577.3 6584.9 3595.1 3642.3 3673.7 3713.9 3745.1 2929.5 3013.0 3401.3 3499.4 3514.9 2709.2 2830.3 2830.8 2854.0 2864.4 859.0 878.4 880.6 882.6 885.0 615.1 715.5 756.0 756.2 758.0 4010.2 4191.3 4197.8 4249.1 4498.5 739.7 763.7 780.1 827.1 835.1 3096.0 3147.2 3147.4 3190.4 3239.1 7332.0 7397.3 7406.7 7721.0 7752.2 7219.0 7538.5 7544.7 7668.2 7685.5 5870.1 5904.0 5941.5 6008.7 6021.3 4338.4 4359.9 4568.0 4600.7 4619.8 6768.2 6777.9 6877.9 6992.2 7064.8 7088.9 7237.4 7264.9 7299.3 7322.5 5371.2 5426.7 5527.5 5537.4 5546.2 5309.5 5493.6 5728.4 6071.9 6122.2 5177.1 5422.5 5518.1 5564.9 5568.3 5332.8 5336.0 5450.0 5521.7 5584.1 5146.5 5215.0 5291.6 5337.7 5344.8 3714.3 3835.4 3982.4 4045.6 4055.6 3727.5 3867.8 3913.6 3956.5 3995.4
12:23:09 INFO - PROCESS | 752 |
12:23:09 INFO - PROCESS | 752 | [#4] prototype.html Cycles:180 Average:3828.00 Median:4424.40 stddev:1176.41 (26.6%) stddev-sans-first:1148.48
12:23:09 INFO - PROCESS | 752 | Values: 240.9 242.2 247.9 248.8 254.6 4436.6 4438.6 4479.8 4504.2 4519.8 4109.0 4348.2 4352.3 4356.9 4474.6 3177.5 3203.0 3234.9 3328.1 3328.8 3313.0 3322.3 3332.5 3333.1 3334.7 3221.6 3292.3 3293.9 3305.3 3312.5 3124.2 3149.9 3167.1 3246.2 3262.3 2831.7 2833.4 2834.4 2834.5 2836.6 4309.4 4317.1 4323.6 4326.7 4330.2 3804.0 3813.8 3816.4 3819.5 3827.7 4322.8 4536.5 4546.9 4579.0 4581.2 3081.7 3113.1 3115.0 3116.6 3122.8 4751.2 4775.2 4779.1 4789.2 4800.4 3187.8 3198.8 3205.5 3211.7 3214.2 3240.9 3242.0 3255.1 3255.7 3261.0 3770.7 3779.5 3782.2 3785.4 3790.6 4469.3 4584.9 4604.8 4627.6 4629.8 1115.5 1125.0 1128.5 1133.0 1134.0 690.4 710.9 718.8 722.2 724.8 4259.9 4350.4 4381.7 4384.3 4392.8 4638.7 4694.1 4709.6 4792.6 4810.9 4381.5 4384.8 4408.7 4412.2 4446.3 2508.8 2524.4 2526.8 2532.3 2538.3 4656.5 4867.4 4897.5 4908.3 4957.3 4833.8 4873.7 4911.6 4920.2 4924.2 4842.7 4909.0 4916.0 4919.5 4940.3 4539.9 4785.5 4788.2 4807.3 4818.7 4703.1 4706.0 4717.4 4726.3 4739.4 4909.2 4910.3 4917.0 4918.3 4925.7 2892.4 3177.9 3196.5 3199.3 3208.4 4730.7 4776.5 4786.0 4786.8 4789.1 4143.2 4553.1 4596.7 4600.8 4624.1 4492.3 4592.1 4593.0 4597.7 4611.8 4472.7 4487.6 4489.7 4495.7 4499.9 4621.6 4653.2 4656.2 4666.6 4672.3 4334.6 4506.7 4516.0 4518.8 4521.7
12:23:09 INFO - PROCESS | 752 |
12:23:09 INFO - PROCESS | 752 | [#5] yui.html Cycles:180 Average:17151.38 Median:419.67 stddev:24261.15 (5781.0%) stddev-sans-first:24297.26
12:23:09 INFO - PROCESS | 752 | Values: 569.8 591.6 614.4 630.4 655.9 12683.9 13661.7 13823.2 13840.7 13847.9 12448.8 12736.7 12841.7 12876.0 12877.6 516.0 651.1 666.9 667.2 668.6 521.7 524.5 529.7 529.9 643.3 691.3 694.3 697.8 701.2 705.3 672.3 690.7 693.1 696.8 701.3 393.7 400.6 508.4 509.0 512.1 11770.5 12440.2 12472.3 12651.2 13009.4 12610.1 12614.9 12913.4 13032.0 13142.9 9813.0 10146.2 10184.2 10224.2 10449.9 3115.3 3187.6 3200.7 3208.3 3303.6 62627.1 62842.4 63676.0 64132.4 65349.0 9934.0 9975.7 10040.6 10054.5 10224.1 18570.4 18571.4 18575.5 18590.3 18607.7 6675.1 6721.7 6830.1 6853.8 6927.4 3642.6 3702.5 3731.2 3740.9 3742.4 2413.1 2553.7 2555.5 2560.0 2572.2 415.1 417.1 417.4 421.9 423.7 517.7 565.8 571.9 594.1 619.8 7909.9 7983.7 8113.1 8119.6 8489.8 430.2 532.3 554.9 568.0 579.0 3591.8 3849.2 3948.8 3955.4 4032.5 93828.5 94652.5 95997.5 96122.0 96533.5 62556.7 81072.6 83904.2 84840.2 85593.1 65054.7 66444.7 67105.7 67272.7 70009.6 7877.0 8105.1 8151.5 8169.2 8218.5 40030.0 40871.0 40990.8 42309.0 42639.0 57521.7 57671.7 58896.4 60997.4 61169.7 10316.4 10778.2 10951.5 11050.8 11216.9 12656.0 12972.6 13007.2 13143.7 13600.7 10966.5 11405.5 11634.8 11677.6 11731.5 12055.4 12114.6 12117.4 12315.0 12574.9 10528.6 10823.1 11019.0 11195.2 11203.0 9852.7 10031.6 10246.4 10351.2 10950.6 9679.5 9704.0 9842.6 10370.8 10564.4
12:23:09 INFO - PROCESS | 752 | -------- Summary: end --------
12:23:09 INFO - PROCESS | 752 |
12:23:10 INFO - TEST-INFO | 752: exit 0
12:23:11 INFO - TEST-OK | dromaeo_css | took 1125867ms
12:23:11 INFO - TEST-START | kraken
12:23:11 INFO - operating with platform_type : w7_
12:23:11 INFO - Initialising browser for kraken test...
12:23:11 INFO - TEST-INFO | started process 1148 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpn5xefp\profile http://localhost:49650/getInfo.html)
12:23:15 INFO - PROCESS | 1148 | __metrics Screen width/height:1600/1200
12:23:15 INFO - PROCESS | 1148 | colorDepth:24
12:23:15 INFO - PROCESS | 1148 | Browser inner width/height: 1010/674
12:23:15 INFO - PROCESS | 1148 | __metrics
12:23:16 INFO - PROCESS | 1148 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
12:23:16 INFO - PROCESS | 1148 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
12:23:16 INFO - PROCESS | 1148 | console.error:
12:23:16 INFO - PROCESS | 1148 | Message: Error: Connection closed before committing the transaction.
12:23:16 INFO - PROCESS | 1148 | Stack:
12:23:16 INFO - PROCESS | 1148 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
12:23:16 INFO - PROCESS | 1148 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
12:23:16 INFO - PROCESS | 1148 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
12:23:16 INFO - PROCESS | 1148 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
12:23:16 INFO - PROCESS | 1148 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
12:23:16 INFO - PROCESS | 1148 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13
12:23:16 INFO - PROCESS | 1148 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
12:23:16 INFO - PROCESS | 1148 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
12:23:16 INFO - PROCESS | 1148 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
12:23:16 INFO - PROCESS | 1148 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
12:23:16 INFO - PROCESS | 1148 | Barrier/this.client.addBlocker/promise13.1%
12:24:17 INFO - TinderboxPrint: I/O read bytes / time
132,777,472 / 3,420,590
12:24:17 INFO - TinderboxPrint: I/O write bytes / time
473,527,296 / 4,321,040
12:24:17 INFO - TinderboxPrint: CPU idle
8,326.6 (86.9%)
12:24:17 INFO - TinderboxPrint: CPU user
1,222.1 (12.7%)
12:24:17 INFO - install - Wall time: 5s; CPU: 6.0%; Read bytes: 20801024; Write bytes: 50938880; Read time: 889260; Write time: 1235620
12:24:17 INFO - run-tests - Wall time: 1194s; CPU: 13.0%; Read bytes: 110347264; Write bytes: 388624896; Read time: 2293600; Write time: 2910250
12:24:17 INFO - Running post-run listener: _upload_blobber_files
12:24:17 INFO - Blob upload gear active.
12:24:17 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
12:24:17 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
12:24:17 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
12:24:17 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Fx-Team-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
12:24:18 INFO - (blobuploader) - INFO - Open directory for files ...
12:24:18 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
12:24:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:24:18 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:24:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
12:24:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:24:19 INFO - (blobuploader) - INFO - Done attempting.
12:24:19 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
12:24:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:24:19 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:24:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
12:24:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:24:20 INFO - (blobuploader) - INFO - Done attempting.
12:24:20 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
12:24:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:24:20 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:24:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
12:24:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:24:28 INFO - (blobuploader) - INFO - Done attempting.
12:24:28 INFO - (blobuploader) - INFO - Iteration through files over.
12:24:28 INFO - Return code: 0
12:24:28 INFO - rmtree: C:\slave\test\build\uploaded_files.json
12:24:28 INFO - Using _rmtree_windows ...
12:24:28 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
12:24:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/17402ebd42d9e9e11a19421f3b15baaf4b2e672f5833759eb7fa1cfc2df8dd4a97763bd8d1a2a4c55ed28712e7db8e63b8b146776a1fd7a7d2300028a698604a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/90a128761fa9885dfd83755a0cdfcf12790cb6da9f78e8a5f16f8ee34c0923ce02f6dfd435bc2a66a4af871e365ff2503694d64227856338e24598044d6ac350", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/db3aa45daaef42f0d983b12fdb8dabfdc1ea2cf669ae16acbeaa2c1d97171ecb6364c4785669e00579512de2996ad68fb80c823204b8a6e33bae98a1fc51f484"}
12:24:28 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
12:24:28 INFO - Writing to file C:\slave\test\properties\blobber_files
12:24:28 INFO - Contents:
12:24:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/17402ebd42d9e9e11a19421f3b15baaf4b2e672f5833759eb7fa1cfc2df8dd4a97763bd8d1a2a4c55ed28712e7db8e63b8b146776a1fd7a7d2300028a698604a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/90a128761fa9885dfd83755a0cdfcf12790cb6da9f78e8a5f16f8ee34c0923ce02f6dfd435bc2a66a4af871e365ff2503694d64227856338e24598044d6ac350", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/db3aa45daaef42f0d983b12fdb8dabfdc1ea2cf669ae16acbeaa2c1d97171ecb6364c4785669e00579512de2996ad68fb80c823204b8a6e33bae98a1fc51f484"}
12:24:28 INFO - Running post-run listener: copy_logs_to_upload_dir
12:24:28 INFO - Copying logs to upload dir...
12:24:28 INFO - mkdir: C:\slave\test\build\upload\logs
12:24:28 INFO - Copying logs to upload dir...
12:24:28 INFO - Using _rmtree_windows ...
12:24:28 INFO - Using _rmtree_windows ...
12:24:28 INFO - Using _rmtree_windows ...
12:24:28 INFO - Using _rmtree_windows ...
12:24:28 INFO - Using _rmtree_windows ...
12:24:28 INFO - Using _rmtree_windows ...
12:24:28 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1481.036000
========= master_lag: 0.05 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 41 secs) (at 2016-09-23 12:24:28.619000) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-23 12:24:28.625306) =========
'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-149
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-149
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-149
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/17402ebd42d9e9e11a19421f3b15baaf4b2e672f5833759eb7fa1cfc2df8dd4a97763bd8d1a2a4c55ed28712e7db8e63b8b146776a1fd7a7d2300028a698604a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/90a128761fa9885dfd83755a0cdfcf12790cb6da9f78e8a5f16f8ee34c0923ce02f6dfd435bc2a66a4af871e365ff2503694d64227856338e24598044d6ac350", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/db3aa45daaef42f0d983b12fdb8dabfdc1ea2cf669ae16acbeaa2c1d97171ecb6364c4785669e00579512de2996ad68fb80c823204b8a6e33bae98a1fc51f484"}
build_url:https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://queue.taskcluster.net/v1/task/S8Veaj5YRyKxYX2ZPmk3mw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/17402ebd42d9e9e11a19421f3b15baaf4b2e672f5833759eb7fa1cfc2df8dd4a97763bd8d1a2a4c55ed28712e7db8e63b8b146776a1fd7a7d2300028a698604a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/90a128761fa9885dfd83755a0cdfcf12790cb6da9f78e8a5f16f8ee34c0923ce02f6dfd435bc2a66a4af871e365ff2503694d64227856338e24598044d6ac350", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/db3aa45daaef42f0d983b12fdb8dabfdc1ea2cf669ae16acbeaa2c1d97171ecb6364c4785669e00579512de2996ad68fb80c823204b8a6e33bae98a1fc51f484"}'
========= master_lag: 0.01 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-23 12:24:28.738625) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 12:24:28.738961) =========
'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-149
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-149
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-149
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-09-23 12:24:28.851054) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-23 12:24:28.851368) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-23 12:24:29.325830) =========
========= Total master_lag: 4.41 =========