builder: fx-team_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-064
starttime: 1476483063.09
results: success (0)
buildid: 20161014145806
builduid: 496a95707b5541c4af52e11b8f9da91f
revision: e412b386433b8c8ba83a28a1df310d9ad0b20285
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 15:11:03.087939) =========
master: http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 15:11:03.088754) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 15:11:03.089225) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 15:11:03.241650) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 15:11:03.242052) =========
'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-064
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-064
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-064
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
--15:11:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,179 (12K) [text/x-python]
0K .......... . 100% 8.37 MB/s
15:11:03 (8.37 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.338000
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 15:11:04.607035) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 16 secs) (at 2016-10-14 15:11:04.607375) =========
'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-064
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-064
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-064
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=16.229000
========= master_lag: -0.02 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 16 secs) (at 2016-10-14 15:11:20.815075) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 15:11:20.815440) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev e412b386433b8c8ba83a28a1df310d9ad0b20285 --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev e412b386433b8c8ba83a28a1df310d9ad0b20285 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-064
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-064
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-064
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-14 15:11:20,447 truncating revision to first 12 chars
2016-10-14 15:11:20,447 Setting DEBUG logging.
2016-10-14 15:11:20,447 attempt 1/10
2016-10-14 15:11:20,447 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e412b386433b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-10-14 15:11:21,447 unpacking tar archive at: fx-team-e412b386433b/testing/mozharness/
2016-10-14 15:11:21,569 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.797000
========= master_lag: 0.06 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 15:11:22.667864) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 15:11:22.668194) =========
script_repo_revision: e412b386433b8c8ba83a28a1df310d9ad0b20285
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 15:11:22.668583) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 15:11:22.668848) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 15:11:22.678882) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 19 secs) (at 2016-10-14 15:11:22.679205) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-064
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-064
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-064
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
15:11:22 INFO - MultiFileLogger online at 20161014 15:11:22 in C:\slave\test
15:11:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team
15:11:22 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
15:11:22 INFO - {'append_to_log': False,
15:11:22 INFO - 'base_work_dir': 'C:\\slave\\test',
15:11:22 INFO - 'blob_upload_branch': 'Fx-Team',
15:11:22 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
15:11:22 INFO - 'branch': 'Fx-Team',
15:11:22 INFO - 'buildbot_json_path': 'buildprops.json',
15:11:22 INFO - 'config_files': ('talos/windows_config.py',),
15:11:22 INFO - 'default_actions': ('clobber',
15:11:22 INFO - 'read-buildbot-config',
15:11:22 INFO - 'download-and-extract',
15:11:22 INFO - 'populate-webroot',
15:11:22 INFO - 'create-virtualenv',
15:11:22 INFO - 'install',
15:11:22 INFO - 'run-tests'),
15:11:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
15:11:22 INFO - 'download_minidump_stackwalk': True,
15:11:22 INFO - 'download_symbols': 'ondemand',
15:11:22 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
15:11:22 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
15:11:22 INFO - 'hg': 'c:/mozilla-build/hg/hg',
15:11:22 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
15:11:22 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
15:11:22 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
15:11:22 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
15:11:22 INFO - 'C:/mozilla-build/tooltool.py'),
15:11:22 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
15:11:22 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
15:11:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
15:11:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
15:11:22 INFO - 'installer_path': 'installer.exe',
15:11:22 INFO - 'log_level': 'info',
15:11:22 INFO - 'log_name': 'talos',
15:11:22 INFO - 'log_to_console': True,
15:11:22 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
15:11:22 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
15:11:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
15:11:22 INFO - 'opt_config_files': (),
15:11:22 INFO - 'pip_index': False,
15:11:22 INFO - 'sps_profile': False,
15:11:22 INFO - 'sps_profile_interval': 0,
15:11:22 INFO - 'suite': 'dromaeojs',
15:11:22 INFO - 'system_bits': '32',
15:11:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
15:11:22 INFO - 'title': 'T-W732-IX-064',
15:11:22 INFO - 'use_talos_json': True,
15:11:22 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
15:11:22 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
15:11:22 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
15:11:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
15:11:22 INFO - 'work_dir': 'build'}
15:11:22 INFO - [mozharness: 2016-10-14 22:11:22.475000Z] Running clobber step.
15:11:22 INFO - Running pre-action listener: _resource_record_pre_action
15:11:22 INFO - Running main action method: clobber
15:11:22 INFO - rmtree: C:\slave\test\build
15:11:22 INFO - Using _rmtree_windows ...
15:11:22 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
15:13:04 INFO - Running post-action listener: _resource_record_post_action
15:13:04 INFO - [mozharness: 2016-10-14 22:13:04.036000Z] Finished clobber step (success)
15:13:04 INFO - [mozharness: 2016-10-14 22:13:04.036000Z] Running read-buildbot-config step.
15:13:04 INFO - Running pre-action listener: _resource_record_pre_action
15:13:04 INFO - Running main action method: read_buildbot_config
15:13:04 INFO - Using buildbot properties:
15:13:04 INFO - {
15:13:04 INFO - "project": "",
15:13:04 INFO - "product": "firefox",
15:13:04 INFO - "script_repo_revision": "production",
15:13:04 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
15:13:04 INFO - "repository": "",
15:13:04 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs",
15:13:04 INFO - "buildid": "20161014145806",
15:13:04 INFO - "stage_platform": "win32-pgo",
15:13:04 INFO - "basedir": "C:\\slave\\test",
15:13:04 INFO - "buildnumber": 237,
15:13:04 INFO - "platform": "win7_ix",
15:13:04 INFO - "master": "http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/",
15:13:04 INFO - "slavebuilddir": "test",
15:13:04 INFO - "branch": "fx-team",
15:13:04 INFO - "revision": "e412b386433b8c8ba83a28a1df310d9ad0b20285",
15:13:04 INFO - "slavename": "t-w732-ix-064",
15:13:04 INFO - "builduid": "496a95707b5541c4af52e11b8f9da91f",
15:13:04 INFO - "repo_path": "integration/fx-team"
15:13:04 INFO - }
15:13:04 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.test_packages.json.
15:13:04 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.zip.
15:13:04 INFO - Running post-action listener: _resource_record_post_action
15:13:04 INFO - [mozharness: 2016-10-14 22:13:04.040000Z] Finished read-buildbot-config step (success)
15:13:04 INFO - [mozharness: 2016-10-14 22:13:04.040000Z] Running download-and-extract step.
15:13:04 INFO - Running pre-action listener: _resource_record_pre_action
15:13:04 INFO - Running main action method: download_and_extract
15:13:04 INFO - mkdir: C:\slave\test\build\tests
15:13: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')]}
15:13:04 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.test_packages.json
15:13:04 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
15:13:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/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
15:13:04 INFO - Downloaded 1218 bytes.
15:13:04 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
15:13:04 INFO - Using the following test package requirements:
15:13:04 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
15:13:04 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:13:04 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
15:13:04 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
15:13:04 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:13:04 INFO - u'jsshell-win32.zip'],
15:13:04 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:13:04 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
15:13:04 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
15:13:04 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:13:04 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
15:13:04 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:13:04 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
15:13:04 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:13:04 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
15:13:04 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
15:13:04 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
15:13:04 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
15:13:04 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.common.tests.zip
15:13:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
15:13:04 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.common.tests.zip into memory
15:13:04 INFO - Http code: 200
15:13:04 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront)
15:13:04 INFO - x-amz-cf-id: _TiveOKHditXovhQZ6ZDLZBxbTzLlt2RaYgWU0Q39oseQJZYEDB2AA==
15:13:04 INFO - x-amz-replication-status: COMPLETED
15:13:04 INFO - x-amz-version-id: _IXYzMwVDwit8P2X.WBSuOcfzZzAkpiU
15:13:05 INFO - Expected file size: 17275157
15:13:05 INFO - Obtained file size: 17275157
15:13:30 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
15:13:30 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.common.tests.zip
15:13:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
15:13:30 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.common.tests.zip into memory
15:13:30 INFO - Http code: 200
15:13:30 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront)
15:13:30 INFO - x-amz-cf-id: gyrwhDuWCQsG_TTwFjWYEy1xzs2NQjlWFXxdTwohH45EcAQHTfU1rg==
15:13:30 INFO - x-amz-replication-status: COMPLETED
15:13:30 INFO - x-amz-version-id: _IXYzMwVDwit8P2X.WBSuOcfzZzAkpiU
15:13:31 INFO - Expected file size: 17275157
15:13:31 INFO - Obtained file size: 17275157
15:13:41 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.talos.tests.zip
15:13:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
15:13:41 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
15:13:41 INFO - Http code: 200
15:13:41 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront)
15:13:41 INFO - x-amz-cf-id: jz5c5vd91ohGQdUyjuR3CdV30WHJeBNoA-0ALQcbK1U5NiWZIn2S5Q==
15:13:41 INFO - x-amz-replication-status: COMPLETED
15:13:41 INFO - x-amz-version-id: 24ouMx2UzOU3cRekNcdTZ7Ij._9vGedY
15:13:41 INFO - Expected file size: 14409006
15:13:41 INFO - Obtained file size: 14409006
15:13:42 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')]}
15:13:42 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.zip
15:13:42 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
15:13:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
15:13:45 INFO - Downloaded 54283376 bytes.
15:13:45 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.zip
15:13:45 INFO - mkdir: C:\slave\test\properties
15:13:45 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
15:13:45 INFO - Writing to file C:\slave\test\properties\build_url
15:13:45 INFO - Contents:
15:13:45 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.zip
15:13:45 INFO - Running post-action listener: _resource_record_post_action
15:13:45 INFO - Running post-action listener: set_extra_try_arguments
15:13:45 INFO - [mozharness: 2016-10-14 22:13:45.556000Z] Finished download-and-extract step (success)
15:13:45 INFO - [mozharness: 2016-10-14 22:13:45.556000Z] Running populate-webroot step.
15:13:45 INFO - Running pre-action listener: _resource_record_pre_action
15:13:45 INFO - Running main action method: populate_webroot
15:13:45 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
15:13:45 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
15:13:45 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
15:13:45 INFO - u'--tppagecycles',
15:13:45 INFO - u'7'],
15:13:45 INFO - u'tests': [u'tsvgm']}},
15:13:45 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
15:13:45 INFO - u'tests': [u'tresize', u'tcanvasmark']},
15:13:45 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
15:13:45 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
15:13:45 INFO - u'tests': [u'dromaeo_css', u'kraken']},
15:13:45 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
15:13:45 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:13:45 INFO - u'talos_options': [u'--disable-e10s'],
15:13:45 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
15:13:45 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:13:45 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
15:13:45 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:13:45 INFO - u'talos_options': [u'--disable-e10s'],
15:13:45 INFO - u'tests': [u'damp', u'tps']},
15:13:45 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:13:45 INFO - u'tests': [u'damp', u'tps']},
15:13:45 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
15:13:45 INFO - u'tests': [u'dromaeo_dom']},
15:13:45 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
15:13:45 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
15:13:45 INFO - u'tests': [u'basic_compositor_video']},
15:13:45 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
15:13:45 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
15:13:45 INFO - u'tests': [u'a11yr',
15:13:45 INFO - u'ts_paint',
15:13:45 INFO - u'tpaint',
15:13:45 INFO - u'sessionrestore',
15:13:45 INFO - u'sessionrestore_no_auto_restore',
15:13:45 INFO - u'tabpaint']},
15:13:45 INFO - u'other-e10s': {u'tests': [u'a11yr',
15:13:45 INFO - u'ts_paint',
15:13:45 INFO - u'tpaint',
15:13:45 INFO - u'sessionrestore',
15:13:45 INFO - u'sessionrestore_no_auto_restore',
15:13:45 INFO - u'tabpaint']},
15:13:45 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
15:13:45 INFO - u'ts_paint',
15:13:45 INFO - u'tpaint',
15:13:45 INFO - u'sessionrestore',
15:13:45 INFO - u'sessionrestore_no_auto_restore',
15:13:45 INFO - u'tabpaint']},
15:13:45 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
15:13:45 INFO - u'ts_paint',
15:13:45 INFO - u'tpaint',
15:13:45 INFO - u'sessionrestore',
15:13:45 INFO - u'sessionrestore_no_auto_restore',
15:13:45 INFO - u'tabpaint']},
15:13:45 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
15:13:45 INFO - u'tests': [u'a11yr',
15:13:45 INFO - u'ts_paint',
15:13:45 INFO - u'tpaint',
15:13:45 INFO - u'sessionrestore',
15:13:45 INFO - u'sessionrestore_no_auto_restore',
15:13:45 INFO - u'tabpaint']},
15:13:45 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
15:13:45 INFO - u'tests': [u'a11yr',
15:13:45 INFO - u'ts_paint',
15:13:45 INFO - u'tpaint',
15:13:45 INFO - u'sessionrestore',
15:13:45 INFO - u'sessionrestore_no_auto_restore',
15:13:45 INFO - u'tabpaint']},
15:13:45 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
15:13:45 INFO - u'tests': [u'tsvgx',
15:13:45 INFO - u'tsvgr_opacity',
15:13:45 INFO - u'tart',
15:13:45 INFO - u'tscrollx',
15:13:45 INFO - u'cart']},
15:13:45 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
15:13:45 INFO - u'tsvgr_opacity',
15:13:45 INFO - u'tart',
15:13:45 INFO - u'tscrollx',
15:13:45 INFO - u'cart']},
15:13:45 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:13:45 INFO - u'talos_options': [u'--disable-e10s'],
15:13:45 INFO - u'tests': [u'tp5o']},
15:13:45 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:13:45 INFO - u'tests': [u'tp5o']},
15:13:45 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:13:45 INFO - u'talos_options': [u'--disable-e10s',
15:13:45 INFO - u'--xperf_path',
15:13:45 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
15:13:45 INFO - u'tests': [u'tp5n']},
15:13:45 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:13:45 INFO - u'talos_options': [u'--xperf_path',
15:13:45 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
15:13:45 INFO - u'tests': [u'tp5n']}},
15:13:45 INFO - u'talos.zip': {u'path': u'',
15:13:45 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
15:13:45 INFO - Running post-action listener: _resource_record_post_action
15:13:45 INFO - [mozharness: 2016-10-14 22:13:45.580000Z] Finished populate-webroot step (success)
15:13:45 INFO - [mozharness: 2016-10-14 22:13:45.580000Z] Running create-virtualenv step.
15:13:45 INFO - Running pre-action listener: _resource_record_pre_action
15:13:45 INFO - Running main action method: create_virtualenv
15:13:45 INFO - Creating virtualenv C:\slave\test\build/venv
15:13:45 INFO - mkdir: C:\slave\test\build\venv\Scripts
15:13:46 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
15:13:46 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'!
15:13:46 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
15:13:46 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
15:13:51 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
15:13:58 INFO - Installing distribute......................................................................................................................................................................................done.
15:14:00 INFO - Return code: 0
15:14:00 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
15:14:00 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
15:14:00 INFO - Reading from file tmpfile_stdout
15:14:00 INFO - Output received:
15: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)
15:14:00 INFO - Using _rmtree_windows ...
15:14:00 INFO - Using _rmtree_windows ...
15:14:00 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
15: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')]}
15:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15: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')]}
15:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15: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 0x0196B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C0E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02005520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-064', 'USERDOMAIN': 'T-W732-IX-064', '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-064', '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
15: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
15: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
15:14:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:14:00 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:14:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:14:00 INFO - 'COMPUTERNAME': 'T-W732-IX-064',
15:14:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:14:00 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:14:00 INFO - 'DCLOCATION': 'SCL3',
15:14:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:14:00 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:14:00 INFO - 'HOMEDRIVE': 'C:',
15:14:00 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:14:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:14:00 INFO - 'KTS_VERSION': '1.19c',
15:14:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:14:00 INFO - 'LOGONSERVER': '\\\\T-W732-IX-064',
15:14:00 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:14:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:14:00 INFO - 'MOZ_NO_REMOTE': '1',
15:14:00 INFO - 'NO_EM_RESTART': '1',
15:14:00 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:14:00 INFO - 'OS': 'Windows_NT',
15:14:00 INFO - 'OURDRIVE': 'C:',
15: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;',
15:14:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:14:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:14:00 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:14:00 INFO - 'PROCESSOR_LEVEL': '6',
15:14:00 INFO - 'PROCESSOR_REVISION': '1e05',
15:14:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:14:00 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:14:00 INFO - 'PROMPT': '$P$G',
15:14:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:14:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:14:00 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:14:00 INFO - 'PWD': 'C:\\slave\\test',
15:14:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:14:00 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:14:00 INFO - 'SYSTEMDRIVE': 'C:',
15:14:00 INFO - 'SYSTEMROOT': 'C:\\windows',
15:14:00 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:00 INFO - 'TEST1': 'testie',
15:14:00 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:00 INFO - 'USERDOMAIN': 'T-W732-IX-064',
15:14:00 INFO - 'USERNAME': 'cltbld',
15:14:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:14:00 INFO - 'WINDIR': 'C:\\windows',
15:14:00 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:14:00 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:14:01 INFO - Ignoring indexes: http://pypi.python.org/simple/
15:14:01 INFO - Downloading/unpacking pip>=1.5
15:14:05 INFO - Running setup.py egg_info for package pip
15:14:05 INFO - warning: no files found matching 'pip\cacert.pem'
15:14:05 INFO - warning: no files found matching '*.html' under directory 'docs'
15:14:05 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
15:14:05 INFO - no previously-included directories found matching 'docs\_build\_sources'
15:14:05 INFO - Installing collected packages: pip
15:14:05 INFO - Found existing installation: pip 0.8.2
15:14:05 INFO - Uninstalling pip:
15:14:05 INFO - Successfully uninstalled pip
15:14:05 INFO - Running setup.py install for pip
15:14:05 INFO - warning: no files found matching 'pip\cacert.pem'
15:14:05 INFO - warning: no files found matching '*.html' under directory 'docs'
15:14:05 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
15:14:05 INFO - no previously-included directories found matching 'docs\_build\_sources'
15:14:05 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
15:14:05 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
15:14:05 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
15:14:05 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
15:14:05 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
15:14:05 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
15:14:05 INFO - Successfully installed pip
15:14:05 INFO - Cleaning up...
15:14:05 INFO - Return code: 0
15:14:05 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
15:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:14:05 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x0196B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C0E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02005520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-064', 'USERDOMAIN': 'T-W732-IX-064', '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-064', '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
15: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
15: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
15:14:05 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:14:05 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:14:05 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:14:05 INFO - 'COMPUTERNAME': 'T-W732-IX-064',
15:14:05 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:14:05 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:14:05 INFO - 'DCLOCATION': 'SCL3',
15:14:05 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:14:05 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:14:05 INFO - 'HOMEDRIVE': 'C:',
15:14:05 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:14:05 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:14:05 INFO - 'KTS_VERSION': '1.19c',
15:14:05 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:14:05 INFO - 'LOGONSERVER': '\\\\T-W732-IX-064',
15:14:05 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:14:05 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:14:05 INFO - 'MOZ_NO_REMOTE': '1',
15:14:05 INFO - 'NO_EM_RESTART': '1',
15:14:05 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:14:05 INFO - 'OS': 'Windows_NT',
15:14:05 INFO - 'OURDRIVE': 'C:',
15: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;',
15:14:05 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:14:05 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:14:05 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:14:05 INFO - 'PROCESSOR_LEVEL': '6',
15:14:05 INFO - 'PROCESSOR_REVISION': '1e05',
15:14:05 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:14:05 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:14:05 INFO - 'PROMPT': '$P$G',
15:14:05 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:14:05 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:14:05 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:14:05 INFO - 'PWD': 'C:\\slave\\test',
15:14:05 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:14:05 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:14:05 INFO - 'SYSTEMDRIVE': 'C:',
15:14:05 INFO - 'SYSTEMROOT': 'C:\\windows',
15:14:05 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:05 INFO - 'TEST1': 'testie',
15:14:05 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:05 INFO - 'USERDOMAIN': 'T-W732-IX-064',
15:14:05 INFO - 'USERNAME': 'cltbld',
15:14:05 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:14:05 INFO - 'WINDIR': 'C:\\windows',
15:14:05 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:14:05 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:14:07 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:14:07 INFO - Downloading/unpacking psutil>=3.1.1
15: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
15: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
15:14:09 INFO - Installing collected packages: psutil
15:14:09 INFO - Successfully installed psutil
15:14:09 INFO - Cleaning up...
15:14:09 INFO - Return code: 0
15:14:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
15:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:14:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:14:09 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 0x0196B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C0E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02005520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-064', 'USERDOMAIN': 'T-W732-IX-064', '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-064', '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
15:14:09 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
15:14:09 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
15:14:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:14:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:14:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:14:09 INFO - 'COMPUTERNAME': 'T-W732-IX-064',
15:14:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:14:09 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:14:09 INFO - 'DCLOCATION': 'SCL3',
15:14:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:14:09 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:14:09 INFO - 'HOMEDRIVE': 'C:',
15:14:09 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:14:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:14:09 INFO - 'KTS_VERSION': '1.19c',
15:14:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:14:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-064',
15:14:09 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:14:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:14:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:14:09 INFO - 'MOZ_NO_REMOTE': '1',
15:14:09 INFO - 'NO_EM_RESTART': '1',
15:14:09 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:14:09 INFO - 'OS': 'Windows_NT',
15:14:09 INFO - 'OURDRIVE': 'C:',
15:14:09 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
15:14:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:14:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:14:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:14:09 INFO - 'PROCESSOR_LEVEL': '6',
15:14:09 INFO - 'PROCESSOR_REVISION': '1e05',
15:14:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:14:09 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:14:09 INFO - 'PROMPT': '$P$G',
15:14:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:14:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:14:09 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:14:09 INFO - 'PWD': 'C:\\slave\\test',
15:14:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:14:09 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:14:09 INFO - 'SYSTEMDRIVE': 'C:',
15:14:09 INFO - 'SYSTEMROOT': 'C:\\windows',
15:14:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:09 INFO - 'TEST1': 'testie',
15:14:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:09 INFO - 'USERDOMAIN': 'T-W732-IX-064',
15:14:09 INFO - 'USERNAME': 'cltbld',
15:14:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:14:09 INFO - 'WINDIR': 'C:\\windows',
15:14:09 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:14:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:14:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:14:09 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:14:09 INFO - Downloading/unpacking mozsystemmonitor==0.3
15:14:09 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
15:14:09 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
15:14:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz
15:14:10 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
15:14:10 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)
15:14:10 INFO - Installing collected packages: mozsystemmonitor
15:14:10 INFO - Running setup.py install for mozsystemmonitor
15:14:10 INFO - Successfully installed mozsystemmonitor
15:14:10 INFO - Cleaning up...
15:14:11 INFO - Return code: 0
15:14:11 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
15:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:14:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:14:11 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x0196B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C0E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02005520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-064', 'USERDOMAIN': 'T-W732-IX-064', '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-064', '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
15:14:11 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build
15:14:11 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1
15:14:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:14:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:14:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:14:11 INFO - 'COMPUTERNAME': 'T-W732-IX-064',
15:14:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:14:11 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:14:11 INFO - 'DCLOCATION': 'SCL3',
15:14:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:14:11 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:14:11 INFO - 'HOMEDRIVE': 'C:',
15:14:11 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:14:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:14:11 INFO - 'KTS_VERSION': '1.19c',
15:14:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:14:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-064',
15:14:11 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:14:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:14:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:14:11 INFO - 'MOZ_NO_REMOTE': '1',
15:14:11 INFO - 'NO_EM_RESTART': '1',
15:14:11 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:14:11 INFO - 'OS': 'Windows_NT',
15:14:11 INFO - 'OURDRIVE': 'C:',
15:14:11 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
15:14:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:14:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:14:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:14:11 INFO - 'PROCESSOR_LEVEL': '6',
15:14:11 INFO - 'PROCESSOR_REVISION': '1e05',
15:14:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:14:11 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:14:11 INFO - 'PROMPT': '$P$G',
15:14:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:14:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:14:11 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:14:11 INFO - 'PWD': 'C:\\slave\\test',
15:14:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:14:11 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:14:11 INFO - 'SYSTEMDRIVE': 'C:',
15:14:11 INFO - 'SYSTEMROOT': 'C:\\windows',
15:14:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:11 INFO - 'TEST1': 'testie',
15:14:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:11 INFO - 'USERDOMAIN': 'T-W732-IX-064',
15:14:11 INFO - 'USERNAME': 'cltbld',
15:14:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:14:11 INFO - 'WINDIR': 'C:\\windows',
15:14:11 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:14:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:14:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:14:11 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:14:11 INFO - Downloading/unpacking jsonschema==2.5.1
15: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
15: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
15:14:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
15:14:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
15:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:14:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:14:12 INFO - Downloading functools32-3.2.3-2.tar.gz
15:14:12 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
15:14:12 INFO - warning: no files found matching '*.txt'
15:14:12 INFO - no previously-included directories found matching 'build'
15:14:12 INFO - no previously-included directories found matching 'dist'
15:14:12 INFO - no previously-included directories found matching '.git*'
15:14:12 INFO - Installing collected packages: jsonschema, functools32
15:14:13 INFO - Running setup.py install for functools32
15:14:13 INFO - warning: no files found matching '*.txt'
15:14:13 INFO - no previously-included directories found matching 'build'
15:14:13 INFO - no previously-included directories found matching 'dist'
15:14:13 INFO - no previously-included directories found matching '.git*'
15:14:13 INFO - Successfully installed jsonschema functools32
15:14:13 INFO - Cleaning up...
15:14:13 INFO - Return code: 0
15:14:13 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
15: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')]}
15:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15: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')]}
15:14:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15: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', '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 0x0196B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C0E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02005520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-064', 'USERDOMAIN': 'T-W732-IX-064', '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-064', '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
15: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', 'functools32==3.2.3-2'] in C:\slave\test\build
15: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 functools32==3.2.3-2
15:14:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:14:13 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:14:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:14:13 INFO - 'COMPUTERNAME': 'T-W732-IX-064',
15:14:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:14:13 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:14:13 INFO - 'DCLOCATION': 'SCL3',
15:14:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:14:13 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:14:13 INFO - 'HOMEDRIVE': 'C:',
15:14:13 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:14:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:14:13 INFO - 'KTS_VERSION': '1.19c',
15:14:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:14:13 INFO - 'LOGONSERVER': '\\\\T-W732-IX-064',
15:14:13 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:14:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:14:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:14:13 INFO - 'MOZ_NO_REMOTE': '1',
15:14:13 INFO - 'NO_EM_RESTART': '1',
15:14:13 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:14:13 INFO - 'OS': 'Windows_NT',
15:14:13 INFO - 'OURDRIVE': 'C:',
15: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;',
15:14:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:14:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:14:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:14:13 INFO - 'PROCESSOR_LEVEL': '6',
15:14:13 INFO - 'PROCESSOR_REVISION': '1e05',
15:14:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:14:13 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:14:13 INFO - 'PROMPT': '$P$G',
15:14:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:14:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:14:13 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:14:13 INFO - 'PWD': 'C:\\slave\\test',
15:14:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:14:13 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:14:13 INFO - 'SYSTEMDRIVE': 'C:',
15:14:13 INFO - 'SYSTEMROOT': 'C:\\windows',
15:14:13 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:13 INFO - 'TEST1': 'testie',
15:14:13 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:13 INFO - 'USERDOMAIN': 'T-W732-IX-064',
15:14:13 INFO - 'USERNAME': 'cltbld',
15:14:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:14:13 INFO - 'WINDIR': 'C:\\windows',
15:14:13 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:14:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:14:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:14:14 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:14:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
15:14:14 INFO - Cleaning up...
15:14:14 INFO - Return code: 0
15:14:14 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
15:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:14:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:14:14 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x0196B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C0E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02005520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-064', 'USERDOMAIN': 'T-W732-IX-064', '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-064', '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
15:14:14 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build
15:14:14 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
15:14:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:14:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:14:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:14:14 INFO - 'COMPUTERNAME': 'T-W732-IX-064',
15:14:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:14:14 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:14:14 INFO - 'DCLOCATION': 'SCL3',
15:14:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:14:14 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:14:14 INFO - 'HOMEDRIVE': 'C:',
15:14:14 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:14:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:14:14 INFO - 'KTS_VERSION': '1.19c',
15:14:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:14:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-064',
15:14:14 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:14:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:14:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:14:14 INFO - 'MOZ_NO_REMOTE': '1',
15:14:14 INFO - 'NO_EM_RESTART': '1',
15:14:14 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:14:14 INFO - 'OS': 'Windows_NT',
15:14:14 INFO - 'OURDRIVE': 'C:',
15:14:14 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
15:14:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:14:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:14:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:14:14 INFO - 'PROCESSOR_LEVEL': '6',
15:14:14 INFO - 'PROCESSOR_REVISION': '1e05',
15:14:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:14:14 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:14:14 INFO - 'PROMPT': '$P$G',
15:14:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:14:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:14:14 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:14:14 INFO - 'PWD': 'C:\\slave\\test',
15:14:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:14:14 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:14:14 INFO - 'SYSTEMDRIVE': 'C:',
15:14:14 INFO - 'SYSTEMROOT': 'C:\\windows',
15:14:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:14 INFO - 'TEST1': 'testie',
15:14:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:14 INFO - 'USERDOMAIN': 'T-W732-IX-064',
15:14:14 INFO - 'USERNAME': 'cltbld',
15:14:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:14:14 INFO - 'WINDIR': 'C:\\windows',
15:14:14 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:14:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:14:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:14:14 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:14:14 INFO - Downloading/unpacking blobuploader==1.2.4
15: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
15: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
15:14:15 INFO - Downloading blobuploader-1.2.4.tar.gz
15:14:15 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
15:14:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
15: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
15: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
15:14:15 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
15:14:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
15:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:14:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:14:16 INFO - Downloading docopt-0.6.1.tar.gz
15:14:16 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
15:14:16 INFO - Installing collected packages: blobuploader, requests, docopt
15:14:16 INFO - Running setup.py install for blobuploader
15:14:16 INFO - Running setup.py install for requests
15:14:17 INFO - Running setup.py install for docopt
15:14:17 INFO - Successfully installed blobuploader requests docopt
15:14:17 INFO - Cleaning up...
15:14:18 INFO - Return code: 0
15:14:18 INFO - Installing None into virtualenv C:\slave\test\build/venv
15:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:14:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:14:18 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 0x0196B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C0E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02005520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-064', 'USERDOMAIN': 'T-W732-IX-064', '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-064', '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
15: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
15: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
15:14:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:14:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:14:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:14:18 INFO - 'COMPUTERNAME': 'T-W732-IX-064',
15:14:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:14:18 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:14:18 INFO - 'DCLOCATION': 'SCL3',
15:14:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:14:18 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:14:18 INFO - 'HOMEDRIVE': 'C:',
15:14:18 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:14:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:14:18 INFO - 'KTS_VERSION': '1.19c',
15:14:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:14:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-064',
15:14:18 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:14:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:14:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:14:18 INFO - 'MOZ_NO_REMOTE': '1',
15:14:18 INFO - 'NO_EM_RESTART': '1',
15:14:18 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:14:18 INFO - 'OS': 'Windows_NT',
15:14:18 INFO - 'OURDRIVE': 'C:',
15: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;',
15:14:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:14:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:14:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:14:18 INFO - 'PROCESSOR_LEVEL': '6',
15:14:18 INFO - 'PROCESSOR_REVISION': '1e05',
15:14:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:14:18 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:14:18 INFO - 'PROMPT': '$P$G',
15:14:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:14:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:14:18 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:14:18 INFO - 'PWD': 'C:\\slave\\test',
15:14:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:14:18 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:14:18 INFO - 'SYSTEMDRIVE': 'C:',
15:14:18 INFO - 'SYSTEMROOT': 'C:\\windows',
15:14:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:18 INFO - 'TEST1': 'testie',
15:14:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:18 INFO - 'USERDOMAIN': 'T-W732-IX-064',
15:14:18 INFO - 'USERNAME': 'cltbld',
15:14:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:14:18 INFO - 'WINDIR': 'C:\\windows',
15:14:18 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:14:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:14:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:14:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
15:14:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iuqqsc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
15:14:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
15:14:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vkiaip-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
15:14:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
15:14:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9w8jiu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
15:14:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
15:14:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-clj9o0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
15:14:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
15:14:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-trw9p1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
15:14:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
15:14:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-khwfmm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
15:14:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
15:14:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l3rtsl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
15:14:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
15:14:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-scidkw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
15:14:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
15:14:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0kvsaf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
15:14:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
15:14:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b_kglg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
15:14:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
15:14:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y4yez4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
15:14:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
15:14:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qbtgbc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
15:14:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
15:14:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mswghv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
15:14:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
15:14:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-t2xtz6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
15:14:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
15:14:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gfvco5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
15:14:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
15:14:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yood6n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
15:14:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
15:14:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-af3hes-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
15:14:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
15:14:21 INFO - Running setup.py install for manifestparser
15:14:22 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
15:14:22 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
15:14:22 INFO - Running setup.py install for mozcrash
15:14:22 INFO - Running setup.py install for mozdebug
15:14:23 INFO - Running setup.py install for mozdevice
15:14:23 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
15:14:23 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
15:14:23 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
15:14:23 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
15:14:23 INFO - Running setup.py install for mozfile
15:14:24 INFO - Running setup.py install for mozhttpd
15:14:24 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
15:14:24 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
15:14:24 INFO - Running setup.py install for mozinfo
15:14:24 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
15:14:24 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
15:14:24 INFO - Running setup.py install for mozInstall
15:14:25 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
15:14:25 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
15:14:25 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
15:14:25 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
15:14:25 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
15:14:25 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
15:14:25 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
15:14:25 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
15:14:25 INFO - Running setup.py install for mozleak
15:14:25 INFO - Running setup.py install for mozlog
15:14:25 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
15:14:25 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
15:14:26 INFO - Running setup.py install for moznetwork
15:14:26 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
15:14:26 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
15:14:26 INFO - Running setup.py install for mozprocess
15:14:27 INFO - Running setup.py install for mozprofile
15:14:27 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
15:14:27 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
15:14:27 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
15:14:27 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
15:14:27 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
15:14:27 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
15:14:28 INFO - Running setup.py install for mozrunner
15:14:28 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
15:14:28 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
15:14:28 INFO - Running setup.py install for mozscreenshot
15:14:29 INFO - Running setup.py install for moztest
15:14:29 INFO - Running setup.py install for mozversion
15:14:29 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
15:14:29 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
15:14:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
15:14:30 INFO - Cleaning up...
15:14:30 INFO - Return code: 0
15:14:30 INFO - Installing None into virtualenv C:\slave\test\build/venv
15:14:30 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')]}
15:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:14:30 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')]}
15:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:14:30 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 0x0196B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C0E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02005520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-064', 'USERDOMAIN': 'T-W732-IX-064', '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-064', '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
15:14:30 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
15:14:30 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
15:14:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:14:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:14:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:14:30 INFO - 'COMPUTERNAME': 'T-W732-IX-064',
15:14:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:14:30 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:14:30 INFO - 'DCLOCATION': 'SCL3',
15:14:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:14:30 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:14:30 INFO - 'HOMEDRIVE': 'C:',
15:14:30 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:14:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:14:30 INFO - 'KTS_VERSION': '1.19c',
15:14:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:14:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-064',
15:14:30 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:14:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:14:30 INFO - 'MOZ_NO_REMOTE': '1',
15:14:30 INFO - 'NO_EM_RESTART': '1',
15:14:30 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:14:30 INFO - 'OS': 'Windows_NT',
15:14:30 INFO - 'OURDRIVE': 'C:',
15:14:30 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;',
15:14:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:14:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:14:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:14:30 INFO - 'PROCESSOR_LEVEL': '6',
15:14:30 INFO - 'PROCESSOR_REVISION': '1e05',
15:14:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:14:30 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:14:30 INFO - 'PROMPT': '$P$G',
15:14:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:14:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:14:30 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:14:30 INFO - 'PWD': 'C:\\slave\\test',
15:14:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:14:30 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:14:30 INFO - 'SYSTEMDRIVE': 'C:',
15:14:30 INFO - 'SYSTEMROOT': 'C:\\windows',
15:14:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:30 INFO - 'TEST1': 'testie',
15:14:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:30 INFO - 'USERDOMAIN': 'T-W732-IX-064',
15:14:30 INFO - 'USERNAME': 'cltbld',
15:14:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:14:30 INFO - 'WINDIR': 'C:\\windows',
15:14:30 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:14:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:14:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
15:14:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-prrhpa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
15:14:30 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))
15:14:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
15:14:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wrdg_a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
15:14:30 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))
15:14:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
15:14:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g09l3x-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
15:14:30 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))
15:14:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
15:14:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0wpkko-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
15:14:31 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))
15:14:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
15:14:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-inij21-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
15:14:31 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))
15:14:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
15:14:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-phc_62-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
15:14:31 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))
15:14:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
15:14:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ek0q3i-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
15:14:31 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))
15:14:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
15:14:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mzfctn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
15:14:31 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))
15:14:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
15:14:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xpbvde-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
15:14:31 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))
15:14:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
15:14:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n2r4gz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
15:14:31 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))
15:14:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
15:14:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tmm3tp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
15:14:32 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))
15:14:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
15:14:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gbvizp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
15:14:32 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))
15:14:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
15:14:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fvizv2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
15:14:32 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))
15:14:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
15:14:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xexyzk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
15:14:32 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))
15:14:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
15:14:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hvlbjc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
15:14:32 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))
15:14:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
15:14:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iblxip-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
15:14:32 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))
15:14:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
15:14:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gfjzxw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
15:14:33 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))
15:14:33 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))
15:14:33 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))
15:14:33 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))
15:14:33 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))
15:14:33 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))
15:14:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
15:14:33 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
15:14:33 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
15:14:34 INFO - Downloading blessings-1.6.tar.gz
15:14:34 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
15:14:34 INFO - Installing collected packages: blessings
15:14:34 INFO - Running setup.py install for blessings
15:14:34 INFO - Successfully installed blessings
15:14:34 INFO - Cleaning up...
15:14:37 INFO - Return code: 0
15:14:37 INFO - Done creating virtualenv C:\slave\test\build/venv.
15:14:37 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
15:14:37 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
15:14:37 INFO - Reading from file tmpfile_stdout
15:14:37 INFO - Using _rmtree_windows ...
15:14:37 INFO - Using _rmtree_windows ...
15:14:37 INFO - Current package versions:
15:14:37 INFO - blessings == 1.6
15:14:37 INFO - blobuploader == 1.2.4
15:14:37 INFO - docopt == 0.6.1
15:14:37 INFO - functools32 == 3.2.3-2
15:14:37 INFO - jsonschema == 2.5.1
15:14:37 INFO - manifestparser == 1.1
15:14:37 INFO - mozInstall == 1.12
15:14:37 INFO - mozcrash == 0.17
15:14:37 INFO - mozdebug == 0.1
15:14:37 INFO - mozdevice == 0.48
15:14:37 INFO - mozfile == 1.2
15:14:37 INFO - mozhttpd == 0.7
15:14:37 INFO - mozinfo == 0.9
15:14:37 INFO - mozleak == 0.1
15:14:37 INFO - mozlog == 3.4
15:14:37 INFO - moznetwork == 0.27
15:14:37 INFO - mozprocess == 0.23
15:14:37 INFO - mozprofile == 0.28
15:14:37 INFO - mozrunner == 6.12
15:14:37 INFO - mozscreenshot == 0.1
15:14:37 INFO - mozsystemmonitor == 0.3
15:14:37 INFO - moztest == 0.7
15:14:37 INFO - mozversion == 1.4
15:14:37 INFO - psutil == 3.1.1
15:14:37 INFO - requests == 1.2.3
15:14:37 INFO - Installing None into virtualenv C:\slave\test\build/venv
15:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:14:37 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\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 0x0196B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C0E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02005520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-064', 'USERDOMAIN': 'T-W732-IX-064', '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-064', '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
15:14:37 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
15:14:37 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
15:14:37 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:14:37 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:14:37 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:14:37 INFO - 'COMPUTERNAME': 'T-W732-IX-064',
15:14:37 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:14:37 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:14:37 INFO - 'DCLOCATION': 'SCL3',
15:14:37 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:14:37 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:14:37 INFO - 'HOMEDRIVE': 'C:',
15:14:37 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:14:37 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:14:37 INFO - 'KTS_VERSION': '1.19c',
15:14:37 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:14:37 INFO - 'LOGONSERVER': '\\\\T-W732-IX-064',
15:14:37 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:14:37 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:14:37 INFO - 'MOZ_NO_REMOTE': '1',
15:14:37 INFO - 'NO_EM_RESTART': '1',
15:14:37 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:14:37 INFO - 'OS': 'Windows_NT',
15:14:37 INFO - 'OURDRIVE': 'C:',
15:14:37 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
15:14:37 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:14:37 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:14:37 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:14:37 INFO - 'PROCESSOR_LEVEL': '6',
15:14:37 INFO - 'PROCESSOR_REVISION': '1e05',
15:14:37 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:14:37 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:14:37 INFO - 'PROMPT': '$P$G',
15:14:37 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:14:37 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:14:37 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:14:37 INFO - 'PWD': 'C:\\slave\\test',
15:14:37 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:14:37 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:14:37 INFO - 'SYSTEMDRIVE': 'C:',
15:14:37 INFO - 'SYSTEMROOT': 'C:\\windows',
15:14:37 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:37 INFO - 'TEST1': 'testie',
15:14:37 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:37 INFO - 'USERDOMAIN': 'T-W732-IX-064',
15:14:37 INFO - 'USERNAME': 'cltbld',
15:14:37 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:14:37 INFO - 'WINDIR': 'C:\\windows',
15:14:37 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:14:37 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:14:38 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))
15:14:38 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))
15:14:38 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))
15:14:38 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))
15:14:38 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))
15:14:38 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))
15:14:38 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))
15:14:38 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))
15:14:38 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))
15:14:38 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))
15:14:38 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))
15:14:38 INFO - Cleaning up...
15:14:38 INFO - Return code: 0
15:14:38 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
15:14: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')]}
15:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:14: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')]}
15:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:14:38 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 0x0196B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C0E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02005520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-064', 'USERDOMAIN': 'T-W732-IX-064', '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-064', '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
15:14:38 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
15:14:38 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
15:14:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:14:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:14:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:14:38 INFO - 'COMPUTERNAME': 'T-W732-IX-064',
15:14:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:14:38 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:14:38 INFO - 'DCLOCATION': 'SCL3',
15:14:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:14:38 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:14:38 INFO - 'HOMEDRIVE': 'C:',
15:14:38 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:14:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:14:38 INFO - 'KTS_VERSION': '1.19c',
15:14:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:14:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-064',
15:14:38 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:14:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:14:38 INFO - 'MOZ_NO_REMOTE': '1',
15:14:38 INFO - 'NO_EM_RESTART': '1',
15:14:38 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:14:38 INFO - 'OS': 'Windows_NT',
15:14:38 INFO - 'OURDRIVE': 'C:',
15:14:38 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;',
15:14:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:14:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:14:38 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:14:38 INFO - 'PROCESSOR_LEVEL': '6',
15:14:38 INFO - 'PROCESSOR_REVISION': '1e05',
15:14:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:14:38 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:14:38 INFO - 'PROMPT': '$P$G',
15:14:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:14:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:14:38 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:14:38 INFO - 'PWD': 'C:\\slave\\test',
15:14:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:14:38 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:14:38 INFO - 'SYSTEMDRIVE': 'C:',
15:14:38 INFO - 'SYSTEMROOT': 'C:\\windows',
15:14:38 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:38 INFO - 'TEST1': 'testie',
15:14:38 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:38 INFO - 'USERDOMAIN': 'T-W732-IX-064',
15:14:38 INFO - 'USERNAME': 'cltbld',
15:14:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:14:38 INFO - 'WINDIR': 'C:\\windows',
15:14:38 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:14:38 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
15:14:38 INFO - Cleaning up...
15:14:38 INFO - Return code: 0
15:14:38 INFO - Running post-action listener: _resource_record_post_action
15:14:38 INFO - Running post-action listener: _start_resource_monitoring
15:14:38 INFO - Starting resource monitoring.
15:14:38 INFO - [mozharness: 2016-10-14 22:14:38.836000Z] Finished create-virtualenv step (success)
15:14:38 INFO - [mozharness: 2016-10-14 22:14:38.836000Z] Running install step.
15:14:38 INFO - Running pre-action listener: _resource_record_pre_action
15:14:38 INFO - Running main action method: install
15:14:38 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
15:14:38 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
15:14:39 INFO - Reading from file tmpfile_stdout
15:14:39 INFO - Using _rmtree_windows ...
15:14:39 INFO - Using _rmtree_windows ...
15:14:39 INFO - Detecting whether we're running mozinstall >=1.0...
15:14:39 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
15:14:39 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
15:14:39 INFO - Reading from file tmpfile_stdout
15:14:39 INFO - Output received:
15:14:39 INFO - Usage: mozinstall-script.py [options] installer
15:14:39 INFO - Options:
15:14:39 INFO - -h, --help show this help message and exit
15:14:39 INFO - -d DEST, --destination=DEST
15:14:39 INFO - Directory to install application into. [default:
15:14:39 INFO - "C:\slave\test"]
15:14:39 INFO - --app=APP Application being installed. [default: firefox]
15:14:39 INFO - Using _rmtree_windows ...
15:14:39 INFO - Using _rmtree_windows ...
15:14:39 INFO - mkdir: C:\slave\test\build\application
15:14:39 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']
15:14:39 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
15:14:41 INFO - Reading from file tmpfile_stdout
15:14:41 INFO - Output received:
15:14:41 INFO - C:\slave\test\build\application\firefox\firefox.exe
15:14:41 INFO - Using _rmtree_windows ...
15:14:41 INFO - Using _rmtree_windows ...
15:14:41 INFO - Running post-action listener: _resource_record_post_action
15:14:41 INFO - [mozharness: 2016-10-14 22:14:41.869000Z] Finished install step (success)
15:14:41 INFO - [mozharness: 2016-10-14 22:14:41.869000Z] Running run-tests step.
15:14:41 INFO - Running pre-action listener: _resource_record_pre_action
15:14:41 INFO - Running main action method: run_tests
15:14:41 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
15:14:41 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
15:14:41 INFO - Python 2.7.3
15:14:41 INFO - Return code: 0
15:14:41 INFO - grabbing minidump binary from tooltool
15:14:41 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')]}
15:14:41 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 0x019C0E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02005520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
15:14:41 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
15:14:41 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
15:14:41 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
15:14:41 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
15:14:42 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp4ljuaq
15:14:42 INFO - INFO - File integrity verified, renaming tmp4ljuaq to win32-minidump_stackwalk.exe
15:14:42 INFO - Return code: 0
15:14:42 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
15:14:42 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
15:14:42 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
15:14:42 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
15:14:42 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
15:14:42 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-064', '--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
15:14:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-064 --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
15:14:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
15:14:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
15:14:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:14:42 INFO - 'COMPUTERNAME': 'T-W732-IX-064',
15:14:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
15:14:42 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:14:42 INFO - 'DCLOCATION': 'SCL3',
15:14:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:14:42 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:14:42 INFO - 'HOMEDRIVE': 'C:',
15:14:42 INFO - 'HOMEPATH': '\\Users\\cltbld',
15:14:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:14:42 INFO - 'KTS_VERSION': '1.19c',
15:14:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
15:14:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-064',
15:14:42 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
15:14:42 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
15:14:42 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:14:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:14:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:14:42 INFO - 'MOZ_NO_REMOTE': '1',
15:14:42 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
15:14:42 INFO - 'NO_EM_RESTART': '1',
15:14:42 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:14:42 INFO - 'OS': 'Windows_NT',
15:14:42 INFO - 'OURDRIVE': 'C:',
15:14:42 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;',
15:14:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
15:14:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:14:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:14:42 INFO - 'PROCESSOR_LEVEL': '6',
15:14:42 INFO - 'PROCESSOR_REVISION': '1e05',
15:14:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
15:14:42 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:14:42 INFO - 'PROMPT': '$P$G',
15:14:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:14:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
15:14:42 INFO - 'PUBLIC': 'C:\\Users\\Public',
15:14:42 INFO - 'PWD': 'C:\\slave\\test',
15:14:42 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
15:14:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:14:42 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:14:42 INFO - 'SYSTEMDRIVE': 'C:',
15:14:42 INFO - 'SYSTEMROOT': 'C:\\windows',
15:14:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:42 INFO - 'TEST1': 'testie',
15:14:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
15:14:42 INFO - 'USERDOMAIN': 'T-W732-IX-064',
15:14:42 INFO - 'USERNAME': 'cltbld',
15:14:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
15:14:42 INFO - 'WINDIR': 'C:\\windows',
15:14:42 INFO - 'WINDOWS_TRACING_FLAGS': '3',
15:14:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
15:14:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:14:42 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-064', '--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
15:14:43 INFO - mozversion application_buildid: 20161014123004
15:14:43 INFO - mozversion application_changeset: e412b386433b8c8ba83a28a1df310d9ad0b20285
15:14:43 INFO - mozversion application_display_name: Nightly
15:14:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
15:14:43 INFO - mozversion application_name: Firefox
15:14:43 INFO - mozversion application_remotingname: firefox
15:14:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
15:14:43 INFO - mozversion application_vendor: Mozilla
15:14:43 INFO - mozversion application_version: 52.0a1
15:14:43 INFO - mozversion platform_buildid: 20161014123004
15:14:43 INFO - mozversion platform_changeset: e412b386433b8c8ba83a28a1df310d9ad0b20285
15:14:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
15:14:43 INFO - mozversion platform_version: 52.0a1
15:14:43 INFO - using testdate: 1476483283
15:14:43 INFO - actual date: 1476483283
15:14:43 INFO - starting webserver on 'localhost:49569'
15:14:43 INFO - SUITE-START | Running 2 tests
15:14:43 INFO - TEST-START | dromaeo_css
15:14:43 INFO - operating with platform_type : w7_
15:14:43 INFO - Initialising browser for dromaeo_css test...
15:14:44 INFO - TEST-INFO | started process 3860 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpchtsno\profile http://localhost:49569/getInfo.html)
15:14:51 INFO - PROCESS | 3860 | __metrics Screen width/height:1600/1200
15:14:51 INFO - PROCESS | 3860 | colorDepth:24
15:14:51 INFO - PROCESS | 3860 | Browser inner width/height: 1010/674
15:14:51 INFO - PROCESS | 3860 | __metrics
15:14:52 INFO - PROCESS | 3860 | 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"
15:14:52 INFO - PROCESS | 3860 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
15:14:53 INFO - TEST-INFO | 3860: exit 0
15:14:53 INFO - Browser initialized.
15:14:53 INFO - Running cycle 1/1 for dromaeo_css test...
15:14:53 INFO - TEST-INFO | started process 1572 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpchtsno\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
15:14:54 INFO - PROCESS | 1572 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
15:15:05 INFO - PROCESS | 1572 | RSS: Main: 123383808
15:15:05 INFO - PROCESS | 1572 |
15:15:56 INFO - PROCESS | 1572 | 1476483356279 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]
15:18:07 INFO - PROCESS | 1572 | Cycle 1(1): loaded http://localhost:49569/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49569/tests/dromaeo/cssquery-ext.html)
15:18:09 INFO - PROCESS | 1572 | RSS: Main: 198713344
15:18:09 INFO - PROCESS | 1572 |
15:21:11 INFO - PROCESS | 1572 | Cycle 1(1): loaded http://localhost:49569/tests/dromaeo/cssquery-ext.html (next: http://localhost:49569/tests/dromaeo/cssquery-jquery.html)
15:21:12 INFO - PROCESS | 1572 | RSS: Main: 198696960
15:21:12 INFO - PROCESS | 1572 |
15:24:14 INFO - PROCESS | 1572 | Cycle 1(1): loaded http://localhost:49569/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49569/tests/dromaeo/cssquery-mootools.html)
15:24:14 INFO - PROCESS | 1572 | RSS: Main: 145657856
15:24:14 INFO - PROCESS | 1572 |
15:27:16 INFO - PROCESS | 1572 | Cycle 1(1): loaded http://localhost:49569/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49569/tests/dromaeo/cssquery-prototype.html)
15:27:17 INFO - PROCESS | 1572 | RSS: Main: 144764928
15:27:17 INFO - PROCESS | 1572 |
15:30:20 INFO - PROCESS | 1572 | Cycle 1(1): loaded http://localhost:49569/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49569/tests/dromaeo/cssquery-yui.html)
15:30:20 INFO - PROCESS | 1572 | RSS: Main: 134373376
15:30:20 INFO - PROCESS | 1572 |
15:33:23 INFO - PROCESS | 1572 | Cycle 1(1): loaded http://localhost:49569/tests/dromaeo/cssquery-yui.html (next: http://localhost:49569/tests/dromaeo/cssquery-dojo.html)
15:33:23 INFO - PROCESS | 1572 | RSS: Main: 183754752
15:33:23 INFO - PROCESS | 1572 |
15:33:23 INFO - PROCESS | 1572 | __start_tp_report
15:33:23 INFO - PROCESS | 1572 | _x_x_mozilla_page_load
15:33:23 INFO - PROCESS | 1572 | _x_x_mozilla_page_load_details
15:33:23 INFO - PROCESS | 1572 | |i|pagename|runs|
15:33:23 INFO - PROCESS | 1572 | |0;dojo.html;2784.4152727927135;2808.3961948181136;2809.606655068291;2815.3524689321443;2815.8169718968265;4658.697184682993;4781.18719817631;4800.039992001596;4889.706617602947;4899.461059283476;4757.33397324374;4792.257200133976;4821.493743156971;4834.34736310598;4947.084789313971;4226.239276930167;4266.125444283922;4271.765052922088;4274.316109422493;4277.893052673677;4224.788760561975;4260.680448966325;4269.87190384289;4271.594198551148;4276.401303817468;3975.86084487043;4243.066525364415;4263.040815816432;4264.701470897038;4285.742855428669;4212.241796476648;4217.240896638604;4292.270314046619;4312.956870431318;4318.848840290606;4167.166566686675;4175.832966681329;4257.93551612097;4276.144771045772;4276.5723427656985;4683;4734.124187025412;4739.099571081483;4756.334113224151;4794.472608013116;4732.858014259577;4928.654994150411;4996.975015124901;5014.498550144993;5064.873378165576;5056.747162641853;5063.518965698289;5149.871253218662;5153.716545589991;5155.9742201288755;4681;4689;4732.574068333866;4759.357486739246;4763;5052.393712754493;5174.611904107207;5175.327207463006;5233.005728911495;5269.683818970874;4972.651914366032;4975.328330675385;5064.746762661852;5070.467600901926;5078.238264260391;4998.150314446553;5026.748662566857;5034.0686972910125;5081.771320290596;5148.253503242009;4995.875103122378;5030.622703297194;5131.7434128293435;5139.8458046258675;5141.562967147759;5046.44489106198;5059.266406371129;5136.640435169502;5149.742512874416;5166.5091816278145;3340.732741380684;3363.8486268117995;3373.79757214563;3435.038189306998;3446.3107378524396;3102.4105419970133;3103.875844966176;3130.499120140812;3149.3228955774616;3159.605049368829;4640.535946405333;4692.554207350297;4797.18447863864;4807.495213002584;4808.422989241243;5102.974485127551;5113.718745469035;5201.401838788588;5210.843674689765;5221.869453263623;4597.793099310472;4632.444106707217;4693.601043911238;4709.952900470951;4746.738929358849;4204.432401625802;4220.894477638084;4240.1519696060295;4248.320268757046;4250.086231460251;5023.6985780853265;5094.923576146435;5134.306868572769;5247.685138891602;5263.710495922761;4983.426905905867;5068.442471328225;5097.923531146962;5099.515546023122;5103.285540024325;5262.052830490548;5267.78928842842;5276.868078298074;5281.7359132042475;5287.444818294178;4913.680610760289;4980.153373926369;4987.725675087905;5006.774695138654;5008.824691135793;4924.384451943507;5030.773615187399;5070.797168113234;5126.154184559506;5182.6113041521285;4912.729799861042;4918.8770280743265;4985.277134815504;5007.649464537448;5013.699178049183;4894.388201474816;4914.852554423373;4917.680350777188;4920.114379411741;4966.279889416087;4845.685030473008;4896.804127834846;4953.0589188054155;4954.455009948975;4958.380202474691;4943.159662857395;4987.950120498748;5033.798648054037;5066.949330506795;5090;4838.153323168502;4853.272009198648;4883.194272944925;4913.115639184981;5007.048660754445
15:33:23 INFO - PROCESS | 1572 | |1;ext.html;502.9119904016797;527.8310940499041;532.2442132172315;539.6033915072419;541.9051665958457;38965.61034389655;41532.584674153324;41601.79199104004;41643.58356416435;41647.99999999996;38935.41596876049;40622;40632.796836015856;40707.18585628286;40873.7956310218;6875.484338674594;6973.198082220533;8499.830003399928;8709;8709.60806763694;9768.706938791813;10530.104941079979;10531.788324451874;10806.675799725981;10843.891561084407;10497.947510262438;10564.524596393143;10593.311434756715;10594.629187978422;10598.682039538826;6924.203716572608;7131.8217044573785;10455.529501172465;10555.41945193014;10665.786684266348;6949.957506374084;7030.507864449491;10027.799444011152;10338.689839304832;10356.63751768692;37516.24967500662;37887.62112378868;37894.43158352627;38121.61878381208;38123.23753524942;2981;3286.2277364819247;3464.688178063961;3466.618671946084;3523.894283171509;29624.25939351512;30064.54903176454;30405.847970760227;30634.387312253853;30784.538231926537;3525.4183059795105;3531.5762108547137;3533.3639944809925;3562.590302115238;3601.837917293728;297300;297791;298556;298604;300337;50105.49894501081;50281;50309.748451258245;50448;50530.49469505258;26970;27013.864930675612;27188.864055679594;27711.168664940084;52591.474085259426;12920.160189587647;13211.207327560378;13352.198868067948;13354.265515396552;13369.866301337057;9147.039560846148;9215.63137474507;9236.8614470783;10048.09567138961;10170.949145254226;16242.918785406233;16565.337386504405;16739.91630041842;17058.914705426392;17157;6400.583962042452;6414;6438.967805161038;6442.973141786383;6465.450436712838;745.2659130756201;770.6339488742915;878.2666473494576;919.7700574856286;936.0732874454242;26407;26942;27167.320816979733;27792.72207277901;27843.443131137265;853.0871966995255;889.7997950461095;902.963881444748;907.2923119966437;922.2898368256405;14121.152730836182;14395.352209150404;14597;14647.340869660891;14690.327418145464;521945;523853;526388;526731;527149;47801.760991194824;48219.51780482221;48416;48600.5139948603;48608.5139148604;979.3829887171037;985.2708995343394;1060.941648209356;1066.072516910293;1088.1566785740956;26335.868320658275;27768.305792355353;28201;28254.43491130207;28396.858015710615;42359;42533.78733106315;42636;42802;42867.785661071495;416896;420852;421818;421881;667837;37793.81103094467;38192.23615527729;38400;38774.2245155101;38882.416763748;41912.790436047624;42893;43045.35431968586;43534.78232608944;43802.561974379845;36902.65928254275;37012.81493592515;37456.81271593625;37553.436698449;37910.24179516339;35683.82158089297;36009.639903600626;36688.26623467462;37016.81491592525;37730.8113459431;34327.65672343245;34332.14169645779;34605.13487162841;34772.47841282332;34853.12867178341;31511;32848.1787955303;33221.50167747437;33284.500732488545;34100.488492672135;24976.75023249817;25049.373765656004;25112.37219069538;25140.12009579656;25362.365940851625
15:33:23 INFO - PROCESS | 1572 | |2;jquery.html;281.20980046070554;306.89412152807273;306.9309405383789;315.9763017773666;317.2164753059942;13087.1493352932;17442.302307907685;17554.473365799015;17773.7333939991;18115.547111322223;16664;17025.40411085612;17039.318427262926;17167.313307467688;17205.053722045315;5270.393904700951;5339.599530035243;5472.179173124044;5542.251796007527;5546.473085056913;5180.948190518103;5500.944990550103;5510.118381059031;5521.3926468088675;5521.751521181536;5185;5451.700156491392;5459.262999495057;5465.262189604392;5466.207399927008;5064.5695115915005;5239.921401178985;5339.813106541253;5364.785408583652;5430.91853622196;4614.492405835355;4622.052479241747;4652.441706995182;4662.160811054009;4670.929936050962;20565.69151462729;21776.346709598736;21810;21943.67084493734;21982.670259946113;1672.765812786211;1759.0764848454535;1774.4055741326672;1813.6100738341188;1873.990630046841;8794.560271986375;8819.51492667903;8862.069482704288;12348.629541113782;13674.17954922698;1592.4904030710177;1713.477389396246;1779.8576113910985;1790.6239689665185;1809.9954525238488;79926.20073799246;83329;84138.5793071043;89163.55418222932;90234;7223.241559636268;7486.925130748623;7523.586202758791;7592.8861067084035;8126.618474859288;11079.50142243601;11387.430628468543;11650.35923024225;12255.448504817305;12257.203281786655;6813.931860681429;6829.3170682932305;6893.551919125241;6906.309369063053;7036.92963070373;6734.797956061326;6736.528443008942;6810.046593476917;6827.931720682829;6853.087437838998;3720.2745464634677;3794.2032173243374;3797.886063418102;3912.2762288976633;3938.78336691479;1588.4599236259728;1606.1407147176205;1616.7332390155493;1618.247514905551;1620.9949831104555;764.4992529892929;818.8198596308803;835.2983493865182;856.4647095565272;890.8351954888367;17698.380556680455;17850.107494625216;17884.28462861497;18064.18711158001;18698.158582863533;762.2872614105829;800.4436916343086;807.2331285278891;824.6577670266772;833.4999000599713;7944.960275198587;9849.950750246346;10028.598856045823;10067.949660251797;10253.692389228336;410255;422137;442967.98448217276;449954;455476;93119;96464.51767741116;99257.00742992622;106303;106905;831.3183189784319;870.8171284030298;894.3962825093037;895.6686026170358;896.1799953042893;15787.368505259661;16305;16685.41601044006;17029.65940681155;17090;33897;42373.36439953341;44626.3306050416;45303;45852.31221531746;59647.70176149091;60129.398706012376;60950;63067.6846615764;71374.64312678404;7363.337299643058;7448.404127669665;7520;7538.773836784905;7574.469787115069;11111.66665000019;12813.871861281268;13000;13040.41318140705;13221.471141154247;10200.591976320864;10680.62617808373;10714;10785.352878827296;10819.729506762395;10677.412742299248;10775.6228532001;10788.838167427339;10948.288361256493;11028.669139925814;10333.534990925276;11244.943775281072;11377.146713996317;11402.657920262405;11505.654830355103;7352.228016058237;7359.742409015659;7450.92549074524;7589.165191828784;7736.149023607286;8286.70996515119;10613.787724245627;10945.726356841144;10974.561017559208;11200.663980080612
15:33:23 INFO - PROCESS | 1572 | |3;mootools.html;1129.3393364881545;1135.9261647992882;1224.1492162946752;1416.3484796993375;1484.4952716076532;6876.2092359378685;7374.889376659341;7389.778306650803;7404.629768511573;7407.777766667009;6400.951695457444;6605.1083103780975;7064.222935477106;7143.6428178590995;7209.567425954433;1918.7409699690502;1926.1428664244418;1935.3032908153054;1939.0692467615554;1944.5527528668415;1887.3111314370224;1898.1268616436387;1907.332433648219;1916.338863092231;1944.3777991042873;1680.02558516061;1727.2141175764996;1895.886246825188;1905.4283714885548;1938.6898096304594;1933.6712758831102;1940.4857712706144;1947.980520194794;1948.6102779444168;1949.6003319319504;1866.150901339887;1873.840723538501;1893.9053047347577;1894.2139012310013;1898.2976298769543;6737.124173857416;7000.824979375506;7040.823979400505;7154.284571542856;7164.785056448315;4247.957520424818;4343.348497725335;4343.543927887558;4356.324769660708;4356.607905288539;2916.6791652918164;2950.6459224892938;3012.141539661208;3044.923876903073;3065.3869226154857;907.2787134228304;938.2728385501223;943.815955888602;944.0983860413289;947.1191791633778;9157.542122893828;9168.724938251864;9185.908140918573;9228.6308547658;9232.53837308132;3658.3780757270797;3767.698584113319;3778.5843557208686;3817.866374676873;3823.770573765583;7268.309510596485;7319;7379.1513975892385;7390.704371825174;7397.18630950595;4220.53574106848;4225.788710564521;4226.36604509326;4227.302495088337;4227.365895115758;3998.260321840469;4007.8797636070963;4016.457778199964;4016.8995775105273;4026.255142798592;2853.08701215609;2854.9143525694262;2932.3255651200343;2991.476491613959;2996.625421822272;851.2338894994555;882.1002577371046;897.6544030548285;898.3980732908967;901.4951627088851;648.5362965479704;742.3393180069743;754.6038329876749;757.1973707869676;761.9695212191561;4402.823887044547;4456.197884380843;4498.8425405110665;4504.09918016398;4585.977070114628;332.1049770867537;334.0213175396113;347.53083337494684;352.4431398390537;352.7125392804856;2919.095080525044;2950.9704902951125;2958.319586495118;3003.984980075086;3077.615298087739;8142.592870356459;8278;8289.58552072394;8553.82892342162;8554.78613034679;8436.746897593082;8509.319254459628;8516.489010659381;8540.40217184791;8542.359323050672;6303.747850085945;6383.265924418752;6393.944999075194;6592.373724496165;6748.865022699617;4311.288637374798;4472.329150627432;4483.730976141442;4524.796384162655;4547.931781023221;7804;8008.1191068983535;8010.559419231999;8014.759557213293;8038.799030024296;7700.922990770021;7799.103103143211;7961.960190199012;8103.797405064921;8190.467619604707;6239.313675495601;6359.745610175726;6361.2048493938255;6374.07575901501;6406.583572067801;6787.049813026081;6950.54821436605;6954.9652251740445;6991.2309645937885;6998.720051197895;6107.053406721965;6425;6475.611463312216;6529.053287273224;6601.306862779531;6198.535109866688;6226.813195604139;6237.594556353823;6262.2172228471445;6293.496520278458;5783.3638299787835;5844.795432159854;5846.444587764156;5862.912056319244;5876.294844618673;3950.7827069511454;3989.4614227080133;4024.396340548941;4031.113155105872;4040.878773636796;4057.472528571267;4085.346344584852;4097.590240975878;4108.383742438658;4188.895277618084
15:33:23 INFO - PROCESS | 1572 | |4;prototype.html;233.84683032613643;245.90286836699505;248.57245537675198;253.92715775847049;257.5608587358553;4991.276264941581;5159.871003224921;5205.973970130144;5295.338082739658;5304.098303288436;5148.691078535281;5168.010316028328;5178.5339319461245;5182.611304152194;5197.168453047513;3723.478712980178;3773.3962565989445;3782.205736795276;3795.7722536647752;3799.639034291738;3778.414345776409;3800.37293846515;3806.0675134592066;3806.3909774436097;3807.6953843692577;3639.490471334002;3732.97343230611;3764.3600587899987;3766.1714422826935;3779.9433008504757;3621.8551257949653;3706.7952915302635;3772.5472943246987;3776.9055773605605;3778.263238668461;3197.58431403916;3246.123546642417;3247.9188020299443;3260.8369581521065;3269.1173383186406;4856.182293612253;5047.217681259411;5075.162598171334;5091.396210193777;5093.719845408501;4274.2092712848225;4290.227759003378;4370.978145109254;4393.692441529094;4402.415130552999;5307.73461326936;5318.9202161967605;5343.625946183769;5349.679019258857;5367.114426119724;3327.4010678077934;3465.6014558325865;3578.3737845876867;3596.62235465275;3606.296772129436;5596.412376700469;5596.748146333425;5618.550515958714;5637.408072152447;5642.858928526779;3526.770759900598;3541.1678255609913;3542.2030043240065;3542.6988705959766;3543.8050907199836;3562.2341196642847;3565.180008598037;3577.9087378349855;3579.838907249128;3590.9102272443397;4210.536840947493;4235.30117530604;4262.573742625713;4284.357346398003;4293.463317085364;5189.221616757441;5218.060749065129;5402.756875940592;5403.675779453245;5445.918311225335;1228.9308301777332;1241.1064033895582;1241.1932244041266;1241.6275117464725;1248.3508575540668;739.5340935210783;739.9112106547141;769.2192424688945;770.8882212079329;772.5673622771154;4633.513481084505;4657.930131048037;4664.253719404879;4675.054715529195;4680.625549955996;5041.420236672756;5043;5054.595632349403;5066.859956509829;5087.287779710843;4612.331211974245;4666.533346665306;4943.975280123576;5089.338386009795;5103.157978933435;2671.919842404731;2902.129361191634;2902.7097290271226;2915.8833646653898;2933.559966005116;5684.971575142098;5781.739821707949;5800.883982320414;5804.796832110856;5805.88388232233;5692.6015178937905;5728.283964504437;5732.283464566929;5736.6558006519745;5738.225339579103;5512.751926163252;5790.768369265183;5797.01450753373;5799.768009279582;5800.88398232033;5396.9730151348995;5566.192902029102;5583.860403489945;5593.412691667317;5646;5545.805896793593;5569.331680198402;5575.721213939368;5575.8884822304135;5580.86047848807;5748.626339287933;5795.710214489343;5800.854978625399;5818.709064546841;5826.359100498857;3364.360771453416;3560;3563.2339047104997;3586.246888153413;3590.497330373802;5550;5593.580481463825;5604.775808967759;5605.523530499953;5607.130894711327;5224.373075230997;5351.652142610717;5393.786398060562;5420.647657902224;5435.83692489226;5139.92290115656;5398.757055932571;5398.811041613525;5436.918446223231;5447.891042179213;5207.817726379559;5264.420913699567;5290.444503327095;5294.2852714883;5297.258383826191;5155.458676838878;5343.358796944236;5415.4313797050745;5418.756155972912;5429.375621803543;5243;5269.552088072557;5394.892102158014;5400.2439658447065;5407.351117865881
15:33:23 INFO - PROCESS | 1572 | |5;yui.html;586.8180863932181;629.5152732396058;648.542777341974;651.9608823470592;670.9161354830643;14250.358733856976;15083.170425626586;15084.924575377136;15095.169765662884;15117.92441037796;12770.936145319283;13297;13306;13307.467701291936;13312.534061307857;485.7304196171124;589.9705014749256;603.2820943077745;604.6795198544778;607.2439812433527;492.045431862188;600.423593350382;612.0268772651483;615.2125279642042;616.848872026352;634.0457611295018;634.4733870887176;635.8442181665498;635.8791829552371;636.3795299582916;500.24463060778123;636.8885445047122;637.3944752485135;637.5186734015834;637.6142433827537;367.6231862341095;449.26769365933643;451.6477147824702;455.6901307111163;456.7305289878954;13341.266230357327;13540.458381664721;13586.728265434636;13607.31963401826;13622.591322260349;12764.744705105939;12805.935970320088;12874.227546347156;13228.206307621573;13261.535846245335;11951.581694640647;12409.503619855195;12472.688182795411;12591.24452532851;12610.68473288166;4258.616724494779;4315.266404711207;4329.437173167499;4347.891302717458;4444.977775111104;78051.6097419515;82190.58904705498;82253.58873205596;82344;82962.01506600756;9907.950460247652;9999.000099989942;10188;10272.33229840058;10280.588776448858;19069.523261918563;19076;19101.331453399063;19104.52238694016;19115.80884191168;7048.436125109979;7137.785866424016;7186.245444228385;7242.9275707243305;7415.480916335805;3626.057225121435;3676.3750162472443;3726.7763934164036;3746.812659367021;3844;2928.4582352264474;2974.6579143398353;3011.713887180714;3012.3372857971212;3016.638003439601;520.4691214960719;523.2151772341488;523.3222976245771;523.3719536556148;533.6371267538111;503.55183886341183;555.9249501317352;569.0866159813522;582.3797655496883;587.5769445998874;8486.787830304169;8595.78510537229;8801.339899507562;8838.469691818511;9027.77430564228;523.3719536556148;523.6386893043788;556.2634170042095;565.2877374508067;604.2054698072021;4325.678163557539;4535.29702896045;4550.271956486946;4586.472555656075;4682.297655351656;108683;110816;111732;112135;112714;98612;99164;99550.5022474883;99760;100815;85893.57053214694;86682.56658716792;86777.13222867817;87949.68075478873;89384;8248.752537423887;8263.710770123016;8333.458325208841;8582.313414926915;8599.183077607617;49910.50089499204;51071.2339314918;52681.736591316796;52733.47266527439;53211;53969;71475;71777.6411117941;72752.81460595627;73020;8747.387682862392;9002.684906028258;9012.594433250388;9187.617036535396;9187.770305742142;11557.826632600685;11733.120015998664;11797.17419780607;11839.348835814113;12161.635150945485;11147.442627868737;11424.314541127558;11445.542178312775;11502.13733969939;11620.941895290469;11151.553937842395;11229.438528073728;11283.887161128283;11409.60066397672;11508.53965841357;11290.887091128983;11305.095592352427;11338.376389298668;11412.657620271406;11431.942250395008;9596.75242218507;9745.512724363894;9792.706218813448;9809.215262778818;9870.506474676382;9166.770830729285;9331.393459425115;9792.706218813448;9980.500974951077;10102.898971010196
15:33:23 INFO - PROCESS | 1572 | __end_tp_report
15:33:23 INFO - PROCESS | 1572 | __start_cc_report
15:33:23 INFO - PROCESS | 1572 | _x_x_mozilla_cycle_collect,1118
15:33:23 INFO - PROCESS | 1572 | __end_cc_report
15:33:23 INFO - PROCESS | 1572 | __startTimestamp1476484403312__endTimestamp
15:33:23 INFO - PROCESS | 1572 |
15:33:23 INFO - PROCESS | 1572 | ------- Summary: start -------
15:33:23 INFO - PROCESS | 1572 | Number of tests: 6
15:33:23 INFO - PROCESS | 1572 |
15:33:23 INFO - PROCESS | 1572 | [#0] dojo.html Cycles:165 Average:4675.91 Median:4913.12 stddev:594.24 (12.1%) stddev-sans-first:577.24
15:33:23 INFO - PROCESS | 1572 | Values: 2784.4 2808.4 2809.6 2815.4 2815.8 4658.7 4781.2 4800.0 4889.7 4899.5 4757.3 4792.3 4821.5 4834.3 4947.1 4226.2 4266.1 4271.8 4274.3 4277.9 4224.8 4260.7 4269.9 4271.6 4276.4 3975.9 4243.1 4263.0 4264.7 4285.7 4212.2 4217.2 4292.3 4313.0 4318.8 4167.2 4175.8 4257.9 4276.1 4276.6 4683.0 4734.1 4739.1 4756.3 4794.5 4732.9 4928.7 4997.0 5014.5 5064.9 5056.7 5063.5 5149.9 5153.7 5156.0 4681.0 4689.0 4732.6 4759.4 4763.0 5052.4 5174.6 5175.3 5233.0 5269.7 4972.7 4975.3 5064.7 5070.5 5078.2 4998.2 5026.7 5034.1 5081.8 5148.3 4995.9 5030.6 5131.7 5139.8 5141.6 5046.4 5059.3 5136.6 5149.7 5166.5 3340.7 3363.8 3373.8 3435.0 3446.3 3102.4 3103.9 3130.5 3149.3 3159.6 4640.5 4692.6 4797.2 4807.5 4808.4 5103.0 5113.7 5201.4 5210.8 5221.9 4597.8 4632.4 4693.6 4710.0 4746.7 4204.4 4220.9 4240.2 4248.3 4250.1 5023.7 5094.9 5134.3 5247.7 5263.7 4983.4 5068.4 5097.9 5099.5 5103.3 5262.1 5267.8 5276.9 5281.7 5287.4 4913.7 4980.2 4987.7 5006.8 5008.8 4924.4 5030.8 5070.8 5126.2 5182.6 4912.7 4918.9 4985.3 5007.6 5013.7 4894.4 4914.9 4917.7 4920.1 4966.3 4845.7 4896.8 4953.1 4954.5 4958.4 4943.2 4988.0 5033.8 5066.9 5090.0 4838.2 4853.3 4883.2 4913.1 5007.0
15:33:23 INFO - PROCESS | 1572 |
15:33:23 INFO - PROCESS | 1572 | [#1] ext.html Cycles:180 Average:56605.11 Median:37486.53 stddev:118824.66 (317.0%) stddev-sans-first:119083.33
15:33:23 INFO - PROCESS | 1572 | Values: 502.9 527.8 532.2 539.6 541.9 38965.6 41532.6 41601.8 41643.6 41648.0 38935.4 40622.0 40632.8 40707.2 40873.8 6875.5 6973.2 8499.8 8709.0 8709.6 9768.7 10530.1 10531.8 10806.7 10843.9 10497.9 10564.5 10593.3 10594.6 10598.7 6924.2 7131.8 10455.5 10555.4 10665.8 6950.0 7030.5 10027.8 10338.7 10356.6 37516.2 37887.6 37894.4 38121.6 38123.2 2981.0 3286.2 3464.7 3466.6 3523.9 29624.3 30064.5 30405.8 30634.4 30784.5 3525.4 3531.6 3533.4 3562.6 3601.8 297300.0 297791.0 298556.0 298604.0 300337.0 50105.5 50281.0 50309.7 50448.0 50530.5 26970.0 27013.9 27188.9 27711.2 52591.5 12920.2 13211.2 13352.2 13354.3 13369.9 9147.0 9215.6 9236.9 10048.1 10170.9 16242.9 16565.3 16739.9 17058.9 17157.0 6400.6 6414.0 6439.0 6443.0 6465.5 745.3 770.6 878.3 919.8 936.1 26407.0 26942.0 27167.3 27792.7 27843.4 853.1 889.8 903.0 907.3 922.3 14121.2 14395.4 14597.0 14647.3 14690.3 521945.0 523853.0 526388.0 526731.0 527149.0 47801.8 48219.5 48416.0 48600.5 48608.5 979.4 985.3 1060.9 1066.1 1088.2 26335.9 27768.3 28201.0 28254.4 28396.9 42359.0 42533.8 42636.0 42802.0 42867.8 416896.0 420852.0 421818.0 421881.0 667837.0 37793.8 38192.2 38400.0 38774.2 38882.4 41912.8 42893.0 43045.4 43534.8 43802.6 36902.7 37012.8 37456.8 37553.4 37910.2 35683.8 36009.6 36688.3 37016.8 37730.8 34327.7 34332.1 34605.1 34772.5 34853.1 31511.0 32848.2 33221.5 33284.5 34100.5 24976.8 25049.4 25112.4 25140.1 25362.4
15:33:23 INFO - PROCESS | 1572 |
15:33:23 INFO - PROCESS | 1572 | [#2] jquery.html Cycles:180 Average:27201.07 Median:243797.16 stddev:72881.17 (29.9%) stddev-sans-first:73057.59
15:33:23 INFO - PROCESS | 1572 | Values: 281.2 306.9 306.9 316.0 317.2 13087.1 17442.3 17554.5 17773.7 18115.5 16664.0 17025.4 17039.3 17167.3 17205.1 5270.4 5339.6 5472.2 5542.3 5546.5 5180.9 5500.9 5510.1 5521.4 5521.8 5185.0 5451.7 5459.3 5465.3 5466.2 5064.6 5239.9 5339.8 5364.8 5430.9 4614.5 4622.1 4652.4 4662.2 4670.9 20565.7 21776.3 21810.0 21943.7 21982.7 1672.8 1759.1 1774.4 1813.6 1874.0 8794.6 8819.5 8862.1 12348.6 13674.2 1592.5 1713.5 1779.9 1790.6 1810.0 79926.2 83329.0 84138.6 89163.6 90234.0 7223.2 7486.9 7523.6 7592.9 8126.6 11079.5 11387.4 11650.4 12255.4 12257.2 6813.9 6829.3 6893.6 6906.3 7036.9 6734.8 6736.5 6810.0 6827.9 6853.1 3720.3 3794.2 3797.9 3912.3 3938.8 1588.5 1606.1 1616.7 1618.2 1621.0 764.5 818.8 835.3 856.5 890.8 17698.4 17850.1 17884.3 18064.2 18698.2 762.3 800.4 807.2 824.7 833.5 7945.0 9850.0 10028.6 10067.9 10253.7 410255.0 422137.0 442968.0 449954.0 455476.0 93119.0 96464.5 99257.0 106303.0 106905.0 831.3 870.8 894.4 895.7 896.2 15787.4 16305.0 16685.4 17029.7 17090.0 33897.0 42373.4 44626.3 45303.0 45852.3 59647.7 60129.4 60950.0 63067.7 71374.6 7363.3 7448.4 7520.0 7538.8 7574.5 11111.7 12813.9 13000.0 13040.4 13221.5 10200.6 10680.6 10714.0 10785.4 10819.7 10677.4 10775.6 10788.8 10948.3 11028.7 10333.5 11244.9 11377.1 11402.7 11505.7 7352.2 7359.7 7450.9 7589.2 7736.1 8286.7 10613.8 10945.7 10974.6 11200.7
15:33:23 INFO - PROCESS | 1572 |
15:33:23 INFO - PROCESS | 1572 | [#3] mootools.html Cycles:180 Average:4578.27 Median:5814.08 stddev:2571.32 (44.2%) stddev-sans-first:2565.47
15:33:23 INFO - PROCESS | 1572 | Values: 1129.3 1135.9 1224.1 1416.3 1484.5 6876.2 7374.9 7389.8 7404.6 7407.8 6401.0 6605.1 7064.2 7143.6 7209.6 1918.7 1926.1 1935.3 1939.1 1944.6 1887.3 1898.1 1907.3 1916.3 1944.4 1680.0 1727.2 1895.9 1905.4 1938.7 1933.7 1940.5 1948.0 1948.6 1949.6 1866.2 1873.8 1893.9 1894.2 1898.3 6737.1 7000.8 7040.8 7154.3 7164.8 4248.0 4343.3 4343.5 4356.3 4356.6 2916.7 2950.6 3012.1 3044.9 3065.4 907.3 938.3 943.8 944.1 947.1 9157.5 9168.7 9185.9 9228.6 9232.5 3658.4 3767.7 3778.6 3817.9 3823.8 7268.3 7319.0 7379.2 7390.7 7397.2 4220.5 4225.8 4226.4 4227.3 4227.4 3998.3 4007.9 4016.5 4016.9 4026.3 2853.1 2854.9 2932.3 2991.5 2996.6 851.2 882.1 897.7 898.4 901.5 648.5 742.3 754.6 757.2 762.0 4402.8 4456.2 4498.8 4504.1 4586.0 332.1 334.0 347.5 352.4 352.7 2919.1 2951.0 2958.3 3004.0 3077.6 8142.6 8278.0 8289.6 8553.8 8554.8 8436.7 8509.3 8516.5 8540.4 8542.4 6303.7 6383.3 6393.9 6592.4 6748.9 4311.3 4472.3 4483.7 4524.8 4547.9 7804.0 8008.1 8010.6 8014.8 8038.8 7700.9 7799.1 7962.0 8103.8 8190.5 6239.3 6359.7 6361.2 6374.1 6406.6 6787.0 6950.5 6955.0 6991.2 6998.7 6107.1 6425.0 6475.6 6529.1 6601.3 6198.5 6226.8 6237.6 6262.2 6293.5 5783.4 5844.8 5846.4 5862.9 5876.3 3950.8 3989.5 4024.4 4031.1 4040.9 4057.5 4085.3 4097.6 4108.4 4188.9
15:33:23 INFO - PROCESS | 1572 |
15:33:23 INFO - PROCESS | 1572 | [#4] prototype.html Cycles:180 Average:4414.16 Median:5121.54 stddev:1403.34 (27.4%) stddev-sans-first:1371.76
15:33:23 INFO - PROCESS | 1572 | Values: 233.8 245.9 248.6 253.9 257.6 4991.3 5159.9 5206.0 5295.3 5304.1 5148.7 5168.0 5178.5 5182.6 5197.2 3723.5 3773.4 3782.2 3795.8 3799.6 3778.4 3800.4 3806.1 3806.4 3807.7 3639.5 3733.0 3764.4 3766.2 3779.9 3621.9 3706.8 3772.5 3776.9 3778.3 3197.6 3246.1 3247.9 3260.8 3269.1 4856.2 5047.2 5075.2 5091.4 5093.7 4274.2 4290.2 4371.0 4393.7 4402.4 5307.7 5318.9 5343.6 5349.7 5367.1 3327.4 3465.6 3578.4 3596.6 3606.3 5596.4 5596.7 5618.6 5637.4 5642.9 3526.8 3541.2 3542.2 3542.7 3543.8 3562.2 3565.2 3577.9 3579.8 3590.9 4210.5 4235.3 4262.6 4284.4 4293.5 5189.2 5218.1 5402.8 5403.7 5445.9 1228.9 1241.1 1241.2 1241.6 1248.4 739.5 739.9 769.2 770.9 772.6 4633.5 4657.9 4664.3 4675.1 4680.6 5041.4 5043.0 5054.6 5066.9 5087.3 4612.3 4666.5 4944.0 5089.3 5103.2 2671.9 2902.1 2902.7 2915.9 2933.6 5685.0 5781.7 5800.9 5804.8 5805.9 5692.6 5728.3 5732.3 5736.7 5738.2 5512.8 5790.8 5797.0 5799.8 5800.9 5397.0 5566.2 5583.9 5593.4 5646.0 5545.8 5569.3 5575.7 5575.9 5580.9 5748.6 5795.7 5800.9 5818.7 5826.4 3364.4 3560.0 3563.2 3586.2 3590.5 5550.0 5593.6 5604.8 5605.5 5607.1 5224.4 5351.7 5393.8 5420.6 5435.8 5139.9 5398.8 5398.8 5436.9 5447.9 5207.8 5264.4 5290.4 5294.3 5297.3 5155.5 5343.4 5415.4 5418.8 5429.4 5243.0 5269.6 5394.9 5400.2 5407.4
15:33:23 INFO - PROCESS | 1572 |
15:33:23 INFO - PROCESS | 1572 | [#5] yui.html Cycles:180 Average:19887.59 Median:25795.85 stddev:30001.62 (116.3%) stddev-sans-first:30050.78
15:33:23 INFO - PROCESS | 1572 | Values: 586.8 629.5 648.5 652.0 670.9 14250.4 15083.2 15084.9 15095.2 15117.9 12770.9 13297.0 13306.0 13307.5 13312.5 485.7 590.0 603.3 604.7 607.2 492.0 600.4 612.0 615.2 616.8 634.0 634.5 635.8 635.9 636.4 500.2 636.9 637.4 637.5 637.6 367.6 449.3 451.6 455.7 456.7 13341.3 13540.5 13586.7 13607.3 13622.6 12764.7 12805.9 12874.2 13228.2 13261.5 11951.6 12409.5 12472.7 12591.2 12610.7 4258.6 4315.3 4329.4 4347.9 4445.0 78051.6 82190.6 82253.6 82344.0 82962.0 9908.0 9999.0 10188.0 10272.3 10280.6 19069.5 19076.0 19101.3 19104.5 19115.8 7048.4 7137.8 7186.2 7242.9 7415.5 3626.1 3676.4 3726.8 3746.8 3844.0 2928.5 2974.7 3011.7 3012.3 3016.6 520.5 523.2 523.3 523.4 533.6 503.6 555.9 569.1 582.4 587.6 8486.8 8595.8 8801.3 8838.5 9027.8 523.4 523.6 556.3 565.3 604.2 4325.7 4535.3 4550.3 4586.5 4682.3 108683.0 110816.0 111732.0 112135.0 112714.0 98612.0 99164.0 99550.5 99760.0 100815.0 85893.6 86682.6 86777.1 87949.7 89384.0 8248.8 8263.7 8333.5 8582.3 8599.2 49910.5 51071.2 52681.7 52733.5 53211.0 53969.0 71475.0 71777.6 72752.8 73020.0 8747.4 9002.7 9012.6 9187.6 9187.8 11557.8 11733.1 11797.2 11839.3 12161.6 11147.4 11424.3 11445.5 11502.1 11620.9 11151.6 11229.4 11283.9 11409.6 11508.5 11290.9 11305.1 11338.4 11412.7 11431.9 9596.8 9745.5 9792.7 9809.2 9870.5 9166.8 9331.4 9792.7 9980.5 10102.9
15:33:23 INFO - PROCESS | 1572 | -------- Summary: end --------
15:33:23 INFO - PROCESS | 1572 |
15:33:24 INFO - TEST-INFO | 1572: exit 0
15:33:24 INFO - TEST-OK | dromaeo_css | took 1120833ms
15:33:24 INFO - TEST-START | kraken
15:33:24 INFO - operating with platform_type : w7_
15:33:24 INFO - Initialising browser for kraken test...
15:33:24 INFO - TEST-INFO | started process 2052 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpsiglod\profile http://localhost:49569/getInfo.html)
15:33:29 INFO - PROCESS | 2052 | __metrics Screen width/height:1600/1200
15:33:29 INFO - PROCESS | 2052 | colorDepth:24
15:33:29 INFO - PROCESS | 2052 | Browser inner width/height: 1010/674
15:33:29 INFO - PROCESS | 2052 | __metrics
15:33:30 INFO - PROCESS | 2052 | 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"
15:33:30 INFO - PROCESS | 2052 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
15:33:30 INFO - PROCESS | 2052 | console.error:
15:33:30 INFO - PROCESS | 2052 | Message: Error: Connection closed before committing the transaction.
15:33:30 INFO - PROCESS | 2052 | Stack:
15:33:30 INFO - PROCESS | 2052 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
15:33:30 INFO - PROCESS | 2052 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
15:33:30 INFO - PROCESS | 2052 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
15:33:30 INFO - PROCESS | 2052 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
15:33:30 INFO - PROCESS | 2052 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
15:33:30 INFO - PROCESS | 2052 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15
15:33:30 INFO - PROCESS | 2052 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
15:33:30 INFO - PROCESS | 2052 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
15:33:30 INFO - PROCESS | 2052 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
15:33:30 INFO - PROCESS | 2052 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
15:33:30 INFO - PROCESS | 2052 | Barrier/this.client.addBlocker/promise13.1%
15:34:27 INFO - TinderboxPrint: I/O read bytes / time
160,620,032 / 1,949,320
15:34:27 INFO - TinderboxPrint: I/O write bytes / time
464,377,856 / 3,683,030
15:34:27 INFO - TinderboxPrint: CPU idle
8,257.4 (86.9%)
15:34:27 INFO - TinderboxPrint: CPU user
1,209.8 (12.7%)
15:34:27 INFO - install - Wall time: 3s; CPU: 8.0%; Read bytes: 0; Write bytes: 35444736; Read time: 0; Write time: 423920
15:34:27 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 160620032; Write bytes: 392945664; Read time: 1949320; Write time: 3233420
15:34:28 INFO - Running post-run listener: _upload_blobber_files
15:34:28 INFO - Blob upload gear active.
15:34:28 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
15:34:28 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
15:34:28 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Fx-Team', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
15:34:28 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Fx-Team -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
15:34:29 INFO - (blobuploader) - INFO - Open directory for files ...
15:34:29 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
15:34:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
15:34:29 INFO - (blobuploader) - INFO - Uploading, attempt #1.
15:34:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
15:34:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
15:34:29 INFO - (blobuploader) - INFO - Done attempting.
15:34:29 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
15:34:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
15:34:29 INFO - (blobuploader) - INFO - Uploading, attempt #1.
15:34:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
15:34:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
15:34:30 INFO - (blobuploader) - INFO - Done attempting.
15:34:30 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
15:34:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
15:34:30 INFO - (blobuploader) - INFO - Uploading, attempt #1.
15:34:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
15:34:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
15:34:38 INFO - (blobuploader) - INFO - Done attempting.
15:34:38 INFO - (blobuploader) - INFO - Iteration through files over.
15:34:38 INFO - Return code: 0
15:34:38 INFO - rmtree: C:\slave\test\build\uploaded_files.json
15:34:38 INFO - Using _rmtree_windows ...
15:34:38 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
15:34:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0605a8160f46cd6110be4e0a67e17b193b59a30e1d4e826b0beb917e25e7a4a9354ed57b4a2fe6fa0628c45dd47a08d7e6b95fc9f1114a2b8b18cac4c6854d0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ee51f030257d8978dc9cb2477cf9f8f86607a9c975f200ba60f9990dfba9cf76176eb36cff14219dd62cdb62ebeed0d9234d46b2dbccd5698e36892422dde21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5098563167a72ee39bba89285ed8310da1e57eea7afc03f364bafff43101e72b024627a9a17ed980bbf88a13cf576bd51abd70de8b9a72c0c939e361567e88e7"}
15:34:38 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
15:34:38 INFO - Writing to file C:\slave\test\properties\blobber_files
15:34:38 INFO - Contents:
15:34:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0605a8160f46cd6110be4e0a67e17b193b59a30e1d4e826b0beb917e25e7a4a9354ed57b4a2fe6fa0628c45dd47a08d7e6b95fc9f1114a2b8b18cac4c6854d0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ee51f030257d8978dc9cb2477cf9f8f86607a9c975f200ba60f9990dfba9cf76176eb36cff14219dd62cdb62ebeed0d9234d46b2dbccd5698e36892422dde21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5098563167a72ee39bba89285ed8310da1e57eea7afc03f364bafff43101e72b024627a9a17ed980bbf88a13cf576bd51abd70de8b9a72c0c939e361567e88e7"}
15:34:38 INFO - Running post-run listener: copy_logs_to_upload_dir
15:34:38 INFO - Copying logs to upload dir...
15:34:38 INFO - mkdir: C:\slave\test\build\upload\logs
15:34:38 INFO - Copying logs to upload dir...
15:34:38 INFO - Using _rmtree_windows ...
15:34:38 INFO - Using _rmtree_windows ...
15:34:38 INFO - Using _rmtree_windows ...
15:34:38 INFO - Using _rmtree_windows ...
15:34:38 INFO - Using _rmtree_windows ...
15:34:38 INFO - Using _rmtree_windows ...
15:34:38 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1396.650000
========= master_lag: 2.49 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 19 secs) (at 2016-10-14 15:34:41.823605) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 15:34:41.824298) =========
'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-064
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-064
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-064
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0605a8160f46cd6110be4e0a67e17b193b59a30e1d4e826b0beb917e25e7a4a9354ed57b4a2fe6fa0628c45dd47a08d7e6b95fc9f1114a2b8b18cac4c6854d0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ee51f030257d8978dc9cb2477cf9f8f86607a9c975f200ba60f9990dfba9cf76176eb36cff14219dd62cdb62ebeed0d9234d46b2dbccd5698e36892422dde21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5098563167a72ee39bba89285ed8310da1e57eea7afc03f364bafff43101e72b024627a9a17ed980bbf88a13cf576bd51abd70de8b9a72c0c939e361567e88e7"}
build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.102000
build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476473404/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0605a8160f46cd6110be4e0a67e17b193b59a30e1d4e826b0beb917e25e7a4a9354ed57b4a2fe6fa0628c45dd47a08d7e6b95fc9f1114a2b8b18cac4c6854d0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ee51f030257d8978dc9cb2477cf9f8f86607a9c975f200ba60f9990dfba9cf76176eb36cff14219dd62cdb62ebeed0d9234d46b2dbccd5698e36892422dde21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5098563167a72ee39bba89285ed8310da1e57eea7afc03f364bafff43101e72b024627a9a17ed980bbf88a13cf576bd51abd70de8b9a72c0c939e361567e88e7"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 15:34:41.944210) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 15:34:41.944530) =========
'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-064
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-064
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-064
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-14 15:34:42.056887) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-14 15:34:42.057269) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-14 15:34:42.602771) =========
========= Total master_lag: 2.58 =========