builder: mozilla-central_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-104
starttime: 1477923048.63
results: success (0)
buildid: 20161031030202
builduid: f081758597ca469e9265bc7a5fa9f6e3
revision: e3279760cd977aac30bd9e8032d3ee71f55d2a67
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 07:10:48.635011) =========
master: http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 07:10:48.635470) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 07:10:48.635763) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 07:10:48.785732) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-31 07:10:48.786118) =========
'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-104
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-104
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-104
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
--07:10:49-- 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% 6.80 MB/s
07:10:49 (6.80 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.173000
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-31 07:10:49.985914) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-10-31 07:10:49.986437) =========
'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-104
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-104
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-104
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=15.504000
========= master_lag: -0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-10-31 07:11:05.483990) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-31 07:11:05.484384) =========
'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev e3279760cd977aac30bd9e8032d3ee71f55d2a67 --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 e3279760cd977aac30bd9e8032d3ee71f55d2a67 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-104
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-104
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-104
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-10-31 07:11:05,473 truncating revision to first 12 chars
2016-10-31 07:11:05,473 Setting DEBUG logging.
2016-10-31 07:11:05,473 attempt 1/10
2016-10-31 07:11:05,473 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e3279760cd97?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-10-31 07:11:05,979 unpacking tar archive at: mozilla-central-e3279760cd97/testing/mozharness/
2016-10-31 07:11:06,157 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.270000
========= master_lag: 0.17 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-31 07:11:06.929010) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 07:11:06.929296) =========
script_repo_revision: e3279760cd977aac30bd9e8032d3ee71f55d2a67
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 07:11:06.929695) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 07:11:06.929965) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 07:11:06.940004) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 31 secs) (at 2016-10-31 07:11:06.940345) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-104
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-104
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-104
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
07:11:07 INFO - MultiFileLogger online at 20161031 07:11:07 in C:\slave\test
07:11:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
07:11:07 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
07:11:07 INFO - {'append_to_log': False,
07:11:07 INFO - 'base_work_dir': 'C:\\slave\\test',
07:11:07 INFO - 'blob_upload_branch': 'Firefox',
07:11:07 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
07:11:07 INFO - 'branch': 'Firefox',
07:11:07 INFO - 'buildbot_json_path': 'buildprops.json',
07:11:07 INFO - 'config_files': ('talos/windows_config.py',),
07:11:07 INFO - 'default_actions': ('clobber',
07:11:07 INFO - 'read-buildbot-config',
07:11:07 INFO - 'download-and-extract',
07:11:07 INFO - 'populate-webroot',
07:11:07 INFO - 'create-virtualenv',
07:11:07 INFO - 'install',
07:11:07 INFO - 'run-tests'),
07:11:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
07:11:07 INFO - 'download_minidump_stackwalk': True,
07:11:07 INFO - 'download_symbols': 'ondemand',
07:11:07 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
07:11:07 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
07:11:07 INFO - 'hg': 'c:/mozilla-build/hg/hg',
07:11:07 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
07:11:07 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
07:11:07 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
07:11:07 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
07:11:07 INFO - 'C:/mozilla-build/tooltool.py'),
07:11:07 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
07:11:07 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
07:11:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
07:11:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
07:11:07 INFO - 'installer_path': 'installer.exe',
07:11:07 INFO - 'log_level': 'info',
07:11:07 INFO - 'log_name': 'talos',
07:11:07 INFO - 'log_to_console': True,
07:11:07 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
07:11:07 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
07:11:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
07:11:07 INFO - 'opt_config_files': (),
07:11:07 INFO - 'pip_index': False,
07:11:07 INFO - 'sps_profile': False,
07:11:07 INFO - 'sps_profile_interval': 0,
07:11:07 INFO - 'suite': 'dromaeojs',
07:11:07 INFO - 'system_bits': '32',
07:11:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
07:11:07 INFO - 'title': 'T-W732-IX-104',
07:11:07 INFO - 'use_talos_json': True,
07:11:07 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
07:11:07 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
07:11:07 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
07:11:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
07:11:07 INFO - 'work_dir': 'build'}
07:11:07 INFO - [mozharness: 2016-10-31 14:11:07.166000Z] Running clobber step.
07:11:07 INFO - Running pre-action listener: _resource_record_pre_action
07:11:07 INFO - Running main action method: clobber
07:11:07 INFO - rmtree: C:\slave\test\build
07:11:07 INFO - Using _rmtree_windows ...
07:11:07 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
07:12:34 INFO - Running post-action listener: _resource_record_post_action
07:12:34 INFO - [mozharness: 2016-10-31 14:12:34.113000Z] Finished clobber step (success)
07:12:34 INFO - [mozharness: 2016-10-31 14:12:34.113000Z] Running read-buildbot-config step.
07:12:34 INFO - Running pre-action listener: _resource_record_pre_action
07:12:34 INFO - Running main action method: read_buildbot_config
07:12:34 INFO - Using buildbot properties:
07:12:34 INFO - {
07:12:34 INFO - "project": "",
07:12:34 INFO - "product": "firefox",
07:12:34 INFO - "script_repo_revision": "production",
07:12:34 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs",
07:12:34 INFO - "repository": "",
07:12:34 INFO - "buildername": "Windows 7 32-bit mozilla-central pgo talos dromaeojs",
07:12:34 INFO - "buildid": "20161031030202",
07:12:34 INFO - "stage_platform": "win32-pgo",
07:12:34 INFO - "basedir": "C:\\slave\\test",
07:12:34 INFO - "nightly_build": "True",
07:12:34 INFO - "buildnumber": 9,
07:12:34 INFO - "slavename": "t-w732-ix-104",
07:12:34 INFO - "master": "http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/",
07:12:34 INFO - "platform": "win7_ix",
07:12:34 INFO - "scheduler": "tests-mozilla-central-win32-pgo-talos",
07:12:34 INFO - "branch": "mozilla-central",
07:12:34 INFO - "repo_path": "mozilla-central",
07:12:34 INFO - "revision": "e3279760cd977aac30bd9e8032d3ee71f55d2a67",
07:12:34 INFO - "pgo_build": "True",
07:12:34 INFO - "builduid": "f081758597ca469e9265bc7a5fa9f6e3",
07:12:34 INFO - "slavebuilddir": "test"
07:12:34 INFO - }
07:12:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
07:12:34 INFO - Running post-action listener: _resource_record_post_action
07:12:34 INFO - [mozharness: 2016-10-31 14:12:34.117000Z] Finished read-buildbot-config step (success)
07:12:34 INFO - [mozharness: 2016-10-31 14:12:34.117000Z] Running download-and-extract step.
07:12:34 INFO - Running pre-action listener: _resource_record_pre_action
07:12:34 INFO - Running main action method: download_and_extract
07:12:34 INFO - mkdir: C:\slave\test\build\tests
07:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:12:34 INFO - trying https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
07:12:34 INFO - Downloading https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/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
07:12:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/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
07:12:35 INFO - Downloaded 1218 bytes.
07:12:35 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
07:12:35 INFO - Using the following test package requirements:
07:12:35 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
07:12:35 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:12:35 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
07:12:35 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
07:12:35 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:12:35 INFO - u'jsshell-win32.zip'],
07:12:35 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:12:35 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
07:12:35 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
07:12:35 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:12:35 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
07:12:35 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:12:35 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
07:12:35 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:12:35 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
07:12:35 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:12:35 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
07:12:35 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
07:12:35 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
07:12:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
07:12:35 INFO - Fetch https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
07:12:35 INFO - Http code: 200
07:12:35 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront)
07:12:35 INFO - x-amz-cf-id: fc4N3d6dnRZRls8-toU-fmdngZSh1H1DZQ7tvSm1XK-Lc-WeV1FB2A==
07:12:35 INFO - x-amz-version-id: jzIy9aiFtQdC3t.u7vz27ctn4qNN2Kcl
07:12:36 INFO - Expected file size: 17150259
07:12:36 INFO - Obtained file size: 17150259
07:13:10 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
07:13:10 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
07:13:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
07:13:10 INFO - Fetch https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
07:13:11 INFO - Http code: 200
07:13:11 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront)
07:13:11 INFO - x-amz-cf-id: SmUsdpfTx2dIzLGHnv-IcVpJ0_VtfbGrIc0W9ZQDmhR0q4bKRATnig==
07:13:11 INFO - x-amz-version-id: jzIy9aiFtQdC3t.u7vz27ctn4qNN2Kcl
07:13:12 INFO - Expected file size: 17150259
07:13:12 INFO - Obtained file size: 17150259
07:13:22 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
07:13:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
07:13:22 INFO - Fetch https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
07:13:23 INFO - Http code: 200
07:13:23 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront)
07:13:23 INFO - x-amz-cf-id: BWk4aBXyFqNkZQwWEOq6lyOjjzFS1uvOaPxFRvV5wW83VoZzjl1odg==
07:13:23 INFO - x-amz-version-id: sBpImsz8P0ZrFy9Wa3mLKnV4.cuFT06_
07:13:24 INFO - Expected file size: 14409038
07:13:24 INFO - Obtained file size: 14409038
07:13:38 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')]}
07:13:38 INFO - trying https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
07:13:38 INFO - Downloading https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
07:13:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/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
07:13:40 INFO - Downloaded 54392975 bytes.
07:13:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
07:13:40 INFO - mkdir: C:\slave\test\properties
07:13:40 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
07:13:40 INFO - Writing to file C:\slave\test\properties\build_url
07:13:40 INFO - Contents:
07:13:40 INFO - build_url:https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
07:13:41 INFO - Running post-action listener: _resource_record_post_action
07:13:41 INFO - Running post-action listener: set_extra_try_arguments
07:13:41 INFO - [mozharness: 2016-10-31 14:13:41.081000Z] Finished download-and-extract step (success)
07:13:41 INFO - [mozharness: 2016-10-31 14:13:41.081000Z] Running populate-webroot step.
07:13:41 INFO - Running pre-action listener: _resource_record_pre_action
07:13:41 INFO - Running main action method: populate_webroot
07:13:41 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
07:13:41 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
07:13:41 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
07:13:41 INFO - u'--tppagecycles',
07:13:41 INFO - u'7'],
07:13:41 INFO - u'tests': [u'tsvgm']}},
07:13:41 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
07:13:41 INFO - u'tests': [u'tresize', u'tcanvasmark']},
07:13:41 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
07:13:41 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
07:13:41 INFO - u'tests': [u'dromaeo_css', u'kraken']},
07:13:41 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
07:13:41 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:13:41 INFO - u'talos_options': [u'--disable-e10s'],
07:13:41 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
07:13:41 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:13:41 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
07:13:41 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:13:41 INFO - u'talos_options': [u'--disable-e10s'],
07:13:41 INFO - u'tests': [u'damp', u'tps']},
07:13:41 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:13:41 INFO - u'tests': [u'damp', u'tps']},
07:13:41 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
07:13:41 INFO - u'tests': [u'dromaeo_dom']},
07:13:41 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
07:13:41 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
07:13:41 INFO - u'tests': [u'basic_compositor_video']},
07:13:41 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
07:13:41 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
07:13:41 INFO - u'tests': [u'a11yr',
07:13:41 INFO - u'ts_paint',
07:13:41 INFO - u'tpaint',
07:13:41 INFO - u'sessionrestore',
07:13:41 INFO - u'sessionrestore_no_auto_restore',
07:13:41 INFO - u'tabpaint']},
07:13:41 INFO - u'other-e10s': {u'tests': [u'a11yr',
07:13:41 INFO - u'ts_paint',
07:13:41 INFO - u'tpaint',
07:13:41 INFO - u'sessionrestore',
07:13:41 INFO - u'sessionrestore_no_auto_restore',
07:13:41 INFO - u'tabpaint']},
07:13:41 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
07:13:41 INFO - u'ts_paint',
07:13:41 INFO - u'tpaint',
07:13:41 INFO - u'sessionrestore',
07:13:41 INFO - u'sessionrestore_no_auto_restore',
07:13:41 INFO - u'tabpaint']},
07:13:41 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
07:13:41 INFO - u'ts_paint',
07:13:41 INFO - u'tpaint',
07:13:41 INFO - u'sessionrestore',
07:13:41 INFO - u'sessionrestore_no_auto_restore',
07:13:41 INFO - u'tabpaint']},
07:13:41 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
07:13:41 INFO - u'tests': [u'a11yr',
07:13:41 INFO - u'ts_paint',
07:13:41 INFO - u'tpaint',
07:13:41 INFO - u'sessionrestore',
07:13:41 INFO - u'sessionrestore_no_auto_restore',
07:13:41 INFO - u'tabpaint']},
07:13:41 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
07:13:41 INFO - u'tests': [u'a11yr',
07:13:41 INFO - u'ts_paint',
07:13:41 INFO - u'tpaint',
07:13:41 INFO - u'sessionrestore',
07:13:41 INFO - u'sessionrestore_no_auto_restore',
07:13:41 INFO - u'tabpaint']},
07:13:41 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
07:13:41 INFO - u'tests': [u'tsvgx',
07:13:41 INFO - u'tsvgr_opacity',
07:13:41 INFO - u'tart',
07:13:41 INFO - u'tscrollx',
07:13:41 INFO - u'cart']},
07:13:41 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
07:13:41 INFO - u'tsvgr_opacity',
07:13:41 INFO - u'tart',
07:13:41 INFO - u'tscrollx',
07:13:41 INFO - u'cart']},
07:13:41 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:13:41 INFO - u'talos_options': [u'--disable-e10s'],
07:13:41 INFO - u'tests': [u'tp5o']},
07:13:41 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:13:41 INFO - u'tests': [u'tp5o']},
07:13:41 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:13:41 INFO - u'talos_options': [u'--disable-e10s',
07:13:41 INFO - u'--xperf_path',
07:13:41 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
07:13:41 INFO - u'tests': [u'tp5n']},
07:13:41 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:13:41 INFO - u'talos_options': [u'--xperf_path',
07:13:41 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
07:13:41 INFO - u'tests': [u'tp5n']}},
07:13:41 INFO - u'talos.zip': {u'path': u'',
07:13:41 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
07:13:41 INFO - Running post-action listener: _resource_record_post_action
07:13:41 INFO - [mozharness: 2016-10-31 14:13:41.160000Z] Finished populate-webroot step (success)
07:13:41 INFO - [mozharness: 2016-10-31 14:13:41.160000Z] Running create-virtualenv step.
07:13:41 INFO - Running pre-action listener: _resource_record_pre_action
07:13:41 INFO - Running main action method: create_virtualenv
07:13:41 INFO - Creating virtualenv C:\slave\test\build/venv
07:13:41 INFO - mkdir: C:\slave\test\build\venv\Scripts
07:13:41 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
07:13:41 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'!
07:13:41 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
07:13:41 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
07:13:54 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
07:13:58 INFO - Installing distribute......................................................................................................................................................................................done.
07:13:59 INFO - Return code: 0
07:13:59 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
07:13:59 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
07:14:00 INFO - Reading from file tmpfile_stdout
07:14:00 INFO - Output received:
07:14:00 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
07:14:00 INFO - Using _rmtree_windows ...
07:14:00 INFO - Using _rmtree_windows ...
07:14:00 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
07:14:00 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')]}
07:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:14:00 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')]}
07:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:14:00 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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01859E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0192E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-104', 'USERDOMAIN': 'T-W732-IX-104', '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-104', '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
07:14:00 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
07:14:00 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
07:14:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:14:00 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:14:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:14:00 INFO - 'COMPUTERNAME': 'T-W732-IX-104',
07:14:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:14:00 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:14:00 INFO - 'DCLOCATION': 'SCL3',
07:14:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:14:00 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:14:00 INFO - 'HOMEDRIVE': 'C:',
07:14:00 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:14:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:14:00 INFO - 'KTS_VERSION': '1.19c',
07:14:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:14:00 INFO - 'LOGONSERVER': '\\\\T-W732-IX-104',
07:14:00 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:14:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:14:00 INFO - 'MOZ_NO_REMOTE': '1',
07:14:00 INFO - 'NO_EM_RESTART': '1',
07:14:00 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:14:00 INFO - 'OS': 'Windows_NT',
07:14:00 INFO - 'OURDRIVE': 'C:',
07:14:00 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;',
07:14:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:14:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:14:00 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:14:00 INFO - 'PROCESSOR_LEVEL': '6',
07:14:00 INFO - 'PROCESSOR_REVISION': '1e05',
07:14:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:14:00 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:14:00 INFO - 'PROMPT': '$P$G',
07:14:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:14:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:14:00 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:14:00 INFO - 'PWD': 'C:\\slave\\test',
07:14:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:14:00 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:14:00 INFO - 'SYSTEMDRIVE': 'C:',
07:14:00 INFO - 'SYSTEMROOT': 'C:\\windows',
07:14:00 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:00 INFO - 'TEST1': 'testie',
07:14:00 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:00 INFO - 'USERDOMAIN': 'T-W732-IX-104',
07:14:00 INFO - 'USERNAME': 'cltbld',
07:14:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:14:00 INFO - 'WINDIR': 'C:\\windows',
07:14:00 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:14:00 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:14:00 INFO - Ignoring indexes: http://pypi.python.org/simple/
07:14:00 INFO - Downloading/unpacking pip>=1.5
07:14:04 INFO - Running setup.py egg_info for package pip
07:14:04 INFO - warning: no files found matching 'pip\cacert.pem'
07:14:04 INFO - warning: no files found matching '*.html' under directory 'docs'
07:14:04 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
07:14:04 INFO - no previously-included directories found matching 'docs\_build\_sources'
07:14:04 INFO - Installing collected packages: pip
07:14:04 INFO - Found existing installation: pip 0.8.2
07:14:04 INFO - Uninstalling pip:
07:14:04 INFO - Successfully uninstalled pip
07:14:04 INFO - Running setup.py install for pip
07:14:04 INFO - warning: no files found matching 'pip\cacert.pem'
07:14:04 INFO - warning: no files found matching '*.html' under directory 'docs'
07:14:04 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
07:14:04 INFO - no previously-included directories found matching 'docs\_build\_sources'
07:14:04 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
07:14:04 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
07:14:04 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
07:14:04 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
07:14:04 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
07:14:04 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
07:14:04 INFO - Successfully installed pip
07:14:04 INFO - Cleaning up...
07:14:04 INFO - Return code: 0
07:14:04 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
07:14: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')]}
07:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:14: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')]}
07:14:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:14: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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01859E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0192E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-104', 'USERDOMAIN': 'T-W732-IX-104', '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-104', '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
07:14:05 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build
07:14:05 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1
07:14:05 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:14:05 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:14:05 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:14:05 INFO - 'COMPUTERNAME': 'T-W732-IX-104',
07:14:05 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:14:05 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:14:05 INFO - 'DCLOCATION': 'SCL3',
07:14:05 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:14:05 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:14:05 INFO - 'HOMEDRIVE': 'C:',
07:14:05 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:14:05 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:14:05 INFO - 'KTS_VERSION': '1.19c',
07:14:05 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:14:05 INFO - 'LOGONSERVER': '\\\\T-W732-IX-104',
07:14:05 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:14:05 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:14:05 INFO - 'MOZ_NO_REMOTE': '1',
07:14:05 INFO - 'NO_EM_RESTART': '1',
07:14:05 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:14:05 INFO - 'OS': 'Windows_NT',
07:14:05 INFO - 'OURDRIVE': 'C:',
07:14:05 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
07:14:05 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:14:05 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:14:05 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:14:05 INFO - 'PROCESSOR_LEVEL': '6',
07:14:05 INFO - 'PROCESSOR_REVISION': '1e05',
07:14:05 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:14:05 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:14:05 INFO - 'PROMPT': '$P$G',
07:14:05 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:14:05 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:14:05 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:14:05 INFO - 'PWD': 'C:\\slave\\test',
07:14:05 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:14:05 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:14:05 INFO - 'SYSTEMDRIVE': 'C:',
07:14:05 INFO - 'SYSTEMROOT': 'C:\\windows',
07:14:05 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:05 INFO - 'TEST1': 'testie',
07:14:05 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:05 INFO - 'USERDOMAIN': 'T-W732-IX-104',
07:14:05 INFO - 'USERNAME': 'cltbld',
07:14:05 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:14:05 INFO - 'WINDIR': 'C:\\windows',
07:14:05 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:14:05 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:14:07 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:14:07 INFO - Downloading/unpacking psutil>=3.1.1
07:14:07 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
07:14:07 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
07:14:08 INFO - Installing collected packages: psutil
07:14:08 INFO - Successfully installed psutil
07:14:08 INFO - Cleaning up...
07:14:08 INFO - Return code: 0
07:14:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
07:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:14:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:14:08 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01859E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0192E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-104', 'USERDOMAIN': 'T-W732-IX-104', '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-104', '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
07:14:08 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build
07:14:08 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
07:14:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:14:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:14:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:14:08 INFO - 'COMPUTERNAME': 'T-W732-IX-104',
07:14:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:14:08 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:14:08 INFO - 'DCLOCATION': 'SCL3',
07:14:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:14:08 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:14:08 INFO - 'HOMEDRIVE': 'C:',
07:14:08 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:14:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:14:08 INFO - 'KTS_VERSION': '1.19c',
07:14:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:14:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-104',
07:14:08 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:14:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:14:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:14:08 INFO - 'MOZ_NO_REMOTE': '1',
07:14:08 INFO - 'NO_EM_RESTART': '1',
07:14:08 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:14:08 INFO - 'OS': 'Windows_NT',
07:14:08 INFO - 'OURDRIVE': 'C:',
07:14:08 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
07:14:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:14:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:14:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:14:08 INFO - 'PROCESSOR_LEVEL': '6',
07:14:08 INFO - 'PROCESSOR_REVISION': '1e05',
07:14:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:14:08 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:14:08 INFO - 'PROMPT': '$P$G',
07:14:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:14:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:14:08 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:14:08 INFO - 'PWD': 'C:\\slave\\test',
07:14:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:14:08 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:14:08 INFO - 'SYSTEMDRIVE': 'C:',
07:14:08 INFO - 'SYSTEMROOT': 'C:\\windows',
07:14:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:08 INFO - 'TEST1': 'testie',
07:14:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:08 INFO - 'USERDOMAIN': 'T-W732-IX-104',
07:14:08 INFO - 'USERNAME': 'cltbld',
07:14:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:14:08 INFO - 'WINDIR': 'C:\\windows',
07:14:08 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:14:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:14:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:14:08 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:14:08 INFO - Downloading/unpacking mozsystemmonitor==0.3
07:14:08 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
07:14:08 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
07:14:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz
07:14:09 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
07:14:09 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)
07:14:09 INFO - Installing collected packages: mozsystemmonitor
07:14:09 INFO - Running setup.py install for mozsystemmonitor
07:14:10 INFO - Successfully installed mozsystemmonitor
07:14:10 INFO - Cleaning up...
07:14:10 INFO - Return code: 0
07:14:10 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
07:14:10 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')]}
07:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:14:10 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')]}
07:14:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:14:10 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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01859E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0192E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-104', 'USERDOMAIN': 'T-W732-IX-104', '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-104', '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
07:14:10 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
07:14:10 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
07:14:10 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:14:10 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:14:10 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:14:10 INFO - 'COMPUTERNAME': 'T-W732-IX-104',
07:14:10 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:14:10 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:14:10 INFO - 'DCLOCATION': 'SCL3',
07:14:10 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:14:10 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:14:10 INFO - 'HOMEDRIVE': 'C:',
07:14:10 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:14:10 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:14:10 INFO - 'KTS_VERSION': '1.19c',
07:14:10 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:14:10 INFO - 'LOGONSERVER': '\\\\T-W732-IX-104',
07:14:10 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:14:10 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:14:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:14:10 INFO - 'MOZ_NO_REMOTE': '1',
07:14:10 INFO - 'NO_EM_RESTART': '1',
07:14:10 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:14:10 INFO - 'OS': 'Windows_NT',
07:14:10 INFO - 'OURDRIVE': 'C:',
07:14:10 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;',
07:14:10 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:14:10 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:14:10 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:14:10 INFO - 'PROCESSOR_LEVEL': '6',
07:14:10 INFO - 'PROCESSOR_REVISION': '1e05',
07:14:10 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:14:10 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:14:10 INFO - 'PROMPT': '$P$G',
07:14:10 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:14:10 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:14:10 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:14:10 INFO - 'PWD': 'C:\\slave\\test',
07:14:10 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:14:10 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:14:10 INFO - 'SYSTEMDRIVE': 'C:',
07:14:10 INFO - 'SYSTEMROOT': 'C:\\windows',
07:14:10 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:10 INFO - 'TEST1': 'testie',
07:14:10 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:10 INFO - 'USERDOMAIN': 'T-W732-IX-104',
07:14:10 INFO - 'USERNAME': 'cltbld',
07:14:10 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:14:10 INFO - 'WINDIR': 'C:\\windows',
07:14:10 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:14:10 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:14:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:14:10 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:14:10 INFO - Downloading/unpacking jsonschema==2.5.1
07:14:10 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
07:14:10 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
07:14:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
07:14:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
07:14:11 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
07:14:11 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
07:14:11 INFO - Downloading functools32-3.2.3-2.tar.gz
07:14:11 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
07:14:12 INFO - warning: no files found matching '*.txt'
07:14:12 INFO - no previously-included directories found matching 'build'
07:14:12 INFO - no previously-included directories found matching 'dist'
07:14:12 INFO - no previously-included directories found matching '.git*'
07:14:12 INFO - Installing collected packages: jsonschema, functools32
07:14:12 INFO - Running setup.py install for functools32
07:14:12 INFO - warning: no files found matching '*.txt'
07:14:12 INFO - no previously-included directories found matching 'build'
07:14:12 INFO - no previously-included directories found matching 'dist'
07:14:12 INFO - no previously-included directories found matching '.git*'
07:14:12 INFO - Successfully installed jsonschema functools32
07:14:12 INFO - Cleaning up...
07:14:12 INFO - Return code: 0
07:14:12 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
07:14:12 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')]}
07:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:14:12 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')]}
07:14:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:14:12 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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01859E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0192E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-104', 'USERDOMAIN': 'T-W732-IX-104', '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-104', '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
07:14:12 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
07:14:12 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
07:14:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:14:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:14:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:14:12 INFO - 'COMPUTERNAME': 'T-W732-IX-104',
07:14:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:14:12 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:14:12 INFO - 'DCLOCATION': 'SCL3',
07:14:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:14:12 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:14:12 INFO - 'HOMEDRIVE': 'C:',
07:14:12 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:14:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:14:12 INFO - 'KTS_VERSION': '1.19c',
07:14:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:14:12 INFO - 'LOGONSERVER': '\\\\T-W732-IX-104',
07:14:12 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:14:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:14:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:14:12 INFO - 'MOZ_NO_REMOTE': '1',
07:14:12 INFO - 'NO_EM_RESTART': '1',
07:14:12 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:14:12 INFO - 'OS': 'Windows_NT',
07:14:12 INFO - 'OURDRIVE': 'C:',
07:14:12 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;',
07:14:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:14:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:14:12 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:14:12 INFO - 'PROCESSOR_LEVEL': '6',
07:14:12 INFO - 'PROCESSOR_REVISION': '1e05',
07:14:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:14:12 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:14:12 INFO - 'PROMPT': '$P$G',
07:14:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:14:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:14:12 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:14:12 INFO - 'PWD': 'C:\\slave\\test',
07:14:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:14:12 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:14:12 INFO - 'SYSTEMDRIVE': 'C:',
07:14:12 INFO - 'SYSTEMROOT': 'C:\\windows',
07:14:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:12 INFO - 'TEST1': 'testie',
07:14:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:12 INFO - 'USERDOMAIN': 'T-W732-IX-104',
07:14:12 INFO - 'USERNAME': 'cltbld',
07:14:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:14:12 INFO - 'WINDIR': 'C:\\windows',
07:14:12 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:14:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:14:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:14:13 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:14:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
07:14:13 INFO - Cleaning up...
07:14:13 INFO - Return code: 0
07:14:13 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
07:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:14:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:14:13 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01859E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0192E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-104', 'USERDOMAIN': 'T-W732-IX-104', '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-104', '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
07:14:13 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build
07:14:13 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
07:14:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:14:13 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:14:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:14:13 INFO - 'COMPUTERNAME': 'T-W732-IX-104',
07:14:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:14:13 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:14:13 INFO - 'DCLOCATION': 'SCL3',
07:14:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:14:13 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:14:13 INFO - 'HOMEDRIVE': 'C:',
07:14:13 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:14:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:14:13 INFO - 'KTS_VERSION': '1.19c',
07:14:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:14:13 INFO - 'LOGONSERVER': '\\\\T-W732-IX-104',
07:14:13 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:14:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:14:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:14:13 INFO - 'MOZ_NO_REMOTE': '1',
07:14:13 INFO - 'NO_EM_RESTART': '1',
07:14:13 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:14:13 INFO - 'OS': 'Windows_NT',
07:14:13 INFO - 'OURDRIVE': 'C:',
07:14:13 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
07:14:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:14:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:14:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:14:13 INFO - 'PROCESSOR_LEVEL': '6',
07:14:13 INFO - 'PROCESSOR_REVISION': '1e05',
07:14:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:14:13 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:14:13 INFO - 'PROMPT': '$P$G',
07:14:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:14:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:14:13 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:14:13 INFO - 'PWD': 'C:\\slave\\test',
07:14:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:14:13 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:14:13 INFO - 'SYSTEMDRIVE': 'C:',
07:14:13 INFO - 'SYSTEMROOT': 'C:\\windows',
07:14:13 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:13 INFO - 'TEST1': 'testie',
07:14:13 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:13 INFO - 'USERDOMAIN': 'T-W732-IX-104',
07:14:13 INFO - 'USERNAME': 'cltbld',
07:14:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:14:13 INFO - 'WINDIR': 'C:\\windows',
07:14:13 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:14:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:14:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:14:13 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:14:13 INFO - Downloading/unpacking blobuploader==1.2.4
07:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
07:14:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
07:14:14 INFO - Downloading blobuploader-1.2.4.tar.gz
07:14:14 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
07:14:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
07:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
07:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
07:14:14 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
07:14:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
07:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
07:14:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
07:14:15 INFO - Downloading docopt-0.6.1.tar.gz
07:14:15 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
07:14:15 INFO - Installing collected packages: blobuploader, requests, docopt
07:14:15 INFO - Running setup.py install for blobuploader
07:14:15 INFO - Running setup.py install for requests
07:14:16 INFO - Running setup.py install for docopt
07:14:16 INFO - Successfully installed blobuploader requests docopt
07:14:16 INFO - Cleaning up...
07:14:17 INFO - Return code: 0
07:14:17 INFO - Installing None into virtualenv C:\slave\test\build/venv
07:14: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')]}
07:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:14: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')]}
07:14:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:14:17 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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01859E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0192E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-104', 'USERDOMAIN': 'T-W732-IX-104', '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-104', '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
07:14:18 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
07:14:18 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
07:14:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:14:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:14:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:14:18 INFO - 'COMPUTERNAME': 'T-W732-IX-104',
07:14:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:14:18 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:14:18 INFO - 'DCLOCATION': 'SCL3',
07:14:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:14:18 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:14:18 INFO - 'HOMEDRIVE': 'C:',
07:14:18 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:14:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:14:18 INFO - 'KTS_VERSION': '1.19c',
07:14:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:14:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-104',
07:14:18 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:14:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:14:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:14:18 INFO - 'MOZ_NO_REMOTE': '1',
07:14:18 INFO - 'NO_EM_RESTART': '1',
07:14:18 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:14:18 INFO - 'OS': 'Windows_NT',
07:14:18 INFO - 'OURDRIVE': 'C:',
07:14:18 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
07:14:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:14:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:14:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:14:18 INFO - 'PROCESSOR_LEVEL': '6',
07:14:18 INFO - 'PROCESSOR_REVISION': '1e05',
07:14:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:14:18 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:14:18 INFO - 'PROMPT': '$P$G',
07:14:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:14:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:14:18 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:14:18 INFO - 'PWD': 'C:\\slave\\test',
07:14:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:14:18 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:14:18 INFO - 'SYSTEMDRIVE': 'C:',
07:14:18 INFO - 'SYSTEMROOT': 'C:\\windows',
07:14:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:18 INFO - 'TEST1': 'testie',
07:14:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:18 INFO - 'USERDOMAIN': 'T-W732-IX-104',
07:14:18 INFO - 'USERNAME': 'cltbld',
07:14:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:14:18 INFO - 'WINDIR': 'C:\\windows',
07:14:18 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:14:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:14:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:14:18 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:14:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
07:14:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mldhdl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
07:14:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
07:14:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k3lmqd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
07:14:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
07:14:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cheb4b-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
07:14:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
07:14:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4dloa0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
07:14:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
07:14:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fqe9zk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
07:14:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
07:14:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a_t_wl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
07:14:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
07:14:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-djkbl8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
07:14:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
07:14:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n1wppt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
07:14:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
07:14:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ktwwur-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
07:14:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
07:14:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-48ycnq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
07:14:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
07:14:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rph0yq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
07:14:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
07:14:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rqwlbn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
07:14:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
07:14:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4rxr_r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
07:14:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
07:14:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o3b6f6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
07:14:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
07:14:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qtrgnf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
07:14:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
07:14:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3iddry-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
07:14:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
07:14:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mwc10t-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
07:14:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
07:14:20 INFO - Running setup.py install for manifestparser
07:14:20 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
07:14:20 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
07:14:21 INFO - Running setup.py install for mozcrash
07:14:21 INFO - Running setup.py install for mozdebug
07:14:22 INFO - Running setup.py install for mozdevice
07:14:22 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
07:14:22 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
07:14:22 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
07:14:22 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
07:14:22 INFO - Running setup.py install for mozfile
07:14:23 INFO - Running setup.py install for mozhttpd
07:14:23 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
07:14:23 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
07:14:23 INFO - Running setup.py install for mozinfo
07:14:23 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
07:14:23 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
07:14:23 INFO - Running setup.py install for mozInstall
07:14:23 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
07:14:23 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
07:14:23 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
07:14:23 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
07:14:23 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
07:14:23 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
07:14:23 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
07:14:23 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
07:14:24 INFO - Running setup.py install for mozleak
07:14:24 INFO - Running setup.py install for mozlog
07:14:24 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
07:14:24 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
07:14:25 INFO - Running setup.py install for moznetwork
07:14:25 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
07:14:25 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
07:14:25 INFO - Running setup.py install for mozprocess
07:14:26 INFO - Running setup.py install for mozprofile
07:14:26 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
07:14:26 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
07:14:26 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
07:14:26 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
07:14:26 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
07:14:26 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
07:14:26 INFO - Running setup.py install for mozrunner
07:14:27 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
07:14:27 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
07:14:27 INFO - Running setup.py install for mozscreenshot
07:14:28 INFO - Running setup.py install for moztest
07:14:28 INFO - Running setup.py install for mozversion
07:14:28 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
07:14:28 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
07:14:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
07:14:28 INFO - Cleaning up...
07:14:28 INFO - Return code: 0
07:14:28 INFO - Installing None into virtualenv C:\slave\test\build/venv
07:14:28 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')]}
07:14:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:14:28 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')]}
07:14:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:14:28 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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01859E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0192E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-104', 'USERDOMAIN': 'T-W732-IX-104', '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-104', '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
07:14:28 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
07:14:28 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
07:14:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:14:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:14:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:14:28 INFO - 'COMPUTERNAME': 'T-W732-IX-104',
07:14:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:14:28 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:14:28 INFO - 'DCLOCATION': 'SCL3',
07:14:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:14:28 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:14:28 INFO - 'HOMEDRIVE': 'C:',
07:14:28 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:14:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:14:28 INFO - 'KTS_VERSION': '1.19c',
07:14:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:14:28 INFO - 'LOGONSERVER': '\\\\T-W732-IX-104',
07:14:28 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:14:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:14:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:14:28 INFO - 'MOZ_NO_REMOTE': '1',
07:14:28 INFO - 'NO_EM_RESTART': '1',
07:14:28 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:14:28 INFO - 'OS': 'Windows_NT',
07:14:28 INFO - 'OURDRIVE': 'C:',
07:14:28 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;',
07:14:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:14:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:14:28 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:14:28 INFO - 'PROCESSOR_LEVEL': '6',
07:14:28 INFO - 'PROCESSOR_REVISION': '1e05',
07:14:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:14:28 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:14:28 INFO - 'PROMPT': '$P$G',
07:14:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:14:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:14:28 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:14:28 INFO - 'PWD': 'C:\\slave\\test',
07:14:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:14:28 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:14:28 INFO - 'SYSTEMDRIVE': 'C:',
07:14:28 INFO - 'SYSTEMROOT': 'C:\\windows',
07:14:28 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:28 INFO - 'TEST1': 'testie',
07:14:28 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:28 INFO - 'USERDOMAIN': 'T-W732-IX-104',
07:14:28 INFO - 'USERNAME': 'cltbld',
07:14:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:14:28 INFO - 'WINDIR': 'C:\\windows',
07:14:28 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:14:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:14:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:14:29 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:14:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
07:14:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o156iw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
07:14:29 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))
07:14:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
07:14:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-okahwk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
07:14:29 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))
07:14:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
07:14:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bbpdlu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
07:14:29 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))
07:14:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
07:14:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-s6nfae-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
07:14:29 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))
07:14:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
07:14:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4xrjcz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
07:14:29 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))
07:14:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
07:14:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ks4qga-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
07:14:30 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))
07:14:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
07:14:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pvgn33-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
07:14:30 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))
07:14:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
07:14:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mqlzra-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
07:14:30 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))
07:14:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
07:14:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4v31vz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
07:14:30 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))
07:14:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
07:14:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-imezrx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
07:14:30 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))
07:14:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
07:14:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ybbrgb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
07:14:30 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))
07:14:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
07:14:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-t2eaik-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
07:14:30 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))
07:14:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
07:14:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iy8wgc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
07:14:31 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))
07:14:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
07:14:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sg2knu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
07:14:31 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))
07:14:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
07:14:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wyifse-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
07:14:31 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))
07:14:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
07:14:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-86mt0e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
07:14:31 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))
07:14:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
07:14:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4jyslt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
07:14:31 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))
07:14:31 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))
07:14:31 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))
07:14:31 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))
07:14:31 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))
07:14:31 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))
07:14:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
07:14:31 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
07:14:31 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
07:14:32 INFO - Downloading blessings-1.6.tar.gz
07:14:32 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
07:14:33 INFO - Installing collected packages: blessings
07:14:33 INFO - Running setup.py install for blessings
07:14:33 INFO - Successfully installed blessings
07:14:33 INFO - Cleaning up...
07:14:36 INFO - Return code: 0
07:14:36 INFO - Done creating virtualenv C:\slave\test\build/venv.
07:14:36 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
07:14:36 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
07:14:36 INFO - Reading from file tmpfile_stdout
07:14:36 INFO - Using _rmtree_windows ...
07:14:36 INFO - Using _rmtree_windows ...
07:14:36 INFO - Current package versions:
07:14:36 INFO - blessings == 1.6
07:14:36 INFO - blobuploader == 1.2.4
07:14:36 INFO - docopt == 0.6.1
07:14:36 INFO - functools32 == 3.2.3-2
07:14:36 INFO - jsonschema == 2.5.1
07:14:36 INFO - manifestparser == 1.1
07:14:36 INFO - mozInstall == 1.12
07:14:36 INFO - mozcrash == 0.17
07:14:36 INFO - mozdebug == 0.1
07:14:36 INFO - mozdevice == 0.48
07:14:36 INFO - mozfile == 1.2
07:14:36 INFO - mozhttpd == 0.7
07:14:36 INFO - mozinfo == 0.9
07:14:36 INFO - mozleak == 0.1
07:14:36 INFO - mozlog == 3.4
07:14:36 INFO - moznetwork == 0.27
07:14:36 INFO - mozprocess == 0.23
07:14:36 INFO - mozprofile == 0.28
07:14:36 INFO - mozrunner == 6.12
07:14:36 INFO - mozscreenshot == 0.1
07:14:36 INFO - mozsystemmonitor == 0.3
07:14:36 INFO - moztest == 0.7
07:14:36 INFO - mozversion == 1.4
07:14:36 INFO - psutil == 3.1.1
07:14:36 INFO - requests == 1.2.3
07:14:36 INFO - Installing None into virtualenv C:\slave\test\build/venv
07:14: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')]}
07:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:14: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')]}
07:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:14:36 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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01859E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0192E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-104', 'USERDOMAIN': 'T-W732-IX-104', '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-104', '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
07:14:36 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
07:14:36 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
07:14:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:14:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:14:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:14:36 INFO - 'COMPUTERNAME': 'T-W732-IX-104',
07:14:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:14:36 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:14:36 INFO - 'DCLOCATION': 'SCL3',
07:14:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:14:36 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:14:36 INFO - 'HOMEDRIVE': 'C:',
07:14:36 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:14:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:14:36 INFO - 'KTS_VERSION': '1.19c',
07:14:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:14:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-104',
07:14:36 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:14:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:14:36 INFO - 'MOZ_NO_REMOTE': '1',
07:14:36 INFO - 'NO_EM_RESTART': '1',
07:14:36 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:14:36 INFO - 'OS': 'Windows_NT',
07:14:36 INFO - 'OURDRIVE': 'C:',
07:14: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;',
07:14:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:14:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:14:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:14:36 INFO - 'PROCESSOR_LEVEL': '6',
07:14:36 INFO - 'PROCESSOR_REVISION': '1e05',
07:14:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:14:36 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:14:36 INFO - 'PROMPT': '$P$G',
07:14:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:14:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:14:36 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:14:36 INFO - 'PWD': 'C:\\slave\\test',
07:14:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:14:36 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:14:36 INFO - 'SYSTEMDRIVE': 'C:',
07:14:36 INFO - 'SYSTEMROOT': 'C:\\windows',
07:14:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:36 INFO - 'TEST1': 'testie',
07:14:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:36 INFO - 'USERDOMAIN': 'T-W732-IX-104',
07:14:36 INFO - 'USERNAME': 'cltbld',
07:14:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:14:36 INFO - 'WINDIR': 'C:\\windows',
07:14:36 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:14:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:14:36 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:14:36 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))
07:14:36 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))
07:14:36 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))
07:14:36 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))
07:14:36 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))
07:14:36 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))
07:14:36 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))
07:14:36 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))
07:14:36 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))
07:14:36 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))
07:14:36 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))
07:14:36 INFO - Cleaning up...
07:14:36 INFO - Return code: 0
07:14:36 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
07:14: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')]}
07:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:14: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')]}
07:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:14:36 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01859E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01878E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0192E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-104', 'USERDOMAIN': 'T-W732-IX-104', '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-104', '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
07:14:36 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build
07:14:36 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
07:14:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:14:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:14:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:14:36 INFO - 'COMPUTERNAME': 'T-W732-IX-104',
07:14:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:14:36 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:14:36 INFO - 'DCLOCATION': 'SCL3',
07:14:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:14:36 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:14:36 INFO - 'HOMEDRIVE': 'C:',
07:14:36 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:14:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:14:36 INFO - 'KTS_VERSION': '1.19c',
07:14:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:14:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-104',
07:14:36 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:14:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:14:36 INFO - 'MOZ_NO_REMOTE': '1',
07:14:36 INFO - 'NO_EM_RESTART': '1',
07:14:36 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:14:36 INFO - 'OS': 'Windows_NT',
07:14:36 INFO - 'OURDRIVE': 'C:',
07:14: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;',
07:14:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:14:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:14:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:14:36 INFO - 'PROCESSOR_LEVEL': '6',
07:14:36 INFO - 'PROCESSOR_REVISION': '1e05',
07:14:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:14:36 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:14:36 INFO - 'PROMPT': '$P$G',
07:14:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:14:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:14:36 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:14:36 INFO - 'PWD': 'C:\\slave\\test',
07:14:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:14:36 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:14:36 INFO - 'SYSTEMDRIVE': 'C:',
07:14:36 INFO - 'SYSTEMROOT': 'C:\\windows',
07:14:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:36 INFO - 'TEST1': 'testie',
07:14:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:36 INFO - 'USERDOMAIN': 'T-W732-IX-104',
07:14:36 INFO - 'USERNAME': 'cltbld',
07:14:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:14:36 INFO - 'WINDIR': 'C:\\windows',
07:14:36 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:14:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:14:37 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
07:14:37 INFO - Cleaning up...
07:14:37 INFO - Return code: 0
07:14:37 INFO - Running post-action listener: _resource_record_post_action
07:14:37 INFO - Running post-action listener: _start_resource_monitoring
07:14:37 INFO - Starting resource monitoring.
07:14:37 INFO - [mozharness: 2016-10-31 14:14:37.343000Z] Finished create-virtualenv step (success)
07:14:37 INFO - [mozharness: 2016-10-31 14:14:37.343000Z] Running install step.
07:14:37 INFO - Running pre-action listener: _resource_record_pre_action
07:14:37 INFO - Running main action method: install
07:14:37 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
07:14:37 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
07:14:37 INFO - Reading from file tmpfile_stdout
07:14:37 INFO - Using _rmtree_windows ...
07:14:37 INFO - Using _rmtree_windows ...
07:14:37 INFO - Detecting whether we're running mozinstall >=1.0...
07:14:37 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
07:14:37 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
07:14:37 INFO - Reading from file tmpfile_stdout
07:14:37 INFO - Output received:
07:14:37 INFO - Usage: mozinstall-script.py [options] installer
07:14:37 INFO - Options:
07:14:37 INFO - -h, --help show this help message and exit
07:14:37 INFO - -d DEST, --destination=DEST
07:14:37 INFO - Directory to install application into. [default:
07:14:37 INFO - "C:\slave\test"]
07:14:37 INFO - --app=APP Application being installed. [default: firefox]
07:14:37 INFO - Using _rmtree_windows ...
07:14:37 INFO - Using _rmtree_windows ...
07:14:37 INFO - mkdir: C:\slave\test\build\application
07:14:37 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']
07:14:37 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
07:14:40 INFO - Reading from file tmpfile_stdout
07:14:40 INFO - Output received:
07:14:40 INFO - C:\slave\test\build\application\firefox\firefox.exe
07:14:40 INFO - Using _rmtree_windows ...
07:14:40 INFO - Using _rmtree_windows ...
07:14:40 INFO - Running post-action listener: _resource_record_post_action
07:14:40 INFO - [mozharness: 2016-10-31 14:14:40.375000Z] Finished install step (success)
07:14:40 INFO - [mozharness: 2016-10-31 14:14:40.375000Z] Running run-tests step.
07:14:40 INFO - Running pre-action listener: _resource_record_pre_action
07:14:40 INFO - Running main action method: run_tests
07:14:40 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
07:14:40 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
07:14:40 INFO - Python 2.7.3
07:14:40 INFO - Return code: 0
07:14:40 INFO - grabbing minidump binary from tooltool
07:14:40 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')]}
07:14:40 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 0x01878E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0192E520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
07:14:40 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
07:14:40 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
07:14:40 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
07:14:40 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
07:14:40 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmph8dmsf
07:14:40 INFO - INFO - File integrity verified, renaming tmph8dmsf to win32-minidump_stackwalk.exe
07:14:40 INFO - Return code: 0
07:14:40 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
07:14:40 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
07:14:40 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
07:14:40 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
07:14:40 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
07:14:40 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-104', '--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
07:14:40 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-104 --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
07:14:40 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:14:40 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:14:40 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:14:40 INFO - 'COMPUTERNAME': 'T-W732-IX-104',
07:14:40 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:14:40 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:14:40 INFO - 'DCLOCATION': 'SCL3',
07:14:40 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:14:40 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:14:40 INFO - 'HOMEDRIVE': 'C:',
07:14:40 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:14:40 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:14:40 INFO - 'KTS_VERSION': '1.19c',
07:14:40 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:14:40 INFO - 'LOGONSERVER': '\\\\T-W732-IX-104',
07:14:40 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
07:14:40 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
07:14:40 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:14:40 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:14:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:14:40 INFO - 'MOZ_NO_REMOTE': '1',
07:14:40 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
07:14:40 INFO - 'NO_EM_RESTART': '1',
07:14:40 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:14:40 INFO - 'OS': 'Windows_NT',
07:14:40 INFO - 'OURDRIVE': 'C:',
07:14:40 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;',
07:14:40 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:14:40 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:14:40 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:14:40 INFO - 'PROCESSOR_LEVEL': '6',
07:14:40 INFO - 'PROCESSOR_REVISION': '1e05',
07:14:40 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:14:40 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:14:40 INFO - 'PROMPT': '$P$G',
07:14:40 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:14:40 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:14:40 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:14:40 INFO - 'PWD': 'C:\\slave\\test',
07:14:40 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
07:14:40 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:14:40 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:14:40 INFO - 'SYSTEMDRIVE': 'C:',
07:14:40 INFO - 'SYSTEMROOT': 'C:\\windows',
07:14:40 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:40 INFO - 'TEST1': 'testie',
07:14:40 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:14:40 INFO - 'USERDOMAIN': 'T-W732-IX-104',
07:14:40 INFO - 'USERNAME': 'cltbld',
07:14:40 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:14:40 INFO - 'WINDIR': 'C:\\windows',
07:14:40 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:14:40 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:14:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:14:40 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-104', '--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
07:14:42 INFO - mozversion application_buildid: 20161031030202
07:14:42 INFO - mozversion application_changeset: e3279760cd977aac30bd9e8032d3ee71f55d2a67
07:14:42 INFO - mozversion application_display_name: Nightly
07:14:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
07:14:42 INFO - mozversion application_name: Firefox
07:14:42 INFO - mozversion application_remotingname: firefox
07:14:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
07:14:42 INFO - mozversion application_vendor: Mozilla
07:14:42 INFO - mozversion application_version: 52.0a1
07:14:42 INFO - mozversion platform_buildid: 20161031030202
07:14:42 INFO - mozversion platform_changeset: e3279760cd977aac30bd9e8032d3ee71f55d2a67
07:14:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
07:14:42 INFO - mozversion platform_version: 52.0a1
07:14:42 INFO - using testdate: 1477923281
07:14:42 INFO - actual date: 1477923281
07:14:42 INFO - starting webserver on 'localhost:49598'
07:14:42 INFO - SUITE-START | Running 2 tests
07:14:42 INFO - TEST-START | dromaeo_css
07:14:42 INFO - operating with platform_type : w7_
07:14:42 INFO - Initialising browser for dromaeo_css test...
07:14:42 INFO - TEST-INFO | started process 3040 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp0xy6ms\profile http://localhost:49598/getInfo.html)
07:14:50 INFO - PROCESS | 3040 | __metrics Screen width/height:1600/1200
07:14:50 INFO - PROCESS | 3040 | colorDepth:24
07:14:50 INFO - PROCESS | 3040 | Browser inner width/height: 1010/674
07:14:50 INFO - PROCESS | 3040 | __metrics
07:14:51 INFO - PROCESS | 3040 | 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"
07:14:51 INFO - PROCESS | 3040 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
07:14:52 INFO - TEST-INFO | 3040: exit 0
07:14:52 INFO - Browser initialized.
07:14:52 INFO - Running cycle 1/1 for dromaeo_css test...
07:14:52 INFO - TEST-INFO | started process 3384 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp0xy6ms\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
07:14:53 INFO - PROCESS | 3384 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
07:15:04 INFO - PROCESS | 3384 | RSS: Main: 124497920
07:15:04 INFO - PROCESS | 3384 |
07:15:55 INFO - PROCESS | 3384 | 1477923355922 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]
07:18:06 INFO - PROCESS | 3384 | Cycle 1(1): loaded http://localhost:49598/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49598/tests/dromaeo/cssquery-ext.html)
07:18:07 INFO - PROCESS | 3384 | RSS: Main: 203665408
07:18:07 INFO - PROCESS | 3384 |
07:21:10 INFO - PROCESS | 3384 | Cycle 1(1): loaded http://localhost:49598/tests/dromaeo/cssquery-ext.html (next: http://localhost:49598/tests/dromaeo/cssquery-jquery.html)
07:21:10 INFO - PROCESS | 3384 | RSS: Main: 199004160
07:21:10 INFO - PROCESS | 3384 |
07:24:12 INFO - PROCESS | 3384 | Cycle 1(1): loaded http://localhost:49598/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49598/tests/dromaeo/cssquery-mootools.html)
07:24:13 INFO - PROCESS | 3384 | RSS: Main: 137379840
07:24:13 INFO - PROCESS | 3384 |
07:27:15 INFO - PROCESS | 3384 | Cycle 1(1): loaded http://localhost:49598/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49598/tests/dromaeo/cssquery-prototype.html)
07:27:16 INFO - PROCESS | 3384 | RSS: Main: 146259968
07:27:16 INFO - PROCESS | 3384 |
07:30:18 INFO - PROCESS | 3384 | Cycle 1(1): loaded http://localhost:49598/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49598/tests/dromaeo/cssquery-yui.html)
07:30:19 INFO - PROCESS | 3384 | RSS: Main: 134041600
07:30:19 INFO - PROCESS | 3384 |
07:33:21 INFO - PROCESS | 3384 | Cycle 1(1): loaded http://localhost:49598/tests/dromaeo/cssquery-yui.html (next: http://localhost:49598/tests/dromaeo/cssquery-dojo.html)
07:33:22 INFO - PROCESS | 3384 | RSS: Main: 183545856
07:33:22 INFO - PROCESS | 3384 |
07:33:22 INFO - PROCESS | 3384 | __start_tp_report
07:33:22 INFO - PROCESS | 3384 | _x_x_mozilla_page_load
07:33:22 INFO - PROCESS | 3384 | _x_x_mozilla_page_load_details
07:33:22 INFO - PROCESS | 3384 | |i|pagename|runs|
07:33:22 INFO - PROCESS | 3384 | |0;dojo.html;2625.566781481055;2704.972950270498;2714.4299697063616;2715.131158029431;2715.6198132261475;4549.043121970721;4552.385427967223;4583.88540286493;4672.415948006499;4674.158651442739;4511.864644060675;4525.570070843273;4525.818967241315;4625.213713668676;4636.698614590049;4041.7372870763306;4069.857554985576;4093.385992101179;4105.055837157455;4119.443875076856;3959.3467077932105;4072;4072.124493233954;4080.2655522006025;4100.282450571153;4056.371262454324;4062.9796851015703;4072.063425412157;4093.5701751315983;4104.527979282391;3958.069853584434;3985.3822657488136;3990.68074554035;4015.7791321477307;4064.979675101635;3275.6560561141214;4012.117334186474;4037.656799172074;4073.8370465181356;4099.938500922489;4423.690341676084;4520.163769702615;4560.863174104782;4606.930896036562;4621.237495813187;4686.297055441677;4721.055788842245;4752.71483710975;4782.426108866924;4883.780229889663;4715.7170569765585;4809.374781278447;4821.566059054667;4895.31465594817;4896.755162241873;4436.7116137451285;4445.97124307956;4489.102179564067;4517.728936263802;4531.3203019547;4720.197566413718;4882.902341953141;4891.682040667345;4993.650444468853;5001.0998020355955;4698.013417182362;4725.669203155746;4755.667103302805;4766.666333356701;4767.76161191939;4794.640401969867;4833.806647734122;4836.879078023077;4838.395200599925;4848.12733707936;4681.485036646034;4770.499097594703;4809.663323567317;4810.807567697253;4817.975910120427;4729.69256998294;4781.019890922353;4814.614830813526;4816.494268101799;4820.68665536739;3186.9362612748077;3285.014495651295;3290.6380298167182;3291.489819078047;3292.226326813197;2975.910722678323;3024.1985873743474;3038.2252525606273;3040.3919216156855;3073.492873675863;4630.884227894329;4656.278276867091;4666.230072038075;4707.7881495332795;4761.666683332133;4794.208955522369;4811.615070794328;4838.395200599925;4848.127337079289;4926.4088309403105;4552.385427967249;4567.086582683477;4676.111538807616;4680.485146633868;4681.368015317957;3976.1848820991627;3994.940075898864;4066.9629244542452;4086.244044851712;4087.795610219477;5019.799208031711;5080.695158290514;5126.79492820283;5141.203113517336;5145.099607568735;4781.737004464788;4842.661013729005;4855.8057677692495;4886.828960986349;4896.559309662148;4928.827491037797;4979.850604481871;4988.45127036033;5016.122178618654;5106.055379754757;4686.882827929329;4730.2431610942085;4733.124371991193;4756.6908150970075;4778.808847646055;4903.166461701448;4910.803567857246;4965.875853103701;5025.1959686451455;5026.723530205728;4692.554207350297;4750.952490475051;4758.76206189696;4798.23228283473;4873.537013983666;4689.929651055305;4735.928961065656;4770.952290477051;4791.928121078117;4820.638452116175;4620.191466493283;4627.7686115693405;4727.38543989293;4729.858104256878;4741.2651039089;4699.224627936353;4704.411948506437;4809.2305231162845;4823.348847905629;4830.033993201444;4550;4554.954450455585;4564.8402305919135;4587.8623641290815;4653.395058642355
07:33:22 INFO - PROCESS | 3384 | |1;ext.html;523.9423663397025;527.2855281094118;538.6552606331949;562.8874225154967;565.4430386069723;26287.211383658498;27487.862560687194;35522.99999999997;37150.628493715056;37296.06759831006;36723.81638091813;36916.630833691655;37264.62735372652;37582.24835503295;37638.247235055285;6254.843628909268;6429.48564114872;7687.923120768804;7827.373810095207;7854.7250846220395;9340.15938565529;9486.9525652372;9544.950055493924;9839.606415743361;9854.70435886921;9578.808423831519;9640.80718385632;9764.804703905918;9767.218622510218;9781.951090244575;6453.870922581569;6596.703148358288;9408.388454750419;9533.952330238339;9627.133557979745;6378.170837791104;6390.392912673288;9189.724308270763;9525.857112143323;9535.570899309549;35320.29359412797;35841.46237806435;35846.64153358459;35924.64075359239;35981.640183598094;2823.632927719404;2884.610577572022;2892.522733748929;3116.3611459650715;3261.3640340133684;27757.306067348276;27848.860755696296;28218.29454263639;28439.573406398922;28569.285767855763;3353.3628610563915;3395.898123056312;3465.3589086018915;3490.528778614905;3513.6662017108333;325854;333312;337553;338808;339952;26287;50692.49307506951;51098.48901511011;51281.743591281804;51298.74350628223;26340.20979370622;26469.867650661625;26607.46785064288;27003.729962700123;27124.321891952466;11591.304521728724;11608.303501789751;11708.590199342982;11727.237729547553;11776.470058847372;8974.596143173572;9143.222826059726;9845.852312215322;9856;9968.152707019985;5986.281646202484;6055.303640081447;6130.938690613126;6133.509319254449;6155.784547540904;2452.0927256915056;2466.691663542057;2467.2474895157147;2487.092211342883;2491.5266099441046;769.2192424688945;799.8840168175699;806.6491076381793;821.7165078048064;911.2709832134266;25420.618690719653;25488;25956;26341.20976370712;26397.20808375752;829.3489610655606;890.3633901760143;895.3195571365678;916.7662246127195;921.3550514639645;13275.269860157785;13369.33153342329;13602.115862468909;13786.862131378759;13806.17162970225;416372;466417;471323;472405;475588;43746.781266093465;44300.77849610796;44629.553704463186;44715.329270060975;44965.999999999345;916.3173435790379;938.4603852784676;999.4952548962873;1008.495752123938;1035.0167341026206;25815.870920645277;25859;26710.732892671604;27127.457450851267;27301.45397092087;35700;38735;39219;39397.2120557593;39565.60434395619;370081;377005;379406;537215;575380;35262.76846778403;36264;36326.818365908;36370.45444318285;36518.08704782402;39517.60482395254;39881.4803070009;40159.59840401559;40488.190236194525;40519.1896162081;31820.204494887814;32622.34755304928;33474.49788253227;33815.15462113368;34171.65828341685;32129.678703213605;33350.332993339514;33714.662853371156;34496.310073798886;35020;32044.359112818078;32065.519017214294;32465.837670811496;32693.836530817192;32728.018159455256;28549.28626784347;30197;30326.545101823933;30639.387212255184;30796.846015769777;23067;23778.286651400485;24013.55749186406;24079.27762167138;24180.39549011289
07:33:22 INFO - PROCESS | 3384 | |2;jquery.html;264.7683277132509;282.31397703580296;286.6846468884228;292.9340898297883;294.43762413789653;12364.876351236486;16158.353665853367;16465.094419806905;16567.668646627077;16635.334586616522;15114.924425377858;15488.303026363812;15714.449994250204;16268.674626507493;16353.345866165339;4932.334134891788;5025.3718285214345;5061.54446099851;5123.180291153416;5146.794128234885;3536.098294934801;3690.612485689004;4031.2945234583976;5062.0888240116765;5115.462876397981;5854.76580936762;5937.554683398763;5938.88122237555;5950.077737950624;5957.553183511232;5610.635308704962;5766.538676905838;5824.708764561755;5929.347771745104;5941.643501389909;4846.297286893416;4912.828051018233;4919.655624106314;4925.13810083234;4927.113119638464;19579.216831326874;20571.177152913864;20724.378268651966;21319.040643171094;21374.679379809317;1800.828921252492;1819.5451137215696;1848.741176235328;1950.1321911749135;1997.1512107354317;12703.745925081446;12949.935250323782;13564.593062208149;13578.456861725519;13716.519921802788;1650.6616143690514;1659.7842280503578;1661.4517209320895;1710.7091794394983;1717.5105095047852;72984.99999999948;75199.24800751977;77284.61357693232;77499.61250193712;77798.22201777967;8210.096889342167;8272.751817445485;8396.664133434595;8458.788530286793;8485.95757021211;9841.458719770339;9950.651727189514;10390.636327728493;10481.737956551147;10821.945890270656;6662.866742665216;6741.662916854138;6809.897851532231;6819.5908245505425;6946.583205007716;6860.382565569123;6983.1620205575655;7026.191987921352;7233.529820561646;7255.963720181366;5357.142857142916;5442.319710036246;5484.314460692413;5530.198121272393;5578.04768331089;1453.8255409350945;1457.0674768148394;1464.8754855837374;1497.857703518164;1508.3664860758508;744.2297222374816;806.9152738962442;808.5068108453838;839.6053854688287;861.3884142259067;17251.223694933757;17508.21213045416;18015.2793888243;18321.3587524436;18342.266309347477;754.3625636337287;812.1918690902589;814.5886327404742;910.1353713971753;917.1699611851285;9777.168940640126;9777.266704997011;10044.698659040241;10130.632364630828;10150.695479135638;345309;372129;380410;382138;382879;78379.60810195912;100480;103572.48213759034;103981;106011.62831389405;808.5876203136443;816.4896939412866;833.1377024779324;848.024771512753;848.5375470368664;15924.522264332089;16307.347706091623;16389.754153687943;16401.343946242017;16706.331746729993;45017.54982450265;45163.54836451725;45411.31883021823;45803.3129503051;46268.604482574105;59604;61255;66060.66969665121;66817.66591167013;67067;6852.314768523108;6977.197622273504;7036.718531258693;7079.646017699198;7162.856742865218;8592.871106933526;11952.864477874587;12248.448819802952;12759.872401275868;12850.42173102194;10161.644342447978;10360.689179324632;10376.429296388469;10381;10504.632337868137;10565.577376905145;10775.622853200413;10896.56413743473;10904.454777261264;11109.944450277697;10167.949160254151;11025.558977640805;11309.321440713584;11395.544178233109;11453.19827612059;7198.5320954137815;7282.635868206463;7303.488755787044;7320.926790732237;7325.377342925911;9785.217182625514;10349.379037257788;10349.482525873827;10594.523246453773;11064.723381915517
07:33:22 INFO - PROCESS | 3384 | |3;mootools.html;1015.3806178231273;1066.1470823341328;1068.0761141612502;1160.0197832831257;1275.3113318807843;5673.602847800656;6371.235451745785;6435.678216089194;6440.838979025527;6555.508336874742;6513.153290072284;6529.444997175246;6556.868862622745;6581.177352830896;6589.5057870659775;1759.7624320716732;1774.4587900690285;1785.8482029027484;1798.3166396769209;1798.9550261243508;1755.2013833705632;1781.7416474611173;1784.2595322940963;1795.703708888032;1805.9097045147755;1710.3415185153742;1714.8199439058906;1740.8433241008306;1746.0920321432848;1747.248683066281;1692.6699293637807;1702.3360889253202;1728.490095421841;1738.0353903583507;1745.6683230186225;1688.6031782531095;1697.533178375944;1710.1620206099046;1712.6660301241263;1731.4719010701856;6298.244210694746;6352.936470635327;6413.871722565523;6524.93475065248;6551.181102362205;3831.3295173344554;3923.7645741255615;3936.704747143947;3948.940765888514;3951.5258169019903;2637.8681065946625;2731.0441345529102;2745.3685652299882;2776.583512473125;2778.6248856404327;854.6538651846013;885.3227281129941;908.9727308180766;915.7023967210616;917.3395155488038;8208.712695055645;8264.628091735887;8283.130271321545;8326.45878017933;8345.624446899905;3122.859471323796;3219.8551065202123;3257.3322468894294;3263.4615288477135;3279.8688052477632;6396.1684980953105;6711.966440167768;6716.1604799400075;6718.160229971254;6737.427318677967;3776.7922764247683;3789.810509474515;3791.563970143414;3791.9241615168094;3795;3280.5079238113995;3354.446516324828;3642.3807952648112;3647.835847386874;3692.7784332939573;2758.8620568971874;2792.287966568512;2836.8723407446714;2843.402885394049;2864.627598412235;850.4174640371366;864.4165188497739;866.5797088412115;871.6208449324438;875.9824803504022;637.1812221295705;653.4054010850103;678.0067201549754;686.5708931917552;724.9963750181321;4021.879343619696;4194.328907374806;4332;4351.73889566627;4426.535213802505;736.1276886889053;749.3555542233674;751.1587022534687;771.2518856708983;814.7840822182127;2894.6526416829693;2934.2517657997073;3014.5478178273434;3038.301190726101;3082.3989322082425;7361.521501102518;7414.703411863465;7436.070491188601;7695.115061767969;7850.057993040871;7495.400367970549;7604.429667774939;7745.883811742829;7755.302022817974;7758.185390534026;5868.2077919480325;6003.129546215774;6116.143739876421;6138.5396095292135;6152.046432802833;4215.114825886476;4347.434833471629;4352;4409;4449.421575195204;7169.892451613126;7255.52839065459;7256.1655409628565;7271.709131634675;7272.563646181246;7254.5647261164495;7399.889001664872;7449.478536502393;7476.813079673052;7515.887261690969;5670.432956704297;5772.94227057724;5774.711264436677;5780.508656764222;5783.219265399285;5555.527780138572;5772.5381969441505;6317.210348706411;6326.2092238470195;6414.935850641434;5639.802606908903;5652.954203472371;5661.943380566142;5790.826275211751;5821.446962538552;5594;5632.493075623213;5695.971520142539;5705.714714264187;5749.511291540266;5343.812966546152;5397.3523177218985;5449.727513624223;5472.315960504937;5489.560835133259;3689.9262014760093;3756.530433695788;3787.5454945406727;3866.130120722815;3988.6210809973004;3759.4172903200047;3827.3876179811095;3827.5598306195143;3848.6536211741077;3895.922081558296
07:33:22 INFO - PROCESS | 3384 | |4;prototype.html;225.81821633584957;231.64442580638723;232.50592490956717;234.4970039265775;238.62297569839654;5047.293378926948;5088.847334579968;5089.974550127254;5097.872553186167;5108.233764935257;4817.735024573647;4900.6079513638915;4922.778474968626;4933.827316043939;5015.9749201254035;3611.837467313964;3651.580068292147;3654.8172591370458;3657.5610926688832;3667.8166091695443;3647.7993710345922;3651.7626354286886;3655.981720091396;3658.762180458275;3665.706743460517;3461.9480707789403;3549.94675079874;3553.6091029986683;3557.8576856925692;3624.2026754114186;3520.9823950880336;3544.0785395797147;3545.996511970728;3557.7331700122463;3604.9279014419826;3113.003838771594;3117.1427857339186;3118.3295591447722;3120.329129237225;3132.7180553750045;4888.608911287089;4900.509949005107;4905.901881962376;4923.753812309406;4926.433460152092;3991.341428664267;4253.106847561985;4256.425382573344;4257.680673949466;4260.190563792869;5194.480551944813;5207.854272060141;5212.713300768456;5231;5236.607254455931;3347.196672798535;3350.882719104844;3351.346487434951;3355.909329467933;3363.5459213006175;5465.371482279549;5466.480684334982;5500.80747173851;5504.724763761796;5509.944900550983;3366.983165084159;3404.336154449908;3414.2488652496413;3432.5880894292345;3439.81326442377;3302.1084307237393;3396.1849156202334;3432.467967465047;3435.553378060886;3489.6335884732243;4122.319817230184;4186.1418409226035;4188.5811418857875;4189.643880270211;4197.139586384784;5255.842324730264;5284.7886084556185;5287.656302340336;5299.33758280215;5308.309919710413;1190.7737529869298;1206.6500714792612;1209.0871392099084;1209.4799236328288;1210.9515619375302;703.8838591632426;735.8086897406713;740.5371642723298;744.0810598910341;749.5952185819597;5007.524285192901;5018.272350509155;5095.770690318945;5173.870653233699;5222.97388513055;5474.753636086386;5488.890222195613;5501.339839219319;5501.779928802883;5514;5125.948740512622;5174.663646862942;5186.4035635902355;5199.532042116228;5200.2199670049795;2829.7028811974856;2885.884564617392;2898.8985385511405;2913.3881884804428;2932.4868148074;5715.8856822864145;5731.455511726379;5734.197212390269;5787.94212057874;5795.391483894215;5489.698066606375;5517.282753242052;5523.364813046471;5526.115821468546;5535.58483113768;5465.59008074388;5730.627509211888;5771.989901767174;5773.430823283598;5775.653460792366;5354.848707527744;5388.892222155457;5449.455054494519;5463.235147079492;5470.398256191736;5331.893362132813;5355.491228333302;5360.954613850262;5378.247045413724;5378.462153784747;5694.658320500783;5758.366579676316;5767.884642307215;5792.103948025987;5799.420057994167;3301.6368199498515;3411.703552649977;3458.8962331130106;3482.8432720528144;3487.668671476206;5451.64564303319;5472.480114389126;5488.725563721718;5497.45025497447;5501.779928802963;5236.842894713165;5303.496167864047;5311.867203319948;5315.255864179096;5317.006122703986;5169.7673604687125;5222.9216561750845;5307.416184219655;5315.760790764502;5343.332083489564;5181.3005244293045;5181.41966700145;5191.520416681227;5196.870078248074;5200.531952124327;5297.390800057889;5302.390225124006;5317.627766056494;5319.255304257485;5327.094393953114;4967.584238492012;5037.974810125926;5130.58955283569;5224.50367215114;5254.868628284171
07:33:22 INFO - PROCESS | 3384 | |5;yui.html;580.4108829538019;583.5623282538095;590.5393792841585;591.5060924128353;591.7662523303295;13326.133801302909;14074.366653500592;14115.717685646281;14116.435342586297;14130.858691413083;12170.513179472811;12518.18631788933;12634.431450584723;12699.555515556958;12733.617991460247;465.22075523498006;474.67247599156644;570.9115087161497;576.0523938121787;579.8985177593926;473.27116240988835;475.4674764264029;475.7621189405297;565.3046752494434;583.98540036499;606.1362558354327;607.6050567131377;608.4523928464373;610.5054905526538;610.914471973924;596.8000719758886;605.3734384911618;605.9636421814705;606.872556763078;607.2166904692984;334.12292731579606;362.5739755786966;431.56843156843155;439.58678841888843;440.5440369217876;11642.94178529102;11943.940280298542;12288.201266917622;12369.196002259825;12467.625971220878;11868.406579671067;12097.334646594434;12265.325407102606;12357.505699771998;12599.93700031494;11204.93553112453;12150;12282.692932676666;12369.13416060876;12401.937990310082;3705.7220708446703;4002.6197511236387;4057.7565346079036;4075.9796201019103;4082.9183416331507;73452.6327368365;73772.6311368445;74128.62935685342;74531;76294;9672.661456849117;9816.36193647425;9898.208143348515;10054.949725251327;10131.08820206185;16729.16354182286;16807.663846722997;16971;17000.914995424944;17034.403795867333;6661.200655921223;6668.433183179387;6787;6809.285025072395;6874.931250687529;3547.7161827053774;3729.776213427203;3768.0768211788286;3824.4072168813877;3836.55879573847;2612.6472926155107;2683.0877501649534;2715.2397328747984;2715.7827373810046;2724.7820174386;471.75468756247244;480.2556038140868;487.07455833915134;505.44653604303227;505.84066019203834;508.9491050894881;641.3041849593244;649.5842660697158;669.4075742967411;676.5230512488683;8877.422967507091;8882.200601945857;9293.070692930789;9295.76760580991;9385.436873787594;622.9657368844757;636.2587585462976;653.4347789162435;657.789507357641;659.5317324699421;4293.527711951683;4422.358757980075;4466.106778644219;4539.682222244412;4561.384213131251;82432;84219.15780842236;85640.57179714061;86234.56882715547;86667.56666216628;50472.49527504751;60287.69856150691;69953;76001.61999190079;76084.23915760772;56917.99999999917;65395;65527.344726552124;67198;67897;8390.538520381439;8610.870836937325;8624.223819856243;8776;8777.253933415732;45194.77402612966;45311.77344113258;45665.77167114143;45950.31074533951;46987.831338552045;55683;56551.71724141353;61575;63024;63711.6814415925;10378.948105259426;10483.423411712429;10503.789924201626;10664.306820056672;10988.725281868017;11076;11648.88351116478;12322;12689.36553172356;13326.533571324957;11312.547498100314;12293;12827.950622689377;13164.40760165814;13197.340132993504;11636.12729045308;11921.165518413627;12432.316222607844;12901.806472902726;13596.252206128362;12632.557860474839;12807.80788288195;12838.935805320914;12877.613671589866;13476.93261533686;10979.17656175774;11044.723881903017;11280.110185194451;11432.885671143182;11562.421878905852;10513.474326283807;11182.105431565618;11193.440327983406;11200.71998200019;11367.829482557934
07:33:22 INFO - PROCESS | 3384 | __end_tp_report
07:33:22 INFO - PROCESS | 3384 | __start_cc_report
07:33:22 INFO - PROCESS | 3384 | _x_x_mozilla_cycle_collect,1157
07:33:22 INFO - PROCESS | 3384 | __end_cc_report
07:33:22 INFO - PROCESS | 3384 | __startTimestamp1477924402202__endTimestamp
07:33:22 INFO - PROCESS | 3384 |
07:33:22 INFO - PROCESS | 3384 | ------- Summary: start -------
07:33:22 INFO - PROCESS | 3384 | Number of tests: 6
07:33:22 INFO - PROCESS | 3384 |
07:33:22 INFO - PROCESS | 3384 | [#0] dojo.html Cycles:165 Average:4466.71 Median:4686.88 stddev:567.60 (12.1%) stddev-sans-first:550.66
07:33:22 INFO - PROCESS | 3384 | Values: 2625.6 2705.0 2714.4 2715.1 2715.6 4549.0 4552.4 4583.9 4672.4 4674.2 4511.9 4525.6 4525.8 4625.2 4636.7 4041.7 4069.9 4093.4 4105.1 4119.4 3959.3 4072.0 4072.1 4080.3 4100.3 4056.4 4063.0 4072.1 4093.6 4104.5 3958.1 3985.4 3990.7 4015.8 4065.0 3275.7 4012.1 4037.7 4073.8 4099.9 4423.7 4520.2 4560.9 4606.9 4621.2 4686.3 4721.1 4752.7 4782.4 4883.8 4715.7 4809.4 4821.6 4895.3 4896.8 4436.7 4446.0 4489.1 4517.7 4531.3 4720.2 4882.9 4891.7 4993.7 5001.1 4698.0 4725.7 4755.7 4766.7 4767.8 4794.6 4833.8 4836.9 4838.4 4848.1 4681.5 4770.5 4809.7 4810.8 4818.0 4729.7 4781.0 4814.6 4816.5 4820.7 3186.9 3285.0 3290.6 3291.5 3292.2 2975.9 3024.2 3038.2 3040.4 3073.5 4630.9 4656.3 4666.2 4707.8 4761.7 4794.2 4811.6 4838.4 4848.1 4926.4 4552.4 4567.1 4676.1 4680.5 4681.4 3976.2 3994.9 4067.0 4086.2 4087.8 5019.8 5080.7 5126.8 5141.2 5145.1 4781.7 4842.7 4855.8 4886.8 4896.6 4928.8 4979.9 4988.5 5016.1 5106.1 4686.9 4730.2 4733.1 4756.7 4778.8 4903.2 4910.8 4965.9 5025.2 5026.7 4692.6 4751.0 4758.8 4798.2 4873.5 4689.9 4735.9 4771.0 4791.9 4820.6 4620.2 4627.8 4727.4 4729.9 4741.3 4699.2 4704.4 4809.2 4823.3 4830.0 4550.0 4555.0 4564.8 4587.9 4653.4
07:33:22 INFO - PROCESS | 3384 |
07:33:22 INFO - PROCESS | 3384 | [#1] ext.html Cycles:180 Average:53088.72 Median:36444.27 stddev:112457.74 (308.6%) stddev-sans-first:112703.96
07:33:22 INFO - PROCESS | 3384 | Values: 523.9 527.3 538.7 562.9 565.4 26287.2 27487.9 35523.0 37150.6 37296.1 36723.8 36916.6 37264.6 37582.2 37638.2 6254.8 6429.5 7687.9 7827.4 7854.7 9340.2 9487.0 9545.0 9839.6 9854.7 9578.8 9640.8 9764.8 9767.2 9782.0 6453.9 6596.7 9408.4 9534.0 9627.1 6378.2 6390.4 9189.7 9525.9 9535.6 35320.3 35841.5 35846.6 35924.6 35981.6 2823.6 2884.6 2892.5 3116.4 3261.4 27757.3 27848.9 28218.3 28439.6 28569.3 3353.4 3395.9 3465.4 3490.5 3513.7 325854.0 333312.0 337553.0 338808.0 339952.0 26287.0 50692.5 51098.5 51281.7 51298.7 26340.2 26469.9 26607.5 27003.7 27124.3 11591.3 11608.3 11708.6 11727.2 11776.5 8974.6 9143.2 9845.9 9856.0 9968.2 5986.3 6055.3 6130.9 6133.5 6155.8 2452.1 2466.7 2467.2 2487.1 2491.5 769.2 799.9 806.6 821.7 911.3 25420.6 25488.0 25956.0 26341.2 26397.2 829.3 890.4 895.3 916.8 921.4 13275.3 13369.3 13602.1 13786.9 13806.2 416372.0 466417.0 471323.0 472405.0 475588.0 43746.8 44300.8 44629.6 44715.3 44966.0 916.3 938.5 999.5 1008.5 1035.0 25815.9 25859.0 26710.7 27127.5 27301.5 35700.0 38735.0 39219.0 39397.2 39565.6 370081.0 377005.0 379406.0 537215.0 575380.0 35262.8 36264.0 36326.8 36370.5 36518.1 39517.6 39881.5 40159.6 40488.2 40519.2 31820.2 32622.3 33474.5 33815.2 34171.7 32129.7 33350.3 33714.7 34496.3 35020.0 32044.4 32065.5 32465.8 32693.8 32728.0 28549.3 30197.0 30326.5 30639.4 30796.8 23067.0 23778.3 24013.6 24079.3 24180.4
07:33:22 INFO - PROCESS | 3384 |
07:33:22 INFO - PROCESS | 3384 | [#2] jquery.html Cycles:180 Average:25196.20 Median:24524.42 stddev:62735.91 (255.8%) stddev-sans-first:62883.98
07:33:22 INFO - PROCESS | 3384 | Values: 264.8 282.3 286.7 292.9 294.4 12364.9 16158.4 16465.1 16567.7 16635.3 15114.9 15488.3 15714.4 16268.7 16353.3 4932.3 5025.4 5061.5 5123.2 5146.8 3536.1 3690.6 4031.3 5062.1 5115.5 5854.8 5937.6 5938.9 5950.1 5957.6 5610.6 5766.5 5824.7 5929.3 5941.6 4846.3 4912.8 4919.7 4925.1 4927.1 19579.2 20571.2 20724.4 21319.0 21374.7 1800.8 1819.5 1848.7 1950.1 1997.2 12703.7 12949.9 13564.6 13578.5 13716.5 1650.7 1659.8 1661.5 1710.7 1717.5 72985.0 75199.2 77284.6 77499.6 77798.2 8210.1 8272.8 8396.7 8458.8 8486.0 9841.5 9950.7 10390.6 10481.7 10821.9 6662.9 6741.7 6809.9 6819.6 6946.6 6860.4 6983.2 7026.2 7233.5 7256.0 5357.1 5442.3 5484.3 5530.2 5578.0 1453.8 1457.1 1464.9 1497.9 1508.4 744.2 806.9 808.5 839.6 861.4 17251.2 17508.2 18015.3 18321.4 18342.3 754.4 812.2 814.6 910.1 917.2 9777.2 9777.3 10044.7 10130.6 10150.7 345309.0 372129.0 380410.0 382138.0 382879.0 78379.6 100480.0 103572.5 103981.0 106011.6 808.6 816.5 833.1 848.0 848.5 15924.5 16307.3 16389.8 16401.3 16706.3 45017.5 45163.5 45411.3 45803.3 46268.6 59604.0 61255.0 66060.7 66817.7 67067.0 6852.3 6977.2 7036.7 7079.6 7162.9 8592.9 11952.9 12248.4 12759.9 12850.4 10161.6 10360.7 10376.4 10381.0 10504.6 10565.6 10775.6 10896.6 10904.5 11109.9 10167.9 11025.6 11309.3 11395.5 11453.2 7198.5 7282.6 7303.5 7320.9 7325.4 9785.2 10349.4 10349.5 10594.5 11064.7
07:33:22 INFO - PROCESS | 3384 |
07:33:22 INFO - PROCESS | 3384 | [#3] mootools.html Cycles:180 Average:4198.34 Median:5574.76 stddev:2301.87 (41.3%) stddev-sans-first:2295.89
07:33:22 INFO - PROCESS | 3384 | Values: 1015.4 1066.1 1068.1 1160.0 1275.3 5673.6 6371.2 6435.7 6440.8 6555.5 6513.2 6529.4 6556.9 6581.2 6589.5 1759.8 1774.5 1785.8 1798.3 1799.0 1755.2 1781.7 1784.3 1795.7 1805.9 1710.3 1714.8 1740.8 1746.1 1747.2 1692.7 1702.3 1728.5 1738.0 1745.7 1688.6 1697.5 1710.2 1712.7 1731.5 6298.2 6352.9 6413.9 6524.9 6551.2 3831.3 3923.8 3936.7 3948.9 3951.5 2637.9 2731.0 2745.4 2776.6 2778.6 854.7 885.3 909.0 915.7 917.3 8208.7 8264.6 8283.1 8326.5 8345.6 3122.9 3219.9 3257.3 3263.5 3279.9 6396.2 6712.0 6716.2 6718.2 6737.4 3776.8 3789.8 3791.6 3791.9 3795.0 3280.5 3354.4 3642.4 3647.8 3692.8 2758.9 2792.3 2836.9 2843.4 2864.6 850.4 864.4 866.6 871.6 876.0 637.2 653.4 678.0 686.6 725.0 4021.9 4194.3 4332.0 4351.7 4426.5 736.1 749.4 751.2 771.3 814.8 2894.7 2934.3 3014.5 3038.3 3082.4 7361.5 7414.7 7436.1 7695.1 7850.1 7495.4 7604.4 7745.9 7755.3 7758.2 5868.2 6003.1 6116.1 6138.5 6152.0 4215.1 4347.4 4352.0 4409.0 4449.4 7169.9 7255.5 7256.2 7271.7 7272.6 7254.6 7399.9 7449.5 7476.8 7515.9 5670.4 5772.9 5774.7 5780.5 5783.2 5555.5 5772.5 6317.2 6326.2 6414.9 5639.8 5653.0 5661.9 5790.8 5821.4 5594.0 5632.5 5696.0 5705.7 5749.5 5343.8 5397.4 5449.7 5472.3 5489.6 3689.9 3756.5 3787.5 3866.1 3988.6 3759.4 3827.4 3827.6 3848.7 3895.9
07:33:22 INFO - PROCESS | 3384 |
07:33:22 INFO - PROCESS | 3384 | [#4] prototype.html Cycles:180 Average:4346.25 Median:5128.27 stddev:1406.27 (27.4%) stddev-sans-first:1375.78
07:33:22 INFO - PROCESS | 3384 | Values: 225.8 231.6 232.5 234.5 238.6 5047.3 5088.8 5090.0 5097.9 5108.2 4817.7 4900.6 4922.8 4933.8 5016.0 3611.8 3651.6 3654.8 3657.6 3667.8 3647.8 3651.8 3656.0 3658.8 3665.7 3461.9 3549.9 3553.6 3557.9 3624.2 3521.0 3544.1 3546.0 3557.7 3604.9 3113.0 3117.1 3118.3 3120.3 3132.7 4888.6 4900.5 4905.9 4923.8 4926.4 3991.3 4253.1 4256.4 4257.7 4260.2 5194.5 5207.9 5212.7 5231.0 5236.6 3347.2 3350.9 3351.3 3355.9 3363.5 5465.4 5466.5 5500.8 5504.7 5509.9 3367.0 3404.3 3414.2 3432.6 3439.8 3302.1 3396.2 3432.5 3435.6 3489.6 4122.3 4186.1 4188.6 4189.6 4197.1 5255.8 5284.8 5287.7 5299.3 5308.3 1190.8 1206.7 1209.1 1209.5 1211.0 703.9 735.8 740.5 744.1 749.6 5007.5 5018.3 5095.8 5173.9 5223.0 5474.8 5488.9 5501.3 5501.8 5514.0 5125.9 5174.7 5186.4 5199.5 5200.2 2829.7 2885.9 2898.9 2913.4 2932.5 5715.9 5731.5 5734.2 5787.9 5795.4 5489.7 5517.3 5523.4 5526.1 5535.6 5465.6 5730.6 5772.0 5773.4 5775.7 5354.8 5388.9 5449.5 5463.2 5470.4 5331.9 5355.5 5361.0 5378.2 5378.5 5694.7 5758.4 5767.9 5792.1 5799.4 3301.6 3411.7 3458.9 3482.8 3487.7 5451.6 5472.5 5488.7 5497.5 5501.8 5236.8 5303.5 5311.9 5315.3 5317.0 5169.8 5222.9 5307.4 5315.8 5343.3 5181.3 5181.4 5191.5 5196.9 5200.5 5297.4 5302.4 5317.6 5319.3 5327.1 4967.6 5038.0 5130.6 5224.5 5254.9
07:33:22 INFO - PROCESS | 3384 |
07:33:22 INFO - PROCESS | 3384 | [#5] yui.html Cycles:180 Average:17053.46 Median:45253.27 stddev:23148.77 (51.2%) stddev-sans-first:23180.66
07:33:22 INFO - PROCESS | 3384 | Values: 580.4 583.6 590.5 591.5 591.8 13326.1 14074.4 14115.7 14116.4 14130.9 12170.5 12518.2 12634.4 12699.6 12733.6 465.2 474.7 570.9 576.1 579.9 473.3 475.5 475.8 565.3 584.0 606.1 607.6 608.5 610.5 610.9 596.8 605.4 606.0 606.9 607.2 334.1 362.6 431.6 439.6 440.5 11642.9 11943.9 12288.2 12369.2 12467.6 11868.4 12097.3 12265.3 12357.5 12599.9 11204.9 12150.0 12282.7 12369.1 12401.9 3705.7 4002.6 4057.8 4076.0 4082.9 73452.6 73772.6 74128.6 74531.0 76294.0 9672.7 9816.4 9898.2 10054.9 10131.1 16729.2 16807.7 16971.0 17000.9 17034.4 6661.2 6668.4 6787.0 6809.3 6874.9 3547.7 3729.8 3768.1 3824.4 3836.6 2612.6 2683.1 2715.2 2715.8 2724.8 471.8 480.3 487.1 505.4 505.8 508.9 641.3 649.6 669.4 676.5 8877.4 8882.2 9293.1 9295.8 9385.4 623.0 636.3 653.4 657.8 659.5 4293.5 4422.4 4466.1 4539.7 4561.4 82432.0 84219.2 85640.6 86234.6 86667.6 50472.5 60287.7 69953.0 76001.6 76084.2 56918.0 65395.0 65527.3 67198.0 67897.0 8390.5 8610.9 8624.2 8776.0 8777.3 45194.8 45311.8 45665.8 45950.3 46987.8 55683.0 56551.7 61575.0 63024.0 63711.7 10378.9 10483.4 10503.8 10664.3 10988.7 11076.0 11648.9 12322.0 12689.4 13326.5 11312.5 12293.0 12828.0 13164.4 13197.3 11636.1 11921.2 12432.3 12901.8 13596.3 12632.6 12807.8 12838.9 12877.6 13476.9 10979.2 11044.7 11280.1 11432.9 11562.4 10513.5 11182.1 11193.4 11200.7 11367.8
07:33:22 INFO - PROCESS | 3384 | -------- Summary: end --------
07:33:22 INFO - PROCESS | 3384 |
07:33:22 INFO - TEST-INFO | 3384: exit 0
07:33:23 INFO - TEST-OK | dromaeo_css | took 1121478ms
07:33:23 INFO - TEST-START | kraken
07:33:23 INFO - operating with platform_type : w7_
07:33:23 INFO - Initialising browser for kraken test...
07:33:23 INFO - TEST-INFO | started process 3864 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmplqajzt\profile http://localhost:49598/getInfo.html)
07:33:27 INFO - PROCESS | 3864 | __metrics Screen width/height:1600/1200
07:33:27 INFO - PROCESS | 3864 | colorDepth:24
07:33:27 INFO - PROCESS | 3864 | Browser inner width/height: 1010/674
07:33:27 INFO - PROCESS | 3864 | __metrics
07:33:28 INFO - PROCESS | 3864 | 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"
07:33:28 INFO - PROCESS | 3864 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
07:33:28 INFO - PROCESS | 3864 | console.error:
07:33:28 INFO - PROCESS | 3864 | Message: Error: Connection closed before committing the transaction.
07:33:28 INFO - PROCESS | 3864 | Stack:
07:33:28 INFO - PROCESS | 3864 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
07:33:28 INFO - PROCESS | 3864 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
07:33:28 INFO - PROCESS | 3864 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
07:33:28 INFO - PROCESS | 3864 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
07:33:28 INFO - PROCESS | 3864 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
07:33:28 INFO - PROCESS | 3864 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15
07:33:28 INFO - PROCESS | 3864 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
07:33:28 INFO - PROCESS | 3864 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
07:33:28 INFO - PROCESS | 3864 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
07:33:28 INFO - PROCESS | 3864 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
07:33:28 INFO - PROCESS | 3864 | Barrier/this.client.addBlocker/promise13.1%
07:34:26 INFO - TinderboxPrint: I/O read bytes / time
201,582,080 / 2,280,940
07:34:26 INFO - TinderboxPrint: I/O write bytes / time
471,550,464 / 3,613,960
07:34:26 INFO - TinderboxPrint: CPU idle
8,258.6 (86.9%)
07:34:26 INFO - TinderboxPrint: CPU user
1,211.0 (12.7%)
07:34:26 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 35496960; Read time: 0; Write time: 261460
07:34:26 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 201582080; Write bytes: 399577600; Read time: 2280940; Write time: 3056320
07:34:26 INFO - Running post-run listener: _upload_blobber_files
07:34:26 INFO - Blob upload gear active.
07:34:26 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
07:34:26 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
07:34:26 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Firefox', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
07:34:26 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Firefox -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
07:34:27 INFO - (blobuploader) - INFO - Open directory for files ...
07:34:27 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
07:34:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
07:34:27 INFO - (blobuploader) - INFO - Uploading, attempt #1.
07:34:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
07:34:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
07:34:28 INFO - (blobuploader) - INFO - Done attempting.
07:34:28 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
07:34:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
07:34:28 INFO - (blobuploader) - INFO - Uploading, attempt #1.
07:34:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
07:34:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
07:34:30 INFO - (blobuploader) - INFO - Done attempting.
07:34:30 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
07:34:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
07:34:30 INFO - (blobuploader) - INFO - Uploading, attempt #1.
07:34:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
07:34:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
07:34:37 INFO - (blobuploader) - INFO - Done attempting.
07:34:37 INFO - (blobuploader) - INFO - Iteration through files over.
07:34:37 INFO - Return code: 0
07:34:37 INFO - rmtree: C:\slave\test\build\uploaded_files.json
07:34:37 INFO - Using _rmtree_windows ...
07:34:37 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
07:34:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/888583d20bbadc7d7c618ed2de7ea69d19f6bc1efcadc3c31eda15ee3f7f10bf245f74b380d595e91ea9d4b29f9d5812b17b4d1eb618b7f69e6b0a2de006326b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d8f9641ede00a6dc2bec4de2e6cfdf6cc5125d3fde41fab945b16e8e05fc40bcf4a7a6fe67f9f60c9dde84d5906c1c7720a29406de20e08f7bceeba5de8fc94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5f3db77d3569b8c59a14b13d052b2a67e49ccd6585073e56d7af8bfc5c97296ad2c696f60f39156331ce5d827852ce4ff66fd9d55ccea5c81ff0280f30669ee"}
07:34:37 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
07:34:37 INFO - Writing to file C:\slave\test\properties\blobber_files
07:34:37 INFO - Contents:
07:34:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/888583d20bbadc7d7c618ed2de7ea69d19f6bc1efcadc3c31eda15ee3f7f10bf245f74b380d595e91ea9d4b29f9d5812b17b4d1eb618b7f69e6b0a2de006326b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d8f9641ede00a6dc2bec4de2e6cfdf6cc5125d3fde41fab945b16e8e05fc40bcf4a7a6fe67f9f60c9dde84d5906c1c7720a29406de20e08f7bceeba5de8fc94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5f3db77d3569b8c59a14b13d052b2a67e49ccd6585073e56d7af8bfc5c97296ad2c696f60f39156331ce5d827852ce4ff66fd9d55ccea5c81ff0280f30669ee"}
07:34:37 INFO - Running post-run listener: copy_logs_to_upload_dir
07:34:37 INFO - Copying logs to upload dir...
07:34:37 INFO - mkdir: C:\slave\test\build\upload\logs
07:34:37 INFO - Copying logs to upload dir...
07:34:37 INFO - Using _rmtree_windows ...
07:34:37 INFO - Using _rmtree_windows ...
07:34:37 INFO - Using _rmtree_windows ...
07:34:37 INFO - Using _rmtree_windows ...
07:34:37 INFO - Using _rmtree_windows ...
07:34:37 INFO - Using _rmtree_windows ...
07:34:37 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1411.397000
========= master_lag: 0.10 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 31 secs) (at 2016-10-31 07:34:38.433698) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 07:34:38.440314) =========
'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-104
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-104
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-104
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/888583d20bbadc7d7c618ed2de7ea69d19f6bc1efcadc3c31eda15ee3f7f10bf245f74b380d595e91ea9d4b29f9d5812b17b4d1eb618b7f69e6b0a2de006326b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d8f9641ede00a6dc2bec4de2e6cfdf6cc5125d3fde41fab945b16e8e05fc40bcf4a7a6fe67f9f60c9dde84d5906c1c7720a29406de20e08f7bceeba5de8fc94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5f3db77d3569b8c59a14b13d052b2a67e49ccd6585073e56d7af8bfc5c97296ad2c696f60f39156331ce5d827852ce4ff66fd9d55ccea5c81ff0280f30669ee"}
build_url:https://queue.taskcluster.net/v1/task/PAbHNtZiTGmpD1viS66zPg/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/PAbHNtZiTGmpD1viS66zPg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/888583d20bbadc7d7c618ed2de7ea69d19f6bc1efcadc3c31eda15ee3f7f10bf245f74b380d595e91ea9d4b29f9d5812b17b4d1eb618b7f69e6b0a2de006326b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d8f9641ede00a6dc2bec4de2e6cfdf6cc5125d3fde41fab945b16e8e05fc40bcf4a7a6fe67f9f60c9dde84d5906c1c7720a29406de20e08f7bceeba5de8fc94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5f3db77d3569b8c59a14b13d052b2a67e49ccd6585073e56d7af8bfc5c97296ad2c696f60f39156331ce5d827852ce4ff66fd9d55ccea5c81ff0280f30669ee"}'
========= master_lag: 0.01 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 07:34:38.555584) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 07:34:38.555931) =========
'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-104
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-104
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-104
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-10-31 07:34:38.667185) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-31 07:34:38.667500) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-31 07:34:39.119734) =========
========= Total master_lag: 0.32 =========