builder: mozilla-central_win7_ix_test-dromaeojs
slave: t-w732-ix-027
starttime: 1478316850.77
results: success (0)
buildid: 20161104185722
builduid: 664b3c7de5374cb9b4091b5f51ae14be
revision: f092853e564086a98e14f0018ce784bb033e1d85
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-04 20:34:10.774282) =========
master: http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-04 20:34:10.774819) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-04 20:34:10.775105) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-04 20:34:10.864481) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-04 20:34:10.864858) =========
'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-027
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-027
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-027
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
--20:34:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,179 (12K) [text/x-python]
0K .......... . 100% 13.30 MB/s
20:34:12 (13.30 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.093000
========= master_lag: 0.09 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-04 20:34:12.043714) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2016-11-04 20:34:12.044062) =========
'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-027
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-027
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-027
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=9.001000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2016-11-04 20:34:21.055785) =========
========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-11-04 20:34:21.056157) =========
'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev f092853e564086a98e14f0018ce784bb033e1d85 --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f092853e564086a98e14f0018ce784bb033e1d85 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-027
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-027
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-027
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
2016-11-04 20:34:21,359 truncating revision to first 12 chars
2016-11-04 20:34:21,359 Setting DEBUG logging.
2016-11-04 20:34:21,359 attempt 1/10
2016-11-04 20:34:21,359 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f092853e5640?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-11-04 20:34:24,851 unpacking tar archive at: mozilla-central-f092853e5640/testing/mozharness/
2016-11-04 20:34:24,980 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=4.068000
========= master_lag: 0.07 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-11-04 20:34:25.193399) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-04 20:34:25.193740) =========
script_repo_revision: f092853e564086a98e14f0018ce784bb033e1d85
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-04 20:34:25.194210) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-04 20:34:25.194499) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-04 20:34:25.205051) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 41 secs) (at 2016-11-04 20:34:25.205394) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox-Non-PGO'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-027
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-027
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-027
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
20:34:25 INFO - MultiFileLogger online at 20161104 20:34:25 in C:\slave\test
20:34:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
20:34:25 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
20:34:25 INFO - {'append_to_log': False,
20:34:25 INFO - 'base_work_dir': 'C:\\slave\\test',
20:34:25 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
20:34:25 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
20:34:25 INFO - 'branch': 'Firefox-Non-PGO',
20:34:25 INFO - 'buildbot_json_path': 'buildprops.json',
20:34:25 INFO - 'config_files': ('talos/windows_config.py',),
20:34:25 INFO - 'default_actions': ('clobber',
20:34:25 INFO - 'read-buildbot-config',
20:34:25 INFO - 'download-and-extract',
20:34:25 INFO - 'populate-webroot',
20:34:25 INFO - 'create-virtualenv',
20:34:25 INFO - 'install',
20:34:25 INFO - 'run-tests'),
20:34:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
20:34:25 INFO - 'download_minidump_stackwalk': True,
20:34:25 INFO - 'download_symbols': 'ondemand',
20:34:25 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
20:34:25 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
20:34:25 INFO - 'hg': 'c:/mozilla-build/hg/hg',
20:34:25 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
20:34:25 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
20:34:25 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
20:34:25 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
20:34:25 INFO - 'C:/mozilla-build/tooltool.py'),
20:34:25 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
20:34:25 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
20:34:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
20:34:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
20:34:25 INFO - 'installer_path': 'installer.exe',
20:34:25 INFO - 'log_level': 'info',
20:34:25 INFO - 'log_name': 'talos',
20:34:25 INFO - 'log_to_console': True,
20:34:25 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
20:34:25 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
20:34:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
20:34:25 INFO - 'opt_config_files': (),
20:34:25 INFO - 'pip_index': False,
20:34:25 INFO - 'sps_profile': False,
20:34:25 INFO - 'sps_profile_interval': 0,
20:34:25 INFO - 'suite': 'dromaeojs',
20:34:25 INFO - 'system_bits': '32',
20:34:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
20:34:25 INFO - 'title': 'T-W732-IX-027',
20:34:25 INFO - 'use_talos_json': True,
20:34:25 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
20:34:25 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
20:34:25 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
20:34:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
20:34:25 INFO - 'work_dir': 'build'}
20:34:25 INFO - [mozharness: 2016-11-05 03:34:25.720000Z] Running clobber step.
20:34:25 INFO - Running pre-action listener: _resource_record_pre_action
20:34:25 INFO - Running main action method: clobber
20:34:25 INFO - rmtree: C:\slave\test\build
20:34:25 INFO - Using _rmtree_windows ...
20:34:25 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
20:36:17 INFO - Running post-action listener: _resource_record_post_action
20:36:17 INFO - [mozharness: 2016-11-05 03:36:17.091000Z] Finished clobber step (success)
20:36:17 INFO - [mozharness: 2016-11-05 03:36:17.091000Z] Running read-buildbot-config step.
20:36:17 INFO - Running pre-action listener: _resource_record_pre_action
20:36:17 INFO - Running main action method: read_buildbot_config
20:36:17 INFO - Using buildbot properties:
20:36:17 INFO - {
20:36:17 INFO - "project": "",
20:36:17 INFO - "product": "firefox",
20:36:17 INFO - "script_repo_revision": "production",
20:36:17 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs",
20:36:17 INFO - "repository": "",
20:36:17 INFO - "buildername": "Windows 7 32-bit mozilla-central talos dromaeojs",
20:36:17 INFO - "buildid": "20161104185722",
20:36:17 INFO - "pgo_build": "False",
20:36:17 INFO - "basedir": "C:\\slave\\test",
20:36:17 INFO - "buildnumber": 67,
20:36:17 INFO - "slavename": "t-w732-ix-027",
20:36:17 INFO - "master": "http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/",
20:36:17 INFO - "platform": "win7_ix",
20:36:17 INFO - "scheduler": "tests-mozilla-central-win32-talos",
20:36:17 INFO - "branch": "mozilla-central",
20:36:17 INFO - "repo_path": "mozilla-central",
20:36:17 INFO - "revision": "f092853e564086a98e14f0018ce784bb033e1d85",
20:36:17 INFO - "stage_platform": "win32",
20:36:17 INFO - "builduid": "664b3c7de5374cb9b4091b5f51ae14be",
20:36:17 INFO - "slavebuilddir": "test"
20:36:17 INFO - }
20:36:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
20:36:17 INFO - Running post-action listener: _resource_record_post_action
20:36:17 INFO - [mozharness: 2016-11-05 03:36:17.095000Z] Finished read-buildbot-config step (success)
20:36:17 INFO - [mozharness: 2016-11-05 03:36:17.095000Z] Running download-and-extract step.
20:36:17 INFO - Running pre-action listener: _resource_record_pre_action
20:36:17 INFO - Running main action method: download_and_extract
20:36:17 INFO - mkdir: C:\slave\test\build\tests
20:36: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')]}
20:36:17 INFO - trying https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
20:36:17 INFO - Downloading https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/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
20:36:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/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
20:36:18 INFO - Downloaded 1274 bytes.
20:36:18 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
20:36:18 INFO - Using the following test package requirements:
20:36:18 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
20:36:18 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
20:36:18 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
20:36:18 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
20:36:18 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'],
20:36:18 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
20:36:18 INFO - u'jsshell-win32.zip'],
20:36:18 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
20:36:18 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
20:36:18 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
20:36:18 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
20:36:18 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
20:36:18 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
20:36:18 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
20:36:18 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
20:36:18 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
20:36:18 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
20:36:18 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
20:36:18 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
20:36:18 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
20:36:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
20:36:18 INFO - Fetch https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
20:36:18 INFO - Http code: 200
20:36:18 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront)
20:36:18 INFO - x-amz-cf-id: 9n_YIl1MwLuJA1zGCoJiwvELmRPpi2NLcK5ZLUW5z4vWdI7hq-tA0Q==
20:36:18 INFO - x-amz-version-id: tn6JOutpcD8_uBvg.5FIK.g7mV3tg33E
20:36:19 INFO - Expected file size: 17052840
20:36:19 INFO - Obtained file size: 17052840
20:37:27 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
20:37:27 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
20:37:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
20:37:27 INFO - Fetch https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
20:37:27 INFO - Http code: 200
20:37:27 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront)
20:37:27 INFO - x-amz-cf-id: _XtFq5x-rrf68rYtyPXGUpEUuC8iFJEmGCcO-fFLzpi-bZNEwukfaQ==
20:37:27 INFO - x-amz-version-id: tn6JOutpcD8_uBvg.5FIK.g7mV3tg33E
20:37:28 INFO - Expected file size: 17052840
20:37:28 INFO - Obtained file size: 17052840
20:37:50 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
20:37:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
20:37:50 INFO - Fetch https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
20:37:51 INFO - Http code: 200
20:37:51 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront)
20:37:51 INFO - x-amz-cf-id: 0vroUs0CslLBo2hy_5AjDflBOo-yZqy8nAg5CNsjpGoNpBzhWo9_jg==
20:37:51 INFO - x-amz-version-id: oqelLnGnieEmcAdcJxRXzA2Q5nQPbYMT
20:37:51 INFO - Expected file size: 14409038
20:37:51 INFO - Obtained file size: 14409038
20:38:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:38:14 INFO - trying https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
20:38:14 INFO - Downloading https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
20:38:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/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
20:38:17 INFO - Downloaded 52935166 bytes.
20:38:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
20:38:17 INFO - mkdir: C:\slave\test\properties
20:38:17 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
20:38:17 INFO - Writing to file C:\slave\test\properties\build_url
20:38:17 INFO - Contents:
20:38:17 INFO - build_url:https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
20:38:18 INFO - Running post-action listener: _resource_record_post_action
20:38:18 INFO - Running post-action listener: set_extra_try_arguments
20:38:18 INFO - [mozharness: 2016-11-05 03:38:18.037000Z] Finished download-and-extract step (success)
20:38:18 INFO - [mozharness: 2016-11-05 03:38:18.037000Z] Running populate-webroot step.
20:38:18 INFO - Running pre-action listener: _resource_record_pre_action
20:38:18 INFO - Running main action method: populate_webroot
20:38:18 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
20:38:18 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
20:38:18 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
20:38:18 INFO - u'--tppagecycles',
20:38:18 INFO - u'7'],
20:38:18 INFO - u'tests': [u'tsvgm']}},
20:38:18 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
20:38:18 INFO - u'tests': [u'tresize', u'tcanvasmark']},
20:38:18 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
20:38:18 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
20:38:18 INFO - u'tests': [u'dromaeo_css', u'kraken']},
20:38:18 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
20:38:18 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
20:38:18 INFO - u'talos_options': [u'--disable-e10s'],
20:38:18 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
20:38:18 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
20:38:18 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
20:38:18 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
20:38:18 INFO - u'talos_options': [u'--disable-e10s'],
20:38:18 INFO - u'tests': [u'damp', u'tps']},
20:38:18 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
20:38:18 INFO - u'tests': [u'damp', u'tps']},
20:38:18 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
20:38:18 INFO - u'tests': [u'dromaeo_dom']},
20:38:18 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
20:38:18 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
20:38:18 INFO - u'tests': [u'basic_compositor_video']},
20:38:18 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
20:38:18 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
20:38:18 INFO - u'tests': [u'a11yr',
20:38:18 INFO - u'ts_paint',
20:38:18 INFO - u'tpaint',
20:38:18 INFO - u'sessionrestore',
20:38:18 INFO - u'sessionrestore_no_auto_restore',
20:38:18 INFO - u'tabpaint']},
20:38:18 INFO - u'other-e10s': {u'tests': [u'a11yr',
20:38:18 INFO - u'ts_paint',
20:38:18 INFO - u'tpaint',
20:38:18 INFO - u'sessionrestore',
20:38:18 INFO - u'sessionrestore_no_auto_restore',
20:38:18 INFO - u'tabpaint']},
20:38:18 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
20:38:18 INFO - u'ts_paint',
20:38:18 INFO - u'tpaint',
20:38:18 INFO - u'sessionrestore',
20:38:18 INFO - u'sessionrestore_no_auto_restore',
20:38:18 INFO - u'tabpaint']},
20:38:18 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
20:38:18 INFO - u'ts_paint',
20:38:18 INFO - u'tpaint',
20:38:18 INFO - u'sessionrestore',
20:38:18 INFO - u'sessionrestore_no_auto_restore',
20:38:18 INFO - u'tabpaint']},
20:38:18 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
20:38:18 INFO - u'tests': [u'a11yr',
20:38:18 INFO - u'ts_paint',
20:38:18 INFO - u'tpaint',
20:38:18 INFO - u'sessionrestore',
20:38:18 INFO - u'sessionrestore_no_auto_restore',
20:38:18 INFO - u'tabpaint']},
20:38:18 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
20:38:18 INFO - u'tests': [u'a11yr',
20:38:18 INFO - u'ts_paint',
20:38:18 INFO - u'tpaint',
20:38:18 INFO - u'sessionrestore',
20:38:18 INFO - u'sessionrestore_no_auto_restore',
20:38:18 INFO - u'tabpaint']},
20:38:18 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
20:38:18 INFO - u'tests': [u'tsvgx',
20:38:18 INFO - u'tsvgr_opacity',
20:38:18 INFO - u'tart',
20:38:18 INFO - u'tscrollx',
20:38:18 INFO - u'cart']},
20:38:18 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
20:38:18 INFO - u'tsvgr_opacity',
20:38:18 INFO - u'tart',
20:38:18 INFO - u'tscrollx',
20:38:18 INFO - u'cart']},
20:38:18 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
20:38:18 INFO - u'talos_options': [u'--disable-e10s'],
20:38:18 INFO - u'tests': [u'tp5o']},
20:38:18 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
20:38:18 INFO - u'tests': [u'tp5o']},
20:38:18 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
20:38:18 INFO - u'talos_options': [u'--disable-e10s',
20:38:18 INFO - u'--xperf_path',
20:38:18 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
20:38:18 INFO - u'tests': [u'tp5n']},
20:38:18 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
20:38:18 INFO - u'talos_options': [u'--xperf_path',
20:38:18 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
20:38:18 INFO - u'tests': [u'tp5n']}},
20:38:18 INFO - u'talos.zip': {u'path': u'',
20:38:18 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
20:38:18 INFO - Running post-action listener: _resource_record_post_action
20:38:18 INFO - [mozharness: 2016-11-05 03:38:18.051000Z] Finished populate-webroot step (success)
20:38:18 INFO - [mozharness: 2016-11-05 03:38:18.052000Z] Running create-virtualenv step.
20:38:18 INFO - Running pre-action listener: _resource_record_pre_action
20:38:18 INFO - Running main action method: create_virtualenv
20:38:18 INFO - Creating virtualenv C:\slave\test\build/venv
20:38:18 INFO - mkdir: C:\slave\test\build\venv\Scripts
20:38:18 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
20:38:18 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'!
20:38:18 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build/venv'] in C:\slave\test\build
20:38:18 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
20:38:51 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
20:38:54 INFO - Installing distribute......................................................................................................................................................................................done.
20:38:55 INFO - Return code: 0
20:38:55 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
20:38:55 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
20:38:56 INFO - Reading from file tmpfile_stdout
20:38:56 INFO - Output received:
20:38:56 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
20:38:56 INFO - Using _rmtree_windows ...
20:38:56 INFO - Using _rmtree_windows ...
20:38:56 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
20:38:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:38:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:38:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:38:56 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01927640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01961E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0197AE90>, '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 0x0137A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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
20:38:56 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build
20:38:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
20:38:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:38:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:38:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:38:56 INFO - 'COMPUTERNAME': 'T-W732-IX-027',
20:38:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:38:56 INFO - 'CYGWINBASE': 'C:\\cygwin',
20:38:56 INFO - 'DCLOCATION': 'SCL3',
20:38:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:38:56 INFO - 'FP_NO_HOST_CHECK': 'NO',
20:38:56 INFO - 'HOMEDRIVE': 'C:',
20:38:56 INFO - 'HOMEPATH': '\\Users\\cltbld',
20:38:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
20:38:56 INFO - 'KTS_VERSION': '1.19c',
20:38:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:38:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027',
20:38:56 INFO - 'MONDIR': 'C:\\Monitor_config\\',
20:38:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:38:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:38:56 INFO - 'MOZ_NO_REMOTE': '1',
20:38:56 INFO - 'NO_EM_RESTART': '1',
20:38:56 INFO - 'NUMBER_OF_PROCESSORS': '8',
20:38:56 INFO - 'OS': 'Windows_NT',
20:38:56 INFO - 'OURDRIVE': 'C:',
20:38:56 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
20:38:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:38:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
20:38:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:38:56 INFO - 'PROCESSOR_LEVEL': '6',
20:38:56 INFO - 'PROCESSOR_REVISION': '1e05',
20:38:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
20:38:56 INFO - 'PROGRAMFILES': 'C:\\Program Files',
20:38:56 INFO - 'PROMPT': '$P$G',
20:38:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:38:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:38:56 INFO - 'PUBLIC': 'C:\\Users\\Public',
20:38:56 INFO - 'PWD': 'C:\\slave\\test',
20:38:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:38:56 INFO - 'SLAVEDIR': 'C:\\slave\\',
20:38:56 INFO - 'SYSTEMDRIVE': 'C:',
20:38:56 INFO - 'SYSTEMROOT': 'C:\\windows',
20:38:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:38:56 INFO - 'TEST1': 'testie',
20:38:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:38:56 INFO - 'USERDOMAIN': 'T-W732-IX-027',
20:38:56 INFO - 'USERNAME': 'cltbld',
20:38:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
20:38:56 INFO - 'WINDIR': 'C:\\windows',
20:38:56 INFO - 'WINDOWS_TRACING_FLAGS': '3',
20:38:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:38:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
20:38:57 INFO - Ignoring indexes: http://pypi.python.org/simple/
20:38:57 INFO - Downloading/unpacking pip>=1.5
20:39:04 INFO - Running setup.py egg_info for package pip
20:39:04 INFO - warning: no files found matching 'pip\cacert.pem'
20:39:04 INFO - warning: no files found matching '*.html' under directory 'docs'
20:39:04 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
20:39:04 INFO - no previously-included directories found matching 'docs\_build\_sources'
20:39:04 INFO - Installing collected packages: pip
20:39:04 INFO - Found existing installation: pip 0.8.2
20:39:04 INFO - Uninstalling pip:
20:39:04 INFO - Successfully uninstalled pip
20:39:04 INFO - Running setup.py install for pip
20:39:04 INFO - warning: no files found matching 'pip\cacert.pem'
20:39:04 INFO - warning: no files found matching '*.html' under directory 'docs'
20:39:04 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
20:39:04 INFO - no previously-included directories found matching 'docs\_build\_sources'
20:39:04 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
20:39:04 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
20:39:04 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
20:39:04 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
20:39:04 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
20:39:04 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
20:39:04 INFO - Successfully installed pip
20:39:04 INFO - Cleaning up...
20:39:04 INFO - Return code: 0
20:39:04 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
20:39:04 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')]}
20:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:39:04 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')]}
20:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:39:04 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 0x01927640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01961E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0197AE90>, '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 0x0137A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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
20:39:04 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
20:39:04 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
20:39:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:39:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:39:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:39:04 INFO - 'COMPUTERNAME': 'T-W732-IX-027',
20:39:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:39:04 INFO - 'CYGWINBASE': 'C:\\cygwin',
20:39:04 INFO - 'DCLOCATION': 'SCL3',
20:39:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:39:04 INFO - 'FP_NO_HOST_CHECK': 'NO',
20:39:04 INFO - 'HOMEDRIVE': 'C:',
20:39:04 INFO - 'HOMEPATH': '\\Users\\cltbld',
20:39:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
20:39:04 INFO - 'KTS_VERSION': '1.19c',
20:39:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:39:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027',
20:39:04 INFO - 'MONDIR': 'C:\\Monitor_config\\',
20:39:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:39:04 INFO - 'MOZ_NO_REMOTE': '1',
20:39:04 INFO - 'NO_EM_RESTART': '1',
20:39:04 INFO - 'NUMBER_OF_PROCESSORS': '8',
20:39:04 INFO - 'OS': 'Windows_NT',
20:39:04 INFO - 'OURDRIVE': 'C:',
20:39:04 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;',
20:39:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:39:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
20:39:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:39:04 INFO - 'PROCESSOR_LEVEL': '6',
20:39:04 INFO - 'PROCESSOR_REVISION': '1e05',
20:39:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
20:39:04 INFO - 'PROGRAMFILES': 'C:\\Program Files',
20:39:04 INFO - 'PROMPT': '$P$G',
20:39:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:39:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:39:04 INFO - 'PUBLIC': 'C:\\Users\\Public',
20:39:04 INFO - 'PWD': 'C:\\slave\\test',
20:39:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:39:04 INFO - 'SLAVEDIR': 'C:\\slave\\',
20:39:04 INFO - 'SYSTEMDRIVE': 'C:',
20:39:04 INFO - 'SYSTEMROOT': 'C:\\windows',
20:39:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:04 INFO - 'TEST1': 'testie',
20:39:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:04 INFO - 'USERDOMAIN': 'T-W732-IX-027',
20:39:04 INFO - 'USERNAME': 'cltbld',
20:39:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
20:39:04 INFO - 'WINDIR': 'C:\\windows',
20:39:04 INFO - 'WINDOWS_TRACING_FLAGS': '3',
20:39:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
20:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple/
20:39:12 INFO - Downloading/unpacking psutil>=3.1.1
20:39: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
20:39: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
20:39:15 INFO - Installing collected packages: psutil
20:39:16 INFO - Successfully installed psutil
20:39:16 INFO - Cleaning up...
20:39:16 INFO - Return code: 0
20:39:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
20:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:39:16 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01927640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01961E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0197AE90>, '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 0x0137A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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
20:39:16 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build
20:39:16 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
20:39:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:39:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:39:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:39:16 INFO - 'COMPUTERNAME': 'T-W732-IX-027',
20:39:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:39:16 INFO - 'CYGWINBASE': 'C:\\cygwin',
20:39:16 INFO - 'DCLOCATION': 'SCL3',
20:39:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:39:16 INFO - 'FP_NO_HOST_CHECK': 'NO',
20:39:16 INFO - 'HOMEDRIVE': 'C:',
20:39:16 INFO - 'HOMEPATH': '\\Users\\cltbld',
20:39:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
20:39:16 INFO - 'KTS_VERSION': '1.19c',
20:39:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:39:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027',
20:39:16 INFO - 'MONDIR': 'C:\\Monitor_config\\',
20:39:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:39:16 INFO - 'MOZ_NO_REMOTE': '1',
20:39:16 INFO - 'NO_EM_RESTART': '1',
20:39:16 INFO - 'NUMBER_OF_PROCESSORS': '8',
20:39:16 INFO - 'OS': 'Windows_NT',
20:39:16 INFO - 'OURDRIVE': 'C:',
20:39:16 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
20:39:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:39:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
20:39:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:39:16 INFO - 'PROCESSOR_LEVEL': '6',
20:39:16 INFO - 'PROCESSOR_REVISION': '1e05',
20:39:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
20:39:16 INFO - 'PROGRAMFILES': 'C:\\Program Files',
20:39:16 INFO - 'PROMPT': '$P$G',
20:39:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:39:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:39:16 INFO - 'PUBLIC': 'C:\\Users\\Public',
20:39:16 INFO - 'PWD': 'C:\\slave\\test',
20:39:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:39:16 INFO - 'SLAVEDIR': 'C:\\slave\\',
20:39:16 INFO - 'SYSTEMDRIVE': 'C:',
20:39:16 INFO - 'SYSTEMROOT': 'C:\\windows',
20:39:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:16 INFO - 'TEST1': 'testie',
20:39:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:16 INFO - 'USERDOMAIN': 'T-W732-IX-027',
20:39:16 INFO - 'USERNAME': 'cltbld',
20:39:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
20:39:16 INFO - 'WINDIR': 'C:\\windows',
20:39:16 INFO - 'WINDOWS_TRACING_FLAGS': '3',
20:39:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
20:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/
20:39:16 INFO - Downloading/unpacking mozsystemmonitor==0.3
20:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
20:39:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
20:39:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz
20:39:18 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
20:39:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3)
20:39:18 INFO - Installing collected packages: mozsystemmonitor
20:39:18 INFO - Running setup.py install for mozsystemmonitor
20:39:18 INFO - Successfully installed mozsystemmonitor
20:39:18 INFO - Cleaning up...
20:39:18 INFO - Return code: 0
20:39:18 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
20:39:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:39:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:39:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:39:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:39:18 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01927640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01961E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0197AE90>, '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 0x0137A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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
20:39:18 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build
20:39:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1
20:39:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:39:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:39:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:39:18 INFO - 'COMPUTERNAME': 'T-W732-IX-027',
20:39:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:39:18 INFO - 'CYGWINBASE': 'C:\\cygwin',
20:39:18 INFO - 'DCLOCATION': 'SCL3',
20:39:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:39:18 INFO - 'FP_NO_HOST_CHECK': 'NO',
20:39:18 INFO - 'HOMEDRIVE': 'C:',
20:39:18 INFO - 'HOMEPATH': '\\Users\\cltbld',
20:39:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
20:39:18 INFO - 'KTS_VERSION': '1.19c',
20:39:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:39:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027',
20:39:18 INFO - 'MONDIR': 'C:\\Monitor_config\\',
20:39:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:39:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:39:18 INFO - 'MOZ_NO_REMOTE': '1',
20:39:18 INFO - 'NO_EM_RESTART': '1',
20:39:18 INFO - 'NUMBER_OF_PROCESSORS': '8',
20:39:18 INFO - 'OS': 'Windows_NT',
20:39:18 INFO - 'OURDRIVE': 'C:',
20:39:18 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
20:39:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:39:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
20:39:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:39:18 INFO - 'PROCESSOR_LEVEL': '6',
20:39:18 INFO - 'PROCESSOR_REVISION': '1e05',
20:39:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
20:39:18 INFO - 'PROGRAMFILES': 'C:\\Program Files',
20:39:18 INFO - 'PROMPT': '$P$G',
20:39:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:39:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:39:18 INFO - 'PUBLIC': 'C:\\Users\\Public',
20:39:18 INFO - 'PWD': 'C:\\slave\\test',
20:39:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:39:18 INFO - 'SLAVEDIR': 'C:\\slave\\',
20:39:18 INFO - 'SYSTEMDRIVE': 'C:',
20:39:18 INFO - 'SYSTEMROOT': 'C:\\windows',
20:39:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:18 INFO - 'TEST1': 'testie',
20:39:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:18 INFO - 'USERDOMAIN': 'T-W732-IX-027',
20:39:18 INFO - 'USERNAME': 'cltbld',
20:39:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
20:39:18 INFO - 'WINDIR': 'C:\\windows',
20:39:18 INFO - 'WINDOWS_TRACING_FLAGS': '3',
20:39:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:39:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
20:39:18 INFO - Ignoring indexes: https://pypi.python.org/simple/
20:39:18 INFO - Downloading/unpacking jsonschema==2.5.1
20:39:18 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
20:39:18 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
20:39:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
20:39:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
20:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
20:39:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
20:39:20 INFO - Downloading functools32-3.2.3-2.tar.gz
20:39:20 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
20:39:20 INFO - warning: no files found matching '*.txt'
20:39:20 INFO - no previously-included directories found matching 'build'
20:39:20 INFO - no previously-included directories found matching 'dist'
20:39:20 INFO - no previously-included directories found matching '.git*'
20:39:20 INFO - Installing collected packages: jsonschema, functools32
20:39:20 INFO - Running setup.py install for functools32
20:39:20 INFO - warning: no files found matching '*.txt'
20:39:20 INFO - no previously-included directories found matching 'build'
20:39:20 INFO - no previously-included directories found matching 'dist'
20:39:20 INFO - no previously-included directories found matching '.git*'
20:39:20 INFO - Successfully installed jsonschema functools32
20:39:20 INFO - Cleaning up...
20:39:20 INFO - Return code: 0
20:39:20 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
20:39:20 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')]}
20:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:39:20 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')]}
20:39:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:39:20 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 0x01927640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01961E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0197AE90>, '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 0x0137A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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
20:39:21 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
20:39:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
20:39:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:39:21 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:39:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:39:21 INFO - 'COMPUTERNAME': 'T-W732-IX-027',
20:39:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:39:21 INFO - 'CYGWINBASE': 'C:\\cygwin',
20:39:21 INFO - 'DCLOCATION': 'SCL3',
20:39:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:39:21 INFO - 'FP_NO_HOST_CHECK': 'NO',
20:39:21 INFO - 'HOMEDRIVE': 'C:',
20:39:21 INFO - 'HOMEPATH': '\\Users\\cltbld',
20:39:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
20:39:21 INFO - 'KTS_VERSION': '1.19c',
20:39:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:39:21 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027',
20:39:21 INFO - 'MONDIR': 'C:\\Monitor_config\\',
20:39:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:39:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:39:21 INFO - 'MOZ_NO_REMOTE': '1',
20:39:21 INFO - 'NO_EM_RESTART': '1',
20:39:21 INFO - 'NUMBER_OF_PROCESSORS': '8',
20:39:21 INFO - 'OS': 'Windows_NT',
20:39:21 INFO - 'OURDRIVE': 'C:',
20:39:21 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
20:39:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:39:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
20:39:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:39:21 INFO - 'PROCESSOR_LEVEL': '6',
20:39:21 INFO - 'PROCESSOR_REVISION': '1e05',
20:39:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
20:39:21 INFO - 'PROGRAMFILES': 'C:\\Program Files',
20:39:21 INFO - 'PROMPT': '$P$G',
20:39:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:39:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:39:21 INFO - 'PUBLIC': 'C:\\Users\\Public',
20:39:21 INFO - 'PWD': 'C:\\slave\\test',
20:39:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:39:21 INFO - 'SLAVEDIR': 'C:\\slave\\',
20:39:21 INFO - 'SYSTEMDRIVE': 'C:',
20:39:21 INFO - 'SYSTEMROOT': 'C:\\windows',
20:39:21 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:21 INFO - 'TEST1': 'testie',
20:39:21 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:21 INFO - 'USERDOMAIN': 'T-W732-IX-027',
20:39:21 INFO - 'USERNAME': 'cltbld',
20:39:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
20:39:21 INFO - 'WINDIR': 'C:\\windows',
20:39:21 INFO - 'WINDOWS_TRACING_FLAGS': '3',
20:39:21 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:39:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
20:39:21 INFO - Ignoring indexes: https://pypi.python.org/simple/
20:39:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
20:39:21 INFO - Cleaning up...
20:39:21 INFO - Return code: 0
20:39:21 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
20:39:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:39:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:39:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:39:21 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01927640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01961E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0197AE90>, '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 0x0137A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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
20:39:21 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build
20:39:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
20:39:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:39:21 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:39:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:39:21 INFO - 'COMPUTERNAME': 'T-W732-IX-027',
20:39:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:39:21 INFO - 'CYGWINBASE': 'C:\\cygwin',
20:39:21 INFO - 'DCLOCATION': 'SCL3',
20:39:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:39:21 INFO - 'FP_NO_HOST_CHECK': 'NO',
20:39:21 INFO - 'HOMEDRIVE': 'C:',
20:39:21 INFO - 'HOMEPATH': '\\Users\\cltbld',
20:39:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
20:39:21 INFO - 'KTS_VERSION': '1.19c',
20:39:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:39:21 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027',
20:39:21 INFO - 'MONDIR': 'C:\\Monitor_config\\',
20:39:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:39:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:39:21 INFO - 'MOZ_NO_REMOTE': '1',
20:39:21 INFO - 'NO_EM_RESTART': '1',
20:39:21 INFO - 'NUMBER_OF_PROCESSORS': '8',
20:39:21 INFO - 'OS': 'Windows_NT',
20:39:21 INFO - 'OURDRIVE': 'C:',
20:39:21 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
20:39:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:39:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
20:39:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:39:21 INFO - 'PROCESSOR_LEVEL': '6',
20:39:21 INFO - 'PROCESSOR_REVISION': '1e05',
20:39:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
20:39:21 INFO - 'PROGRAMFILES': 'C:\\Program Files',
20:39:21 INFO - 'PROMPT': '$P$G',
20:39:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:39:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:39:21 INFO - 'PUBLIC': 'C:\\Users\\Public',
20:39:21 INFO - 'PWD': 'C:\\slave\\test',
20:39:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:39:21 INFO - 'SLAVEDIR': 'C:\\slave\\',
20:39:21 INFO - 'SYSTEMDRIVE': 'C:',
20:39:21 INFO - 'SYSTEMROOT': 'C:\\windows',
20:39:21 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:21 INFO - 'TEST1': 'testie',
20:39:21 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:21 INFO - 'USERDOMAIN': 'T-W732-IX-027',
20:39:21 INFO - 'USERNAME': 'cltbld',
20:39:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
20:39:21 INFO - 'WINDIR': 'C:\\windows',
20:39:21 INFO - 'WINDOWS_TRACING_FLAGS': '3',
20:39:21 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:39:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
20:39:21 INFO - Ignoring indexes: https://pypi.python.org/simple/
20:39:21 INFO - Downloading/unpacking blobuploader==1.2.4
20:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
20:39:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
20:39:22 INFO - Downloading blobuploader-1.2.4.tar.gz
20:39:22 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
20:39:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
20:39:22 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
20:39:22 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
20:39:23 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
20:39:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
20:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
20:39:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
20:39:23 INFO - Downloading docopt-0.6.1.tar.gz
20:39:23 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
20:39:23 INFO - Installing collected packages: blobuploader, requests, docopt
20:39:23 INFO - Running setup.py install for blobuploader
20:39:23 INFO - Running setup.py install for requests
20:39:24 INFO - Running setup.py install for docopt
20:39:24 INFO - Successfully installed blobuploader requests docopt
20:39:24 INFO - Cleaning up...
20:39:25 INFO - Return code: 0
20:39:25 INFO - Installing None into virtualenv C:\slave\test\build/venv
20:39:25 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')]}
20:39:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:39:25 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')]}
20:39:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:39:25 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 0x01927640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01961E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0197AE90>, '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 0x0137A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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
20:39:25 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
20:39:25 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
20:39:25 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:39:25 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:39:25 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:39:25 INFO - 'COMPUTERNAME': 'T-W732-IX-027',
20:39:25 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:39:25 INFO - 'CYGWINBASE': 'C:\\cygwin',
20:39:25 INFO - 'DCLOCATION': 'SCL3',
20:39:25 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:39:25 INFO - 'FP_NO_HOST_CHECK': 'NO',
20:39:25 INFO - 'HOMEDRIVE': 'C:',
20:39:25 INFO - 'HOMEPATH': '\\Users\\cltbld',
20:39:25 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
20:39:25 INFO - 'KTS_VERSION': '1.19c',
20:39:25 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:39:25 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027',
20:39:25 INFO - 'MONDIR': 'C:\\Monitor_config\\',
20:39:25 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:39:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:39:25 INFO - 'MOZ_NO_REMOTE': '1',
20:39:25 INFO - 'NO_EM_RESTART': '1',
20:39:25 INFO - 'NUMBER_OF_PROCESSORS': '8',
20:39:25 INFO - 'OS': 'Windows_NT',
20:39:25 INFO - 'OURDRIVE': 'C:',
20:39:25 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;',
20:39:25 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:39:25 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
20:39:25 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:39:25 INFO - 'PROCESSOR_LEVEL': '6',
20:39:25 INFO - 'PROCESSOR_REVISION': '1e05',
20:39:25 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
20:39:25 INFO - 'PROGRAMFILES': 'C:\\Program Files',
20:39:25 INFO - 'PROMPT': '$P$G',
20:39:25 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:39:25 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:39:25 INFO - 'PUBLIC': 'C:\\Users\\Public',
20:39:25 INFO - 'PWD': 'C:\\slave\\test',
20:39:25 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:39:25 INFO - 'SLAVEDIR': 'C:\\slave\\',
20:39:25 INFO - 'SYSTEMDRIVE': 'C:',
20:39:25 INFO - 'SYSTEMROOT': 'C:\\windows',
20:39:25 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:25 INFO - 'TEST1': 'testie',
20:39:25 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:25 INFO - 'USERDOMAIN': 'T-W732-IX-027',
20:39:25 INFO - 'USERNAME': 'cltbld',
20:39:25 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
20:39:25 INFO - 'WINDIR': 'C:\\windows',
20:39:25 INFO - 'WINDOWS_TRACING_FLAGS': '3',
20:39:25 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:39:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
20:39:26 INFO - Ignoring indexes: https://pypi.python.org/simple/
20:39:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
20:39:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vsw83g-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
20:39:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
20:39:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kt6_wm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
20:39:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
20:39:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kin3ik-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
20:39:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
20:39:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y_h5da-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
20:39:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
20:39:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-q_mmny-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
20:39:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
20:39:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xrabdh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
20:39:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
20:39:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-de_lri-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
20:39:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
20:39:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6ohepo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
20:39:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
20:39:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g0kr3k-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
20:39:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
20:39:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8hqvsz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
20:39:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
20:39:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rmkfgr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
20:39:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
20:39:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4mqzv3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
20:39:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
20:39:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lkihyy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
20:39:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
20:39:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dlffr7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
20:39:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
20:39:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nzqiij-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
20:39:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
20:39:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5esvuv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
20:39:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
20:39:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oxfqgs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
20:39:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
20:39:28 INFO - Running setup.py install for manifestparser
20:39:28 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
20:39:28 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
20:39:28 INFO - Running setup.py install for mozcrash
20:39:29 INFO - Running setup.py install for mozdebug
20:39:29 INFO - Running setup.py install for mozdevice
20:39:29 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
20:39:29 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
20:39:29 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
20:39:29 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
20:39:30 INFO - Running setup.py install for mozfile
20:39:30 INFO - Running setup.py install for mozhttpd
20:39:30 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
20:39:30 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
20:39:31 INFO - Running setup.py install for mozinfo
20:39:31 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
20:39:31 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
20:39:31 INFO - Running setup.py install for mozInstall
20:39:31 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
20:39:31 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
20:39:31 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
20:39:31 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
20:39:31 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
20:39:31 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
20:39:31 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
20:39:31 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
20:39:31 INFO - Running setup.py install for mozleak
20:39:32 INFO - Running setup.py install for mozlog
20:39:32 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
20:39:32 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
20:39:33 INFO - Running setup.py install for moznetwork
20:39:33 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
20:39:33 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
20:39:33 INFO - Running setup.py install for mozprocess
20:39:34 INFO - Running setup.py install for mozprofile
20:39:34 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
20:39:34 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
20:39:34 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
20:39:34 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
20:39:34 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
20:39:34 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
20:39:34 INFO - Running setup.py install for mozrunner
20:39:34 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
20:39:34 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
20:39:35 INFO - Running setup.py install for mozscreenshot
20:39:35 INFO - Running setup.py install for moztest
20:39:36 INFO - Running setup.py install for mozversion
20:39:36 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
20:39:36 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
20:39:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
20:39:36 INFO - Cleaning up...
20:39:36 INFO - Return code: 0
20:39:36 INFO - Installing None into virtualenv C:\slave\test\build/venv
20:39: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')]}
20:39:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:39: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')]}
20:39:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:39:36 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 0x01927640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01961E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0197AE90>, '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 0x0137A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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
20:39:36 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
20:39:36 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
20:39:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:39:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:39:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:39:36 INFO - 'COMPUTERNAME': 'T-W732-IX-027',
20:39:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:39:36 INFO - 'CYGWINBASE': 'C:\\cygwin',
20:39:36 INFO - 'DCLOCATION': 'SCL3',
20:39:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:39:36 INFO - 'FP_NO_HOST_CHECK': 'NO',
20:39:36 INFO - 'HOMEDRIVE': 'C:',
20:39:36 INFO - 'HOMEPATH': '\\Users\\cltbld',
20:39:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
20:39:36 INFO - 'KTS_VERSION': '1.19c',
20:39:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:39:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027',
20:39:36 INFO - 'MONDIR': 'C:\\Monitor_config\\',
20:39:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:39:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:39:36 INFO - 'MOZ_NO_REMOTE': '1',
20:39:36 INFO - 'NO_EM_RESTART': '1',
20:39:36 INFO - 'NUMBER_OF_PROCESSORS': '8',
20:39:36 INFO - 'OS': 'Windows_NT',
20:39:36 INFO - 'OURDRIVE': 'C:',
20:39:36 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
20:39:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:39:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
20:39:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:39:36 INFO - 'PROCESSOR_LEVEL': '6',
20:39:36 INFO - 'PROCESSOR_REVISION': '1e05',
20:39:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
20:39:36 INFO - 'PROGRAMFILES': 'C:\\Program Files',
20:39:36 INFO - 'PROMPT': '$P$G',
20:39:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:39:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:39:36 INFO - 'PUBLIC': 'C:\\Users\\Public',
20:39:36 INFO - 'PWD': 'C:\\slave\\test',
20:39:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:39:36 INFO - 'SLAVEDIR': 'C:\\slave\\',
20:39:36 INFO - 'SYSTEMDRIVE': 'C:',
20:39:36 INFO - 'SYSTEMROOT': 'C:\\windows',
20:39:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:36 INFO - 'TEST1': 'testie',
20:39:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:36 INFO - 'USERDOMAIN': 'T-W732-IX-027',
20:39:36 INFO - 'USERNAME': 'cltbld',
20:39:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
20:39:36 INFO - 'WINDIR': 'C:\\windows',
20:39:36 INFO - 'WINDOWS_TRACING_FLAGS': '3',
20:39:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:39:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
20:39:36 INFO - Ignoring indexes: https://pypi.python.org/simple/
20:39:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
20:39:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9lwkvz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
20:39:37 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))
20:39:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
20:39:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gyw2v_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
20:39:37 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))
20:39:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
20:39:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cb1pui-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
20:39:37 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))
20:39:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
20:39:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kwdjbf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
20:39:37 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))
20:39:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
20:39:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-m0b0x9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
20:39:37 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))
20:39:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
20:39:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-unzdjv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
20:39:37 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))
20:39:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
20:39:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0eso08-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
20:39:37 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))
20:39:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
20:39:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w5vefd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
20:39:38 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))
20:39:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
20:39:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5v7kbx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
20:39:38 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))
20:39:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
20:39:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ctfy8l-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
20:39:38 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))
20:39:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
20:39:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ts_6ia-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
20:39:38 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))
20:39:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
20:39:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tzkupq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
20:39:38 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))
20:39:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
20:39:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-deznu9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
20:39:38 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))
20:39:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
20:39:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eecxx3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
20:39:38 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))
20:39:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
20:39:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ksrn1x-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
20:39:39 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))
20:39:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
20:39:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8rwmif-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
20:39:39 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))
20:39:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
20:39:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gsbw5r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
20:39:39 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))
20:39:39 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))
20:39:39 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))
20:39:39 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))
20:39:39 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))
20:39:39 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))
20:39:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
20:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
20:39:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
20:39:40 INFO - Downloading blessings-1.6.tar.gz
20:39:40 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
20:39:41 INFO - Installing collected packages: blessings
20:39:41 INFO - Running setup.py install for blessings
20:39:41 INFO - Successfully installed blessings
20:39:41 INFO - Cleaning up...
20:39:44 INFO - Return code: 0
20:39:44 INFO - Done creating virtualenv C:\slave\test\build/venv.
20:39:44 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
20:39:44 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
20:39:44 INFO - Reading from file tmpfile_stdout
20:39:44 INFO - Using _rmtree_windows ...
20:39:44 INFO - Using _rmtree_windows ...
20:39:44 INFO - Current package versions:
20:39:44 INFO - blessings == 1.6
20:39:44 INFO - blobuploader == 1.2.4
20:39:44 INFO - docopt == 0.6.1
20:39:44 INFO - functools32 == 3.2.3-2
20:39:44 INFO - jsonschema == 2.5.1
20:39:44 INFO - manifestparser == 1.1
20:39:44 INFO - mozInstall == 1.12
20:39:44 INFO - mozcrash == 0.17
20:39:44 INFO - mozdebug == 0.1
20:39:44 INFO - mozdevice == 0.48
20:39:44 INFO - mozfile == 1.2
20:39:44 INFO - mozhttpd == 0.7
20:39:44 INFO - mozinfo == 0.9
20:39:44 INFO - mozleak == 0.1
20:39:44 INFO - mozlog == 3.4
20:39:44 INFO - moznetwork == 0.27
20:39:44 INFO - mozprocess == 0.23
20:39:44 INFO - mozprofile == 0.28
20:39:44 INFO - mozrunner == 6.12
20:39:44 INFO - mozscreenshot == 0.1
20:39:44 INFO - mozsystemmonitor == 0.3
20:39:44 INFO - moztest == 0.7
20:39:44 INFO - mozversion == 1.4
20:39:44 INFO - psutil == 3.1.1
20:39:44 INFO - requests == 1.2.3
20:39:44 INFO - Installing None into virtualenv C:\slave\test\build/venv
20:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:39:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:39:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:39:44 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 0x01927640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01961E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0197AE90>, '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 0x0137A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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
20:39:44 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
20:39:44 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
20:39:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:39:44 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:39:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:39:44 INFO - 'COMPUTERNAME': 'T-W732-IX-027',
20:39:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:39:44 INFO - 'CYGWINBASE': 'C:\\cygwin',
20:39:44 INFO - 'DCLOCATION': 'SCL3',
20:39:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:39:44 INFO - 'FP_NO_HOST_CHECK': 'NO',
20:39:44 INFO - 'HOMEDRIVE': 'C:',
20:39:44 INFO - 'HOMEPATH': '\\Users\\cltbld',
20:39:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
20:39:44 INFO - 'KTS_VERSION': '1.19c',
20:39:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:39:44 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027',
20:39:44 INFO - 'MONDIR': 'C:\\Monitor_config\\',
20:39:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:39:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:39:44 INFO - 'MOZ_NO_REMOTE': '1',
20:39:44 INFO - 'NO_EM_RESTART': '1',
20:39:44 INFO - 'NUMBER_OF_PROCESSORS': '8',
20:39:44 INFO - 'OS': 'Windows_NT',
20:39:44 INFO - 'OURDRIVE': 'C:',
20:39:44 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
20:39:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:39:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
20:39:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:39:44 INFO - 'PROCESSOR_LEVEL': '6',
20:39:44 INFO - 'PROCESSOR_REVISION': '1e05',
20:39:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
20:39:44 INFO - 'PROGRAMFILES': 'C:\\Program Files',
20:39:44 INFO - 'PROMPT': '$P$G',
20:39:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:39:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:39:44 INFO - 'PUBLIC': 'C:\\Users\\Public',
20:39:44 INFO - 'PWD': 'C:\\slave\\test',
20:39:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:39:44 INFO - 'SLAVEDIR': 'C:\\slave\\',
20:39:44 INFO - 'SYSTEMDRIVE': 'C:',
20:39:44 INFO - 'SYSTEMROOT': 'C:\\windows',
20:39:44 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:44 INFO - 'TEST1': 'testie',
20:39:44 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:44 INFO - 'USERDOMAIN': 'T-W732-IX-027',
20:39:44 INFO - 'USERNAME': 'cltbld',
20:39:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
20:39:44 INFO - 'WINDIR': 'C:\\windows',
20:39:44 INFO - 'WINDOWS_TRACING_FLAGS': '3',
20:39:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:39:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
20:39:44 INFO - Ignoring indexes: https://pypi.python.org/simple/
20:39:44 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))
20:39:44 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))
20:39:44 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))
20:39:44 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))
20:39:44 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))
20:39:44 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))
20:39:44 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))
20:39:44 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))
20:39:44 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))
20:39:44 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))
20:39:44 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))
20:39:44 INFO - Cleaning up...
20:39:44 INFO - Return code: 0
20:39:44 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
20:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:39:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:39:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:39:44 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 0x01927640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01961E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0197AE90>, '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 0x0137A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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
20:39:44 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
20:39:44 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
20:39:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:39:44 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:39:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:39:44 INFO - 'COMPUTERNAME': 'T-W732-IX-027',
20:39:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:39:44 INFO - 'CYGWINBASE': 'C:\\cygwin',
20:39:44 INFO - 'DCLOCATION': 'SCL3',
20:39:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:39:44 INFO - 'FP_NO_HOST_CHECK': 'NO',
20:39:44 INFO - 'HOMEDRIVE': 'C:',
20:39:44 INFO - 'HOMEPATH': '\\Users\\cltbld',
20:39:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
20:39:44 INFO - 'KTS_VERSION': '1.19c',
20:39:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:39:44 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027',
20:39:44 INFO - 'MONDIR': 'C:\\Monitor_config\\',
20:39:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:39:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:39:44 INFO - 'MOZ_NO_REMOTE': '1',
20:39:44 INFO - 'NO_EM_RESTART': '1',
20:39:44 INFO - 'NUMBER_OF_PROCESSORS': '8',
20:39:44 INFO - 'OS': 'Windows_NT',
20:39:44 INFO - 'OURDRIVE': 'C:',
20:39:44 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
20:39:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:39:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
20:39:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:39:44 INFO - 'PROCESSOR_LEVEL': '6',
20:39:44 INFO - 'PROCESSOR_REVISION': '1e05',
20:39:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
20:39:44 INFO - 'PROGRAMFILES': 'C:\\Program Files',
20:39:44 INFO - 'PROMPT': '$P$G',
20:39:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:39:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:39:44 INFO - 'PUBLIC': 'C:\\Users\\Public',
20:39:44 INFO - 'PWD': 'C:\\slave\\test',
20:39:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:39:44 INFO - 'SLAVEDIR': 'C:\\slave\\',
20:39:44 INFO - 'SYSTEMDRIVE': 'C:',
20:39:44 INFO - 'SYSTEMROOT': 'C:\\windows',
20:39:44 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:44 INFO - 'TEST1': 'testie',
20:39:44 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:44 INFO - 'USERDOMAIN': 'T-W732-IX-027',
20:39:44 INFO - 'USERNAME': 'cltbld',
20:39:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
20:39:44 INFO - 'WINDIR': 'C:\\windows',
20:39:44 INFO - 'WINDOWS_TRACING_FLAGS': '3',
20:39:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:39:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
20:39:45 INFO - Ignoring indexes: https://pypi.python.org/simple/
20:39:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
20:39:45 INFO - Cleaning up...
20:39:45 INFO - Return code: 0
20:39:45 INFO - Running post-action listener: _resource_record_post_action
20:39:45 INFO - Running post-action listener: _start_resource_monitoring
20:39:45 INFO - Starting resource monitoring.
20:39:45 INFO - [mozharness: 2016-11-05 03:39:45.496000Z] Finished create-virtualenv step (success)
20:39:45 INFO - [mozharness: 2016-11-05 03:39:45.497000Z] Running install step.
20:39:45 INFO - Running pre-action listener: _resource_record_pre_action
20:39:45 INFO - Running main action method: install
20:39:45 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
20:39:45 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
20:39:45 INFO - Reading from file tmpfile_stdout
20:39:45 INFO - Using _rmtree_windows ...
20:39:45 INFO - Using _rmtree_windows ...
20:39:45 INFO - Detecting whether we're running mozinstall >=1.0...
20:39:45 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
20:39:45 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
20:39:45 INFO - Reading from file tmpfile_stdout
20:39:45 INFO - Output received:
20:39:45 INFO - Usage: mozinstall-script.py [options] installer
20:39:45 INFO - Options:
20:39:45 INFO - -h, --help show this help message and exit
20:39:45 INFO - -d DEST, --destination=DEST
20:39:45 INFO - Directory to install application into. [default:
20:39:45 INFO - "C:\slave\test"]
20:39:45 INFO - --app=APP Application being installed. [default: firefox]
20:39:45 INFO - Using _rmtree_windows ...
20:39:45 INFO - Using _rmtree_windows ...
20:39:45 INFO - mkdir: C:\slave\test\build\application
20:39:45 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']
20:39:45 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
20:39:48 INFO - Reading from file tmpfile_stdout
20:39:48 INFO - Output received:
20:39:48 INFO - C:\slave\test\build\application\firefox\firefox.exe
20:39:48 INFO - Using _rmtree_windows ...
20:39:48 INFO - Using _rmtree_windows ...
20:39:48 INFO - Running post-action listener: _resource_record_post_action
20:39:48 INFO - [mozharness: 2016-11-05 03:39:48.201000Z] Finished install step (success)
20:39:48 INFO - [mozharness: 2016-11-05 03:39:48.201000Z] Running run-tests step.
20:39:48 INFO - Running pre-action listener: _resource_record_pre_action
20:39:48 INFO - Running main action method: run_tests
20:39:48 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
20:39:48 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
20:39:48 INFO - Python 2.7.3
20:39:48 INFO - Return code: 0
20:39:48 INFO - grabbing minidump binary from tooltool
20:39: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')]}
20:39:48 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0197AE90>, '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 0x0137A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019E7520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
20:39:48 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build
20:39:48 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o
20:39:48 INFO - Calling ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] with output_timeout 600
20:39:48 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
20:39:49 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpzdjgbq
20:39:49 INFO - INFO - File integrity verified, renaming tmpzdjgbq to win32-minidump_stackwalk.exe
20:39:49 INFO - Return code: 0
20:39:49 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
20:39:49 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
20:39:49 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
20:39:49 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
20:39:49 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
20:39:49 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-027', '--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
20:39:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-027 --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
20:39:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:39:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:39:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:39:49 INFO - 'COMPUTERNAME': 'T-W732-IX-027',
20:39:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:39:49 INFO - 'CYGWINBASE': 'C:\\cygwin',
20:39:49 INFO - 'DCLOCATION': 'SCL3',
20:39:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:39:49 INFO - 'FP_NO_HOST_CHECK': 'NO',
20:39:49 INFO - 'HOMEDRIVE': 'C:',
20:39:49 INFO - 'HOMEPATH': '\\Users\\cltbld',
20:39:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
20:39:49 INFO - 'KTS_VERSION': '1.19c',
20:39:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:39:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027',
20:39:49 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
20:39:49 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
20:39:49 INFO - 'MONDIR': 'C:\\Monitor_config\\',
20:39:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:39:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:39:49 INFO - 'MOZ_NO_REMOTE': '1',
20:39:49 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
20:39:49 INFO - 'NO_EM_RESTART': '1',
20:39:49 INFO - 'NUMBER_OF_PROCESSORS': '8',
20:39:49 INFO - 'OS': 'Windows_NT',
20:39:49 INFO - 'OURDRIVE': 'C:',
20:39:49 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
20:39:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:39:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
20:39:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:39:49 INFO - 'PROCESSOR_LEVEL': '6',
20:39:49 INFO - 'PROCESSOR_REVISION': '1e05',
20:39:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
20:39:49 INFO - 'PROGRAMFILES': 'C:\\Program Files',
20:39:49 INFO - 'PROMPT': '$P$G',
20:39:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:39:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:39:49 INFO - 'PUBLIC': 'C:\\Users\\Public',
20:39:49 INFO - 'PWD': 'C:\\slave\\test',
20:39:49 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
20:39:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:39:49 INFO - 'SLAVEDIR': 'C:\\slave\\',
20:39:49 INFO - 'SYSTEMDRIVE': 'C:',
20:39:49 INFO - 'SYSTEMROOT': 'C:\\windows',
20:39:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:49 INFO - 'TEST1': 'testie',
20:39:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:39:49 INFO - 'USERDOMAIN': 'T-W732-IX-027',
20:39:49 INFO - 'USERNAME': 'cltbld',
20:39:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
20:39:49 INFO - 'WINDIR': 'C:\\windows',
20:39:49 INFO - 'WINDOWS_TRACING_FLAGS': '3',
20:39:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:39:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
20:39:49 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-027', '--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
20:39:50 INFO - mozversion application_buildid: 20161104185722
20:39:50 INFO - mozversion application_changeset: f092853e564086a98e14f0018ce784bb033e1d85
20:39:50 INFO - mozversion application_display_name: Nightly
20:39:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
20:39:50 INFO - mozversion application_name: Firefox
20:39:50 INFO - mozversion application_remotingname: firefox
20:39:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
20:39:50 INFO - mozversion application_vendor: Mozilla
20:39:50 INFO - mozversion application_version: 52.0a1
20:39:50 INFO - mozversion platform_buildid: 20161104185722
20:39:50 INFO - mozversion platform_changeset: f092853e564086a98e14f0018ce784bb033e1d85
20:39:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
20:39:50 INFO - mozversion platform_version: 52.0a1
20:39:50 INFO - using testdate: 1478317190
20:39:50 INFO - actual date: 1478317190
20:39:50 INFO - starting webserver on 'localhost:49896'
20:39:50 INFO - SUITE-START | Running 2 tests
20:39:50 INFO - TEST-START | dromaeo_css
20:39:50 INFO - operating with platform_type : w7_
20:39:50 INFO - Initialising browser for dromaeo_css test...
20:39:50 INFO - TEST-INFO | started process 448 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmparwi5a\profile http://localhost:49896/getInfo.html)
20:40:00 INFO - PROCESS | 448 | __metrics Screen width/height:1600/1200
20:40:00 INFO - PROCESS | 448 | colorDepth:24
20:40:00 INFO - PROCESS | 448 | Browser inner width/height: 1010/674
20:40:00 INFO - PROCESS | 448 | __metrics
20:40:02 INFO - PROCESS | 448 | 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"
20:40:02 INFO - PROCESS | 448 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
20:40:04 INFO - TEST-INFO | 448: exit 0
20:40:04 INFO - Browser initialized.
20:40:04 INFO - Running cycle 1/1 for dromaeo_css test...
20:40:04 INFO - TEST-INFO | started process 1964 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmparwi5a\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
20:40:05 INFO - PROCESS | 1964 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
20:40:16 INFO - PROCESS | 1964 | RSS: Main: 138330112
20:40:16 INFO - PROCESS | 1964 |
20:41:07 INFO - PROCESS | 1964 | 1478317267986 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]
20:43:23 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49896/tests/dromaeo/cssquery-ext.html)
20:43:26 INFO - PROCESS | 1964 | RSS: Main: 280891392
20:43:26 INFO - PROCESS | 1964 |
20:46:28 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-ext.html (next: http://localhost:49896/tests/dromaeo/cssquery-jquery.html)
20:46:29 INFO - PROCESS | 1964 | RSS: Main: 209371136
20:46:29 INFO - PROCESS | 1964 |
20:49:31 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49896/tests/dromaeo/cssquery-mootools.html)
20:49:32 INFO - PROCESS | 1964 | RSS: Main: 149270528
20:49:32 INFO - PROCESS | 1964 |
20:52:34 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49896/tests/dromaeo/cssquery-prototype.html)
20:52:35 INFO - PROCESS | 1964 | RSS: Main: 144736256
20:52:35 INFO - PROCESS | 1964 |
20:55:38 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49896/tests/dromaeo/cssquery-yui.html)
20:55:39 INFO - PROCESS | 1964 | RSS: Main: 142475264
20:55:39 INFO - PROCESS | 1964 |
20:58:41 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-yui.html (next: http://localhost:49896/tests/dromaeo/cssquery-dojo.html)
20:58:42 INFO - PROCESS | 1964 | RSS: Main: 187674624
20:58:42 INFO - PROCESS | 1964 |
20:58:42 INFO - PROCESS | 1964 | __start_tp_report
20:58:42 INFO - PROCESS | 1964 | _x_x_mozilla_page_load
20:58:42 INFO - PROCESS | 1964 | _x_x_mozilla_page_load_details
20:58:42 INFO - PROCESS | 1964 | |i|pagename|runs|
20:58:42 INFO - PROCESS | 1964 | |0;dojo.html;2349.71803383594;2371.573116838969;2376.7979721723655;2395.54484647917;2427.490227052319;4077.633013028827;4162.167566486707;4237.194932962735;4239.3852891330735;4250.511191213011;4054.959450405495;4082.8571000015004;4120.299549076657;4223.514295855977;4256.21260066887;3536.2220311531423;3618.837152328145;3620.0768803954957;3628.183658676803;3638.381475149231;3610.2598967211657;3645.198056427595;3649.525561676978;3662;3667.6332366763377;3615.0600843780676;3649.1424515238896;3657.963420365802;3662.450632405134;3673.265346930611;3602.423612222058;3604.4593311003428;3667.064898450905;3678;3688.2254726507467;3381.2054167270917;3496.7727097738566;3514.016075498864;3516.5604299462566;3535.840887160058;4045.3122969095325;4125.133721918401;4157.729747566429;4161.0221597924465;4162.438070860426;4313.784310784448;4316.978415107905;4380.277254253045;4380.54004329544;4398.7140835845885;4359.498657654347;4441.244988351988;4445.51099379068;4447.288433850601;4448.088141930896;4000.7999600020175;4020.7587544747407;4031.9113839263237;4045.3931910213114;4112.465379500646;4473.843415480507;4505.459344878635;4511;4515.638748900145;4521.773911304422;4275.46556680415;4281.571842815693;4344.869653910388;4348.04343044536;4363.36731173978;4254.532001479834;4287.356896465492;4396.384506169139;4402.823887044547;4418.734875907456;4177.26897792885;4320.697551171388;4322.783860806947;4324.762138082373;4341.782910854445;4323.762193079411;4363.6072753452345;4399.406080179135;4415.116976604692;4418.315161150027;3067.9233019174253;3088.7992280501694;3118.0489950565297;3127.9843600782306;3130.0453361724894;2773.66715994082;2799.1602519244148;2809.578563215493;2823.9294017649313;2845.2175651695948;4006.09862780872;4067.206894655573;4074.30736774749;4077.918441631151;4078.0416602098485;4342.5874541918465;4344.1311737652595;4370.147821174841;4373.956260437355;4385.693001489865;4045.514538255369;4067.89830254246;4069.654079403284;4072.0430698785763;4082.9591704082577;3709.740318177756;3741.9251614968093;3741.9438708419398;3758.9436158457647;3796.7532110412735;4486.820527178877;4491.528389519053;4558.339040839126;4565.520620334817;4566.520515345974;4302.978485107555;4379.189849877783;4467.128909862546;4475.7985890635655;4478.641708663234;4393.890152746079;4433.7118087324225;4439;4440.2673558862425;4452.1763473757455;4303.182395344875;4352.695311328177;4362.6509879210225;4374.387585738063;4378.387025816324;4302.22559939214;4321.049369138785;4402.955970440383;4404.163208990281;4414.095110502403;4105.58944105587;4132.987418082469;4134.255833949918;4137.172565486854;4179.728317659343;4150.750954942713;4160.604742549453;4168.4997800262945;4177.832886684499;4178.080822219107;3964.7026473015217;4070.9796451017555;4075.7758323292505;4076.9796151019054;4079.816408261576;4208.936865946952;4214.367844823301;4218.029853133735;4219.810108545184;4225.894352641209;4147.52303485091;4182.059036716714;4222.429971953869;4227.365895115634;4240.406343111905
20:58:42 INFO - PROCESS | 1964 | |1;ext.html;508.3645443196005;526.4603781124348;530.9309789727334;557.2699763310062;558.555948021323;24443.877780611096;34654;35133;36718.265634687355;36892;34629.30741385171;34645.13387165322;34915.82542087286;34939;34951.300973980506;6875.484338674594;7007.159140903073;7017.75437859675;7023.086998690163;7047.577145371269;6909.48178886583;6950.443964482854;6976.825579360531;6995.755148569801;7002.89495657563;7001.159860816683;7005.159380874302;7038.120234970629;7039.824004399906;7048.04851345067;6834.316568343175;6951.89572156418;6968.721251149948;6991.300869913019;6998.090248267744;6904.344087311697;6919.481038922051;6998.020277161201;6999.580025198505;7002.859942801167;31314.21714457134;31645.683543164734;31653.89211377614;31723;31831.045068647978;2549.005887703797;2596.415806443554;2626.8949242030294;2813.8030337876357;2871.1673614651727;23638.64542031854;24467.265982020566;24688.876555617288;25564.233073007654;25952.740472595222;2444.4499987502845;2598.584226523766;2631.7236690147643;2718.429129882727;2721.7414345637135;192619;193211;195709;196184;196787;43447.78276108599;43611.12777744427;43720.78139609282;43746.12507749827;43888;45702.314465283045;45722;45784.313235301495;48401;52975.73512132492;9257.67598134062;9718.271129665303;9740.902590974;9749.220062394992;9761.609535618638;7006.719731210796;7050.753223637148;7103.325184107502;7188.281171882874;7196.892046619305;15543.766843497357;15693.21533923322;15747.68504629901;15781;15859.286332115082;6445.774397896145;6555.2133743951035;6558;6588.934110658832;6604.669766511655;735.6280536798831;832.8334333133398;868.3400615532241;909.2226146644656;920.2545937790411;21647;22086.226982055552;22252.554948901256;22321.21875734374;22393.55212895733;822.5599304372251;827.6399766101765;857.1342943626858;869.4652788535005;917.3532659475054;11722.58970936013;13009.674758130934;14038.929805350908;14114.64713382153;14120.293985300694;293121;297772;297909;298791;299590;41194.38208426876;42023;42382.78808605937;42422.36366454505;42449;1036.4765793274348;1038.7558923652934;1043.5251960358019;1059.8516207730925;1060.9045185933458;23486.76513234914;24184.758152418955;24623.753762462147;24742.752572474044;25027.74972250327;35542;35863.46204806982;36017.819910900274;36125.0968725784;36129.819350903075;226714;254748;257627;263211;263248;20399.4900127498;20637.58724825465;20676.68984965257;20918.16327346889;20934.895325523274;34743.65256347404;34976.30047398987;35268;35648.287034258654;35895;28411.289717756394;30247;30811.229719257197;31051;31554.26685997027;29333.559996600496;30213;30495.695043049287;30780.84609576938;31189;27588.586171207047;29180.562291566068;29493.852530738066;29716.257093572833;29855.253618659706;26789.185191847202;26804;28079.014715290912;28552.714472855005;30349.544756828225;21754;22110.33668989933;22220;22322.21872234464;22542.887285564124
20:58:42 INFO - PROCESS | 1964 | |2;jquery.html;266.25847016060277;288.5700306543251;296.4234563773461;297.546241980979;307.55558218374443;11039.834402483959;11901.345426001566;11906.40467976601;14781.260936953136;15004.099754014767;13618.863811361884;14368.928155359235;14490.710185796304;14971.176585287805;15173.468928587501;4285.657147428206;4394.978025109886;4400.119976004796;4403.207422663935;4410.80151393188;3912.3153448146604;4132.173565286939;4406.669499787513;4422.491413487442;4425.159219748238;4367;4418.293073108303;4424.513303536608;4425.4910685271125;4427.1367083418745;4192.266353388145;4391.231534481485;4392.099619577979;4394.956050439487;4395.912081758363;4166;4182.163567286524;4235.745855248664;4239.046214601721;4254.510731265913;10237.334573252789;10547.367157970546;10680.839787403118;10682.14542836579;10690.78618427627;1502.8346881842988;1515.8256800467866;1526.114853385034;1586.2623879895905;1640.7949006374204;7845.254700803472;8101.716439924574;8459;8472.237498625092;8536.317094632415;1449.1522459361286;1453.1281231261264;1462.0788902991048;1488.739470592642;1503.5789978805951;56198.43801561973;56205.437945620426;57766.422335776944;61097;61580;8405.41162118658;8427.831443371098;8471.364647651451;8794.86807697885;8846.15961483658;10224.488775561193;11602.245854019462;11628.360440175702;11651.825222621668;11666.183367164387;4905.681130726492;5272.420033796279;5531.3362396511875;5537.473939975695;5554.2779438672715;5045.142325804622;5094.235864620263;5114.948850511521;5205.531502164823;5225.451327610622;3414.3683418567643;3486.093615659947;3562.5012498250267;3565.7860528368383;3698.7965661888857;1510.2901636230954;1600.2078970909242;1608.5656872644554;1614.1606364690294;1618.2636900210375;754.9584772837546;773.9883901741479;798.3932211519287;844.9860167798544;863.8488264553804;14740.157480314962;14900.403983840524;15323.923380383027;15385.923070384577;15722.29249683767;676.8984652302194;712.8574285142992;724.1310427487036;727.3853593713304;744.2334395572569;8456.746297611082;8507.65969361231;8604.8709269361;8852.601632926533;9366.344355895159;265495;273854;274557;275962;276863;62878;65744.6712766433;67048;72536;73488.63255683794;761.0828951113895;794.0233512779198;795.9323457506061;796.9322607578421;799.2367289238763;13994.37025333842;14323.498677546237;14353.210573418562;14508.85491145075;14723.484678036217;35542.111447214025;35854.82072589725;36704.08239794026;36936.815315923246;37034;35350.46974295336;44830.96521313098;46265.76867115643;49700;49958.25062624136;6348.460380867678;6461.644609546332;6494.642794646151;6512.837179070561;6538.869222615616;8591.269742071994;9671.274654401088;9750.268729845431;10065.094141527297;10358.223133265186;6676.799696009128;8322.417430779788;8523.48859068458;8633.611487482953;8985.685501007434;8524.48853068793;8628.52543110135;8830.222577103556;8904.109589041043;9170.495622740813;8427.957860210905;8517.48895066298;8537.274331681876;8588.699395521127;8884.422512536665;6336.904946425715;6393.424591786761;6458.838529036811;6501.902471462838;6720.361565651256;6268.937310626835;8271.83456330858;8507.191816777397;8569.48583085017;8901.866472002795
20:58:42 INFO - PROCESS | 1964 | |3;mootools.html;1161.5992482593508;1171.6660751685772;1236.8268442418062;1429.2353590828902;1466.860648238417;5232.555232805216;5505.256790333304;5918.3489816120245;6323.083152942826;6338.049292606106;5520.585956053302;5556.805511807088;5608.186812912136;5608.635438696482;5799.246098007264;1745.7817772778435;1774.3346245158066;1775.1213149490954;1775.8224177582267;1795.0306834309522;1781.2607767776421;1813.4468986958973;1816.9182386792627;1823.4256209294097;1825.434115424216;1520.7034628247495;1571.4499925026264;1704.81247062823;1757.1214392803597;1757.2882982392089;1758.7098128808732;1768;1771.8405343519146;1772.929082836698;1773.202059073422;1660.178211785162;1660.3524625396105;1698.4734732233048;1700.948971530856;1766.8233176682359;6025.15647809307;6044.093385992092;6083.939160608382;6093.542984276197;6108.450239478469;3525.5416795816636;3553.680168784822;3610.765300255501;3610.9097272568133;3620.149264922741;2209.3703294560974;2348.2602980061224;2359.8466099703464;2379.1316169598144;2379.5835728747575;685.0329379505375;705.8447141628833;716.0262043620723;722.2019668266543;728.0498948871721;7323.890141647879;7441.776746697554;7463.14173870001;7547.660355283971;7807.726729564495;3362.882299119519;3401.846916888746;3548.467729840493;3559.750817442754;3572.231970126383;6360;6408.967955160288;6417.294097649255;6423.454006409414;6433.967830160913;3548.2193917338145;3575.5173051638217;3577.44549594808;3578.4453409721536;3581.5702115746276;3297.225152089257;3323.5513205717402;3332.3835090508333;3332.583427071616;3341.2482191506715;2437.854208521992;2449.9877500612383;2471.6663250461315;2613.8954441822116;2630.3292660371867;826.5990994367727;846.6740304982626;850.965961361551;854.0690647194464;859.4757198109148;605.4157737783061;609.5793902207552;690.4028015766427;705.8552996635677;715.7745310227261;4076.490438695163;4088.264112459726;4099.344104943195;4118.5263694674895;4242.936355954663;748.9213632568502;756.43267549338;769.4190885881123;779.5556532776263;821.6138414944967;2687.8118531702985;2757.6139340492346;2822.7600653944646;2825.9858700706363;2836.248394175545;6860.245373008965;7378.557286562824;7387.889181662279;7403.7408690695565;7407.6666550005375;6597.241317248482;6788.8302792429595;6921.861562768817;6928.584284942919;7210.098737657793;5467.835964921059;5792.797252096157;5805.129230615357;5892.292924849045;5900.43945825146;3998.320285551405;4064.4716186895516;4068.6541643960595;4081;4144.378343248537;6856.588604683735;6875.4155896749335;6934.618595977269;6959.547629404073;6962.686679099554;6948.33990770876;7015.754448594274;7017.649117544204;7031.332023457763;7038.929610703827;5118.923216151687;5189.662671926313;5326.627136100436;5326.760295786777;5423.674579525241;5937.7921772737745;6095.8780824382375;6099.84750381244;6100.23747031621;6105.145279660762;5375.865603359947;5415.241866138665;5420.430854760351;5420.810271640474;5433.538149257357;5029.44676085623;5150.690958542499;5174.534291913746;5229.581633469389;5328.8401347959625;4788.4253889533475;4820.517948205152;4837.298591704253;4842.498825364149;4845.224764037737;3391.609964853975;3482.512448257297;3487.47687846825;3506.01831487184;3538;3494.109002204422;3523.788572685647;3534.151803567177;3571.9642803571633;3574.6425357464045
20:58:42 INFO - PROCESS | 1964 | |4;prototype.html;235.67476881902977;236.69348194088653;240.16063856820415;240.21689292905123;249.0982642832946;4302.483701955765;4341.804618792154;4387.7147985380925;4400.471943366801;4410.933835992462;4134.173165366924;4171.916561668765;4265.573442655733;4326.30779075348;4350.847720329789;3172.7779055466126;3183.5065564837487;3197.5363572282004;3227.757918156136;3229.289556297611;3219.6297425795983;3220.1788543921384;3223.081421794789;3225.919352016195;3232.8383580820982;2972.3758010817755;3038.4075105354464;3093.4122516721864;3132.9843350783212;3192.696693814084;3178.507331363631;3182.9045128646176;3194.4409728297455;3198.5362122492343;3210.213497693057;2736.698963114056;2765.9723402765917;2780.42905758248;2782.664321125871;2810.676772171206;4200.306949353353;4235.682323825695;4238.131183107455;4242.384854196124;4265.957340426618;3688.686461650763;3714.201446688975;3730.08612889841;3768.4347347898033;3778.981105094457;4196.3705444183315;4439.400680908068;4440.666949978765;4501.977490112561;4516.41286632739;3112.9688703113134;3200.0879749271344;3211.3898359311656;3222.5971753530807;3236.223306406469;4616.907661846744;4690.835820746258;4692.929606055912;4710.010897711485;4732.242841145435;3246.7240284576073;3293.374258890803;3296.7362610991063;3314.93370132596;3315.353506066294;3321;3324.900252992414;3334.7665663403814;3349.2631621043333;3357.2949680567353;3602.189507360823;3810.8475660973804;3820.522434695663;3825.311443940062;3829.6744776694295;4532.161550113239;4551.362809206714;4553.2942393929;4558.8632341029825;4573.931391029138;1099.6646022962914;1121.315997241682;1125.2685754259796;1127.5567273889435;1132.881047490018;720.5820624038046;734.3794493652855;736;741.3735393592406;764.399946042354;4123.422720819088;4152;4163.834126444601;4166.6250037496775;4186.330187170038;4439.445069366329;4469.284914413678;4481.596656300949;4484.170428470743;4490.438695163105;4101.610347017028;4139.937900931488;4160.3343465046055;4161.313383291723;4179.185058913483;2323.8838058097026;2387.248016874679;2405;2411.2043025801445;2411.78293953545;4516.774161291923;4607.470140933769;4613.008203236321;4618.960733834861;4639.837605683785;4588.738097023345;4598.931016034762;4611.377464042312;4612.74629895359;4618.676692631483;4584.129015487047;4621.560951709582;4625.676202665781;4643.512431194676;4645.744484053342;4311.8706438806885;4456.376107345039;4502.6623003275545;4511.390962220058;4538.6187290468815;4581.679282450196;4622.399088118523;4633.513481084572;4636.235021221595;4650;4757;4802.351682522814;4810.58087864074;4810.831620893252;4818.747125747261;3093.6906309368883;3153.290509635314;3157.25804435962;3158.8104713717253;3160.241542029942;4665.580097791215;4680.274557443602;4687.460941991714;4700.106979673851;4703.365045718784;4266.914661706686;4484.148011877733;4490.461144662661;4513.097380523974;4540.387047748512;4493.618042466427;4497.77511124449;4497.910041799211;4505.189065968026;4520.525344838876;4394.890127746832;4403.691741578059;4417.447819022622;4422.535633758409;4425.579569940851;4519.864404067883;4543.409356783597;4559.179347717442;4564.383808185985;4575.771211439482;4166.979090122818;4243.5544267852665;4456.933146002748;4465.821367145408;4490.438695163105
20:58:42 INFO - PROCESS | 1964 | |5;yui.html;510.75739023963615;513.4044508370291;554.254527660297;581.3227589857815;589;10703.090237329827;11184.273022253548;11190.44047797611;11214.887851121486;11216.383098929566;11147.777044459106;11715.121365897548;11993;11999.10006749493;12010.519579216822;452.5248489086454;635.4154178156108;636.5639536917204;637.1494055435999;640.1390130274773;591.5001823459191;612.3172662481323;624.9156363890885;627.0093252660785;627.53248829622;659.6701649175412;661.7981515637729;663.8970959501261;663.9402453779159;665.1021121485979;654.8428377189488;655.6328456064596;655.8884989551788;656.3928366261189;656.8981807819796;482.8358358158243;483.1858318732946;483.52855965433423;483.87661146407794;483.91289567877766;10608.09831164352;10657;10661.626843060456;10672.839907401318;10684.145268378588;10886.836697449544;11033.172512061628;11121.110311175087;11397.829032564518;11439.084873210124;8185.71350002747;8512.063672995966;8752.124787521261;8777;8888.200061994452;2679.2498100531884;2728.7544121029205;2741.8629068546493;2782.7912906531888;2786.7352601502826;46010.76994615039;46024.539754602694;46564;46951.76524117426;47806.282905756445;7882.605869706492;8282.296004839656;8567.143285671384;8634;8952.68665596701;15112.395504179709;15139;15139.243037848064;15177.848221517865;15180.620484487976;5604.243427137366;5665.716714164275;5764.193012978187;5768.451997060272;5843.824685259449;2947.705229477035;3038.0733876167983;3052.5573791800066;3092.335147943203;3187.2031992002;2233.408146841056;2303.6544518322385;2307.4231442139467;2315.617923042713;2322.5006623576014;382.95404551454004;384.33126360133167;385.54505683293985;391.7199202570176;398.9960100399017;474.8290615378461;522.4644739142334;532.0288331730927;568.7980766827799;572.6335145506879;7117.71529138839;7710.53736775784;7784.494007889469;7910.5649189295145;8244.332770523351;398.9122393073519;471.9433667959798;498.99002019959397;529.5710474515655;532.2787622771167;3137.7019183177563;3173.317736686577;3199.328141090397;3210.3579284143266;3265.9875438614104;61191;63898;64314;66977.66511167413;67140.32859671341;40880.59119408827;58060.41939580635;58244.55453959237;58895.70552147212;59633.403665963655;50449;51257.743711281204;53379.73310133425;53687.19469207965;54284.45715542794;6552.443042341454;6566.9014964774615;6662.200535935719;6709.161354830646;6778.084958530535;29643.555346670248;36334.8183259082;36587;36742.63257367499;37366.252674946896;48250;49583.25625115554;51177.48822511876;51635.22547161871;51848;8413.789655258668;8469.576521173793;8627.784305392415;8641.179087986631;9028.864567031631;9393.342466027312;9614.71155865325;9739.269554783561;9766.218702503924;9768.462734549668;9218.677346292847;9231.446113233227;9246.722598322061;9516.809663806824;9532.475713835805;8641.611127499153;8974.596143173443;9195.908040919505;9281.118293762082;9454.385464945031;9076;9198.724038278595;9287.981406042927;9324.580393882155;9332.81334373295;7360.558366497813;7434.7769566913075;7573.35626471734;7878.960605196938;8134.43058985889;7518.661660225193;7684.116326622287;7696;7755.773941154861;8058.3150432213915
20:58:42 INFO - PROCESS | 1964 | __end_tp_report
20:58:42 INFO - PROCESS | 1964 | __start_cc_report
20:58:42 INFO - PROCESS | 1964 | _x_x_mozilla_cycle_collect,2336
20:58:42 INFO - PROCESS | 1964 | __end_cc_report
20:58:42 INFO - PROCESS | 1964 | __startTimestamp1478318322186__endTimestamp
20:58:42 INFO - PROCESS | 1964 |
20:58:42 INFO - PROCESS | 1964 | ------- Summary: start -------
20:58:42 INFO - PROCESS | 1964 | Number of tests: 6
20:58:42 INFO - PROCESS | 1964 |
20:58:42 INFO - PROCESS | 1964 | [#0] dojo.html Cycles:165 Average:4019.32 Median:4168.50 stddev:494.13 (11.9%) stddev-sans-first:477.97
20:58:42 INFO - PROCESS | 1964 | Values: 2349.7 2371.6 2376.8 2395.5 2427.5 4077.6 4162.2 4237.2 4239.4 4250.5 4055.0 4082.9 4120.3 4223.5 4256.2 3536.2 3618.8 3620.1 3628.2 3638.4 3610.3 3645.2 3649.5 3662.0 3667.6 3615.1 3649.1 3658.0 3662.5 3673.3 3602.4 3604.5 3667.1 3678.0 3688.2 3381.2 3496.8 3514.0 3516.6 3535.8 4045.3 4125.1 4157.7 4161.0 4162.4 4313.8 4317.0 4380.3 4380.5 4398.7 4359.5 4441.2 4445.5 4447.3 4448.1 4000.8 4020.8 4031.9 4045.4 4112.5 4473.8 4505.5 4511.0 4515.6 4521.8 4275.5 4281.6 4344.9 4348.0 4363.4 4254.5 4287.4 4396.4 4402.8 4418.7 4177.3 4320.7 4322.8 4324.8 4341.8 4323.8 4363.6 4399.4 4415.1 4418.3 3067.9 3088.8 3118.0 3128.0 3130.0 2773.7 2799.2 2809.6 2823.9 2845.2 4006.1 4067.2 4074.3 4077.9 4078.0 4342.6 4344.1 4370.1 4374.0 4385.7 4045.5 4067.9 4069.7 4072.0 4083.0 3709.7 3741.9 3741.9 3758.9 3796.8 4486.8 4491.5 4558.3 4565.5 4566.5 4303.0 4379.2 4467.1 4475.8 4478.6 4393.9 4433.7 4439.0 4440.3 4452.2 4303.2 4352.7 4362.7 4374.4 4378.4 4302.2 4321.0 4403.0 4404.2 4414.1 4105.6 4133.0 4134.3 4137.2 4179.7 4150.8 4160.6 4168.5 4177.8 4178.1 3964.7 4071.0 4075.8 4077.0 4079.8 4208.9 4214.4 4218.0 4219.8 4225.9 4147.5 4182.1 4222.4 4227.4 4240.4
20:58:42 INFO - PROCESS | 1964 |
20:58:42 INFO - PROCESS | 1964 | [#1] ext.html Cycles:180 Average:38293.44 Median:33230.18 stddev:66177.28 (199.1%) stddev-sans-first:66302.11
20:58:42 INFO - PROCESS | 1964 | Values: 508.4 526.5 530.9 557.3 558.6 24443.9 34654.0 35133.0 36718.3 36892.0 34629.3 34645.1 34915.8 34939.0 34951.3 6875.5 7007.2 7017.8 7023.1 7047.6 6909.5 6950.4 6976.8 6995.8 7002.9 7001.2 7005.2 7038.1 7039.8 7048.0 6834.3 6951.9 6968.7 6991.3 6998.1 6904.3 6919.5 6998.0 6999.6 7002.9 31314.2 31645.7 31653.9 31723.0 31831.0 2549.0 2596.4 2626.9 2813.8 2871.2 23638.6 24467.3 24688.9 25564.2 25952.7 2444.4 2598.6 2631.7 2718.4 2721.7 192619.0 193211.0 195709.0 196184.0 196787.0 43447.8 43611.1 43720.8 43746.1 43888.0 45702.3 45722.0 45784.3 48401.0 52975.7 9257.7 9718.3 9740.9 9749.2 9761.6 7006.7 7050.8 7103.3 7188.3 7196.9 15543.8 15693.2 15747.7 15781.0 15859.3 6445.8 6555.2 6558.0 6588.9 6604.7 735.6 832.8 868.3 909.2 920.3 21647.0 22086.2 22252.6 22321.2 22393.6 822.6 827.6 857.1 869.5 917.4 11722.6 13009.7 14038.9 14114.6 14120.3 293121.0 297772.0 297909.0 298791.0 299590.0 41194.4 42023.0 42382.8 42422.4 42449.0 1036.5 1038.8 1043.5 1059.9 1060.9 23486.8 24184.8 24623.8 24742.8 25027.7 35542.0 35863.5 36017.8 36125.1 36129.8 226714.0 254748.0 257627.0 263211.0 263248.0 20399.5 20637.6 20676.7 20918.2 20934.9 34743.7 34976.3 35268.0 35648.3 35895.0 28411.3 30247.0 30811.2 31051.0 31554.3 29333.6 30213.0 30495.7 30780.8 31189.0 27588.6 29180.6 29493.9 29716.3 29855.3 26789.2 26804.0 28079.0 28552.7 30349.5 21754.0 22110.3 22220.0 22322.2 22542.9
20:58:42 INFO - PROCESS | 1964 |
20:58:42 INFO - PROCESS | 1964 | [#2] jquery.html Cycles:180 Average:19048.56 Median:4425.33 stddev:45804.07 (1035.0%) stddev-sans-first:45910.86
20:58:42 INFO - PROCESS | 1964 | Values: 266.3 288.6 296.4 297.5 307.6 11039.8 11901.3 11906.4 14781.3 15004.1 13618.9 14368.9 14490.7 14971.2 15173.5 4285.7 4395.0 4400.1 4403.2 4410.8 3912.3 4132.2 4406.7 4422.5 4425.2 4367.0 4418.3 4424.5 4425.5 4427.1 4192.3 4391.2 4392.1 4395.0 4395.9 4166.0 4182.2 4235.7 4239.0 4254.5 10237.3 10547.4 10680.8 10682.1 10690.8 1502.8 1515.8 1526.1 1586.3 1640.8 7845.3 8101.7 8459.0 8472.2 8536.3 1449.2 1453.1 1462.1 1488.7 1503.6 56198.4 56205.4 57766.4 61097.0 61580.0 8405.4 8427.8 8471.4 8794.9 8846.2 10224.5 11602.2 11628.4 11651.8 11666.2 4905.7 5272.4 5531.3 5537.5 5554.3 5045.1 5094.2 5114.9 5205.5 5225.5 3414.4 3486.1 3562.5 3565.8 3698.8 1510.3 1600.2 1608.6 1614.2 1618.3 755.0 774.0 798.4 845.0 863.8 14740.2 14900.4 15323.9 15385.9 15722.3 676.9 712.9 724.1 727.4 744.2 8456.7 8507.7 8604.9 8852.6 9366.3 265495.0 273854.0 274557.0 275962.0 276863.0 62878.0 65744.7 67048.0 72536.0 73488.6 761.1 794.0 795.9 796.9 799.2 13994.4 14323.5 14353.2 14508.9 14723.5 35542.1 35854.8 36704.1 36936.8 37034.0 35350.5 44831.0 46265.8 49700.0 49958.3 6348.5 6461.6 6494.6 6512.8 6538.9 8591.3 9671.3 9750.3 10065.1 10358.2 6676.8 8322.4 8523.5 8633.6 8985.7 8524.5 8628.5 8830.2 8904.1 9170.5 8428.0 8517.5 8537.3 8588.7 8884.4 6336.9 6393.4 6458.8 6501.9 6720.4 6268.9 8271.8 8507.2 8569.5 8901.9
20:58:42 INFO - PROCESS | 1964 |
20:58:42 INFO - PROCESS | 1964 | [#3] mootools.html Cycles:180 Average:3943.34 Median:5074.18 stddev:2126.09 (41.9%) stddev-sans-first:2121.77
20:58:42 INFO - PROCESS | 1964 | Values: 1161.6 1171.7 1236.8 1429.2 1466.9 5232.6 5505.3 5918.3 6323.1 6338.0 5520.6 5556.8 5608.2 5608.6 5799.2 1745.8 1774.3 1775.1 1775.8 1795.0 1781.3 1813.4 1816.9 1823.4 1825.4 1520.7 1571.4 1704.8 1757.1 1757.3 1758.7 1768.0 1771.8 1772.9 1773.2 1660.2 1660.4 1698.5 1700.9 1766.8 6025.2 6044.1 6083.9 6093.5 6108.5 3525.5 3553.7 3610.8 3610.9 3620.1 2209.4 2348.3 2359.8 2379.1 2379.6 685.0 705.8 716.0 722.2 728.0 7323.9 7441.8 7463.1 7547.7 7807.7 3362.9 3401.8 3548.5 3559.8 3572.2 6360.0 6409.0 6417.3 6423.5 6434.0 3548.2 3575.5 3577.4 3578.4 3581.6 3297.2 3323.6 3332.4 3332.6 3341.2 2437.9 2450.0 2471.7 2613.9 2630.3 826.6 846.7 851.0 854.1 859.5 605.4 609.6 690.4 705.9 715.8 4076.5 4088.3 4099.3 4118.5 4242.9 748.9 756.4 769.4 779.6 821.6 2687.8 2757.6 2822.8 2826.0 2836.2 6860.2 7378.6 7387.9 7403.7 7407.7 6597.2 6788.8 6921.9 6928.6 7210.1 5467.8 5792.8 5805.1 5892.3 5900.4 3998.3 4064.5 4068.7 4081.0 4144.4 6856.6 6875.4 6934.6 6959.5 6962.7 6948.3 7015.8 7017.6 7031.3 7038.9 5118.9 5189.7 5326.6 5326.8 5423.7 5937.8 6095.9 6099.8 6100.2 6105.1 5375.9 5415.2 5420.4 5420.8 5433.5 5029.4 5150.7 5174.5 5229.6 5328.8 4788.4 4820.5 4837.3 4842.5 4845.2 3391.6 3482.5 3487.5 3506.0 3538.0 3494.1 3523.8 3534.2 3572.0 3574.6
20:58:42 INFO - PROCESS | 1964 |
20:58:42 INFO - PROCESS | 1964 | [#4] prototype.html Cycles:180 Average:3734.71 Median:4266.44 stddev:1124.27 (26.4%) stddev-sans-first:1096.32
20:58:42 INFO - PROCESS | 1964 | Values: 235.7 236.7 240.2 240.2 249.1 4302.5 4341.8 4387.7 4400.5 4410.9 4134.2 4171.9 4265.6 4326.3 4350.8 3172.8 3183.5 3197.5 3227.8 3229.3 3219.6 3220.2 3223.1 3225.9 3232.8 2972.4 3038.4 3093.4 3133.0 3192.7 3178.5 3182.9 3194.4 3198.5 3210.2 2736.7 2766.0 2780.4 2782.7 2810.7 4200.3 4235.7 4238.1 4242.4 4266.0 3688.7 3714.2 3730.1 3768.4 3779.0 4196.4 4439.4 4440.7 4502.0 4516.4 3113.0 3200.1 3211.4 3222.6 3236.2 4616.9 4690.8 4692.9 4710.0 4732.2 3246.7 3293.4 3296.7 3314.9 3315.4 3321.0 3324.9 3334.8 3349.3 3357.3 3602.2 3810.8 3820.5 3825.3 3829.7 4532.2 4551.4 4553.3 4558.9 4573.9 1099.7 1121.3 1125.3 1127.6 1132.9 720.6 734.4 736.0 741.4 764.4 4123.4 4152.0 4163.8 4166.6 4186.3 4439.4 4469.3 4481.6 4484.2 4490.4 4101.6 4139.9 4160.3 4161.3 4179.2 2323.9 2387.2 2405.0 2411.2 2411.8 4516.8 4607.5 4613.0 4619.0 4639.8 4588.7 4598.9 4611.4 4612.7 4618.7 4584.1 4621.6 4625.7 4643.5 4645.7 4311.9 4456.4 4502.7 4511.4 4538.6 4581.7 4622.4 4633.5 4636.2 4650.0 4757.0 4802.4 4810.6 4810.8 4818.7 3093.7 3153.3 3157.3 3158.8 3160.2 4665.6 4680.3 4687.5 4700.1 4703.4 4266.9 4484.1 4490.5 4513.1 4540.4 4493.6 4497.8 4497.9 4505.2 4520.5 4394.9 4403.7 4417.4 4422.5 4425.6 4519.9 4543.4 4559.2 4564.4 4575.8 4167.0 4243.6 4456.9 4465.8 4490.4
20:58:42 INFO - PROCESS | 1964 |
20:58:42 INFO - PROCESS | 1964 | [#5] yui.html Cycles:180 Average:13326.06 Median:570.72 stddev:17718.85 (3104.7%) stddev-sans-first:17742.43
20:58:42 INFO - PROCESS | 1964 | Values: 510.8 513.4 554.3 581.3 589.0 10703.1 11184.3 11190.4 11214.9 11216.4 11147.8 11715.1 11993.0 11999.1 12010.5 452.5 635.4 636.6 637.1 640.1 591.5 612.3 624.9 627.0 627.5 659.7 661.8 663.9 663.9 665.1 654.8 655.6 655.9 656.4 656.9 482.8 483.2 483.5 483.9 483.9 10608.1 10657.0 10661.6 10672.8 10684.1 10886.8 11033.2 11121.1 11397.8 11439.1 8185.7 8512.1 8752.1 8777.0 8888.2 2679.2 2728.8 2741.9 2782.8 2786.7 46010.8 46024.5 46564.0 46951.8 47806.3 7882.6 8282.3 8567.1 8634.0 8952.7 15112.4 15139.0 15139.2 15177.8 15180.6 5604.2 5665.7 5764.2 5768.5 5843.8 2947.7 3038.1 3052.6 3092.3 3187.2 2233.4 2303.7 2307.4 2315.6 2322.5 383.0 384.3 385.5 391.7 399.0 474.8 522.5 532.0 568.8 572.6 7117.7 7710.5 7784.5 7910.6 8244.3 398.9 471.9 499.0 529.6 532.3 3137.7 3173.3 3199.3 3210.4 3266.0 61191.0 63898.0 64314.0 66977.7 67140.3 40880.6 58060.4 58244.6 58895.7 59633.4 50449.0 51257.7 53379.7 53687.2 54284.5 6552.4 6566.9 6662.2 6709.2 6778.1 29643.6 36334.8 36587.0 36742.6 37366.3 48250.0 49583.3 51177.5 51635.2 51848.0 8413.8 8469.6 8627.8 8641.2 9028.9 9393.3 9614.7 9739.3 9766.2 9768.5 9218.7 9231.4 9246.7 9516.8 9532.5 8641.6 8974.6 9195.9 9281.1 9454.4 9076.0 9198.7 9288.0 9324.6 9332.8 7360.6 7434.8 7573.4 7879.0 8134.4 7518.7 7684.1 7696.0 7755.8 8058.3
20:58:42 INFO - PROCESS | 1964 | -------- Summary: end --------
20:58:42 INFO - PROCESS | 1964 |
20:58:43 INFO - TEST-INFO | 1964: exit 0
20:58:43 INFO - TEST-OK | dromaeo_css | took 1133335ms
20:58:43 INFO - TEST-START | kraken
20:58:43 INFO - operating with platform_type : w7_
20:58:43 INFO - Initialising browser for kraken test...
20:58:43 INFO - TEST-INFO | started process 3552 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmphawy6f\profile http://localhost:49896/getInfo.html)
20:58:50 INFO - PROCESS | 3552 | __metrics Screen width/height:1600/1200
20:58:50 INFO - PROCESS | 3552 | colorDepth:24
20:58:50 INFO - PROCESS | 3552 | Browser inner width/height: 1010/674
20:58:50 INFO - PROCESS | 3552 | __metrics
20:58:52 INFO - PROCESS | 3552 | 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"
20:58:52 INFO - PROCESS | 3552 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
20:58:53 INFO - PROCESS | 3552 | console.error:
20:58:53 INFO - PROCESS | 3552 | Message: Error: Connection closed before committing the transaction.
20:58:53 INFO - PROCESS | 3552 | Stack:
20:58:53 INFO - PROCESS | 3552 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23
20:58:53 INFO - PROCESS | 3552 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7
20:58:53 INFO - PROCESS | 3552 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11
20:58:53 INFO - PROCESS | 3552 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7
20:58:53 INFO - PROCESS | 3552 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7
20:58:53 INFO - PROCESS | 3552 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15
20:58:53 INFO - PROCESS | 3552 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
20:58:53 INFO - PROCESS | 3552 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
20:58:53 INFO - PROCESS | 3552 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5
20:58:53 INFO - PROCESS | 3552 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
20:58:53 INFO - PROCESS | 3552 | Barrier/this.client.addBlocker/promise13.1%
20:59:54 INFO - TinderboxPrint: I/O read bytes / time
41,761,280 / 862,390
20:59:54 INFO - TinderboxPrint: I/O write bytes / time
459,478,528 / 3,508,320
20:59:54 INFO - TinderboxPrint: CPU idle
8,400.3 (86.9%)
20:59:54 INFO - TinderboxPrint: CPU user
1,228.4 (12.7%)
20:59:54 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 37210112; Read time: 0; Write time: 210030
20:59:54 INFO - run-tests - Wall time: 1207s; CPU: 13.0%; Read bytes: 41761280; Write bytes: 386137600; Read time: 862390; Write time: 3248640
20:59:55 INFO - Running post-run listener: _upload_blobber_files
20:59:55 INFO - Blob upload gear active.
20:59:55 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
20:59:55 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
20:59:55 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Firefox-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
20:59:55 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Firefox-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
20:59:55 INFO - (blobuploader) - INFO - Open directory for files ...
20:59:55 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
20:59:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:59:55 INFO - (blobuploader) - INFO - Uploading, attempt #1.
20:59:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
20:59:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
20:59:56 INFO - (blobuploader) - INFO - Done attempting.
20:59:56 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
20:59:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:59:56 INFO - (blobuploader) - INFO - Uploading, attempt #1.
20:59:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
20:59:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
20:59:57 INFO - (blobuploader) - INFO - Done attempting.
20:59:57 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
20:59:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:59:57 INFO - (blobuploader) - INFO - Uploading, attempt #1.
21:00:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
21:00:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
21:00:06 INFO - (blobuploader) - INFO - Done attempting.
21:00:06 INFO - (blobuploader) - INFO - Iteration through files over.
21:00:06 INFO - Return code: 0
21:00:06 INFO - rmtree: C:\slave\test\build\uploaded_files.json
21:00:06 INFO - Using _rmtree_windows ...
21:00:06 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
21:00:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a588f253dcb9a4c6244e9e88293acfce486101e9ded4c37137ab89bee32606de492503ce1f9cba0c36e847b39a36ef0ddb6a31cc5f1d372a351c538818f45b86", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/886f675fa1469e477e0a8246186ad48501458583ad07e04eaa99150040116ec90f4683187764e824fa21b5548754fdaf455eaf7b5ac0f6dece605b146c2394d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/82819d2e5b0487f935ef50fe3ee02514179a7aea34e778cec5e0c551b3a50d8221a27bd28139632c437e123bf3e9c01d22b7eb7a8aafbef316ba4ec514196689"}
21:00:06 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
21:00:06 INFO - Writing to file C:\slave\test\properties\blobber_files
21:00:06 INFO - Contents:
21:00:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a588f253dcb9a4c6244e9e88293acfce486101e9ded4c37137ab89bee32606de492503ce1f9cba0c36e847b39a36ef0ddb6a31cc5f1d372a351c538818f45b86", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/886f675fa1469e477e0a8246186ad48501458583ad07e04eaa99150040116ec90f4683187764e824fa21b5548754fdaf455eaf7b5ac0f6dece605b146c2394d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/82819d2e5b0487f935ef50fe3ee02514179a7aea34e778cec5e0c551b3a50d8221a27bd28139632c437e123bf3e9c01d22b7eb7a8aafbef316ba4ec514196689"}
21:00:06 INFO - Running post-run listener: copy_logs_to_upload_dir
21:00:06 INFO - Copying logs to upload dir...
21:00:06 INFO - mkdir: C:\slave\test\build\upload\logs
21:00:06 INFO - Copying logs to upload dir...
21:00:06 INFO - Using _rmtree_windows ...
21:00:06 INFO - Using _rmtree_windows ...
21:00:06 INFO - Using _rmtree_windows ...
21:00:06 INFO - Using _rmtree_windows ...
21:00:06 INFO - Using _rmtree_windows ...
21:00:06 INFO - Using _rmtree_windows ...
21:00:06 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1541.131000
========= master_lag: 0.04 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 41 secs) (at 2016-11-04 21:00:06.372952) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-04 21:00:06.373749) =========
'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-027
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-027
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-027
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a588f253dcb9a4c6244e9e88293acfce486101e9ded4c37137ab89bee32606de492503ce1f9cba0c36e847b39a36ef0ddb6a31cc5f1d372a351c538818f45b86", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/886f675fa1469e477e0a8246186ad48501458583ad07e04eaa99150040116ec90f4683187764e824fa21b5548754fdaf455eaf7b5ac0f6dece605b146c2394d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/82819d2e5b0487f935ef50fe3ee02514179a7aea34e778cec5e0c551b3a50d8221a27bd28139632c437e123bf3e9c01d22b7eb7a8aafbef316ba4ec514196689"}
build_url:https://queue.taskcluster.net/v1/task/deuVik7wRC2IVr8DFN5eEw/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/deuVik7wRC2IVr8DFN5eEw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a588f253dcb9a4c6244e9e88293acfce486101e9ded4c37137ab89bee32606de492503ce1f9cba0c36e847b39a36ef0ddb6a31cc5f1d372a351c538818f45b86", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/886f675fa1469e477e0a8246186ad48501458583ad07e04eaa99150040116ec90f4683187764e824fa21b5548754fdaf455eaf7b5ac0f6dece605b146c2394d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/82819d2e5b0487f935ef50fe3ee02514179a7aea34e778cec5e0c551b3a50d8221a27bd28139632c437e123bf3e9c01d22b7eb7a8aafbef316ba4ec514196689"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-04 21:00:06.489894) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 21:00:06.490229) =========
'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-027
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-027
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-027
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.101000
========= master_lag: 0.01 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 21:00:06.601540) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-04 21:00:06.601854) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-04 21:00:07.141377) =========
========= Total master_lag: 0.23 =========